Log opened at Wed, Nov 16 2016 01:50:27 UTC (actual time) Current log level is Debug Scheduler version 3.5.3 Multi-user license: Arne Henden 16-Nov-2016 01:50:28.7: ++ Observatory Startup ++ 16-Nov-2016 01:50:28.7: Start ACP Sequencer's StartupObs script 16-Nov-2016 01:50:38.7: Sequencer is now active 16-Nov-2016 01:51:04.3: Sequencer is no longer active 16-Nov-2016 01:51:05.6: Open the observatory dome/roof 16-Nov-2016 01:51:31.2: Dispatcher stopped at 16-Nov-2016 01:51:31 UTC 16-Nov-2016 01:51:31.2: **DISPATCHER INTERRUPT LEVEL 1 RECEIVED** 16-Nov-2016 01:53:46.9: ++ Auto Focus ++ 16-Nov-2016 01:53:46.9: Doing initial autofocus. 16-Nov-2016 01:53:46.9: Start special ACP AutoFocus script for scheduler 16-Nov-2016 01:53:56.9: Sequencer is now active 16-Nov-2016 01:56:47.0: Sequencer is no longer active 16-Nov-2016 01:56:47.0: Next periodic autofocus ASAP after 16-Nov-2016 03:56:33 UTC 16-Nov-2016 02:38:48.2: Dispatcher started at 16-Nov-2016 02:38:48 UTC 16-Nov-2016 02:38:48.2: Priority: W[0]=0.00 16-Nov-2016 02:38:48.2: Transit Altitude: W[1]=0.00 16-Nov-2016 02:38:48.2: Highest Altitude: W[6]=0.00 16-Nov-2016 02:38:48.2: Lateness: W[5]=0.70 16-Nov-2016 02:38:48.2: Slew Distance: W[2]=0.30 16-Nov-2016 02:38:48.2: Retry Count: W[3]=0.20 16-Nov-2016 02:38:48.2: Meridian Crossing: W[4]=0.30 16-Nov-2016 02:38:48.2: Obs Conditions: W[7]=0.40 16-Nov-2016 02:38:48.2: Rising Plan Delay: disabled 16-Nov-2016 02:38:51.3: ++ Observatory Startup ++ 16-Nov-2016 02:38:51.3: Start ACP Sequencer's StartupObs script 16-Nov-2016 02:39:01.3: Sequencer is now active 16-Nov-2016 02:39:06.6: Sequencer is no longer active 16-Nov-2016 02:39:08.3: ++ Auto Focus ++ 16-Nov-2016 02:39:08.3: Doing initial autofocus. 16-Nov-2016 02:39:08.3: Start special ACP AutoFocus script for scheduler 16-Nov-2016 02:39:18.3: Sequencer is now active 16-Nov-2016 02:41:04.1: Sequencer is no longer active 16-Nov-2016 02:41:04.1: Next periodic autofocus ASAP after 16-Nov-2016 04:40:53 UTC 16-Nov-2016 02:41:04.1: Plan MP_Gem has Monitor Mode. Time to resubmit it. 16-Nov-2016 02:41:04.1: Plan GSC_3649-0825 has Monitor Mode. Time to resubmit it. 16-Nov-2016 02:41:04.1: Plan 2MASS_23275353+5214532 has Monitor Mode. Time to resubmit it. 16-Nov-2016 02:41:04.1: Plan IC_348 has Monitor Mode. Time to resubmit it. 16-Nov-2016 02:41:04.1: Plan KH_15D has Monitor Mode. Time to resubmit it. 16-Nov-2016 02:41:04.1: Plan V1184_Tau has Monitor Mode. Time to resubmit it. 16-Nov-2016 02:41:04.1: Plan CB_32 has Monitor Mode. Time to resubmit it. 16-Nov-2016 02:41:04.1: Plan NGC_2438 has Monitor Mode. Time to resubmit it. 16-Nov-2016 02:41:04.1: Plan V1404_Cyg has Monitor Mode. Time to resubmit it. 16-Nov-2016 02:41:04.1: Plan SDSS073224 has Monitor Mode. Time to resubmit it. 16-Nov-2016 02:41:04.1: Plan M67 has Monitor Mode. Time to resubmit it. 16-Nov-2016 02:41:04.1: Plan M56 has Monitor Mode. Time to resubmit it. 16-Nov-2016 02:41:04.1: Plan NGC_7790 has Monitor Mode. Time to resubmit it. 16-Nov-2016 02:41:04.1: Plan T_Ori has Monitor Mode. Time to resubmit it. 16-Nov-2016 02:41:04.1: Plan U_Aqr has Monitor Mode. Time to resubmit it. 16-Nov-2016 02:41:04.1: Plan V339_Del_TMO61 has Monitor Mode. Time to resubmit it. 16-Nov-2016 02:41:04.1: SelectRunning: No running plans 16-Nov-2016 02:41:04.5: Obs CB_32 in Plan CB_32 was vetoed by AirMass constraint. 16-Nov-2016 02:41:04.5: AirMass estimates ready after 16-Nov-2016 05:26:06 UTC 16-Nov-2016 02:41:04.5: Obs KH_15D in Plan KH_15D was vetoed by AirMass constraint. 16-Nov-2016 02:41:04.5: AirMass estimates ready after 16-Nov-2016 06:03:07 UTC 16-Nov-2016 02:41:04.5: Obs M67 in Plan M67 was vetoed by AirMass constraint. 16-Nov-2016 02:41:04.5: AirMass estimates ready after 16-Nov-2016 08:35:56 UTC 16-Nov-2016 02:41:04.5: Obs MP_Gem in Plan MP_Gem was vetoed by AirMass constraint. 16-Nov-2016 02:41:04.5: AirMass estimates ready after 16-Nov-2016 05:47:03 UTC 16-Nov-2016 02:41:04.5: Obs NGC_2438 in Plan NGC_2438 was vetoed by AirMass constraint. 16-Nov-2016 02:41:04.5: AirMass estimates ready after 16-Nov-2016 08:28:59 UTC 16-Nov-2016 02:41:04.5: Obs SDSS073224 in Plan SDSS073224 was vetoed by AirMass constraint. 16-Nov-2016 02:41:04.5: AirMass estimates ready after 16-Nov-2016 06:15:55 UTC 16-Nov-2016 02:41:04.5: Obs T_Ori in Plan T_Ori was vetoed by AirmassRange constraint. 16-Nov-2016 02:41:04.5: AirmassRange estimates ready after 16-Nov-2016 05:42:56 UTC 16-Nov-2016 02:41:04.6: Obs V1184_Tau in Plan V1184_Tau was vetoed by AirMass constraint. 16-Nov-2016 02:41:04.6: AirMass estimates ready after 16-Nov-2016 04:42:48 UTC 16-Nov-2016 02:41:04.6: Obs V841_Oph in Plan V841_Oph was vetoed by AirmassRange constraint. 16-Nov-2016 02:41:04.6: AirmassRange estimates ready after 16-Nov-2016 17:35:48 UTC 16-Nov-2016 02:41:04.6: Obs SDSSJ121027+502735 in Plan WD was vetoed by AirMass constraint. 16-Nov-2016 02:41:04.6: AirMass estimates ready after 16-Nov-2016 10:10:45 UTC 16-Nov-2016 02:41:04.6: Efficiency: Selecting from 8 pending plans: 16-Nov-2016 02:41:04.6: Selected Plan M56 (TotEff=1.13) 16-Nov-2016 02:41:04.7: Dispatcher cycle time: 0.5700008 sec. 16-Nov-2016 02:41:04.7: Acquire data for Observation M56... 16-Nov-2016 02:41:04.7: (belongs to Project AAVSO_Monitoring, Plan M56) 16-Nov-2016 02:41:04.7: Doing 3 sets 16-Nov-2016 02:41:04.7: Send Observation M56 to ACP Sequencer 16-Nov-2016 02:41:14.7: Sequencer is now active 16-Nov-2016 02:47:41.2: Sequencer is no longer active 16-Nov-2016 02:47:41.2: Post-job status check done (stat=Completed) 16-Nov-2016 02:47:41.2: Send Observation M56 to ACP Sequencer 16-Nov-2016 02:47:51.2: Sequencer is now active 16-Nov-2016 02:53:07.4: Sequencer is no longer active 16-Nov-2016 02:53:07.4: Post-job status check done (stat=Completed) 16-Nov-2016 02:53:07.5: Send Observation M56 to ACP Sequencer 16-Nov-2016 02:53:17.5: Sequencer is now active 16-Nov-2016 02:58:37.7: Sequencer is no longer active 16-Nov-2016 02:58:37.7: Post-job status check done (stat=Completed) 16-Nov-2016 02:58:37.7: Acquisition time: 1052.9634741 sec. 16-Nov-2016 02:58:37.7: Data for Observation M56 acquired successfully. 16-Nov-2016 02:58:37.7: Image Efficiency: 68.3% 16-Nov-2016 02:58:37.7: Cycle Efficiency: 99.9% 16-Nov-2016 02:58:38.3: SelectRunning: No running plans 16-Nov-2016 02:58:38.3: Efficiency: Selecting from 7 pending plans: 16-Nov-2016 02:58:38.3: Selected Plan U_Aqr (TotEff=1.42) 16-Nov-2016 02:58:38.4: Dispatcher cycle time: 0.2100002 sec. 16-Nov-2016 02:58:38.4: Acquire data for Observation U_Aqr... 16-Nov-2016 02:58:38.4: (belongs to Project AAVSO_Monitoring, Plan U_Aqr) 16-Nov-2016 02:58:38.4: Send Observation U_Aqr to ACP Sequencer 16-Nov-2016 02:58:48.4: Sequencer is now active 16-Nov-2016 03:00:16.2: Dispatcher stopped at 16-Nov-2016 03:00:16 UTC 16-Nov-2016 03:00:16.2: **DISPATCHER INTERRUPT LEVEL 1 RECEIVED** 16-Nov-2016 03:00:37.5: Sequencer is no longer active 16-Nov-2016 03:00:37.5: ** Sequencer script "AcquireScheduler" failed: 16-Nov-2016 03:00:37.5: **Script Error** Source: ACP Message: The script was aborted. at line 1526 column 13. Script Context: 1523: If Not SUP.ExternalGuider Then ' Update pointing if internal guider 1524: Console.PrintLine " (internal guider - attempt pointing update before guider restart)" 1525: isPointing = True ' For ACP web UI (typ) => 1526: SUP.UpdatePointing OBS.Name, OBS.RA, OBS.Dec, OBS.PA ^ 1527: isPointing = False 1528: End If 1529: End If -- end -- 16-Nov-2016 03:00:40.5: Post-job status check done (stat=Running) 16-Nov-2016 03:00:40.9: RUN FAILED: Scheduler was stopped during run 16-Nov-2016 03:00:40.9: ACP data acquisition failed for Observation U_Aqr. 16-Nov-2016 03:00:40.9: (Observation U_Aqr interrupted by operator or system.) 16-Nov-2016 03:00:41.5: Dispatcher started at 16-Nov-2016 03:00:41 UTC 16-Nov-2016 03:00:41.5: Priority: W[0]=0.00 16-Nov-2016 03:00:41.5: Transit Altitude: W[1]=0.00 16-Nov-2016 03:00:41.5: Highest Altitude: W[6]=0.00 16-Nov-2016 03:00:41.5: Lateness: W[5]=0.70 16-Nov-2016 03:00:41.5: Slew Distance: W[2]=0.30 16-Nov-2016 03:00:41.5: Retry Count: W[3]=0.20 16-Nov-2016 03:00:41.5: Meridian Crossing: W[4]=0.30 16-Nov-2016 03:00:41.5: Obs Conditions: W[7]=0.40 16-Nov-2016 03:00:41.5: Rising Plan Delay: disabled 16-Nov-2016 03:00:44.0: ++ Observatory Startup ++ 16-Nov-2016 03:00:44.0: Start ACP Sequencer's StartupObs script 16-Nov-2016 03:00:54.3: Sequencer is now active 16-Nov-2016 03:00:59.4: Sequencer is no longer active 16-Nov-2016 03:01:02.9: ++ Auto Focus ++ 16-Nov-2016 03:01:02.9: Doing initial autofocus. 16-Nov-2016 03:01:02.9: Start special ACP AutoFocus script for scheduler 16-Nov-2016 03:01:12.9: Sequencer is now active 16-Nov-2016 03:03:51.2: Sequencer is no longer active 16-Nov-2016 03:03:51.2: Next periodic autofocus ASAP after 16-Nov-2016 05:03:35 UTC 16-Nov-2016 03:03:51.4: SelectRunning: No running plans 16-Nov-2016 03:03:51.4: Efficiency: Selecting from 6 pending plans: 16-Nov-2016 03:03:51.4: Selected Plan V339_Del_TMO61 (TotEff=1.39) 16-Nov-2016 03:03:51.5: Dispatcher cycle time: 0.1100002 sec. 16-Nov-2016 03:03:51.5: Acquire data for Observation V339_Del_TMO61... 16-Nov-2016 03:03:51.5: (belongs to Project AAVSO_Monitoring, Plan V339_Del_TMO61) 16-Nov-2016 03:03:51.5: Doing 3 sets 16-Nov-2016 03:03:51.5: Send Observation V339_Del_TMO61 to ACP Sequencer 16-Nov-2016 03:04:01.5: Sequencer is now active 16-Nov-2016 03:08:58.2: Sequencer is no longer active 16-Nov-2016 03:08:58.2: Post-job status check done (stat=Completed) 16-Nov-2016 03:08:58.2: Send Observation V339_Del_TMO61 to ACP Sequencer 16-Nov-2016 03:09:08.6: Sequencer is now active 16-Nov-2016 03:13:13.6: Sequencer is no longer active 16-Nov-2016 03:13:13.6: Post-job status check done (stat=Completed) 16-Nov-2016 03:13:13.6: Send Observation V339_Del_TMO61 to ACP Sequencer 16-Nov-2016 03:13:23.6: Sequencer is now active 16-Nov-2016 03:17:29.6: Sequencer is no longer active 16-Nov-2016 03:17:29.6: Post-job status check done (stat=Completed) 16-Nov-2016 03:17:29.6: Acquisition time: 818.0631454 sec. 16-Nov-2016 03:17:29.6: Data for Observation V339_Del_TMO61 acquired successfully. 16-Nov-2016 03:17:29.6: Image Efficiency: 33.0% 16-Nov-2016 03:17:29.6: Cycle Efficiency: 100.0% 16-Nov-2016 03:17:32.7: SelectRunning: No running plans 16-Nov-2016 03:17:32.8: Efficiency: Selecting from 5 pending plans: 16-Nov-2016 03:17:32.8: Selected Plan V1404_Cyg (TotEff=1.19) 16-Nov-2016 03:17:32.8: Dispatcher cycle time: 0.1000002 sec. 16-Nov-2016 03:17:32.8: Acquire data for Observation V1404_Cyg_TMO61... 16-Nov-2016 03:17:32.8: (belongs to Project Walker, Plan V1404_Cyg) 16-Nov-2016 03:17:32.8: Send Observation V1404_Cyg_TMO61 to ACP Sequencer 16-Nov-2016 03:17:42.8: Sequencer is now active 16-Nov-2016 03:32:22.8: Sequencer is no longer active 16-Nov-2016 03:32:22.8: Post-job status check done (stat=Completed) 16-Nov-2016 03:32:22.8: Acquisition time: 889.9732461 sec. 16-Nov-2016 03:32:22.8: Data for Observation V1404_Cyg_TMO61 acquired successfully. 16-Nov-2016 03:32:22.8: Image Efficiency: 80.9% 16-Nov-2016 03:32:22.8: Cycle Efficiency: 100.0% 16-Nov-2016 03:32:26.2: SelectRunning: No running plans 16-Nov-2016 03:32:26.2: Efficiency: Selecting from 4 pending plans: 16-Nov-2016 03:32:26.2: Selected Plan GSC_3649-0825 (TotEff=1.08) 16-Nov-2016 03:32:26.3: Dispatcher cycle time: 0.0900001 sec. 16-Nov-2016 03:32:26.3: Acquire data for Observation GSC_3649-0825... 16-Nov-2016 03:32:26.3: (belongs to Project Staels, Plan GSC_3649-0825) 16-Nov-2016 03:32:26.3: Send Observation GSC_3649-0825 to ACP Sequencer 16-Nov-2016 03:32:36.3: Sequencer is now active 16-Nov-2016 03:36:50.9: Sequencer is no longer active 16-Nov-2016 03:36:50.9: Post-job status check done (stat=Completed) 16-Nov-2016 03:36:50.9: Acquisition time: 264.6103705 sec. 16-Nov-2016 03:36:50.9: Data for Observation GSC_3649-0825 acquired successfully. 16-Nov-2016 03:36:50.9: Image Efficiency: 51.0% 16-Nov-2016 03:36:50.9: Cycle Efficiency: 100.0% 16-Nov-2016 03:36:51.1: SelectRunning: No running plans 16-Nov-2016 03:36:51.1: Efficiency: Selecting from 3 pending plans: 16-Nov-2016 03:36:51.1: Selected Plan 2MASS_23275353+5214532 (TotEff=1.09) 16-Nov-2016 03:36:51.2: Dispatcher cycle time: 0.0800001 sec. 16-Nov-2016 03:36:51.2: Acquire data for Observation 2MASS_23275353+5214532... 16-Nov-2016 03:36:51.2: (belongs to Project Staels, Plan 2MASS_23275353+5214532) 16-Nov-2016 03:36:51.2: Send Observation 2MASS_23275353+5214532 to ACP Sequencer 16-Nov-2016 03:37:01.2: Sequencer is now active 16-Nov-2016 03:40:59.5: Sequencer is no longer active 16-Nov-2016 03:40:59.5: Post-job status check done (stat=Completed) 16-Nov-2016 03:40:59.5: Acquisition time: 248.3003476 sec. 16-Nov-2016 03:40:59.5: Data for Observation 2MASS_23275353+5214532 acquired successfully. 16-Nov-2016 03:40:59.5: Image Efficiency: 54.3% 16-Nov-2016 03:40:59.5: Cycle Efficiency: 100.0% 16-Nov-2016 03:41:00.0: SelectRunning: No running plans 16-Nov-2016 03:41:00.1: Efficiency: Selecting from 2 pending plans: 16-Nov-2016 03:41:00.1: Selected Plan NGC_7790 (TotEff=1.03) 16-Nov-2016 03:41:00.1: Dispatcher cycle time: 0.0900001 sec. 16-Nov-2016 03:41:00.1: Acquire data for Observation NGC_7790... 16-Nov-2016 03:41:00.1: (belongs to Project AAVSO_Monitoring, Plan NGC_7790) 16-Nov-2016 03:41:00.1: Doing 3 sets 16-Nov-2016 03:41:00.1: Send Observation NGC_7790 to ACP Sequencer 16-Nov-2016 03:41:10.1: Sequencer is now active 16-Nov-2016 03:48:18.7: Sequencer is no longer active 16-Nov-2016 03:48:18.7: Post-job status check done (stat=Completed) 16-Nov-2016 03:48:18.7: Send Observation NGC_7790 to ACP Sequencer 16-Nov-2016 03:48:28.7: Sequencer is now active 16-Nov-2016 03:55:09.8: Sequencer is no longer active 16-Nov-2016 03:55:09.8: Post-job status check done (stat=Completed) 16-Nov-2016 03:55:09.8: Send Observation NGC_7790 to ACP Sequencer 16-Nov-2016 03:55:19.8: Sequencer is now active 16-Nov-2016 04:02:04.0: Sequencer is no longer active 16-Nov-2016 04:02:04.0: Post-job status check done (stat=Completed) 16-Nov-2016 04:02:04.0: Acquisition time: 1263.8037695 sec. 16-Nov-2016 04:02:04.0: Data for Observation NGC_7790 acquired successfully. 16-Nov-2016 04:02:04.0: Image Efficiency: 57.0% 16-Nov-2016 04:02:04.0: Cycle Efficiency: 100.0% 16-Nov-2016 04:02:04.5: **EXCEPTION IN SCHEDULER: 16-Nov-2016 04:02:04.5: DOME FAILURE: Dome reports shutter error status 16-Nov-2016 04:02:04.5: Traceback: at DC3.Scheduler.ACPSequencer.get_DomeOpen() at DC3.Scheduler.Engine.DoStartupIf(Boolean openDome) at DC3.Scheduler.Engine.Run() 16-Nov-2016 04:02:04.6: Run statistics: 16-Nov-2016 04:02:04.6: Observations: 22 16-Nov-2016 04:02:04.6: Considered: 12 16-Nov-2016 04:02:04.6: Completed: 7 16-Nov-2016 04:02:04.6: Skipped: 1 16-Nov-2016 04:02:04.6: Never Eligible: 10 16-Nov-2016 04:02:04.6: Failed: 4 16-Nov-2016 04:02:04.6: Shutter-Open efficiency: 23.19% 16-Nov-2016 04:02:04.6: Overall Efficiency: 53.11% 16-Nov-2016 04:02:04.6: Release ACP sequencer Log closed at Wed, Nov 16 2016 04:02:06 UTC (actual time)