Log opened at Fri, Jan 27 2017 01:09:20 UTC (actual time) Current log level is Debug Scheduler version 3.5.3 Multi-user license: Arne Henden 27-Jan-2017 01:09:20.9: Simulated clock initialized at 27-Jan-2017 01:09:20 UTC 27-Jan-2017 01:09:21.0: Loaded Constraint plugin AirMass 27-Jan-2017 01:09:21.0: Loaded Constraint plugin AirmassRange 27-Jan-2017 01:09:21.0: Loaded Constraint plugin Horizon 27-Jan-2017 01:09:21.0: Loaded Constraint plugin HourAngle 27-Jan-2017 01:09:21.0: Loaded Constraint plugin MoonAvoid 27-Jan-2017 01:09:21.0: Loaded Constraint plugin MoonDown 27-Jan-2017 01:09:21.0: Loaded Constraint plugin SkyCondition 27-Jan-2017 01:09:21.0: Loaded Constraint plugin TimeRange 27-Jan-2017 01:09:21.5: 32 plans are now pending. 27-Jan-2017 01:09:21.5: Attach ACP sequencer 27-Jan-2017 01:11:25.8: Dispatcher started at 27-Jan-2017 01:11:25 UTC 27-Jan-2017 01:11:25.8: Priority: W[0]=0.00 27-Jan-2017 01:11:25.8: Transit Altitude: W[1]=0.00 27-Jan-2017 01:11:25.8: Highest Altitude: W[6]=0.00 27-Jan-2017 01:11:25.8: Lateness: W[5]=0.70 27-Jan-2017 01:11:25.8: Slew Distance: W[2]=0.30 27-Jan-2017 01:11:25.8: Retry Count: W[3]=0.20 27-Jan-2017 01:11:25.8: Meridian Crossing: W[4]=0.30 27-Jan-2017 01:11:25.8: Obs Conditions: W[7]=0.40 27-Jan-2017 01:11:25.8: Rising Plan Delay: disabled 27-Jan-2017 01:11:27.0: ++ Observatory Startup ++ 27-Jan-2017 01:11:27.0: Start ACP Sequencer's StartupObs script 27-Jan-2017 01:11:37.0: Sequencer is now active 27-Jan-2017 01:11:42.2: Sequencer is no longer active 27-Jan-2017 01:11:42.2: Open the observatory dome/roof 27-Jan-2017 01:31:02.0: ++ Auto Focus ++ 27-Jan-2017 01:31:02.0: Doing initial autofocus. 27-Jan-2017 01:31:02.0: Start special ACP AutoFocus script for scheduler 27-Jan-2017 01:31:12.0: Sequencer is now active 27-Jan-2017 01:34:56.3: Sequencer is no longer active 27-Jan-2017 01:34:56.3: Next periodic autofocus ASAP after 27-Jan-2017 03:34:21 UTC 27-Jan-2017 01:34:56.8: SelectRunning: No running plans 27-Jan-2017 01:34:56.9: Obs AT_Cnc in Plan AT_Cnc was vetoed by AirMass constraint. 27-Jan-2017 01:34:56.9: AirMass estimates ready after 27-Jan-2017 02:00:34 UTC 27-Jan-2017 01:34:56.9: Obs BX_Pup in Plan BX_Pup was vetoed by AirMass constraint. 27-Jan-2017 01:34:56.9: AirMass estimates ready after 27-Jan-2017 04:08:04 UTC 27-Jan-2017 01:34:56.9: Obs ES_Dra in Plan ES_Dra was vetoed by AirMass constraint. 27-Jan-2017 01:34:56.9: AirMass estimates ready after 27-Jan-2017 07:22:30 UTC 27-Jan-2017 01:34:56.9: Obs FO_Aqr in Plan FO_Aqr was vetoed by AirMass constraint. 27-Jan-2017 01:34:56.9: AirMass estimates ready after 27-Jan-2017 17:14:45 UTC 27-Jan-2017 01:34:56.9: Obs M56 in Plan M56 was vetoed by AirmassRange constraint. 27-Jan-2017 01:34:56.9: AirmassRange estimates ready after 27-Jan-2017 13:08:50 UTC 27-Jan-2017 01:34:56.9: Obs M67 in Plan M67 was vetoed by AirMass constraint. 27-Jan-2017 01:34:56.9: AirMass estimates ready after 27-Jan-2017 03:52:49 UTC 27-Jan-2017 01:34:56.9: Obs MV_Leo in Plan MV_Leo was vetoed by AirMass constraint. 27-Jan-2017 01:34:56.9: AirMass estimates ready after 27-Jan-2017 13:37:48 UTC 27-Jan-2017 01:34:56.9: Obs QQ_Vul_TMO61 in Plan QQ_Vul was vetoed by AirMass constraint. 27-Jan-2017 01:34:56.9: AirMass estimates ready after 27-Jan-2017 13:41:31 UTC 27-Jan-2017 01:34:57.0: Obs SY_Cnc in Plan SY_Cnc was vetoed by AirMass constraint. 27-Jan-2017 01:34:57.0: AirMass estimates ready after 27-Jan-2017 02:49:13 UTC 27-Jan-2017 01:34:57.0: Obs U_Aqr in Plan U_Aqr was vetoed by AirmassRange constraint. 27-Jan-2017 01:34:57.0: AirmassRange estimates ready after 27-Jan-2017 18:15:31 UTC 27-Jan-2017 01:34:57.0: STRICT VETO: 27-Jan-2017 01:34:57.0: Obs V1404_Cyg_TMO61 in Plan V1404_Cyg was vetoed by AirMass constraint. 27-Jan-2017 01:34:57.0: AirMass estimates ready after 27-Jan-2017 15:09:45 UTC 27-Jan-2017 01:34:57.0: Obs V1721_Aql in Plan V1721_Aql was vetoed by AirmassRange constraint. 27-Jan-2017 01:34:57.0: AirmassRange estimates ready after 27-Jan-2017 13:49:22 UTC 27-Jan-2017 01:34:57.0: Obs V1722_Aql in Plan V1722_Aql was vetoed by AirmassRange constraint. 27-Jan-2017 01:34:57.0: AirmassRange estimates ready after 27-Jan-2017 13:37:04 UTC 27-Jan-2017 01:34:57.0: Obs V1723_Aql in Plan V1723_Aql was vetoed by AirmassRange constraint. 27-Jan-2017 01:34:57.0: AirmassRange estimates ready after 27-Jan-2017 14:03:25 UTC 27-Jan-2017 01:34:57.0: Obs V1724_Aql in Plan V1724_Aql was vetoed by AirmassRange constraint. 27-Jan-2017 01:34:57.0: AirmassRange estimates ready after 27-Jan-2017 13:56:52 UTC 27-Jan-2017 01:34:57.0: Obs V339_Del_TMO61 in Plan V339_Del_TMO61 was vetoed by AirmassRange constraint. 27-Jan-2017 01:34:57.0: AirmassRange estimates ready after 27-Jan-2017 14:34:15 UTC 27-Jan-2017 01:34:57.0: Obs V841_Oph in Plan V841_Oph was vetoed by AirmassRange constraint. 27-Jan-2017 01:34:57.0: AirmassRange estimates ready after 27-Jan-2017 12:52:41 UTC 27-Jan-2017 01:34:57.0: Obs SDSSJ121027+502735 in Plan WD was vetoed by AirMass constraint. 27-Jan-2017 01:34:57.0: AirMass estimates ready after 27-Jan-2017 05:27:37 UTC 27-Jan-2017 01:34:57.0: Efficiency: Selecting from 14 pending plans: 27-Jan-2017 01:34:57.2: Selected Plan BY_Cam (TotEff=141.30) 27-Jan-2017 01:34:57.2: Dispatcher cycle time: 0.9200013 sec. 27-Jan-2017 01:34:57.2: Acquire data for Observation BY_Cam... 27-Jan-2017 01:34:57.2: (belongs to Project Walker, Plan BY_Cam) 27-Jan-2017 01:34:57.2: Doing 200 sets 27-Jan-2017 01:34:57.2: Send Observation BY_Cam to ACP Sequencer 27-Jan-2017 01:35:07.2: Sequencer is now active 27-Jan-2017 10:03:03.5: Sequencer is no longer active 27-Jan-2017 10:03:03.5: Post-job status check done (stat=Completed) 27-Jan-2017 10:03:03.6: TestConstraints: AirMass vetoed/failed for end time of Obs 27-Jan-2017 10:03:03.6: ***Running Plan BY_Cam (best efforts) completed early: 27-Jan-2017 10:03:03.6: Obs BY_Cam would would not meet constraints 27-Jan-2017 10:03:03.6: ***Running Plan BY_Cam (best efforts) completed early: 27-Jan-2017 10:03:03.6: Obs BY_Cam would extend past dawn 27-Jan-2017 10:03:03.6: Acquisition time: 30486.3558066 sec. 27-Jan-2017 10:03:03.6: Data for Observation BY_Cam acquired successfully. 27-Jan-2017 10:03:03.6: Image Efficiency: 78.7% 27-Jan-2017 10:03:03.6: Cycle Efficiency: 100.0% 27-Jan-2017 10:03:04.0: ++ Auto Focus ++ 27-Jan-2017 10:03:04.0: Doing periodic autofocus. 27-Jan-2017 10:03:04.0: Start special ACP AutoFocus script for scheduler 27-Jan-2017 10:03:14.0: Sequencer is now active 27-Jan-2017 10:06:25.6: Sequencer is no longer active 27-Jan-2017 10:06:25.6: Next periodic autofocus ASAP after 27-Jan-2017 12:06:02 UTC 27-Jan-2017 10:06:25.6: SelectRunning: No running plans 27-Jan-2017 10:06:25.7: Obs BX_Pup in Plan BX_Pup was vetoed by AirMass constraint. 27-Jan-2017 10:06:25.7: AirMass estimates ready after 28-Jan-2017 04:04:09 UTC 27-Jan-2017 10:06:25.7: Obs CB_32 in Plan CB_32 was vetoed by AirMass constraint. 27-Jan-2017 10:06:25.7: AirMass estimates ready after 28-Jan-2017 00:39:03 UTC 27-Jan-2017 10:06:26.0: Obs GSC_3649-0825 in Plan GSC_3649-0825 was vetoed by AirMass constraint. 27-Jan-2017 10:06:26.0: AirMass estimates ready after 27-Jan-2017 16:39:11 UTC 27-Jan-2017 10:06:26.0: Obs GSC_4030_204 in Plan GSC_4030_204 was vetoed by AirMass constraint. 27-Jan-2017 10:06:26.0: AirMass estimates ready after 27-Jan-2017 18:03:06 UTC 27-Jan-2017 10:06:26.0: Obs MP_Gem in Plan MP_Gem was vetoed by AirMass constraint. 27-Jan-2017 10:06:26.0: AirMass estimates ready after 28-Jan-2017 01:00:00 UTC 27-Jan-2017 10:06:26.0: Obs NGC_7790 in Plan NGC_7790 was vetoed by AirmassRange constraint. 27-Jan-2017 10:06:26.0: AirmassRange estimates ready after 27-Jan-2017 16:53:47 UTC 27-Jan-2017 10:06:26.0: Obs Swift_J0614.0+1709 in Plan Swift_J0614.0+1709 was vetoed by AirMass constraint. 27-Jan-2017 10:06:26.0: AirMass estimates ready after 28-Jan-2017 00:00:45 UTC 27-Jan-2017 10:06:26.1: Obs T_Ori in Plan T_Ori was vetoed by AirmassRange constraint. 27-Jan-2017 10:06:26.1: AirmassRange estimates ready after 28-Jan-2017 00:55:53 UTC 27-Jan-2017 10:06:26.1: Obs V0809_Cep in Plan V0809_Cep was vetoed by AirmassRange constraint. 27-Jan-2017 10:06:26.1: AirmassRange estimates ready after 27-Jan-2017 16:04:26 UTC 27-Jan-2017 10:06:26.1: Obs V0959_Mon in Plan V0959_Mon was vetoed by AirmassRange constraint. 27-Jan-2017 10:06:26.1: AirmassRange estimates ready after 28-Jan-2017 01:23:53 UTC 27-Jan-2017 10:06:26.1: Obs V0965_Per in Plan V0965_Per was vetoed by AirmassRange constraint. 27-Jan-2017 10:06:26.1: AirmassRange estimates ready after 27-Jan-2017 20:49:33 UTC 27-Jan-2017 10:06:26.1: Obs V647_Aur in Plan V647_Aur was vetoed by AirMass constraint. 27-Jan-2017 10:06:26.1: AirMass estimates ready after 28-Jan-2017 01:05:48 UTC 27-Jan-2017 10:06:26.1: Efficiency: Selecting from 7 pending plans: 27-Jan-2017 10:06:26.1: Selected Plan SDSS073224 (TotEff=1.23) 27-Jan-2017 10:06:26.2: Dispatcher cycle time: 0.5400007 sec. 27-Jan-2017 10:06:26.2: Acquire data for Observation SDSS073224... 27-Jan-2017 10:06:26.2: (belongs to Project AGN, Plan SDSS073224) 27-Jan-2017 10:06:26.2: Send Observation SDSS073224 to ACP Sequencer 27-Jan-2017 10:06:36.2: Sequencer is now active 27-Jan-2017 10:21:57.7: Sequencer is no longer active 27-Jan-2017 10:21:57.7: Post-job status check done (stat=Failed) 27-Jan-2017 10:21:57.7: ACP data acquisition failed for Observation SDSS073224. 27-Jan-2017 10:21:57.7: (Observation SDSS073224 interrupted by acquisition process failure.) 27-Jan-2017 10:21:58.7: SelectRunning: No running plans 27-Jan-2017 10:21:58.8: Efficiency: Selecting from 6 pending plans: 27-Jan-2017 10:21:58.8: Selected Plan M67 (TotEff=1.23) 27-Jan-2017 10:21:58.8: Dispatcher cycle time: 0.1200002 sec. 27-Jan-2017 10:21:58.8: Acquire data for Observation M67... 27-Jan-2017 10:21:58.8: (belongs to Project AAVSO_Monitoring, Plan M67) 27-Jan-2017 10:21:58.9: Send Observation M67 to ACP Sequencer 27-Jan-2017 10:22:08.9: Sequencer is now active 27-Jan-2017 10:53:27.1: Sequencer is no longer active 27-Jan-2017 10:53:27.1: Post-job status check done (stat=Completed) 27-Jan-2017 10:53:27.1: Acquisition time: 1888.2856438 sec. 27-Jan-2017 10:53:27.1: Data for Observation M67 acquired successfully. 27-Jan-2017 10:53:27.2: Image Efficiency: 74.7% 27-Jan-2017 10:53:27.2: Cycle Efficiency: 100.0% 27-Jan-2017 10:53:28.1: SelectRunning: No running plans 27-Jan-2017 10:53:28.1: Efficiency: Selecting from 5 pending plans: 27-Jan-2017 10:53:28.2: Selected Plan AT_Cnc (TotEff=1.18) 27-Jan-2017 10:53:28.2: Dispatcher cycle time: 0.1100002 sec. 27-Jan-2017 10:53:28.2: Acquire data for Observation AT_Cnc... 27-Jan-2017 10:53:28.2: (belongs to Project SXN, Plan AT_Cnc) 27-Jan-2017 10:53:28.2: Send Observation AT_Cnc to ACP Sequencer 27-Jan-2017 10:53:38.2: Sequencer is now active 27-Jan-2017 11:03:12.5: Sequencer is no longer active 27-Jan-2017 11:03:12.5: Post-job status check done (stat=Completed) 27-Jan-2017 11:03:12.5: Acquisition time: 584.241818 sec. 27-Jan-2017 11:03:12.5: Data for Observation AT_Cnc acquired successfully. 27-Jan-2017 11:03:12.5: Image Efficiency: 61.6% 27-Jan-2017 11:03:12.5: Cycle Efficiency: 100.0% 27-Jan-2017 11:03:13.3: SelectRunning: No running plans 27-Jan-2017 11:03:13.3: Efficiency: Selecting from 4 pending plans: 27-Jan-2017 11:03:13.3: Selected Plan SY_Cnc (TotEff=1.17) 27-Jan-2017 11:03:13.4: Dispatcher cycle time: 0.0900001 sec. 27-Jan-2017 11:03:13.4: Acquire data for Observation SY_Cnc... 27-Jan-2017 11:03:13.4: (belongs to Project SXN, Plan SY_Cnc) 27-Jan-2017 11:03:13.4: Send Observation SY_Cnc to ACP Sequencer 27-Jan-2017 11:03:23.4: Sequencer is now active 27-Jan-2017 11:35:43.0: ** Script interrupted by weather safety event. 27-Jan-2017 11:35:46.0: Post-job status check done (stat=Running) 27-Jan-2017 11:35:46.0: ACP ABORT: Acquisition process was interrupted by weather unsafe. 27-Jan-2017 11:35:46.0: ACP data acquisition failed for Observation SY_Cnc. 27-Jan-2017 11:35:46.0: (Plan SY_Cnc will be resubmitted in its entirety. 27-Jan-2017 11:35:46.0: (Observation SY_Cnc interrupted by weather unsafe event.) 27-Jan-2017 11:35:46.5: -- Weather Unsafe -- 27-Jan-2017 12:13:45.3: ++ Weather Safe ++ 27-Jan-2017 12:13:47.3: ++ Observatory Startup ++ 27-Jan-2017 12:13:47.4: Start ACP Sequencer's StartupObs script 27-Jan-2017 12:13:48.0: WARNING: Request sent while Weather Safety Script is running 27-Jan-2017 12:14:48.0: WARNING: Request sent while Weather Safety Script is running 27-Jan-2017 12:15:48.2: WARNING: Request sent while Weather Safety Script is running 27-Jan-2017 12:16:48.4: WARNING: Request sent while Weather Safety Script is running 27-Jan-2017 12:17:48.4: WARNING: Request sent while Weather Safety Script is running 27-Jan-2017 12:18:48.7: WARNING: Request sent while Weather Safety Script is running 27-Jan-2017 12:19:49.1: WARNING: Request sent while Weather Safety Script is running 27-Jan-2017 12:20:49.3: WARNING: Request sent while Weather Safety Script is running 27-Jan-2017 12:21:49.5: WARNING: Request sent while Weather Safety Script is running 27-Jan-2017 12:22:49.7: WARNING: Request sent while Weather Safety Script is running 27-Jan-2017 12:23:49.8: WARNING: Request sent while Weather Safety Script is running 27-Jan-2017 12:24:50.0: WARNING: Request sent while Weather Safety Script is running 27-Jan-2017 12:25:50.4: WARNING: Request sent while Weather Safety Script is running 27-Jan-2017 12:26:50.6: WARNING: Request sent while Weather Safety Script is running 27-Jan-2017 12:27:50.8: WARNING: Request sent while Weather Safety Script is running 27-Jan-2017 12:28:51.2: -- Weather Unsafe -- 27-Jan-2017 13:08:39.7: -- Observatory Shutdown -- 27-Jan-2017 13:08:40.2: Weather disconnected. 27-Jan-2017 13:08:40.3: Start ACP Sequencer's ShutdownObs script 27-Jan-2017 13:08:40.6: WARNING: Request sent while Weather Safety Script is running 27-Jan-2017 13:09:40.8: WARNING: Request sent while Weather Safety Script is running 27-Jan-2017 13:10:41.1: WARNING: Request sent while Weather Safety Script is running 27-Jan-2017 13:11:41.5: WARNING: Request sent while Weather Safety Script is running 27-Jan-2017 13:12:41.8: WARNING: Request sent while Weather Safety Script is running 27-Jan-2017 13:13:42.3: WARNING: Request sent while Weather Safety Script is running 27-Jan-2017 13:14:42.7: WARNING: Request sent while Weather Safety Script is running 27-Jan-2017 13:15:43.2: WARNING: Request sent while Weather Safety Script is running 27-Jan-2017 13:16:43.7: WARNING: Request sent while Weather Safety Script is running 27-Jan-2017 13:17:44.0: WARNING: Request sent while Weather Safety Script is running 27-Jan-2017 13:18:44.4: WARNING: Request sent while Weather Safety Script is running 27-Jan-2017 13:19:44.8: WARNING: Request sent while Weather Safety Script is running 27-Jan-2017 13:20:45.2: WARNING: Request sent while Weather Safety Script is running 27-Jan-2017 13:21:45.5: WARNING: Request sent while Weather Safety Script is running 27-Jan-2017 13:22:45.8: WARNING: Request sent while Weather Safety Script is running 27-Jan-2017 13:23:45.8: **EXCEPTION IN SCHEDULER: 27-Jan-2017 13:23:45.8: Observatory shutdown failed, cannot continue. 27-Jan-2017 13:23:45.8: Traceback: at DC3.Scheduler.Engine.DoShutdownIf() at DC3.Scheduler.Engine.Run() 27-Jan-2017 13:23:45.9: Run statistics: 27-Jan-2017 13:23:45.9: Observations: 34 27-Jan-2017 13:23:45.9: Considered: 20 27-Jan-2017 13:23:45.9: Completed: 3 27-Jan-2017 13:23:45.9: Skipped: 14 27-Jan-2017 13:23:45.9: Never Eligible: 14 27-Jan-2017 13:23:45.9: Failed: 3 27-Jan-2017 13:23:45.9: Shutter-Open efficiency: 71.83% 27-Jan-2017 13:23:45.9: Overall Efficiency: 99.90% 27-Jan-2017 13:23:45.9: Release ACP sequencer Log closed at Fri, Jan 27 2017 13:23:48 UTC (actual time)