Log opened at Sat, Apr 15 2017 02:45:34 UTC (actual time) Current log level is Debug Scheduler version 3.5.3 Multi-user license: Arne Henden 15-Apr-2017 02:45:34.0: ++ Observatory Startup ++ 15-Apr-2017 02:45:34.0: Start ACP Sequencer's StartupObs script 15-Apr-2017 02:45:44.0: Sequencer is now active 15-Apr-2017 02:46:08.0: Sequencer is no longer active 15-Apr-2017 02:46:08.0: Open the observatory dome/roof 15-Apr-2017 02:47:16.8: ++ Auto Focus ++ 15-Apr-2017 02:47:16.8: Doing initial autofocus. 15-Apr-2017 02:47:16.8: Start special ACP AutoFocus script for scheduler 15-Apr-2017 02:47:27.2: Sequencer is now active 15-Apr-2017 02:51:12.7: Sequencer is no longer active 15-Apr-2017 02:51:12.7: Next periodic autofocus ASAP after 15-Apr-2017 04:50:42 UTC 15-Apr-2017 02:51:13.8: Plan V1724_Aql has Monitor Mode. Time to resubmit it. 15-Apr-2017 02:51:13.9: Plan V0959_Mon has Monitor Mode. Time to resubmit it. 15-Apr-2017 02:51:13.9: Plan MP_Gem has Monitor Mode. Time to resubmit it. 15-Apr-2017 02:51:13.9: Plan RZ_Leo has Monitor Mode. Time to resubmit it. 15-Apr-2017 02:51:13.9: Plan V1723_Aql has Monitor Mode. Time to resubmit it. 15-Apr-2017 02:51:13.9: Plan V1722_Aql has Monitor Mode. Time to resubmit it. 15-Apr-2017 02:51:13.9: Plan V1721_Aql has Monitor Mode. Time to resubmit it. 15-Apr-2017 02:51:13.9: Plan SDSS073224 has Monitor Mode. Time to resubmit it. 15-Apr-2017 02:51:13.9: Plan BY_Cam has Monitor Mode. Time to resubmit it. 15-Apr-2017 02:51:13.9: Plan ES_Dra has Monitor Mode. Time to resubmit it. 15-Apr-2017 02:51:13.9: Plan Z_Cam has Monitor Mode. Time to resubmit it. 15-Apr-2017 02:51:13.9: Plan CY_UMa has Monitor Mode. Time to resubmit it. 15-Apr-2017 02:51:13.9: Plan M56 has Monitor Mode. Time to resubmit it. 15-Apr-2017 02:51:13.9: Plan V339_Del_TMO61 has Monitor Mode. Time to resubmit it. 15-Apr-2017 02:51:13.9: Plan V841_Oph has Monitor Mode. Time to resubmit it. 15-Apr-2017 02:51:13.9: SelectRunning: No running plans 15-Apr-2017 02:51:13.9: Obs BC_Lac in Plan BC_Lac was vetoed by AirmassRange constraint. 15-Apr-2017 02:51:13.9: AirmassRange estimates ready after 15-Apr-2017 10:55:21 UTC 15-Apr-2017 02:51:13.9: Obs CB_32 in Plan CB_32 was vetoed by AirMass constraint. 15-Apr-2017 02:51:13.9: AirMass estimates ready after 15-Apr-2017 19:32:25 UTC 15-Apr-2017 02:51:14.0: Obs CM_Cas in Plan CM_Cas was vetoed by AirmassRange constraint. 15-Apr-2017 02:51:14.0: AirmassRange estimates ready after 15-Apr-2017 13:26:46 UTC 15-Apr-2017 02:51:14.0: Obs FO_Aqr in Plan FO_Aqr was vetoed by AirMass constraint. 15-Apr-2017 02:51:14.0: AirMass estimates ready after 15-Apr-2017 12:08:07 UTC 15-Apr-2017 02:51:14.0: Obs FU_Cam in Plan FU_Cam was vetoed by AirmassRange constraint. 15-Apr-2017 02:51:14.0: AirmassRange estimates ready after 15-Apr-2017 15:04:16 UTC 15-Apr-2017 02:51:14.0: STRICT VETO: 15-Apr-2017 02:51:14.0: Obs FY_Cam in Plan FY_Cam was vetoed by AirmassRange constraint. 15-Apr-2017 02:51:14.0: AirmassRange estimates ready after 15-Apr-2017 15:23:46 UTC 15-Apr-2017 02:51:14.0: Obs GSC_3649-0825 in Plan GSC_3649-0825 was vetoed by AirMass constraint. 15-Apr-2017 02:51:14.0: AirMass estimates ready after 15-Apr-2017 11:32:33 UTC 15-Apr-2017 02:51:14.0: Obs GSC_4030_204 in Plan GSC_4030_204 was vetoed by AirMass constraint. 15-Apr-2017 02:51:14.0: AirMass estimates ready after 15-Apr-2017 12:56:28 UTC 15-Apr-2017 02:51:14.0: Obs M56 in Plan M56 was vetoed by AirmassRange constraint. 15-Apr-2017 02:51:14.0: AirmassRange estimates ready after 15-Apr-2017 08:02:12 UTC 15-Apr-2017 02:51:14.0: Obs MV_Leo in Plan MV_Leo was vetoed by AirMass constraint. 15-Apr-2017 02:51:14.0: AirMass estimates ready after 15-Apr-2017 08:31:10 UTC 15-Apr-2017 02:51:14.0: Obs NGC_7790 in Plan NGC_7790 was vetoed by AirmassRange constraint. 15-Apr-2017 02:51:14.0: AirmassRange estimates ready after 15-Apr-2017 11:47:09 UTC 15-Apr-2017 02:51:14.1: Obs T_Ori in Plan T_Ori was vetoed by AirmassRange constraint. 15-Apr-2017 02:51:14.1: AirmassRange estimates ready after 15-Apr-2017 19:49:15 UTC 15-Apr-2017 02:51:14.1: Obs U_Aqr in Plan U_Aqr was vetoed by AirmassRange constraint. 15-Apr-2017 02:51:14.1: AirmassRange estimates ready after 15-Apr-2017 13:08:53 UTC 15-Apr-2017 02:51:14.1: Obs V0524_Cas in Plan V0524_Cas was vetoed by AirmassRange constraint. 15-Apr-2017 02:51:14.1: AirmassRange estimates ready after 15-Apr-2017 12:16:57 UTC 15-Apr-2017 02:51:14.1: Obs V0809_Cep in Plan V0809_Cep was vetoed by AirmassRange constraint. 15-Apr-2017 02:51:14.1: AirmassRange estimates ready after 15-Apr-2017 10:57:48 UTC 15-Apr-2017 02:51:14.1: Obs V0965_Per in Plan V0965_Per was vetoed by AirmassRange constraint. 15-Apr-2017 02:51:14.1: AirmassRange estimates ready after 15-Apr-2017 15:42:55 UTC 15-Apr-2017 02:51:14.1: Obs V1404_Cyg_TMO61 in Plan V1404_Cyg was vetoed by AirMass constraint. 15-Apr-2017 02:51:14.1: AirMass estimates ready after 15-Apr-2017 10:03:07 UTC 15-Apr-2017 02:51:14.1: Obs V1721_Aql in Plan V1721_Aql was vetoed by AirmassRange constraint. 15-Apr-2017 02:51:14.1: AirmassRange estimates ready after 15-Apr-2017 08:42:44 UTC 15-Apr-2017 02:51:14.1: Obs V1722_Aql in Plan V1722_Aql was vetoed by AirmassRange constraint. 15-Apr-2017 02:51:14.1: AirmassRange estimates ready after 15-Apr-2017 08:30:26 UTC 15-Apr-2017 02:51:14.2: Obs V1723_Aql in Plan V1723_Aql was vetoed by AirmassRange constraint. 15-Apr-2017 02:51:14.2: AirmassRange estimates ready after 15-Apr-2017 08:56:47 UTC 15-Apr-2017 02:51:14.2: Obs V1724_Aql in Plan V1724_Aql was vetoed by AirmassRange constraint. 15-Apr-2017 02:51:14.2: AirmassRange estimates ready after 15-Apr-2017 08:50:14 UTC 15-Apr-2017 02:51:14.2: Obs V1904_Cyg in Plan V1904_Cyg was vetoed by AirmassRange constraint. 15-Apr-2017 02:51:14.2: AirmassRange estimates ready after 15-Apr-2017 10:02:55 UTC 15-Apr-2017 02:51:14.2: Obs V1907_Cyg in Plan V1907_Cyg was vetoed by AirmassRange constraint. 15-Apr-2017 02:51:14.2: AirmassRange estimates ready after 15-Apr-2017 09:59:36 UTC 15-Apr-2017 02:51:14.2: Obs V2481_Cyg in Plan V2481_Cyg was vetoed by AirmassRange constraint. 15-Apr-2017 02:51:14.2: AirmassRange estimates ready after 15-Apr-2017 09:35:33 UTC 15-Apr-2017 02:51:14.2: Obs V339_Del_TMO61 in Plan V339_Del_TMO61 was vetoed by AirmassRange constraint. 15-Apr-2017 02:51:14.2: AirmassRange estimates ready after 15-Apr-2017 09:27:37 UTC 15-Apr-2017 02:51:14.2: Obs V841_Oph in Plan V841_Oph was vetoed by AirmassRange constraint. 15-Apr-2017 02:51:14.2: AirmassRange estimates ready after 15-Apr-2017 07:46:03 UTC 15-Apr-2017 02:51:14.2: Obs WZ_Per in Plan WZ_Per was vetoed by AirmassRange constraint. 15-Apr-2017 02:51:14.2: AirmassRange estimates ready after 15-Apr-2017 16:03:14 UTC 15-Apr-2017 02:51:14.2: Efficiency: Selecting from 13 pending plans: 15-Apr-2017 02:51:14.3: Selected Plan BX_Pup (TotEff=1.20) 15-Apr-2017 02:51:14.4: Dispatcher cycle time: 0.5900008 sec. 15-Apr-2017 02:51:14.4: Acquire data for Observation BX_Pup... 15-Apr-2017 02:51:14.4: (belongs to Project SXN, Plan BX_Pup) 15-Apr-2017 02:51:14.4: Send Observation BX_Pup to ACP Sequencer 15-Apr-2017 02:51:24.4: Sequencer is now active 15-Apr-2017 03:01:28.8: Sequencer is no longer active 15-Apr-2017 03:01:28.8: Post-job status check done (stat=Completed) 15-Apr-2017 03:01:28.8: Acquisition time: 614.3978639 sec. 15-Apr-2017 03:01:28.8: Data for Observation BX_Pup acquired successfully. 15-Apr-2017 03:01:28.8: Image Efficiency: 58.5% 15-Apr-2017 03:01:28.8: Cycle Efficiency: 99.9% 15-Apr-2017 03:01:31.3: SelectRunning: No running plans 15-Apr-2017 03:01:31.4: Efficiency: Selecting from 12 pending plans: 15-Apr-2017 03:01:31.5: Selected Plan BY_Cam (TotEff=2.26) 15-Apr-2017 03:01:31.5: Dispatcher cycle time: 0.4600007 sec. 15-Apr-2017 03:01:31.5: Acquire data for Observation BY_Cam... 15-Apr-2017 03:01:31.5: (belongs to Project Walker, Plan BY_Cam) 15-Apr-2017 03:01:31.5: Doing 30 sets 15-Apr-2017 03:01:31.5: Send Observation BY_Cam to ACP Sequencer 15-Apr-2017 03:01:41.5: Sequencer is now active 15-Apr-2017 03:28:06.0: Sequencer is no longer active 15-Apr-2017 03:28:06.0: Post-job status check done (stat=Completed) 15-Apr-2017 03:28:06.0: Send Observation BY_Cam to ACP Sequencer 15-Apr-2017 03:28:16.0: Sequencer is now active 15-Apr-2017 03:53:18.9: Sequencer is no longer active 15-Apr-2017 03:53:18.9: Post-job status check done (stat=Completed) 15-Apr-2017 03:53:18.9: Send Observation BY_Cam to ACP Sequencer 15-Apr-2017 03:53:28.9: Sequencer is now active 15-Apr-2017 04:18:36.1: Sequencer is no longer active 15-Apr-2017 04:18:36.1: Post-job status check done (stat=Completed) 15-Apr-2017 04:18:36.1: Send Observation BY_Cam to ACP Sequencer 15-Apr-2017 04:18:46.1: Sequencer is now active 15-Apr-2017 04:43:51.1: Sequencer is no longer active 15-Apr-2017 04:43:51.1: Post-job status check done (stat=Completed) 15-Apr-2017 04:43:51.1: Send Observation BY_Cam to ACP Sequencer 15-Apr-2017 04:44:01.1: Sequencer is now active 15-Apr-2017 05:08:42.5: Sequencer is no longer active 15-Apr-2017 05:08:42.5: Post-job status check done (stat=Completed) 15-Apr-2017 05:08:42.5: Send Observation BY_Cam to ACP Sequencer 15-Apr-2017 05:08:52.5: Sequencer is now active 15-Apr-2017 05:33:58.6: Sequencer is no longer active 15-Apr-2017 05:33:58.6: Post-job status check done (stat=Completed) 15-Apr-2017 05:33:58.6: TestConstraints: AirMass vetoed/failed for end time of Obs 15-Apr-2017 05:33:58.6: ***Running Plan BY_Cam (best efforts) completed early: 15-Apr-2017 05:33:58.6: Obs BY_Cam would would not meet constraints 15-Apr-2017 05:33:58.6: Acquisition time: 9147.0218539 sec. 15-Apr-2017 05:33:58.6: Data for Observation BY_Cam acquired successfully. 15-Apr-2017 05:33:58.7: Image Efficiency: 78.7% 15-Apr-2017 05:33:58.7: Cycle Efficiency: 100.0% 15-Apr-2017 05:33:58.8: ++ Auto Focus ++ 15-Apr-2017 05:33:58.8: Doing periodic autofocus. 15-Apr-2017 05:33:58.8: Start special ACP AutoFocus script for scheduler 15-Apr-2017 05:34:08.8: Sequencer is now active 15-Apr-2017 05:49:18.2: Sequencer is no longer active 15-Apr-2017 05:49:18.2: ** Autofocus failed (n=1), will retry ASAP after 0 min. 15-Apr-2017 05:49:18.2: SelectRunning: No running plans 15-Apr-2017 05:49:18.6: STRICT VETO: 15-Apr-2017 05:49:18.6: Obs M67 in Plan M67 was vetoed by AirMass constraint. 15-Apr-2017 05:49:18.6: AirMass estimates ready after 15-Apr-2017 22:42:15 UTC 15-Apr-2017 05:49:18.6: Obs MP_Gem in Plan MP_Gem was vetoed by AirMass constraint. 15-Apr-2017 05:49:18.6: AirMass estimates ready after 15-Apr-2017 19:53:22 UTC 15-Apr-2017 05:49:18.7: Obs SDSS073224 in Plan SDSS073224 was vetoed by AirMass constraint. 15-Apr-2017 05:49:18.7: AirMass estimates ready after 15-Apr-2017 20:22:14 UTC 15-Apr-2017 05:49:18.7: Obs SDSSJ121027+502735 in Plan SDSSJ121027+502735 was vetoed by MoonDown constraint. 15-Apr-2017 05:49:18.7: MoonDown estimates ready after 15-Apr-2017 15:49:49 UTC 15-Apr-2017 05:49:18.7: Obs V0959_Mon in Plan V0959_Mon was vetoed by AirmassRange constraint. 15-Apr-2017 05:49:18.7: AirmassRange estimates ready after 15-Apr-2017 20:17:15 UTC 15-Apr-2017 05:49:18.7: Efficiency: Selecting from 6 pending plans: 15-Apr-2017 05:49:18.7: Selected Plan CY_UMa (TotEff=1.95) 15-Apr-2017 05:49:18.8: Dispatcher cycle time: 0.5450311 sec. 15-Apr-2017 05:49:18.8: Acquire data for Observation CY_UMa... 15-Apr-2017 05:49:18.8: (belongs to Project Walker, Plan CY_UMa) 15-Apr-2017 05:49:18.8: Doing 20 sets 15-Apr-2017 05:49:18.8: Send Observation CY_UMa to ACP Sequencer 15-Apr-2017 05:49:28.8: Sequencer is now active 15-Apr-2017 06:04:14.9: ** Script interrupted by weather safety event. 15-Apr-2017 06:04:17.9: Post-job status check done (stat=Running) 15-Apr-2017 06:04:17.9: ACP ABORT: Acquisition process was interrupted by weather unsafe. 15-Apr-2017 06:04:17.9: ACP data acquisition failed for Observation CY_UMa. 15-Apr-2017 06:04:17.9: (Plan CY_UMa will be resubmitted in its entirety. 15-Apr-2017 06:04:17.9: (Observation CY_UMa interrupted by weather unsafe event.) 15-Apr-2017 06:04:18.4: -- Weather Unsafe -- 15-Apr-2017 06:24:55.5: ++ Weather Safe ++ 15-Apr-2017 06:24:57.0: ++ Observatory Startup ++ 15-Apr-2017 06:24:57.1: Start ACP Sequencer's StartupObs script 15-Apr-2017 06:24:57.4: WARNING: Request sent while Weather Safety Script is running 15-Apr-2017 06:25:57.6: WARNING: Request sent while Weather Safety Script is running 15-Apr-2017 06:26:57.9: WARNING: Request sent while Weather Safety Script is running 15-Apr-2017 06:27:58.5: WARNING: Request sent while Weather Safety Script is running 15-Apr-2017 06:28:58.7: WARNING: Request sent while Weather Safety Script is running 15-Apr-2017 06:29:59.0: WARNING: Request sent while Weather Safety Script is running 15-Apr-2017 06:30:59.3: WARNING: Request sent while Weather Safety Script is running 15-Apr-2017 06:31:59.5: WARNING: Request sent while Weather Safety Script is running 15-Apr-2017 06:32:59.8: WARNING: Request sent while Weather Safety Script is running 15-Apr-2017 06:34:00.0: WARNING: Request sent while Weather Safety Script is running 15-Apr-2017 06:35:00.2: WARNING: Request sent while Weather Safety Script is running 15-Apr-2017 06:36:00.8: WARNING: Request sent while Weather Safety Script is running 15-Apr-2017 06:37:01.4: WARNING: Request sent while Weather Safety Script is running 15-Apr-2017 06:38:01.8: WARNING: Request sent while Weather Safety Script is running 15-Apr-2017 06:39:02.0: WARNING: Request sent while Weather Safety Script is running 15-Apr-2017 06:40:02.5: **EXCEPTION IN SCHEDULER: 15-Apr-2017 06:40:02.5: Observatory startup failed, cannot continue. 15-Apr-2017 06:40:02.5: Traceback: at DC3.Scheduler.Engine.DoStartupIf(Boolean openDome) at DC3.Scheduler.Engine.Run() 15-Apr-2017 06:40:02.6: Run statistics: 15-Apr-2017 06:40:02.6: Observations: 59 15-Apr-2017 06:40:02.6: Considered: 42 15-Apr-2017 06:40:02.6: Completed: 18 15-Apr-2017 06:40:02.6: Skipped: 21 15-Apr-2017 06:40:02.6: Never Eligible: 17 15-Apr-2017 06:40:02.6: Failed: 3 15-Apr-2017 06:40:02.6: Shutter-Open efficiency: 71.33% 15-Apr-2017 06:40:02.6: Overall Efficiency: 99.90% 15-Apr-2017 06:40:02.6: Release ACP sequencer Log closed at Sat, Apr 15 2017 06:40:05 UTC (actual time)