Log opened at Sun, Jan 18 2015 04:18:56 UTC (actual time)
Current log level is Verbose
Scheduler version 3.5.3
Multi-user license: Arne Henden
18-Jan-2015 04:18:56.3: Simulated clock initialized at 18-Jan-2015 04:18:56 UTC
18-Jan-2015 04:18:56.3: Loaded Constraint plugin AirMass
18-Jan-2015 04:18:56.3: Loaded Constraint plugin AirmassRange
18-Jan-2015 04:18:56.3: Loaded Constraint plugin Horizon
18-Jan-2015 04:18:56.3: Loaded Constraint plugin HourAngle
18-Jan-2015 04:18:56.3: Loaded Constraint plugin MoonAvoid
18-Jan-2015 04:18:56.3: Loaded Constraint plugin MoonDown
18-Jan-2015 04:18:56.3: Loaded Constraint plugin SkyCondition
18-Jan-2015 04:18:56.3: Loaded Constraint plugin TimeRange
18-Jan-2015 04:18:56.9: 84 plans are now pending.
18-Jan-2015 04:18:56.9: Attach ACP sequencer
18-Jan-2015 04:19:05.8: Dispatcher started at 18-Jan-2015 04:19:05 UTC
18-Jan-2015 04:19:05.8:     Priority:          W[0]=1.00
18-Jan-2015 04:19:05.8:     Transit Altitude:  W[1]=0.70
18-Jan-2015 04:19:05.8:     Highest Altitude:  W[6]=0.00
18-Jan-2015 04:19:05.8:     Lateness:          W[5]=0.00
18-Jan-2015 04:19:05.8:     Slew Distance:     W[2]=0.30
18-Jan-2015 04:19:05.8:     Retry Count:       W[3]=0.20
18-Jan-2015 04:19:05.8:     Meridian Crossing: W[4]=0.30
18-Jan-2015 04:19:05.8:     Obs Conditions:    W[7]=0.40
18-Jan-2015 04:19:05.8:     Rising Plan Delay: disabled
18-Jan-2015 04:19:19.1: Weather successfully connected. Wait for valid data...
18-Jan-2015 04:19:27.6: ++ Observatory Startup ++
18-Jan-2015 04:19:27.6: Start ACP Sequencer's StartupObs script
18-Jan-2015 04:19:45.8: ++ Auto Focus ++
18-Jan-2015 04:19:45.9: Doing initial autofocus.
18-Jan-2015 04:19:45.9: Start special ACP AutoFocus script for scheduler
18-Jan-2015 04:22:48.6: Next periodic autofocus ASAP after 18-Jan-2015 06:22:22 UTC
18-Jan-2015 04:22:52.3: Dispatcher cycle time: 1.684803 sec.
18-Jan-2015 04:22:52.3: Acquire data for Observation WX_CMi...
18-Jan-2015 04:22:52.3:   (belongs to Project Mogul_Mira, Plan WX_CMi)
18-Jan-2015 04:22:52.3: Send Observation WX_CMi to ACP Sequencer
18-Jan-2015 04:41:57.8: Acquisition time: 1145.525612 sec.
18-Jan-2015 04:41:57.8: Data for Observation WX_CMi acquired successfully.
18-Jan-2015 04:41:57.9: Image Efficiency: 56.7%
18-Jan-2015 04:41:57.9: Cycle Efficiency: 99.8%
18-Jan-2015 04:42:06.0: Dispatcher cycle time: 0.0936001 sec.
18-Jan-2015 04:42:06.0: Acquire data for Observation NGC_2438...
18-Jan-2015 04:42:06.0:   (belongs to Project Staels, Plan NGC_2438)
18-Jan-2015 04:42:06.0: Send Observation NGC_2438 to ACP Sequencer
18-Jan-2015 04:48:51.1: ACP data acquisition failed for Observation NGC_2438.
18-Jan-2015 04:48:51.1:   (Observation NGC_2438 interrupted by acquisition process failure.)
18-Jan-2015 04:48:54.7: Dispatcher cycle time: 0.0780001 sec.
18-Jan-2015 04:48:54.7: Acquire data for Observation SY_CMa...
18-Jan-2015 04:48:54.7:   (belongs to Project Mogul_Mira, Plan SY_CMa)
18-Jan-2015 04:48:54.7: Send Observation SY_CMa to ACP Sequencer
18-Jan-2015 04:50:15.7: ACP data acquisition failed for Observation SY_CMa.
18-Jan-2015 04:50:15.7:   (Observation SY_CMa interrupted by acquisition process failure.)
18-Jan-2015 04:50:23.2: Dispatcher cycle time: 0.0780001 sec.
18-Jan-2015 04:50:23.2: Acquire data for Observation TT_Mon...
18-Jan-2015 04:50:23.2:   (belongs to Project Mogul_Mira, Plan TT_Mon)
18-Jan-2015 04:50:23.2: Send Observation TT_Mon to ACP Sequencer
18-Jan-2015 04:51:47.5: ACP data acquisition failed for Observation TT_Mon.
18-Jan-2015 04:51:47.5:   (Observation TT_Mon interrupted by acquisition process failure.)
18-Jan-2015 04:51:55.8: Dispatcher cycle time: 0.0624001 sec.
18-Jan-2015 04:51:55.8: Acquire data for Observation T_CMi...
18-Jan-2015 04:51:55.8:   (belongs to Project Mogul_Mira, Plan T_CMi)
18-Jan-2015 04:51:55.8: Send Observation T_CMi to ACP Sequencer
18-Jan-2015 04:53:21.0: ACP data acquisition failed for Observation T_CMi.
18-Jan-2015 04:53:21.0:   (Observation T_CMi interrupted by acquisition process failure.)
18-Jan-2015 04:53:31.3: Dispatcher cycle time: 0.0312 sec.
18-Jan-2015 04:53:31.3: Acquire data for Observation NGC_2419...
18-Jan-2015 04:53:31.3:   (belongs to Project Welch_gc, Plan NGC_2419)
18-Jan-2015 04:53:31.3: Send Observation NGC_2419 to ACP Sequencer
18-Jan-2015 04:55:01.9: ACP data acquisition failed for Observation NGC_2419.
18-Jan-2015 04:55:01.9:   (Observation NGC_2419 interrupted by acquisition process failure.)
18-Jan-2015 05:00:37.9: **Dome closing when asked to open. Assuming weather closure.
18-Jan-2015 05:00:39.4: **EXCEPTION IN SCHEDULER:
18-Jan-2015 05:00:39.4: **Dome failed to open for reason other than hardware weather closure
18-Jan-2015 05:00:39.4: Traceback:
   at DC3.Scheduler.Engine.DoStartupIf(Boolean openDome)
   at DC3.Scheduler.Engine.Run()
18-Jan-2015 05:00:39.5: Run statistics:
18-Jan-2015 05:00:39.5:   Observations:   126
18-Jan-2015 05:00:39.5:   Considered:     30
18-Jan-2015 05:00:39.5:   Completed:      2
18-Jan-2015 05:00:39.5:   Skipped:        0
18-Jan-2015 05:00:39.5:   Never Eligible: 96
18-Jan-2015 05:00:39.5:   Failed:         28
18-Jan-2015 05:00:39.5: Shutter-Open efficiency: 29.99%
18-Jan-2015 05:00:39.5: Overall Efficiency: 87.27%
18-Jan-2015 05:00:39.5: Release ACP sequencer
Log closed at Sun, Jan 18 2015 05:00:41 UTC (actual time)