Log opened at Wed, Jan 31 2018 02:35:43 UTC (actual time)
Current log level is Debug
Scheduler version 3.5.3
Multi-user license: Arne Henden
31-Jan-2018 02:35:43.3: Simulated clock initialized at 31-Jan-2018 02:35:43 UTC
31-Jan-2018 02:35:43.3: Loaded Constraint plugin AirMass
31-Jan-2018 02:35:43.3: Loaded Constraint plugin AirmassRange
31-Jan-2018 02:35:43.3: Loaded Constraint plugin Horizon
31-Jan-2018 02:35:43.3: Loaded Constraint plugin HourAngle
31-Jan-2018 02:35:43.3: Loaded Constraint plugin MoonAvoid
31-Jan-2018 02:35:43.3: Loaded Constraint plugin MoonDown
31-Jan-2018 02:35:43.3: Loaded Constraint plugin SkyCondition
31-Jan-2018 02:35:43.3: Loaded Constraint plugin TimeRange
31-Jan-2018 02:35:43.9: 28 plans are now pending.
31-Jan-2018 02:35:43.9: Attach ACP sequencer
31-Jan-2018 02:35:54.5: Dispatcher started at 31-Jan-2018 02:35:54 UTC
31-Jan-2018 02:35:54.5:     Priority:          W[0]=0.00
31-Jan-2018 02:35:54.5:     Transit Altitude:  W[1]=0.70
31-Jan-2018 02:35:54.5:     Highest Altitude:  W[6]=0.00
31-Jan-2018 02:35:54.5:     Lateness:          W[5]=0.00
31-Jan-2018 02:35:54.5:     Slew Distance:     W[2]=0.00
31-Jan-2018 02:35:54.5:     Retry Count:       W[3]=0.20
31-Jan-2018 02:35:54.5:     Meridian Crossing: W[4]=0.00
31-Jan-2018 02:35:54.5:     Obs Conditions:    W[7]=0.40
31-Jan-2018 02:35:54.5:     Rising Plan Delay: disabled
31-Jan-2018 02:36:05.0: Weather successfully connected. Wait for valid data...
31-Jan-2018 02:36:06.2: ++ Observatory Startup ++
31-Jan-2018 02:36:06.2: Start ACP Sequencer's StartupObs script
31-Jan-2018 02:36:16.2: Sequencer is now active
31-Jan-2018 02:36:26.2: Sequencer is no longer active
31-Jan-2018 02:36:26.3: Open the observatory dome/roof
31-Jan-2018 02:37:32.6: ++ Auto Focus ++
31-Jan-2018 02:37:32.7: Doing initial autofocus.
31-Jan-2018 02:37:32.7: Start special ACP AutoFocus script for scheduler
31-Jan-2018 02:37:42.7: Sequencer is now active
31-Jan-2018 02:41:08.9: Sequencer is no longer active
31-Jan-2018 02:41:08.9: Next periodic autofocus ASAP after 31-Jan-2018 05:40:50 UTC
31-Jan-2018 02:41:09.4: Plan V0959_Mon has Monitor Mode. Time to resubmit it.
31-Jan-2018 02:41:09.4: Plan CB_32 has Monitor Mode. Time to resubmit it.
31-Jan-2018 02:41:09.4: Plan SDSS073224 has Monitor Mode. Time to resubmit it.
31-Jan-2018 02:41:09.4: Plan M67 has Monitor Mode. Time to resubmit it.
31-Jan-2018 02:41:09.4: Plan T_Ori has Monitor Mode. Time to resubmit it.
31-Jan-2018 02:41:09.4: Plan V841_Oph has Monitor Mode. Time to resubmit it.
31-Jan-2018 02:41:09.4: Plan Mkn_501 has Monitor Mode. Time to resubmit it.
31-Jan-2018 02:41:09.4: Plan RX_J0524+42 has Monitor Mode. Time to resubmit it.
31-Jan-2018 02:41:09.5: SelectRunning: No running plans
31-Jan-2018 02:41:09.5: Obs 2MASS_J19395313+0557550 in Plan 2MASS_J19395313+0557550 was vetoed by AirmassRange constraint.
31-Jan-2018 02:41:09.5:     AirmassRange estimates ready after 31-Jan-2018 14:11:03 UTC
31-Jan-2018 02:41:09.5: Obs BC_Lac in Plan BC_Lac was vetoed by AirmassRange constraint.
31-Jan-2018 02:41:09.5:     AirmassRange estimates ready after 31-Jan-2018 15:47:17 UTC
31-Jan-2018 02:41:09.5: Obs IRAS_19067+2158 in Plan IRAS_19067+2158 was vetoed by AirmassRange constraint.
31-Jan-2018 02:41:09.5:     AirmassRange estimates ready after 31-Jan-2018 13:02:27 UTC
31-Jan-2018 02:41:09.5: Obs IRAS_19183+2339 in Plan IRAS_19183+2339 was vetoed by AirmassRange constraint.
31-Jan-2018 02:41:09.5:     AirmassRange estimates ready after 31-Jan-2018 13:10:31 UTC
31-Jan-2018 02:41:09.5: Obs M56 in Plan M56 was vetoed by AirmassRange constraint.
31-Jan-2018 02:41:09.5:     AirmassRange estimates ready after 31-Jan-2018 12:54:08 UTC
31-Jan-2018 02:41:09.5: Obs M67 in Plan M67 was vetoed by AirMass constraint.
31-Jan-2018 02:41:09.5:     AirMass estimates ready after 31-Jan-2018 03:38:06 UTC
31-Jan-2018 02:41:09.5: Obs Mkn_501 in Plan Mkn_501 was vetoed by AirMass constraint.
31-Jan-2018 02:41:09.5:     AirMass estimates ready after 31-Jan-2018 10:14:22 UTC
31-Jan-2018 02:41:09.6: Obs NSVS_11201899 in Plan NSVS_11201899 was vetoed by AirmassRange constraint.
31-Jan-2018 02:41:09.6:     AirmassRange estimates ready after 31-Jan-2018 13:03:28 UTC
31-Jan-2018 02:41:09.6: Obs SDSSJ121027+502735 in Plan SDSSJ121027+502735 was vetoed by AirMass constraint.
31-Jan-2018 02:41:09.6:     AirMass estimates ready after 31-Jan-2018 05:12:55 UTC
31-Jan-2018 02:41:09.6: Obs U_Aqr in Plan U_Aqr was vetoed by AirmassRange constraint.
31-Jan-2018 02:41:09.6:     AirmassRange estimates ready after 31-Jan-2018 18:00:49 UTC
31-Jan-2018 02:41:10.3: Obs V1721_Aql in Plan V1721_Aql was vetoed by AirmassRange constraint.
31-Jan-2018 02:41:10.3:     AirmassRange estimates ready after 31-Jan-2018 13:34:40 UTC
31-Jan-2018 02:41:10.3: Obs V1722_Aql in Plan V1722_Aql was vetoed by AirmassRange constraint.
31-Jan-2018 02:41:10.3:     AirmassRange estimates ready after 31-Jan-2018 13:22:21 UTC
31-Jan-2018 02:41:10.3: Obs V1723_Aql in Plan V1723_Aql was vetoed by AirmassRange constraint.
31-Jan-2018 02:41:10.3:     AirmassRange estimates ready after 31-Jan-2018 13:48:43 UTC
31-Jan-2018 02:41:10.3: Obs V1724_Aql in Plan V1724_Aql was vetoed by AirmassRange constraint.
31-Jan-2018 02:41:10.3:     AirmassRange estimates ready after 31-Jan-2018 13:42:10 UTC
31-Jan-2018 02:41:10.3: Obs V1904_Cyg in Plan V1904_Cyg was vetoed by AirmassRange constraint.
31-Jan-2018 02:41:10.3:     AirmassRange estimates ready after 31-Jan-2018 14:54:51 UTC
31-Jan-2018 02:41:10.3: Obs V1907_Cyg in Plan V1907_Cyg was vetoed by AirmassRange constraint.
31-Jan-2018 02:41:10.3:     AirmassRange estimates ready after 31-Jan-2018 14:51:32 UTC
31-Jan-2018 02:41:10.3: Obs V2481_Cyg in Plan V2481_Cyg was vetoed by AirmassRange constraint.
31-Jan-2018 02:41:10.3:     AirmassRange estimates ready after 31-Jan-2018 14:27:29 UTC
31-Jan-2018 02:41:10.4: Obs V339_Del_TMO61 in Plan V339_Del_TMO61 was vetoed by AirmassRange constraint.
31-Jan-2018 02:41:10.4:     AirmassRange estimates ready after 31-Jan-2018 14:19:32 UTC
31-Jan-2018 02:41:10.4: Obs V841_Oph in Plan V841_Oph was vetoed by AirmassRange constraint.
31-Jan-2018 02:41:10.4:     AirmassRange estimates ready after 31-Jan-2018 12:37:58 UTC
31-Jan-2018 02:41:10.4: Efficiency: Selecting from 17 pending plans:
31-Jan-2018 02:41:10.5:   Selected Plan V0603_Per (TotEff=0.89)
31-Jan-2018 02:41:10.6: Dispatcher cycle time: 1.5630894 sec.
31-Jan-2018 02:41:10.6: Acquire data for Observation V0603_Per...
31-Jan-2018 02:41:10.6:   (belongs to Project AAVSO_HGUA_Miras_Manual_ver167, Plan V0603_Per)
31-Jan-2018 02:41:10.6: Send Observation V0603_Per to ACP Sequencer
31-Jan-2018 02:41:20.6: Sequencer is now active
31-Jan-2018 02:54:54.3: Sequencer is no longer active
31-Jan-2018 02:54:54.3: Post-job status check done (stat=Completed)
31-Jan-2018 02:54:54.3: Acquisition time: 823.7461155 sec.
31-Jan-2018 02:54:54.3: Data for Observation V0603_Per acquired successfully.
31-Jan-2018 02:54:54.3: Image Efficiency: 47.3%
31-Jan-2018 02:54:54.3: Cycle Efficiency: 99.8%
31-Jan-2018 02:54:54.6: SelectRunning: No running plans
31-Jan-2018 02:54:54.7: STRICT VETO:
31-Jan-2018 02:54:54.7: Obs V0809_Cep in Plan V0809_Cep was vetoed by AirmassRange constraint.
31-Jan-2018 02:54:54.7:     AirmassRange estimates ready after 31-Jan-2018 15:49:44 UTC
31-Jan-2018 02:54:54.7: Efficiency: Selecting from 15 pending plans:
31-Jan-2018 02:54:54.8:   Selected Plan FY_Cam (TotEff=0.89)
31-Jan-2018 02:54:54.9: Dispatcher cycle time: 0.3740214 sec.
31-Jan-2018 02:54:54.9: Acquire data for Observation FY_Cam...
31-Jan-2018 02:54:54.9:   (belongs to Project AAVSO_HGUA_Miras_Manual_ver167, Plan FY_Cam)
31-Jan-2018 02:54:54.9: Send Observation FY_Cam to ACP Sequencer
31-Jan-2018 02:55:04.9: Sequencer is now active
31-Jan-2018 03:08:19.5: Sequencer is no longer active
31-Jan-2018 03:08:19.5: Post-job status check done (stat=Completed)
31-Jan-2018 03:08:19.5: Acquisition time: 804.5410171 sec.
31-Jan-2018 03:08:19.5: Data for Observation FY_Cam acquired successfully.
31-Jan-2018 03:08:19.5: Image Efficiency: 48.5%
31-Jan-2018 03:08:19.5: Cycle Efficiency: 99.9%
31-Jan-2018 03:08:20.1: SelectRunning: No running plans
31-Jan-2018 03:08:20.2: Efficiency: Selecting from 14 pending plans:
31-Jan-2018 03:08:20.6:   Selected Plan T_Ori (TotEff=0.87)
31-Jan-2018 03:08:20.8: Dispatcher cycle time: 0.6700384 sec.
31-Jan-2018 03:08:20.8: Acquire data for Observation T_Ori...
31-Jan-2018 03:08:20.8:   (belongs to Project AAVSO_Monitoring, Plan T_Ori)
31-Jan-2018 03:08:20.8: Doing 3 sets
31-Jan-2018 03:08:20.8: Send Observation T_Ori to ACP Sequencer
31-Jan-2018 03:08:30.8: Sequencer is now active
31-Jan-2018 03:12:58.1: Sequencer is no longer active
31-Jan-2018 03:12:58.1: Post-job status check done (stat=Completed)
31-Jan-2018 03:12:58.1: Send Observation T_Ori to ACP Sequencer
31-Jan-2018 03:13:08.1: Sequencer is now active
31-Jan-2018 03:15:56.1: Sequencer is no longer active
31-Jan-2018 03:15:56.1: Post-job status check done (stat=Completed)
31-Jan-2018 03:15:56.1: Send Observation T_Ori to ACP Sequencer
31-Jan-2018 03:16:06.1: Sequencer is now active
31-Jan-2018 03:18:52.9: Sequencer is no longer active
31-Jan-2018 03:18:52.9: Post-job status check done (stat=Completed)
31-Jan-2018 03:18:52.9: Acquisition time: 632.1311557 sec.
31-Jan-2018 03:18:52.9: Data for Observation T_Ori acquired successfully.
31-Jan-2018 03:18:52.9: Image Efficiency: 42.7%
31-Jan-2018 03:18:52.9: Cycle Efficiency: 99.9%
31-Jan-2018 03:18:53.1: SelectRunning: No running plans
31-Jan-2018 03:18:53.1: STRICT VETO:
31-Jan-2018 03:18:53.1: Obs GSC_3649-0825 in Plan GSC_3649-0825 was vetoed by AirMass constraint.
31-Jan-2018 03:18:53.1:     AirMass estimates ready after 31-Jan-2018 16:24:29 UTC
31-Jan-2018 03:18:53.2: Efficiency: Selecting from 12 pending plans:
31-Jan-2018 03:18:53.2:   Selected Plan CB_32 (TotEff=0.88)
31-Jan-2018 03:18:53.3: Dispatcher cycle time: 0.2770158 sec.
31-Jan-2018 03:18:53.3: Acquire data for Observation CB_32...
31-Jan-2018 03:18:53.3:   (belongs to Project Staels, Plan CB_32)
31-Jan-2018 03:18:53.4: Send Observation CB_32 to ACP Sequencer
31-Jan-2018 03:19:03.4: Sequencer is now active
31-Jan-2018 03:22:22.5: Sequencer is no longer active
31-Jan-2018 03:22:22.5: Post-job status check done (stat=Completed)
31-Jan-2018 03:22:22.5: Acquisition time: 209.172964 sec.
31-Jan-2018 03:22:22.5: Data for Observation CB_32 acquired successfully.
31-Jan-2018 03:22:22.6: Image Efficiency: 43.0%
31-Jan-2018 03:22:22.6: Cycle Efficiency: 99.9%
31-Jan-2018 03:22:22.7: SelectRunning: No running plans
31-Jan-2018 03:22:22.8: Efficiency: Selecting from 11 pending plans:
31-Jan-2018 03:22:22.9:   Selected Plan WZ_Per (TotEff=0.83)
31-Jan-2018 03:22:23.0: Dispatcher cycle time: 0.2530144 sec.
31-Jan-2018 03:22:23.0: Acquire data for Observation WZ_Per...
31-Jan-2018 03:22:23.0:   (belongs to Project AAVSO_HGUA_Miras_Manual_ver167, Plan WZ_Per)
31-Jan-2018 03:22:23.0: Send Observation WZ_Per to ACP Sequencer
31-Jan-2018 03:22:33.0: Sequencer is now active
31-Jan-2018 03:36:24.8: Sequencer is no longer active
31-Jan-2018 03:36:24.8: Post-job status check done (stat=Completed)
31-Jan-2018 03:36:24.8: Acquisition time: 841.8441507 sec.
31-Jan-2018 03:36:24.8: Data for Observation WZ_Per acquired successfully.
31-Jan-2018 03:36:24.9: Image Efficiency: 46.3%
31-Jan-2018 03:36:24.9: Cycle Efficiency: 100.0%
31-Jan-2018 03:36:25.1: SelectRunning: No running plans
31-Jan-2018 03:36:25.2: Efficiency: Selecting from 10 pending plans:
31-Jan-2018 03:36:25.6:   Selected Plan V0959_Mon (TotEff=0.82)
31-Jan-2018 03:36:25.7: Dispatcher cycle time: 0.5970341 sec.
31-Jan-2018 03:36:25.7: Acquire data for Observation V0959_Mon...
31-Jan-2018 03:36:25.7:   (belongs to Project Nova_Monitoring, Plan V0959_Mon)
31-Jan-2018 03:36:25.7: Send Observation V0959_Mon to ACP Sequencer
31-Jan-2018 03:36:35.7: Sequencer is now active
31-Jan-2018 03:51:51.6: Sequencer is no longer active
31-Jan-2018 03:51:51.6: Post-job status check done (stat=Completed)
31-Jan-2018 03:51:51.6: Acquisition time: 925.9129592 sec.
31-Jan-2018 03:51:51.6: Data for Observation V0959_Mon acquired successfully.
31-Jan-2018 03:51:51.6: Image Efficiency: 68.0%
31-Jan-2018 03:51:51.6: Cycle Efficiency: 99.9%
31-Jan-2018 03:51:51.8: SelectRunning: No running plans
31-Jan-2018 03:51:51.8: STRICT VETO:
31-Jan-2018 03:51:51.8: Obs AF_And in Plan AF_And was vetoed by AirMass constraint.
31-Jan-2018 03:51:51.8:     AirMass estimates ready after 31-Jan-2018 18:00:12 UTC
31-Jan-2018 03:51:51.9: Efficiency: Selecting from 9 pending plans:
31-Jan-2018 03:51:51.9:   Selected Plan MP_Gem (TotEff=0.81)
31-Jan-2018 03:51:52.1: Dispatcher cycle time: 0.2760158 sec.
31-Jan-2018 03:51:52.1: Acquire data for Observation MP_Gem...
31-Jan-2018 03:51:52.1:   (belongs to Project Staels, Plan MP_Gem)
31-Jan-2018 03:51:52.1: Send Observation MP_Gem to ACP Sequencer
31-Jan-2018 03:52:02.1: Sequencer is now active
31-Jan-2018 04:22:28.1: Sequencer is no longer active
31-Jan-2018 04:22:28.1: Post-job status check done (stat=Completed)
31-Jan-2018 04:22:28.1: Acquisition time: 1836.0470159 sec.
31-Jan-2018 04:22:28.1: Data for Observation MP_Gem acquired successfully.
31-Jan-2018 04:22:28.1: Image Efficiency: 78.4%
31-Jan-2018 04:22:28.1: Cycle Efficiency: 100.0%
31-Jan-2018 04:22:28.4: SelectRunning: No running plans
31-Jan-2018 04:22:28.5: Obs NGC_7790 in Plan NGC_7790 was vetoed by AirmassRange constraint.
31-Jan-2018 04:22:28.5:     AirmassRange estimates ready after 31-Jan-2018 16:39:05 UTC
31-Jan-2018 04:22:28.5: Efficiency: Selecting from 7 pending plans:
31-Jan-2018 04:22:28.6:   Selected Plan CM_Cas (TotEff=0.72)
31-Jan-2018 04:22:28.7: Dispatcher cycle time: 0.2740156 sec.
31-Jan-2018 04:22:28.7: Acquire data for Observation CM_Cas...
31-Jan-2018 04:22:28.7:   (belongs to Project AAVSO_HGUA_Miras_Manual_ver167, Plan CM_Cas)
31-Jan-2018 04:22:28.7: Send Observation CM_Cas to ACP Sequencer
31-Jan-2018 04:22:38.7: Sequencer is now active
31-Jan-2018 04:37:09.3: Sequencer is no longer active
31-Jan-2018 04:37:09.3: Post-job status check done (stat=Completed)
31-Jan-2018 04:37:09.3: Acquisition time: 880.6163684 sec.
31-Jan-2018 04:37:09.3: Data for Observation CM_Cas acquired successfully.
31-Jan-2018 04:37:09.3: Image Efficiency: 44.3%
31-Jan-2018 04:37:09.3: Cycle Efficiency: 100.0%
31-Jan-2018 04:37:09.5: SelectRunning: No running plans
31-Jan-2018 04:37:09.6: Efficiency: Selecting from 6 pending plans:
31-Jan-2018 04:37:09.6:   Selected Plan RX_J0524+42 (TotEff=0.67)
31-Jan-2018 04:37:09.7: Dispatcher cycle time: 0.1840105 sec.
31-Jan-2018 04:37:09.7: Acquire data for Observation RX_J0524+42...
31-Jan-2018 04:37:09.7:   (belongs to Project Walker, Plan RX_J0524+42)
31-Jan-2018 04:37:09.7: Doing 30 sets
31-Jan-2018 04:37:09.7: Send Observation RX_J0524+42 to ACP Sequencer
31-Jan-2018 04:37:19.7: Sequencer is now active
31-Jan-2018 05:03:37.9: Sequencer is no longer active
31-Jan-2018 05:03:37.9: Post-job status check done (stat=Completed)
31-Jan-2018 05:03:37.9: Send Observation RX_J0524+42 to ACP Sequencer
31-Jan-2018 05:03:47.9: Sequencer is now active
31-Jan-2018 05:28:54.5: Sequencer is no longer active
31-Jan-2018 05:28:54.5: Post-job status check done (stat=Completed)
31-Jan-2018 05:28:54.5: Send Observation RX_J0524+42 to ACP Sequencer
31-Jan-2018 05:29:04.5: Sequencer is now active
31-Jan-2018 05:54:16.5: Sequencer is no longer active
31-Jan-2018 05:54:16.5: Post-job status check done (stat=Completed)
31-Jan-2018 05:54:16.8: Send Observation RX_J0524+42 to ACP Sequencer
31-Jan-2018 05:54:26.9: Sequencer is now active
31-Jan-2018 06:19:47.0: Sequencer is no longer active
31-Jan-2018 06:19:47.0: Post-job status check done (stat=Completed)
31-Jan-2018 06:19:47.0: Send Observation RX_J0524+42 to ACP Sequencer
31-Jan-2018 06:19:57.1: Sequencer is now active
31-Jan-2018 06:45:10.5: Sequencer is no longer active
31-Jan-2018 06:45:10.5: Post-job status check done (stat=Completed)
31-Jan-2018 06:45:10.8: Send Observation RX_J0524+42 to ACP Sequencer
31-Jan-2018 06:45:20.8: Sequencer is now active
31-Jan-2018 07:10:28.8: Sequencer is no longer active
31-Jan-2018 07:10:28.8: Post-job status check done (stat=Completed)
31-Jan-2018 07:10:28.9: Send Observation RX_J0524+42 to ACP Sequencer
31-Jan-2018 07:10:38.9: Sequencer is now active
31-Jan-2018 07:35:36.7: Sequencer is no longer active
31-Jan-2018 07:35:36.7: Post-job status check done (stat=Completed)
31-Jan-2018 07:35:36.7: Send Observation RX_J0524+42 to ACP Sequencer
31-Jan-2018 07:35:46.7: Sequencer is now active
31-Jan-2018 08:00:44.4: Sequencer is no longer active
31-Jan-2018 08:00:44.4: Post-job status check done (stat=Completed)
31-Jan-2018 08:00:44.4: Send Observation RX_J0524+42 to ACP Sequencer
31-Jan-2018 08:00:54.4: Sequencer is now active
31-Jan-2018 08:25:54.6: Sequencer is no longer active
31-Jan-2018 08:25:54.6: Post-job status check done (stat=Completed)
31-Jan-2018 08:25:54.6: Send Observation RX_J0524+42 to ACP Sequencer
31-Jan-2018 08:26:04.6: Sequencer is now active
31-Jan-2018 08:51:00.7: Sequencer is no longer active
31-Jan-2018 08:51:00.7: Post-job status check done (stat=Completed)
31-Jan-2018 08:51:00.7: Send Observation RX_J0524+42 to ACP Sequencer
31-Jan-2018 08:51:10.7: Sequencer is now active
31-Jan-2018 09:16:12.6: Sequencer is no longer active
31-Jan-2018 09:16:12.6: Post-job status check done (stat=Completed)
31-Jan-2018 09:16:12.6: TestConstraints: AirMass vetoed/failed for end time of Obs
31-Jan-2018 09:16:12.6: ***Running Plan RX_J0524+42 (best efforts) completed early:
31-Jan-2018 09:16:12.6:    Obs RX_J0524+42 would would not meet constraints
31-Jan-2018 09:16:12.6: Acquisition time: 16742.7286293 sec.
31-Jan-2018 09:16:12.6: Data for Observation RX_J0524+42 acquired successfully.
31-Jan-2018 09:16:12.6: Image Efficiency: 78.8%
31-Jan-2018 09:16:12.6: Cycle Efficiency: 100.0%
31-Jan-2018 09:16:12.9: ++ Auto Focus ++
31-Jan-2018 09:16:13.0: Doing periodic autofocus.
31-Jan-2018 09:16:13.0: Start special ACP AutoFocus script for scheduler
31-Jan-2018 09:16:23.0: Sequencer is now active
31-Jan-2018 12:56:07.9: Sequencer is no longer active
31-Jan-2018 12:56:07.9: ** Sequencer script "AutoFocusScheduler" failed:
31-Jan-2018 12:56:07.9: **Script Error**
Source:  ACP
Message: The script was aborted. at line 69 column 9.
Script Context:
      66:         Telescope.Tracking = True                               ' AcquireStar needs tracking scope
      67:     Else
      68:         Call SUP.StartSlewJ2000("FocusArea", CT.RightAscension, CT.Declination)
=>    69:         Call SUP.WaitForSlew()
                  ^
      70:     End If
      71:     
      72: 	If Not SUP.AutoFocus(CT.RightAscension, CT.Declination) Then
-- end --

31-Jan-2018 12:56:07.9: ** Autofocus failed (n=1), will retry ASAP after 0 min.
31-Jan-2018 12:56:07.9: SelectRunning: No running plans
31-Jan-2018 12:56:07.9: Obs GSC_4030_204 in Plan GSC_4030_204 was vetoed by AirMass constraint.
31-Jan-2018 12:56:07.9:     AirMass estimates ready after 31-Jan-2018 17:48:24 UTC
31-Jan-2018 12:56:07.9: Plan M56 would extend past daylight, deferred to next night
31-Jan-2018 12:56:07.9: Plan M67 would extend past daylight, deferred to next night
31-Jan-2018 12:56:07.9: Plan SDSS073224 would extend past daylight, deferred to next night
31-Jan-2018 12:56:07.9: Plan SDSSJ121027+502735 would extend past daylight, deferred to next night
31-Jan-2018 12:56:08.0: Plan V0524_Cas would extend past daylight, deferred to next night
31-Jan-2018 12:56:08.0: Plan V0965_Per would extend past daylight, deferred to next night
31-Jan-2018 12:56:08.0: Efficiency: Selecting from 2 pending plans:
31-Jan-2018 12:56:08.0:   Selected Plan V841_Oph (TotEff=0.71)
31-Jan-2018 12:56:08.0: Dispatcher cycle time: 0.1310075 sec.
31-Jan-2018 12:56:08.0: Acquire data for Observation V841_Oph...
31-Jan-2018 12:56:08.0:   (belongs to Project AAVSO_Monitoring, Plan V841_Oph)
31-Jan-2018 12:56:08.1: Send Observation V841_Oph to ACP Sequencer
31-Jan-2018 12:56:18.1: Sequencer is now active
31-Jan-2018 12:56:23.2: Sequencer is no longer active
31-Jan-2018 12:56:23.2: ** Sequencer script "AcquireScheduler" failed:
31-Jan-2018 12:56:23.2: **Script Error (Tracking has been stopped)**
Source:  ASCOM.SiTechDll.Telescope
Message: sttgaSiTech Says: Motor(s) in Blinky Mode! at line 1741 column 9.
Script Context:
    1738:             SUP.CalcSimImageCoordinates TargetRA, TargetDec         ' Calculate simulator image
    1739:         End If
    1740:     Else
=>  1741:         SUP.StartSlewJ2000 OBS.Name, TargetRA, TargetDec            ' START THE SLEW
                  ^
    1742:     End If
    1743:     If SUP.HaveRotator Then SUP.StartRotateToPA OBS.PA, TargetRA    ' Always start rotator slew
    1744:     
-- end --

31-Jan-2018 12:56:26.2: Post-job status check done (stat=Running)
31-Jan-2018 12:56:26.2: ACP ERROR: Run failed due to script error (see ACP run log)
31-Jan-2018 12:56:26.2: ACP data acquisition failed for Observation V841_Oph.
31-Jan-2018 12:56:26.2:   (Observation V841_Oph interrupted by acquisition process failure.)
31-Jan-2018 12:56:26.3: ++ Auto Focus ++
31-Jan-2018 12:56:26.4: Doing periodic autofocus.
31-Jan-2018 12:56:26.4: Start special ACP AutoFocus script for scheduler
31-Jan-2018 12:56:36.4: Sequencer is now active
31-Jan-2018 12:56:41.6: Sequencer is no longer active
31-Jan-2018 12:56:41.6: ** Sequencer script "AutoFocusScheduler" failed:
31-Jan-2018 12:56:41.6: **Script Error (Tracking has been stopped)**
Source:  ASCOM.SiTechDll.Telescope
Message: sttgaSiTech Says: Motor(s) in Blinky Mode! at line 68 column 9.
Script Context:
      65:     If Util.Prefs.AutoFocus.UseAcquireStar Then
      66:         Telescope.Tracking = True                               ' AcquireStar needs tracking scope
      67:     Else
=>    68:         Call SUP.StartSlewJ2000("FocusArea", CT.RightAscension, CT.Declination)
                  ^
      69:         Call SUP.WaitForSlew()
      70:     End If
      71:     
-- end --

31-Jan-2018 12:56:41.6: ** Autofocus failed (n=2), will retry ASAP after 5 min.
31-Jan-2018 12:56:41.9: SelectRunning: No running plans
31-Jan-2018 12:56:41.9: Plan Mkn_501 would extend past daylight, deferred to next night
31-Jan-2018 12:56:41.9: No pending plans that can be started now.
31-Jan-2018 12:56:41.9: Dispatcher cycle time: 0.0280016 sec.
31-Jan-2018 12:57:12.4: SelectRunning: No running plans
31-Jan-2018 12:57:12.4: No pending plans that can be started now.
31-Jan-2018 12:57:12.4: Dispatcher cycle time: 0.0120007 sec.
31-Jan-2018 12:57:42.6: SelectRunning: No running plans
31-Jan-2018 12:57:42.6: No pending plans that can be started now.
31-Jan-2018 12:57:42.6: Dispatcher cycle time: 0.0130007 sec.
31-Jan-2018 12:58:12.7: SelectRunning: No running plans
31-Jan-2018 12:58:12.7: No pending plans that can be started now.
31-Jan-2018 12:58:12.7: Dispatcher cycle time: 0.0130008 sec.
31-Jan-2018 12:58:42.9: SelectRunning: No running plans
31-Jan-2018 12:58:42.9: No pending plans that can be started now.
31-Jan-2018 12:58:42.9: Dispatcher cycle time: 0.0120007 sec.
31-Jan-2018 12:59:13.1: SelectRunning: No running plans
31-Jan-2018 12:59:13.1: No pending plans that can be started now.
31-Jan-2018 12:59:13.1: Dispatcher cycle time: 0.0120007 sec.
31-Jan-2018 12:59:43.8: SelectRunning: No running plans
31-Jan-2018 12:59:43.8: No pending plans that can be started now.
31-Jan-2018 12:59:43.8: Dispatcher cycle time: 0.0120007 sec.
31-Jan-2018 13:00:14.2: SelectRunning: No running plans
31-Jan-2018 13:00:14.2: No pending plans that can be started now.
31-Jan-2018 13:00:14.2: Dispatcher cycle time: 0.0130007 sec.
31-Jan-2018 13:00:44.4: SelectRunning: No running plans
31-Jan-2018 13:00:44.4: No pending plans that can be started now.
31-Jan-2018 13:00:44.4: Dispatcher cycle time: 0.0110006 sec.
31-Jan-2018 13:01:14.6: SelectRunning: No running plans
31-Jan-2018 13:01:14.6: No pending plans that can be started now.
31-Jan-2018 13:01:14.6: Dispatcher cycle time: 0.0120007 sec.
31-Jan-2018 13:01:44.7: ++ Auto Focus ++
31-Jan-2018 13:01:44.8: Doing periodic autofocus.
31-Jan-2018 13:01:44.8: Start special ACP AutoFocus script for scheduler
31-Jan-2018 13:01:54.8: Sequencer is now active
31-Jan-2018 13:01:59.9: Sequencer is no longer active
31-Jan-2018 13:01:59.9: ** Sequencer script "AutoFocusScheduler" failed:
31-Jan-2018 13:01:59.9: **Script Error (Tracking has been stopped)**
Source:  ASCOM.SiTechDll.Telescope
Message: sttgaSiTech Says: Motor(s) in Blinky Mode! at line 68 column 9.
Script Context:
      65:     If Util.Prefs.AutoFocus.UseAcquireStar Then
      66:         Telescope.Tracking = True                               ' AcquireStar needs tracking scope
      67:     Else
=>    68:         Call SUP.StartSlewJ2000("FocusArea", CT.RightAscension, CT.Declination)
                  ^
      69:         Call SUP.WaitForSlew()
      70:     End If
      71:     
-- end --

31-Jan-2018 13:01:59.9: ** Autofocus failed (n=3), will retry ASAP after 10 min.
31-Jan-2018 13:01:59.9: SelectRunning: No running plans
31-Jan-2018 13:01:59.9: No pending plans that can be started now.
31-Jan-2018 13:01:59.9: Dispatcher cycle time: 0.0120007 sec.
31-Jan-2018 13:02:30.2: SelectRunning: No running plans
31-Jan-2018 13:02:30.2: Plan IRAS_19067+2158 would extend past daylight, deferred to next night
31-Jan-2018 13:02:30.2: No pending plans that can be started now.
31-Jan-2018 13:02:30.2: Dispatcher cycle time: 0.0130008 sec.
31-Jan-2018 13:03:00.7: SelectRunning: No running plans
31-Jan-2018 13:03:00.7: No pending plans that can be started now.
31-Jan-2018 13:03:00.7: Dispatcher cycle time: 0.0120007 sec.
31-Jan-2018 13:03:30.8: SelectRunning: No running plans
31-Jan-2018 13:03:30.8: Plan NSVS_11201899 would extend past daylight, deferred to next night
31-Jan-2018 13:03:30.8: No pending plans that can be started now.
31-Jan-2018 13:03:30.8: Dispatcher cycle time: 0.0140008 sec.
31-Jan-2018 13:04:01.0: SelectRunning: No running plans
31-Jan-2018 13:04:01.0: No pending plans that can be started now.
31-Jan-2018 13:04:01.0: Dispatcher cycle time: 0.018001 sec.
31-Jan-2018 13:04:31.2: SelectRunning: No running plans
31-Jan-2018 13:04:31.3: No pending plans that can be started now.
31-Jan-2018 13:04:31.3: Dispatcher cycle time: 0.0110006 sec.
31-Jan-2018 13:05:01.4: SelectRunning: No running plans
31-Jan-2018 13:05:01.4: No pending plans that can be started now.
31-Jan-2018 13:05:01.4: Dispatcher cycle time: 0.0120007 sec.
31-Jan-2018 13:05:31.6: SelectRunning: No running plans
31-Jan-2018 13:05:31.6: No pending plans that can be started now.
31-Jan-2018 13:05:31.6: Dispatcher cycle time: 0.0120007 sec.
31-Jan-2018 13:06:01.7: SelectRunning: No running plans
31-Jan-2018 13:06:01.7: No pending plans that can be started now.
31-Jan-2018 13:06:01.7: Dispatcher cycle time: 0.0120007 sec.
31-Jan-2018 13:06:31.9: ++ Dawn Flats ++
31-Jan-2018 13:06:32.0: Start ACP AutoFlat script for Dawn
31-Jan-2018 13:06:42.0: Sequencer is now active
31-Jan-2018 13:16:11.7: Sequencer is no longer active
31-Jan-2018 13:16:11.7: ** Sequencer script "AutoFlat" failed:
31-Jan-2018 13:16:11.7: **Script Error (Tracking has been stopped)**
Source:  ASCOM.SiTechDll.Telescope
Message: sttgaSiTech Says: Motor(s) in Blinky Mode! at line 1372 column 13.
Script Context:
    1369:                 Console.PrintLine "  (tracking on)"
    1370:                 Telescope.Tracking = True
    1371:             End If
=>  1372:             SUP.StartSlewJ2000 SLEWLABEL, RA, Dec               ' Start the slew
                      ^
    1373:             SUP.WaitForSlew
    1374:             Util.WaitUntil EvTime(0) + PreSlewTime              ' Wait till time to start
    1375:             Console.PrintLine "Starting dawn flats. High Sun at " & _
-- end --

31-Jan-2018 13:16:11.9: -- Observatory Shutdown --
31-Jan-2018 13:16:12.0: Weather disconnected.
31-Jan-2018 13:16:12.0: Start ACP Sequencer's ShutdownObs script
31-Jan-2018 13:16:22.0: Sequencer is now active
31-Jan-2018 13:57:13.0: Dispatcher stopped at 31-Jan-2018 13:57:13 UTC
31-Jan-2018 13:57:13.0: **DISPATCHER INTERRUPT LEVEL 1 RECEIVED**
31-Jan-2018 13:57:14.8: Sequencer is no longer active
31-Jan-2018 13:57:14.8: ** Sequencer script "ShutdownObs" failed:
31-Jan-2018 13:57:14.8: **Script Error**
Source:  ACP
Message: The script was aborted. at line 38 column 9.
Script Context:
      35:     
      36:     if(Telescope.Connected) {
      37:         Console.PrintLine("Parking scope"); 
=>    38:         Telescope.Park();                                               // Park the scope if possible, and/or close/park dome
                  ^
      39:         Console.PrintLine("OK");
      40:         if(Telescope.Connected) {
      41:             Console.PrintLine("Disconnecting Scope");
-- end --

31-Jan-2018 13:57:14.9: **EXCEPTION IN SCHEDULER:
31-Jan-2018 13:57:14.9: Observatory shutdown failed, cannot continue.
31-Jan-2018 13:57:14.9: Traceback:
   at DC3.Scheduler.Engine.DoShutdownIf()
   at DC3.Scheduler.Engine.Run()
31-Jan-2018 13:57:14.9: Run statistics:
31-Jan-2018 13:57:14.9:   Observations:   51
31-Jan-2018 13:57:14.9:   Considered:     35
31-Jan-2018 13:57:14.9:   Completed:      21
31-Jan-2018 13:57:14.9:   Skipped:        10
31-Jan-2018 13:57:14.9:   Never Eligible: 16
31-Jan-2018 13:57:14.9:   Failed:         4
31-Jan-2018 13:57:14.9: Shutter-Open efficiency: 70.86%
31-Jan-2018 13:57:14.9: Overall Efficiency: 99.90%
31-Jan-2018 13:57:14.9: Release ACP sequencer
Log closed at Wed, Jan 31 2018 13:57:16 UTC (actual time)