Log opened at Sat, Jan 12 2019 19:17:55 UTC (actual time)
Current log level is Verbose
ACP Expert Scheduler version 8.1.1
Full license: Arne Henden
2019-01-13 00:57:47.4: ++ Weather Safe ++
2019-01-13 01:09:09.8: ++ Observatory Startup ++
2019-01-13 01:09:09.8: Start ACP Sequencer's StartupObs script
2019-01-13 01:10:14.2: Open the observatory dome/roof
2019-01-13 01:11:23.1: Observatory startup succeeded, and dome opened successfully
2019-01-13 01:18:50.9: ++ Auto Focus ++
2019-01-13 01:18:50.9: Doing initial autofocus.
2019-01-13 01:18:50.9: Start special ACP AutoFocus script for scheduler
2019-01-13 01:23:08.5: Next periodic autofocus ASAP after 13-Jan-2019 04:22:53 UTC
2019-01-13 01:23:10.1: Plan RZ_Leo has Monitor Mode. Time to resubmit it.
2019-01-13 01:23:10.1: Plan V0965_Per has Monitor Mode. Time to resubmit it.
2019-01-13 01:23:10.1: Plan M67 has Monitor Mode. Time to resubmit it.
2019-01-13 01:23:10.1: Plan QZ_Aur has Monitor Mode. Time to resubmit it.
2019-01-13 01:23:10.1: Plan SDSSJ121027+502735 has Monitor Mode. Time to resubmit it.
2019-01-13 01:23:10.1: Plan Mkn_501 has Monitor Mode. Time to resubmit it.
2019-01-13 01:23:10.1: Plan AF_And has Monitor Mode. Time to resubmit it.
2019-01-13 01:23:11.0: Rising plan delay enabled for Plan FU_Cam:
2019-01-13 01:23:11.0:     448 min. remaining to meet strict constraints.
2019-01-13 01:23:11.0:     691 min. remaining for the plan to fit before dawn.
2019-01-13 01:23:11.0:     but only 89 min till mid-Plan transit.
2019-01-13 01:23:11.0:     deferred (rising) for 88 min, until 13-Jan-2019 02:51:22 UTC
2019-01-13 01:23:11.0: Rising plan delay enabled for Plan FW_Cam:
2019-01-13 01:23:11.0:     460 min. remaining to meet strict constraints.
2019-01-13 01:23:11.0:     691 min. remaining for the plan to fit before dawn.
2019-01-13 01:23:11.0:     but only 96 min till mid-Plan transit.
2019-01-13 01:23:11.0:     deferred (rising) for 95 min, until 13-Jan-2019 02:58:11 UTC
2019-01-13 01:23:11.0: Rising plan delay enabled for Plan FY_Cam:
2019-01-13 01:23:11.0:     453 min. remaining to meet strict constraints.
2019-01-13 01:23:11.0:     691 min. remaining for the plan to fit before dawn.
2019-01-13 01:23:11.0:     but only 102 min till mid-Plan transit.
2019-01-13 01:23:11.0:     deferred (rising) for 100 min, until 13-Jan-2019 03:03:54 UTC
2019-01-13 01:23:11.0: Rising plan delay enabled for Plan HO_Cam:
2019-01-13 01:23:11.0:     545 min. remaining to meet strict constraints.
2019-01-13 01:23:11.0:     691 min. remaining for the plan to fit before dawn.
2019-01-13 01:23:11.0:     but only 198 min till mid-Plan transit.
2019-01-13 01:23:11.0:     deferred (rising) for 196 min, until 13-Jan-2019 04:40:08 UTC
2019-01-13 01:23:11.1: Rising plan delay enabled for Plan HQ_Aur:
2019-01-13 01:23:11.1:     548 min. remaining to meet strict constraints.
2019-01-13 01:23:11.1:     691 min. remaining for the plan to fit before dawn.
2019-01-13 01:23:11.1:     but only 215 min till mid-Plan transit.
2019-01-13 01:23:11.1:     deferred (rising) for 213 min, until 13-Jan-2019 04:57:07 UTC
2019-01-13 01:23:11.1: Rising plan delay enabled for Plan QZ_Aur:
2019-01-13 01:23:11.1:     514 min. remaining to meet strict constraints.
2019-01-13 01:23:11.1:     708 min. remaining for the plan to fit before dawn.
2019-01-13 01:23:11.1:     but only 222 min till mid-Plan transit.
2019-01-13 01:23:11.1:     deferred (rising) for 222 min, until 13-Jan-2019 05:05:59 UTC
2019-01-13 01:23:11.2: Rising plan delay enabled for Plan V0603_Per:
2019-01-13 01:23:11.2:     445 min. remaining to meet strict constraints.
2019-01-13 01:23:11.2:     691 min. remaining for the plan to fit before dawn.
2019-01-13 01:23:11.2:     but only 113 min till mid-Plan transit.
2019-01-13 01:23:11.2:     deferred (rising) for 112 min, until 13-Jan-2019 03:15:14 UTC
2019-01-13 01:23:11.2: Rising plan delay enabled for Plan V0965_Per:
2019-01-13 01:23:11.2:     384 min. remaining to meet strict constraints.
2019-01-13 01:23:11.2:     708 min. remaining for the plan to fit before dawn.
2019-01-13 01:23:11.2:     but only 85 min till mid-Plan transit.
2019-01-13 01:23:11.2:     deferred (rising) for 85 min, until 13-Jan-2019 02:49:04 UTC
2019-01-13 01:23:11.3: Rising plan delay enabled for Plan WZ_Per:
2019-01-13 01:23:11.3:     424 min. remaining to meet strict constraints.
2019-01-13 01:23:11.3:     691 min. remaining for the plan to fit before dawn.
2019-01-13 01:23:11.3:     but only 107 min till mid-Plan transit.
2019-01-13 01:23:11.3:     deferred (rising) for 105 min, until 13-Jan-2019 03:09:01 UTC
2019-01-13 01:23:11.4: Dispatcher cycle time: 1.3 sec.
2019-01-13 01:23:11.4: Acquire data for Observation AF_And...
2019-01-13 01:23:11.4:   (belongs to Project AAVSO_UIS01_AF_And, Plan AF_And)
2019-01-13 01:23:12.0: Send Observation AF_And to ACP Sequencer
2019-01-13 01:54:01.4: Acquisition time: 1849.3325891 sec.
2019-01-13 01:54:01.4: Data for Observation AF_And of Plan AF_And acquired successfully.
2019-01-13 01:54:01.4: Plan AF_And completed successfully.
2019-01-13 01:54:01.4: Image Efficiency: 70.2%
2019-01-13 01:54:01.4: Cycle Efficiency: 99.9%
2019-01-13 01:54:03.8: Rising plan delay enabled for Plan CB_32:
2019-01-13 01:54:03.8:     413 min. remaining to meet strict constraints.
2019-01-13 01:54:03.8:     677 min. remaining for the plan to fit before dawn.
2019-01-13 01:54:03.8:     but only 199 min till mid-Plan transit.
2019-01-13 01:54:03.8:     deferred (rising) for 199 min, until 13-Jan-2019 05:13:55 UTC
2019-01-13 01:54:03.8: Rising plan delay enabled for Plan DN_Lyn:
2019-01-13 01:54:03.8:     631 min. remaining to meet strict constraints.
2019-01-13 01:54:03.8:     660 min. remaining for the plan to fit before dawn.
2019-01-13 01:54:03.8:     but only 306 min till mid-Plan transit.
2019-01-13 01:54:03.8:     deferred (rising) for 304 min, until 13-Jan-2019 06:58:33 UTC
2019-01-13 01:54:03.9: Dispatcher cycle time: 0.2 sec.
2019-01-13 01:54:03.9: Acquire data for Observation GSC_3649-0825...
2019-01-13 01:54:03.9:   (belongs to Project Staels, Plan GSC_3649-0825)
2019-01-13 01:54:04.4: Send Observation GSC_3649-0825 to ACP Sequencer
2019-01-13 01:59:18.9: Acquisition time: 314.5104403 sec.
2019-01-13 01:59:18.9: Data for Observation GSC_3649-0825 of Plan GSC_3649-0825 acquired successfully.
2019-01-13 01:59:18.9: Plan GSC_3649-0825 completed successfully.
2019-01-13 01:59:18.9: Image Efficiency: 42.9%
2019-01-13 01:59:18.9: Cycle Efficiency: 99.9%
2019-01-13 01:59:22.5: Rising plan delay enabled for Plan T_Ori:
2019-01-13 01:59:22.5:     390 min. remaining to meet strict constraints.
2019-01-13 01:59:22.5:     672 min. remaining for the plan to fit before dawn.
2019-01-13 01:59:22.5:     but only 193 min till mid-Plan transit.
2019-01-13 01:59:22.5:     deferred (rising) for 193 min, until 13-Jan-2019 05:13:14 UTC
2019-01-13 01:59:22.5: Dispatcher cycle time: 0.1 sec.
2019-01-13 01:59:22.5: Acquire data for Observation GSC_4030_204...
2019-01-13 01:59:22.5:   (belongs to Project Staels, Plan GSC_4030_204)
2019-01-13 01:59:22.6: Send Observation GSC_4030_204 to ACP Sequencer
2019-01-13 02:01:50.1: Acquisition time: 147.5602066 sec.
2019-01-13 02:01:50.1: Data for Observation GSC_4030_204 of Plan GSC_4030_204 acquired successfully.
2019-01-13 02:01:50.1: Plan GSC_4030_204 completed successfully.
2019-01-13 02:01:50.1: Image Efficiency: 20.3%
2019-01-13 02:01:50.1: Cycle Efficiency: 99.9%
2019-01-13 02:01:52.6: Rising plan delay enabled for Plan MP_Gem:
2019-01-13 02:01:52.6:     528 min. remaining to meet strict constraints.
2019-01-13 02:01:52.6:     635 min. remaining for the plan to fit before dawn.
2019-01-13 02:01:52.6:     but only 246 min till mid-Plan transit.
2019-01-13 02:01:52.6:     deferred (rising) for 243 min, until 13-Jan-2019 06:05:14 UTC
2019-01-13 02:01:52.7: Dispatcher cycle time: 1.0 sec.
2019-01-13 02:01:52.7: Acquire data for Observation NGC_7790...
2019-01-13 02:01:52.7:   (belongs to Project AAVSO_Monitoring, Plan NGC_7790)
2019-01-13 02:01:55.5: Send Observation NGC_7790 to ACP Sequencer
2019-01-13 02:19:03.9: Acquisition time: 1028.3514397 sec.
2019-01-13 02:19:03.9: Data for Observation NGC_7790 of Plan NGC_7790 acquired successfully.
2019-01-13 02:19:03.9: Plan NGC_7790 completed successfully.
2019-01-13 02:19:03.9: Image Efficiency: 45.2%
2019-01-13 02:19:03.9: Cycle Efficiency: 99.9%
2019-01-13 02:19:04.5: Dispatcher cycle time: 0.1 sec.
2019-01-13 02:19:04.5: Acquire data for Observation V0809_Cep...
2019-01-13 02:19:04.5:   (belongs to Project Nova_Monitoring, Plan V0809_Cep)
2019-01-13 02:19:06.0: Send Observation V0809_Cep to ACP Sequencer
2019-01-13 02:49:52.4: Acquisition time: 1846.6025852 sec.
2019-01-13 02:49:52.4: Data for Observation V0809_Cep of Plan V0809_Cep acquired successfully.
2019-01-13 02:49:52.4: Plan V0809_Cep completed successfully.
2019-01-13 02:49:52.4: Image Efficiency: 75.8%
2019-01-13 02:49:52.4: Cycle Efficiency: 100.0%
2019-01-13 02:49:55.0: Rising plan delay enabled for Plan SDSS073224:
2019-01-13 02:49:55.0:     539 min. remaining to meet strict constraints.
2019-01-13 02:49:55.0:     578 min. remaining for the plan to fit before dawn.
2019-01-13 02:49:55.0:     but only 237 min till mid-Plan transit.
2019-01-13 02:49:55.0:     deferred (rising) for 233 min, until 13-Jan-2019 06:43:10 UTC
2019-01-13 02:49:55.0: Rising plan delay enabled for Plan V0959_Mon:
2019-01-13 02:49:55.0:     438 min. remaining to meet strict constraints.
2019-01-13 02:49:55.0:     622 min. remaining for the plan to fit before dawn.
2019-01-13 02:49:55.0:     but only 206 min till mid-Plan transit.
2019-01-13 02:49:55.0:     deferred (rising) for 206 min, until 13-Jan-2019 06:16:52 UTC
2019-01-13 02:49:55.1: Dispatcher cycle time: 0.1 sec.
2019-01-13 02:49:55.1: Acquire data for Observation CM_Cas...
2019-01-13 02:49:55.1:   (belongs to Project AAVSO_HGUA_Miras_Manual_ver167, Plan CM_Cas)
2019-01-13 02:49:55.1: Send Observation CM_Cas to ACP Sequencer
2019-01-13 03:06:09.5: Acquisition time: 974.3913641 sec.
2019-01-13 03:06:09.5: Data for Observation CM_Cas of Plan CM_Cas acquired successfully.
2019-01-13 03:06:09.5: Plan CM_Cas completed successfully.
2019-01-13 03:06:09.5: Image Efficiency: 40.0%
2019-01-13 03:06:09.5: Cycle Efficiency: 100.0%
2019-01-13 03:06:10.5: Dispatcher cycle time: 0.1 sec.
2019-01-13 03:06:10.5: Acquire data for Observation V0965_Per...
2019-01-13 03:06:10.5:   (belongs to Project Nova_Monitoring, Plan V0965_Per)
2019-01-13 03:06:12.2: Send Observation V0965_Per to ACP Sequencer
2019-01-13 03:26:53.6: Acquisition time: 1241.4017379 sec.
2019-01-13 03:26:53.6: Data for Observation V0965_Per of Plan V0965_Per acquired successfully.
2019-01-13 03:26:53.6: Plan V0965_Per completed successfully.
2019-01-13 03:26:53.6: Image Efficiency: 72.5%
2019-01-13 03:26:53.6: Cycle Efficiency: 100.0%
2019-01-13 03:26:56.0: Dispatcher cycle time: 0.1 sec.
2019-01-13 03:26:56.0: Acquire data for Observation V0603_Per...
2019-01-13 03:26:56.0:   (belongs to Project AAVSO_HGUA_Miras_Manual_ver167, Plan V0603_Per)
2019-01-13 03:26:56.0: Send Observation V0603_Per to ACP Sequencer
2019-01-13 03:42:11.8: Acquisition time: 915.8012821 sec.
2019-01-13 03:42:11.8: Data for Observation V0603_Per of Plan V0603_Per acquired successfully.
2019-01-13 03:42:11.8: Plan V0603_Per completed successfully.
2019-01-13 03:42:11.9: Image Efficiency: 42.6%
2019-01-13 03:42:11.9: Cycle Efficiency: 100.0%
2019-01-13 03:42:14.4: Dispatcher cycle time: 0.1 sec.
2019-01-13 03:42:14.4: Acquire data for Observation FY_Cam...
2019-01-13 03:42:14.4:   (belongs to Project AAVSO_HGUA_Miras_Manual_ver167, Plan FY_Cam)
2019-01-13 03:42:14.5: Send Observation FY_Cam to ACP Sequencer
2019-01-13 03:57:33.5: Acquisition time: 919.0012866 sec.
2019-01-13 03:57:33.5: Data for Observation FY_Cam of Plan FY_Cam acquired successfully.
2019-01-13 03:57:33.5: Plan FY_Cam completed successfully.
2019-01-13 03:57:33.5: Image Efficiency: 42.4%
2019-01-13 03:57:33.5: Cycle Efficiency: 100.0%
2019-01-13 03:57:37.2: Dispatcher cycle time: 0.1 sec.
2019-01-13 03:57:37.2: Acquire data for Observation FW_Cam...
2019-01-13 03:57:37.2:   (belongs to Project AAVSO_HGUA_Miras_Manual_ver167, Plan FW_Cam)
2019-01-13 03:57:37.2: Send Observation FW_Cam to ACP Sequencer
2019-01-13 04:12:31.3: Acquisition time: 894.0612517 sec.
2019-01-13 04:12:31.3: Data for Observation FW_Cam of Plan FW_Cam acquired successfully.
2019-01-13 04:12:31.3: Plan FW_Cam completed successfully.
2019-01-13 04:12:31.3: Image Efficiency: 43.6%
2019-01-13 04:12:31.3: Cycle Efficiency: 100.0%
2019-01-13 04:12:33.8: Dispatcher cycle time: 0.1 sec.
2019-01-13 04:12:33.8: Acquire data for Observation FU_Cam...
2019-01-13 04:12:33.8:   (belongs to Project AAVSO_HGUA_Miras_Manual_ver167, Plan FU_Cam)
2019-01-13 04:12:34.7: Send Observation FU_Cam to ACP Sequencer
2019-01-13 04:28:01.3: Acquisition time: 926.5612972 sec.
2019-01-13 04:28:01.3: Data for Observation FU_Cam of Plan FU_Cam acquired successfully.
2019-01-13 04:28:01.3: Plan FU_Cam completed successfully.
2019-01-13 04:28:01.3: Image Efficiency: 42.1%
2019-01-13 04:28:01.3: Cycle Efficiency: 100.0%
2019-01-13 04:28:03.9: ++ Auto Focus ++
2019-01-13 04:28:03.9: Doing periodic autofocus.
2019-01-13 04:28:03.9: Start special ACP AutoFocus script for scheduler
2019-01-13 04:31:29.1: Next periodic autofocus ASAP after 13-Jan-2019 07:31:14 UTC
2019-01-13 04:31:29.4: Dispatcher cycle time: 0.1 sec.
2019-01-13 04:31:29.4: Acquire data for Observation WZ_Per...
2019-01-13 04:31:29.4:   (belongs to Project AAVSO_HGUA_Miras_Manual_ver167, Plan WZ_Per)
2019-01-13 04:31:30.1: Send Observation WZ_Per to ACP Sequencer
2019-01-13 04:34:45.7: ACP ERROR: Run failed for unknown reason (see ACP run log)
2019-01-13 04:34:46.6: ACP data acquisition failed for Observation WZ_Per.
2019-01-13 04:34:46.6:   (Observation WZ_Per interrupted by acquisition process failure.)
2019-01-13 04:34:48.2: -- Weather Unsafe --
2019-01-13 12:37:30.5: Dispatcher stopped at 13-Jan-2019 12:37:30 UTC
2019-01-13 12:37:30.5: **DISPATCHER INTERRUPT LEVEL 1 RECEIVED**
2019-01-14 06:02:00.4: ++ Weather Safe ++
2019-01-14 13:54:33.5: -- Weather Unsafe --
2019-01-15 00:51:44.1: ++ Weather Safe ++
2019-01-15 14:01:03.5: -- Weather Unsafe --
2019-01-16 00:55:46.5: ++ Weather Safe ++
2019-01-16 12:18:03.9: -- Weather Unsafe --
2019-01-17 02:36:27.7: ++ Weather Safe ++
2019-01-17 13:59:55.4: -- Weather Unsafe --
2019-01-18 01:03:00.1: ++ Weather Safe ++
2019-01-18 06:32:48.7: -- Weather Unsafe --
2019-01-18 07:38:14.8: ++ Weather Safe ++
2019-01-18 08:11:45.6: -- Weather Unsafe --
2019-01-18 13:38:15.5: ++ Weather Safe ++
2019-01-18 13:46:00.5: -- Weather Unsafe --
2019-01-19 04:15:10.7: ++ Weather Safe ++
2019-01-19 12:12:58.2: -- Weather Unsafe --
2019-01-20 01:12:49.1: ++ Weather Safe ++
2019-01-20 13:53:14.2: -- Weather Unsafe --
2019-01-21 01:00:37.4: ++ Weather Safe ++
2019-01-21 14:01:40.2: -- Weather Unsafe --
2019-01-22 01:00:16.6: Dispatcher started at 22-Jan-2019 01:00:16 UTC
2019-01-22 01:00:18.0: Run statistics:
2019-01-22 01:00:18.0:   Observations:   54
2019-01-22 01:00:18.0:   Considered:     30
2019-01-22 01:00:18.0:   Completed:      15
2019-01-22 01:00:18.0:   Skipped:        11
2019-01-22 01:00:18.0:   Never Eligible: 24
2019-01-22 01:00:18.0:   Failed:         4
Log closed at Tue, Jan 22 2019 01:00:18 UTC (actual time)