Log opened at Fri, Jul 09 2021 03:21:00 UTC (actual time)
Current log level is Debug
ACP Expert Scheduler version 8.2.3
Full license: Arne Henden
2021-07-09 03:21:00.6: ++ Observatory Startup ++
2021-07-09 03:21:00.6: Start ACP Sequencer's StartupObs script
2021-07-09 03:21:00.6: ACP.RunScript(StartupObs, ):
2021-07-09 03:21:00.6:   Script ACP-Weather is loaded, and
2021-07-09 03:21:00.6:   the console is idle.
2021-07-09 03:21:00.6:   In start loop, i = 0
2021-07-09 03:21:00.6:   Console is now idle
2021-07-09 03:21:00.9:   Loaded C:\Users\Public\Documents\ACP Config\Scheduler\StartupObs.js successfully
2021-07-09 03:21:00.9:   Script started successfully
2021-07-09 03:21:10.9: Sequencer is now active
2021-07-09 03:22:02.3: Sequencer is no longer active
2021-07-09 03:22:02.3: Open the observatory dome/roof
2021-07-09 03:23:33.1: Observatory startup succeeded, and dome opened successfully
2021-07-09 03:23:33.1: ++ Auto Focus ++
2021-07-09 03:23:33.1: Doing initial autofocus.
2021-07-09 03:23:33.1: Start special ACP AutoFocus script for scheduler
2021-07-09 03:23:33.1: ACP.RunScript(AutoFocusScheduler, ):
2021-07-09 03:23:33.1:   Script StartupObs is loaded, and
2021-07-09 03:23:33.1:   the console is idle.
2021-07-09 03:23:33.1:   In start loop, i = 0
2021-07-09 03:23:33.1:   Console is now idle
2021-07-09 03:23:33.2:   Loaded C:\Program Files (x86)\ACP Scheduler\AutoFocusScheduler.vbs successfully
2021-07-09 03:23:33.2:   Script started successfully
2021-07-09 03:23:43.2: Sequencer is now active
2021-07-09 03:31:44.4: Sequencer is no longer active
2021-07-09 03:31:44.6: ** ACP script "AutoFocusScheduler" failed:
2021-07-09 03:31:44.6: **Script Error**
Source:   ACP
Message:  The script was aborted.
Location: line 81 column 2.

2021-07-09 03:31:44.6: ** Autofocus failed (n=1), will retry ASAP after 0 min.
2021-07-09 03:31:46.1: Plan NGC_7089 has Monitor Mode. Time to resubmit it.
2021-07-09 03:31:46.1: Plan FY_Aql has Monitor Mode. Time to resubmit it.
2021-07-09 03:31:46.1: SelectRunning: No running plans
2021-07-09 03:31:46.1: Obs FY_Aql in Plan FY_Aql was vetoed by AirmassRange constraint.
2021-07-09 03:31:46.1:     AirmassRange estimates ready after 09-Jul-2021 03:39:20 UTC
2021-07-09 03:31:46.1: [AirmassRange] Target will never be below the maximum air mass
2021-07-09 03:31:46.1: Obs HP_1 in Plan HP_1 was failed by AirmassRange constraint.
2021-07-09 03:31:46.2: [AirmassRange] Target will never be below the maximum air mass
2021-07-09 03:31:46.2: Obs NGC_1261 in Plan NGC_1261 was failed by AirmassRange constraint.
2021-07-09 03:31:46.2: Obs NGC_1904 in Plan NGC_1904 was vetoed by AirmassRange constraint.
2021-07-09 03:31:46.2:     AirmassRange estimates ready after 09-Jul-2021 15:57:58 UTC
2021-07-09 03:31:46.2: Obs NGC_288 in Plan NGC_288 was vetoed by AirmassRange constraint.
2021-07-09 03:31:46.2:     AirmassRange estimates ready after 09-Jul-2021 11:58:36 UTC
2021-07-09 03:31:46.2: Obs NGC_4590 in Plan NGC_4590 was vetoed by AirmassRange constraint.
2021-07-09 03:31:46.2:     AirmassRange estimates ready after 09-Jul-2021 23:46:53 UTC
2021-07-09 03:31:46.2: [AirmassRange] Target will never be below the maximum air mass
2021-07-09 03:31:46.2: Obs NGC_5286 in Plan NGC_5286 was failed by AirmassRange constraint.
2021-07-09 03:31:46.2: [AirmassRange] Target will never be below the maximum air mass
2021-07-09 03:31:46.2: Obs NGC_5824 in Plan NGC_5824 was failed by AirmassRange constraint.
2021-07-09 03:31:46.3: [AirmassRange] Target will never be below the maximum air mass
2021-07-09 03:31:46.3: Obs NGC_5946 in Plan NGC_5946 was failed by AirmassRange constraint.
2021-07-09 03:31:46.3: [AirmassRange] Target will never be below the maximum air mass
2021-07-09 03:31:46.3: Obs NGC_6139 in Plan NGC_6139 was failed by AirmassRange constraint.
2021-07-09 03:31:46.4: Obs NGC_6273 in Plan NGC_6273 was vetoed by AirmassRange constraint.
2021-07-09 03:31:46.4:     AirmassRange estimates ready after 09-Jul-2021 04:03:47 UTC
2021-07-09 03:31:46.4: Obs NGC_6284 in Plan NGC_6284 was vetoed by AirmassRange constraint.
2021-07-09 03:31:46.4:     AirmassRange estimates ready after 09-Jul-2021 03:43:14 UTC
2021-07-09 03:31:46.4: Obs NGC_6293 in Plan NGC_6293 was vetoed by AirmassRange constraint.
2021-07-09 03:31:46.4:     AirmassRange estimates ready after 09-Jul-2021 04:17:21 UTC
2021-07-09 03:31:46.4: [AirmassRange] Target will never be below the maximum air mass
2021-07-09 03:31:46.4: Obs NGC_6380 in Plan NGC_6380 was failed by AirmassRange constraint.
2021-07-09 03:31:46.4: [AirmassRange] Target will never be below the maximum air mass
2021-07-09 03:31:46.4: Obs NGC_6388 in Plan NGC_6388 was failed by AirmassRange constraint.
2021-07-09 03:31:46.4: Obs NGC_6401 in Plan NGC_6401 was vetoed by AirmassRange constraint.
2021-07-09 03:31:46.4:     AirmassRange estimates ready after 09-Jul-2021 04:07:15 UTC
2021-07-09 03:31:46.5: [AirmassRange] Target will never be below the maximum air mass
2021-07-09 03:31:46.5: Obs NGC_6441 in Plan NGC_6441 was failed by AirmassRange constraint.
2021-07-09 03:31:46.5: Obs NGC_6544 in Plan NGC_6544 was vetoed by AirmassRange constraint.
2021-07-09 03:31:46.5:     AirmassRange estimates ready after 09-Jul-2021 04:48:55 UTC
2021-07-09 03:31:46.5: Obs NGC_6553 in Plan NGC_6553 was vetoed by AirmassRange constraint.
2021-07-09 03:31:46.5:     AirmassRange estimates ready after 09-Jul-2021 05:04:07 UTC
2021-07-09 03:31:46.5: [AirmassRange] Target will never be below the maximum air mass
2021-07-09 03:31:46.5: Obs NGC_6558 in Plan NGC_6558 was failed by AirmassRange constraint.
2021-07-09 03:31:46.5: [AirmassRange] Target will never be below the maximum air mass
2021-07-09 03:31:46.5: Obs NGC_6569 in Plan NGC_6569 was failed by AirmassRange constraint.
2021-07-09 03:31:46.5: Obs NGC_6626 in Plan NGC_6626 was vetoed by AirmassRange constraint.
2021-07-09 03:31:46.5:     AirmassRange estimates ready after 09-Jul-2021 05:04:25 UTC
2021-07-09 03:31:46.6: Obs NGC_6638 in Plan NGC_6638 was vetoed by AirmassRange constraint.
2021-07-09 03:31:46.6:     AirmassRange estimates ready after 09-Jul-2021 05:19:22 UTC
2021-07-09 03:31:46.6: Obs NGC_6642 in Plan NGC_6642 was vetoed by AirmassRange constraint.
2021-07-09 03:31:46.6:     AirmassRange estimates ready after 09-Jul-2021 04:55:46 UTC
2021-07-09 03:31:46.6: Obs NGC_6656 in Plan NGC_6656 was vetoed by AirmassRange constraint.
2021-07-09 03:31:46.6:     AirmassRange estimates ready after 09-Jul-2021 05:04:49 UTC
2021-07-09 03:31:46.6: Obs NGC_6712 in Plan NGC_6712 was vetoed by AirmassRange constraint.
2021-07-09 03:31:46.6:     AirmassRange estimates ready after 09-Jul-2021 03:46:29 UTC
2021-07-09 03:31:46.6: [AirmassRange] Target will never be below the maximum air mass
2021-07-09 03:31:46.6: Obs NGC_6715 in Plan NGC_6715 was failed by AirmassRange constraint.
2021-07-09 03:31:46.6: Obs NGC_6717 in Plan NGC_6717 was vetoed by AirmassRange constraint.
2021-07-09 03:31:46.6:     AirmassRange estimates ready after 09-Jul-2021 05:11:17 UTC
2021-07-09 03:31:46.6: Obs NGC_6864 in Plan NGC_6864 was vetoed by AirmassRange constraint.
2021-07-09 03:31:46.6:     AirmassRange estimates ready after 09-Jul-2021 06:15:01 UTC
2021-07-09 03:31:46.6: Obs NGC_7089 in Plan NGC_7089 was vetoed by AirmassRange constraint.
2021-07-09 03:31:46.6:     AirmassRange estimates ready after 09-Jul-2021 05:58:03 UTC
2021-07-09 03:31:46.6: Obs NGC_7492 in Plan NGC_7492 was vetoed by AirmassRange constraint.
2021-07-09 03:31:46.6:     AirmassRange estimates ready after 09-Jul-2021 08:33:49 UTC
2021-07-09 03:31:46.7: [AirmassRange] Target will never be below the maximum air mass
2021-07-09 03:31:46.7: Obs Ter_1 in Plan Ter_1 was failed by AirmassRange constraint.
2021-07-09 03:31:46.7: Obs Ter_5 in Plan Ter_5 was vetoed by AirmassRange constraint.
2021-07-09 03:31:46.7:     AirmassRange estimates ready after 09-Jul-2021 04:26:54 UTC
2021-07-09 03:31:46.7: [AirmassRange] Target will never be below the maximum air mass
2021-07-09 03:31:46.7: Obs Ton_2 in Plan Ton_2 was failed by AirmassRange constraint.
2021-07-09 03:31:46.7: Plan ZTF21abfaohe couldn't be run before latest time, failed.
2021-07-09 03:31:46.7: Efficiency: Selecting from 15 pending plans:
2021-07-09 03:31:46.9:   Selected Plan NGC_5897 (TotEff=1.51)
2021-07-09 03:31:47.0: Dispatcher cycle time: 2.4 sec.
2021-07-09 03:31:47.0: Acquire data for Observation NGC_5897...
2021-07-09 03:31:47.0:   (belongs to Project AAVSO_Pgc_Welch_AUTO_ver2411, Plan NGC_5897)
2021-07-09 03:31:47.0: ++ Auto Focus ++
2021-07-09 03:31:47.1: Doing periodic autofocus.
2021-07-09 03:31:47.1: Start special ACP AutoFocus script for scheduler
2021-07-09 03:31:47.1: ACP.RunScript(AutoFocusScheduler, ):
2021-07-09 03:31:47.1:   Script AutoFocusScheduler is loaded, and
2021-07-09 03:31:47.1:   the console is idle.
2021-07-09 03:31:47.1:   In start loop, i = 0
2021-07-09 03:31:47.1:   Console is now idle
2021-07-09 03:31:47.1:   Loaded C:\Program Files (x86)\ACP Scheduler\AutoFocusScheduler.vbs successfully
2021-07-09 03:31:47.1:   Script started successfully
2021-07-09 03:31:57.1: Sequencer is now active
2021-07-09 03:36:20.1: **DISPATCHER INTERRUPT LEVEL 1 RECEIVED**
2021-07-09 03:36:20.7: Sequencer is no longer active
2021-07-09 03:36:20.7: ** ACP script "AutoFocusScheduler" failed:
2021-07-09 03:36:20.7: **Script Error**
Source:   ACP
Message:  The script was aborted.
Location: line 81 column 2.

2021-07-09 03:36:20.7: ** Autofocus failed (n=2), will retry ASAP after 5 min.
2021-07-09 03:36:20.7:    Obs NGC_5897 interrupted by dispatcher
2021-07-09 03:36:20.7: ACP data acquisition failed for Observation NGC_5897.
2021-07-09 03:36:20.7:   (Observation NGC_5897 interrupted by operator or system.)
2021-07-09 03:38:26.6: Requested shutdown of scheduler
2021-07-09 03:38:26.9: Run statistics:
2021-07-09 03:38:26.9:   Observations:   72
2021-07-09 03:38:26.9:   Considered:     1
2021-07-09 03:38:26.9:   Completed:      0
2021-07-09 03:38:26.9:   Skipped:        0
2021-07-09 03:38:26.9:   Never Eligible: 71
2021-07-09 03:38:26.9:   Failed:         1
2021-07-09 03:38:26.9: Shutter-Open efficiency: 0.00%
2021-07-09 03:38:26.9: Overall Efficiency: 0.00%
2021-07-09 03:38:26.9: Release ACP sequencer
Log closed at Fri, Jul 09 2021 03:38:28 UTC (actual time)