Log opened at Mon, Sep 25 2017 04:28:34 UTC (actual time) Current log level is Debug Scheduler version 3.5.3 Multi-user license: Arne Henden 25-Sep-2017 04:28:35.0: Simulated clock initialized at 25-Sep-2017 04:28:35 UTC 25-Sep-2017 04:28:35.0: Loaded Constraint plugin AirMass 25-Sep-2017 04:28:35.0: Loaded Constraint plugin AirmassRange 25-Sep-2017 04:28:35.0: Loaded Constraint plugin Horizon 25-Sep-2017 04:28:35.0: Loaded Constraint plugin HourAngle 25-Sep-2017 04:28:35.0: Loaded Constraint plugin MoonAvoid 25-Sep-2017 04:28:35.0: Loaded Constraint plugin MoonDown 25-Sep-2017 04:28:35.0: Loaded Constraint plugin SkyCondition 25-Sep-2017 04:28:35.0: Loaded Constraint plugin TimeRange 25-Sep-2017 04:28:35.5: 10 plans are now pending. 25-Sep-2017 04:28:35.5: Attach ACP sequencer 25-Sep-2017 04:28:53.9: Dispatcher started at 25-Sep-2017 04:28:53 UTC 25-Sep-2017 04:28:53.9: Priority: W[0]=0.00 25-Sep-2017 04:28:53.9: Transit Altitude: W[1]=0.70 25-Sep-2017 04:28:53.9: Highest Altitude: W[6]=0.00 25-Sep-2017 04:28:53.9: Lateness: W[5]=0.00 25-Sep-2017 04:28:53.9: Slew Distance: W[2]=0.00 25-Sep-2017 04:28:53.9: Retry Count: W[3]=0.20 25-Sep-2017 04:28:53.9: Meridian Crossing: W[4]=0.00 25-Sep-2017 04:28:53.9: Obs Conditions: W[7]=0.40 25-Sep-2017 04:28:53.9: Rising Plan Delay: disabled 25-Sep-2017 04:28:57.2: Weather successfully connected. Wait for valid data... 25-Sep-2017 04:28:58.6: ++ Observatory Startup ++ 25-Sep-2017 04:28:58.6: Start ACP Sequencer's StartupObs script 25-Sep-2017 04:29:08.7: Sequencer is now active 25-Sep-2017 04:29:13.9: Sequencer is no longer active 25-Sep-2017 04:29:13.9: ++ Auto Focus ++ 25-Sep-2017 04:29:14.0: Doing initial autofocus. 25-Sep-2017 04:29:14.0: Start special ACP AutoFocus script for scheduler 25-Sep-2017 04:29:24.0: Sequencer is now active 25-Sep-2017 04:30:54.3: Sequencer is no longer active 25-Sep-2017 04:30:54.3: ** Autofocus failed (n=1), will retry ASAP after 0 min. 25-Sep-2017 04:30:54.5: Plan V0809_Cep has Monitor Mode. Time to resubmit it. 25-Sep-2017 04:30:54.5: Plan V1724_Aql has Monitor Mode. Time to resubmit it. 25-Sep-2017 04:30:54.5: Plan V0959_Mon has Monitor Mode. Time to resubmit it. 25-Sep-2017 04:30:54.5: Plan MP_Gem has Monitor Mode. Time to resubmit it. 25-Sep-2017 04:30:54.5: Plan GSC_3649-0825 has Monitor Mode. Time to resubmit it. 25-Sep-2017 04:30:54.5: Plan CB_32 has Monitor Mode. Time to resubmit it. 25-Sep-2017 04:30:54.5: Plan V0965_Per has Monitor Mode. Time to resubmit it. 25-Sep-2017 04:30:54.5: Plan V1723_Aql has Monitor Mode. Time to resubmit it. 25-Sep-2017 04:30:54.5: Plan V1722_Aql has Monitor Mode. Time to resubmit it. 25-Sep-2017 04:30:54.5: Plan V1721_Aql has Monitor Mode. Time to resubmit it. 25-Sep-2017 04:30:54.5: Plan SDSS073224 has Monitor Mode. Time to resubmit it. 25-Sep-2017 04:30:54.5: Plan QQ_Vul has Monitor Mode. Time to resubmit it. 25-Sep-2017 04:30:54.5: Plan GSC_4030_204 has Monitor Mode. Time to resubmit it. 25-Sep-2017 04:30:54.5: Plan HQ_Aur has Monitor Mode. Time to resubmit it. 25-Sep-2017 04:30:54.6: Plan HX_Aur has Monitor Mode. Time to resubmit it. 25-Sep-2017 04:30:54.6: Plan FU_Cam has Monitor Mode. Time to resubmit it. 25-Sep-2017 04:30:54.6: Plan FW_Cam has Monitor Mode. Time to resubmit it. 25-Sep-2017 04:30:54.6: Plan FY_Cam has Monitor Mode. Time to resubmit it. 25-Sep-2017 04:30:54.6: Plan HI_Cam has Monitor Mode. Time to resubmit it. 25-Sep-2017 04:30:54.6: Plan HO_Cam has Monitor Mode. Time to resubmit it. 25-Sep-2017 04:30:54.6: Plan CM_Cas has Monitor Mode. Time to resubmit it. 25-Sep-2017 04:30:54.6: Plan V0524_Cas has Monitor Mode. Time to resubmit it. 25-Sep-2017 04:30:54.6: Plan V1904_Cyg has Monitor Mode. Time to resubmit it. 25-Sep-2017 04:30:54.6: Plan V1907_Cyg has Monitor Mode. Time to resubmit it. 25-Sep-2017 04:30:54.6: Plan V2481_Cyg has Monitor Mode. Time to resubmit it. 25-Sep-2017 04:30:54.6: Plan BC_Lac has Monitor Mode. Time to resubmit it. 25-Sep-2017 04:30:54.6: Plan DN_Lyn has Monitor Mode. Time to resubmit it. 25-Sep-2017 04:30:54.6: Plan WZ_Per has Monitor Mode. Time to resubmit it. 25-Sep-2017 04:30:54.6: Plan M56 has Monitor Mode. Time to resubmit it. 25-Sep-2017 04:30:54.6: Plan NGC_7790 has Monitor Mode. Time to resubmit it. 25-Sep-2017 04:30:54.6: Plan T_Ori has Monitor Mode. Time to resubmit it. 25-Sep-2017 04:30:54.6: Plan U_Aqr has Monitor Mode. Time to resubmit it. 25-Sep-2017 04:30:54.6: Plan V339_Del_TMO61 has Monitor Mode. Time to resubmit it. 25-Sep-2017 04:30:54.6: Plan V0603_Per has Monitor Mode. Time to resubmit it. 25-Sep-2017 04:30:54.6: Plan NSVS_11201899 has Monitor Mode. Time to resubmit it. 25-Sep-2017 04:30:54.6: Plan IRAS_19067+2158 has Monitor Mode. Time to resubmit it. 25-Sep-2017 04:30:54.6: Plan IRAS_19183+2339 has Monitor Mode. Time to resubmit it. 25-Sep-2017 04:30:54.6: Plan 2MASS_J19395313+0557550 has Monitor Mode. Time to resubmit it. 25-Sep-2017 04:30:54.6: Plan AF_And has Monitor Mode. Time to resubmit it. 25-Sep-2017 04:30:54.6: SelectRunning: No running plans 25-Sep-2017 04:30:54.6: Obs AL_Boo in Plan AL_Boo was vetoed by AirmassRange constraint. 25-Sep-2017 04:30:54.6: AirmassRange estimates ready after 25-Sep-2017 16:03:43 UTC 25-Sep-2017 04:30:54.7: Obs CB_32 in Plan CB_32 was vetoed by AirMass constraint. 25-Sep-2017 04:30:54.7: AirMass estimates ready after 25-Sep-2017 08:51:34 UTC 25-Sep-2017 04:30:54.7: Obs CV_Vir in Plan CV_Vir was vetoed by AirmassRange constraint. 25-Sep-2017 04:30:54.7: AirmassRange estimates ready after 25-Sep-2017 15:10:28 UTC 25-Sep-2017 04:30:54.7: Obs DN_Lyn in Plan DN_Lyn was vetoed by AirmassRange constraint. 25-Sep-2017 04:30:54.7: AirmassRange estimates ready after 25-Sep-2017 09:03:19 UTC 25-Sep-2017 04:30:54.7: Obs FY_Cam in Plan FY_Cam was vetoed by AirmassRange constraint. 25-Sep-2017 04:30:54.7: AirmassRange estimates ready after 25-Sep-2017 04:42:54 UTC 25-Sep-2017 04:30:54.7: Obs HI_Cam in Plan HI_Cam was vetoed by AirmassRange constraint. 25-Sep-2017 04:30:54.7: AirmassRange estimates ready after 25-Sep-2017 05:38:50 UTC 25-Sep-2017 04:30:54.7: Obs HO_Cam in Plan HO_Cam was vetoed by AirmassRange constraint. 25-Sep-2017 04:30:54.7: AirmassRange estimates ready after 25-Sep-2017 06:23:36 UTC 25-Sep-2017 04:30:54.7: Obs HQ_Aur in Plan HQ_Aur was vetoed by AirmassRange constraint. 25-Sep-2017 04:30:54.7: AirmassRange estimates ready after 25-Sep-2017 06:54:42 UTC 25-Sep-2017 04:30:54.7: Obs HX_Aur in Plan HX_Aur was vetoed by AirmassRange constraint. 25-Sep-2017 04:30:54.7: AirmassRange estimates ready after 25-Sep-2017 06:32:51 UTC 25-Sep-2017 04:30:54.8: Obs KK_UMa in Plan KK_UMa was vetoed by AirmassRange constraint. 25-Sep-2017 04:30:54.8: AirmassRange estimates ready after 25-Sep-2017 12:43:34 UTC 25-Sep-2017 04:30:54.8: Obs M67 in Plan M67 was vetoed by AirMass constraint. 25-Sep-2017 04:30:54.8: AirMass estimates ready after 25-Sep-2017 12:01:23 UTC 25-Sep-2017 04:30:54.8: STRICT VETO: 25-Sep-2017 04:30:54.8: Obs Mkn_501 in Plan Mkn_501 was vetoed by AirMass constraint. 25-Sep-2017 04:30:54.8: AirMass estimates ready after 25-Sep-2017 18:37:38 UTC 25-Sep-2017 04:30:54.8: Obs MP_Gem in Plan MP_Gem was vetoed by AirMass constraint. 25-Sep-2017 04:30:54.8: AirMass estimates ready after 25-Sep-2017 09:12:30 UTC 25-Sep-2017 04:30:54.8: Obs SDSS073224 in Plan SDSS073224 was vetoed by AirMass constraint. 25-Sep-2017 04:30:54.8: AirMass estimates ready after 25-Sep-2017 09:41:22 UTC 25-Sep-2017 04:30:54.8: Obs SDSSJ121027+502735 in Plan SDSSJ121027+502735 was vetoed by AirMass constraint. 25-Sep-2017 04:30:54.8: AirMass estimates ready after 25-Sep-2017 13:36:12 UTC 25-Sep-2017 04:30:54.8: Obs T_Ori in Plan T_Ori was vetoed by AirmassRange constraint. 25-Sep-2017 04:30:54.8: AirmassRange estimates ready after 25-Sep-2017 09:08:23 UTC 25-Sep-2017 04:30:54.8: Obs V0336_Ser in Plan V0336_Ser was vetoed by AirmassRange constraint. 25-Sep-2017 04:30:54.8: AirmassRange estimates ready after 25-Sep-2017 18:21:33 UTC 25-Sep-2017 04:30:54.9: Obs V0603_Per in Plan V0603_Per was vetoed by AirmassRange constraint. 25-Sep-2017 04:30:54.9: AirmassRange estimates ready after 25-Sep-2017 05:13:36 UTC 25-Sep-2017 04:30:54.9: Obs V0959_Mon in Plan V0959_Mon was vetoed by AirmassRange constraint. 25-Sep-2017 04:30:54.9: AirmassRange estimates ready after 25-Sep-2017 09:36:24 UTC 25-Sep-2017 04:30:54.9: Obs V0965_Per in Plan V0965_Per was vetoed by AirmassRange constraint. 25-Sep-2017 04:30:54.9: AirmassRange estimates ready after 25-Sep-2017 05:02:03 UTC 25-Sep-2017 04:30:54.9: Obs V841_Oph in Plan V841_Oph was vetoed by AirmassRange constraint. 25-Sep-2017 04:30:54.9: AirmassRange estimates ready after 25-Sep-2017 21:01:15 UTC 25-Sep-2017 04:30:54.9: Obs WZ_Per in Plan WZ_Per was vetoed by AirmassRange constraint. 25-Sep-2017 04:30:54.9: AirmassRange estimates ready after 25-Sep-2017 05:22:23 UTC 25-Sep-2017 04:30:54.9: Efficiency: Selecting from 27 pending plans: 25-Sep-2017 04:30:55.2: Selected Plan V1907_Cyg (TotEff=0.90) 25-Sep-2017 04:30:55.2: Dispatcher cycle time: 0.8580491 sec. 25-Sep-2017 04:30:55.2: Acquire data for Observation V1907_Cyg... 25-Sep-2017 04:30:55.2: (belongs to Project AAVSO_HGUA_Miras_Manual_ver167, Plan V1907_Cyg) 25-Sep-2017 04:30:55.2: Send Observation V1907_Cyg to ACP Sequencer 25-Sep-2017 04:31:05.2: Sequencer is now active 25-Sep-2017 04:45:00.4: Sequencer is no longer active 25-Sep-2017 04:45:00.4: Post-job status check done (stat=Completed) 25-Sep-2017 04:45:00.4: Acquisition time: 845.1783414 sec. 25-Sep-2017 04:45:00.4: Data for Observation V1907_Cyg acquired successfully. 25-Sep-2017 04:45:00.4: Image Efficiency: 46.1% 25-Sep-2017 04:45:00.4: Cycle Efficiency: 99.9% 25-Sep-2017 04:45:01.1: ++ Auto Focus ++ 25-Sep-2017 04:45:01.1: Doing periodic autofocus. 25-Sep-2017 04:45:01.1: Start special ACP AutoFocus script for scheduler 25-Sep-2017 04:45:11.1: Sequencer is now active 25-Sep-2017 04:47:19.4: Sequencer is no longer active 25-Sep-2017 04:47:19.4: ** Autofocus failed (n=2), will retry ASAP after 5 min. 25-Sep-2017 04:47:19.8: SelectRunning: No running plans 25-Sep-2017 04:47:19.9: STRICT VETO: 25-Sep-2017 04:47:19.9: Obs V1723_Aql in Plan V1723_Aql was vetoed by AirmassRange constraint. 25-Sep-2017 04:47:19.9: AirmassRange estimates ready after 25-Sep-2017 22:12:00 UTC 25-Sep-2017 04:47:20.3: Efficiency: Selecting from 26 pending plans: 25-Sep-2017 04:47:20.5: Selected Plan U_Aqr (TotEff=0.90) 25-Sep-2017 04:47:20.6: Dispatcher cycle time: 1.2230699 sec. 25-Sep-2017 04:47:20.6: Acquire data for Observation U_Aqr... 25-Sep-2017 04:47:20.6: (belongs to Project AAVSO_Monitoring, Plan U_Aqr) 25-Sep-2017 04:47:20.6: Send Observation U_Aqr to ACP Sequencer 25-Sep-2017 04:47:30.6: Sequencer is now active 25-Sep-2017 04:55:54.5: Sequencer is no longer active 25-Sep-2017 04:55:54.5: Post-job status check done (stat=Failed) 25-Sep-2017 04:55:54.5: ACP data acquisition failed for Observation U_Aqr. 25-Sep-2017 04:55:54.5: (Observation U_Aqr interrupted by acquisition process failure.) 25-Sep-2017 04:55:54.7: ++ Auto Focus ++ 25-Sep-2017 04:55:54.7: Doing periodic autofocus. 25-Sep-2017 04:55:54.7: Start special ACP AutoFocus script for scheduler 25-Sep-2017 04:56:04.7: Sequencer is now active 25-Sep-2017 04:57:45.0: Sequencer is no longer active 25-Sep-2017 04:57:45.0: ** Autofocus failed (n=3), will retry ASAP after 10 min. 25-Sep-2017 04:57:45.1: SelectRunning: No running plans 25-Sep-2017 04:57:45.2: Efficiency: Selecting from 25 pending plans: 25-Sep-2017 04:57:45.4: Selected Plan BC_Lac (TotEff=0.89) 25-Sep-2017 04:57:45.5: Dispatcher cycle time: 0.4500257 sec. 25-Sep-2017 04:57:45.5: Acquire data for Observation BC_Lac... 25-Sep-2017 04:57:45.5: (belongs to Project AAVSO_HGUA_Miras_Manual_ver167, Plan BC_Lac) 25-Sep-2017 04:57:45.5: Send Observation BC_Lac to ACP Sequencer 25-Sep-2017 04:57:55.5: Sequencer is now active 25-Sep-2017 05:11:53.6: Sequencer is no longer active 25-Sep-2017 05:11:53.6: Post-job status check done (stat=Completed) 25-Sep-2017 05:11:53.6: Acquisition time: 848.1055089 sec. 25-Sep-2017 05:11:53.6: Data for Observation BC_Lac acquired successfully. 25-Sep-2017 05:11:53.6: Image Efficiency: 46.0% 25-Sep-2017 05:11:53.6: Cycle Efficiency: 99.9% 25-Sep-2017 05:11:54.0: ++ Auto Focus ++ 25-Sep-2017 05:11:54.0: Doing periodic autofocus. 25-Sep-2017 05:11:54.0: Start special ACP AutoFocus script for scheduler 25-Sep-2017 05:12:04.0: Sequencer is now active 25-Sep-2017 05:13:57.9: Sequencer is no longer active 25-Sep-2017 05:13:57.9: ** Autofocus failed (n=4), will retry ASAP after 15 min. 25-Sep-2017 05:13:58.0: SelectRunning: No running plans 25-Sep-2017 05:13:58.0: STRICT VETO: 25-Sep-2017 05:13:58.0: Obs FY_Aql in Plan FY_Aql was vetoed by AirMass constraint. 25-Sep-2017 05:13:58.0: AirMass estimates ready after 25-Sep-2017 22:59:06 UTC 25-Sep-2017 05:13:58.1: STRICT VETO: 25-Sep-2017 05:13:58.1: Obs V1724_Aql in Plan V1724_Aql was vetoed by AirmassRange constraint. 25-Sep-2017 05:13:58.1: AirmassRange estimates ready after 25-Sep-2017 22:05:27 UTC 25-Sep-2017 05:13:58.2: Efficiency: Selecting from 24 pending plans: 25-Sep-2017 05:13:58.3: Selected Plan V0809_Cep (TotEff=0.89) 25-Sep-2017 05:13:58.4: Dispatcher cycle time: 0.438025 sec. 25-Sep-2017 05:13:58.4: Acquire data for Observation V0809_Cep... 25-Sep-2017 05:13:58.4: (belongs to Project Nova_Monitoring, Plan V0809_Cep) 25-Sep-2017 05:13:58.4: Send Observation V0809_Cep to ACP Sequencer 25-Sep-2017 05:14:08.4: Sequencer is now active 25-Sep-2017 05:28:23.1: ** Script interrupted by weather safety event. 25-Sep-2017 05:28:26.1: Post-job status check done (stat=Running) 25-Sep-2017 05:28:26.1: ACP ABORT: Acquisition process was interrupted by weather unsafe. 25-Sep-2017 05:28:26.1: ACP data acquisition failed for Observation V0809_Cep. 25-Sep-2017 05:28:26.1: (Plan V0809_Cep will be resubmitted in its entirety. 25-Sep-2017 05:28:26.1: (Observation V0809_Cep interrupted by weather unsafe event.) 25-Sep-2017 05:28:26.5: -- Weather Unsafe -- 25-Sep-2017 05:51:40.4: ++ Weather Safe ++ 25-Sep-2017 05:51:42.0: ++ Observatory Startup ++ 25-Sep-2017 05:51:42.1: Start ACP Sequencer's StartupObs script 25-Sep-2017 05:51:42.4: WARNING: Request sent while Weather Safety Script is running 25-Sep-2017 05:52:42.7: WARNING: Request sent while Weather Safety Script is running 25-Sep-2017 05:53:42.9: WARNING: Request sent while Weather Safety Script is running 25-Sep-2017 05:54:43.1: WARNING: Request sent while Weather Safety Script is running 25-Sep-2017 05:55:43.4: WARNING: Request sent while Weather Safety Script is running 25-Sep-2017 05:56:43.7: WARNING: Request sent while Weather Safety Script is running 25-Sep-2017 05:57:43.9: WARNING: Request sent while Weather Safety Script is running 25-Sep-2017 05:58:44.2: WARNING: Request sent while Weather Safety Script is running 25-Sep-2017 05:59:44.4: WARNING: Request sent while Weather Safety Script is running 25-Sep-2017 06:00:44.7: WARNING: Request sent while Weather Safety Script is running 25-Sep-2017 06:01:44.9: WARNING: Request sent while Weather Safety Script is running 25-Sep-2017 06:02:45.0: WARNING: Request sent while Weather Safety Script is running 25-Sep-2017 06:03:45.5: WARNING: Request sent while Weather Safety Script is running 25-Sep-2017 06:04:45.9: WARNING: Request sent while Weather Safety Script is running 25-Sep-2017 06:05:46.1: WARNING: Request sent while Weather Safety Script is running 25-Sep-2017 06:06:46.5: **EXCEPTION IN SCHEDULER: 25-Sep-2017 06:06:46.5: Observatory startup failed, cannot continue. 25-Sep-2017 06:06:46.5: Traceback: at DC3.Scheduler.Engine.DoStartupIf(Boolean openDome) at DC3.Scheduler.Engine.Run() 25-Sep-2017 06:06:46.6: Run statistics: 25-Sep-2017 06:06:46.6: Observations: 51 25-Sep-2017 06:06:46.6: Considered: 31 25-Sep-2017 06:06:46.6: Completed: 2 25-Sep-2017 06:06:46.6: Skipped: 26 25-Sep-2017 06:06:46.6: Never Eligible: 20 25-Sep-2017 06:06:46.6: Failed: 3 25-Sep-2017 06:06:46.6: Shutter-Open efficiency: 25.34% 25-Sep-2017 06:06:46.6: Overall Efficiency: 99.90% 25-Sep-2017 06:06:46.6: Release ACP sequencer Log closed at Mon, Sep 25 2017 06:06:48 UTC (actual time)