Log opened at Tue, Oct 18 2016 01:03:55 UTC (actual time) Current log level is SuperDebug Scheduler version 3.5.3 Multi-user license: Arne Henden 18-Oct-2016 01:03:55.9: Simulated clock initialized at 18-Oct-2016 01:03:55 UTC 18-Oct-2016 01:03:55.9: Loaded Constraint plugin AirMass 18-Oct-2016 01:03:56.0: Loaded Constraint plugin AirmassRange 18-Oct-2016 01:03:56.0: Loaded Constraint plugin Horizon 18-Oct-2016 01:03:56.0: Loaded Constraint plugin HourAngle 18-Oct-2016 01:03:56.0: Loaded Constraint plugin MoonAvoid 18-Oct-2016 01:03:56.0: Loaded Constraint plugin MoonDown 18-Oct-2016 01:03:56.0: Loaded Constraint plugin SkyCondition 18-Oct-2016 01:03:56.0: Loaded Constraint plugin TimeRange 18-Oct-2016 01:03:56.5: Attach ACP sequencer 18-Oct-2016 01:04:07.1: Dispatcher started at 18-Oct-2016 01:04:07 UTC 18-Oct-2016 01:04:07.1: Priority: W[0]=0.00 18-Oct-2016 01:04:07.1: Transit Altitude: W[1]=0.00 18-Oct-2016 01:04:07.1: Highest Altitude: W[6]=0.00 18-Oct-2016 01:04:07.1: Lateness: W[5]=0.70 18-Oct-2016 01:04:07.1: Slew Distance: W[2]=0.30 18-Oct-2016 01:04:07.1: Retry Count: W[3]=0.20 18-Oct-2016 01:04:07.1: Meridian Crossing: W[4]=0.30 18-Oct-2016 01:04:07.1: Obs Conditions: W[7]=0.40 18-Oct-2016 01:04:07.1: Rising Plan Delay: disabled 18-Oct-2016 01:04:18.5: Weather successfully connected. Wait for valid data... 18-Oct-2016 01:04:20.1: ++ Observatory Startup ++ 18-Oct-2016 01:04:20.1: Start ACP Sequencer's StartupObs script 18-Oct-2016 01:04:30.1: Sequencer is now active 18-Oct-2016 01:04:35.4: Sequencer is no longer active Log level changed from SuperDebug to Verbose 18-Oct-2016 01:25:00.3: ++ Auto Focus ++ 18-Oct-2016 01:25:00.3: Doing initial autofocus. 18-Oct-2016 01:25:00.3: Start special ACP AutoFocus script for scheduler 18-Oct-2016 01:27:56.6: Next periodic autofocus ASAP after 18-Oct-2016 03:27:43 UTC 18-Oct-2016 01:27:59.1: Plan SA68_216 has Monitor Mode. Time to resubmit it. 18-Oct-2016 01:27:59.1: Plan SA44_28 has Monitor Mode. Time to resubmit it. 18-Oct-2016 01:27:59.1: Plan SA93_317 has Monitor Mode. Time to resubmit it. 18-Oct-2016 01:27:59.8: Dispatcher cycle time: 1.9700028 sec. 18-Oct-2016 01:27:59.8: Acquire data for Observation SA61... 18-Oct-2016 01:27:59.8: (belongs to Project Landolt_1, Plan SA61) 18-Oct-2016 01:27:59.8: Send Observation SA61 to ACP Sequencer 18-Oct-2016 01:33:49.6: Acquisition time: 349.8004897 sec. 18-Oct-2016 01:33:49.6: Data for Observation SA61 acquired successfully. 18-Oct-2016 01:33:49.6: Image Efficiency: 37.0% 18-Oct-2016 01:33:49.6: Cycle Efficiency: 99.4% 18-Oct-2016 01:33:50.5: Dispatcher cycle time: 0.2600003 sec. 18-Oct-2016 01:33:50.5: Acquire data for Observation SA110... 18-Oct-2016 01:33:50.5: (belongs to Project Landolt_1, Plan SA110) 18-Oct-2016 01:33:50.5: Send Observation SA110 to ACP Sequencer 18-Oct-2016 01:40:30.9: Acquisition time: 400.4005606 sec. 18-Oct-2016 01:40:30.9: Data for Observation SA110 acquired successfully. 18-Oct-2016 01:40:30.9: Image Efficiency: 44.9% 18-Oct-2016 01:40:30.9: Cycle Efficiency: 99.9% 18-Oct-2016 01:40:31.3: Dispatcher cycle time: 0.3100004 sec. 18-Oct-2016 01:40:31.3: Acquire data for Observation V849_Her_TMO61... 18-Oct-2016 01:40:31.3: (belongs to Project Walker, Plan V849_Her) 18-Oct-2016 01:40:31.3: Send Observation V849_Her_TMO61 to ACP Sequencer 18-Oct-2016 01:41:34.9: Dispatcher stopped at 18-Oct-2016 01:41:34 UTC 18-Oct-2016 01:41:34.9: **DISPATCHER INTERRUPT LEVEL 1 RECEIVED** 18-Oct-2016 01:41:45.0: ** Sequencer script "AcquireScheduler" failed: 18-Oct-2016 01:41:45.0: **Script Error** Source: ACP Message: The script was aborted. at line 951 column 17. Script Context: 948: If Not bPtgUpdDone Then ' If didn't get ptg upd from auto-flip 949: On Error Resume Next ' May fail on slew-back below horizon 950: isPointing = True ' For ACP web UI (typ.) => 951: bPtgUpdDone = SUP.UpdatePointing(OBS.Name, OBS.RA, OBS.Dec, OBS.PA) ' Uses Clear or filter specified for pointing updates ^ 952: If Err.Number <> 0 Then 953: bPtgUpdDone = False ' Hard error, probably horizon 954: Console.PrintLine "**Pointing update error from " & Err.Source & _ -- end -- 18-Oct-2016 01:41:48.0: RUN FAILED: Scheduler was stopped during run 18-Oct-2016 01:41:48.0: ACP data acquisition failed for Observation V849_Her_TMO61. 18-Oct-2016 01:41:48.0: (Observation V849_Her_TMO61 interrupted by operator or system.) 18-Oct-2016 02:06:09.7: Dispatcher started at 18-Oct-2016 02:06:09 UTC 18-Oct-2016 02:06:09.7: Priority: W[0]=0.00 18-Oct-2016 02:06:09.7: Transit Altitude: W[1]=0.00 18-Oct-2016 02:06:09.7: Highest Altitude: W[6]=0.00 18-Oct-2016 02:06:09.7: Lateness: W[5]=0.70 18-Oct-2016 02:06:09.7: Slew Distance: W[2]=0.30 18-Oct-2016 02:06:09.7: Retry Count: W[3]=0.20 18-Oct-2016 02:06:09.7: Meridian Crossing: W[4]=0.30 18-Oct-2016 02:06:09.7: Obs Conditions: W[7]=0.40 18-Oct-2016 02:06:09.7: Rising Plan Delay: disabled 18-Oct-2016 02:06:12.3: ++ Observatory Startup ++ 18-Oct-2016 02:06:12.3: Start ACP Sequencer's StartupObs script 18-Oct-2016 02:06:27.5: ++ Auto Focus ++ 18-Oct-2016 02:06:27.6: Doing initial autofocus. 18-Oct-2016 02:06:27.6: Start special ACP AutoFocus script for scheduler 18-Oct-2016 02:10:07.6: Next periodic autofocus ASAP after 18-Oct-2016 04:09:41 UTC 18-Oct-2016 02:10:09.7: Dispatcher cycle time: 0.6300009 sec. 18-Oct-2016 02:10:09.7: Acquire data for Observation SA112_810... 18-Oct-2016 02:10:09.7: (belongs to Project Landolt_1, Plan SA112_810) 18-Oct-2016 02:10:09.7: Send Observation SA112_810 to ACP Sequencer 18-Oct-2016 02:10:52.9: Dispatcher stopped at 18-Oct-2016 02:10:52 UTC 18-Oct-2016 02:10:52.9: **DISPATCHER INTERRUPT LEVEL 1 RECEIVED** 18-Oct-2016 02:10:58.5: ** Sequencer script "AcquireScheduler" failed: 18-Oct-2016 02:10:58.5: **Script Error** Source: ACP Message: The script was aborted. at line 951 column 17. Script Context: 948: If Not bPtgUpdDone Then ' If didn't get ptg upd from auto-flip 949: On Error Resume Next ' May fail on slew-back below horizon 950: isPointing = True ' For ACP web UI (typ.) => 951: bPtgUpdDone = SUP.UpdatePointing(OBS.Name, OBS.RA, OBS.Dec, OBS.PA) ' Uses Clear or filter specified for pointing updates ^ 952: If Err.Number <> 0 Then 953: bPtgUpdDone = False ' Hard error, probably horizon 954: Console.PrintLine "**Pointing update error from " & Err.Source & _ -- end -- 18-Oct-2016 02:11:01.5: RUN FAILED: Scheduler was stopped during run 18-Oct-2016 02:11:01.5: ACP data acquisition failed for Observation SA112_810. 18-Oct-2016 02:11:01.5: (Observation SA112_810 interrupted by operator or system.) 18-Oct-2016 02:28:42.0: Dispatcher started at 18-Oct-2016 02:28:42 UTC 18-Oct-2016 02:28:42.0: Priority: W[0]=0.00 18-Oct-2016 02:28:42.0: Transit Altitude: W[1]=0.00 18-Oct-2016 02:28:42.0: Highest Altitude: W[6]=0.00 18-Oct-2016 02:28:42.0: Lateness: W[5]=0.70 18-Oct-2016 02:28:42.0: Slew Distance: W[2]=0.30 18-Oct-2016 02:28:42.0: Retry Count: W[3]=0.20 18-Oct-2016 02:28:42.0: Meridian Crossing: W[4]=0.30 18-Oct-2016 02:28:42.0: Obs Conditions: W[7]=0.40 18-Oct-2016 02:28:42.0: Rising Plan Delay: disabled 18-Oct-2016 02:28:44.9: ++ Observatory Startup ++ 18-Oct-2016 02:28:44.9: Start ACP Sequencer's StartupObs script 18-Oct-2016 02:29:01.7: ++ Auto Focus ++ 18-Oct-2016 02:29:01.8: Doing initial autofocus. 18-Oct-2016 02:29:01.8: Start special ACP AutoFocus script for scheduler 18-Oct-2016 02:32:05.8: Next periodic autofocus ASAP after 18-Oct-2016 04:31:51 UTC 18-Oct-2016 02:32:06.5: Dispatcher cycle time: 0.2400004 sec. 18-Oct-2016 02:32:06.5: Acquire data for Observation SA111... 18-Oct-2016 02:32:06.5: (belongs to Project Landolt_1, Plan SA111) 18-Oct-2016 02:32:06.8: Send Observation SA111 to ACP Sequencer 18-Oct-2016 02:37:25.2: Acquisition time: 318.7414463 sec. 18-Oct-2016 02:37:25.2: Data for Observation SA111 acquired successfully. 18-Oct-2016 02:37:25.2: Image Efficiency: 40.8% 18-Oct-2016 02:37:25.2: Cycle Efficiency: 99.9% 18-Oct-2016 02:37:27.9: Dispatcher cycle time: 0.3000004 sec. 18-Oct-2016 02:37:27.9: Acquire data for Observation SA1119... 18-Oct-2016 02:37:27.9: (belongs to Project Landolt_1, Plan SA1119) 18-Oct-2016 02:37:27.9: Send Observation SA1119 to ACP Sequencer 18-Oct-2016 02:42:30.3: Acquisition time: 302.4404234 sec. 18-Oct-2016 02:42:30.3: Data for Observation SA1119 acquired successfully. 18-Oct-2016 02:42:30.4: Image Efficiency: 42.9% 18-Oct-2016 02:42:30.4: Cycle Efficiency: 99.9% 18-Oct-2016 02:42:32.8: Dispatcher cycle time: 0.3000004 sec. 18-Oct-2016 02:42:32.8: Acquire data for Observation SA109... 18-Oct-2016 02:42:32.8: (belongs to Project Landolt_2, Plan SA109) 18-Oct-2016 02:42:32.8: Send Observation SA109 to ACP Sequencer 18-Oct-2016 02:49:08.7: Acquisition time: 395.9205543 sec. 18-Oct-2016 02:49:08.7: Data for Observation SA109 acquired successfully. 18-Oct-2016 02:49:08.7: Image Efficiency: 45.4% 18-Oct-2016 02:49:08.7: Cycle Efficiency: 99.9% 18-Oct-2016 02:49:10.5: Dispatcher cycle time: 0.2900004 sec. 18-Oct-2016 02:49:10.5: Acquire data for Observation SA116_180... 18-Oct-2016 02:49:10.5: (belongs to Project Landolt_2, Plan SA116_180) 18-Oct-2016 02:49:10.5: Send Observation SA116_180 to ACP Sequencer 18-Oct-2016 02:55:48.9: Acquisition time: 398.4115578 sec. 18-Oct-2016 02:55:48.9: Data for Observation SA116_180 acquired successfully. 18-Oct-2016 02:55:49.0: Image Efficiency: 23.8% 18-Oct-2016 02:55:49.0: Cycle Efficiency: 99.9% 18-Oct-2016 02:55:50.4: Dispatcher cycle time: 0.3000004 sec. 18-Oct-2016 02:55:50.4: Acquire data for Observation U_Aqr... 18-Oct-2016 02:55:50.4: (belongs to Project AAVSO_Monitoring, Plan U_Aqr) 18-Oct-2016 02:55:50.4: Send Observation U_Aqr to ACP Sequencer 18-Oct-2016 03:32:11.3: Dispatcher stopped at 18-Oct-2016 03:32:11 UTC 18-Oct-2016 03:32:11.3: **DISPATCHER INTERRUPT LEVEL 1 RECEIVED** 18-Oct-2016 03:32:21.3: RUN FAILED: Scheduler was stopped during run 18-Oct-2016 03:32:21.3: ACP data acquisition failed for Observation U_Aqr. 18-Oct-2016 03:32:21.3: (Observation U_Aqr interrupted by operator or system.) 18-Oct-2016 03:47:05.0: Dispatcher started at 18-Oct-2016 03:47:05 UTC 18-Oct-2016 03:47:05.0: Priority: W[0]=0.00 18-Oct-2016 03:47:05.0: Transit Altitude: W[1]=0.00 18-Oct-2016 03:47:05.0: Highest Altitude: W[6]=0.00 18-Oct-2016 03:47:05.0: Lateness: W[5]=0.70 18-Oct-2016 03:47:05.0: Slew Distance: W[2]=0.30 18-Oct-2016 03:47:05.0: Retry Count: W[3]=0.20 18-Oct-2016 03:47:05.0: Meridian Crossing: W[4]=0.30 18-Oct-2016 03:47:05.0: Obs Conditions: W[7]=0.40 18-Oct-2016 03:47:05.0: Rising Plan Delay: disabled 18-Oct-2016 03:47:06.6: ++ Observatory Startup ++ 18-Oct-2016 03:47:06.6: Start ACP Sequencer's StartupObs script 18-Oct-2016 03:47:23.8: ++ Auto Focus ++ 18-Oct-2016 03:47:23.9: Doing initial autofocus. 18-Oct-2016 03:47:23.9: Start special ACP AutoFocus script for scheduler 18-Oct-2016 03:51:19.0: Next periodic autofocus ASAP after 18-Oct-2016 05:50:24 UTC 18-Oct-2016 03:51:20.8: Dispatcher cycle time: 0.6100009 sec. 18-Oct-2016 03:51:20.8: Acquire data for Observation SA110... 18-Oct-2016 03:51:20.9: (belongs to Project Landolt_2, Plan SA110) 18-Oct-2016 03:51:20.9: Send Observation SA110 to ACP Sequencer 18-Oct-2016 03:58:26.7: Acquisition time: 425.8515962 sec. 18-Oct-2016 03:58:26.7: Data for Observation SA110 acquired successfully. 18-Oct-2016 03:58:26.7: Image Efficiency: 42.2% 18-Oct-2016 03:58:26.7: Cycle Efficiency: 99.8% 18-Oct-2016 03:58:30.7: Dispatcher cycle time: 0.3100004 sec. 18-Oct-2016 03:58:30.7: Acquire data for Observation M56... 18-Oct-2016 03:58:30.7: (belongs to Project AAVSO_Monitoring, Plan M56) 18-Oct-2016 03:58:30.7: Doing 3 sets 18-Oct-2016 03:58:31.0: Send Observation M56 to ACP Sequencer 18-Oct-2016 04:04:48.5: Send Observation M56 to ACP Sequencer 18-Oct-2016 04:09:14.3: Dispatcher stopped at 18-Oct-2016 04:09:14 UTC 18-Oct-2016 04:09:14.3: **DISPATCHER INTERRUPT LEVEL 1 RECEIVED** 18-Oct-2016 04:09:24.3: RUN FAILED: Scheduler was stopped during run 18-Oct-2016 04:09:24.3: ACP data acquisition failed for Observation M56. 18-Oct-2016 04:09:24.3: (Observation M56 interrupted by operator or system.) 20-Oct-2016 00:31:52.2: Requested shutdown of scheduler 20-Oct-2016 00:31:52.3: Run statistics: 20-Oct-2016 00:31:52.3: Observations: 65 20-Oct-2016 00:31:52.3: Considered: 22 20-Oct-2016 00:31:52.3: Completed: 7 20-Oct-2016 00:31:52.3: Skipped: 9 20-Oct-2016 00:31:52.3: Never Eligible: 43 20-Oct-2016 00:31:52.3: Failed: 6 20-Oct-2016 00:31:52.3: Shutter-Open efficiency: 22.59% 20-Oct-2016 00:31:52.3: Overall Efficiency: 99.90% 20-Oct-2016 00:31:52.3: Release ACP sequencer Log closed at Thu, Oct 20 2016 00:31:54 UTC (actual time)