Log opened at Fri, Dec 09 2016 00:52:47 UTC (actual time)
Current log level is Verbose
Scheduler version 3.5.3
Multi-user license: Arne Henden
09-Dec-2016 00:52:47.4: Simulated clock initialized at 09-Dec-2016 00:52:47 UTC
09-Dec-2016 00:52:47.4: Loaded Constraint plugin AirMass
09-Dec-2016 00:52:47.4: Loaded Constraint plugin AirmassRange
09-Dec-2016 00:52:47.4: Loaded Constraint plugin Horizon
09-Dec-2016 00:52:47.4: Loaded Constraint plugin HourAngle
09-Dec-2016 00:52:47.4: Loaded Constraint plugin MoonAvoid
09-Dec-2016 00:52:47.4: Loaded Constraint plugin MoonDown
09-Dec-2016 00:52:47.4: Loaded Constraint plugin SkyCondition
09-Dec-2016 00:52:47.4: Loaded Constraint plugin TimeRange
09-Dec-2016 00:52:48.0: 13 plans are now pending.
09-Dec-2016 00:52:48.0: Attach ACP sequencer
09-Dec-2016 00:52:58.7: Dispatcher started at 09-Dec-2016 00:52:58 UTC
09-Dec-2016 00:52:58.7:     Priority:          W[0]=0.00
09-Dec-2016 00:52:58.7:     Transit Altitude:  W[1]=0.00
09-Dec-2016 00:52:58.7:     Highest Altitude:  W[6]=0.00
09-Dec-2016 00:52:58.7:     Lateness:          W[5]=0.70
09-Dec-2016 00:52:58.7:     Slew Distance:     W[2]=0.30
09-Dec-2016 00:52:58.7:     Retry Count:       W[3]=0.20
09-Dec-2016 00:52:58.7:     Meridian Crossing: W[4]=0.30
09-Dec-2016 00:52:58.7:     Obs Conditions:    W[7]=0.40
09-Dec-2016 00:52:58.7:     Rising Plan Delay: disabled
09-Dec-2016 00:53:00.1: ++ Observatory Startup ++
09-Dec-2016 00:53:00.1: Start ACP Sequencer's StartupObs script
09-Dec-2016 00:59:34.4: ++ Auto Focus ++
09-Dec-2016 00:59:34.5: Doing initial autofocus.
09-Dec-2016 00:59:34.5: Start special ACP AutoFocus script for scheduler
09-Dec-2016 02:41:57.2: Dispatcher stopped at 09-Dec-2016 02:41:57 UTC
09-Dec-2016 02:41:57.2: **DISPATCHER INTERRUPT LEVEL 1 RECEIVED**
09-Dec-2016 02:42:44.5: ** Sequencer script "AutoFocusScheduler" failed:
09-Dec-2016 02:42:44.5: **Script Error**
Source:  ACP
Message: The script was aborted. at line 69 column 9.
Script Context:
      66:         Telescope.Tracking = True                               ' AcquireStar needs tracking scope
      67:     Else
      68:         Call SUP.StartSlewJ2000("FocusArea", CT.RightAscension, CT.Declination)
=>    69:         Call SUP.WaitForSlew()
                  ^
      70:     End If
      71:     
      72: 	If Not SUP.AutoFocus(CT.RightAscension, CT.Declination) Then
-- end --

09-Dec-2016 02:42:44.5: ** Autofocus failed (n=1), will retry ASAP after 0 min.
09-Dec-2016 02:58:12.9: Dispatcher started at 09-Dec-2016 02:58:12 UTC
09-Dec-2016 02:58:12.9:     Priority:          W[0]=0.00
09-Dec-2016 02:58:12.9:     Transit Altitude:  W[1]=0.00
09-Dec-2016 02:58:12.9:     Highest Altitude:  W[6]=0.00
09-Dec-2016 02:58:12.9:     Lateness:          W[5]=0.70
09-Dec-2016 02:58:12.9:     Slew Distance:     W[2]=0.30
09-Dec-2016 02:58:12.9:     Retry Count:       W[3]=0.20
09-Dec-2016 02:58:12.9:     Meridian Crossing: W[4]=0.30
09-Dec-2016 02:58:12.9:     Obs Conditions:    W[7]=0.40
09-Dec-2016 02:58:12.9:     Rising Plan Delay: disabled
09-Dec-2016 02:58:14.0: ++ Observatory Startup ++
09-Dec-2016 02:58:14.0: Start ACP Sequencer's StartupObs script
09-Dec-2016 02:58:32.2: ++ Auto Focus ++
09-Dec-2016 02:58:32.2: Doing initial autofocus.
09-Dec-2016 02:58:32.2: Start special ACP AutoFocus script for scheduler
09-Dec-2016 03:01:57.9: Next periodic autofocus ASAP after 09-Dec-2016 05:01:33 UTC
09-Dec-2016 03:01:58.6: Plan V0809_Cep has Monitor Mode. Time to resubmit it.
09-Dec-2016 03:01:58.6: Plan V0959_Mon has Monitor Mode. Time to resubmit it.
09-Dec-2016 03:01:58.6: Plan MP_Gem has Monitor Mode. Time to resubmit it.
09-Dec-2016 03:01:58.6: Plan GSC_3649-0825 has Monitor Mode. Time to resubmit it.
09-Dec-2016 03:01:58.6: Plan CB_32 has Monitor Mode. Time to resubmit it.
09-Dec-2016 03:01:58.6: Plan V1404_Cyg has Monitor Mode. Time to resubmit it.
09-Dec-2016 03:01:58.6: Plan V0965_Per has Monitor Mode. Time to resubmit it.
09-Dec-2016 03:01:58.6: Plan SDSS073224 has Monitor Mode. Time to resubmit it.
09-Dec-2016 03:01:58.6: Plan M67 has Monitor Mode. Time to resubmit it.
09-Dec-2016 03:01:58.6: Plan QQ_Vul has Monitor Mode. Time to resubmit it.
09-Dec-2016 03:01:58.6: Plan FO_Aqr has Monitor Mode. Time to resubmit it.
09-Dec-2016 03:01:58.6: Plan GSC_4030_204 has Monitor Mode. Time to resubmit it.
09-Dec-2016 03:01:58.6: Plan BX_Pup has Monitor Mode. Time to resubmit it.
09-Dec-2016 03:01:58.6: Plan Z_Cam has Monitor Mode. Time to resubmit it.
09-Dec-2016 03:01:58.6: Plan AT_Cnc has Monitor Mode. Time to resubmit it.
09-Dec-2016 03:01:58.6: Plan SY_Cnc has Monitor Mode. Time to resubmit it.
09-Dec-2016 03:01:58.6: Plan NGC_7790 has Monitor Mode. Time to resubmit it.
09-Dec-2016 03:01:58.6: Plan T_Ori has Monitor Mode. Time to resubmit it.
09-Dec-2016 03:01:58.6: Plan U_Aqr has Monitor Mode. Time to resubmit it.
09-Dec-2016 03:01:59.0: Dispatcher cycle time: 0.5800008 sec.
09-Dec-2016 03:01:59.0: Acquire data for Observation V1404_Cyg_TMO61...
09-Dec-2016 03:01:59.0:   (belongs to Project Walker, Plan V1404_Cyg)
09-Dec-2016 03:01:59.0: Send Observation V1404_Cyg_TMO61 to ACP Sequencer
09-Dec-2016 03:05:21.5: ACP data acquisition failed for Observation V1404_Cyg_TMO61.
09-Dec-2016 03:05:21.5:   (Observation V1404_Cyg_TMO61 interrupted by acquisition process failure.)
09-Dec-2016 03:05:23.5: Dispatcher cycle time: 0.2600004 sec.
09-Dec-2016 03:05:23.5: Acquire data for Observation FO_Aqr...
09-Dec-2016 03:05:23.5:   (belongs to Project Walker, Plan FO_Aqr)
09-Dec-2016 03:05:23.5: Doing 500 sets
09-Dec-2016 03:05:23.5: Send Observation FO_Aqr to ACP Sequencer
09-Dec-2016 03:08:44.5: Send Observation FO_Aqr to ACP Sequencer
09-Dec-2016 03:11:05.1: Send Observation FO_Aqr to ACP Sequencer
09-Dec-2016 03:13:24.9: Send Observation FO_Aqr to ACP Sequencer
09-Dec-2016 03:15:47.0: Send Observation FO_Aqr to ACP Sequencer
09-Dec-2016 03:18:11.0: Send Observation FO_Aqr to ACP Sequencer
09-Dec-2016 03:20:29.5: Send Observation FO_Aqr to ACP Sequencer
09-Dec-2016 03:22:52.8: Send Observation FO_Aqr to ACP Sequencer
09-Dec-2016 03:25:15.3: Send Observation FO_Aqr to ACP Sequencer
09-Dec-2016 03:27:37.3: Send Observation FO_Aqr to ACP Sequencer
09-Dec-2016 03:29:58.0: Send Observation FO_Aqr to ACP Sequencer
09-Dec-2016 03:32:19.7: Send Observation FO_Aqr to ACP Sequencer
09-Dec-2016 03:34:41.4: Send Observation FO_Aqr to ACP Sequencer
09-Dec-2016 03:37:01.8: Send Observation FO_Aqr to ACP Sequencer
09-Dec-2016 03:39:21.4: Send Observation FO_Aqr to ACP Sequencer
09-Dec-2016 03:41:39.9: Send Observation FO_Aqr to ACP Sequencer
09-Dec-2016 03:44:01.2: Send Observation FO_Aqr to ACP Sequencer
09-Dec-2016 03:46:23.2: Send Observation FO_Aqr to ACP Sequencer
09-Dec-2016 03:48:45.8: Send Observation FO_Aqr to ACP Sequencer
09-Dec-2016 03:51:05.8: ***Running Plan FO_Aqr (best efforts) completed early:
09-Dec-2016 03:51:05.8:    Obs FO_Aqr would would not meet constraints
09-Dec-2016 03:51:05.8: Acquisition time: 2742.0678389 sec.
09-Dec-2016 03:51:05.8: Data for Observation FO_Aqr acquired successfully.
09-Dec-2016 03:51:05.8: Image Efficiency: 69.3%
09-Dec-2016 03:51:05.8: Cycle Efficiency: 100.0%
09-Dec-2016 03:51:07.8: Dispatcher cycle time: 0.1200002 sec.
09-Dec-2016 03:51:07.8: Acquire data for Observation V0809_Cep...
09-Dec-2016 03:51:07.8:   (belongs to Project Nova_Monitoring, Plan V0809_Cep)
09-Dec-2016 03:51:07.8: Send Observation V0809_Cep to ACP Sequencer
09-Dec-2016 04:21:25.1: Acquisition time: 1817.2645443 sec.
09-Dec-2016 04:21:25.1: Data for Observation V0809_Cep acquired successfully.
09-Dec-2016 04:21:25.1: Image Efficiency: 77.0%
09-Dec-2016 04:21:25.1: Cycle Efficiency: 100.0%
09-Dec-2016 04:21:25.4: Dispatcher cycle time: 0.1700002 sec.
09-Dec-2016 04:21:25.4: Acquire data for Observation GSC_3649-0825...
09-Dec-2016 04:21:25.4:   (belongs to Project Staels, Plan GSC_3649-0825)
09-Dec-2016 04:21:25.4: Send Observation GSC_3649-0825 to ACP Sequencer
09-Dec-2016 04:25:38.7: Acquisition time: 253.3503547 sec.
09-Dec-2016 04:25:38.7: Data for Observation GSC_3649-0825 acquired successfully.
09-Dec-2016 04:25:38.8: Image Efficiency: 53.2%
09-Dec-2016 04:25:38.8: Cycle Efficiency: 99.9%
09-Dec-2016 04:25:39.9: Dispatcher cycle time: 0.1900003 sec.
09-Dec-2016 04:25:39.9: Acquire data for Observation NGC_7790...
09-Dec-2016 04:25:39.9:   (belongs to Project AAVSO_Monitoring, Plan NGC_7790)
09-Dec-2016 04:25:39.9: Doing 3 sets
09-Dec-2016 04:25:39.9: Send Observation NGC_7790 to ACP Sequencer
09-Dec-2016 04:31:34.8: Send Observation NGC_7790 to ACP Sequencer
09-Dec-2016 04:37:08.2: Send Observation NGC_7790 to ACP Sequencer
09-Dec-2016 04:42:39.0: Acquisition time: 1019.0424268 sec.
09-Dec-2016 04:42:39.0: Data for Observation NGC_7790 acquired successfully.
09-Dec-2016 04:42:39.0: Image Efficiency: 45.6%
09-Dec-2016 04:42:39.0: Cycle Efficiency: 100.0%
09-Dec-2016 04:42:40.3: Dispatcher cycle time: 0.1500002 sec.
09-Dec-2016 04:42:40.3: Acquire data for Observation BY Cam...
09-Dec-2016 04:42:40.3:   (belongs to Project Walker, Plan BY_Cam)
09-Dec-2016 04:42:40.3: Send Observation BY Cam to ACP Sequencer
09-Dec-2016 12:57:17.4: ACP data acquisition failed for Observation BY Cam.
09-Dec-2016 12:57:17.4:   (Observation BY Cam interrupted by acquisition process failure.)
09-Dec-2016 12:57:18.8: ++ Auto Focus ++
09-Dec-2016 12:57:18.8: Doing periodic autofocus.
09-Dec-2016 12:57:18.8: Start special ACP AutoFocus script for scheduler
09-Dec-2016 13:00:46.3: Next periodic autofocus ASAP after 09-Dec-2016 15:00:14 UTC
09-Dec-2016 13:01:17.7: ++ Dawn Flats ++
09-Dec-2016 13:01:17.7: Start ACP AutoFlat script for Dawn
09-Dec-2016 13:44:51.0: -- Observatory Shutdown --
09-Dec-2016 18:28:52.1: **EXCEPTION IN SCHEDULER:
09-Dec-2016 18:28:52.1: Exception from HRESULT: 0x800A9C68
09-Dec-2016 18:28:52.1: Traceback:
   at ACP.UtilClass.set_WeatherConnected(Boolean )
   at DC3.Scheduler.ACPSequencer.DisconnectWeather()
   at DC3.Scheduler.ACPSequencer.DoShutdown()
   at DC3.Scheduler.Engine.DoShutdownIf()
   at DC3.Scheduler.Engine.Run()
09-Dec-2016 18:28:52.1: Run statistics:
09-Dec-2016 18:28:52.1:   Observations:   35
09-Dec-2016 18:28:52.1:   Considered:     17
09-Dec-2016 18:28:52.1:   Completed:      5
09-Dec-2016 18:28:52.1:   Skipped:        8
09-Dec-2016 18:28:52.1:   Never Eligible: 18
09-Dec-2016 18:28:52.1:   Failed:         4
09-Dec-2016 18:28:52.1: Shutter-Open efficiency: 10.91%
09-Dec-2016 18:28:52.1: Overall Efficiency: 99.90%
09-Dec-2016 18:28:52.1: Release ACP sequencer
09-Dec-2016 18:29:04.0: Failed to stop sequencer:
09-Dec-2016 18:29:04.0: The interface is unknown. (Exception from HRESULT: 0x800706B5)
Log closed at Fri, Dec 09 2016 18:29:05 UTC (actual time)