Log opened at Fri, Jan 26 2018 02:04:47 UTC (actual time) Current log level is Debug Scheduler version 3.5.3 Multi-user license: Arne Henden 26-Jan-2018 02:04:47.6: ++ Observatory Startup ++ 26-Jan-2018 02:04:47.6: Start ACP Sequencer's StartupObs script 26-Jan-2018 02:04:57.6: Sequencer is now active 26-Jan-2018 02:05:20.7: Sequencer is no longer active 26-Jan-2018 02:05:20.8: Open the observatory dome/roof 26-Jan-2018 02:06:26.9: ++ Auto Focus ++ 26-Jan-2018 02:06:27.0: Doing initial autofocus. 26-Jan-2018 02:06:27.0: Start special ACP AutoFocus script for scheduler 26-Jan-2018 02:06:37.3: Sequencer is now active 26-Jan-2018 02:10:56.0: Sequencer is no longer active 26-Jan-2018 02:10:56.0: Next periodic autofocus ASAP after 26-Jan-2018 05:10:02 UTC 26-Jan-2018 02:10:56.4: Plan V0959_Mon has Monitor Mode. Time to resubmit it. 26-Jan-2018 02:10:56.4: Plan MP_Gem has Monitor Mode. Time to resubmit it. 26-Jan-2018 02:10:56.4: Plan CB_32 has Monitor Mode. Time to resubmit it. 26-Jan-2018 02:10:56.4: Plan V0965_Per has Monitor Mode. Time to resubmit it. 26-Jan-2018 02:10:56.4: Plan SDSSJ121027+502735 has Monitor Mode. Time to resubmit it. 26-Jan-2018 02:10:56.4: Plan T_Ori has Monitor Mode. Time to resubmit it. 26-Jan-2018 02:10:56.4: Plan Mkn_501 has Monitor Mode. Time to resubmit it. 26-Jan-2018 02:10:56.4: Plan RX_J0524+42 has Monitor Mode. Time to resubmit it. 26-Jan-2018 02:10:56.4: SelectRunning: No running plans 26-Jan-2018 02:10:56.4: Obs 2MASS_J19395313+0557550 in Plan 2MASS_J19395313+0557550 was vetoed by AirmassRange constraint. 26-Jan-2018 02:10:56.4: AirmassRange estimates ready after 26-Jan-2018 14:30:43 UTC 26-Jan-2018 02:10:56.4: Obs IRAS_19067+2158 in Plan IRAS_19067+2158 was vetoed by AirmassRange constraint. 26-Jan-2018 02:10:56.4: AirmassRange estimates ready after 26-Jan-2018 13:22:07 UTC 26-Jan-2018 02:10:56.5: Obs IRAS_19183+2339 in Plan IRAS_19183+2339 was vetoed by AirmassRange constraint. 26-Jan-2018 02:10:56.5: AirmassRange estimates ready after 26-Jan-2018 13:30:11 UTC 26-Jan-2018 02:10:56.5: Obs M56 in Plan M56 was vetoed by AirmassRange constraint. 26-Jan-2018 02:10:56.5: AirmassRange estimates ready after 26-Jan-2018 13:13:48 UTC 26-Jan-2018 02:10:56.5: Obs M67 in Plan M67 was vetoed by AirMass constraint. 26-Jan-2018 02:10:56.5: AirMass estimates ready after 26-Jan-2018 03:57:46 UTC 26-Jan-2018 02:10:56.5: Obs Mkn_501 in Plan Mkn_501 was vetoed by AirMass constraint. 26-Jan-2018 02:10:56.5: AirMass estimates ready after 26-Jan-2018 10:34:01 UTC 26-Jan-2018 02:10:56.5: Obs NSVS_11201899 in Plan NSVS_11201899 was vetoed by AirmassRange constraint. 26-Jan-2018 02:10:56.5: AirmassRange estimates ready after 26-Jan-2018 13:23:08 UTC 26-Jan-2018 02:10:56.5: Obs SDSSJ121027+502735 in Plan SDSSJ121027+502735 was vetoed by AirMass constraint. 26-Jan-2018 02:10:56.5: AirMass estimates ready after 26-Jan-2018 05:32:35 UTC 26-Jan-2018 02:10:56.5: Obs U_Aqr in Plan U_Aqr was vetoed by AirmassRange constraint. 26-Jan-2018 02:10:56.5: AirmassRange estimates ready after 26-Jan-2018 18:20:29 UTC 26-Jan-2018 02:10:56.6: Obs V1721_Aql in Plan V1721_Aql was vetoed by AirmassRange constraint. 26-Jan-2018 02:10:56.6: AirmassRange estimates ready after 26-Jan-2018 13:54:20 UTC 26-Jan-2018 02:10:56.6: Obs V1722_Aql in Plan V1722_Aql was vetoed by AirmassRange constraint. 26-Jan-2018 02:10:56.6: AirmassRange estimates ready after 26-Jan-2018 13:42:01 UTC 26-Jan-2018 02:10:56.6: Obs V1723_Aql in Plan V1723_Aql was vetoed by AirmassRange constraint. 26-Jan-2018 02:10:56.6: AirmassRange estimates ready after 26-Jan-2018 14:08:23 UTC 26-Jan-2018 02:10:56.6: Obs V1724_Aql in Plan V1724_Aql was vetoed by AirmassRange constraint. 26-Jan-2018 02:10:56.6: AirmassRange estimates ready after 26-Jan-2018 14:01:50 UTC 26-Jan-2018 02:10:56.6: Obs V1904_Cyg in Plan V1904_Cyg was vetoed by AirmassRange constraint. 26-Jan-2018 02:10:56.6: AirmassRange estimates ready after 26-Jan-2018 15:14:31 UTC 26-Jan-2018 02:10:56.6: Obs V1907_Cyg in Plan V1907_Cyg was vetoed by AirmassRange constraint. 26-Jan-2018 02:10:56.6: AirmassRange estimates ready after 26-Jan-2018 15:11:12 UTC 26-Jan-2018 02:10:56.6: Obs V2481_Cyg in Plan V2481_Cyg was vetoed by AirmassRange constraint. 26-Jan-2018 02:10:56.6: AirmassRange estimates ready after 26-Jan-2018 14:47:09 UTC 26-Jan-2018 02:10:56.6: Obs V339_Del_TMO61 in Plan V339_Del_TMO61 was vetoed by AirmassRange constraint. 26-Jan-2018 02:10:56.6: AirmassRange estimates ready after 26-Jan-2018 14:39:12 UTC 26-Jan-2018 02:10:56.6: Obs V841_Oph in Plan V841_Oph was vetoed by AirmassRange constraint. 26-Jan-2018 02:10:56.6: AirmassRange estimates ready after 26-Jan-2018 12:57:38 UTC 26-Jan-2018 02:10:56.6: Efficiency: Selecting from 14 pending plans: 26-Jan-2018 02:10:56.7: Selected Plan V0965_Per (TotEff=0.89) 26-Jan-2018 02:10:56.8: Dispatcher cycle time: 0.8370479 sec. 26-Jan-2018 02:10:56.8: Acquire data for Observation V0965_Per... 26-Jan-2018 02:10:56.8: (belongs to Project Nova_Monitoring, Plan V0965_Per) 26-Jan-2018 02:10:56.8: Send Observation V0965_Per to ACP Sequencer 26-Jan-2018 02:11:06.8: Sequencer is now active 26-Jan-2018 02:30:37.8: Sequencer is no longer active 26-Jan-2018 02:30:37.8: Post-job status check done (stat=Completed) 26-Jan-2018 02:30:37.8: Acquisition time: 1180.959547 sec. 26-Jan-2018 02:30:37.8: Data for Observation V0965_Per acquired successfully. 26-Jan-2018 02:30:37.8: Image Efficiency: 76.2% 26-Jan-2018 02:30:37.8: Cycle Efficiency: 99.9% 26-Jan-2018 02:30:38.2: SelectRunning: No running plans 26-Jan-2018 02:30:38.2: STRICT VETO: 26-Jan-2018 02:30:38.2: Obs BC_Lac in Plan BC_Lac was vetoed by AirmassRange constraint. 26-Jan-2018 02:30:38.2: AirmassRange estimates ready after 26-Jan-2018 16:06:57 UTC 26-Jan-2018 02:30:38.2: Efficiency: Selecting from 12 pending plans: 26-Jan-2018 02:30:38.3: Selected Plan V0524_Cas (TotEff=0.80) 26-Jan-2018 02:30:38.4: Dispatcher cycle time: 0.3300189 sec. 26-Jan-2018 02:30:38.4: Acquire data for Observation V0524_Cas... 26-Jan-2018 02:30:38.4: (belongs to Project AAVSO_HGUA_Miras_Manual_ver167, Plan V0524_Cas) 26-Jan-2018 02:30:38.4: Send Observation V0524_Cas to ACP Sequencer 26-Jan-2018 02:30:48.4: Sequencer is now active 26-Jan-2018 02:44:50.5: Sequencer is no longer active 26-Jan-2018 02:44:50.5: Post-job status check done (stat=Completed) 26-Jan-2018 02:44:50.5: Acquisition time: 852.0697356 sec. 26-Jan-2018 02:44:50.5: Data for Observation V0524_Cas acquired successfully. 26-Jan-2018 02:44:50.5: Image Efficiency: 45.8% 26-Jan-2018 02:44:50.5: Cycle Efficiency: 100.0% 26-Jan-2018 02:44:50.8: SelectRunning: No running plans 26-Jan-2018 02:44:50.9: Efficiency: Selecting from 11 pending plans: 26-Jan-2018 02:44:51.0: Selected Plan T_Ori (TotEff=0.81) 26-Jan-2018 02:44:51.1: Dispatcher cycle time: 0.2420138 sec. 26-Jan-2018 02:44:51.1: Acquire data for Observation T_Ori... 26-Jan-2018 02:44:51.1: (belongs to Project AAVSO_Monitoring, Plan T_Ori) 26-Jan-2018 02:44:51.1: Doing 3 sets 26-Jan-2018 02:44:51.1: Send Observation T_Ori to ACP Sequencer 26-Jan-2018 02:45:01.1: Sequencer is now active 26-Jan-2018 02:49:37.6: Sequencer is no longer active 26-Jan-2018 02:49:37.6: Post-job status check done (stat=Completed) 26-Jan-2018 02:49:37.6: Send Observation T_Ori to ACP Sequencer 26-Jan-2018 02:49:47.6: Sequencer is now active 26-Jan-2018 02:52:35.2: Sequencer is no longer active 26-Jan-2018 02:52:35.2: Post-job status check done (stat=Completed) 26-Jan-2018 02:52:35.2: Send Observation T_Ori to ACP Sequencer 26-Jan-2018 02:52:45.2: Sequencer is now active 26-Jan-2018 02:55:31.4: Sequencer is no longer active 26-Jan-2018 02:55:31.4: Post-job status check done (stat=Completed) 26-Jan-2018 02:55:31.4: Acquisition time: 640.2786218 sec. 26-Jan-2018 02:55:31.4: Data for Observation T_Ori acquired successfully. 26-Jan-2018 02:55:31.4: Image Efficiency: 42.1% 26-Jan-2018 02:55:31.4: Cycle Efficiency: 100.0% 26-Jan-2018 02:55:31.6: SelectRunning: No running plans 26-Jan-2018 02:55:31.6: Efficiency: Selecting from 10 pending plans: 26-Jan-2018 02:55:31.7: Selected Plan CB_32 (TotEff=0.83) 26-Jan-2018 02:55:31.8: Dispatcher cycle time: 0.2350135 sec. 26-Jan-2018 02:55:31.8: Acquire data for Observation CB_32... 26-Jan-2018 02:55:31.8: (belongs to Project Staels, Plan CB_32) 26-Jan-2018 02:55:31.8: Send Observation CB_32 to ACP Sequencer 26-Jan-2018 02:55:41.8: Sequencer is now active 26-Jan-2018 02:59:01.4: Sequencer is no longer active 26-Jan-2018 02:59:01.4: Post-job status check done (stat=Completed) 26-Jan-2018 02:59:01.4: Acquisition time: 209.6039887 sec. 26-Jan-2018 02:59:01.4: Data for Observation CB_32 acquired successfully. 26-Jan-2018 02:59:01.4: Image Efficiency: 42.9% 26-Jan-2018 02:59:01.4: Cycle Efficiency: 99.9% 26-Jan-2018 02:59:01.6: SelectRunning: No running plans 26-Jan-2018 02:59:01.6: Efficiency: Selecting from 9 pending plans: 26-Jan-2018 02:59:01.7: Selected Plan V0959_Mon (TotEff=0.72) 26-Jan-2018 02:59:01.8: Dispatcher cycle time: 0.2500143 sec. 26-Jan-2018 02:59:01.8: Acquire data for Observation V0959_Mon... 26-Jan-2018 02:59:01.8: (belongs to Project Nova_Monitoring, Plan V0959_Mon) 26-Jan-2018 02:59:01.8: Send Observation V0959_Mon to ACP Sequencer 26-Jan-2018 02:59:11.8: Sequencer is now active 26-Jan-2018 03:13:55.5: Sequencer is no longer active 26-Jan-2018 03:13:55.5: Post-job status check done (stat=Completed) 26-Jan-2018 03:13:55.5: Acquisition time: 893.7001168 sec. 26-Jan-2018 03:13:55.5: Data for Observation V0959_Mon acquired successfully. 26-Jan-2018 03:13:55.5: Image Efficiency: 70.5% 26-Jan-2018 03:13:55.5: Cycle Efficiency: 100.0% 26-Jan-2018 03:13:55.8: SelectRunning: No running plans 26-Jan-2018 03:13:55.8: STRICT VETO: 26-Jan-2018 03:13:55.8: Obs V0809_Cep in Plan V0809_Cep was vetoed by AirmassRange constraint. 26-Jan-2018 03:13:55.8: AirmassRange estimates ready after 26-Jan-2018 16:09:24 UTC 26-Jan-2018 03:13:55.8: Efficiency: Selecting from 7 pending plans: 26-Jan-2018 03:13:55.9: Selected Plan MP_Gem (TotEff=0.71) 26-Jan-2018 03:13:56.2: Dispatcher cycle time: 0.4820276 sec. 26-Jan-2018 03:13:56.2: Acquire data for Observation MP_Gem... 26-Jan-2018 03:13:56.2: (belongs to Project Staels, Plan MP_Gem) 26-Jan-2018 03:13:56.2: Send Observation MP_Gem to ACP Sequencer 26-Jan-2018 03:14:06.3: Sequencer is now active 26-Jan-2018 03:44:22.4: Sequencer is no longer active 26-Jan-2018 03:44:22.4: Post-job status check done (stat=Completed) 26-Jan-2018 03:44:22.4: Acquisition time: 1826.1284485 sec. 26-Jan-2018 03:44:22.4: Data for Observation MP_Gem acquired successfully. 26-Jan-2018 03:44:22.4: Image Efficiency: 78.8% 26-Jan-2018 03:44:22.4: Cycle Efficiency: 100.0% 26-Jan-2018 03:44:22.6: SelectRunning: No running plans 26-Jan-2018 03:44:22.6: Obs GSC_3649-0825 in Plan GSC_3649-0825 was vetoed by AirMass constraint. 26-Jan-2018 03:44:22.6: AirMass estimates ready after 26-Jan-2018 16:44:09 UTC 26-Jan-2018 03:44:22.7: Efficiency: Selecting from 5 pending plans: 26-Jan-2018 03:44:22.7: Selected Plan RX_J0524+42 (TotEff=0.69) 26-Jan-2018 03:44:22.8: Dispatcher cycle time: 0.1950111 sec. 26-Jan-2018 03:44:22.8: Acquire data for Observation RX_J0524+42... 26-Jan-2018 03:44:22.8: (belongs to Project Walker, Plan RX_J0524+42) 26-Jan-2018 03:44:22.8: Doing 30 sets 26-Jan-2018 03:44:22.8: Send Observation RX_J0524+42 to ACP Sequencer 26-Jan-2018 03:44:32.8: Sequencer is now active 26-Jan-2018 04:10:18.1: Sequencer is no longer active 26-Jan-2018 04:10:18.1: Post-job status check done (stat=Completed) 26-Jan-2018 04:10:18.1: Send Observation RX_J0524+42 to ACP Sequencer 26-Jan-2018 04:10:28.1: Sequencer is now active 26-Jan-2018 04:35:31.3: Sequencer is no longer active 26-Jan-2018 04:35:31.3: Post-job status check done (stat=Completed) 26-Jan-2018 04:35:31.3: Send Observation RX_J0524+42 to ACP Sequencer 26-Jan-2018 04:35:41.3: Sequencer is now active 26-Jan-2018 05:00:47.9: Sequencer is no longer active 26-Jan-2018 05:00:47.9: Post-job status check done (stat=Completed) 26-Jan-2018 05:00:47.9: Send Observation RX_J0524+42 to ACP Sequencer 26-Jan-2018 05:00:57.9: Sequencer is now active 26-Jan-2018 05:26:07.7: Sequencer is no longer active 26-Jan-2018 05:26:07.7: Post-job status check done (stat=Completed) 26-Jan-2018 05:26:08.0: Send Observation RX_J0524+42 to ACP Sequencer 26-Jan-2018 05:26:18.0: Sequencer is now active 26-Jan-2018 05:51:29.2: Sequencer is no longer active 26-Jan-2018 05:51:29.2: Post-job status check done (stat=Completed) 26-Jan-2018 05:51:29.2: Send Observation RX_J0524+42 to ACP Sequencer 26-Jan-2018 05:51:39.2: Sequencer is now active 26-Jan-2018 06:16:46.1: Sequencer is no longer active 26-Jan-2018 06:16:46.1: Post-job status check done (stat=Completed) 26-Jan-2018 06:16:46.2: Send Observation RX_J0524+42 to ACP Sequencer 26-Jan-2018 06:16:56.2: Sequencer is now active 26-Jan-2018 06:42:14.0: Sequencer is no longer active 26-Jan-2018 06:42:14.0: Post-job status check done (stat=Completed) 26-Jan-2018 06:42:14.1: Send Observation RX_J0524+42 to ACP Sequencer 26-Jan-2018 06:42:24.1: Sequencer is now active 26-Jan-2018 07:07:29.3: Sequencer is no longer active 26-Jan-2018 07:07:29.3: Post-job status check done (stat=Completed) 26-Jan-2018 07:07:29.3: Send Observation RX_J0524+42 to ACP Sequencer 26-Jan-2018 07:07:39.3: Sequencer is now active 26-Jan-2018 07:32:38.5: Sequencer is no longer active 26-Jan-2018 07:32:38.5: Post-job status check done (stat=Completed) 26-Jan-2018 07:32:38.8: Send Observation RX_J0524+42 to ACP Sequencer 26-Jan-2018 07:32:48.8: Sequencer is now active 26-Jan-2018 07:57:50.4: Sequencer is no longer active 26-Jan-2018 07:57:50.4: Post-job status check done (stat=Completed) 26-Jan-2018 07:57:50.8: Send Observation RX_J0524+42 to ACP Sequencer 26-Jan-2018 07:58:00.8: Sequencer is now active 26-Jan-2018 08:23:02.2: Sequencer is no longer active 26-Jan-2018 08:23:02.2: Post-job status check done (stat=Completed) 26-Jan-2018 08:23:02.2: Send Observation RX_J0524+42 to ACP Sequencer 26-Jan-2018 08:23:12.2: Sequencer is now active 26-Jan-2018 08:48:18.9: Sequencer is no longer active 26-Jan-2018 08:48:18.9: Post-job status check done (stat=Completed) 26-Jan-2018 08:48:19.0: Send Observation RX_J0524+42 to ACP Sequencer 26-Jan-2018 08:48:29.0: Sequencer is now active 26-Jan-2018 09:13:29.3: Sequencer is no longer active 26-Jan-2018 09:13:29.4: Post-job status check done (stat=Completed) 26-Jan-2018 09:13:29.4: Send Observation RX_J0524+42 to ACP Sequencer 26-Jan-2018 09:13:39.4: Sequencer is now active 26-Jan-2018 09:38:48.6: Sequencer is no longer active 26-Jan-2018 09:38:48.6: Post-job status check done (stat=Completed) 26-Jan-2018 09:38:48.6: TestConstraints: AirMass vetoed/failed for end time of Obs 26-Jan-2018 09:38:48.6: ***Running Plan RX_J0524+42 (best efforts) completed early: 26-Jan-2018 09:38:48.6: Obs RX_J0524+42 would would not meet constraints 26-Jan-2018 09:38:48.6: Acquisition time: 21265.6293244 sec. 26-Jan-2018 09:38:48.6: Data for Observation RX_J0524+42 acquired successfully. 26-Jan-2018 09:38:48.7: Image Efficiency: 79.0% 26-Jan-2018 09:38:48.7: Cycle Efficiency: 100.0% 26-Jan-2018 09:38:48.9: ++ Auto Focus ++ 26-Jan-2018 09:38:48.9: Doing periodic autofocus. 26-Jan-2018 09:38:48.9: Start special ACP AutoFocus script for scheduler 26-Jan-2018 09:38:58.9: Sequencer is now active 26-Jan-2018 10:03:41.7: ** Script interrupted by weather safety event. 26-Jan-2018 10:03:41.7: ** Autofocus failed (n=1), will retry ASAP after 0 min. 26-Jan-2018 10:03:42.0: -- Weather Unsafe -- 26-Jan-2018 10:46:22.0: ++ Weather Safe ++ 26-Jan-2018 10:46:23.1: ++ Observatory Startup ++ 26-Jan-2018 10:46:23.2: Start ACP Sequencer's StartupObs script 26-Jan-2018 10:46:23.4: WARNING: Request sent while Weather Safety Script is running 26-Jan-2018 10:47:23.7: WARNING: Request sent while Weather Safety Script is running 26-Jan-2018 10:48:24.0: WARNING: Request sent while Weather Safety Script is running 26-Jan-2018 10:49:24.2: WARNING: Request sent while Weather Safety Script is running 26-Jan-2018 10:50:24.5: WARNING: Request sent while Weather Safety Script is running 26-Jan-2018 10:51:24.8: WARNING: Request sent while Weather Safety Script is running 26-Jan-2018 10:52:25.1: WARNING: Request sent while Weather Safety Script is running 26-Jan-2018 10:53:25.3: WARNING: Request sent while Weather Safety Script is running 26-Jan-2018 10:54:25.5: WARNING: Request sent while Weather Safety Script is running 26-Jan-2018 10:55:25.8: WARNING: Request sent while Weather Safety Script is running 26-Jan-2018 10:56:26.0: WARNING: Request sent while Weather Safety Script is running 26-Jan-2018 10:57:26.3: WARNING: Request sent while Weather Safety Script is running 26-Jan-2018 10:58:26.5: WARNING: Request sent while Weather Safety Script is running 26-Jan-2018 10:59:26.8: WARNING: Request sent while Weather Safety Script is running 26-Jan-2018 11:00:27.0: WARNING: Request sent while Weather Safety Script is running 26-Jan-2018 11:01:27.7: **EXCEPTION IN SCHEDULER: 26-Jan-2018 11:01:27.7: Observatory startup failed, cannot continue. 26-Jan-2018 11:01:27.7: Traceback: at DC3.Scheduler.Engine.DoStartupIf(Boolean openDome) at DC3.Scheduler.Engine.Run() 26-Jan-2018 11:01:27.8: Run statistics: 26-Jan-2018 11:01:27.8: Observations: 51 26-Jan-2018 11:01:27.8: Considered: 33 26-Jan-2018 11:01:27.8: Completed: 23 26-Jan-2018 11:01:27.8: Skipped: 7 26-Jan-2018 11:01:27.8: Never Eligible: 18 26-Jan-2018 11:01:27.8: Failed: 3 26-Jan-2018 11:01:27.8: Shutter-Open efficiency: 76.36% 26-Jan-2018 11:01:27.8: Overall Efficiency: 99.90% 26-Jan-2018 11:01:27.8: Release ACP sequencer Log closed at Fri, Jan 26 2018 11:01:29 UTC (actual time)