Log opened at Tue, Dec 20 2016 01:22:53 UTC (actual time) Current log level is Verbose Scheduler version 3.5.3 Multi-user license: Arne Henden 20-Dec-2016 01:22:54.6: ++ Observatory Startup ++ 20-Dec-2016 01:22:54.6: Start ACP Sequencer's StartupObs script 20-Dec-2016 01:23:15.5: Open the observatory dome/roof 20-Dec-2016 01:24:22.9: ++ Auto Focus ++ 20-Dec-2016 01:24:22.9: Doing initial autofocus. 20-Dec-2016 01:24:22.9: Start special ACP AutoFocus script for scheduler 20-Dec-2016 01:28:46.8: Next periodic autofocus ASAP after 20-Dec-2016 03:27:48 UTC 20-Dec-2016 01:28:47.0: Plan V0809_Cep has Monitor Mode. Time to resubmit it. 20-Dec-2016 01:28:47.1: Plan BY_Cam has Monitor Mode. Time to resubmit it. 20-Dec-2016 01:28:47.5: Dispatcher cycle time: 0.5400008 sec. 20-Dec-2016 01:28:47.5: Acquire data for Observation QQ_Vul_TMO61... 20-Dec-2016 01:28:47.5: (belongs to Project Walker, Plan QQ_Vul) 20-Dec-2016 01:28:47.5: Doing 1000 sets 20-Dec-2016 01:28:47.6: Send Observation QQ_Vul_TMO61 to ACP Sequencer 20-Dec-2016 01:33:59.2: Send Observation QQ_Vul_TMO61 to ACP Sequencer 20-Dec-2016 01:38:08.5: Send Observation QQ_Vul_TMO61 to ACP Sequencer 20-Dec-2016 01:42:13.8: Send Observation QQ_Vul_TMO61 to ACP Sequencer 20-Dec-2016 01:46:19.3: Send Observation QQ_Vul_TMO61 to ACP Sequencer 20-Dec-2016 01:50:25.6: Send Observation QQ_Vul_TMO61 to ACP Sequencer 20-Dec-2016 01:54:36.6: Send Observation QQ_Vul_TMO61 to ACP Sequencer 20-Dec-2016 01:58:47.8: Send Observation QQ_Vul_TMO61 to ACP Sequencer 20-Dec-2016 02:02:53.4: Send Observation QQ_Vul_TMO61 to ACP Sequencer 20-Dec-2016 02:06:58.8: Send Observation QQ_Vul_TMO61 to ACP Sequencer 20-Dec-2016 02:10:58.5: Send Observation QQ_Vul_TMO61 to ACP Sequencer 20-Dec-2016 02:15:06.0: Send Observation QQ_Vul_TMO61 to ACP Sequencer 20-Dec-2016 02:19:13.9: ***Running Plan QQ_Vul (best efforts) completed early: 20-Dec-2016 02:19:13.9: Obs QQ_Vul_TMO61 would would not meet constraints 20-Dec-2016 02:19:13.9: Acquisition time: 3026.1642467 sec. 20-Dec-2016 02:19:13.9: Data for Observation QQ_Vul_TMO61 acquired successfully. 20-Dec-2016 02:19:14.0: Image Efficiency: 79.3% 20-Dec-2016 02:19:14.0: Cycle Efficiency: 100.0% 20-Dec-2016 02:19:14.8: Dispatcher cycle time: 0.2500004 sec. 20-Dec-2016 02:19:14.8: Acquire data for Observation BY_Cam... 20-Dec-2016 02:19:14.8: (belongs to Project Walker, Plan BY_Cam) 20-Dec-2016 02:19:14.8: Doing 200 sets 20-Dec-2016 02:19:14.8: Send Observation BY_Cam to ACP Sequencer 20-Dec-2016 10:48:59.2: ***Running Plan BY_Cam (best efforts) completed early: 20-Dec-2016 10:48:59.2: Obs BY_Cam would would not meet constraints 20-Dec-2016 10:48:59.2: ***Running Plan BY_Cam (best efforts) completed early: 20-Dec-2016 10:48:59.2: Obs BY_Cam would extend past dawn 20-Dec-2016 10:48:59.2: Acquisition time: 30584.3210349 sec. 20-Dec-2016 10:48:59.2: Data for Observation BY_Cam acquired successfully. 20-Dec-2016 10:48:59.2: Image Efficiency: 78.5% 20-Dec-2016 10:48:59.2: Cycle Efficiency: 100.0% 20-Dec-2016 10:49:01.8: ++ Auto Focus ++ 20-Dec-2016 10:49:01.8: Doing periodic autofocus. 20-Dec-2016 10:49:01.8: Start special ACP AutoFocus script for scheduler 20-Dec-2016 10:52:48.3: Next periodic autofocus ASAP after 20-Dec-2016 12:52:01 UTC 20-Dec-2016 10:52:48.6: Dispatcher cycle time: 0.3200005 sec. 20-Dec-2016 10:52:48.6: Acquire data for Observation V647_Aur... 20-Dec-2016 10:52:48.6: (belongs to Project NMSU, Plan V647_Aur) 20-Dec-2016 10:52:48.6: Doing 300 sets 20-Dec-2016 10:52:48.6: Send Observation V647_Aur to ACP Sequencer 20-Dec-2016 10:55:55.5: Send Observation V647_Aur to ACP Sequencer 20-Dec-2016 10:58:11.2: Send Observation V647_Aur to ACP Sequencer 20-Dec-2016 11:00:28.6: Send Observation V647_Aur to ACP Sequencer 20-Dec-2016 11:02:44.5: Send Observation V647_Aur to ACP Sequencer 20-Dec-2016 11:04:58.0: Send Observation V647_Aur to ACP Sequencer 20-Dec-2016 11:07:15.4: Send Observation V647_Aur to ACP Sequencer 20-Dec-2016 11:09:30.8: Send Observation V647_Aur to ACP Sequencer 20-Dec-2016 11:11:44.9: Send Observation V647_Aur to ACP Sequencer 20-Dec-2016 11:13:59.5: Send Observation V647_Aur to ACP Sequencer 20-Dec-2016 11:16:13.5: Send Observation V647_Aur to ACP Sequencer 20-Dec-2016 11:18:33.4: Send Observation V647_Aur to ACP Sequencer 20-Dec-2016 11:20:52.1: Send Observation V647_Aur to ACP Sequencer 20-Dec-2016 11:23:09.6: Send Observation V647_Aur to ACP Sequencer 20-Dec-2016 11:25:27.5: Send Observation V647_Aur to ACP Sequencer 20-Dec-2016 11:27:46.1: Send Observation V647_Aur to ACP Sequencer 20-Dec-2016 11:30:02.8: Send Observation V647_Aur to ACP Sequencer 20-Dec-2016 11:32:22.9: Send Observation V647_Aur to ACP Sequencer 20-Dec-2016 11:34:43.7: Send Observation V647_Aur to ACP Sequencer 20-Dec-2016 11:37:03.2: Send Observation V647_Aur to ACP Sequencer 20-Dec-2016 11:39:22.3: Send Observation V647_Aur to ACP Sequencer 20-Dec-2016 11:41:42.0: Send Observation V647_Aur to ACP Sequencer 20-Dec-2016 11:43:57.8: Send Observation V647_Aur to ACP Sequencer 20-Dec-2016 11:46:17.7: Send Observation V647_Aur to ACP Sequencer 20-Dec-2016 11:48:36.9: Send Observation V647_Aur to ACP Sequencer 20-Dec-2016 11:50:53.8: Send Observation V647_Aur to ACP Sequencer 20-Dec-2016 11:53:12.5: ***Running Plan V647_Aur (best efforts) completed early: 20-Dec-2016 11:53:12.5: Obs V647_Aur would would not meet constraints 20-Dec-2016 11:53:12.5: Acquisition time: 3623.488079 sec. 20-Dec-2016 11:53:12.5: Data for Observation V647_Aur acquired successfully. 20-Dec-2016 11:53:12.8: Image Efficiency: 71.7% 20-Dec-2016 11:53:12.8: Cycle Efficiency: 100.0% 20-Dec-2016 11:53:15.1: Dispatcher cycle time: 0.8200011 sec. 20-Dec-2016 11:53:15.1: Acquire data for Observation Swift_J0614.0+1709... 20-Dec-2016 11:53:15.1: (belongs to Project NMSU, Plan Swift_J0614.0+1709) 20-Dec-2016 11:53:15.1: Doing 300 sets 20-Dec-2016 11:53:15.2: Send Observation Swift_J0614.0+1709 to ACP Sequencer 20-Dec-2016 11:56:05.3: Send Observation Swift_J0614.0+1709 to ACP Sequencer 20-Dec-2016 11:58:25.5: Send Observation Swift_J0614.0+1709 to ACP Sequencer 20-Dec-2016 12:00:43.4: Send Observation Swift_J0614.0+1709 to ACP Sequencer 20-Dec-2016 12:03:04.2: Send Observation Swift_J0614.0+1709 to ACP Sequencer 20-Dec-2016 12:05:23.6: Send Observation Swift_J0614.0+1709 to ACP Sequencer 20-Dec-2016 12:07:42.7: Send Observation Swift_J0614.0+1709 to ACP Sequencer 20-Dec-2016 12:10:02.3: Send Observation Swift_J0614.0+1709 to ACP Sequencer 20-Dec-2016 12:12:18.0: ***Running Plan Swift_J0614.0+1709 (best efforts) completed early: 20-Dec-2016 12:12:18.0: Obs Swift_J0614.0+1709 would would not meet constraints 20-Dec-2016 12:12:18.0: Acquisition time: 1142.6426149 sec. 20-Dec-2016 12:12:18.0: Data for Observation Swift_J0614.0+1709 acquired successfully. 20-Dec-2016 12:12:18.0: Image Efficiency: 70.0% 20-Dec-2016 12:12:18.0: Cycle Efficiency: 99.9% 20-Dec-2016 12:12:19.4: Dispatcher cycle time: 0.1600002 sec. 20-Dec-2016 12:12:19.4: Acquire data for Observation SDSS073224... 20-Dec-2016 12:12:19.4: (belongs to Project AGN, Plan SDSS073224) 20-Dec-2016 12:12:19.4: Send Observation SDSS073224 to ACP Sequencer 20-Dec-2016 12:14:00.0: ACP data acquisition failed for Observation SDSS073224. 20-Dec-2016 12:14:00.0: (Observation SDSS073224 interrupted by acquisition process failure.) 20-Dec-2016 12:14:00.3: Dispatcher cycle time: 0.1300002 sec. 20-Dec-2016 12:14:00.3: Acquire data for Observation M67... 20-Dec-2016 12:14:00.3: (belongs to Project AAVSO_Monitoring, Plan M67) 20-Dec-2016 12:14:00.4: Send Observation M67 to ACP Sequencer 20-Dec-2016 12:45:50.2: Acquisition time: 1909.8666875 sec. 20-Dec-2016 12:45:50.2: Data for Observation M67 acquired successfully. 20-Dec-2016 12:45:50.2: Image Efficiency: 73.8% 20-Dec-2016 12:45:50.2: Cycle Efficiency: 100.0% 20-Dec-2016 12:45:50.6: Dispatcher cycle time: 0.1000001 sec. 20-Dec-2016 12:45:50.6: Acquire data for Observation AT_Cnc... 20-Dec-2016 12:45:50.6: (belongs to Project SXN, Plan AT_Cnc) 20-Dec-2016 12:45:50.6: Send Observation AT_Cnc to ACP Sequencer 20-Dec-2016 12:55:46.5: Acquisition time: 595.9768442 sec. 20-Dec-2016 12:55:46.5: Data for Observation AT_Cnc acquired successfully. 20-Dec-2016 12:55:46.6: Image Efficiency: 60.4% 20-Dec-2016 12:55:46.6: Cycle Efficiency: 100.0% 20-Dec-2016 12:55:47.2: ++ Auto Focus ++ 20-Dec-2016 12:55:47.2: Doing periodic autofocus. 20-Dec-2016 12:55:47.2: Start special ACP AutoFocus script for scheduler 20-Dec-2016 13:00:00.1: Next periodic autofocus ASAP after 20-Dec-2016 14:59:12 UTC 20-Dec-2016 13:00:00.8: Dispatcher cycle time: 0.0600001 sec. 20-Dec-2016 13:00:00.8: Acquire data for Observation ES_Dra... 20-Dec-2016 13:00:00.8: (belongs to Project NMSU, Plan ES_Dra) 20-Dec-2016 13:00:00.8: Send Observation ES_Dra to ACP Sequencer 20-Dec-2016 13:04:19.5: ACP data acquisition failed for Observation ES_Dra. 20-Dec-2016 13:04:19.5: (Observation ES_Dra interrupted by acquisition process failure.) 20-Dec-2016 13:06:23.5: ++ Dawn Flats ++ 20-Dec-2016 13:06:23.6: Start ACP AutoFlat script for Dawn 20-Dec-2016 13:52:31.3: -- Observatory Shutdown -- 20-Dec-2016 13:52:31.4: Weather disconnected. 20-Dec-2016 13:52:31.4: Start ACP Sequencer's ShutdownObs script 20-Dec-2016 13:52:46.8: **EXCEPTION IN SCHEDULER: 20-Dec-2016 13:52:46.8: 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)) 20-Dec-2016 13:52:47.0: Traceback: at System.RuntimeType.InvokeDispMethod(String name, BindingFlags invokeAttr, Object target, Object[] args, Boolean[] byrefModifiers, Int32 culture, String[] namedParameters) at System.RuntimeType.InvokeMember(String name, BindingFlags bindingFlags, Binder binder, Object target, Object[] providedArgs, ParameterModifier[] modifiers, CultureInfo culture, String[] namedParams) at System.Type.InvokeMember(String name, BindingFlags invokeAttr, Binder binder, Object target, Object[] args) at DC3.Scheduler.ACPSequencer.RunScript(String scrPath, String param) at DC3.Scheduler.ACPSequencer.DoShutdown() at DC3.Scheduler.Engine.DoShutdownIf() at DC3.Scheduler.Engine.Run() 20-Dec-2016 13:52:47.0: Run statistics: 20-Dec-2016 13:52:47.0: Observations: 34 20-Dec-2016 13:52:47.0: Considered: 26 20-Dec-2016 13:52:47.0: Completed: 6 20-Dec-2016 13:52:47.0: Skipped: 16 20-Dec-2016 13:52:47.0: Never Eligible: 8 20-Dec-2016 13:52:47.0: Failed: 4 20-Dec-2016 13:52:47.0: Shutter-Open efficiency: 44.87% 20-Dec-2016 13:52:47.0: Overall Efficiency: 99.90% 20-Dec-2016 13:52:47.0: Release ACP sequencer 20-Dec-2016 13:52:47.1: Failed to stop sequencer: 20-Dec-2016 13:52:47.1: 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 Tue, Dec 20 2016 13:52:48 UTC (actual time)