Log opened at Mon, Oct 02 2017 04:07:39 UTC (actual time) Current log level is Debug Scheduler version 3.5.3 Multi-user license: Arne Henden 02-Oct-2017 04:07:39.9: Simulated clock initialized at 02-Oct-2017 04:07:39 UTC 02-Oct-2017 04:07:39.9: Loaded Constraint plugin AirMass 02-Oct-2017 04:07:39.9: Loaded Constraint plugin AirmassRange 02-Oct-2017 04:07:39.9: Loaded Constraint plugin Horizon 02-Oct-2017 04:07:39.9: Loaded Constraint plugin HourAngle 02-Oct-2017 04:07:39.9: Loaded Constraint plugin MoonAvoid 02-Oct-2017 04:07:39.9: Loaded Constraint plugin MoonDown 02-Oct-2017 04:07:40.0: Loaded Constraint plugin SkyCondition 02-Oct-2017 04:07:40.0: Loaded Constraint plugin TimeRange 02-Oct-2017 04:07:40.5: 22 plans are now pending. 02-Oct-2017 04:07:40.5: Attach ACP sequencer 02-Oct-2017 04:08:01.9: Weather successfully connected. Wait for valid data... 02-Oct-2017 04:08:14.3: Dispatcher started at 02-Oct-2017 04:08:14 UTC 02-Oct-2017 04:08:14.3: Priority: W[0]=0.00 02-Oct-2017 04:08:14.3: Transit Altitude: W[1]=0.70 02-Oct-2017 04:08:14.3: Highest Altitude: W[6]=0.00 02-Oct-2017 04:08:14.3: Lateness: W[5]=0.00 02-Oct-2017 04:08:14.3: Slew Distance: W[2]=0.00 02-Oct-2017 04:08:14.3: Retry Count: W[3]=0.20 02-Oct-2017 04:08:14.3: Meridian Crossing: W[4]=0.00 02-Oct-2017 04:08:14.3: Obs Conditions: W[7]=0.40 02-Oct-2017 04:08:14.3: Rising Plan Delay: disabled 02-Oct-2017 04:08:15.2: ++ Observatory Startup ++ 02-Oct-2017 04:08:15.2: Start ACP Sequencer's StartupObs script 02-Oct-2017 04:08:25.2: Sequencer is now active 02-Oct-2017 04:08:50.7: Sequencer is no longer active 02-Oct-2017 04:08:50.8: ++ Auto Focus ++ 02-Oct-2017 04:08:50.8: Doing initial autofocus. 02-Oct-2017 04:08:50.8: Start special ACP AutoFocus script for scheduler 02-Oct-2017 04:09:00.8: Sequencer is now active 02-Oct-2017 04:09:06.0: Sequencer is no longer active 02-Oct-2017 04:09:06.0: ** Sequencer script "AutoFocusScheduler" failed: 02-Oct-2017 04:09:06.0: **Script Error (Tracking has been stopped)** Source: ACP.AcquireSupport Message: FilterInfo.txt has more offset entries than filters in the camera at line 38 column 5. Script Context: 35: Dim Filt, dtStart, buf, CT 36: 37: Set SUP = CreateObject("ACP.AcquireSupport") => 38: SUP.Initialize ^ 39: 40: dtStart = Util.SysUTCDate 41: buf = "AutoFocus-" & Util.FormatVar(dtStart, "dd-mmm-yyyy@HhNnSs") & ".log" ' Typical Log Name -- end -- 02-Oct-2017 04:09:06.0: ** Autofocus failed (n=1), will retry ASAP after 0 min. 02-Oct-2017 04:09:06.3: SelectRunning: No running plans 02-Oct-2017 04:09:06.4: Obs AL_Boo in Plan AL_Boo was vetoed by AirmassRange constraint. 02-Oct-2017 04:09:06.4: AirmassRange estimates ready after 02-Oct-2017 15:36:12 UTC 02-Oct-2017 04:09:06.4: Obs CB_32 in Plan CB_32 was vetoed by AirMass constraint. 02-Oct-2017 04:09:06.4: AirMass estimates ready after 02-Oct-2017 08:24:02 UTC 02-Oct-2017 04:09:06.4: Obs CV_Vir in Plan CV_Vir was vetoed by AirmassRange constraint. 02-Oct-2017 04:09:06.4: AirmassRange estimates ready after 02-Oct-2017 14:42:56 UTC 02-Oct-2017 04:09:06.4: Obs DN_Lyn in Plan DN_Lyn was vetoed by AirmassRange constraint. 02-Oct-2017 04:09:06.4: AirmassRange estimates ready after 02-Oct-2017 08:35:48 UTC 02-Oct-2017 04:09:06.4: Obs FY_Cam in Plan FY_Cam was vetoed by AirmassRange constraint. 02-Oct-2017 04:09:06.4: AirmassRange estimates ready after 02-Oct-2017 04:15:23 UTC 02-Oct-2017 04:09:06.4: Obs HI_Cam in Plan HI_Cam was vetoed by AirmassRange constraint. 02-Oct-2017 04:09:06.4: AirmassRange estimates ready after 02-Oct-2017 05:11:19 UTC 02-Oct-2017 04:09:06.4: Obs HO_Cam in Plan HO_Cam was vetoed by AirmassRange constraint. 02-Oct-2017 04:09:06.4: AirmassRange estimates ready after 02-Oct-2017 05:56:05 UTC 02-Oct-2017 04:09:06.4: Obs HQ_Aur in Plan HQ_Aur was vetoed by AirmassRange constraint. 02-Oct-2017 04:09:06.4: AirmassRange estimates ready after 02-Oct-2017 06:27:11 UTC 02-Oct-2017 04:09:06.4: Obs HX_Aur in Plan HX_Aur was vetoed by AirmassRange constraint. 02-Oct-2017 04:09:06.4: AirmassRange estimates ready after 02-Oct-2017 06:05:20 UTC 02-Oct-2017 04:09:06.4: Obs KK_UMa in Plan KK_UMa was vetoed by AirmassRange constraint. 02-Oct-2017 04:09:06.4: AirmassRange estimates ready after 02-Oct-2017 12:16:03 UTC 02-Oct-2017 04:09:06.4: Obs M67 in Plan M67 was vetoed by AirMass constraint. 02-Oct-2017 04:09:06.4: AirMass estimates ready after 02-Oct-2017 11:33:52 UTC 02-Oct-2017 04:09:06.4: Obs MP_Gem in Plan MP_Gem was vetoed by AirMass constraint. 02-Oct-2017 04:09:06.4: AirMass estimates ready after 02-Oct-2017 08:44:59 UTC 02-Oct-2017 04:09:06.4: Obs omi_Cet in Plan omi_Cet was vetoed by AirMass constraint. 02-Oct-2017 04:09:06.4: AirMass estimates ready after 02-Oct-2017 04:43:04 UTC 02-Oct-2017 04:09:06.4: Obs SDSS073224 in Plan SDSS073224 was vetoed by AirMass constraint. 02-Oct-2017 04:09:06.4: AirMass estimates ready after 02-Oct-2017 09:13:51 UTC 02-Oct-2017 04:09:06.5: Obs SDSSJ121027+502735 in Plan SDSSJ121027+502735 was vetoed by AirMass constraint. 02-Oct-2017 04:09:06.5: AirMass estimates ready after 02-Oct-2017 13:08:41 UTC 02-Oct-2017 04:09:06.5: Obs T_Ori in Plan T_Ori was vetoed by AirmassRange constraint. 02-Oct-2017 04:09:06.5: AirmassRange estimates ready after 02-Oct-2017 08:40:52 UTC 02-Oct-2017 04:09:06.5: Obs U_Aqr in Plan U_Aqr was vetoed by MoonAvoid constraint. 02-Oct-2017 04:09:06.5: MoonAvoid estimates ready after 02-Oct-2017 16:09:06 UTC 02-Oct-2017 04:09:06.5: Obs V0603_Per in Plan V0603_Per was vetoed by AirmassRange constraint. 02-Oct-2017 04:09:06.5: AirmassRange estimates ready after 02-Oct-2017 04:46:05 UTC 02-Oct-2017 04:09:06.5: Obs V0959_Mon in Plan V0959_Mon was vetoed by AirmassRange constraint. 02-Oct-2017 04:09:06.5: AirmassRange estimates ready after 02-Oct-2017 09:08:53 UTC 02-Oct-2017 04:09:06.5: Obs V0965_Per in Plan V0965_Per was vetoed by AirmassRange constraint. 02-Oct-2017 04:09:06.5: AirmassRange estimates ready after 02-Oct-2017 04:34:32 UTC 02-Oct-2017 04:09:06.5: Obs V841_Oph in Plan V841_Oph was vetoed by AirmassRange constraint. 02-Oct-2017 04:09:06.5: AirmassRange estimates ready after 02-Oct-2017 20:33:44 UTC 02-Oct-2017 04:09:06.5: Obs WZ_Per in Plan WZ_Per was vetoed by AirmassRange constraint. 02-Oct-2017 04:09:06.5: AirmassRange estimates ready after 02-Oct-2017 04:54:51 UTC 02-Oct-2017 04:09:06.5: No pending plans that can be started now. 02-Oct-2017 04:09:06.5: Dispatcher cycle time: 0.4860278 sec. 02-Oct-2017 04:09:36.9: ++ Auto Focus ++ 02-Oct-2017 04:09:36.9: Doing periodic autofocus. 02-Oct-2017 04:09:36.9: Start special ACP AutoFocus script for scheduler 02-Oct-2017 04:09:46.9: Sequencer is now active 02-Oct-2017 04:09:51.9: Sequencer is no longer active 02-Oct-2017 04:09:51.9: ** Sequencer script "AutoFocusScheduler" failed: 02-Oct-2017 04:09:51.9: **Script Error (Tracking has been stopped)** Source: ACP.AcquireSupport Message: FilterInfo.txt has more offset entries than filters in the camera at line 38 column 5. Script Context: 35: Dim Filt, dtStart, buf, CT 36: 37: Set SUP = CreateObject("ACP.AcquireSupport") => 38: SUP.Initialize ^ 39: 40: dtStart = Util.SysUTCDate 41: buf = "AutoFocus-" & Util.FormatVar(dtStart, "dd-mmm-yyyy@HhNnSs") & ".log" ' Typical Log Name -- end -- 02-Oct-2017 04:09:51.9: ** Autofocus failed (n=2), will retry ASAP after 5 min. 02-Oct-2017 04:09:52.0: SelectRunning: No running plans 02-Oct-2017 04:09:52.0: No pending plans that can be started now. 02-Oct-2017 04:09:52.0: Dispatcher cycle time: 0.0120007 sec. 02-Oct-2017 04:10:22.1: SelectRunning: No running plans 02-Oct-2017 04:10:22.1: No pending plans that can be started now. 02-Oct-2017 04:10:22.1: Dispatcher cycle time: 0.0120007 sec. 02-Oct-2017 04:10:52.3: SelectRunning: No running plans 02-Oct-2017 04:10:52.3: No pending plans that can be started now. 02-Oct-2017 04:10:52.3: Dispatcher cycle time: 0.0120007 sec. 02-Oct-2017 04:11:22.4: SelectRunning: No running plans 02-Oct-2017 04:11:22.4: No pending plans that can be started now. 02-Oct-2017 04:11:22.4: Dispatcher cycle time: 0.0220012 sec. 02-Oct-2017 04:11:52.5: SelectRunning: No running plans 02-Oct-2017 04:11:52.5: No pending plans that can be started now. 02-Oct-2017 04:11:52.5: Dispatcher cycle time: 0.0120007 sec. 02-Oct-2017 04:12:22.7: SelectRunning: No running plans 02-Oct-2017 04:12:22.7: No pending plans that can be started now. 02-Oct-2017 04:12:22.7: Dispatcher cycle time: 0.0120007 sec. 02-Oct-2017 04:12:53.2: SelectRunning: No running plans 02-Oct-2017 04:12:53.2: No pending plans that can be started now. 02-Oct-2017 04:12:53.2: Dispatcher cycle time: 0.0110007 sec. 02-Oct-2017 04:13:34.3: SelectRunning: No running plans 02-Oct-2017 04:13:34.3: No pending plans that can be started now. 02-Oct-2017 04:13:34.3: Dispatcher cycle time: 0 sec. 02-Oct-2017 04:14:14.5: SelectRunning: No running plans 02-Oct-2017 04:14:14.5: No pending plans that can be started now. 02-Oct-2017 04:14:14.5: Dispatcher cycle time: 0 sec. 02-Oct-2017 04:14:52.2: SelectRunning: No running plans 02-Oct-2017 04:14:52.2: No pending plans that can be started now. 02-Oct-2017 04:14:52.2: Dispatcher cycle time: 0 sec. 02-Oct-2017 04:15:25.9: ++ Auto Focus ++ 02-Oct-2017 04:15:25.9: Doing periodic autofocus. 02-Oct-2017 04:15:25.9: Start special ACP AutoFocus script for scheduler 02-Oct-2017 04:15:36.2: Sequencer is now active 02-Oct-2017 04:15:41.5: Sequencer is no longer active 02-Oct-2017 04:15:41.5: ** Sequencer script "AutoFocusScheduler" failed: 02-Oct-2017 04:15:41.5: **Script Error (Tracking has been stopped)** Source: ACP.AcquireSupport Message: FilterInfo.txt has more offset entries than filters in the camera at line 38 column 5. Script Context: 35: Dim Filt, dtStart, buf, CT 36: 37: Set SUP = CreateObject("ACP.AcquireSupport") => 38: SUP.Initialize ^ 39: 40: dtStart = Util.SysUTCDate 41: buf = "AutoFocus-" & Util.FormatVar(dtStart, "dd-mmm-yyyy@HhNnSs") & ".log" ' Typical Log Name -- end -- 02-Oct-2017 04:15:41.5: ** Autofocus failed (n=3), will retry ASAP after 10 min. 02-Oct-2017 04:15:42.3: SelectRunning: No running plans 02-Oct-2017 04:15:42.3: Efficiency: Only one pending plan FY_Cam, it is the "best" 02-Oct-2017 04:15:42.3: Dispatcher cycle time: 0.0468 sec. 02-Oct-2017 04:15:42.3: Acquire data for Observation FY_Cam... 02-Oct-2017 04:15:42.3: (belongs to Project AAVSO_HGUA_Miras_Manual_ver167, Plan FY_Cam) 02-Oct-2017 04:15:42.3: Send Observation FY_Cam to ACP Sequencer 02-Oct-2017 04:15:52.4: Sequencer is now active 02-Oct-2017 04:15:58.0: Sequencer is no longer active 02-Oct-2017 04:15:58.0: ** Sequencer script "AcquireScheduler" failed: 02-Oct-2017 04:15:58.0: **Script Error (Tracking has been stopped)** Source: ACP.AcquireSupport Message: FilterInfo.txt has more offset entries than filters in the camera at line 792 column 5. Script Context: 789: ' Support library 790: ' 791: Set SUP = CreateObject("ACP.AcquireSupport") ' Support methods here => 792: Call SUP.Initialize() ' Do initialization (may log) ^ 793: 794: cdir = ACPApp.Path & "\Calibration" ' Cal library directory 795: -- end -- 02-Oct-2017 04:16:01.1: Post-job status check done (stat=Running) 02-Oct-2017 04:16:01.1: ACP ERROR: Run failed due to script error (see ACP run log) 02-Oct-2017 04:16:01.1: ACP data acquisition failed for Observation FY_Cam. 02-Oct-2017 04:16:01.1: (Observation FY_Cam interrupted by acquisition process failure.) 02-Oct-2017 04:16:03.1: SelectRunning: No running plans 02-Oct-2017 04:16:03.1: No pending plans that can be started now. 02-Oct-2017 04:16:03.1: Dispatcher cycle time: 0.0156 sec. 02-Oct-2017 04:16:41.4: SelectRunning: No running plans 02-Oct-2017 04:16:41.4: No pending plans that can be started now. 02-Oct-2017 04:16:41.4: Dispatcher cycle time: 0 sec. 02-Oct-2017 04:17:17.7: SelectRunning: No running plans 02-Oct-2017 04:17:17.7: No pending plans that can be started now. 02-Oct-2017 04:17:17.7: Dispatcher cycle time: 0 sec. 02-Oct-2017 04:17:54.2: SelectRunning: No running plans 02-Oct-2017 04:17:54.2: No pending plans that can be started now. 02-Oct-2017 04:17:54.2: Dispatcher cycle time: 0 sec. 02-Oct-2017 04:18:28.0: SelectRunning: No running plans 02-Oct-2017 04:18:28.0: No pending plans that can be started now. 02-Oct-2017 04:18:28.0: Dispatcher cycle time: 0 sec. 02-Oct-2017 04:19:02.4: SelectRunning: No running plans 02-Oct-2017 04:19:02.4: No pending plans that can be started now. 02-Oct-2017 04:19:02.4: Dispatcher cycle time: 0 sec. 02-Oct-2017 04:19:33.0: SelectRunning: No running plans 02-Oct-2017 04:19:33.0: No pending plans that can be started now. 02-Oct-2017 04:19:33.0: Dispatcher cycle time: 0.0156 sec. 02-Oct-2017 04:20:10.7: SelectRunning: No running plans 02-Oct-2017 04:20:10.7: No pending plans that can be started now. 02-Oct-2017 04:20:10.7: Dispatcher cycle time: 0 sec. 02-Oct-2017 04:20:46.4: SelectRunning: No running plans 02-Oct-2017 04:20:46.4: No pending plans that can be started now. 02-Oct-2017 04:20:46.4: Dispatcher cycle time: 0 sec. 02-Oct-2017 04:21:24.1: SelectRunning: No running plans 02-Oct-2017 04:21:24.1: No pending plans that can be started now. 02-Oct-2017 04:21:24.1: Dispatcher cycle time: 0 sec. 02-Oct-2017 04:22:05.4: SelectRunning: No running plans 02-Oct-2017 04:22:05.4: No pending plans that can be started now. 02-Oct-2017 04:22:05.4: Dispatcher cycle time: 0 sec. 02-Oct-2017 04:22:44.0: SelectRunning: No running plans 02-Oct-2017 04:22:44.0: No pending plans that can be started now. 02-Oct-2017 04:22:44.0: Dispatcher cycle time: 0 sec. 02-Oct-2017 04:23:22.4: SelectRunning: No running plans 02-Oct-2017 04:23:22.4: No pending plans that can be started now. 02-Oct-2017 04:23:22.4: Dispatcher cycle time: 0 sec. 02-Oct-2017 04:23:59.4: SelectRunning: No running plans 02-Oct-2017 04:23:59.4: No pending plans that can be started now. 02-Oct-2017 04:23:59.4: Dispatcher cycle time: 0 sec. 02-Oct-2017 04:24:30.6: SelectRunning: No running plans 02-Oct-2017 04:24:30.6: No pending plans that can be started now. 02-Oct-2017 04:24:30.6: Dispatcher cycle time: 0.0156001 sec. 02-Oct-2017 04:25:04.4: SelectRunning: No running plans 02-Oct-2017 04:25:04.4: No pending plans that can be started now. 02-Oct-2017 04:25:04.4: Dispatcher cycle time: 0 sec. 02-Oct-2017 04:25:43.6: ++ Auto Focus ++ 02-Oct-2017 04:25:43.6: Doing periodic autofocus. 02-Oct-2017 04:25:43.6: Start special ACP AutoFocus script for scheduler 02-Oct-2017 04:25:54.0: Sequencer is now active 02-Oct-2017 04:25:59.0: Sequencer is no longer active 02-Oct-2017 04:25:59.0: ** Sequencer script "AutoFocusScheduler" failed: 02-Oct-2017 04:25:59.0: **Script Error (Tracking has been stopped)** Source: ACP.AcquireSupport Message: FilterInfo.txt has more offset entries than filters in the camera at line 38 column 5. Script Context: 35: Dim Filt, dtStart, buf, CT 36: 37: Set SUP = CreateObject("ACP.AcquireSupport") => 38: SUP.Initialize ^ 39: 40: dtStart = Util.SysUTCDate 41: buf = "AutoFocus-" & Util.FormatVar(dtStart, "dd-mmm-yyyy@HhNnSs") & ".log" ' Typical Log Name -- end -- 02-Oct-2017 04:25:59.0: ** Autofocus failed (n=4), will retry ASAP after 15 min. 02-Oct-2017 04:26:01.1: SelectRunning: No running plans 02-Oct-2017 04:26:01.1: No pending plans that can be started now. 02-Oct-2017 04:26:01.1: Dispatcher cycle time: 0 sec. 02-Oct-2017 04:26:41.2: SelectRunning: No running plans 02-Oct-2017 04:26:41.2: No pending plans that can be started now. 02-Oct-2017 04:26:41.2: Dispatcher cycle time: 0 sec. 02-Oct-2017 04:27:20.8: SelectRunning: No running plans 02-Oct-2017 04:27:20.8: No pending plans that can be started now. 02-Oct-2017 04:27:20.8: Dispatcher cycle time: 0 sec. 02-Oct-2017 04:27:56.6: SelectRunning: No running plans 02-Oct-2017 04:27:56.6: No pending plans that can be started now. 02-Oct-2017 04:27:56.6: Dispatcher cycle time: 0 sec. 02-Oct-2017 04:28:32.3: SelectRunning: No running plans 02-Oct-2017 04:28:32.3: No pending plans that can be started now. 02-Oct-2017 04:28:32.3: Dispatcher cycle time: 0 sec. 02-Oct-2017 04:29:03.5: SelectRunning: No running plans 02-Oct-2017 04:29:03.5: No pending plans that can be started now. 02-Oct-2017 04:29:03.5: Dispatcher cycle time: 0.0156 sec. 02-Oct-2017 04:29:41.2: SelectRunning: No running plans 02-Oct-2017 04:29:41.2: No pending plans that can be started now. 02-Oct-2017 04:29:41.2: Dispatcher cycle time: 0 sec. 02-Oct-2017 04:30:18.9: SelectRunning: No running plans 02-Oct-2017 04:30:18.9: No pending plans that can be started now. 02-Oct-2017 04:30:18.9: Dispatcher cycle time: 0.0156 sec. 02-Oct-2017 04:30:56.6: SelectRunning: No running plans 02-Oct-2017 04:30:56.6: No pending plans that can be started now. 02-Oct-2017 04:30:56.6: Dispatcher cycle time: 0 sec. 02-Oct-2017 04:31:34.3: SelectRunning: No running plans 02-Oct-2017 04:31:34.3: No pending plans that can be started now. 02-Oct-2017 04:31:34.3: Dispatcher cycle time: 0 sec. 02-Oct-2017 04:32:14.6: SelectRunning: No running plans 02-Oct-2017 04:32:14.6: No pending plans that can be started now. 02-Oct-2017 04:32:14.6: Dispatcher cycle time: 0 sec. 02-Oct-2017 04:32:52.3: SelectRunning: No running plans 02-Oct-2017 04:32:52.3: No pending plans that can be started now. 02-Oct-2017 04:32:52.3: Dispatcher cycle time: 0 sec. 02-Oct-2017 04:33:23.5: SelectRunning: No running plans 02-Oct-2017 04:33:23.5: No pending plans that can be started now. 02-Oct-2017 04:33:23.5: Dispatcher cycle time: 0.0156001 sec. 02-Oct-2017 04:34:01.1: SelectRunning: No running plans 02-Oct-2017 04:34:01.1: No pending plans that can be started now. 02-Oct-2017 04:34:01.1: Dispatcher cycle time: 0 sec. 02-Oct-2017 04:34:36.9: SelectRunning: No running plans 02-Oct-2017 04:34:36.9: Efficiency: Only one pending plan V0965_Per, it is the "best" 02-Oct-2017 04:34:36.9: Dispatcher cycle time: 0.0468001 sec. 02-Oct-2017 04:34:36.9: Acquire data for Observation V0965_Per... 02-Oct-2017 04:34:36.9: (belongs to Project Nova_Monitoring, Plan V0965_Per) 02-Oct-2017 04:34:36.9: Send Observation V0965_Per to ACP Sequencer 02-Oct-2017 04:34:46.9: Sequencer is now active 02-Oct-2017 04:34:52.2: Sequencer is no longer active 02-Oct-2017 04:34:52.2: ** Sequencer script "AcquireScheduler" failed: 02-Oct-2017 04:34:52.2: **Script Error (Tracking has been stopped)** Source: ACP.AcquireSupport Message: FilterInfo.txt has more offset entries than filters in the camera at line 792 column 5. Script Context: 789: ' Support library 790: ' 791: Set SUP = CreateObject("ACP.AcquireSupport") ' Support methods here => 792: Call SUP.Initialize() ' Do initialization (may log) ^ 793: 794: cdir = ACPApp.Path & "\Calibration" ' Cal library directory 795: -- end -- 02-Oct-2017 04:34:55.3: Post-job status check done (stat=Running) 02-Oct-2017 04:34:55.3: ACP ERROR: Run failed due to script error (see ACP run log) 02-Oct-2017 04:34:55.3: ACP data acquisition failed for Observation V0965_Per. 02-Oct-2017 04:34:55.3: (Observation V0965_Per interrupted by acquisition process failure.) 02-Oct-2017 04:35:04.2: SelectRunning: No running plans 02-Oct-2017 04:35:04.2: No pending plans that can be started now. 02-Oct-2017 04:35:04.2: Dispatcher cycle time: 0 sec. 02-Oct-2017 04:35:35.4: SelectRunning: No running plans 02-Oct-2017 04:35:35.4: No pending plans that can be started now. 02-Oct-2017 04:35:35.4: Dispatcher cycle time: 0.0156 sec. 02-Oct-2017 04:36:13.7: SelectRunning: No running plans 02-Oct-2017 04:36:13.7: No pending plans that can be started now. 02-Oct-2017 04:36:13.7: Dispatcher cycle time: 0 sec. 02-Oct-2017 04:36:53.4: SelectRunning: No running plans 02-Oct-2017 04:36:53.4: No pending plans that can be started now. 02-Oct-2017 04:36:53.4: Dispatcher cycle time: 0 sec. 02-Oct-2017 04:37:24.6: SelectRunning: No running plans 02-Oct-2017 04:37:24.6: No pending plans that can be started now. 02-Oct-2017 04:37:24.6: Dispatcher cycle time: 0.0156 sec. 02-Oct-2017 04:38:02.2: SelectRunning: No running plans 02-Oct-2017 04:38:02.2: No pending plans that can be started now. 02-Oct-2017 04:38:02.2: Dispatcher cycle time: 0 sec. 02-Oct-2017 04:38:39.3: SelectRunning: No running plans 02-Oct-2017 04:38:39.3: No pending plans that can be started now. 02-Oct-2017 04:38:39.3: Dispatcher cycle time: 0 sec. 02-Oct-2017 04:39:20.2: SelectRunning: No running plans 02-Oct-2017 04:39:20.6: No pending plans that can be started now. 02-Oct-2017 04:39:20.6: Dispatcher cycle time: 0.3588007 sec. 02-Oct-2017 04:39:51.4: SelectRunning: No running plans 02-Oct-2017 04:39:51.4: No pending plans that can be started now. 02-Oct-2017 04:39:51.4: Dispatcher cycle time: 0.0156 sec. 02-Oct-2017 04:40:29.0: SelectRunning: No running plans 02-Oct-2017 04:40:29.0: No pending plans that can be started now. 02-Oct-2017 04:40:29.0: Dispatcher cycle time: 0 sec. 02-Oct-2017 04:41:08.2: ++ Auto Focus ++ 02-Oct-2017 04:41:08.2: Doing periodic autofocus. 02-Oct-2017 04:41:08.2: Start special ACP AutoFocus script for scheduler 02-Oct-2017 04:41:18.5: Sequencer is now active 02-Oct-2017 04:41:23.8: Sequencer is no longer active 02-Oct-2017 04:41:23.8: ** Sequencer script "AutoFocusScheduler" failed: 02-Oct-2017 04:41:23.8: **Script Error (Tracking has been stopped)** Source: ACP.AcquireSupport Message: FilterInfo.txt has more offset entries than filters in the camera at line 38 column 5. Script Context: 35: Dim Filt, dtStart, buf, CT 36: 37: Set SUP = CreateObject("ACP.AcquireSupport") => 38: SUP.Initialize ^ 39: 40: dtStart = Util.SysUTCDate 41: buf = "AutoFocus-" & Util.FormatVar(dtStart, "dd-mmm-yyyy@HhNnSs") & ".log" ' Typical Log Name -- end -- 02-Oct-2017 04:41:23.8: ** Autofocus failed (n=5), will retry ASAP after 20 min. 02-Oct-2017 04:41:25.9: SelectRunning: No running plans 02-Oct-2017 04:41:25.9: No pending plans that can be started now. 02-Oct-2017 04:41:25.9: Dispatcher cycle time: 0 sec. 02-Oct-2017 04:42:01.3: SelectRunning: No running plans 02-Oct-2017 04:42:01.3: No pending plans that can be started now. 02-Oct-2017 04:42:01.3: Dispatcher cycle time: 0 sec. 02-Oct-2017 04:42:39.5: SelectRunning: No running plans 02-Oct-2017 04:42:39.5: No pending plans that can be started now. 02-Oct-2017 04:42:39.5: Dispatcher cycle time: 0.0156 sec. 02-Oct-2017 04:43:10.8: SelectRunning: No running plans 02-Oct-2017 04:43:10.8: Efficiency: Only one pending plan omi_Cet, it is the "best" 02-Oct-2017 04:43:10.9: Dispatcher cycle time: 0.0468 sec. 02-Oct-2017 04:43:10.9: Acquire data for Observation omi_Cet... 02-Oct-2017 04:43:10.9: (belongs to Project AAVSO_Monitoring, Plan omi_Cet) 02-Oct-2017 04:43:10.9: Send Observation omi_Cet to ACP Sequencer 02-Oct-2017 04:43:20.9: Sequencer is now active 02-Oct-2017 04:43:26.1: Sequencer is no longer active 02-Oct-2017 04:43:26.1: ** Sequencer script "AcquireScheduler" failed: 02-Oct-2017 04:43:26.1: **Script Error (Tracking has been stopped)** Source: ACP.AcquireSupport Message: FilterInfo.txt has more offset entries than filters in the camera at line 792 column 5. Script Context: 789: ' Support library 790: ' 791: Set SUP = CreateObject("ACP.AcquireSupport") ' Support methods here => 792: Call SUP.Initialize() ' Do initialization (may log) ^ 793: 794: cdir = ACPApp.Path & "\Calibration" ' Cal library directory 795: -- end -- 02-Oct-2017 04:43:29.2: Post-job status check done (stat=Running) 02-Oct-2017 04:43:29.2: ACP ERROR: Run failed due to script error (see ACP run log) 02-Oct-2017 04:43:29.2: ACP data acquisition failed for Observation omi_Cet. 02-Oct-2017 04:43:29.2: (Observation omi_Cet interrupted by acquisition process failure.) 02-Oct-2017 04:43:29.7: SelectRunning: No running plans 02-Oct-2017 04:43:29.7: No pending plans that can be started now. 02-Oct-2017 04:43:29.7: Dispatcher cycle time: 0.0156 sec. 02-Oct-2017 04:44:05.3: SelectRunning: No running plans 02-Oct-2017 04:44:05.3: No pending plans that can be started now. 02-Oct-2017 04:44:05.3: Dispatcher cycle time: 0 sec. 02-Oct-2017 04:44:45.0: SelectRunning: No running plans 02-Oct-2017 04:44:45.0: No pending plans that can be started now. 02-Oct-2017 04:44:45.0: Dispatcher cycle time: 0 sec. 02-Oct-2017 04:45:15.7: SelectRunning: No running plans 02-Oct-2017 04:45:15.7: No pending plans that can be started now. 02-Oct-2017 04:45:15.7: Dispatcher cycle time: 0.0156 sec. 02-Oct-2017 04:45:54.5: SelectRunning: No running plans 02-Oct-2017 04:45:54.5: No pending plans that can be started now. 02-Oct-2017 04:45:54.5: Dispatcher cycle time: 0 sec. 02-Oct-2017 04:46:25.7: SelectRunning: No running plans 02-Oct-2017 04:46:25.7: Efficiency: Only one pending plan V0603_Per, it is the "best" 02-Oct-2017 04:46:25.8: Dispatcher cycle time: 0.0468001 sec. 02-Oct-2017 04:46:25.8: Acquire data for Observation V0603_Per... 02-Oct-2017 04:46:25.8: (belongs to Project AAVSO_HGUA_Miras_Manual_ver167, Plan V0603_Per) 02-Oct-2017 04:46:25.8: Send Observation V0603_Per to ACP Sequencer 02-Oct-2017 04:46:35.8: Sequencer is now active 02-Oct-2017 04:46:40.9: Sequencer is no longer active 02-Oct-2017 04:46:40.9: ** Sequencer script "AcquireScheduler" failed: 02-Oct-2017 04:46:40.9: **Script Error (Tracking has been stopped)** Source: ACP.AcquireSupport Message: FilterInfo.txt has more offset entries than filters in the camera at line 792 column 5. Script Context: 789: ' Support library 790: ' 791: Set SUP = CreateObject("ACP.AcquireSupport") ' Support methods here => 792: Call SUP.Initialize() ' Do initialization (may log) ^ 793: 794: cdir = ACPApp.Path & "\Calibration" ' Cal library directory 795: -- end -- 02-Oct-2017 04:46:44.0: Post-job status check done (stat=Running) 02-Oct-2017 04:46:44.0: ACP ERROR: Run failed due to script error (see ACP run log) 02-Oct-2017 04:46:44.0: ACP data acquisition failed for Observation V0603_Per. 02-Oct-2017 04:46:44.0: (Observation V0603_Per interrupted by acquisition process failure.) 02-Oct-2017 04:46:49.1: SelectRunning: No running plans 02-Oct-2017 04:46:49.1: No pending plans that can be started now. 02-Oct-2017 04:46:49.1: Dispatcher cycle time: 0 sec. 02-Oct-2017 04:47:26.8: SelectRunning: No running plans 02-Oct-2017 04:47:26.8: No pending plans that can be started now. 02-Oct-2017 04:47:26.8: Dispatcher cycle time: 0.0156001 sec. 02-Oct-2017 04:47:59.9: SelectRunning: No running plans 02-Oct-2017 04:47:59.9: No pending plans that can be started now. 02-Oct-2017 04:47:59.9: Dispatcher cycle time: 0 sec. 02-Oct-2017 04:48:35.7: SelectRunning: No running plans 02-Oct-2017 04:48:35.7: No pending plans that can be started now. 02-Oct-2017 04:48:35.7: Dispatcher cycle time: 0 sec. 02-Oct-2017 04:49:11.4: SelectRunning: No running plans 02-Oct-2017 04:49:11.4: No pending plans that can be started now. 02-Oct-2017 04:49:11.4: Dispatcher cycle time: 0 sec. 02-Oct-2017 04:49:49.1: SelectRunning: No running plans 02-Oct-2017 04:49:49.1: No pending plans that can be started now. 02-Oct-2017 04:49:49.1: Dispatcher cycle time: 0 sec. 02-Oct-2017 04:50:26.8: SelectRunning: No running plans 02-Oct-2017 04:50:26.8: No pending plans that can be started now. 02-Oct-2017 04:50:26.8: Dispatcher cycle time: 0 sec. 02-Oct-2017 04:50:46.0: Dispatcher stopped at 02-Oct-2017 04:50:46 UTC 02-Oct-2017 04:50:46.0: **DISPATCHER INTERRUPT LEVEL 1 RECEIVED** 02-Oct-2017 04:50:50.1: Dispatcher started at 02-Oct-2017 04:50:50 UTC 02-Oct-2017 04:50:50.1: Priority: W[0]=0.00 02-Oct-2017 04:50:50.1: Transit Altitude: W[1]=0.70 02-Oct-2017 04:50:50.1: Highest Altitude: W[6]=0.00 02-Oct-2017 04:50:50.1: Lateness: W[5]=0.00 02-Oct-2017 04:50:50.1: Slew Distance: W[2]=0.00 02-Oct-2017 04:50:50.1: Retry Count: W[3]=0.20 02-Oct-2017 04:50:50.1: Meridian Crossing: W[4]=0.00 02-Oct-2017 04:50:50.1: Obs Conditions: W[7]=0.40 02-Oct-2017 04:50:50.1: Rising Plan Delay: disabled 02-Oct-2017 04:50:54.0: ++ Observatory Startup ++ 02-Oct-2017 04:50:54.0: Start ACP Sequencer's StartupObs script 02-Oct-2017 04:51:04.1: Sequencer is now active 02-Oct-2017 04:51:09.1: Sequencer is no longer active 02-Oct-2017 04:51:12.9: ++ Auto Focus ++ 02-Oct-2017 04:51:12.9: Doing initial autofocus. 02-Oct-2017 04:51:12.9: Start special ACP AutoFocus script for scheduler 02-Oct-2017 04:51:22.9: Sequencer is now active 02-Oct-2017 04:51:28.3: Sequencer is no longer active 02-Oct-2017 04:51:28.3: ** Sequencer script "AutoFocusScheduler" failed: 02-Oct-2017 04:51:28.3: **Script Error (Tracking has been stopped)** Source: ACP.AcquireSupport Message: FilterInfo.txt has more offset entries than filters in the camera at line 38 column 5. Script Context: 35: Dim Filt, dtStart, buf, CT 36: 37: Set SUP = CreateObject("ACP.AcquireSupport") => 38: SUP.Initialize ^ 39: 40: dtStart = Util.SysUTCDate 41: buf = "AutoFocus-" & Util.FormatVar(dtStart, "dd-mmm-yyyy@HhNnSs") & ".log" ' Typical Log Name -- end -- 02-Oct-2017 04:51:28.3: ** Autofocus failed (n=1), will retry ASAP after 0 min. 02-Oct-2017 04:51:29.8: SelectRunning: No running plans 02-Oct-2017 04:51:29.8: No pending plans that can be started now. 02-Oct-2017 04:51:29.8: Dispatcher cycle time: 0 sec. 02-Oct-2017 04:52:07.5: ++ Auto Focus ++ 02-Oct-2017 04:52:07.5: Doing periodic autofocus. 02-Oct-2017 04:52:07.5: Start special ACP AutoFocus script for scheduler 02-Oct-2017 04:52:17.5: Sequencer is now active 02-Oct-2017 04:52:22.9: Sequencer is no longer active 02-Oct-2017 04:52:22.9: ** Sequencer script "AutoFocusScheduler" failed: 02-Oct-2017 04:52:22.9: **Script Error (Tracking has been stopped)** Source: ACP.AcquireSupport Message: FilterInfo.txt has more offset entries than filters in the camera at line 38 column 5. Script Context: 35: Dim Filt, dtStart, buf, CT 36: 37: Set SUP = CreateObject("ACP.AcquireSupport") => 38: SUP.Initialize ^ 39: 40: dtStart = Util.SysUTCDate 41: buf = "AutoFocus-" & Util.FormatVar(dtStart, "dd-mmm-yyyy@HhNnSs") & ".log" ' Typical Log Name -- end -- 02-Oct-2017 04:52:22.9: ** Autofocus failed (n=2), will retry ASAP after 5 min. 02-Oct-2017 04:52:24.9: SelectRunning: No running plans 02-Oct-2017 04:52:24.9: No pending plans that can be started now. 02-Oct-2017 04:52:24.9: Dispatcher cycle time: 0 sec. 02-Oct-2017 04:53:02.0: SelectRunning: No running plans 02-Oct-2017 04:53:02.0: No pending plans that can be started now. 02-Oct-2017 04:53:02.0: Dispatcher cycle time: 0 sec. 02-Oct-2017 04:53:42.3: SelectRunning: No running plans 02-Oct-2017 04:53:42.3: No pending plans that can be started now. 02-Oct-2017 04:53:42.3: Dispatcher cycle time: 0 sec. 02-Oct-2017 04:54:17.4: SelectRunning: No running plans 02-Oct-2017 04:54:17.4: No pending plans that can be started now. 02-Oct-2017 04:54:17.4: Dispatcher cycle time: 0 sec. 02-Oct-2017 04:54:53.1: SelectRunning: No running plans 02-Oct-2017 04:54:53.1: Efficiency: Only one pending plan WZ_Per, it is the "best" 02-Oct-2017 04:54:53.2: Dispatcher cycle time: 0.0624001 sec. 02-Oct-2017 04:54:53.2: Acquire data for Observation WZ_Per... 02-Oct-2017 04:54:53.2: (belongs to Project AAVSO_HGUA_Miras_Manual_ver167, Plan WZ_Per) 02-Oct-2017 04:54:53.2: Send Observation WZ_Per to ACP Sequencer 02-Oct-2017 04:55:03.2: Sequencer is now active 02-Oct-2017 04:55:08.2: Sequencer is no longer active 02-Oct-2017 04:55:08.2: ** Sequencer script "AcquireScheduler" failed: 02-Oct-2017 04:55:08.2: **Script Error (Tracking has been stopped)** Source: ACP.AcquireSupport Message: FilterInfo.txt has more offset entries than filters in the camera at line 792 column 5. Script Context: 789: ' Support library 790: ' 791: Set SUP = CreateObject("ACP.AcquireSupport") ' Support methods here => 792: Call SUP.Initialize() ' Do initialization (may log) ^ 793: 794: cdir = ACPApp.Path & "\Calibration" ' Cal library directory 795: -- end -- 02-Oct-2017 04:55:11.3: Post-job status check done (stat=Running) 02-Oct-2017 04:55:11.3: ACP ERROR: Run failed due to script error (see ACP run log) 02-Oct-2017 04:55:11.3: ACP data acquisition failed for Observation WZ_Per. 02-Oct-2017 04:55:11.3: (Observation WZ_Per interrupted by acquisition process failure.) 02-Oct-2017 04:55:21.0: **Dome closing when asked to open. Assuming weather closure. 02-Oct-2017 04:55:23.5: **EXCEPTION IN SCHEDULER: 02-Oct-2017 04:55:23.5: **Dome failed to open for reason other than hardware weather closure 02-Oct-2017 04:55:23.5: Traceback: at DC3.Scheduler.Engine.DoStartupIf(Boolean openDome) at DC3.Scheduler.Engine.Run() 02-Oct-2017 04:55:24.0: Run statistics: 02-Oct-2017 04:55:24.0: Observations: 52 02-Oct-2017 04:55:24.0: Considered: 35 02-Oct-2017 04:55:24.0: Completed: 0 02-Oct-2017 04:55:24.0: Skipped: 0 02-Oct-2017 04:55:24.0: Never Eligible: 17 02-Oct-2017 04:55:24.0: Failed: 35 02-Oct-2017 04:55:24.0: Shutter-Open efficiency: 0.00% 02-Oct-2017 04:55:24.0: Overall Efficiency: 4.10% 02-Oct-2017 04:55:24.0: Release ACP sequencer Log closed at Mon, Oct 02 2017 04:55:25 UTC (actual time)