Log opened at Thu, Nov 15 2012 04:59:12 UTC (actual time)
Current log level is Debug
Scheduler version 3.5.3
Multi-user license: Arne Henden
15-Nov-2012 04:59:13.0: ++ Observatory Startup ++
15-Nov-2012 04:59:13.0: Start ACP Sequencer's StartupObs script
15-Nov-2012 04:59:23.1: Sequencer is now active
15-Nov-2012 04:59:28.3: Sequencer is no longer active
15-Nov-2012 04:59:28.3: SelectRunning: No running plans
15-Nov-2012 04:59:28.3: Obs CW_Mon in Plan CW_Mon was vetoed by AirMass constraint.
15-Nov-2012 04:59:28.3:     AirMass estimates ready after 15-Nov-2012 06:28:58 UTC
15-Nov-2012 04:59:28.3: Obs SDSS_J075507 in Plan SDSS_J075507 was vetoed by AirMass constraint.
15-Nov-2012 04:59:28.3:     AirMass estimates ready after 15-Nov-2012 07:08:17 UTC
15-Nov-2012 04:59:28.4: Obs V1108_Her in Plan V1108_Her was vetoed by AirMass constraint.
15-Nov-2012 04:59:28.4:     AirMass estimates ready after 15-Nov-2012 17:26:33 UTC
15-Nov-2012 04:59:28.4: No pending plans that can be started now.
15-Nov-2012 04:59:28.4: Dispatcher cycle time: 0.0936002 sec.
15-Nov-2012 04:59:58.9: SelectRunning: No running plans
15-Nov-2012 04:59:58.9: No pending plans that can be started now.
15-Nov-2012 04:59:58.9: Dispatcher cycle time: 0 sec.
15-Nov-2012 05:00:29.4: SelectRunning: No running plans
15-Nov-2012 05:00:29.4: No pending plans that can be started now.
15-Nov-2012 05:00:29.4: Dispatcher cycle time: 0 sec.
15-Nov-2012 05:01:00.0: SelectRunning: No running plans
15-Nov-2012 05:01:00.0: No pending plans that can be started now.
15-Nov-2012 05:01:00.0: Dispatcher cycle time: 0 sec.
15-Nov-2012 05:01:30.6: SelectRunning: No running plans
15-Nov-2012 05:01:30.6: No pending plans that can be started now.
15-Nov-2012 05:01:30.6: Dispatcher cycle time: 0 sec.
15-Nov-2012 05:02:01.4: SelectRunning: No running plans
15-Nov-2012 05:02:01.4: No pending plans that can be started now.
15-Nov-2012 05:02:01.4: Dispatcher cycle time: 0 sec.
15-Nov-2012 05:02:32.2: SelectRunning: No running plans
15-Nov-2012 05:02:32.2: No pending plans that can be started now.
15-Nov-2012 05:02:32.2: Dispatcher cycle time: 0 sec.
15-Nov-2012 05:03:03.0: SelectRunning: No running plans
15-Nov-2012 05:03:03.0: No pending plans that can be started now.
15-Nov-2012 05:03:03.0: Dispatcher cycle time: 0 sec.
15-Nov-2012 05:03:33.9: SelectRunning: No running plans
15-Nov-2012 05:03:33.9: No pending plans that can be started now.
15-Nov-2012 05:03:33.9: Dispatcher cycle time: 0 sec.
15-Nov-2012 05:04:04.7: SelectRunning: No running plans
15-Nov-2012 05:04:04.7: No pending plans that can be started now.
15-Nov-2012 05:04:04.7: Dispatcher cycle time: 0 sec.
15-Nov-2012 05:04:35.5: SelectRunning: No running plans
15-Nov-2012 05:04:35.5: No pending plans that can be started now.
15-Nov-2012 05:04:35.5: Dispatcher cycle time: 0 sec.
15-Nov-2012 05:05:06.0: SelectRunning: No running plans
15-Nov-2012 05:05:06.0: No pending plans that can be started now.
15-Nov-2012 05:05:06.0: Dispatcher cycle time: 0 sec.
15-Nov-2012 05:05:36.6: SelectRunning: No running plans
15-Nov-2012 05:05:36.6: No pending plans that can be started now.
15-Nov-2012 05:05:36.6: Dispatcher cycle time: 0 sec.
15-Nov-2012 05:06:07.1: SelectRunning: No running plans
15-Nov-2012 05:06:07.1: No pending plans that can be started now.
15-Nov-2012 05:06:07.1: Dispatcher cycle time: 0 sec.
15-Nov-2012 05:06:37.6: SelectRunning: No running plans
15-Nov-2012 05:06:37.6: No pending plans that can be started now.
15-Nov-2012 05:06:37.6: Dispatcher cycle time: 0.0156 sec.
15-Nov-2012 05:07:08.2: SelectRunning: No running plans
15-Nov-2012 05:07:08.2: No pending plans that can be started now.
15-Nov-2012 05:07:08.2: Dispatcher cycle time: 0 sec.
15-Nov-2012 05:07:38.7: SelectRunning: No running plans
15-Nov-2012 05:07:38.7: No pending plans that can be started now.
15-Nov-2012 05:07:38.7: Dispatcher cycle time: 0 sec.
15-Nov-2012 05:08:09.3: SelectRunning: No running plans
15-Nov-2012 05:08:09.3: No pending plans that can be started now.
15-Nov-2012 05:08:09.3: Dispatcher cycle time: 0 sec.
15-Nov-2012 05:08:40.1: SelectRunning: No running plans
15-Nov-2012 05:08:40.1: No pending plans that can be started now.
15-Nov-2012 05:08:40.1: Dispatcher cycle time: 0 sec.
15-Nov-2012 05:09:10.9: SelectRunning: No running plans
15-Nov-2012 05:09:10.9: No pending plans that can be started now.
15-Nov-2012 05:09:10.9: Dispatcher cycle time: 0 sec.
15-Nov-2012 05:09:41.7: SelectRunning: No running plans
15-Nov-2012 05:09:41.7: No pending plans that can be started now.
15-Nov-2012 05:09:41.7: Dispatcher cycle time: 0 sec.
15-Nov-2012 05:10:12.6: SelectRunning: No running plans
15-Nov-2012 05:10:12.6: No pending plans that can be started now.
15-Nov-2012 05:10:12.6: Dispatcher cycle time: 0 sec.
15-Nov-2012 05:10:43.4: SelectRunning: No running plans
15-Nov-2012 05:10:43.4: No pending plans that can be started now.
15-Nov-2012 05:10:43.4: Dispatcher cycle time: 0 sec.
15-Nov-2012 05:11:13.9: SelectRunning: No running plans
15-Nov-2012 05:11:13.9: No pending plans that can be started now.
15-Nov-2012 05:11:13.9: Dispatcher cycle time: 0.0156 sec.
15-Nov-2012 05:11:44.5: SelectRunning: No running plans
15-Nov-2012 05:11:44.5: No pending plans that can be started now.
15-Nov-2012 05:11:44.5: Dispatcher cycle time: 0 sec.
15-Nov-2012 05:12:15.0: SelectRunning: No running plans
15-Nov-2012 05:12:15.0: No pending plans that can be started now.
15-Nov-2012 05:12:15.0: Dispatcher cycle time: 0 sec.
15-Nov-2012 05:12:45.5: SelectRunning: No running plans
15-Nov-2012 05:12:45.5: No pending plans that can be started now.
15-Nov-2012 05:12:45.5: Dispatcher cycle time: 0 sec.
15-Nov-2012 05:13:16.1: SelectRunning: No running plans
15-Nov-2012 05:13:16.1: Efficiency: Only one pending plan M33-Clear (1-6), it is the "best"
15-Nov-2012 05:13:16.1: Dispatcher cycle time: 0.0624001 sec.
15-Nov-2012 05:13:16.1: Acquire data for Observation M33...
15-Nov-2012 05:13:16.1:   (belongs to Project M33, Plan M33-Clear (1-6))
15-Nov-2012 05:13:16.1: Send Observation M33 to ACP Sequencer
15-Nov-2012 05:13:26.1: Sequencer is now active
15-Nov-2012 05:31:58.2: Dispatcher stopped at 15-Nov-2012 05:31:58 UTC
15-Nov-2012 05:31:58.2: **DISPATCHER INTERRUPT LEVEL 1 RECEIVED**
15-Nov-2012 05:32:06.2: Sequencer is no longer active
15-Nov-2012 05:32:09.3: Post-job status check done (stat=Running)
15-Nov-2012 05:32:09.3: RUN FAILED: Scheduler was stopped during run
15-Nov-2012 05:32:09.3: ACP data acquisition failed for Observation M33.
15-Nov-2012 05:32:09.3:   (Observation M33 interrupted by operator or system.)
08-Dec-2012 02:50:15.7: Weather successfully connected. Wait for valid data...
08-Dec-2012 02:50:23.0: COMException while trying to get ACP Weather.Available. Probably operator script abort.
08-Dec-2012 02:50:23.0: The RPC server is unavailable. (Exception from HRESULT: 0x800706BA)
08-Dec-2012 02:50:24.0: COMException while trying to get ACP Weather.Available. Probably operator script abort.
08-Dec-2012 02:50:24.0: The RPC server is unavailable. (Exception from HRESULT: 0x800706BA)
08-Dec-2012 02:50:25.0: COMException while trying to get ACP Weather.Available. Probably operator script abort.
08-Dec-2012 02:50:25.0: The RPC server is unavailable. (Exception from HRESULT: 0x800706BA)
08-Dec-2012 02:50:26.0: COMException while trying to get ACP Weather.Available. Probably operator script abort.
08-Dec-2012 02:50:26.0: The RPC server is unavailable. (Exception from HRESULT: 0x800706BA)
08-Dec-2012 02:50:27.0: COMException while trying to get ACP Weather.Available. Probably operator script abort.
08-Dec-2012 02:50:27.0: The RPC server is unavailable. (Exception from HRESULT: 0x800706BA)
08-Dec-2012 02:50:28.1: **EXCEPTION IN SCHEDULER:
08-Dec-2012 02:50:28.1: ConnectWeather() waited for over 5 seconds to read Weather.Available!
08-Dec-2012 02:50:28.1: Traceback:
   at DC3.Scheduler.ACPSequencer.ConnectWeather()
   at DC3.Scheduler.Engine.PollWeather()
   at DC3.Scheduler.Engine.Run()
08-Dec-2012 02:50:28.1: Run statistics:
08-Dec-2012 02:50:28.1:   Observations:   7
08-Dec-2012 02:50:28.1:   Considered:     4
08-Dec-2012 02:50:28.1:   Completed:      3
08-Dec-2012 02:50:28.1:   Skipped:        0
08-Dec-2012 02:50:28.1:   Never Eligible: 3
08-Dec-2012 02:50:28.1:   Failed:         1
08-Dec-2012 02:50:28.1: Shutter-Open efficiency: 0.00%
08-Dec-2012 02:50:28.1: Overall Efficiency: 57.97%
08-Dec-2012 02:50:28.1: Release ACP sequencer
08-Dec-2012 02:50:28.1: Failed to stop sequencer:
08-Dec-2012 02:50:28.1: The RPC server is unavailable. (Exception from HRESULT: 0x800706BA)
Log closed at Sat, Dec 08 2012 02:50:29 UTC (actual time)