Log opened at Tue, Jan 22 2019 01:00:18 UTC (actual time)
Current log level is Verbose
ACP Expert Scheduler version 8.1.1
Full license: Arne Henden
2019-01-22 01:03:25.7: ++ Weather Safe ++
2019-01-22 01:16:20.5: ++ Observatory Startup ++
2019-01-22 01:16:20.5: Start ACP Sequencer's StartupObs script
2019-01-22 01:16:42.3: Open the observatory dome/roof
2019-01-22 01:17:49.4: Observatory startup succeeded, and dome opened successfully
2019-01-22 01:26:12.9: Plan GSC_3649-0825 has Monitor Mode. Time to resubmit it.
2019-01-22 01:26:12.9: Plan GSC_4030_204 has Monitor Mode. Time to resubmit it.
2019-01-22 01:26:12.9: Plan CY_UMa has Monitor Mode. Time to resubmit it.
2019-01-22 01:26:12.9: Plan V0809_Cep has Monitor Mode. Time to resubmit it.
2019-01-22 01:26:12.9: Plan V0965_Per has Monitor Mode. Time to resubmit it.
2019-01-22 01:26:12.9: Plan NGC_7790 has Monitor Mode. Time to resubmit it.
2019-01-22 01:26:12.9: Plan HX_Aur has Monitor Mode. Time to resubmit it.
2019-01-22 01:26:12.9: Plan FU_Cam has Monitor Mode. Time to resubmit it.
2019-01-22 01:26:12.9: Plan FW_Cam has Monitor Mode. Time to resubmit it.
2019-01-22 01:26:12.9: Plan FY_Cam has Monitor Mode. Time to resubmit it.
2019-01-22 01:26:12.9: Plan HI_Cam has Monitor Mode. Time to resubmit it.
2019-01-22 01:26:12.9: Plan CM_Cas has Monitor Mode. Time to resubmit it.
2019-01-22 01:26:12.9: Plan WZ_Per has Monitor Mode. Time to resubmit it.
2019-01-22 01:26:12.9: Plan V0603_Per has Monitor Mode. Time to resubmit it.
2019-01-22 01:26:12.9: Plan CV_Vir has Monitor Mode. Time to resubmit it.
2019-01-22 01:26:12.9: Plan AF_And has Monitor Mode. Time to resubmit it.
2019-01-22 01:26:13.0: Rising plan delay enabled for Plan CB_32:
2019-01-22 01:26:13.0:     406 min. remaining to meet strict constraints.
2019-01-22 01:26:13.0:     704 min. remaining for the plan to fit before dawn.
2019-01-22 01:26:13.0:     but only 192 min till mid-Plan transit.
2019-01-22 01:26:13.0:     deferred (rising) for 192 min, until 22-Jan-2019 04:38:31 UTC
2019-01-22 01:26:13.4: Rising plan delay enabled for Plan DN_Lyn:
2019-01-22 01:26:13.4:     624 min. remaining to meet strict constraints.
2019-01-22 01:26:13.4:     687 min. remaining for the plan to fit before dawn.
2019-01-22 01:26:13.4:     but only 298 min till mid-Plan transit.
2019-01-22 01:26:13.4:     deferred (rising) for 296 min, until 22-Jan-2019 06:23:10 UTC
2019-01-22 01:26:13.4: Rising plan delay enabled for Plan FU_Cam:
2019-01-22 01:26:13.4:     409 min. remaining to meet strict constraints.
2019-01-22 01:26:13.4:     704 min. remaining for the plan to fit before dawn.
2019-01-22 01:26:13.4:     but only 59 min till mid-Plan transit.
2019-01-22 01:26:13.4:     deferred (rising) for 59 min, until 22-Jan-2019 02:26:12 UTC
2019-01-22 01:26:13.4: Rising plan delay enabled for Plan FW_Cam:
2019-01-22 01:26:13.4:     422 min. remaining to meet strict constraints.
2019-01-22 01:26:13.4:     704 min. remaining for the plan to fit before dawn.
2019-01-22 01:26:13.4:     but only 66 min till mid-Plan transit.
2019-01-22 01:26:13.4:     deferred (rising) for 66 min, until 22-Jan-2019 02:33:02 UTC
2019-01-22 01:26:13.4: Rising plan delay enabled for Plan FY_Cam:
2019-01-22 01:26:13.4:     415 min. remaining to meet strict constraints.
2019-01-22 01:26:13.4:     704 min. remaining for the plan to fit before dawn.
2019-01-22 01:26:13.4:     but only 72 min till mid-Plan transit.
2019-01-22 01:26:13.4:     deferred (rising) for 72 min, until 22-Jan-2019 02:38:44 UTC
2019-01-22 01:26:13.4: Rising plan delay enabled for Plan HI_Cam:
2019-01-22 01:26:13.4:     477 min. remaining to meet strict constraints.
2019-01-22 01:26:13.4:     704 min. remaining for the plan to fit before dawn.
2019-01-22 01:26:13.4:     but only 131 min till mid-Plan transit.
2019-01-22 01:26:13.4:     deferred (rising) for 131 min, until 22-Jan-2019 03:37:56 UTC
2019-01-22 01:26:13.4: Rising plan delay enabled for Plan HO_Cam:
2019-01-22 01:26:13.4:     507 min. remaining to meet strict constraints.
2019-01-22 01:26:13.4:     687 min. remaining for the plan to fit before dawn.
2019-01-22 01:26:13.4:     but only 160 min till mid-Plan transit.
2019-01-22 01:26:13.4:     deferred (rising) for 158 min, until 22-Jan-2019 04:04:45 UTC
2019-01-22 01:26:13.4: Rising plan delay enabled for Plan HQ_Aur:
2019-01-22 01:26:13.4:     509 min. remaining to meet strict constraints.
2019-01-22 01:26:13.4:     687 min. remaining for the plan to fit before dawn.
2019-01-22 01:26:13.4:     but only 177 min till mid-Plan transit.
2019-01-22 01:26:13.4:     deferred (rising) for 175 min, until 22-Jan-2019 04:21:44 UTC
2019-01-22 01:26:13.4: Rising plan delay enabled for Plan HX_Aur:
2019-01-22 01:26:13.4:     477 min. remaining to meet strict constraints.
2019-01-22 01:26:13.4:     704 min. remaining for the plan to fit before dawn.
2019-01-22 01:26:13.4:     but only 158 min till mid-Plan transit.
2019-01-22 01:26:13.4:     deferred (rising) for 158 min, until 22-Jan-2019 04:04:47 UTC
2019-01-22 01:26:13.5: Rising plan delay enabled for Plan MP_Gem:
2019-01-22 01:26:13.5:     528 min. remaining to meet strict constraints.
2019-01-22 01:26:13.5:     669 min. remaining for the plan to fit before dawn.
2019-01-22 01:26:13.5:     but only 247 min till mid-Plan transit.
2019-01-22 01:26:13.5:     deferred (rising) for 243 min, until 22-Jan-2019 05:29:50 UTC
2019-01-22 01:26:13.5: Rising plan delay enabled for Plan QZ_Aur:
2019-01-22 01:26:13.5:     476 min. remaining to meet strict constraints.
2019-01-22 01:26:13.5:     704 min. remaining for the plan to fit before dawn.
2019-01-22 01:26:13.5:     but only 184 min till mid-Plan transit.
2019-01-22 01:26:13.5:     deferred (rising) for 184 min, until 22-Jan-2019 04:30:36 UTC
2019-01-22 01:26:13.5: Rising plan delay enabled for Plan T_Ori:
2019-01-22 01:26:13.5:     387 min. remaining to meet strict constraints.
2019-01-22 01:26:13.5:     704 min. remaining for the plan to fit before dawn.
2019-01-22 01:26:13.5:     but only 191 min till mid-Plan transit.
2019-01-22 01:26:13.5:     deferred (rising) for 191 min, until 22-Jan-2019 04:37:51 UTC
2019-01-22 01:26:13.6: Rising plan delay enabled for Plan V0603_Per:
2019-01-22 01:26:13.6:     407 min. remaining to meet strict constraints.
2019-01-22 01:26:13.6:     704 min. remaining for the plan to fit before dawn.
2019-01-22 01:26:13.6:     but only 83 min till mid-Plan transit.
2019-01-22 01:26:13.6:     deferred (rising) for 83 min, until 22-Jan-2019 02:50:04 UTC
2019-01-22 01:26:13.6: Rising plan delay enabled for Plan V0965_Per:
2019-01-22 01:26:13.6:     345 min. remaining to meet strict constraints.
2019-01-22 01:26:13.6:     704 min. remaining for the plan to fit before dawn.
2019-01-22 01:26:13.6:     but only 47 min till mid-Plan transit.
2019-01-22 01:26:13.6:     deferred (rising) for 47 min, until 22-Jan-2019 02:13:40 UTC
2019-01-22 01:26:13.7: Rising plan delay enabled for Plan WZ_Per:
2019-01-22 01:26:13.7:     386 min. remaining to meet strict constraints.
2019-01-22 01:26:13.7:     704 min. remaining for the plan to fit before dawn.
2019-01-22 01:26:13.7:     but only 77 min till mid-Plan transit.
2019-01-22 01:26:13.7:     deferred (rising) for 77 min, until 22-Jan-2019 02:43:51 UTC
2019-01-22 01:26:13.8: Dispatcher cycle time: 0.9 sec.
2019-01-22 01:26:13.8: Acquire data for Observation AF_And...
2019-01-22 01:26:13.8:   (belongs to Project AAVSO_UIS01_AF_And, Plan AF_And)
2019-01-22 01:26:15.0: ++ Auto Focus ++
2019-01-22 01:26:15.1: Doing initial autofocus.
2019-01-22 01:26:15.1: Start special ACP AutoFocus script for scheduler
2019-01-22 01:30:49.0: Next periodic autofocus ASAP after 22-Jan-2019 04:30:37 UTC
2019-01-22 01:30:49.0: Send Observation AF_And to ACP Sequencer
2019-01-22 02:02:25.3: Acquisition time: 1896.2826548 sec.
2019-01-22 02:02:25.3: Data for Observation AF_And of Plan AF_And acquired successfully.
2019-01-22 02:02:25.3: Plan AF_And completed successfully.
2019-01-22 02:02:25.3: Image Efficiency: 68.5%
2019-01-22 02:02:25.3: Cycle Efficiency: 100.0%
2019-01-22 02:02:28.8: Rising plan delay enabled for Plan SDSS073224:
2019-01-22 02:02:28.8:     551 min. remaining to meet strict constraints.
2019-01-22 02:02:28.8:     624 min. remaining for the plan to fit before dawn.
2019-01-22 02:02:28.8:     but only 249 min till mid-Plan transit.
2019-01-22 02:02:28.8:     deferred (rising) for 245 min, until 22-Jan-2019 06:07:47 UTC
2019-01-22 02:02:28.9: Rising plan delay enabled for Plan V0959_Mon:
2019-01-22 02:02:28.9:     450 min. remaining to meet strict constraints.
2019-01-22 02:02:28.9:     667 min. remaining for the plan to fit before dawn.
2019-01-22 02:02:28.9:     but only 219 min till mid-Plan transit.
2019-01-22 02:02:28.9:     deferred (rising) for 219 min, until 22-Jan-2019 05:41:29 UTC
2019-01-22 02:02:28.9: Dispatcher cycle time: 1.1 sec.
2019-01-22 02:02:28.9: Acquire data for Observation GSC_3649-0825...
2019-01-22 02:02:28.9:   (belongs to Project Staels, Plan GSC_3649-0825)
2019-01-22 02:02:29.8: Send Observation GSC_3649-0825 to ACP Sequencer
2019-01-22 02:07:51.5: Acquisition time: 321.6604503 sec.
2019-01-22 02:07:51.5: Data for Observation GSC_3649-0825 of Plan GSC_3649-0825 acquired successfully.
2019-01-22 02:07:51.5: Plan GSC_3649-0825 completed successfully.
2019-01-22 02:07:51.5: Image Efficiency: 41.8%
2019-01-22 02:07:51.5: Cycle Efficiency: 99.6%
2019-01-22 02:07:54.0: Dispatcher cycle time: 0.1 sec.
2019-01-22 02:07:54.0: Acquire data for Observation GSC_4030_204...
2019-01-22 02:07:54.0:   (belongs to Project Staels, Plan GSC_4030_204)
2019-01-22 02:07:54.9: Send Observation GSC_4030_204 to ACP Sequencer
2019-01-22 02:10:30.8: Acquisition time: 155.9002183 sec.
2019-01-22 02:10:30.8: Data for Observation GSC_4030_204 of Plan GSC_4030_204 acquired successfully.
2019-01-22 02:10:30.8: Plan GSC_4030_204 completed successfully.
2019-01-22 02:10:30.8: Image Efficiency: 19.2%
2019-01-22 02:10:30.8: Cycle Efficiency: 99.9%
2019-01-22 02:10:33.3: Dispatcher cycle time: 1.0 sec.
2019-01-22 02:10:33.3: Acquire data for Observation CM_Cas...
2019-01-22 02:10:33.3:   (belongs to Project AAVSO_HGUA_Miras_Manual_ver167, Plan CM_Cas)
2019-01-22 02:10:36.4: Send Observation CM_Cas to ACP Sequencer
2019-01-22 02:25:19.2: Acquisition time: 882.851236 sec.
2019-01-22 02:25:19.2: Data for Observation CM_Cas of Plan CM_Cas acquired successfully.
2019-01-22 02:25:19.2: Plan CM_Cas completed successfully.
2019-01-22 02:25:19.2: Image Efficiency: 44.1%
2019-01-22 02:25:19.2: Cycle Efficiency: 99.9%
2019-01-22 02:25:22.5: Dispatcher cycle time: 0.9 sec.
2019-01-22 02:25:22.5: Acquire data for Observation NGC_7790...
2019-01-22 02:25:22.5:   (belongs to Project AAVSO_Monitoring, Plan NGC_7790)
2019-01-22 02:25:23.5: Send Observation NGC_7790 to ACP Sequencer
2019-01-22 02:43:26.1: Acquisition time: 1082.6515157 sec.
2019-01-22 02:43:26.1: Data for Observation NGC_7790 of Plan NGC_7790 acquired successfully.
2019-01-22 02:43:26.2: Plan NGC_7790 completed successfully.
2019-01-22 02:43:26.2: Image Efficiency: 42.9%
2019-01-22 02:43:26.2: Cycle Efficiency: 99.9%
2019-01-22 02:43:29.9: Dispatcher cycle time: 0.1 sec.
2019-01-22 02:43:29.9: Acquire data for Observation V0965_Per...
2019-01-22 02:43:29.9:   (belongs to Project Nova_Monitoring, Plan V0965_Per)
2019-01-22 02:43:29.9: Send Observation V0965_Per to ACP Sequencer
2019-01-22 03:04:27.9: Acquisition time: 1257.9817612 sec.
2019-01-22 03:04:27.9: Data for Observation V0965_Per of Plan V0965_Per acquired successfully.
2019-01-22 03:04:27.9: Plan V0965_Per completed successfully.
2019-01-22 03:04:27.9: Image Efficiency: 71.5%
2019-01-22 03:04:27.9: Cycle Efficiency: 100.0%
2019-01-22 03:04:30.1: Dispatcher cycle time: 1.1 sec.
2019-01-22 03:04:30.1: Acquire data for Observation FY_Cam...
2019-01-22 03:04:30.1:   (belongs to Project AAVSO_HGUA_Miras_Manual_ver167, Plan FY_Cam)
2019-01-22 03:04:30.1: Send Observation FY_Cam to ACP Sequencer
2019-01-22 03:19:46.2: Acquisition time: 916.0312824 sec.
2019-01-22 03:19:46.2: Data for Observation FY_Cam of Plan FY_Cam acquired successfully.
2019-01-22 03:19:46.2: Plan FY_Cam completed successfully.
2019-01-22 03:19:46.2: Image Efficiency: 42.5%
2019-01-22 03:19:46.2: Cycle Efficiency: 99.9%
2019-01-22 03:19:47.0: Dispatcher cycle time: 0.2 sec.
2019-01-22 03:19:47.0: Acquire data for Observation V0603_Per...
2019-01-22 03:19:47.0:   (belongs to Project AAVSO_HGUA_Miras_Manual_ver167, Plan V0603_Per)
2019-01-22 03:19:48.7: Send Observation V0603_Per to ACP Sequencer
2019-01-22 03:35:43.6: Acquisition time: 954.8813368 sec.
2019-01-22 03:35:43.6: Data for Observation V0603_Per of Plan V0603_Per acquired successfully.
2019-01-22 03:35:43.6: Plan V0603_Per completed successfully.
2019-01-22 03:35:43.6: Image Efficiency: 40.8%
2019-01-22 03:35:43.6: Cycle Efficiency: 100.0%
2019-01-22 03:35:45.5: Dispatcher cycle time: 0.1 sec.
2019-01-22 03:35:45.5: Acquire data for Observation FW_Cam...
2019-01-22 03:35:45.5:   (belongs to Project AAVSO_HGUA_Miras_Manual_ver167, Plan FW_Cam)
2019-01-22 03:35:45.7: Send Observation FW_Cam to ACP Sequencer
2019-01-22 03:50:56.9: Acquisition time: 911.401276 sec.
2019-01-22 03:50:56.9: Data for Observation FW_Cam of Plan FW_Cam acquired successfully.
2019-01-22 03:50:56.9: Plan FW_Cam completed successfully.
2019-01-22 03:50:57.0: Image Efficiency: 42.8%
2019-01-22 03:50:57.0: Cycle Efficiency: 100.0%
2019-01-22 03:50:57.7: Dispatcher cycle time: 0.1 sec.
2019-01-22 03:50:57.7: Acquire data for Observation FU_Cam...
2019-01-22 03:50:57.7:   (belongs to Project AAVSO_HGUA_Miras_Manual_ver167, Plan FU_Cam)
2019-01-22 03:50:59.2: Send Observation FU_Cam to ACP Sequencer
2019-01-22 04:06:19.7: Acquisition time: 920.4612886 sec.
2019-01-22 04:06:19.7: Data for Observation FU_Cam of Plan FU_Cam acquired successfully.
2019-01-22 04:06:19.7: Plan FU_Cam completed successfully.
2019-01-22 04:06:19.7: Image Efficiency: 42.4%
2019-01-22 04:06:19.7: Cycle Efficiency: 100.0%
2019-01-22 04:06:22.0: Rising plan delay enabled for Plan CY_UMa:
2019-01-22 04:06:22.0:     714 min. remaining to meet strict constraints.
2019-01-22 04:06:22.0:     544 min. remaining for the plan to fit before dawn.
2019-01-22 04:06:22.0:     but only 351 min till mid-Plan transit.
2019-01-22 04:06:22.0:     deferred (rising) for 351 min, until 22-Jan-2019 09:58:05 UTC
2019-01-22 04:06:22.0: Rising plan delay enabled for Plan HO_Cam:
2019-01-22 04:06:22.0:     346 min. remaining to meet strict constraints.
2019-01-22 04:06:22.0:     527 min. remaining for the plan to fit before dawn.
2019-01-22 04:06:22.0:     but only 0 min till mid-Plan transit.
2019-01-22 04:06:22.0:     ending rising plan delay. Plan is now eligible.
2019-01-22 04:06:22.1: Dispatcher cycle time: 0.1 sec.
2019-01-22 04:06:22.1: Acquire data for Observation HI_Cam...
2019-01-22 04:06:22.1:   (belongs to Project AAVSO_HGUA_Miras_Manual_ver167, Plan HI_Cam)
2019-01-22 04:06:22.1: Send Observation HI_Cam to ACP Sequencer
2019-01-22 04:22:02.3: Acquisition time: 940.1613162 sec.
2019-01-22 04:22:02.3: Data for Observation HI_Cam of Plan HI_Cam acquired successfully.
2019-01-22 04:22:02.3: Plan HI_Cam completed successfully.
2019-01-22 04:22:02.3: Image Efficiency: 41.5%
2019-01-22 04:22:02.3: Cycle Efficiency: 100.0%
2019-01-22 04:22:05.9: Rising plan delay enabled for Plan HQ_Aur:
2019-01-22 04:22:05.9:     334 min. remaining to meet strict constraints.
2019-01-22 04:22:05.9:     511 min. remaining for the plan to fit before dawn.
2019-01-22 04:22:05.9:     but only 1 min till mid-Plan transit.
2019-01-22 04:22:05.9:     ending rising plan delay. Plan is now eligible.
2019-01-22 04:22:05.9: Rising plan delay enabled for Plan M67:
2019-01-22 04:22:05.9:     428 min. remaining to meet strict constraints.
2019-01-22 04:22:05.9:     528 min. remaining for the plan to fit before dawn.
2019-01-22 04:22:05.9:     but only 210 min till mid-Plan transit.
2019-01-22 04:22:05.9:     deferred (rising) for 210 min, until 22-Jan-2019 07:52:46 UTC
2019-01-22 04:22:06.0: Dispatcher cycle time: 0.2 sec.
2019-01-22 04:22:06.0: Acquire data for Observation HO_Cam...
2019-01-22 04:22:06.0:   (belongs to Project AAVSO_HGUA_Miras_Manual_ver167, Plan HO_Cam)
2019-01-22 04:22:06.0: Send Observation HO_Cam to ACP Sequencer
2019-01-22 04:36:52.4: Acquisition time: 886.3312409 sec.
2019-01-22 04:36:52.4: Data for Observation HO_Cam of Plan HO_Cam acquired successfully.
2019-01-22 04:36:52.4: Plan HO_Cam completed successfully.
2019-01-22 04:36:52.4: Image Efficiency: 44.0%
2019-01-22 04:36:52.4: Cycle Efficiency: 100.0%
2019-01-22 04:36:54.9: ++ Auto Focus ++
2019-01-22 04:36:54.9: Doing periodic autofocus.
2019-01-22 04:36:54.9: Start special ACP AutoFocus script for scheduler
2019-01-22 04:40:23.7: Next periodic autofocus ASAP after 22-Jan-2019 07:40:09 UTC
2019-01-22 04:40:24.7: Dispatcher cycle time: 0.1 sec.
2019-01-22 04:40:24.7: Acquire data for Observation QZ_Aur...
2019-01-22 04:40:24.7:   (belongs to Project AAVSO_Monitoring, Plan QZ_Aur)
2019-01-22 04:40:26.7: Send Observation QZ_Aur to ACP Sequencer
2019-01-22 05:04:43.4: ACP ERROR: Run failed for unknown reason (see ACP run log)
2019-01-22 05:04:43.4: ACP data acquisition failed for Observation QZ_Aur.
2019-01-22 05:04:43.4:   (Observation QZ_Aur interrupted by acquisition process failure.)
2019-01-22 05:04:46.2: -- Weather Unsafe --
2019-01-22 13:08:19.3: ++ Weather Safe ++
2019-01-22 13:08:20.4: ++ Observatory Startup ++
2019-01-22 13:08:20.4: Start ACP Sequencer's StartupObs script
2019-01-22 13:08:39.2: Open the observatory dome/roof
2019-01-22 13:10:01.8: Observatory startup succeeded, and dome opened successfully
2019-01-22 13:10:01.8: ++ Auto Focus ++
2019-01-22 13:10:01.8: Doing initial autofocus.
2019-01-22 13:10:01.8: Start special ACP AutoFocus script for scheduler
2019-01-22 13:14:18.2: Next periodic autofocus ASAP after 22-Jan-2019 16:14:04 UTC
2019-01-22 13:25:40.5: -- Weather Unsafe --
2019-01-22 13:25:41.7: Skipping dawn sky flats because the weather was unsafe at sky-flat time
2019-01-22 13:26:14.5: -- Observatory Shutdown --
2019-01-22 13:26:14.5: Start ACP Sequencer's ShutdownObs script
2019-01-22 13:26:14.5: WARNING: Request sent while Weather Safety Script is running
2019-01-22 13:46:08.2: Dispatcher stopped at 22-Jan-2019 13:46:08 UTC
2019-01-22 13:46:08.2: **DISPATCHER INTERRUPT LEVEL 1 RECEIVED**
2019-01-23 01:30:35.2: ++ Weather Safe ++
2019-01-23 03:41:00.3: Dispatcher started at 23-Jan-2019 03:41:00 UTC
2019-01-23 03:41:01.0: Run statistics:
2019-01-23 03:41:01.0:   Observations:   54
2019-01-23 03:41:01.0:   Considered:     31
2019-01-23 03:41:01.0:   Completed:      12
2019-01-23 03:41:01.0:   Skipped:        15
2019-01-23 03:41:01.0:   Never Eligible: 23
2019-01-23 03:41:01.0:   Failed:         4
Log closed at Wed, Jan 23 2019 03:41:01 UTC (actual time)