Log opened at Thu, May 04 2017 03:23:54 UTC (actual time) Current log level is Debug Scheduler version 3.5.3 Multi-user license: Arne Henden 04-May-2017 03:23:54.0: Simulated clock initialized at 04-May-2017 03:23:54 UTC 04-May-2017 03:23:54.0: Loaded Constraint plugin AirMass 04-May-2017 03:23:54.0: Loaded Constraint plugin AirmassRange 04-May-2017 03:23:54.0: Loaded Constraint plugin Horizon 04-May-2017 03:23:54.0: Loaded Constraint plugin HourAngle 04-May-2017 03:23:54.0: Loaded Constraint plugin MoonAvoid 04-May-2017 03:23:54.0: Loaded Constraint plugin MoonDown 04-May-2017 03:23:54.0: Loaded Constraint plugin SkyCondition 04-May-2017 03:23:54.0: Loaded Constraint plugin TimeRange 04-May-2017 03:23:54.6: 37 plans are now pending. 04-May-2017 03:23:54.6: Attach ACP sequencer 04-May-2017 03:24:04.0: Dispatcher started at 04-May-2017 03:24:04 UTC 04-May-2017 03:24:04.0: Priority: W[0]=0.00 04-May-2017 03:24:04.0: Transit Altitude: W[1]=0.00 04-May-2017 03:24:04.0: Highest Altitude: W[6]=0.00 04-May-2017 03:24:04.0: Lateness: W[5]=0.70 04-May-2017 03:24:04.0: Slew Distance: W[2]=0.00 04-May-2017 03:24:04.0: Retry Count: W[3]=0.20 04-May-2017 03:24:04.0: Meridian Crossing: W[4]=0.30 04-May-2017 03:24:04.0: Obs Conditions: W[7]=0.40 04-May-2017 03:24:04.0: Rising Plan Delay: disabled 04-May-2017 03:24:15.8: Weather successfully connected. Wait for valid data... 04-May-2017 03:24:16.9: ++ Observatory Startup ++ 04-May-2017 03:24:16.9: Start ACP Sequencer's StartupObs script 04-May-2017 03:24:26.9: Sequencer is now active 04-May-2017 03:24:51.7: Sequencer is no longer active 04-May-2017 03:24:51.8: Open the observatory dome/roof 04-May-2017 03:25:58.0: **Weather went unsafe while dome opening. 04-May-2017 03:25:58.3: -- Weather Unsafe -- 04-May-2017 03:45:38.6: ++ Weather Safe ++ 04-May-2017 03:45:39.7: ++ Observatory Startup ++ 04-May-2017 03:45:39.7: Start ACP Sequencer's StartupObs script 04-May-2017 03:45:49.7: Sequencer is now active 04-May-2017 03:45:54.8: Sequencer is no longer active 04-May-2017 03:45:55.2: Open the observatory dome/roof 04-May-2017 03:47:16.1: ++ Auto Focus ++ 04-May-2017 03:47:16.1: Doing initial autofocus. 04-May-2017 03:47:16.1: Start special ACP AutoFocus script for scheduler 04-May-2017 03:47:26.1: Sequencer is now active 04-May-2017 03:49:21.5: Sequencer is no longer active 04-May-2017 03:49:21.5: ** Autofocus failed (n=1), will retry ASAP after 0 min. 04-May-2017 03:49:22.8: Plan QQ_Vul has Monitor Mode. Time to resubmit it. 04-May-2017 03:49:22.8: Plan AL_Boo has Monitor Mode. Time to resubmit it. 04-May-2017 03:49:22.8: Plan V1015_Her has Monitor Mode. Time to resubmit it. 04-May-2017 03:49:22.8: Plan DN_Lyn has Monitor Mode. Time to resubmit it. 04-May-2017 03:49:22.8: Plan V0336_Ser has Monitor Mode. Time to resubmit it. 04-May-2017 03:49:22.8: Plan KK_UMa has Monitor Mode. Time to resubmit it. 04-May-2017 03:49:22.8: Plan CV_Vir has Monitor Mode. Time to resubmit it. 04-May-2017 03:49:22.8: SelectRunning: No running plans 04-May-2017 03:49:22.9: Obs BX_Pup in Plan BX_Pup was vetoed by AirMass constraint. 04-May-2017 03:49:22.9: AirMass estimates ready after 04-May-2017 21:42:49 UTC 04-May-2017 03:49:22.9: Obs CB_32 in Plan CB_32 was vetoed by AirMass constraint. 04-May-2017 03:49:22.9: AirMass estimates ready after 04-May-2017 18:17:44 UTC 04-May-2017 03:49:22.9: Obs CM_Cas in Plan CM_Cas was vetoed by AirmassRange constraint. 04-May-2017 03:49:22.9: AirmassRange estimates ready after 04-May-2017 12:12:05 UTC 04-May-2017 03:49:22.9: Obs FU_Cam in Plan FU_Cam was vetoed by AirmassRange constraint. 04-May-2017 03:49:22.9: AirmassRange estimates ready after 04-May-2017 13:49:35 UTC 04-May-2017 03:49:22.9: Obs FW_Cam in Plan FW_Cam was vetoed by AirmassRange constraint. 04-May-2017 03:49:22.9: AirmassRange estimates ready after 04-May-2017 13:50:51 UTC 04-May-2017 03:49:22.9: Obs FY_Cam in Plan FY_Cam was vetoed by AirmassRange constraint. 04-May-2017 03:49:22.9: AirmassRange estimates ready after 04-May-2017 14:09:04 UTC 04-May-2017 03:49:22.9: Obs GSC_3649-0825 in Plan GSC_3649-0825 was vetoed by AirMass constraint. 04-May-2017 03:49:22.9: AirMass estimates ready after 04-May-2017 10:17:51 UTC 04-May-2017 03:49:22.9: Obs GSC_4030_204 in Plan GSC_4030_204 was vetoed by AirMass constraint. 04-May-2017 03:49:22.9: AirMass estimates ready after 04-May-2017 11:41:46 UTC 04-May-2017 03:49:22.9: Obs HI_Cam in Plan HI_Cam was vetoed by AirmassRange constraint. 04-May-2017 03:49:22.9: AirmassRange estimates ready after 04-May-2017 15:05:00 UTC 04-May-2017 03:49:22.9: Obs HQ_Aur in Plan HQ_Aur was vetoed by AirmassRange constraint. 04-May-2017 03:49:22.9: AirmassRange estimates ready after 04-May-2017 16:20:52 UTC 04-May-2017 03:49:23.0: Obs HX_Aur in Plan HX_Aur was vetoed by AirmassRange constraint. 04-May-2017 03:49:23.0: AirmassRange estimates ready after 04-May-2017 15:59:01 UTC 04-May-2017 03:49:23.0: Obs M56 in Plan M56 was vetoed by AirmassRange constraint. 04-May-2017 03:49:23.0: AirmassRange estimates ready after 04-May-2017 06:47:31 UTC 04-May-2017 03:49:23.0: Obs MP_Gem in Plan MP_Gem was vetoed by AirMass constraint. 04-May-2017 03:49:23.0: AirMass estimates ready after 04-May-2017 18:38:40 UTC 04-May-2017 03:49:23.0: Obs MV_Leo in Plan MV_Leo was vetoed by AirMass constraint. 04-May-2017 03:49:23.0: AirMass estimates ready after 04-May-2017 07:16:28 UTC 04-May-2017 03:49:23.0: Obs NGC_7790 in Plan NGC_7790 was vetoed by AirmassRange constraint. 04-May-2017 03:49:23.0: AirmassRange estimates ready after 04-May-2017 10:32:27 UTC 04-May-2017 03:49:23.0: Obs QQ_Vul_TMO61 in Plan QQ_Vul was vetoed by AirMass constraint. 04-May-2017 03:49:23.0: AirMass estimates ready after 04-May-2017 07:20:12 UTC 04-May-2017 03:49:23.0: STRICT VETO: 04-May-2017 03:49:23.0: Obs SDSS073224 in Plan SDSS073224 was vetoed by AirMass constraint. 04-May-2017 03:49:23.0: AirMass estimates ready after 04-May-2017 19:07:32 UTC 04-May-2017 03:49:23.0: Obs SDSSJ121027+502735 in Plan SDSSJ121027+502735 was vetoed by MoonDown constraint. 04-May-2017 03:49:23.0: MoonDown estimates ready after 04-May-2017 09:03:02 UTC 04-May-2017 03:49:23.0: Obs T_Ori in Plan T_Ori was vetoed by AirmassRange constraint. 04-May-2017 03:49:23.0: AirmassRange estimates ready after 04-May-2017 18:34:33 UTC 04-May-2017 03:49:23.1: Obs U_Aqr in Plan U_Aqr was vetoed by AirmassRange constraint. 04-May-2017 03:49:23.1: AirmassRange estimates ready after 04-May-2017 11:54:11 UTC 04-May-2017 03:49:23.1: Obs V0336_Ser in Plan V0336_Ser was vetoed by AirmassRange constraint. 04-May-2017 03:49:23.1: AirmassRange estimates ready after 04-May-2017 03:51:39 UTC 04-May-2017 03:49:23.1: Obs V0524_Cas in Plan V0524_Cas was vetoed by AirmassRange constraint. 04-May-2017 03:49:23.1: AirmassRange estimates ready after 04-May-2017 11:02:16 UTC 04-May-2017 03:49:23.1: Obs V0603_Per in Plan V0603_Per was vetoed by AirmassRange constraint. 04-May-2017 03:49:23.1: AirmassRange estimates ready after 04-May-2017 14:39:46 UTC 04-May-2017 03:49:23.1: Obs V0809_Cep in Plan V0809_Cep was vetoed by AirmassRange constraint. 04-May-2017 03:49:23.1: AirmassRange estimates ready after 04-May-2017 09:43:07 UTC 04-May-2017 03:49:23.1: Obs V0959_Mon in Plan V0959_Mon was vetoed by AirmassRange constraint. 04-May-2017 03:49:23.1: AirmassRange estimates ready after 04-May-2017 19:02:34 UTC 04-May-2017 03:49:23.1: Obs V0965_Per in Plan V0965_Per was vetoed by AirmassRange constraint. 04-May-2017 03:49:23.1: AirmassRange estimates ready after 04-May-2017 14:28:13 UTC 04-May-2017 03:49:23.1: Obs V1015_Her in Plan V1015_Her was vetoed by AirmassRange constraint. 04-May-2017 03:49:23.1: AirmassRange estimates ready after 04-May-2017 05:16:07 UTC 04-May-2017 03:49:23.1: Obs V1721_Aql in Plan V1721_Aql was vetoed by AirmassRange constraint. 04-May-2017 03:49:23.1: AirmassRange estimates ready after 04-May-2017 07:28:02 UTC 04-May-2017 03:49:23.1: Obs V1722_Aql in Plan V1722_Aql was vetoed by AirmassRange constraint. 04-May-2017 03:49:23.1: AirmassRange estimates ready after 04-May-2017 07:15:44 UTC 04-May-2017 03:49:23.1: Obs V1723_Aql in Plan V1723_Aql was vetoed by AirmassRange constraint. 04-May-2017 03:49:23.1: AirmassRange estimates ready after 04-May-2017 07:42:06 UTC 04-May-2017 03:49:23.1: Obs V1724_Aql in Plan V1724_Aql was vetoed by AirmassRange constraint. 04-May-2017 03:49:23.1: AirmassRange estimates ready after 04-May-2017 07:35:33 UTC 04-May-2017 03:49:23.1: Obs V339_Del_TMO61 in Plan V339_Del_TMO61 was vetoed by AirmassRange constraint. 04-May-2017 03:49:23.1: AirmassRange estimates ready after 04-May-2017 08:12:55 UTC 04-May-2017 03:49:23.2: Obs V841_Oph in Plan V841_Oph was vetoed by AirmassRange constraint. 04-May-2017 03:49:23.2: AirmassRange estimates ready after 04-May-2017 06:31:21 UTC 04-May-2017 03:49:23.2: Obs WZ_Per in Plan WZ_Per was vetoed by AirmassRange constraint. 04-May-2017 03:49:23.2: AirmassRange estimates ready after 04-May-2017 14:48:32 UTC 04-May-2017 03:49:23.2: Efficiency: Selecting from 10 pending plans: 04-May-2017 03:49:23.3: Selected Plan BY_Cam (TotEff=2.14) 04-May-2017 03:49:23.3: Dispatcher cycle time: 1.5260873 sec. 04-May-2017 03:49:23.3: Acquire data for Observation BY_Cam... 04-May-2017 03:49:23.3: (belongs to Project Walker, Plan BY_Cam) 04-May-2017 03:49:23.3: Doing 30 sets 04-May-2017 03:49:23.4: Send Observation BY_Cam to ACP Sequencer 04-May-2017 03:49:33.4: Sequencer is now active 04-May-2017 04:00:03.9: ** Script interrupted by weather safety event. 04-May-2017 04:00:06.9: Post-job status check done (stat=Running) 04-May-2017 04:00:06.9: ACP ABORT: Acquisition process was interrupted by weather unsafe. 04-May-2017 04:00:06.9: ACP data acquisition failed for Observation BY_Cam. 04-May-2017 04:00:06.9: (Plan BY_Cam will be resubmitted in its entirety. 04-May-2017 04:00:06.9: (Observation BY_Cam interrupted by weather unsafe event.) 04-May-2017 04:00:07.3: -- Weather Unsafe -- 04-May-2017 04:19:49.8: ++ Weather Safe ++ 04-May-2017 04:19:50.9: ++ Observatory Startup ++ 04-May-2017 04:19:50.9: Start ACP Sequencer's StartupObs script 04-May-2017 04:20:00.9: Sequencer is now active 04-May-2017 04:20:05.9: Sequencer is no longer active 04-May-2017 04:20:06.3: Open the observatory dome/roof 04-May-2017 04:21:27.8: ++ Auto Focus ++ 04-May-2017 04:21:27.8: Doing initial autofocus. 04-May-2017 04:21:27.8: Start special ACP AutoFocus script for scheduler 04-May-2017 04:21:37.8: Sequencer is now active 04-May-2017 04:24:17.3: Sequencer is no longer active 04-May-2017 04:24:17.3: ** Autofocus failed (n=2), will retry ASAP after 5 min. 04-May-2017 04:24:17.4: SelectRunning: No running plans 04-May-2017 04:24:17.5: STRICT VETO: 04-May-2017 04:24:17.5: Obs M67 in Plan M67 was vetoed by AirMass constraint. 04-May-2017 04:24:17.5: AirMass estimates ready after 04-May-2017 21:27:33 UTC 04-May-2017 04:24:17.5: Efficiency: Selecting from 10 pending plans: 04-May-2017 04:24:17.6: Selected Plan BY_Cam (TotEff=2.19) 04-May-2017 04:24:17.7: Dispatcher cycle time: 0.3310189 sec. 04-May-2017 04:24:17.7: Acquire data for Observation BY_Cam... 04-May-2017 04:24:17.7: (belongs to Project Walker, Plan BY_Cam) 04-May-2017 04:24:17.7: Doing 30 sets 04-May-2017 04:24:17.7: TestConstraints: AirMass vetoed/failed for end time of Obs 04-May-2017 04:24:17.7: ***Running Plan BY_Cam (best efforts) completed early: 04-May-2017 04:24:17.7: Obs BY_Cam would would not meet constraints 04-May-2017 04:24:17.7: Acquisition time: 0 sec. 04-May-2017 04:24:17.7: Data for Observation BY_Cam acquired successfully. 04-May-2017 04:24:17.7: Image Efficiency: 0.0% 04-May-2017 04:24:17.7: Cycle Efficiency: 0.0% 04-May-2017 04:24:17.8: SelectRunning: No running plans 04-May-2017 04:24:17.9: Efficiency: Selecting from 9 pending plans: 04-May-2017 04:24:18.0: Selected Plan DN_Lyn (TotEff=1.14) 04-May-2017 04:24:18.0: Dispatcher cycle time: 0.2050117 sec. 04-May-2017 04:24:18.0: Acquire data for Observation DN_Lyn... 04-May-2017 04:24:18.0: (belongs to Project AAVSO_HGUA_Miras_Manual_ver167, Plan DN_Lyn) 04-May-2017 04:24:18.0: Send Observation DN_Lyn to ACP Sequencer 04-May-2017 04:24:28.0: Sequencer is now active 04-May-2017 04:36:49.8: ** Script interrupted by weather safety event. 04-May-2017 04:36:52.8: Post-job status check done (stat=Running) 04-May-2017 04:36:52.8: ACP ABORT: Acquisition process was interrupted by weather unsafe. 04-May-2017 04:36:52.8: ACP data acquisition failed for Observation DN_Lyn. 04-May-2017 04:36:52.8: (Plan DN_Lyn will be resubmitted in its entirety. 04-May-2017 04:36:52.8: (Observation DN_Lyn interrupted by weather unsafe event.) 04-May-2017 04:36:53.3: -- Weather Unsafe -- 04-May-2017 09:24:52.0: ++ Weather Safe ++ 04-May-2017 09:24:53.2: ++ Observatory Startup ++ 04-May-2017 09:24:53.3: Start ACP Sequencer's StartupObs script 04-May-2017 09:24:53.6: WARNING: Request sent while Weather Safety Script is running 04-May-2017 09:25:54.2: WARNING: Request sent while Weather Safety Script is running 04-May-2017 09:26:54.5: WARNING: Request sent while Weather Safety Script is running 04-May-2017 09:27:54.8: WARNING: Request sent while Weather Safety Script is running 04-May-2017 09:28:55.0: WARNING: Request sent while Weather Safety Script is running 04-May-2017 09:29:55.3: WARNING: Request sent while Weather Safety Script is running 04-May-2017 09:30:55.6: WARNING: Request sent while Weather Safety Script is running 04-May-2017 09:31:55.8: WARNING: Request sent while Weather Safety Script is running 04-May-2017 09:32:56.1: WARNING: Request sent while Weather Safety Script is running 04-May-2017 09:33:56.3: WARNING: Request sent while Weather Safety Script is running 04-May-2017 09:34:56.6: WARNING: Request sent while Weather Safety Script is running 04-May-2017 09:35:56.9: WARNING: Request sent while Weather Safety Script is running 04-May-2017 09:36:57.1: WARNING: Request sent while Weather Safety Script is running 04-May-2017 09:37:57.3: WARNING: Request sent while Weather Safety Script is running 04-May-2017 09:38:57.6: WARNING: Request sent while Weather Safety Script is running 04-May-2017 09:39:58.0: **EXCEPTION IN SCHEDULER: 04-May-2017 09:39:58.0: Observatory startup failed, cannot continue. 04-May-2017 09:39:58.0: Traceback: at DC3.Scheduler.Engine.DoStartupIf(Boolean openDome) at DC3.Scheduler.Engine.Run() 04-May-2017 09:39:58.0: Run statistics: 04-May-2017 09:39:58.0: Observations: 55 04-May-2017 09:39:58.0: Considered: 21 04-May-2017 09:39:58.0: Completed: 10 04-May-2017 09:39:58.0: Skipped: 9 04-May-2017 09:39:58.0: Never Eligible: 34 04-May-2017 09:39:58.0: Failed: 2 04-May-2017 09:39:58.0: Shutter-Open efficiency: 0.00% 04-May-2017 09:39:58.0: Overall Efficiency: 99.84% 04-May-2017 09:39:58.0: Release ACP sequencer Log closed at Thu, May 04 2017 09:39:59 UTC (actual time)