Log opened at Wed, Nov 09 2016 00:55:50 UTC (actual time) Current log level is Debug Scheduler version 3.5.3 Multi-user license: Arne Henden 09-Nov-2016 00:55:50.6: Simulated clock initialized at 09-Nov-2016 00:55:50 UTC 09-Nov-2016 00:55:50.7: Loaded Constraint plugin AirMass 09-Nov-2016 00:55:50.7: Loaded Constraint plugin AirmassRange 09-Nov-2016 00:55:50.7: Loaded Constraint plugin Horizon 09-Nov-2016 00:55:50.7: Loaded Constraint plugin HourAngle 09-Nov-2016 00:55:50.7: Loaded Constraint plugin MoonAvoid 09-Nov-2016 00:55:50.7: Loaded Constraint plugin MoonDown 09-Nov-2016 00:55:50.7: Loaded Constraint plugin SkyCondition 09-Nov-2016 00:55:50.7: Loaded Constraint plugin TimeRange 09-Nov-2016 00:55:51.2: 3 plans are now pending. 09-Nov-2016 00:55:51.2: Attach ACP sequencer 09-Nov-2016 00:56:13.1: Weather successfully connected. Wait for valid data... 09-Nov-2016 00:56:14.6: Dispatcher started at 09-Nov-2016 00:56:14 UTC 09-Nov-2016 00:56:14.6: Priority: W[0]=0.00 09-Nov-2016 00:56:14.6: Transit Altitude: W[1]=0.00 09-Nov-2016 00:56:14.6: Highest Altitude: W[6]=0.00 09-Nov-2016 00:56:14.6: Lateness: W[5]=0.70 09-Nov-2016 00:56:14.6: Slew Distance: W[2]=0.30 09-Nov-2016 00:56:14.6: Retry Count: W[3]=0.20 09-Nov-2016 00:56:14.6: Meridian Crossing: W[4]=0.30 09-Nov-2016 00:56:14.6: Obs Conditions: W[7]=0.40 09-Nov-2016 00:56:14.6: Rising Plan Delay: disabled 09-Nov-2016 00:56:16.9: ++ Observatory Startup ++ 09-Nov-2016 00:56:16.9: Start ACP Sequencer's StartupObs script 09-Nov-2016 00:56:26.9: Sequencer is now active 09-Nov-2016 00:56:52.8: Sequencer is no longer active 09-Nov-2016 00:56:52.8: Open the observatory dome/roof 09-Nov-2016 01:05:38.1: ++ Auto Focus ++ 09-Nov-2016 01:05:38.2: Doing initial autofocus. 09-Nov-2016 01:05:38.2: Start special ACP AutoFocus script for scheduler 09-Nov-2016 01:05:48.2: Sequencer is now active 09-Nov-2016 01:08:39.3: Sequencer is no longer active 09-Nov-2016 01:08:39.3: Next periodic autofocus ASAP after 09-Nov-2016 03:08:24 UTC 09-Nov-2016 01:08:39.5: Plan GSC_3649-0825 has Monitor Mode. Time to resubmit it. 09-Nov-2016 01:08:39.5: Plan 2MASS_23275353+5214532 has Monitor Mode. Time to resubmit it. 09-Nov-2016 01:08:39.5: Plan IC_348 has Monitor Mode. Time to resubmit it. 09-Nov-2016 01:08:39.5: Plan KH_15D has Monitor Mode. Time to resubmit it. 09-Nov-2016 01:08:39.5: Plan V1184_Tau has Monitor Mode. Time to resubmit it. 09-Nov-2016 01:08:39.5: Plan CB_32 has Monitor Mode. Time to resubmit it. 09-Nov-2016 01:08:39.5: Plan NGC_2438 has Monitor Mode. Time to resubmit it. 09-Nov-2016 01:08:39.5: Plan V1404_Cyg has Monitor Mode. Time to resubmit it. 09-Nov-2016 01:08:39.5: Plan SDSS073224 has Monitor Mode. Time to resubmit it. 09-Nov-2016 01:08:39.5: Plan WD has Monitor Mode. Time to resubmit it. 09-Nov-2016 01:08:39.5: Plan M67 has Monitor Mode. Time to resubmit it. 09-Nov-2016 01:08:39.5: Plan QQ_Vul has Monitor Mode. Time to resubmit it. 09-Nov-2016 01:08:39.5: Plan M56 has Monitor Mode. Time to resubmit it. 09-Nov-2016 01:08:39.5: Plan NGC_7790 has Monitor Mode. Time to resubmit it. 09-Nov-2016 01:08:39.5: Plan T_Ori has Monitor Mode. Time to resubmit it. 09-Nov-2016 01:08:39.5: Plan U_Aqr has Monitor Mode. Time to resubmit it. 09-Nov-2016 01:08:39.5: Plan V339_Del_TMO61 has Monitor Mode. Time to resubmit it. 09-Nov-2016 01:08:39.5: SelectRunning: No running plans 09-Nov-2016 01:08:39.8: Obs CB_32 in Plan CB_32 was vetoed by AirMass constraint. 09-Nov-2016 01:08:39.8: AirMass estimates ready after 09-Nov-2016 05:53:37 UTC 09-Nov-2016 01:08:39.8: Obs IC_348 in Plan IC_348 was vetoed by AirMass constraint. 09-Nov-2016 01:08:39.8: AirMass estimates ready after 09-Nov-2016 02:46:17 UTC 09-Nov-2016 01:08:39.9: Obs KH_15D in Plan KH_15D was vetoed by AirMass constraint. 09-Nov-2016 01:08:39.9: AirMass estimates ready after 09-Nov-2016 06:30:38 UTC 09-Nov-2016 01:08:39.9: Obs M67 in Plan M67 was vetoed by AirMass constraint. 09-Nov-2016 01:08:39.9: AirMass estimates ready after 09-Nov-2016 09:03:27 UTC 09-Nov-2016 01:08:39.9: Obs MP_Gem in Plan MP_Gem was vetoed by AirMass constraint. 09-Nov-2016 01:08:39.9: AirMass estimates ready after 09-Nov-2016 06:14:34 UTC 09-Nov-2016 01:08:39.9: Obs NGC_2438 in Plan NGC_2438 was vetoed by AirMass constraint. 09-Nov-2016 01:08:39.9: AirMass estimates ready after 09-Nov-2016 08:56:30 UTC 09-Nov-2016 01:08:39.9: Obs SDSS073224 in Plan SDSS073224 was vetoed by AirMass constraint. 09-Nov-2016 01:08:39.9: AirMass estimates ready after 09-Nov-2016 06:43:26 UTC 09-Nov-2016 01:08:39.9: Obs T_Ori in Plan T_Ori was vetoed by AirmassRange constraint. 09-Nov-2016 01:08:39.9: AirmassRange estimates ready after 09-Nov-2016 06:10:27 UTC 09-Nov-2016 01:08:39.9: Obs U_Aqr in Plan U_Aqr was vetoed by MoonAvoid constraint. 09-Nov-2016 01:08:39.9: MoonAvoid estimates ready after 09-Nov-2016 13:08:39 UTC 09-Nov-2016 01:08:39.9: Obs V1184_Tau in Plan V1184_Tau was vetoed by AirMass constraint. 09-Nov-2016 01:08:39.9: AirMass estimates ready after 09-Nov-2016 05:10:19 UTC 09-Nov-2016 01:08:39.9: Obs V838_Mon in Plan V838_Mon was vetoed by AirmassRange constraint. 09-Nov-2016 01:08:39.9: AirmassRange estimates ready after 09-Nov-2016 07:32:38 UTC 09-Nov-2016 01:08:39.9: Obs V841_Oph in Plan V841_Oph was vetoed by AirmassRange constraint. 09-Nov-2016 01:08:39.9: AirmassRange estimates ready after 09-Nov-2016 18:03:19 UTC 09-Nov-2016 01:08:39.9: Obs SDSSJ121027+502735 in Plan WD was vetoed by AirMass constraint. 09-Nov-2016 01:08:39.9: AirMass estimates ready after 09-Nov-2016 10:38:15 UTC 09-Nov-2016 01:08:39.9: Efficiency: Selecting from 7 pending plans: 09-Nov-2016 01:08:40.0: Selected Plan M56 (TotEff=0.96) 09-Nov-2016 01:08:40.0: Dispatcher cycle time: 0.6600009 sec. 09-Nov-2016 01:08:40.0: Acquire data for Observation M56... 09-Nov-2016 01:08:40.0: (belongs to Project AAVSO_Monitoring, Plan M56) 09-Nov-2016 01:08:40.0: Doing 3 sets 09-Nov-2016 01:08:40.1: Send Observation M56 to ACP Sequencer 09-Nov-2016 01:08:50.1: Sequencer is now active 09-Nov-2016 01:14:38.5: Sequencer is no longer active 09-Nov-2016 01:14:38.5: Post-job status check done (stat=Completed) 09-Nov-2016 01:14:38.5: Send Observation M56 to ACP Sequencer 09-Nov-2016 01:14:48.5: Sequencer is now active 09-Nov-2016 01:20:10.5: Sequencer is no longer active 09-Nov-2016 01:20:10.5: Post-job status check done (stat=Completed) 09-Nov-2016 01:20:10.6: Send Observation M56 to ACP Sequencer 09-Nov-2016 01:20:20.6: Sequencer is now active 09-Nov-2016 01:25:43.5: Sequencer is no longer active 09-Nov-2016 01:25:43.5: Post-job status check done (stat=Completed) 09-Nov-2016 01:25:43.5: Acquisition time: 1023.4454365 sec. 09-Nov-2016 01:25:43.5: Data for Observation M56 acquired successfully. 09-Nov-2016 01:25:43.6: Image Efficiency: 70.3% 09-Nov-2016 01:25:43.6: Cycle Efficiency: 99.9% 09-Nov-2016 01:25:43.9: SelectRunning: No running plans 09-Nov-2016 01:25:43.9: Efficiency: Selecting from 6 pending plans: 09-Nov-2016 01:25:44.0: Selected Plan QQ_Vul (TotEff=9.59) 09-Nov-2016 01:25:44.0: Dispatcher cycle time: 0.2000003 sec. 09-Nov-2016 01:25:44.0: Acquire data for Observation QQ_Vul_TMO61... 09-Nov-2016 01:25:44.0: (belongs to Project Walker, Plan QQ_Vul) 09-Nov-2016 01:25:44.0: Doing 1000 sets 09-Nov-2016 01:25:44.0: Send Observation QQ_Vul_TMO61 to ACP Sequencer 09-Nov-2016 01:25:54.0: Sequencer is now active 09-Nov-2016 01:36:01.2: Sequencer is no longer active 09-Nov-2016 01:36:01.2: Post-job status check done (stat=Completed) 09-Nov-2016 01:36:01.2: Send Observation QQ_Vul_TMO61 to ACP Sequencer 09-Nov-2016 01:36:11.2: Sequencer is now active 09-Nov-2016 01:45:09.4: Sequencer is no longer active 09-Nov-2016 01:45:09.4: Post-job status check done (stat=Completed) 09-Nov-2016 01:45:09.4: Send Observation QQ_Vul_TMO61 to ACP Sequencer 09-Nov-2016 01:45:19.4: Sequencer is now active 09-Nov-2016 01:54:36.1: Sequencer is no longer active 09-Nov-2016 01:54:36.1: Post-job status check done (stat=Completed) 09-Nov-2016 01:54:36.1: Send Observation QQ_Vul_TMO61 to ACP Sequencer 09-Nov-2016 01:54:46.1: Sequencer is now active 09-Nov-2016 02:03:21.4: Sequencer is no longer active 09-Nov-2016 02:03:21.4: Post-job status check done (stat=Completed) 09-Nov-2016 02:03:21.4: Send Observation QQ_Vul_TMO61 to ACP Sequencer 09-Nov-2016 02:03:31.4: Sequencer is now active 09-Nov-2016 02:12:37.6: Sequencer is no longer active 09-Nov-2016 02:12:37.6: Post-job status check done (stat=Completed) 09-Nov-2016 02:12:37.6: Send Observation QQ_Vul_TMO61 to ACP Sequencer 09-Nov-2016 02:12:47.6: Sequencer is now active 09-Nov-2016 02:21:11.9: Sequencer is no longer active 09-Nov-2016 02:21:11.9: Post-job status check done (stat=Completed) 09-Nov-2016 02:21:11.9: Send Observation QQ_Vul_TMO61 to ACP Sequencer 09-Nov-2016 02:21:21.9: Sequencer is now active 09-Nov-2016 02:30:00.3: Sequencer is no longer active 09-Nov-2016 02:30:00.3: Post-job status check done (stat=Completed) 09-Nov-2016 02:30:00.3: Send Observation QQ_Vul_TMO61 to ACP Sequencer 09-Nov-2016 02:30:10.3: Sequencer is now active 09-Nov-2016 02:38:57.3: Sequencer is no longer active 09-Nov-2016 02:38:57.3: Post-job status check done (stat=Completed) 09-Nov-2016 02:38:57.3: Send Observation QQ_Vul_TMO61 to ACP Sequencer 09-Nov-2016 02:39:07.3: Sequencer is now active 09-Nov-2016 02:48:13.7: Sequencer is no longer active 09-Nov-2016 02:48:13.7: Post-job status check done (stat=Completed) 09-Nov-2016 02:48:13.7: Send Observation QQ_Vul_TMO61 to ACP Sequencer 09-Nov-2016 02:48:23.7: Sequencer is now active 09-Nov-2016 02:56:32.5: Sequencer is no longer active 09-Nov-2016 02:56:32.5: Post-job status check done (stat=Completed) 09-Nov-2016 02:56:32.6: Send Observation QQ_Vul_TMO61 to ACP Sequencer 09-Nov-2016 02:56:42.6: Sequencer is now active 09-Nov-2016 03:06:02.5: Sequencer is no longer active 09-Nov-2016 03:06:02.5: Post-job status check done (stat=Completed) 09-Nov-2016 03:06:02.5: Send Observation QQ_Vul_TMO61 to ACP Sequencer 09-Nov-2016 03:06:12.5: Sequencer is now active 09-Nov-2016 03:15:26.8: Sequencer is no longer active 09-Nov-2016 03:15:26.8: Post-job status check done (stat=Completed) 09-Nov-2016 03:15:26.8: Send Observation QQ_Vul_TMO61 to ACP Sequencer 09-Nov-2016 03:15:36.8: Sequencer is now active 09-Nov-2016 03:23:55.3: Sequencer is no longer active 09-Nov-2016 03:23:55.3: Post-job status check done (stat=Completed) 09-Nov-2016 03:23:55.3: Send Observation QQ_Vul_TMO61 to ACP Sequencer 09-Nov-2016 03:24:05.3: Sequencer is now active 09-Nov-2016 03:33:08.8: Sequencer is no longer active 09-Nov-2016 03:33:08.8: Post-job status check done (stat=Completed) 09-Nov-2016 03:33:08.8: Send Observation QQ_Vul_TMO61 to ACP Sequencer 09-Nov-2016 03:33:18.8: Sequencer is now active 09-Nov-2016 03:42:34.7: Sequencer is no longer active 09-Nov-2016 03:42:34.7: Post-job status check done (stat=Completed) 09-Nov-2016 03:42:34.7: Send Observation QQ_Vul_TMO61 to ACP Sequencer 09-Nov-2016 03:42:44.7: Sequencer is now active 09-Nov-2016 03:51:39.1: Sequencer is no longer active 09-Nov-2016 03:51:39.1: Post-job status check done (stat=Completed) 09-Nov-2016 03:51:39.1: Send Observation QQ_Vul_TMO61 to ACP Sequencer 09-Nov-2016 03:51:49.1: Sequencer is now active 09-Nov-2016 04:01:07.1: Sequencer is no longer active 09-Nov-2016 04:01:07.1: Post-job status check done (stat=Completed) 09-Nov-2016 04:01:07.1: Send Observation QQ_Vul_TMO61 to ACP Sequencer 09-Nov-2016 04:01:17.1: Sequencer is now active 09-Nov-2016 04:10:44.7: Sequencer is no longer active 09-Nov-2016 04:10:44.7: Post-job status check done (stat=Completed) 09-Nov-2016 04:10:44.8: Send Observation QQ_Vul_TMO61 to ACP Sequencer 09-Nov-2016 04:10:54.8: Sequencer is now active 09-Nov-2016 04:11:31.8: ** Script interrupted by weather safety event. 09-Nov-2016 04:11:34.8: Post-job status check done (stat=Running) 09-Nov-2016 04:11:34.8: ACP ABORT: Acquisition process was interrupted by weather unsafe. 09-Nov-2016 04:11:34.8: ACP data acquisition failed for Observation QQ_Vul_TMO61. 09-Nov-2016 04:11:34.8: (Plan QQ_Vul will be resubmitted in its entirety. 09-Nov-2016 04:11:34.8: (Observation QQ_Vul_TMO61 interrupted by weather unsafe event.) 09-Nov-2016 04:11:35.9: -- Weather Unsafe -- 09-Nov-2016 04:59:29.2: COMException while trying to get ACP Weather.Available. Probably operator script abort. 09-Nov-2016 04:59:29.2: The callee (server [not server application]) is not available and disappeared; all connections are invalid. The call may have executed. (Exception from HRESULT: 0x80010007 (RPC_E_SERVER_DIED)) 09-Nov-2016 04:59:30.2: COMException while trying to get ACP Weather.Available. Probably operator script abort. 09-Nov-2016 04:59:30.2: The callee (server [not server application]) is not available and disappeared; all connections are invalid. The call may have executed. (Exception from HRESULT: 0x80010007 (RPC_E_SERVER_DIED)) 09-Nov-2016 04:59:31.2: COMException while trying to get ACP Weather.Available. Probably operator script abort. 09-Nov-2016 04:59:31.2: The callee (server [not server application]) is not available and disappeared; all connections are invalid. The call may have executed. (Exception from HRESULT: 0x80010007 (RPC_E_SERVER_DIED)) 09-Nov-2016 04:59:32.2: COMException while trying to get ACP Weather.Available. Probably operator script abort. 09-Nov-2016 04:59:32.2: The callee (server [not server application]) is not available and disappeared; all connections are invalid. The call may have executed. (Exception from HRESULT: 0x80010007 (RPC_E_SERVER_DIED)) 09-Nov-2016 04:59:33.2: COMException while trying to get ACP Weather.Available. Probably operator script abort. 09-Nov-2016 04:59:33.2: The callee (server [not server application]) is not available and disappeared; all connections are invalid. The call may have executed. (Exception from HRESULT: 0x80010007 (RPC_E_SERVER_DIED)) 09-Nov-2016 04:59:34.2: **EXCEPTION IN SCHEDULER: 09-Nov-2016 04:59:34.2: ConnectWeather() waited for over 5 seconds to read Weather.Available! 09-Nov-2016 04:59:34.2: Traceback: at DC3.Scheduler.ACPSequencer.ConnectWeather() at DC3.Scheduler.Engine.PollWeather() at DC3.Scheduler.Engine.Run() 09-Nov-2016 04:59:34.2: Run statistics: 09-Nov-2016 04:59:34.2: Observations: 22 09-Nov-2016 04:59:34.2: Considered: 8 09-Nov-2016 04:59:34.2: Completed: 1 09-Nov-2016 04:59:34.2: Skipped: 5 09-Nov-2016 04:59:34.2: Never Eligible: 14 09-Nov-2016 04:59:34.2: Failed: 2 09-Nov-2016 04:59:34.2: Shutter-Open efficiency: 55.61% 09-Nov-2016 04:59:34.2: Overall Efficiency: 99.90% 09-Nov-2016 04:59:34.2: Release ACP sequencer 09-Nov-2016 04:59:34.2: Failed to stop sequencer: 09-Nov-2016 04:59:34.2: The callee (server [not server application]) is not available and disappeared; all connections are invalid. The call may have executed. (Exception from HRESULT: 0x80010007 (RPC_E_SERVER_DIED)) Log closed at Wed, Nov 09 2016 04:59:35 UTC (actual time)