Log opened at Sat, Nov 20 2021 03:54:03 UTC (actual time)
Current log level is Normal
ACP Expert Scheduler Engine Version 8.3.2
Part of ACP Expert Release 8.3
Full license: Arne Henden
2021-11-20 03:54:03.9: REAL TIME internal clock initialized at 20-Nov-2021 03:54:03 UTC
2021-11-20 03:54:03.9: Dispatcher started at 20-Nov-2021 03:54:03 UTC
2021-11-20 03:54:04.6: Loaded Constraint plugin AirMass
2021-11-20 03:54:05.0: Loaded Constraint plugin AirmassRange
2021-11-20 03:54:05.1: Loaded Constraint plugin Horizon
2021-11-20 03:54:05.1: Loaded Constraint plugin HourAngle
2021-11-20 03:54:05.2: Loaded Constraint plugin MoonAvoid
2021-11-20 03:54:05.2: Loaded Constraint plugin MoonDown
2021-11-20 03:54:05.3: Loaded Constraint plugin SkyCondition
2021-11-20 03:54:05.3: Loaded Constraint plugin TimeRange
2021-11-20 03:54:06.4: 4 plans are now pending.
2021-11-20 03:54:06.5: Attach ACP sequencer
2021-11-20 03:54:13.3: Weather successfully connected. Wait for valid data...
2021-11-20 03:54:13.6: ==== Dispatcher is running in NORMAL (NIGHT) mode ====
2021-11-20 03:54:13.6: Sequencer does sky flats
2021-11-20 03:54:14.1: HTTP command listener failed to start: Access is denied
2021-11-20 03:54:14.1:   If this is Windows 8-10, you will need to add permission (ACL) to listen on port 9123.
2021-11-20 03:54:14.1:   See Scheduler Help > Configuring the Program > Noteworthy Configuration Options, at the bottom.
2021-11-20 03:54:18.8: ++ Weather Safe ++
2021-11-20 03:54:29.2: ++ Observatory Startup ++
2021-11-20 03:54:29.3: Weather safety is now disabled
2021-11-20 03:54:29.3: Start ACP Sequencer's StartupObs script
2021-11-20 03:54:48.1: Weather safety is re-enabled
2021-11-20 03:54:48.1: Observatory startup succeeded
2021-11-20 03:54:48.1: ++ Auto Focus ++
2021-11-20 03:54:48.2: Doing initial autofocus.
2021-11-20 03:54:48.2: Start special ACP AutoFocus script for scheduler
2021-11-20 03:59:08.1: Next periodic autofocus ASAP after 3600sec. 20-Nov-2021 04:58:48 UTC
2021-11-20 03:59:09.3: Dispatcher cycle time: 1.2 sec.
2021-11-20 03:59:09.3: Acquire data for Observation SA114...
2021-11-20 03:59:09.3:   (belongs to Project Landolt, Plan SA114)
2021-11-20 03:59:09.4: Send Observation SA114 to ACP Sequencer
2021-11-20 05:07:06.6: ACP data acquisition failed for Observation SA114.
2021-11-20 05:07:06.6:   (Observation SA114 interrupted by acquisition process failure.)
2021-11-20 05:07:07.1: Weather safety is now disabled
2021-11-20 05:07:07.1: Open the observatory dome/roof
2021-11-20 05:08:08.3: Weather safety is re-enabled
2021-11-20 05:08:08.3: ++ Auto Focus ++
2021-11-20 05:08:08.4: Doing initial autofocus.
2021-11-20 05:08:08.4: Start special ACP AutoFocus script for scheduler
2021-11-20 05:14:08.6: **DISPATCHER INTERRUPT LEVEL 1 RECEIVED**
2021-11-20 05:14:14.0: ** Autofocus failed (n=1), will retry ASAP after 0 min.
2021-11-20 05:22:39.7: ++ Observatory Startup ++
2021-11-20 05:22:39.7: Weather safety is now disabled
2021-11-20 05:22:39.7: Start ACP Sequencer's StartupObs script
2021-11-20 05:23:01.1: Weather safety is re-enabled
2021-11-20 05:23:01.1: Observatory startup succeeded
2021-11-20 05:23:01.1: ++ Auto Focus ++
2021-11-20 05:23:01.1: Doing initial autofocus.
2021-11-20 05:23:01.1: Start special ACP AutoFocus script for scheduler
2021-11-20 05:28:51.0: ** Autofocus failed (n=1), will retry ASAP after 0 min.
2021-11-20 05:28:51.3: Dispatcher cycle time: 0.3 sec.
2021-11-20 05:28:51.3: Acquire data for Observation SA114...
2021-11-20 05:28:51.3:   (belongs to Project Landolt, Plan SA114)
2021-11-20 05:28:51.3: ++ Auto Focus ++
2021-11-20 05:28:51.3: Doing periodic autofocus.
2021-11-20 05:28:51.3: Start special ACP AutoFocus script for scheduler
2021-11-20 05:34:08.5: ** Autofocus failed (n=2), will retry ASAP after 5 min.
2021-11-20 05:34:08.5: Send Observation SA114 to ACP Sequencer
2021-11-20 05:40:03.3: Acquisition time: 354.7455521 sec.
2021-11-20 05:40:03.3: Data for Observation SA114 of Plan SA114 acquired successfully.
2021-11-20 05:40:03.3: Plan SA114 completed successfully.
2021-11-20 05:40:03.3: Image Efficiency: 39.4%
2021-11-20 05:40:03.3: Cycle Efficiency: 99.9%
2021-11-20 05:40:03.5: ++ Auto Focus ++
2021-11-20 05:40:03.5: Doing periodic autofocus.
2021-11-20 05:40:03.5: Start special ACP AutoFocus script for scheduler
2021-11-20 05:44:43.3: ** ACP script "AutoFocusScheduler" failed:
2021-11-20 05:44:43.3: **Script Error**
Source:   ACP
Message:  The script was aborted.
Location: line 81 column 2.

2021-11-20 05:44:43.3: ** Autofocus failed (n=3), will retry ASAP after 10 min.
2021-11-20 05:44:43.4: Dispatcher cycle time: 0.1 sec.
2021-11-20 05:44:43.4: Acquire data for Observation SA94...
2021-11-20 05:44:43.4:   (belongs to Project Landolt, Plan SA94)
2021-11-20 05:44:43.8: Send Observation SA94 to ACP Sequencer
2021-11-20 05:44:56.1: **DISPATCHER INTERRUPT LEVEL 1 RECEIVED**
2021-11-20 05:45:29.1: ASSERT: ACP script exited with AcquireNow still True
2021-11-20 05:45:29.1: ACP ERROR: Run failed for unknown reason (see ACP run log)
2021-11-20 05:45:29.1: ACP data acquisition failed for Observation SA94.
2021-11-20 05:45:29.1:   (Observation SA94 interrupted by acquisition process failure.)
2021-11-20 05:51:19.0: ++ Observatory Startup ++
2021-11-20 05:51:19.0: Weather safety is now disabled
2021-11-20 05:51:19.0: Start ACP Sequencer's StartupObs script
2021-11-20 05:51:54.7: Weather safety is re-enabled
2021-11-20 05:51:54.7: Observatory startup succeeded
2021-11-20 05:51:54.7: ++ Auto Focus ++
2021-11-20 05:51:54.8: Doing initial autofocus.
2021-11-20 05:51:54.8: Start special ACP AutoFocus script for scheduler
2021-11-20 05:52:31.2: ** ACP script "AutoFocusScheduler" failed:
2021-11-20 05:52:31.2: **Script Error**
Source:   ACP
Message:  The script was aborted.
Location: line 81 column 2.

2021-11-20 05:52:31.2: ** Autofocus failed (n=1), will retry ASAP after 0 min.
2021-11-20 05:52:31.5: Dispatcher cycle time: 0.3 sec.
2021-11-20 05:52:31.5: Acquire data for Observation SA94...
2021-11-20 05:52:31.5:   (belongs to Project Landolt, Plan SA94)
2021-11-20 05:52:31.5: ++ Auto Focus ++
2021-11-20 05:52:31.5: Doing periodic autofocus.
2021-11-20 05:52:31.5: Start special ACP AutoFocus script for scheduler
2021-11-20 05:52:51.7: **DISPATCHER INTERRUPT LEVEL 1 RECEIVED**
2021-11-20 05:52:52.1: ** ACP script "AutoFocusScheduler" failed:
2021-11-20 05:52:52.1: **Script Error**
Source:   ACP
Message:  The script was aborted.
Location: line 78 column 9.

2021-11-20 05:52:52.1: ** Autofocus failed (n=2), will retry ASAP after 5 min.
2021-11-20 05:52:52.1:    Obs SA94 interrupted by dispatcher
2021-11-20 05:52:52.1: ACP data acquisition failed for Observation SA94.
2021-11-20 05:52:52.1:   (Observation SA94 interrupted by operator or system.)
2021-11-20 05:54:07.3: ++ Observatory Startup ++
2021-11-20 05:54:07.3: Weather safety is now disabled
2021-11-20 05:54:07.3: Start ACP Sequencer's StartupObs script
2021-11-20 05:54:25.9: Weather safety is re-enabled
2021-11-20 05:54:25.9: Observatory startup succeeded
2021-11-20 05:54:25.9: ++ Auto Focus ++
2021-11-20 05:54:25.9: Doing initial autofocus.
2021-11-20 05:54:25.9: Start special ACP AutoFocus script for scheduler
2021-11-20 05:59:59.7: ** Autofocus failed (n=1), will retry ASAP after 0 min.
2021-11-20 05:59:59.8: Dispatcher cycle time: 0.1 sec.
2021-11-20 05:59:59.8: Acquire data for Observation Target1...
2021-11-20 05:59:59.9:   (belongs to Project 2021-11-08 UTC, Plan Procyon)
2021-11-20 05:59:59.9: Doing 5 sets
2021-11-20 05:59:59.9: ++ Auto Focus ++
2021-11-20 05:59:59.9: Doing periodic autofocus.
2021-11-20 05:59:59.9: Start special ACP AutoFocus script for scheduler
2021-11-20 06:06:13.1: **DISPATCHER INTERRUPT LEVEL 1 RECEIVED**
2021-11-20 06:06:23.9: ** ACP script "AutoFocusScheduler" failed:
2021-11-20 06:06:23.9: **Script Error**
Source:   ACP
Message:  The script was aborted.
Location: line 81 column 2.

2021-11-20 06:06:23.9: ** Autofocus failed (n=2), will retry ASAP after 5 min.
2021-11-20 06:06:24.0:    Obs Target1 interrupted by dispatcher
2021-11-20 06:06:24.0: ACP data acquisition failed for Observation Target1.
2021-11-20 06:06:24.0:   (Observation Target1 interrupted by operator or system.)
2021-11-20 06:08:15.0: ++ Observatory Startup ++
2021-11-20 06:08:15.1: Weather safety is now disabled
2021-11-20 06:08:15.1: Start ACP Sequencer's StartupObs script
2021-11-20 06:08:33.6: Weather safety is re-enabled
2021-11-20 06:08:33.7: Observatory startup succeeded
2021-11-20 06:08:33.7: ++ Auto Focus ++
2021-11-20 06:08:33.7: Doing initial autofocus.
2021-11-20 06:08:33.7: Start special ACP AutoFocus script for scheduler
2021-11-20 06:09:32.0: **DISPATCHER INTERRUPT LEVEL 1 RECEIVED**
2021-11-20 06:09:33.5: ** ACP script "AutoFocusScheduler" failed:
2021-11-20 06:09:33.5: **Script Error**
Source:   ACP
Message:  The script was aborted.
Location: line 81 column 2.

2021-11-20 06:09:33.5: ** Autofocus failed (n=1), will retry ASAP after 0 min.
2021-11-20 06:11:02.9: ++ Observatory Startup ++
2021-11-20 06:11:03.0: Weather safety is now disabled
2021-11-20 06:11:03.0: Start ACP Sequencer's StartupObs script
2021-11-20 06:11:21.6: Weather safety is re-enabled
2021-11-20 06:11:21.6: Observatory startup succeeded
2021-11-20 06:11:21.6: ++ Auto Focus ++
2021-11-20 06:11:21.6: Doing initial autofocus.
2021-11-20 06:11:21.6: Start special ACP AutoFocus script for scheduler
2021-11-20 06:17:17.9: ** Autofocus failed (n=1), will retry ASAP after 0 min.
2021-11-20 06:17:23.2: ++ Auto Focus ++
2021-11-20 06:17:23.2: Doing periodic autofocus.
2021-11-20 06:17:23.2: Start special ACP AutoFocus script for scheduler
2021-11-20 06:18:04.1: **DISPATCHER INTERRUPT LEVEL 1 RECEIVED**
2021-11-20 06:18:05.0: **DISPATCHER INTERRUPT LEVEL 1 RECEIVED**
2021-11-20 06:18:38.5: ** ACP script "AutoFocusScheduler" failed:
2021-11-20 06:18:38.5: **Script Error**
Source:   ACP
Message:  The script was aborted.
Location: line 81 column 2.

2021-11-20 06:18:38.5: ** Autofocus failed (n=2), will retry ASAP after 5 min.
2021-11-20 06:20:42.9: ++ Observatory Startup ++
2021-11-20 06:20:42.9: Weather safety is now disabled
2021-11-20 06:20:42.9: Start ACP Sequencer's StartupObs script
2021-11-20 06:21:01.8: Weather safety is re-enabled
2021-11-20 06:21:01.9: Observatory startup succeeded
2021-11-20 06:21:01.9: ++ Auto Focus ++
2021-11-20 06:21:01.9: Doing initial autofocus.
2021-11-20 06:21:01.9: Start special ACP AutoFocus script for scheduler
2021-11-20 06:21:21.2: ** Autofocus failed (n=1), will retry ASAP after 0 min.
2021-11-20 06:21:26.4: ++ Auto Focus ++
2021-11-20 06:21:26.4: Doing periodic autofocus.
2021-11-20 06:21:26.4: Start special ACP AutoFocus script for scheduler
2021-11-20 06:21:45.6: ** Autofocus failed (n=2), will retry ASAP after 5 min.
2021-11-20 06:21:46.7: **DISPATCHER INTERRUPT LEVEL 1 RECEIVED**
2021-11-20 06:24:42.0: ++ Observatory Startup ++
2021-11-20 06:24:42.0: Weather safety is now disabled
2021-11-20 06:24:42.0: Start ACP Sequencer's StartupObs script
2021-11-20 06:25:00.9: Weather safety is re-enabled
2021-11-20 06:25:00.9: Observatory startup succeeded
2021-11-20 06:25:00.9: ++ Auto Focus ++
2021-11-20 06:25:01.0: Doing initial autofocus.
2021-11-20 06:25:01.0: Start special ACP AutoFocus script for scheduler
2021-11-20 06:25:52.4: **DISPATCHER INTERRUPT LEVEL 1 RECEIVED**
2021-11-20 06:25:54.8: ** ACP script "AutoFocusScheduler" failed:
2021-11-20 06:25:54.8: **Script Error**
Source:   ACP
Message:  The script was aborted.
Location: line 78 column 9.

2021-11-20 06:25:54.8: ** Autofocus failed (n=1), will retry ASAP after 0 min.
2021-11-20 06:28:17.9: ++ Observatory Startup ++
2021-11-20 06:28:17.9: Weather safety is now disabled
2021-11-20 06:28:17.9: Start ACP Sequencer's StartupObs script
2021-11-20 06:28:36.5: Weather safety is re-enabled
2021-11-20 06:28:36.5: Observatory startup succeeded
2021-11-20 06:28:36.5: ++ Auto Focus ++
2021-11-20 06:28:36.5: Doing initial autofocus.
2021-11-20 06:28:36.5: Start special ACP AutoFocus script for scheduler
2021-11-20 06:28:51.6: ** Autofocus failed (n=1), will retry ASAP after 0 min.
2021-11-20 06:28:56.9: ++ Auto Focus ++
2021-11-20 06:28:56.9: Doing periodic autofocus.
2021-11-20 06:28:56.9: Start special ACP AutoFocus script for scheduler
2021-11-20 06:29:07.3: **DISPATCHER INTERRUPT LEVEL 1 RECEIVED**
2021-11-20 06:30:31.1: ** ACP script "AutoFocusScheduler" failed:
2021-11-20 06:30:31.1: **Script Error (Tracking has been stopped)**
Source:   ACP Observatory Control Software
Message:  
Location: line 81 column 2.

2021-11-20 06:30:31.1: ** Autofocus failed (n=2), will retry ASAP after 5 min.
2021-11-20 06:32:17.4: ++ Observatory Startup ++
2021-11-20 06:32:17.4: Weather safety is now disabled
2021-11-20 06:32:17.4: Start ACP Sequencer's StartupObs script
2021-11-20 06:32:36.2: Weather safety is re-enabled
2021-11-20 06:32:36.2: Observatory startup succeeded
2021-11-20 06:32:36.2: ++ Auto Focus ++
2021-11-20 06:32:36.2: Doing initial autofocus.
2021-11-20 06:32:36.2: Start special ACP AutoFocus script for scheduler
2021-11-20 06:33:55.3: ** Autofocus failed (n=1), will retry ASAP after 0 min.
2021-11-20 06:34:00.5: ++ Auto Focus ++
2021-11-20 06:34:00.5: Doing periodic autofocus.
2021-11-20 06:34:00.5: Start special ACP AutoFocus script for scheduler
2021-11-20 06:34:23.3: **DISPATCHER INTERRUPT LEVEL 1 RECEIVED**
2021-11-20 06:34:26.3: **DISPATCHER INTERRUPT LEVEL 1 RECEIVED**
2021-11-20 06:34:58.0: ** Autofocus failed (n=2), will retry ASAP after 5 min.
2021-11-20 06:42:01.8: ++ Observatory Startup ++
2021-11-20 06:42:01.8: Weather safety is now disabled
2021-11-20 06:42:01.8: Start ACP Sequencer's StartupObs script
2021-11-20 06:42:20.6: Weather safety is re-enabled
2021-11-20 06:42:20.6: Observatory startup succeeded
2021-11-20 06:42:20.6: ++ Auto Focus ++
2021-11-20 06:42:20.6: Doing initial autofocus.
2021-11-20 06:42:20.6: Start special ACP AutoFocus script for scheduler
2021-11-20 06:43:35.5: ** Autofocus failed (n=1), will retry ASAP after 0 min.
2021-11-20 06:43:40.7: ++ Auto Focus ++
2021-11-20 06:43:40.7: Doing periodic autofocus.
2021-11-20 06:43:40.7: Start special ACP AutoFocus script for scheduler
2021-11-20 06:43:53.5: **DISPATCHER INTERRUPT LEVEL 1 RECEIVED**
2021-11-20 06:45:01.9: ** Autofocus failed (n=2), will retry ASAP after 5 min.
2021-11-20 06:47:23.2: ++ Observatory Startup ++
2021-11-20 06:47:23.2: Weather safety is now disabled
2021-11-20 06:47:23.2: Start ACP Sequencer's StartupObs script
2021-11-20 06:47:42.1: Weather safety is re-enabled
2021-11-20 06:47:42.1: Observatory startup succeeded
2021-11-20 06:47:42.1: ++ Auto Focus ++
2021-11-20 06:47:42.1: Doing initial autofocus.
2021-11-20 06:47:42.1: Start special ACP AutoFocus script for scheduler
2021-11-20 06:53:38.9: Next periodic autofocus ASAP after 3600sec. 20-Nov-2021 07:53:19 UTC
2021-11-20 07:53:20.9: ++ Auto Focus ++
2021-11-20 07:53:20.9: Doing periodic autofocus.
2021-11-20 07:53:20.9: Start special ACP AutoFocus script for scheduler
2021-11-20 07:57:39.7: Next periodic autofocus ASAP after 3600sec. 20-Nov-2021 08:57:21 UTC
2021-11-20 08:57:23.0: ++ Auto Focus ++
2021-11-20 08:57:23.0: Doing periodic autofocus.
2021-11-20 08:57:23.0: Start special ACP AutoFocus script for scheduler
2021-11-20 09:02:37.0: Next periodic autofocus ASAP after 3600sec. 20-Nov-2021 10:02:18 UTC
2021-11-20 10:02:22.2: ++ Auto Focus ++
2021-11-20 10:02:22.3: Doing periodic autofocus.
2021-11-20 10:02:22.3: Start special ACP AutoFocus script for scheduler
2021-11-20 10:06:34.0: Next periodic autofocus ASAP after 3600sec. 20-Nov-2021 11:06:20 UTC
2021-11-20 11:06:22.5: ++ Auto Focus ++
2021-11-20 11:06:22.8: Doing periodic autofocus.
2021-11-20 11:06:22.8: Start special ACP AutoFocus script for scheduler
2021-11-20 11:11:59.3: Next periodic autofocus ASAP after 3600sec. 20-Nov-2021 12:11:39 UTC
2021-11-20 11:54:32.7: Run statistics:
2021-11-20 11:54:32.7:   Observations:   3
2021-11-20 11:54:32.7:   Considered:     2
2021-11-20 11:54:32.7:   Completed:      0
2021-11-20 11:54:32.7:   Skipped:        0
2021-11-20 11:54:32.7:   Never Eligible: 1
2021-11-20 11:54:32.7:   Failed:         2
Log closed at Sat, Nov 20 2021 11:54:33 UTC (actual time)