Log opened at Wed, Sep 13 2017 03:37:09 UTC (actual time)
Current log level is Debug
Scheduler version 3.5.3
Multi-user license: Arne Henden
13-Sep-2017 03:37:09.6: ++ Observatory Startup ++
13-Sep-2017 03:37:09.6: Start ACP Sequencer's StartupObs script
13-Sep-2017 03:37:19.6: Sequencer is now active
13-Sep-2017 03:37:42.7: Sequencer is no longer active
13-Sep-2017 03:37:42.9: Open the observatory dome/roof
13-Sep-2017 03:38:48.9: ++ Auto Focus ++
13-Sep-2017 03:38:48.9: Doing initial autofocus.
13-Sep-2017 03:38:48.9: Start special ACP AutoFocus script for scheduler
13-Sep-2017 03:38:58.9: Sequencer is now active
13-Sep-2017 03:53:08.4: Sequencer is no longer active
13-Sep-2017 03:53:08.4: ** Autofocus failed (n=1), will retry ASAP after 0 min.
13-Sep-2017 03:53:08.4: Plan V0809_Cep has Monitor Mode. Time to resubmit it.
13-Sep-2017 03:53:08.4: Plan GSC_3649-0825 has Monitor Mode. Time to resubmit it.
13-Sep-2017 03:53:08.4: Plan CB_32 has Monitor Mode. Time to resubmit it.
13-Sep-2017 03:53:08.4: Plan V0965_Per has Monitor Mode. Time to resubmit it.
13-Sep-2017 03:53:08.4: Plan NGC_7790 has Monitor Mode. Time to resubmit it.
13-Sep-2017 03:53:08.4: Plan V339_Del_TMO61 has Monitor Mode. Time to resubmit it.
13-Sep-2017 03:53:08.4: Plan AF_And has Monitor Mode. Time to resubmit it.
13-Sep-2017 03:53:08.4: SelectRunning: No running plans
13-Sep-2017 03:53:08.4: Obs AL_Boo in Plan AL_Boo was vetoed by AirmassRange constraint.
13-Sep-2017 03:53:08.4:     AirmassRange estimates ready after 13-Sep-2017 16:50:53 UTC
13-Sep-2017 03:53:08.4: Obs CB_32 in Plan CB_32 was vetoed by AirMass constraint.
13-Sep-2017 03:53:08.4:     AirMass estimates ready after 13-Sep-2017 09:38:44 UTC
13-Sep-2017 03:53:08.4: Obs CV_Vir in Plan CV_Vir was vetoed by AirmassRange constraint.
13-Sep-2017 03:53:08.4:     AirmassRange estimates ready after 13-Sep-2017 15:57:38 UTC
13-Sep-2017 03:53:08.4: Obs DN_Lyn in Plan DN_Lyn was vetoed by AirmassRange constraint.
13-Sep-2017 03:53:08.4:     AirmassRange estimates ready after 13-Sep-2017 09:50:29 UTC
13-Sep-2017 03:53:08.5: Obs FU_Cam in Plan FU_Cam was vetoed by AirmassRange constraint.
13-Sep-2017 03:53:08.5:     AirmassRange estimates ready after 13-Sep-2017 05:10:35 UTC
13-Sep-2017 03:53:08.5: Obs FW_Cam in Plan FW_Cam was vetoed by AirmassRange constraint.
13-Sep-2017 03:53:08.5:     AirmassRange estimates ready after 13-Sep-2017 05:11:51 UTC
13-Sep-2017 03:53:08.5: Obs FY_Cam in Plan FY_Cam was vetoed by AirmassRange constraint.
13-Sep-2017 03:53:08.5:     AirmassRange estimates ready after 13-Sep-2017 05:30:04 UTC
13-Sep-2017 03:53:08.5: Obs HI_Cam in Plan HI_Cam was vetoed by AirmassRange constraint.
13-Sep-2017 03:53:08.5:     AirmassRange estimates ready after 13-Sep-2017 06:26:01 UTC
13-Sep-2017 03:53:08.5: Obs HO_Cam in Plan HO_Cam was vetoed by AirmassRange constraint.
13-Sep-2017 03:53:08.5:     AirmassRange estimates ready after 13-Sep-2017 07:10:46 UTC
13-Sep-2017 03:53:08.5: Obs HQ_Aur in Plan HQ_Aur was vetoed by AirmassRange constraint.
13-Sep-2017 03:53:08.5:     AirmassRange estimates ready after 13-Sep-2017 07:41:52 UTC
13-Sep-2017 03:53:08.5: Obs HX_Aur in Plan HX_Aur was vetoed by AirmassRange constraint.
13-Sep-2017 03:53:08.5:     AirmassRange estimates ready after 13-Sep-2017 07:20:02 UTC
13-Sep-2017 03:53:08.5: Obs KK_UMa in Plan KK_UMa was vetoed by AirmassRange constraint.
13-Sep-2017 03:53:08.5:     AirmassRange estimates ready after 13-Sep-2017 13:30:44 UTC
13-Sep-2017 03:53:08.6: Obs M67 in Plan M67 was vetoed by AirMass constraint.
13-Sep-2017 03:53:08.6:     AirMass estimates ready after 13-Sep-2017 12:48:33 UTC
13-Sep-2017 03:53:08.6: Obs MP_Gem in Plan MP_Gem was vetoed by AirMass constraint.
13-Sep-2017 03:53:08.6:     AirMass estimates ready after 13-Sep-2017 09:59:41 UTC
13-Sep-2017 03:53:08.6: Obs SDSS073224 in Plan SDSS073224 was vetoed by AirMass constraint.
13-Sep-2017 03:53:08.6:     AirMass estimates ready after 13-Sep-2017 10:28:33 UTC
13-Sep-2017 03:53:08.6: Obs SDSSJ121027+502735 in Plan SDSSJ121027+502735 was vetoed by AirMass constraint.
13-Sep-2017 03:53:08.6:     AirMass estimates ready after 13-Sep-2017 14:23:22 UTC
13-Sep-2017 03:53:08.6: Obs T_Ori in Plan T_Ori was vetoed by AirmassRange constraint.
13-Sep-2017 03:53:08.6:     AirmassRange estimates ready after 13-Sep-2017 09:55:33 UTC
13-Sep-2017 03:53:08.6: STRICT VETO:
13-Sep-2017 03:53:08.6: Obs V0336_Ser in Plan V0336_Ser was vetoed by AirmassRange constraint.
13-Sep-2017 03:53:08.6:     AirmassRange estimates ready after 13-Sep-2017 19:08:43 UTC
13-Sep-2017 03:53:08.6: Obs V0603_Per in Plan V0603_Per was vetoed by AirmassRange constraint.
13-Sep-2017 03:53:08.6:     AirmassRange estimates ready after 13-Sep-2017 06:00:46 UTC
13-Sep-2017 03:53:08.6: Obs V0959_Mon in Plan V0959_Mon was vetoed by AirmassRange constraint.
13-Sep-2017 03:53:08.6:     AirmassRange estimates ready after 13-Sep-2017 10:23:34 UTC
13-Sep-2017 03:53:08.7: Obs V0965_Per in Plan V0965_Per was vetoed by AirmassRange constraint.
13-Sep-2017 03:53:08.7:     AirmassRange estimates ready after 13-Sep-2017 05:49:13 UTC
13-Sep-2017 03:53:08.7: Obs V841_Oph in Plan V841_Oph was vetoed by AirmassRange constraint.
13-Sep-2017 03:53:08.7:     AirmassRange estimates ready after 13-Sep-2017 21:48:25 UTC
13-Sep-2017 03:53:08.7: Efficiency: Selecting from 18 pending plans:
13-Sep-2017 03:53:08.8:   Selected Plan V1723_Aql (TotEff=1.03)
13-Sep-2017 03:53:08.9: Dispatcher cycle time: 0.5050288 sec.
13-Sep-2017 03:53:08.9: Acquire data for Observation V1723_Aql...
13-Sep-2017 03:53:08.9:   (belongs to Project Nova_Monitoring, Plan V1723_Aql)
13-Sep-2017 03:53:08.9: Send Observation V1723_Aql to ACP Sequencer
13-Sep-2017 03:53:18.9: Sequencer is now active
13-Sep-2017 04:12:46.3: Sequencer is no longer active
13-Sep-2017 04:12:46.3: Post-job status check done (stat=Completed)
13-Sep-2017 04:12:46.4: Acquisition time: 1177.445346 sec.
13-Sep-2017 04:12:46.4: Data for Observation V1723_Aql acquired successfully.
13-Sep-2017 04:12:46.4: Image Efficiency: 76.4%
13-Sep-2017 04:12:46.4: Cycle Efficiency: 100.0%
13-Sep-2017 04:12:46.5: ++ Auto Focus ++
13-Sep-2017 04:12:46.5: Doing periodic autofocus.
13-Sep-2017 04:12:46.5: Start special ACP AutoFocus script for scheduler
13-Sep-2017 04:12:56.5: Sequencer is now active
13-Sep-2017 04:15:12.7: Sequencer is no longer active
13-Sep-2017 04:15:12.7: ** Autofocus failed (n=2), will retry ASAP after 5 min.
13-Sep-2017 04:15:12.8: SelectRunning: No running plans
13-Sep-2017 04:15:12.9: Efficiency: Selecting from 17 pending plans:
13-Sep-2017 04:15:13.0:   Selected Plan V1724_Aql (TotEff=1.04)
13-Sep-2017 04:15:13.1: Dispatcher cycle time: 0.34902 sec.
13-Sep-2017 04:15:13.1: Acquire data for Observation V1724_Aql...
13-Sep-2017 04:15:13.1:   (belongs to Project Nova_Monitoring, Plan V1724_Aql)
13-Sep-2017 04:15:13.1: Send Observation V1724_Aql to ACP Sequencer
13-Sep-2017 04:15:23.1: Sequencer is now active
13-Sep-2017 04:34:54.8: Sequencer is no longer active
13-Sep-2017 04:34:54.8: Post-job status check done (stat=Completed)
13-Sep-2017 04:34:54.8: Acquisition time: 1181.6715877 sec.
13-Sep-2017 04:34:54.8: Data for Observation V1724_Aql acquired successfully.
13-Sep-2017 04:34:54.8: Image Efficiency: 76.1%
13-Sep-2017 04:34:54.8: Cycle Efficiency: 100.0%
13-Sep-2017 04:34:54.9: ++ Auto Focus ++
13-Sep-2017 04:34:55.0: Doing periodic autofocus.
13-Sep-2017 04:34:55.0: Start special ACP AutoFocus script for scheduler
13-Sep-2017 04:35:05.0: Sequencer is now active
13-Sep-2017 04:38:28.5: Sequencer is no longer active
13-Sep-2017 04:38:28.5: Next periodic autofocus ASAP after 13-Sep-2017 06:37:45 UTC
13-Sep-2017 04:38:28.5: SelectRunning: No running plans
13-Sep-2017 04:38:28.6: Efficiency: Selecting from 16 pending plans:
13-Sep-2017 04:38:28.7:   Selected Plan V1015_Her (TotEff=1.05)
13-Sep-2017 04:38:28.8: Dispatcher cycle time: 0.2940168 sec.
13-Sep-2017 04:38:28.8: Acquire data for Observation V1015_Her...
13-Sep-2017 04:38:28.8:   (belongs to Project AAVSO_HGUA_Miras_Manual_ver167, Plan V1015_Her)
13-Sep-2017 04:38:28.8: Send Observation V1015_Her to ACP Sequencer
13-Sep-2017 04:38:38.8: Sequencer is now active
13-Sep-2017 04:49:34.8: ** Script interrupted by weather safety event.
13-Sep-2017 04:49:37.8: Post-job status check done (stat=Running)
13-Sep-2017 04:49:37.8: ACP ABORT: Acquisition process was interrupted by weather unsafe.
13-Sep-2017 04:49:37.8: ACP data acquisition failed for Observation V1015_Her.
13-Sep-2017 04:49:37.8:   (Plan V1015_Her will be resubmitted in its entirety.
13-Sep-2017 04:49:37.8:   (Observation V1015_Her interrupted by weather unsafe event.)
13-Sep-2017 04:49:38.2: -- Weather Unsafe --
13-Sep-2017 06:29:52.7: ++ Weather Safe ++
13-Sep-2017 06:29:54.3: ++ Observatory Startup ++
13-Sep-2017 06:29:54.4: Start ACP Sequencer's StartupObs script
13-Sep-2017 06:29:54.4: WARNING: Request sent while Weather Safety Script is running
13-Sep-2017 06:30:54.6: WARNING: Request sent while Weather Safety Script is running
13-Sep-2017 06:31:54.6: WARNING: Request sent while Weather Safety Script is running
13-Sep-2017 06:32:54.7: WARNING: Request sent while Weather Safety Script is running
13-Sep-2017 06:33:54.9: WARNING: Request sent while Weather Safety Script is running
13-Sep-2017 06:34:55.1: WARNING: Request sent while Weather Safety Script is running
13-Sep-2017 06:35:55.4: WARNING: Request sent while Weather Safety Script is running
13-Sep-2017 06:36:55.7: WARNING: Request sent while Weather Safety Script is running
13-Sep-2017 06:37:55.9: WARNING: Request sent while Weather Safety Script is running
13-Sep-2017 06:38:56.1: WARNING: Request sent while Weather Safety Script is running
13-Sep-2017 06:39:56.4: WARNING: Request sent while Weather Safety Script is running
13-Sep-2017 06:40:56.6: WARNING: Request sent while Weather Safety Script is running
13-Sep-2017 06:41:56.9: WARNING: Request sent while Weather Safety Script is running
13-Sep-2017 06:42:57.1: WARNING: Request sent while Weather Safety Script is running
13-Sep-2017 06:43:57.4: WARNING: Request sent while Weather Safety Script is running
13-Sep-2017 06:44:57.7: **EXCEPTION IN SCHEDULER:
13-Sep-2017 06:44:57.7: Observatory startup failed, cannot continue.
13-Sep-2017 06:44:57.7: Traceback:
   at DC3.Scheduler.Engine.DoStartupIf(Boolean openDome)
   at DC3.Scheduler.Engine.Run()
13-Sep-2017 06:44:57.8: Run statistics:
13-Sep-2017 06:44:57.8:   Observations:   51
13-Sep-2017 06:44:57.8:   Considered:     43
13-Sep-2017 06:44:57.8:   Completed:      11
13-Sep-2017 06:44:57.8:   Skipped:        30
13-Sep-2017 06:44:57.8:   Never Eligible: 8
13-Sep-2017 06:44:57.8:   Failed:         2
13-Sep-2017 06:44:57.8: Shutter-Open efficiency: 74.33%
13-Sep-2017 06:44:57.8: Overall Efficiency: 99.90%
13-Sep-2017 06:44:57.8: Release ACP sequencer
Log closed at Wed, Sep 13 2017 06:45:00 UTC (actual time)