Log opened at Wed, Jan 24 2018 00:23:15 UTC (actual time) Current log level is Debug Scheduler version 3.5.3 Multi-user license: Arne Henden 24-Jan-2018 00:23:15.0: Simulated clock initialized at 24-Jan-2018 00:23:15 UTC 24-Jan-2018 00:23:15.1: Loaded Constraint plugin AirMass 24-Jan-2018 00:23:15.1: Loaded Constraint plugin AirmassRange 24-Jan-2018 00:23:15.1: Loaded Constraint plugin Horizon 24-Jan-2018 00:23:15.1: Loaded Constraint plugin HourAngle 24-Jan-2018 00:23:15.1: Loaded Constraint plugin MoonAvoid 24-Jan-2018 00:23:15.1: Loaded Constraint plugin MoonDown 24-Jan-2018 00:23:15.1: Loaded Constraint plugin SkyCondition 24-Jan-2018 00:23:15.1: Loaded Constraint plugin TimeRange 24-Jan-2018 00:23:15.7: 27 plans are now pending. 24-Jan-2018 00:23:15.7: Attach ACP sequencer 24-Jan-2018 00:23:40.6: Weather successfully connected. Wait for valid data... 24-Jan-2018 00:23:46.8: Dispatcher started at 24-Jan-2018 00:23:46 UTC 24-Jan-2018 00:23:46.8: Priority: W[0]=0.00 24-Jan-2018 00:23:46.8: Transit Altitude: W[1]=0.70 24-Jan-2018 00:23:46.8: Highest Altitude: W[6]=0.00 24-Jan-2018 00:23:46.8: Lateness: W[5]=0.00 24-Jan-2018 00:23:46.8: Slew Distance: W[2]=0.00 24-Jan-2018 00:23:46.8: Retry Count: W[3]=0.20 24-Jan-2018 00:23:46.8: Meridian Crossing: W[4]=0.00 24-Jan-2018 00:23:46.8: Obs Conditions: W[7]=0.40 24-Jan-2018 00:23:46.8: Rising Plan Delay: disabled 24-Jan-2018 00:28:19.3: ++ Observatory Startup ++ 24-Jan-2018 00:28:19.3: Start ACP Sequencer's StartupObs script 24-Jan-2018 00:28:29.3: Sequencer is now active 24-Jan-2018 00:28:34.3: Sequencer is no longer active 24-Jan-2018 00:28:34.4: Open the observatory dome/roof 24-Jan-2018 01:28:22.2: ++ Auto Focus ++ 24-Jan-2018 01:28:22.2: Doing initial autofocus. 24-Jan-2018 01:28:22.2: Start special ACP AutoFocus script for scheduler 24-Jan-2018 01:28:32.2: Sequencer is now active 24-Jan-2018 01:31:41.1: Sequencer is no longer active 24-Jan-2018 01:31:41.1: Next periodic autofocus ASAP after 24-Jan-2018 04:31:27 UTC 24-Jan-2018 01:31:41.7: Plan V0959_Mon has Monitor Mode. Time to resubmit it. 24-Jan-2018 01:31:41.7: Plan MP_Gem has Monitor Mode. Time to resubmit it. 24-Jan-2018 01:31:41.7: Plan CB_32 has Monitor Mode. Time to resubmit it. 24-Jan-2018 01:31:41.7: Plan SDSS073224 has Monitor Mode. Time to resubmit it. 24-Jan-2018 01:31:41.7: Plan SDSSJ121027+502735 has Monitor Mode. Time to resubmit it. 24-Jan-2018 01:31:41.7: Plan M67 has Monitor Mode. Time to resubmit it. 24-Jan-2018 01:31:41.8: Plan HQ_Aur has Monitor Mode. Time to resubmit it. 24-Jan-2018 01:31:41.8: Plan HX_Aur has Monitor Mode. Time to resubmit it. 24-Jan-2018 01:31:41.8: Plan AL_Boo has Monitor Mode. Time to resubmit it. 24-Jan-2018 01:31:41.8: Plan FU_Cam has Monitor Mode. Time to resubmit it. 24-Jan-2018 01:31:41.8: Plan FW_Cam has Monitor Mode. Time to resubmit it. 24-Jan-2018 01:31:41.8: Plan FY_Cam has Monitor Mode. Time to resubmit it. 24-Jan-2018 01:31:41.8: Plan HI_Cam has Monitor Mode. Time to resubmit it. 24-Jan-2018 01:31:41.8: Plan HO_Cam has Monitor Mode. Time to resubmit it. 24-Jan-2018 01:31:41.8: Plan V1015_Her has Monitor Mode. Time to resubmit it. 24-Jan-2018 01:31:41.8: Plan DN_Lyn has Monitor Mode. Time to resubmit it. 24-Jan-2018 01:31:41.8: Plan T_Ori has Monitor Mode. Time to resubmit it. 24-Jan-2018 01:31:41.8: Plan V0336_Ser has Monitor Mode. Time to resubmit it. 24-Jan-2018 01:31:41.8: Plan KK_UMa has Monitor Mode. Time to resubmit it. 24-Jan-2018 01:31:41.8: Plan CV_Vir has Monitor Mode. Time to resubmit it. 24-Jan-2018 01:31:41.8: Plan Mkn_501 has Monitor Mode. Time to resubmit it. 24-Jan-2018 01:31:41.8: SelectRunning: No running plans 24-Jan-2018 01:31:41.8: Obs 2MASS_J19395313+0557550 in Plan 2MASS_J19395313+0557550 was vetoed by AirmassRange constraint. 24-Jan-2018 01:31:41.8: AirmassRange estimates ready after 24-Jan-2018 14:38:34 UTC 24-Jan-2018 01:31:41.8: Obs AL_Boo in Plan AL_Boo was vetoed by AirmassRange constraint. 24-Jan-2018 01:31:41.8: AirmassRange estimates ready after 24-Jan-2018 08:07:58 UTC 24-Jan-2018 01:31:41.8: Obs CV_Vir in Plan CV_Vir was vetoed by AirmassRange constraint. 24-Jan-2018 01:31:41.8: AirmassRange estimates ready after 24-Jan-2018 07:14:43 UTC 24-Jan-2018 01:31:41.9: Obs IRAS_19067+2158 in Plan IRAS_19067+2158 was vetoed by AirmassRange constraint. 24-Jan-2018 01:31:41.9: AirmassRange estimates ready after 24-Jan-2018 13:29:59 UTC 24-Jan-2018 01:31:41.9: Obs IRAS_19183+2339 in Plan IRAS_19183+2339 was vetoed by AirmassRange constraint. 24-Jan-2018 01:31:41.9: AirmassRange estimates ready after 24-Jan-2018 13:38:03 UTC 24-Jan-2018 01:31:41.9: Obs KK_UMa in Plan KK_UMa was vetoed by AirmassRange constraint. 24-Jan-2018 01:31:41.9: AirmassRange estimates ready after 24-Jan-2018 04:47:49 UTC 24-Jan-2018 01:31:41.9: Obs M56 in Plan M56 was vetoed by AirmassRange constraint. 24-Jan-2018 01:31:41.9: AirmassRange estimates ready after 24-Jan-2018 13:21:40 UTC 24-Jan-2018 01:31:41.9: Obs M67 in Plan M67 was vetoed by AirMass constraint. 24-Jan-2018 01:31:41.9: AirMass estimates ready after 24-Jan-2018 04:05:38 UTC 24-Jan-2018 01:31:42.0: Obs Mkn_501 in Plan Mkn_501 was vetoed by AirMass constraint. 24-Jan-2018 01:31:42.0: AirMass estimates ready after 24-Jan-2018 10:41:53 UTC 24-Jan-2018 01:31:42.0: Obs NSVS_11201899 in Plan NSVS_11201899 was vetoed by AirmassRange constraint. 24-Jan-2018 01:31:42.0: AirmassRange estimates ready after 24-Jan-2018 13:31:00 UTC 24-Jan-2018 01:31:42.0: Obs SDSS073224 in Plan SDSS073224 was vetoed by AirMass constraint. 24-Jan-2018 01:31:42.0: AirMass estimates ready after 24-Jan-2018 01:45:37 UTC 24-Jan-2018 01:31:42.0: Obs SDSSJ121027+502735 in Plan SDSSJ121027+502735 was vetoed by AirMass constraint. 24-Jan-2018 01:31:42.0: AirMass estimates ready after 24-Jan-2018 05:40:27 UTC 24-Jan-2018 01:31:42.0: Obs U_Aqr in Plan U_Aqr was vetoed by AirmassRange constraint. 24-Jan-2018 01:31:42.0: AirmassRange estimates ready after 24-Jan-2018 18:28:21 UTC 24-Jan-2018 01:31:42.0: Obs V0336_Ser in Plan V0336_Ser was vetoed by AirmassRange constraint. 24-Jan-2018 01:31:42.0: AirmassRange estimates ready after 24-Jan-2018 10:25:48 UTC 24-Jan-2018 01:31:42.0: Obs V0959_Mon in Plan V0959_Mon was vetoed by AirmassRange constraint. 24-Jan-2018 01:31:42.0: AirmassRange estimates ready after 24-Jan-2018 01:40:39 UTC 24-Jan-2018 01:31:42.1: Obs V1015_Her in Plan V1015_Her was vetoed by AirmassRange constraint. 24-Jan-2018 01:31:42.1: AirmassRange estimates ready after 24-Jan-2018 11:50:17 UTC 24-Jan-2018 01:31:42.1: Obs V1721_Aql in Plan V1721_Aql was vetoed by AirmassRange constraint. 24-Jan-2018 01:31:42.1: AirmassRange estimates ready after 24-Jan-2018 14:02:11 UTC 24-Jan-2018 01:31:42.1: Obs V1722_Aql in Plan V1722_Aql was vetoed by AirmassRange constraint. 24-Jan-2018 01:31:42.1: AirmassRange estimates ready after 24-Jan-2018 13:49:53 UTC 24-Jan-2018 01:31:42.1: Obs V1723_Aql in Plan V1723_Aql was vetoed by AirmassRange constraint. 24-Jan-2018 01:31:42.1: AirmassRange estimates ready after 24-Jan-2018 14:16:15 UTC 24-Jan-2018 01:31:42.1: Obs V1724_Aql in Plan V1724_Aql was vetoed by AirmassRange constraint. 24-Jan-2018 01:31:42.1: AirmassRange estimates ready after 24-Jan-2018 14:09:42 UTC 24-Jan-2018 01:31:42.1: Obs V1904_Cyg in Plan V1904_Cyg was vetoed by AirmassRange constraint. 24-Jan-2018 01:31:42.1: AirmassRange estimates ready after 24-Jan-2018 15:22:23 UTC 24-Jan-2018 01:31:42.1: Obs V1907_Cyg in Plan V1907_Cyg was vetoed by AirmassRange constraint. 24-Jan-2018 01:31:42.1: AirmassRange estimates ready after 24-Jan-2018 15:19:03 UTC 24-Jan-2018 01:31:42.1: Obs V2481_Cyg in Plan V2481_Cyg was vetoed by AirmassRange constraint. 24-Jan-2018 01:31:42.1: AirmassRange estimates ready after 24-Jan-2018 14:55:01 UTC 24-Jan-2018 01:31:42.1: Obs V339_Del_TMO61 in Plan V339_Del_TMO61 was vetoed by AirmassRange constraint. 24-Jan-2018 01:31:42.1: AirmassRange estimates ready after 24-Jan-2018 14:47:04 UTC 24-Jan-2018 01:31:42.1: Obs V841_Oph in Plan V841_Oph was vetoed by AirmassRange constraint. 24-Jan-2018 01:31:42.1: AirmassRange estimates ready after 24-Jan-2018 13:05:30 UTC 24-Jan-2018 01:31:42.4: Efficiency: Selecting from 23 pending plans: 24-Jan-2018 01:31:42.6: Selected Plan CM_Cas (TotEff=0.89) 24-Jan-2018 01:31:42.7: Dispatcher cycle time: 1.57309 sec. 24-Jan-2018 01:31:42.7: Acquire data for Observation CM_Cas... 24-Jan-2018 01:31:42.7: (belongs to Project AAVSO_HGUA_Miras_Manual_ver167, Plan CM_Cas) 24-Jan-2018 01:31:42.7: Send Observation CM_Cas to ACP Sequencer 24-Jan-2018 01:31:52.7: Sequencer is now active 24-Jan-2018 01:45:26.2: Sequencer is no longer active 24-Jan-2018 01:45:26.2: Post-job status check done (stat=Completed) 24-Jan-2018 01:45:26.2: Acquisition time: 823.5691054 sec. 24-Jan-2018 01:45:26.2: Data for Observation CM_Cas acquired successfully. 24-Jan-2018 01:45:26.3: Image Efficiency: 47.3% 24-Jan-2018 01:45:26.3: Cycle Efficiency: 99.8% 24-Jan-2018 01:45:27.0: SelectRunning: No running plans 24-Jan-2018 01:45:27.1: Efficiency: Selecting from 23 pending plans: 24-Jan-2018 01:45:27.6: Selected Plan FU_Cam (TotEff=0.90) 24-Jan-2018 01:45:27.6: Dispatcher cycle time: 0.8720499 sec. 24-Jan-2018 01:45:27.6: Acquire data for Observation FU_Cam... 24-Jan-2018 01:45:27.6: (belongs to Project AAVSO_HGUA_Miras_Manual_ver167, Plan FU_Cam) 24-Jan-2018 01:45:27.7: Send Observation FU_Cam to ACP Sequencer 24-Jan-2018 01:45:37.7: Sequencer is now active 24-Jan-2018 01:59:17.5: Sequencer is no longer active 24-Jan-2018 01:59:17.5: Post-job status check done (stat=Completed) 24-Jan-2018 01:59:17.5: Acquisition time: 829.8744661 sec. 24-Jan-2018 01:59:17.5: Data for Observation FU_Cam acquired successfully. 24-Jan-2018 01:59:17.6: Image Efficiency: 46.9% 24-Jan-2018 01:59:17.6: Cycle Efficiency: 99.9% 24-Jan-2018 01:59:18.0: SelectRunning: No running plans 24-Jan-2018 01:59:18.2: Efficiency: Selecting from 23 pending plans: 24-Jan-2018 01:59:18.3: Selected Plan V0965_Per (TotEff=0.90) 24-Jan-2018 01:59:18.4: Dispatcher cycle time: 0.4010229 sec. 24-Jan-2018 01:59:18.4: Acquire data for Observation V0965_Per... 24-Jan-2018 01:59:18.4: (belongs to Project Nova_Monitoring, Plan V0965_Per) 24-Jan-2018 01:59:18.4: Send Observation V0965_Per to ACP Sequencer 24-Jan-2018 01:59:28.4: Sequencer is now active 24-Jan-2018 02:18:56.7: Sequencer is no longer active 24-Jan-2018 02:18:56.7: Post-job status check done (stat=Completed) 24-Jan-2018 02:18:56.7: Acquisition time: 1178.3483977 sec. 24-Jan-2018 02:18:56.7: Data for Observation V0965_Per acquired successfully. 24-Jan-2018 02:18:56.8: Image Efficiency: 76.4% 24-Jan-2018 02:18:56.8: Cycle Efficiency: 100.0% 24-Jan-2018 02:18:57.0: SelectRunning: No running plans 24-Jan-2018 02:18:57.1: Efficiency: Selecting from 22 pending plans: 24-Jan-2018 02:18:57.3: Selected Plan FW_Cam (TotEff=0.90) 24-Jan-2018 02:18:57.4: Dispatcher cycle time: 0.4000229 sec. 24-Jan-2018 02:18:57.4: Acquire data for Observation FW_Cam... 24-Jan-2018 02:18:57.4: (belongs to Project AAVSO_HGUA_Miras_Manual_ver167, Plan FW_Cam) 24-Jan-2018 02:18:57.4: Send Observation FW_Cam to ACP Sequencer 24-Jan-2018 02:19:07.4: Sequencer is now active 24-Jan-2018 02:32:33.7: Sequencer is no longer active 24-Jan-2018 02:32:33.7: Post-job status check done (stat=Completed) 24-Jan-2018 02:32:33.7: Acquisition time: 816.3546929 sec. 24-Jan-2018 02:32:33.7: Data for Observation FW_Cam acquired successfully. 24-Jan-2018 02:32:33.8: Image Efficiency: 47.7% 24-Jan-2018 02:32:33.8: Cycle Efficiency: 99.9% 24-Jan-2018 02:32:34.1: SelectRunning: No running plans 24-Jan-2018 02:32:34.1: STRICT VETO: 24-Jan-2018 02:32:34.1: Obs BC_Lac in Plan BC_Lac was vetoed by AirmassRange constraint. 24-Jan-2018 02:32:34.1: AirmassRange estimates ready after 24-Jan-2018 16:14:48 UTC 24-Jan-2018 02:32:34.3: Efficiency: Selecting from 20 pending plans: 24-Jan-2018 02:32:34.4: Selected Plan FY_Cam (TotEff=0.90) 24-Jan-2018 02:32:34.5: Dispatcher cycle time: 0.3650209 sec. 24-Jan-2018 02:32:34.5: Acquire data for Observation FY_Cam... 24-Jan-2018 02:32:34.5: (belongs to Project AAVSO_HGUA_Miras_Manual_ver167, Plan FY_Cam) 24-Jan-2018 02:32:34.5: Send Observation FY_Cam to ACP Sequencer 24-Jan-2018 02:32:44.5: Sequencer is now active 24-Jan-2018 02:46:02.8: Sequencer is no longer active 24-Jan-2018 02:46:02.8: Post-job status check done (stat=Completed) 24-Jan-2018 02:46:02.8: Acquisition time: 808.3462348 sec. 24-Jan-2018 02:46:02.8: Data for Observation FY_Cam acquired successfully. 24-Jan-2018 02:46:02.9: Image Efficiency: 48.2% 24-Jan-2018 02:46:02.9: Cycle Efficiency: 100.0% 24-Jan-2018 02:46:03.8: SelectRunning: No running plans 24-Jan-2018 02:46:03.9: Efficiency: Selecting from 19 pending plans: 24-Jan-2018 02:46:04.0: Selected Plan V0603_Per (TotEff=0.90) 24-Jan-2018 02:46:04.1: Dispatcher cycle time: 0.3390194 sec. 24-Jan-2018 02:46:04.1: Acquire data for Observation V0603_Per... 24-Jan-2018 02:46:04.1: (belongs to Project AAVSO_HGUA_Miras_Manual_ver167, Plan V0603_Per) 24-Jan-2018 02:46:04.1: Send Observation V0603_Per to ACP Sequencer 24-Jan-2018 02:46:14.1: Sequencer is now active 24-Jan-2018 02:59:39.6: Sequencer is no longer active 24-Jan-2018 02:59:39.6: Post-job status check done (stat=Completed) 24-Jan-2018 02:59:39.6: Acquisition time: 815.4746425 sec. 24-Jan-2018 02:59:39.6: Data for Observation V0603_Per acquired successfully. 24-Jan-2018 02:59:39.6: Image Efficiency: 47.8% 24-Jan-2018 02:59:39.6: Cycle Efficiency: 100.0% 24-Jan-2018 02:59:39.9: SelectRunning: No running plans 24-Jan-2018 02:59:40.0: Efficiency: Selecting from 18 pending plans: 24-Jan-2018 02:59:40.1: Selected Plan HI_Cam (TotEff=0.90) 24-Jan-2018 02:59:40.2: Dispatcher cycle time: 0.3130179 sec. 24-Jan-2018 02:59:40.2: Acquire data for Observation HI_Cam... 24-Jan-2018 02:59:40.2: (belongs to Project AAVSO_HGUA_Miras_Manual_ver167, Plan HI_Cam) 24-Jan-2018 02:59:40.2: Send Observation HI_Cam to ACP Sequencer 24-Jan-2018 02:59:50.2: Sequencer is now active 24-Jan-2018 03:13:18.7: Sequencer is no longer active 24-Jan-2018 03:13:18.7: Post-job status check done (stat=Completed) 24-Jan-2018 03:13:18.7: Acquisition time: 818.4838146 sec. 24-Jan-2018 03:13:18.7: Data for Observation HI_Cam acquired successfully. 24-Jan-2018 03:13:18.7: Image Efficiency: 47.6% 24-Jan-2018 03:13:18.7: Cycle Efficiency: 100.0% 24-Jan-2018 03:13:18.9: SelectRunning: No running plans 24-Jan-2018 03:13:19.0: Efficiency: Selecting from 17 pending plans: 24-Jan-2018 03:13:19.1: Selected Plan HO_Cam (TotEff=0.88) 24-Jan-2018 03:13:19.2: Dispatcher cycle time: 0.3070176 sec. 24-Jan-2018 03:13:19.2: Acquire data for Observation HO_Cam... 24-Jan-2018 03:13:19.2: (belongs to Project AAVSO_HGUA_Miras_Manual_ver167, Plan HO_Cam) 24-Jan-2018 03:13:19.2: Send Observation HO_Cam to ACP Sequencer 24-Jan-2018 03:13:29.2: Sequencer is now active 24-Jan-2018 03:27:00.1: Sequencer is no longer active 24-Jan-2018 03:27:00.1: Post-job status check done (stat=Completed) 24-Jan-2018 03:27:00.1: Acquisition time: 820.8789516 sec. 24-Jan-2018 03:27:00.1: Data for Observation HO_Cam acquired successfully. 24-Jan-2018 03:27:00.1: Image Efficiency: 47.5% 24-Jan-2018 03:27:00.1: Cycle Efficiency: 100.0% 24-Jan-2018 03:27:00.2: SelectRunning: No running plans 24-Jan-2018 03:27:00.3: STRICT VETO: 24-Jan-2018 03:27:00.3: Obs V0809_Cep in Plan V0809_Cep was vetoed by AirmassRange constraint. 24-Jan-2018 03:27:00.3: AirmassRange estimates ready after 24-Jan-2018 16:17:16 UTC 24-Jan-2018 03:27:00.4: Efficiency: Selecting from 15 pending plans: 24-Jan-2018 03:27:00.5: Selected Plan HX_Aur (TotEff=0.89) 24-Jan-2018 03:27:00.5: Dispatcher cycle time: 0.2940168 sec. 24-Jan-2018 03:27:00.5: Acquire data for Observation HX_Aur... 24-Jan-2018 03:27:00.5: (belongs to Project AAVSO_HGUA_Miras_Manual_ver167, Plan HX_Aur) 24-Jan-2018 03:27:00.8: Send Observation HX_Aur to ACP Sequencer 24-Jan-2018 03:27:10.8: Sequencer is now active 24-Jan-2018 03:40:39.4: Sequencer is no longer active 24-Jan-2018 03:40:39.4: Post-job status check done (stat=Completed) 24-Jan-2018 03:40:39.4: Acquisition time: 818.8548358 sec. 24-Jan-2018 03:40:39.4: Data for Observation HX_Aur acquired successfully. 24-Jan-2018 03:40:39.4: Image Efficiency: 47.6% 24-Jan-2018 03:40:39.4: Cycle Efficiency: 100.0% 24-Jan-2018 03:40:39.5: SelectRunning: No running plans 24-Jan-2018 03:40:39.7: Efficiency: Selecting from 14 pending plans: 24-Jan-2018 03:40:39.7: Selected Plan HQ_Aur (TotEff=0.89) 24-Jan-2018 03:40:39.8: Dispatcher cycle time: 0.2460141 sec. 24-Jan-2018 03:40:39.8: Acquire data for Observation HQ_Aur... 24-Jan-2018 03:40:39.8: (belongs to Project AAVSO_HGUA_Miras_Manual_ver167, Plan HQ_Aur) 24-Jan-2018 03:40:39.8: Send Observation HQ_Aur to ACP Sequencer 24-Jan-2018 03:40:49.8: Sequencer is now active 24-Jan-2018 03:54:25.4: Sequencer is no longer active 24-Jan-2018 03:54:25.4: Post-job status check done (stat=Completed) 24-Jan-2018 03:54:25.4: Acquisition time: 825.6312234 sec. 24-Jan-2018 03:54:25.4: Data for Observation HQ_Aur acquired successfully. 24-Jan-2018 03:54:25.4: Image Efficiency: 47.2% 24-Jan-2018 03:54:25.4: Cycle Efficiency: 100.0% 24-Jan-2018 03:54:25.8: SelectRunning: No running plans 24-Jan-2018 03:54:25.8: Obs GSC_3649-0825 in Plan GSC_3649-0825 was vetoed by AirMass constraint. 24-Jan-2018 03:54:25.8: AirMass estimates ready after 24-Jan-2018 16:52:01 UTC 24-Jan-2018 03:54:25.9: Efficiency: Selecting from 12 pending plans: 24-Jan-2018 03:54:26.0: Selected Plan T_Ori (TotEff=0.89) 24-Jan-2018 03:54:26.0: Dispatcher cycle time: 0.2420138 sec. 24-Jan-2018 03:54:26.0: Acquire data for Observation T_Ori... 24-Jan-2018 03:54:26.0: (belongs to Project AAVSO_Monitoring, Plan T_Ori) 24-Jan-2018 03:54:26.0: Doing 3 sets 24-Jan-2018 03:54:26.0: Send Observation T_Ori to ACP Sequencer 24-Jan-2018 03:54:36.0: Sequencer is now active 24-Jan-2018 03:58:53.2: Sequencer is no longer active 24-Jan-2018 03:58:53.2: Post-job status check done (stat=Completed) 24-Jan-2018 03:58:53.3: Send Observation T_Ori to ACP Sequencer 24-Jan-2018 03:59:03.3: Sequencer is now active 24-Jan-2018 04:01:52.1: Sequencer is no longer active 24-Jan-2018 04:01:52.1: Post-job status check done (stat=Completed) 24-Jan-2018 04:01:52.1: Send Observation T_Ori to ACP Sequencer 24-Jan-2018 04:02:02.1: Sequencer is now active 24-Jan-2018 04:04:51.4: Sequencer is no longer active 24-Jan-2018 04:04:51.4: Post-job status check done (stat=Completed) 24-Jan-2018 04:04:51.4: Acquisition time: 625.3377673 sec. 24-Jan-2018 04:04:51.4: Data for Observation T_Ori acquired successfully. 24-Jan-2018 04:04:51.4: Image Efficiency: 43.2% 24-Jan-2018 04:04:51.4: Cycle Efficiency: 100.0% 24-Jan-2018 04:04:51.8: SelectRunning: No running plans 24-Jan-2018 04:04:51.9: Efficiency: Selecting from 11 pending plans: 24-Jan-2018 04:04:52.0: Selected Plan CB_32 (TotEff=0.89) 24-Jan-2018 04:04:52.0: Dispatcher cycle time: 0.2240128 sec. 24-Jan-2018 04:04:52.0: Acquire data for Observation CB_32... 24-Jan-2018 04:04:52.0: (belongs to Project Staels, Plan CB_32) 24-Jan-2018 04:04:52.0: Send Observation CB_32 to ACP Sequencer 24-Jan-2018 04:05:02.0: Sequencer is now active 24-Jan-2018 04:08:27.7: Sequencer is no longer active 24-Jan-2018 04:08:27.7: Post-job status check done (stat=Completed) 24-Jan-2018 04:08:27.7: Acquisition time: 215.6383338 sec. 24-Jan-2018 04:08:27.7: Data for Observation CB_32 acquired successfully. 24-Jan-2018 04:08:27.7: Image Efficiency: 41.7% 24-Jan-2018 04:08:27.7: Cycle Efficiency: 99.9% 24-Jan-2018 04:08:27.9: SelectRunning: No running plans 24-Jan-2018 04:08:28.0: Efficiency: Selecting from 11 pending plans: 24-Jan-2018 04:08:28.4: Selected Plan V0959_Mon (TotEff=0.83) 24-Jan-2018 04:08:28.4: Dispatcher cycle time: 0.5540317 sec. 24-Jan-2018 04:08:28.4: Acquire data for Observation V0959_Mon... 24-Jan-2018 04:08:28.4: (belongs to Project Nova_Monitoring, Plan V0959_Mon) 24-Jan-2018 04:08:28.4: Send Observation V0959_Mon to ACP Sequencer 24-Jan-2018 04:08:38.4: Sequencer is now active 24-Jan-2018 04:23:17.4: Sequencer is no longer active 24-Jan-2018 04:23:17.4: Post-job status check done (stat=Completed) 24-Jan-2018 04:23:17.4: Acquisition time: 888.966846 sec. 24-Jan-2018 04:23:17.4: Data for Observation V0959_Mon acquired successfully. 24-Jan-2018 04:23:17.4: Image Efficiency: 70.8% 24-Jan-2018 04:23:17.4: Cycle Efficiency: 99.9% 24-Jan-2018 04:23:17.7: SelectRunning: No running plans 24-Jan-2018 04:23:17.7: STRICT VETO: 24-Jan-2018 04:23:17.7: Obs AF_And in Plan AF_And was vetoed by AirMass constraint. 24-Jan-2018 04:23:17.7: AirMass estimates ready after 24-Jan-2018 18:27:44 UTC 24-Jan-2018 04:23:17.7: STRICT VETO: 24-Jan-2018 04:23:17.7: Obs NGC_7790 in Plan NGC_7790 was vetoed by AirmassRange constraint. 24-Jan-2018 04:23:17.7: AirmassRange estimates ready after 24-Jan-2018 17:06:37 UTC 24-Jan-2018 04:23:17.8: Efficiency: Selecting from 8 pending plans: 24-Jan-2018 04:23:17.8: Selected Plan MP_Gem (TotEff=0.82) 24-Jan-2018 04:23:17.9: Dispatcher cycle time: 0.2300131 sec. 24-Jan-2018 04:23:17.9: Acquire data for Observation MP_Gem... 24-Jan-2018 04:23:17.9: (belongs to Project Staels, Plan MP_Gem) 24-Jan-2018 04:23:17.9: Send Observation MP_Gem to ACP Sequencer 24-Jan-2018 04:23:27.9: Sequencer is now active 24-Jan-2018 04:54:02.3: Sequencer is no longer active 24-Jan-2018 04:54:02.4: Post-job status check done (stat=Completed) 24-Jan-2018 04:54:02.4: Acquisition time: 1844.4694976 sec. 24-Jan-2018 04:54:02.4: Data for Observation MP_Gem acquired successfully. 24-Jan-2018 04:54:02.4: Image Efficiency: 78.1% 24-Jan-2018 04:54:02.4: Cycle Efficiency: 100.0% 24-Jan-2018 04:54:03.3: ++ Auto Focus ++ 24-Jan-2018 04:54:03.4: Doing periodic autofocus. 24-Jan-2018 04:54:03.4: Start special ACP AutoFocus script for scheduler 24-Jan-2018 04:54:13.4: Sequencer is now active 24-Jan-2018 04:56:43.3: Sequencer is no longer active 24-Jan-2018 04:56:43.3: Next periodic autofocus ASAP after 24-Jan-2018 07:56:27 UTC 24-Jan-2018 04:56:43.3: SelectRunning: No running plans 24-Jan-2018 04:56:43.4: Efficiency: Selecting from 8 pending plans: 24-Jan-2018 04:56:43.4: Selected Plan DN_Lyn (TotEff=0.83) 24-Jan-2018 04:56:43.5: Dispatcher cycle time: 0.1590091 sec. 24-Jan-2018 04:56:43.5: Acquire data for Observation DN_Lyn... 24-Jan-2018 04:56:43.5: (belongs to Project AAVSO_HGUA_Miras_Manual_ver167, Plan DN_Lyn) 24-Jan-2018 04:56:43.5: Send Observation DN_Lyn to ACP Sequencer 24-Jan-2018 04:56:53.5: Sequencer is now active 24-Jan-2018 05:10:39.1: Sequencer is no longer active 24-Jan-2018 05:10:39.1: Post-job status check done (stat=Completed) 24-Jan-2018 05:10:39.1: Acquisition time: 835.641796 sec. 24-Jan-2018 05:10:39.1: Data for Observation DN_Lyn acquired successfully. 24-Jan-2018 05:10:39.2: Image Efficiency: 46.7% 24-Jan-2018 05:10:39.2: Cycle Efficiency: 100.0% 24-Jan-2018 05:10:39.6: SelectRunning: No running plans 24-Jan-2018 05:10:39.6: Efficiency: Selecting from 7 pending plans: 24-Jan-2018 05:10:39.7: Selected Plan WZ_Per (TotEff=0.71) 24-Jan-2018 05:10:39.7: Dispatcher cycle time: 0.1690097 sec. 24-Jan-2018 05:10:39.7: Acquire data for Observation WZ_Per... 24-Jan-2018 05:10:39.7: (belongs to Project AAVSO_HGUA_Miras_Manual_ver167, Plan WZ_Per) 24-Jan-2018 05:10:39.7: Send Observation WZ_Per to ACP Sequencer 24-Jan-2018 05:10:49.7: Sequencer is now active 24-Jan-2018 05:25:22.6: Sequencer is no longer active 24-Jan-2018 05:25:22.6: Post-job status check done (stat=Completed) 24-Jan-2018 05:25:22.6: Acquisition time: 882.9054993 sec. 24-Jan-2018 05:25:22.6: Data for Observation WZ_Per acquired successfully. 24-Jan-2018 05:25:22.7: Image Efficiency: 44.2% 24-Jan-2018 05:25:22.7: Cycle Efficiency: 100.0% 24-Jan-2018 05:25:23.0: SelectRunning: No running plans 24-Jan-2018 05:25:23.1: Efficiency: Selecting from 6 pending plans: 24-Jan-2018 05:25:23.1: Selected Plan RX_J0524+42 (TotEff=0.64) 24-Jan-2018 05:25:23.2: Dispatcher cycle time: 0.1450083 sec. 24-Jan-2018 05:25:23.2: Acquire data for Observation RX_J0524+42... 24-Jan-2018 05:25:23.2: (belongs to Project Walker, Plan RX_J0524+42) 24-Jan-2018 05:25:23.2: Doing 30 sets 24-Jan-2018 05:25:23.2: Send Observation RX_J0524+42 to ACP Sequencer 24-Jan-2018 05:25:33.2: Sequencer is now active 24-Jan-2018 05:51:32.2: Sequencer is no longer active 24-Jan-2018 05:51:32.2: Post-job status check done (stat=Completed) 24-Jan-2018 05:51:32.2: Send Observation RX_J0524+42 to ACP Sequencer 24-Jan-2018 05:51:42.2: Sequencer is now active 24-Jan-2018 06:16:45.6: Sequencer is no longer active 24-Jan-2018 06:16:45.6: Post-job status check done (stat=Completed) 24-Jan-2018 06:16:45.9: Send Observation RX_J0524+42 to ACP Sequencer 24-Jan-2018 06:16:56.0: Sequencer is now active 24-Jan-2018 06:41:59.7: Sequencer is no longer active 24-Jan-2018 06:41:59.7: Post-job status check done (stat=Completed) 24-Jan-2018 06:42:00.1: Send Observation RX_J0524+42 to ACP Sequencer 24-Jan-2018 06:42:10.1: Sequencer is now active 24-Jan-2018 07:07:12.7: Sequencer is no longer active 24-Jan-2018 07:07:12.7: Post-job status check done (stat=Completed) 24-Jan-2018 07:07:13.1: Send Observation RX_J0524+42 to ACP Sequencer 24-Jan-2018 07:07:23.1: Sequencer is now active 24-Jan-2018 07:32:22.0: Sequencer is no longer active 24-Jan-2018 07:32:22.0: Post-job status check done (stat=Completed) 24-Jan-2018 07:32:22.0: Send Observation RX_J0524+42 to ACP Sequencer 24-Jan-2018 07:32:32.0: Sequencer is now active 24-Jan-2018 07:57:25.0: Sequencer is no longer active 24-Jan-2018 07:57:25.0: Post-job status check done (stat=Completed) 24-Jan-2018 07:57:25.1: Send Observation RX_J0524+42 to ACP Sequencer 24-Jan-2018 07:57:35.1: Sequencer is now active 24-Jan-2018 08:22:29.8: Sequencer is no longer active 24-Jan-2018 08:22:29.8: Post-job status check done (stat=Completed) 24-Jan-2018 08:22:29.9: Send Observation RX_J0524+42 to ACP Sequencer 24-Jan-2018 08:22:39.9: Sequencer is now active 24-Jan-2018 08:47:31.4: Sequencer is no longer active 24-Jan-2018 08:47:31.4: Post-job status check done (stat=Completed) 24-Jan-2018 08:47:31.5: Send Observation RX_J0524+42 to ACP Sequencer 24-Jan-2018 08:47:41.5: Sequencer is now active 24-Jan-2018 09:12:36.9: Sequencer is no longer active 24-Jan-2018 09:12:36.9: Post-job status check done (stat=Completed) 24-Jan-2018 09:12:37.0: Send Observation RX_J0524+42 to ACP Sequencer 24-Jan-2018 09:12:47.0: Sequencer is now active 24-Jan-2018 09:37:36.8: Sequencer is no longer active 24-Jan-2018 09:37:36.8: Post-job status check done (stat=Completed) 24-Jan-2018 09:37:36.8: Send Observation RX_J0524+42 to ACP Sequencer 24-Jan-2018 09:37:46.8: Sequencer is now active 24-Jan-2018 10:02:34.9: Sequencer is no longer active 24-Jan-2018 10:02:34.9: Post-job status check done (stat=Completed) 24-Jan-2018 10:02:34.9: TestConstraints: AirMass vetoed/failed for end time of Obs 24-Jan-2018 10:02:34.9: ***Running Plan RX_J0524+42 (best efforts) completed early: 24-Jan-2018 10:02:34.9: Obs RX_J0524+42 would would not meet constraints 24-Jan-2018 10:02:34.9: Acquisition time: 16631.5632709 sec. 24-Jan-2018 10:02:34.9: Data for Observation RX_J0524+42 acquired successfully. 24-Jan-2018 10:02:34.9: Image Efficiency: 79.4% 24-Jan-2018 10:02:34.9: Cycle Efficiency: 100.0% 24-Jan-2018 10:02:35.2: ++ Auto Focus ++ 24-Jan-2018 10:02:35.2: Doing periodic autofocus. 24-Jan-2018 10:02:35.2: Start special ACP AutoFocus script for scheduler 24-Jan-2018 10:02:45.2: Sequencer is now active 24-Jan-2018 13:33:49.3: ** Script interrupted by weather safety event. 24-Jan-2018 13:33:49.3: ** Autofocus failed (n=1), will retry ASAP after 0 min. 24-Jan-2018 13:33:50.3: -- Weather Unsafe -- 24-Jan-2018 13:33:52.3: -- Observatory Shutdown -- 24-Jan-2018 13:33:52.6: Weather disconnected. 24-Jan-2018 13:33:52.7: Start ACP Sequencer's ShutdownObs script 24-Jan-2018 13:33:53.0: WARNING: Request sent while Weather Safety Script is running 24-Jan-2018 13:34:53.1: WARNING: Request sent while Weather Safety Script is running 24-Jan-2018 13:35:53.6: WARNING: Request sent while Weather Safety Script is running 24-Jan-2018 13:36:53.8: WARNING: Request sent while Weather Safety Script is running 24-Jan-2018 13:37:53.9: WARNING: Request sent while Weather Safety Script is running 24-Jan-2018 13:38:54.2: WARNING: Request sent while Weather Safety Script is running 24-Jan-2018 13:39:54.7: WARNING: Request sent while Weather Safety Script is running 24-Jan-2018 13:40:54.9: WARNING: Request sent while Weather Safety Script is running 24-Jan-2018 13:41:55.0: WARNING: Request sent while Weather Safety Script is running 24-Jan-2018 13:42:55.2: WARNING: Request sent while Weather Safety Script is running 24-Jan-2018 13:43:55.4: WARNING: Request sent while Weather Safety Script is running 24-Jan-2018 13:44:55.9: WARNING: Request sent while Weather Safety Script is running 24-Jan-2018 13:45:56.0: WARNING: Request sent while Weather Safety Script is running 24-Jan-2018 13:46:56.3: WARNING: Request sent while Weather Safety Script is running 24-Jan-2018 13:47:56.4: WARNING: Request sent while Weather Safety Script is running 24-Jan-2018 13:48:56.5: **EXCEPTION IN SCHEDULER: 24-Jan-2018 13:48:56.5: Observatory shutdown failed, cannot continue. 24-Jan-2018 13:48:56.5: Traceback: at DC3.Scheduler.Engine.DoShutdownIf() at DC3.Scheduler.Engine.Run() 24-Jan-2018 13:48:56.5: Run statistics: 24-Jan-2018 13:48:56.5: Observations: 51 24-Jan-2018 13:48:56.5: Considered: 30 24-Jan-2018 13:48:56.5: Completed: 17 24-Jan-2018 13:48:56.5: Skipped: 10 24-Jan-2018 13:48:56.5: Never Eligible: 21 24-Jan-2018 13:48:56.5: Failed: 3 24-Jan-2018 13:48:56.5: Shutter-Open efficiency: 68.22% 24-Jan-2018 13:48:56.5: Overall Efficiency: 99.90% 24-Jan-2018 13:48:56.5: Release ACP sequencer Log closed at Wed, Jan 24 2018 13:48:58 UTC (actual time)