Scheduler 'Focus' step gets stuck after first sample

INDI Library v2.0.7 is Released (01 Apr 2024)

Bi-monthly release with minor bug fixes and improvements

Scheduler 'Focus' step gets stuck after first sample

Sergio
Topic Author
Offline
Junior Member

Posts: 37
Thank you received: 4

Scheduler 'Focus' step gets stuck after first sample was created by Sergio

Since I updated KStars to v3.5.7 I'm experiencing issues with the 'Focus' step in the scheduler.

Before the update:
- I manually select the L filter (slot 1) and then launch the scheduler.
- At the scheduled time, it wakes up, slews to the target
- starts autofocus (linear algorithm) with the L filter
- aligns the target using the L filter
- starts the capture (that involves a filter change, say Ha in slot 3, and autofocus), etc.

After the update (see log below):
- I manually select the L filter (slot 1) and then launch the scheduler, as before
- At the scheduled time, it wakes up, slews to the target
- starts (linear) autofocus with the Ha filter (the first needed for the capture, slot 3), samples one point and then it the autofocus process gets stuck; the last message is just "Focuser reached requested position"
- (I manually cancelled the scheduler when I was back about 35 min later)

I find somewhat odd that the filter change ("Setting current filter to slot 3 ") is actually done **after** the first autofocus movements of the linear algorithm (100 steps outward, 50 inward).

What I'm doing as workaround:
- Focus manually for the L filter (the one I use for align)
- Disable the Focus step of the job and launch the scheduler
- When alignment is complete, I manually cancel the scheduler
- Enable the focus step and disable the align step, and launch the scheduler again
- Autofocus is performed on the first filter for the capture, then the capture starts (with further autofocus/filter changes as defined in the sequence).

Interestingly enough, when doing this, the problem does not show.

Thanks in advance,
Sergio

[2022-02-02T19:49:26.442 CET INFO ][ org.kde.kstars.ekos.scheduler] - "Scheduler is awake."
[2022-02-02T19:49:26.444 CET INFO ][ org.kde.kstars.ekos.scheduler] - Scheduler is starting...
[2022-02-02T19:49:26.445 CET INFO ][ org.kde.kstars.ekos.scheduler] - "Scheduler started."
[2022-02-02T19:49:27.425 CET INFO ][ org.kde.kstars.ekos.scheduler] - Option to sort jobs based on priority and altitude is false
[2022-02-02T19:49:27.426 CET INFO ][ org.kde.kstars.ekos.scheduler] - "Job 'RosetaNeb' has a total score of -1000 at 02/02 19:49."
[2022-02-02T19:49:27.428 CET INFO ][ org.kde.kstars.ekos.scheduler] - "Job 'RosetaNeb' has a total score of -1000 at 02/02 19:49."
[2022-02-02T19:49:28.380 CET INFO ][ org.kde.kstars.ekos.scheduler] - "Sleeping until observation job RosetaNeb is ready at mié. feb. 2 20:13:48 2022..."
[2022-02-02T20:13:48.270 CET INFO ][ org.kde.kstars.ekos.scheduler] - "Scheduler is awake. Jobs shall be started when ready..."
[2022-02-02T20:13:51.273 CET INFO ][ org.kde.kstars.ekos.scheduler] - Unparking mount in progress...
[2022-02-02T20:13:51.280 CET INFO ][           org.kde.kstars.indi] - EQMod Mount :  "[INFO] Mount is unparked. "
[2022-02-02T20:13:52.273 CET INFO ][ org.kde.kstars.ekos.scheduler] - "Mount unparked."
[2022-02-02T20:13:54.308 CET INFO ][ org.kde.kstars.ekos.scheduler] - Executing Job  "RosetaNeb"
[2022-02-02T20:13:55.279 CET INFO ][     org.kde.kstars.ekos.align] - Target updated to JNow RA: "06h 33m 07s" DE: " 04° 55' 33\""
[2022-02-02T20:13:55.282 CET INFO ][ org.kde.kstars.ekos.scheduler] - "Job 'RosetaNeb' is slewing to target."
[2022-02-02T20:13:55.322 CET INFO ][           org.kde.kstars.indi] - EQMod Mount :  "[INFO] Starting Goto RA=6.55188 DE=4.92593 (current RA=9.69568 DE=90) "
[2022-02-02T20:13:55.322 CET INFO ][           org.kde.kstars.indi] - EQMod Mount :  "[INFO] Aligned Eqmod Goto RA=6.55188 DE=4.92593 (target RA=6.55188 DE=4.92593) "
[2022-02-02T20:13:55.323 CET INFO ][           org.kde.kstars.indi] - EQMod Mount :  "[INFO] Setting Eqmod Goto RA=6.55188 DE=4.92593 (target RA=6.55188 DE=4.92593) "
[2022-02-02T20:13:55.425 CET INFO ][           org.kde.kstars.indi] - EQMod Mount :  "[INFO] Slewing mount: RA increment = 1207282, DE increment = -2177896 "
[2022-02-02T20:13:55.713 CET INFO ][           org.kde.kstars.indi] - EQMod Mount :  "[INFO] Slewing to RA:  6:33:07 - DEC:  4:55:33 "
[2022-02-02T20:13:55.722 CET INFO ][     org.kde.kstars.ekos.guide] - "Mount is moving. Resetting calibration..."
[2022-02-02T20:13:55.724 CET INFO ][     org.kde.kstars.ekos.guide] - "Calibration is cleared."
[2022-02-02T20:14:18.009 CET INFO ][           org.kde.kstars.indi] - EQMod Mount :  "[INFO] Iterative Goto (1): RA diff = 22.65 arcsecs DE diff = 0.10 arcsecs "
[2022-02-02T20:14:18.010 CET INFO ][           org.kde.kstars.indi] - EQMod Mount :  "[INFO] Iterative goto (1): slew mount to RA increment = 2426, DE increment = 1 "
[2022-02-02T20:14:19.400 CET INFO ][           org.kde.kstars.indi] - EQMod Mount :  "[INFO] Iterative Goto (2): RA diff = 1.30 arcsecs DE diff = 2.15 arcsecs "
[2022-02-02T20:14:19.528 CET INFO ][           org.kde.kstars.indi] - EQMod Mount :  "[INFO] Telescope slew is complete. Tracking TRACK_SIDEREAL... "
[2022-02-02T20:14:19.538 CET INFO ][ org.kde.kstars.ekos.scheduler] - "Job 'RosetaNeb' slew is complete."
[2022-02-02T20:14:20.275 CET INFO ][ org.kde.kstars.ekos.scheduler] - "Job 'RosetaNeb' is focusing."
[2022-02-02T20:14:20.284 CET INFO ][     org.kde.kstars.ekos.focus] - "Autofocus in progress..."
[2022-02-02T20:14:20.286 CET INFO ][     org.kde.kstars.ekos.focus] - "Focusing outward by 100 steps..."
[2022-02-02T20:14:20.295 CET INFO ][           org.kde.kstars.indi] - MoonLite :  "[INFO] Focuser is moving to position 5502 "
[2022-02-02T20:14:21.445 CET INFO ][     org.kde.kstars.ekos.focus] - "Focusing inward by 50 steps..."
[2022-02-02T20:14:21.449 CET INFO ][           org.kde.kstars.indi] - MoonLite :  "[INFO] Focuser reached requested position. "
[2022-02-02T20:14:21.491 CET INFO ][           org.kde.kstars.indi] - MoonLite :  "[INFO] Focuser is moving to position 5452 "
[2022-02-02T20:14:22.308 CET INFO ][           org.kde.kstars.indi] - MoonLite :  "[INFO] Focuser reached requested position. "
[2022-02-02T20:14:22.350 CET INFO ][           org.kde.kstars.indi] - MI G2-8300 :  "[INFO] Setting current filter to slot 3 "
[2022-02-02T20:14:26.711 CET INFO ][     org.kde.kstars.ekos.focus] - "Capturing image..."
[2022-02-02T20:14:36.799 CET INFO ][     org.kde.kstars.ekos.focus] - "Image received."
[2022-02-02T20:14:36.801 CET INFO ][     org.kde.kstars.ekos.focus] - "Detecting sources..."
[2022-02-02T20:14:36.944 CET INFO ][     org.kde.kstars.ekos.focus] - "Detection complete."
[2022-02-02T20:14:36.970 CET INFO ][     org.kde.kstars.ekos.focus] - "Focusing inward by 10 steps..."
[2022-02-02T20:14:36.972 CET INFO ][           org.kde.kstars.indi] - MoonLite :  "[INFO] Focuser is moving to position 5442 "
[2022-02-02T20:14:37.649 CET INFO ][           org.kde.kstars.indi] - MoonLite :  "[INFO] Focuser reached requested position. "
[2022-02-02T20:50:07.221 CET INFO ][ org.kde.kstars.ekos.scheduler] - Scheduler is stopping...
[2022-02-02T20:50:07.223 CET INFO ][ org.kde.kstars.ekos.scheduler] - "Job 'RosetaNeb' is stopping guiding..."
[2022-02-02T20:50:07.223 CET INFO ][ org.kde.kstars.ekos.scheduler] - "Job 'RosetaNeb' has not been processed upon scheduler stop, marking aborted."
[2022-02-02T20:50:07.261 CET INFO ][     org.kde.kstars.ekos.focus] - "Autofocus aborted."
[2022-02-02T20:50:07.292 CET INFO ][           org.kde.kstars.indi] - MI G2-8300 :  "[INFO] Exposure aborted. "

2 years 2 months ago #80438

Please Log in or Create an account to join the conversation.

Time to create page: 0.263 seconds