Hi everyone

After capture 21, the guiding stops after a dither but capture continues for the next 20 -5 minute- frames regardless, until I intervene manually.
Would it be possible to check guiding is active before beginning the next capture and restart if necessary? Even better, realign and restart after a guiding failure?

Cheers and TIA,
Steve
log: drive.google.com/file/d/1AxQiqhn7QR7EKeQ...K2_/view?usp=sharing

Here is another instance of the same issue. This time, I stayed awake to catch it.
Guiding stops after frame 32 at 03:00 but capture of frame 33 goes ahead.

Please could we recover when guiding is aborted?

Please could anyone help or suggest what I'm doing wrong?
Cheers,
Steve

logs:https://drive.google.com/drive/folders/1l3v5fU0DviKFjLfd2I-LCvLzcdCJrlRU?usp=sharing

Is this with internal guider? I'm using PHD2, and when that stops guiding, ongoing captures are (immediately) aborted:

[2020-06-22T05:02:13.534 WEST INFO ][ org.kde.kstars.indi] - ZWO CCD ASI1600MM Pro : "[INFO] Taking a 120 seconds frame... "
[2020-06-22T05:03:05.659 WEST INFO ][ org.kde.kstars.ekos.guide] - "Mount is slewing. Aborting guide..."
[2020-06-22T05:03:05.696 WEST INFO ][ org.kde.kstars.ekos.capture] - "Autoguiding stopped. Aborting..."
[2020-06-22T05:03:05.697 WEST INFO ][ org.kde.kstars.ekos.capture] - "CCD capture aborted"
[2020-06-22T05:03:05.700 WEST INFO ][ org.kde.kstars.ekos.guide] - "Autoguiding aborted."
[2020-06-22T05:03:06.000 WEST INFO ][ org.kde.kstars.ekos.guide] - "PHD2: Guiding Stopped."
[2020-06-22T05:03:06.090 WEST INFO ][ org.kde.kstars.ekos.guide] - "PHD2: Looping Exposures Stopped."
[2020-06-22T05:03:06.096 WEST INFO ][ org.kde.kstars.ekos.guide] - "PHD2: Lock Position Lost."

Hi
No, this is like you, with PHD2 and yes, guiding is aborted, but capture continues and keeps retrying without ever regaining the guide star:

[2020-06-22T01:41:01.523 CEST INFO ][ org.kde.kstars.ekos.guide] - "PHD2: Guiding Stopped."
[2020-06-22T01:41:01.524 CEST DEBG ][ org.kde.kstars.ekos.capture] - Guiding state changed from "Guiding" to "Aborted"
[2020-06-22T01:41:01.524 CEST INFO ][ org.kde.kstars.ekos.guide] - "Autoguiding aborted."
[2020-06-22T01:41:01.525 CEST DEBG ][ org.kde.kstars.ekos.capture] - "Client module indicates that storage for '/home/steve/astro/LBN_71/Light/LBN_71_Light' has already 21 captures processed."
[2020-06-22T01:41:01.525 CEST DEBG ][ org.kde.kstars.ekos.capture] - Preparing capture job "/home/steve/astro/LBN_71/Light/LBN_71_Light" for execution.
[2020-06-22T01:41:01.526 CEST INFO ][ org.kde.kstars.ekos.capture] - "Job requires 300,000-second images, has 21/64 frames captured and will be processed."
[2020-06-22T01:41:01.527 CEST INFO ][ org.kde.kstars.ekos.capture] - "Capturing 300,000-second image..."
[2020-06-22T01:41:01.528 CEST DEBG ][ org.kde.kstars.ekos.scheduler] - Guide State "Aborted"
[2020-06-22T01:41:01.545 CEST DEBG ][ org.kde.kstars.ekos.scheduler] - Capture State "In Progress"
[2020-06-22T01:41:01.545 CEST DEBG ][ org.kde.kstars.indi] - EQMod Mount : "[MOUNT] SetMotion() : Axis = 1 -- dir=forward mode=slew speedmode=lowspeed "
[2020-06-22T01:41:01.545 CEST DEBG ][ org.kde.kstars.indi] - EQMod Mount : "[SCOPE] CheckMotorStatus() : Axis = 1 "
[2020-06-22T01:41:01.545 CEST DEBG ][ org.kde.kstars.indi] - EQMod Mount : "[MOUNT] SetSpeed() : Axis = 1 -- period=620 "
[2020-06-22T01:41:01.545 CEST DEBG ][ org.kde.kstars.indi] - EQMod Mount : "[COMM] dispatch_command: \":f1\", 0 bytes written "
[2020-06-22T01:41:01.546 CEST DEBG ][ org.kde.kstars.indi] - Canon DSLR EOS 700D : "[DEBUG] Aborting exposure... "
[2020-06-22T01:41:01.546 CEST DEBG ][ org.kde.kstars.indi] - Canon DSLR EOS 700D : "[DEBUG] Closing internal shutter. "
[2020-06-22T01:41:01.546 CEST DEBG ][ org.kde.kstars.indi] - Canon DSLR EOS 700D : "[DEBUG] Using widget:eosremoterelease "
[2020-06-22T01:41:01.546 CEST DEBG ][ org.kde.kstars.indi] - Canon DSLR EOS 700D : "[DEBUG] Setting radio/menu widget eosremoterelease: 4 (Release Full) "
[2020-06-22T01:41:01.546 CEST DEBG ][ org.kde.kstars.indi] - Canon DSLR EOS 700D : "[DEBUG] Setting new configuration OK. "
[2020-06-22T01:41:01.546 CEST DEBG ][ org.kde.kstars.indi] - Canon DSLR EOS 700D : "[DEBUG] Reading exposure... "
[2020-06-22T01:41:01.546 CEST DEBG ][ org.kde.kstars.indi] - Canon DSLR EOS 700D : "[DEBUG] Exposure complete. "
[2020-06-22T01:41:01.546 CEST DEBG ][ org.kde.kstars.indi] - Canon DSLR EOS 700D : "[DEBUG] Capture event completed. "
[2020-06-22T01:41:01.546 CEST DEBG ][ org.kde.kstars.indi] - EQMod Mount : "[COMM] read_eqmod: \"=111\", 5 bytes read "
[2020-06-22T01:41:01.546 CEST DEBG ][ org.kde.kstars.indi] - EQMod Mount : "[COMM] dispatch_command: \":I16C0200\", 0 bytes written "
[2020-06-22T01:41:01.546 CEST DEBG ][ org.kde.kstars.indi] - EQMod Mount : "[COMM] read_eqmod: \"=\", 2 bytes read "
[2020-06-22T01:41:01.546 CEST DEBG ][ org.kde.kstars.indi] - EQMod Mount : "[DEBUG] End Timed guide West/East "
[2020-06-22T01:41:01.547 CEST DEBG ][ org.kde.kstars.ekos.scheduler] - Capture State "Capturing"
[2020-06-22T01:41:01.566 CEST DEBG ][ org.kde.kstars.ekos.guide] - PHD2: event: "{\"Event\":\"LoopingExposuresStopped\",\"Timestamp\":1592782861.561,\"Host\":\"E5430\",\"Inst\":1}\r\n"
[2020-06-22T01:41:01.566 CEST INFO ][ org.kde.kstars.ekos.guide] - "PHD2: Looping Exposures Stopped."
[2020-06-22T01:41:01.566 CEST DEBG ][ org.kde.kstars.ekos.guide] - PHD2: event: "{\"Event\":\"LockPositionLost\",\"Timestamp\":1592782861.561,\"Host\":\"E5430\",\"Inst\":1}\r\n"
[2020-06-22T01:41:01.567 CEST INFO ][ org.kde.kstars.ekos.guide] - "PHD2: Lock Position Lost."
[2020-06-22T01:41:01.738 CEST DEBG ][ org.kde.kstars.ekos.guide] - PHD2: error: "{\"jsonrpc\":\"2.0\",\"error\":{\"code\":2,\"message\":\"no star selected\"},\"id\":3470}\r\n"
[2020-06-22T01:41:01.739 CEST INFO ][ org.kde.kstars.ekos.guide] - "PHD2 Error: no star selected"
[2020-06-22T01:41:01.957 CEST DEBG ][ org.kde.kstars.indi] - EQMod Mount : "[SCOPE] Compute local time: lst=17.69274535 (17:41:33.88) - julian date=2459022.48681422 "
[2020-06-22T01:41:01.998 CEST DEBG ][ org.kde.kstars.indi] - EQMod Mount : "[COMM] dispatch_command: \":j1\", 0 bytes written "
[2020-06-22T01:41:01.998 CEST DEBG ][ org.kde.kstars.indi] - EQMod Mount : "[COMM] read_eqmod: \"=F33B95\", 8 bytes read "
[2020-06-22T01:41:01.998 CEST DEBG ][ org.kde.kstars.indi] - EQMod Mount : "[SCOPE] GetRAEncoder() = 9780211 "
[2020-06-22T01:41:01.998 CEST DEBG ][ org.kde.kstars.indi] - EQMod Mount : "[COMM] dispatch_command: \":j2\", 0 bytes written "
[2020-06-22T01:41:01.998 CEST DEBG ][ org.kde.kstars.indi] - EQMod Mount : "[COMM] read_eqmod: \"=00648D\", 8 bytes read "
[2020-06-22T01:41:01.998 CEST DEBG ][ org.kde.kstars.indi] - EQMod Mount : "[SCOPE] Current encoders RA=9780211 DE=9266176 "
[2020-06-22T01:41:01.998 CEST DEBG ][ org.kde.kstars.indi] - EQMod Mount : "[COMM] dispatch_command: \":f1\", 0 bytes written "
[2020-06-22T01:41:02.010 CEST DEBG ][ org.kde.kstars.indi] - EQMod Mount : "[COMM] read_eqmod: \"=111\", 5 bytes read "
[2020-06-22T01:41:02.011 CEST DEBG ][ org.kde.kstars.indi] - EQMod Mount : "[COMM] dispatch_command: \":f2\", 0 bytes written "
[2020-06-22T01:41:02.027 CEST DEBG ][ org.kde.kstars.indi] - EQMod Mount : "[COMM] read_eqmod: \"=301\", 5 bytes read "
[2020-06-22T01:41:02.500 CEST DEBG ][ org.kde.kstars.indi] - Canon DSLR EOS 700D : "[DEBUG] Event timed out #1, retrying... "
[2020-06-22T01:41:03.028 CEST DEBG ][ org.kde.kstars.indi] - EQMod Mount : "[SCOPE] Compute local time: lst=17.69304352 (17:41:34.96) - julian date=2459022.48682661 "
[2020-06-22T01:41:03.028 CEST DEBG ][ org.kde.kstars.indi] - EQMod Mount : "[COMM] dispatch_command: \":j1\", 0 bytes written "
[2020-06-22T01:41:03.048 CEST DEBG ][ org.kde.kstars.indi] - EQMod Mount : "[COMM] read_eqmod: \"=633C95\", 8 bytes read "
[2020-06-22T01:41:03.049 CEST DEBG ][ org.kde.kstars.indi] - EQMod Mount : "[SCOPE] GetRAEncoder() = 9780323 "
[2020-06-22T01:41:03.050 CEST DEBG ][ org.kde.kstars.indi] - EQMod Mount : "[COMM] dispatch_command: \":j2\", 0 bytes written "
[2020-06-22T01:41:03.064 CEST DEBG ][ org.kde.kstars.indi] - EQMod Mount : "[COMM] read_eqmod: \"=00648D\", 8 bytes read "
[2020-06-22T01:41:03.064 CEST DEBG ][ org.kde.kstars.indi] - EQMod Mount : "[SCOPE] Current encoders RA=9780323 DE=9266176 "
[2020-06-22T01:41:03.065 CEST DEBG ][ org.kde.kstars.indi] - EQMod Mount : "[COMM] dispatch_command: \":f1\", 0 bytes written "
[2020-06-22T01:41:03.105 CEST DEBG ][ org.kde.kstars.indi] - EQMod Mount : "[COMM] read_eqmod: \"=111\", 5 bytes read "
[2020-06-22T01:41:03.105 CEST DEBG ][ org.kde.kstars.indi] - EQMod Mount : "[COMM] dispatch_command: \":f2\", 0 bytes written "
[2020-06-22T01:41:03.105 CEST DEBG ][ org.kde.kstars.indi] - EQMod Mount : "[COMM] read_eqmod: \"=301\", 5 bytes read "
[2020-06-22T01:41:03.503 CEST DEBG ][ org.kde.kstars.indi] - Canon DSLR EOS 700D : "[DEBUG] Event timed out #2, retrying... "
[2020-06-22T01:41:04.097 CEST DEBG ][ org.kde.kstars.indi] - EQMod Mount : "[SCOPE] Compute local time: lst=17.69334145 (17:41:36.03) - julian date=2459022.48683899 "
[2020-06-22T01:41:04.098 CEST DEBG ][ org.kde.kstars.indi] - EQMod Mount : "[COMM] dispatch_command: \":j1\", 0 bytes written "
[2020-06-22T01:41:04.118 CEST DEBG ][ org.kde.kstars.indi] - EQMod Mount : "[COMM] read_eqmod: \"=D33C95\", 8 bytes read "
[2020-06-22T01:41:04.118 CEST DEBG ][ org.kde.kstars.indi] - EQMod Mount : "[SCOPE] GetRAEncoder() = 9780435 "
[2020-06-22T01:41:04.118 CEST DEBG ][ org.kde.kstars.indi] - EQMod Mount : "[COMM] dispatch_command: \":j2\", 0 bytes written "
[2020-06-22T01:41:04.134 CEST DEBG ][ org.kde.kstars.indi] - EQMod Mount : "[COMM] read_eqmod: \"=00648D\", 8 bytes read "
[2020-06-22T01:41:04.134 CEST DEBG ][ org.kde.kstars.indi] - EQMod Mount : "[SCOPE] Current encoders RA=9780435 DE=9266176 "
[2020-06-22T01:41:04.135 CEST DEBG ][ org.kde.kstars.indi] - EQMod Mount : "[COMM] dispatch_command: \":f1\", 0 bytes written "
[2020-06-22T01:41:04.150 CEST DEBG ][ org.kde.kstars.indi] - EQMod Mount : "[COMM] read_eqmod: \"=111\", 5 bytes read "
[2020-06-22T01:41:04.150 CEST DEBG ][ org.kde.kstars.indi] - EQMod Mount : "[COMM] dispatch_command: \":f2\", 0 bytes written "
[2020-06-22T01:41:04.166 CEST DEBG ][ org.kde.kstars.indi] - EQMod Mount : "[COMM] read_eqmod: \"=301\", 5 bytes read "
[2020-06-22T01:41:04.505 CEST DEBG ][ org.kde.kstars.indi] - Canon DSLR EOS 700D : "[DEBUG] Event timed out #3, retrying... "
[2020-06-22T01:41:05.167 CEST DEBG ][ org.kde.kstars.indi] - EQMod Mount : "[SCOPE] Compute local time: lst=17.69363943 (17:41:37.10) - julian date=2459022.48685137 "
[2020-06-22T01:41:05.169 CEST DEBG ][ org.kde.kstars.indi] - EQMod Mount : "[COMM] dispatch_command: \":j1\", 0 bytes written "
[2020-06-22T01:41:05.189 CEST DEBG ][ org.kde.kstars.indi] - EQMod Mount : "[COMM] read_eqmod: \"=433D95\", 8 bytes read "
[2020-06-22T01:41:05.189 CEST DEBG ][ org.kde.kstars.indi] - EQMod Mount : "[SCOPE] GetRAEncoder() = 9780547 "
[2020-06-22T01:41:05.189 CEST DEBG ][ org.kde.kstars.indi] - EQMod Mount : "[COMM] dispatch_command: \":j2\", 0 bytes written "
[2020-06-22T01:41:05.204 CEST DEBG ][ org.kde.kstars.indi] - EQMod Mount : "[COMM] read_eqmod: \"=00648D\", 8 bytes read "
[2020-06-22T01:41:05.204 CEST DEBG ][ org.kde.kstars.indi] - EQMod Mount : "[SCOPE] Current encoders RA=9780547 DE=9266176 "
[2020-06-22T01:41:05.204 CEST DEBG ][ org.kde.kstars.indi] - EQMod Mount : "[COMM] dispatch_command: \":f1\", 0 bytes written "
[2020-06-22T01:41:05.220 CEST DEBG ][ org.kde.kstars.indi] - EQMod Mount : "[COMM] read_eqmod: \"=111\", 5 bytes read "
[2020-06-22T01:41:05.220 CEST DEBG ][ org.kde.kstars.indi] - EQMod Mount : "[COMM] dispatch_command: \":f2\", 0 bytes written "
[2020-06-22T01:41:05.236 CEST DEBG ][ org.kde.kstars.indi] - EQMod Mount : "[COMM] read_eqmod: \"=301\", 5 bytes read "
[2020-06-22T01:41:05.508 CEST DEBG ][ org.kde.kstars.indi] - Canon DSLR EOS 700D : "[DEBUG] Event timed out #4, retrying... "
[2020-06-22T01:41:06.237 CEST DEBG ][ org.kde.kstars.indi] - EQMod Mount : "[SCOPE] Compute local time: lst=17.69393740 (17:41:38.17) - julian date=2459022.48686375 "
[2020-06-22T01:41:06.237 CEST DEBG ][ org.kde.kstars.indi] - EQMod Mount : "[COMM] dispatch_command: \":j1\", 0 bytes written "
[2020-06-22T01:41:06.257 CEST DEBG ][ org.kde.kstars.indi] - EQMod Mount : "[COMM] read_eqmod: \"=B33D95\", 8 bytes read "
[2020-06-22T01:41:06.257 CEST DEBG ][ org.kde.kstars.indi] - EQMod Mount : "[SCOPE] GetRAEncoder() = 9780659 "
[2020-06-22T01:41:06.257 CEST DEBG ][ org.kde.kstars.indi] - EQMod Mount : "[COMM] dispatch_command: \":j2\", 0 bytes written "
[2020-06-22T01:41:06.273 CEST DEBG ][ org.kde.kstars.indi] - EQMod Mount : "[COMM] read_eqmod: \"=00648D\", 8 bytes read "
[2020-06-22T01:41:06.273 CEST DEBG ][ org.kde.kstars.indi] - EQMod Mount : "[SCOPE] Current encoders RA=9780659 DE=9266176 "
[2020-06-22T01:41:06.274 CEST DEBG ][ org.kde.kstars.indi] - EQMod Mount : "[COMM] dispatch_command: \":f1\", 0 bytes written "
[2020-06-22T01:41:06.289 CEST DEBG ][ org.kde.kstars.indi] - EQMod Mount : "[COMM] read_eqmod: \"=111\", 5 bytes read "
[2020-06-22T01:41:06.289 CEST DEBG ][ org.kde.kstars.indi] - EQMod Mount : "[COMM] dispatch_command: \":f2\", 0 bytes written "
[2020-06-22T01:41:06.305 CEST DEBG ][ org.kde.kstars.indi] - EQMod Mount : "[COMM] read_eqmod: \"=301\", 5 bytes read "
[2020-06-22T01:41:06.511 CEST DEBG ][ org.kde.kstars.indi] - Canon DSLR EOS 700D : "[DEBUG] Event timed out #5, retrying... "
[2020-06-22T01:41:07.307 CEST DEBG ][ org.kde.kstars.indi] - EQMod Mount : "[SCOPE] Compute local time: lst=17.69423538 (17:41:39.25) - julian date=2459022.48687613 "
[2020-06-22T01:41:07.307 CEST DEBG ][ org.kde.kstars.indi] - EQMod Mount : "[COMM] dispatch_command: \":j1\", 0 bytes written "
[2020-06-22T01:41:07.327 CEST DEBG ][ org.kde.kstars.indi] - EQMod Mount : "[COMM] read_eqmod: \"=233E95\", 8 bytes read "
[2020-06-22T01:41:07.328 CEST DEBG ][ org.kde.kstars.indi] - EQMod Mount : "[SCOPE] GetRAEncoder() = 9780771 "
[2020-06-22T01:41:07.328 CEST DEBG ][ org.kde.kstars.indi] - EQMod Mount : "[COMM] dispatch_command: \":j2\", 0 bytes written "
[2020-06-22T01:41:07.343 CEST DEBG ][ org.kde.kstars.indi] - EQMod Mount : "[COMM] read_eqmod: \"=00648D\", 8 bytes read "
[2020-06-22T01:41:07.343 CEST DEBG ][ org.kde.kstars.indi] - EQMod Mount : "[SCOPE] Current encoders RA=9780771 DE=9266176 "
[2020-06-22T01:41:07.344 CEST DEBG ][ org.kde.kstars.indi] - EQMod Mount : "[COMM] dispatch_command: \":f1\", 0 bytes written "
[2020-06-22T01:41:07.359 CEST DEBG ][ org.kde.kstars.indi] - EQMod Mount : "[COMM] read_eqmod: \"=111\", 5 bytes read "
[2020-06-22T01:41:07.359 CEST DEBG ][ org.kde.kstars.indi] - EQMod Mount : "[COMM] dispatch_command: \":f2\", 0 bytes written "
[2020-06-22T01:41:07.375 CEST DEBG ][ org.kde.kstars.indi] - EQMod Mount : "[COMM] read_eqmod: \"=301\", 5 bytes read "
[2020-06-22T01:41:07.514 CEST DEBG ][ org.kde.kstars.indi] - Canon DSLR EOS 700D : "[DEBUG] Event timed out #6, retrying... "
[2020-06-22T01:41:08.376 CEST DEBG ][ org.kde.kstars.indi] - EQMod Mount : "[SCOPE] Compute local time: lst=17.69453334 (17:41:40.32) - julian date=2459022.48688852 "
[2020-06-22T01:41:08.377 CEST DEBG ][ org.kde.kstars.indi] - EQMod Mount : "[COMM] dispatch_command: \":j1\", 0 bytes written "
[2020-06-22T01:41:08.397 CEST DEBG ][ org.kde.kstars.indi] - EQMod Mount : "[COMM] read_eqmod: \"=933E95\", 8 bytes read "
[2020-06-22T01:41:08.397 CEST DEBG ][ org.kde.kstars.indi] - EQMod Mount : "[SCOPE] GetRAEncoder() = 9780883 "
[2020-06-22T01:41:08.398 CEST DEBG ][ org.kde.kstars.indi] - EQMod Mount : "[COMM] dispatch_command: \":j2\", 0 bytes written "
[2020-06-22T01:41:08.413 CEST DEBG ][ org.kde.kstars.indi] - EQMod Mount : "[COMM] read_eqmod: \"=00648D\", 8 bytes read "
[2020-06-22T01:41:08.413 CEST DEBG ][ org.kde.kstars.indi] - EQMod Mount : "[SCOPE] Current encoders RA=9780883 DE=9266176 "
[2020-06-22T01:41:08.414 CEST DEBG ][ org.kde.kstars.indi] - EQMod Mount : "[COMM] dispatch_command: \":f1\", 0 bytes written "
[2020-06-22T01:41:08.429 CEST DEBG ][ org.kde.kstars.indi] - EQMod Mount : "[COMM] read_eqmod: \"=111\", 5 bytes read "
[2020-06-22T01:41:08.429 CEST DEBG ][ org.kde.kstars.indi] - EQMod Mount : "[COMM] dispatch_command: \":f2\", 0 bytes written "
[2020-06-22T01:41:08.445 CEST DEBG ][ org.kde.kstars.indi] - EQMod Mount : "[COMM] read_eqmod: \"=301\", 5 bytes read "
[2020-06-22T01:41:08.516 CEST DEBG ][ org.kde.kstars.indi] - Canon DSLR EOS 700D : "[DEBUG] Event timed out #7, retrying... "
[2020-06-22T01:41:09.446 CEST DEBG ][ org.kde.kstars.indi] - EQMod Mount : "[SCOPE] Compute local time: lst=17.69483133 (17:41:41.39) - julian date=2459022.48690090 "
[2020-06-22T01:41:09.446 CEST DEBG ][ org.kde.kstars.indi] - EQMod Mount : "[COMM] dispatch_command: \":j1\", 0 bytes written "
[2020-06-22T01:41:09.467 CEST DEBG ][ org.kde.kstars.indi] - EQMod Mount : "[COMM] read_eqmod: \"=033F95\", 8 bytes read "
[2020-06-22T01:41:09.467 CEST DEBG ][ org.kde.kstars.indi] - EQMod Mount : "[SCOPE] GetRAEncoder() = 9780995 "
[2020-06-22T01:41:09.467 CEST DEBG ][ org.kde.kstars.indi] - EQMod Mount : "[COMM] dispatch_command: \":j2\", 0 bytes written "
[2020-06-22T01:41:09.483 CEST DEBG ][ org.kde.kstars.indi] - EQMod Mount : "[COMM] read_eqmod: \"=00648D\", 8 bytes read "
[2020-06-22T01:41:09.483 CEST DEBG ][ org.kde.kstars.indi] - EQMod Mount : "[SCOPE] Current encoders RA=9780995 DE=9266176 "
[2020-06-22T01:41:09.484 CEST DEBG ][ org.kde.kstars.indi] - EQMod Mount : "[COMM] dispatch_command: \":f1\", 0 bytes written "
[2020-06-22T01:41:09.499 CEST DEBG ][ org.kde.kstars.indi] - EQMod Mount : "[COMM] read_eqmod: \"=111\", 5 bytes read "
[2020-06-22T01:41:09.499 CEST DEBG ][ org.kde.kstars.indi] - EQMod Mount : "[COMM] dispatch_command: \":f2\", 0 bytes written "
[2020-06-22T01:41:09.515 CEST DEBG ][ org.kde.kstars.indi] - EQMod Mount : "[COMM] read_eqmod: \"=301\", 5 bytes read "
[2020-06-22T01:41:09.519 CEST DEBG ][ org.kde.kstars.indi] - Canon DSLR EOS 700D : "[DEBUG] Event timed out #8, retrying... "
[2020-06-22T01:41:10.516 CEST DEBG ][ org.kde.kstars.indi] - EQMod Mount : "[SCOPE] Compute local time: lst=17.69512935 (17:41:42.47) - julian date=2459022.48691328 "
[2020-06-22T01:41:10.517 CEST DEBG ][ org.kde.kstars.indi] - EQMod Mount : "[COMM] dispatch_command: \":j1\", 0 bytes written "
[2020-06-22T01:41:10.522 CEST DEBG ][ org.kde.kstars.indi] - Canon DSLR EOS 700D : "[DEBUG] Event timed out #9, retrying... "
[2020-06-22T01:41:10.537 CEST DEBG ][ org.kde.kstars.indi] - EQMod Mount : "[COMM] read_eqmod: \"=733F95\", 8 bytes read "
[2020-06-22T01:41:10.537 CEST DEBG ][ org.kde.kstars.indi] - EQMod Mount : "[SCOPE] GetRAEncoder() = 9781107 "
[2020-06-22T01:41:10.537 CEST DEBG ][ org.kde.kstars.indi] - EQMod Mount : "[COMM] dispatch_command: \":j2\", 0 bytes written "
[2020-06-22T01:41:10.553 CEST DEBG ][ org.kde.kstars.indi] - EQMod Mount : "[COMM] read_eqmod: \"=00648D\", 8 bytes read "
[2020-06-22T01:41:10.553 CEST DEBG ][ org.kde.kstars.indi] - EQMod Mount : "[SCOPE] Current encoders RA=9781107 DE=9266176 "
[2020-06-22T01:41:10.554 CEST DEBG ][ org.kde.kstars.indi] - EQMod Mount : "[COMM] dispatch_command: \":f1\", 0 bytes written "
[2020-06-22T01:41:10.569 CEST DEBG ][ org.kde.kstars.indi] - EQMod Mount : "[COMM] read_eqmod: \"=111\", 5 bytes read "
[2020-06-22T01:41:10.569 CEST DEBG ][ org.kde.kstars.indi] - EQMod Mount : "[COMM] dispatch_command: \":f2\", 0 bytes written "
[2020-06-22T01:41:10.584 CEST DEBG ][ org.kde.kstars.indi] - EQMod Mount : "[COMM] read_eqmod: \"=301\", 5 bytes read "
[2020-06-22T01:41:11.525 CEST DEBG ][ org.kde.kstars.indi] - Canon DSLR EOS 700D : "[DEBUG] Event timed out #10, retrying... "
[2020-06-22T01:41:11.527 CEST INFO ][ org.kde.kstars.indi] - Canon DSLR EOS 700D : "[INFO] Starting 300 seconds exposure. "
[2020-06-22T01:41:11.527 CEST DEBG ][ org.kde.kstars.indi] - Canon DSLR EOS 700D : "[DEBUG] Starting exposure (exptime: 300 secs, mirror lock: 0) "
[2020-06-22T01:41:11.527 CEST DEBG ][ org.kde.kstars.indi] - Canon DSLR EOS 700D : "[DEBUG] Mutex locked "
[2020-06-22T01:41:11.527 CEST DEBG ][ org.kde.kstars.indi] - Canon DSLR EOS 700D : "[DEBUG] Setting radio/menu widget iso: 4 (800) "
[2020-06-22T01:41:11.529 CEST DEBG ][ org.kde.kstars.indi] - Canon DSLR EOS 700D : "[DEBUG] Setting new configuration OK. "
[2020-06-22T01:41:11.530 CEST DEBG ][ org.kde.kstars.indi] - Canon DSLR EOS 700D : "[DEBUG] Setting radio/menu widget imageformat: 9 (RAW) "
[2020-06-22T01:41:11.530 CEST DEBG ][ org.kde.kstars.indi] - Canon DSLR EOS 700D : "[DEBUG] Setting new configuration OK. "
[2020-06-22T01:41:11.530 CEST DEBG ][ org.kde.kstars.indi] - Canon DSLR EOS 700D : "[DEBUG] Using internal bulb widget:eosremoterelease "
[2020-06-22T01:41:11.530 CEST DEBG ][ org.kde.kstars.indi] - Canon DSLR EOS 700D : "[DEBUG] Setting radio/menu widget eosremoterelease: 2 (Press Full) "
[2020-06-22T01:41:11.585 CEST DEBG ][ org.kde.kstars.indi] - EQMod Mount : "[SCOPE] Compute local time: lst=17.69542723 (17:41:43.54) - julian date=2459022.48692566 "
[2020-06-22T01:41:11.586 CEST DEBG ][ org.kde.kstars.indi] - EQMod Mount : "[COMM] dispatch_command: \":j1\", 0 bytes written "
[2020-06-22T01:41:11.593 CEST DEBG ][ org.kde.kstars.indi] - Canon DSLR EOS 700D : "[DEBUG] Setting new configuration OK. "
[2020-06-22T01:41:11.593 CEST DEBG ][ org.kde.kstars.indi] - Canon DSLR EOS 700D : "[DEBUG] Exposure started. "
[2020-06-22T01:41:11.593 CEST DEBG ][ org.kde.kstars.indi] - Canon DSLR EOS 700D : "[DEBUG] Time left: 299999 ms "
[2020-06-22T01:41:11.606 CEST DEBG ][ org.kde.kstars.indi] - EQMod Mount : "[COMM] read_eqmod: \"=E33F95\", 8 bytes read "
[2020-06-22T01:41:11.607 CEST DEBG ][ org.kde.kstars.indi] - EQMod Mount : "[SCOPE] GetRAEncoder() = 9781219 "
[2020-06-22T01:41:11.607 CEST DEBG ][ org.kde.kstars.indi] - EQMod Mount : "[COMM] dispatch_command: \":j2\", 0 bytes written "
[2020-06-22T01:41:11.622 CEST DEBG ][ org.kde.kstars.indi] - EQMod Mount : "[COMM] read_eqmod: \"=00648D\", 8 bytes read "
[2020-06-22T01:41:11.622 CEST DEBG ][ org.kde.kstars.indi] - EQMod Mount : "[SCOPE] Current encoders RA=9781219 DE=9266176 "
[2020-06-22T01:41:11.623 CEST DEBG ][ org.kde.kstars.indi] - EQMod Mount : "[COMM] dispatch_command: \":f1\", 0 bytes written "
[2020-06-22T01:41:11.638 CEST DEBG ][ org.kde.kstars.indi] - EQMod Mount : "[COMM] read_eqmod: \"=111\", 5 bytes read "
[2020-06-22T01:41:11.638 CEST DEBG ][ org.kde.kstars.indi] - EQMod Mount : "[COMM] dispatch_command: \":f2\", 0 bytes written "
[2020-06-22T01:41:11.654 CEST DEBG ][ org.kde.kstars.indi] - EQMod Mount : "[COMM] read_eqmod: \"=301\", 5 bytes read "
[2020-06-22T01:41:12.656 CEST DEBG ][ org.kde.kstars.indi] - EQMod Mount : "[SCOPE] Compute local time: lst=17.69572529 (17:41:44.61) - julian date=2459022.48693804 "
[2020-06-22T01:41:12.656 CEST DEBG ][ org.kde.kstars.indi] - EQMod Mount : "[COMM] dispatch_command: \":j1\", 0 bytes written "
[2020-06-22T01:41:12.689 CEST DEBG ][ org.kde.kstars.indi] - EQMod Mount : "[COMM] read_eqmod: \"=534095\", 8 bytes read "
[2020-06-22T01:41:12.689 CEST DEBG ][ org.kde.kstars.indi] - EQMod Mount : "[SCOPE] GetRAEncoder() = 9781331 "
[2020-06-22T01:41:12.689 CEST DEBG ][ org.kde.kstars.indi] - EQMod Mount : "[COMM] dispatch_command: \":j2\", 0 bytes written "
[2020-06-22T01:41:12.692 CEST DEBG ][ org.kde.kstars.indi] - EQMod Mount : "[COMM] read_eqmod: \"=00648D\", 8 bytes read "
[2020-06-22T01:41:12.692 CEST DEBG ][ org.kde.kstars.indi] - EQMod Mount : "[SCOPE] Current encoders RA=9781331 DE=9266176 "
[2020-06-22T01:41:12.693 CEST DEBG ][ org.kde.kstars.indi] - EQMod Mount : "[COMM] dispatch_command: \":f1\", 0 bytes written "
[2020-06-22T01:41:12.709 CEST DEBG ][ org.kde.kstars.indi] - EQMod Mount : "[COMM] read_eqmod: \"=111\", 5 bytes read "
[2020-06-22T01:41:12.709 CEST DEBG ][ org.kde.kstars.indi] - EQMod Mount : "[COMM] dispatch_command: \":f2\", 0 bytes written "
[2020-06-22T01:41:12.724 CEST DEBG ][ org.kde.kstars.indi] - EQMod Mount : "[COMM] read_eqmod: \"=301\", 5 bytes read "
[2020-06-22T01:41:13.725 CEST DEBG ][ org.kde.kstars.indi] - EQMod Mount : "[SCOPE] Compute local time: lst=17.69602309 (17:41:45.68) - julian date=2459022.48695042 "
[2020-06-22T01:41:13.725 CEST DEBG ][ org.kde.kstars.indi] - EQMod Mount : "[COMM] dispatch_command: \":j1\", 0 bytes written "
[2020-06-22T01:41:13.746 CEST DEBG ][ org.kde.kstars.indi] - EQMod Mount : "[COMM] read_eqmod: \"=C34095\", 8 bytes read "
[2020-06-22T01:41:13.746 CEST DEBG ][ org.kde.kstars.indi] - EQMod Mount : "[SCOPE] GetRAEncoder() = 9781443 "
[2020-06-22T01:41:13.746 CEST DEBG ][ org.kde.kstars.indi] - EQMod Mount : "[COMM] dispatch_command: \":j2\", 0 bytes written "
[2020-06-22T01:41:13.762 CEST DEBG ][ org.kde.kstars.indi] - EQMod Mount : "[COMM] read_eqmod: \"=00648D\", 8 bytes read "
[2020-06-22T01:41:13.762 CEST DEBG ][ org.kde.kstars.indi] - EQMod Mount : "[SCOPE] Current encoders RA=9781443 DE=9266176 "
[2020-06-22T01:41:13.763 CEST DEBG ][ org.kde.kstars.indi] - EQMod Mount : "[COMM] dispatch_command: \":f1\", 0 bytes written "
[2020-06-22T01:41:13.778 CEST DEBG ][ org.kde.kstars.indi] - EQMod Mount : "[COMM] read_eqmod: \"=111\", 5 bytes read "
[2020-06-22T01:41:13.778 CEST DEBG ][ org.kde.kstars.indi] - EQMod Mount : "[COMM] dispatch_command: \":f2\", 0 bytes written "
[2020-06-22T01:41:13.794 CEST DEBG ][ org.kde.kstars.indi] - EQMod Mount : "[COMM] read_eqmod: \"=301\", 5 bytes read "
[2020-06-22T01:41:14.795 CEST DEBG ][ org.kde.kstars.indi] - EQMod Mount : "[SCOPE] Compute local time: lst=17.69632126 (17:41:46.76) - julian date=2459022.48696281 "
[2020-06-22T01:41:14.795 CEST DEBG ][ org.kde.kstars.indi] - EQMod Mount : "[COMM] dispatch_command: \":j1\", 0 bytes written "
[2020-06-22T01:41:14.816 CEST DEBG ][ org.kde.kstars.indi] - EQMod Mount : "[COMM] read_eqmod: \"=334195\", 8 bytes read "
[2020-06-22T01:41:14.816 CEST DEBG ][ org.kde.kstars.indi] - EQMod Mount : "[SCOPE] GetRAEncoder() = 9781555 "
[2020-06-22T01:41:14.816 CEST DEBG ][ org.kde.kstars.indi] - EQMod Mount : "[COMM] dispatch_command: \":j2\", 0 bytes written "
[2020-06-22T01:41:14.832 CEST DEBG ][ org.kde.kstars.indi] - EQMod Mount : "[COMM] read_eqmod: \"=00648D\", 8 bytes read "
[2020-06-22T01:41:14.832 CEST DEBG ][ org.kde.kstars.indi] - EQMod Mount : "[SCOPE] Current encoders RA=9781555 DE=9266176 "
[2020-06-22T01:41:14.832 CEST DEBG ][ org.kde.kstars.indi] - EQMod Mount : "[COMM] dispatch_command: \":f1\", 0 bytes written "
[2020-06-22T01:41:14.848 CEST DEBG ][ org.kde.kstars.indi] - EQMod Mount : "[COMM] read_eqmod: \"=111\", 5 bytes read "
[2020-06-22T01:41:14.848 CEST DEBG ][ org.kde.kstars.indi] - EQMod Mount : "[COMM] dispatch_command: \":f2\", 0 bytes written "
[2020-06-22T01:41:14.864 CEST DEBG ][ org.kde.kstars.indi] - EQMod Mount : "[COMM] read_eqmod: \"=301\", 5 bytes read "
[2020-06-22T01:41:15.672 CEST INFO ][ org.kde.kstars.ekos.capture] - "Capturing 300,000-second image..."
[2020-06-22T01:41:15.673 CEST DEBG ][ org.kde.kstars.ekos.scheduler] - Capture State "Capturing"
[2020-06-22T01:41:15.673 CEST DEBG ][ org.kde.kstars.indi] - Canon DSLR EOS 700D : "[DEBUG] Aborting exposure... "
[2020-06-22T01:41:15.673 CEST DEBG ][ org.kde.kstars.indi] - Canon DSLR EOS 700D : "[DEBUG] Closing internal shutter. "
[2020-06-22T01:41:15.673 CEST DEBG ][ org.kde.kstars.indi] - Canon DSLR EOS 700D : "[DEBUG] Using widget:eosremoterelease "
[2020-06-22T01:41:15.673 CEST DEBG ][ org.kde.kstars.indi] - Canon DSLR EOS 700D : "[DEBUG] Setting radio/menu widget eosremoterelease: 4 (Release Full) "
[2020-06-22T01:41:15.685 CEST DEBG ][ org.kde.kstars.indi] - Canon DSLR EOS 700D : "[DEBUG] Setting new configuration OK. "
[2020-06-22T01:41:15.685 CEST DEBG ][ org.kde.kstars.indi] - Canon DSLR EOS 700D : "[DEBUG] Reading exposure... "
[2020-06-22T01:41:15.685 CEST DEBG ][ org.kde.kstars.indi] - Canon DSLR EOS 700D : "[DEBUG] Exposure complete. "
[2020-06-22T01:41:15.865 CEST DEBG ][ org.kde.kstars.indi] - EQMod Mount : "[SCOPE] Compute local time: lst=17.69661922 (17:41:47.83) - julian date=2459022.48697519 "
[2020-06-22T01:41:15.865 CEST DEBG ][ org.kde.kstars.indi] - EQMod Mount : "[COMM] dispatch_command: \":j1\", 0 bytes written "
[2020-06-22T01:41:15.885 CEST DEBG ][ org.kde.kstars.indi] - EQMod Mount : "[COMM] read_eqmod: \"=A34195\", 8 bytes read "
[2020-06-22T01:41:15.886 CEST DEBG ][ org.kde.kstars.indi] - EQMod Mount : "[SCOPE] GetRAEncoder() = 9781667 "
[2020-06-22T01:41:15.886 CEST DEBG ][ org.kde.kstars.indi] - EQMod Mount : "[COMM] dispatch_command: \":j2\", 0 bytes written "
[2020-06-22T01:41:15.901 CEST DEBG ][ org.kde.kstars.indi] - EQMod Mount : "[COMM] read_eqmod: \"=00648D\", 8 bytes read "
[2020-06-22T01:41:15.902 CEST DEBG ][ org.kde.kstars.indi] - EQMod Mount : "[SCOPE] Current encoders RA=9781667 DE=9266176 "
[2020-06-22T01:41:15.902 CEST DEBG ][ org.kde.kstars.indi] - EQMod Mount : "[COMM] dispatch_command: \":f1\", 0 bytes written "
[2020-06-22T01:41:15.918 CEST DEBG ][ org.kde.kstars.indi] - EQMod Mount : "[COMM] read_eqmod: \"=111\", 5 bytes read "
[2020-06-22T01:41:15.918 CEST DEBG ][ org.kde.kstars.indi] - EQMod Mount : "[COMM] dispatch_command: \":f2\", 0 bytes written "
[2020-06-22T01:41:15.934 CEST DEBG ][ org.kde.kstars.indi] - EQMod Mount : "[COMM] read_eqmod: \"=301\", 5 bytes read "
[2020-06-22T01:41:16.935 CEST DEBG ][ org.kde.kstars.indi] - EQMod Mount : "[SCOPE] Compute local time: lst=17.69691721 (17:41:48.90) - julian date=2459022.48698757 "

So testing with simulators I can see that the Scheduler restarts guiding on failure... not sure why it's not doing that with PHD2. I'll ask Eric since he's been investigating issues with PHD2 integration.

Hi
Any news on this?

I tried with an old git: Build: 2020-03-08T08:53:10Z
This works fine. If guiding fails, the scheduler recovers the guide star before going ahead.

The latest stable version: Build: 2020-04-25T20:01:00Z
Does not restart guiding.

Cheers and TIA,
Steve

Hi Steve,
I took a look into your log from June 21st/22nd. At 01:41:01 the scheduler starts successfully guiding and then asks the capture module to start. The current implementation has one weakness that if guiding fails exactly between the scheduler asks the capture module to start and the real start of the exposure, capturing is not stopped. In your case it was a matter of 20ms.

If guiding fails after capturing has started, everything is fine, capture tries a restart and in case this fails, capture aborts causing the scheduler for re-trying. But in your case, guiding failed unfortunately exactly in-between the scheduler sending the start command and capture starting. Therefore it glitched through.

It looks like we have a weakness that reacting on a failed guiding is controlled by capture, as soon as capturing has started. If guiding fails before the scheduler sends the start command to capture, the scheduler handles guiding failures. In the short time frame afterwards, unfortunately, nobody handles it.

What is really surprising for me that it seems as if it fails frequently for you, since the time frame where the weakness exists are (in your case) tiny 20ms.

Wolfgang

Hi Wolfgang and thanks for your time.

Is there a workaround or setting I could use?

Thanks

Hm, totally avoiding, I'm afraid, no. But hitting these 20ms isn't that easy, so it should occur only very rarely. As far as I see it happens only once, namely on June 22nd at 01:41:01 with frame 21, frame 032 is a succession.

Did it happen in other nights? I think it has nothing to do with the version you are using, this constructive weakness seems to be untouched for a long time.

I will take it on my list.

Wolfgang

Hi Wolfgang, everyone

It happened on 2 consecutive nights with: Build: 2020-04-25T20:01:00Z
log: drive.google.com/file/d/1AxQiqhn7QR7EKeQ...K2_/view?usp=sharing
I lost 20 frames
and...
log: drive.google.com/drive/folders/1l3v5fU0D...cdCJrlRU?usp=sharing
In the latter case I intervened manually as soon as guiding failed.

It doesn't seem to happen with an older git: 2020-03-08T08:53:10Z
log: drive.google.com/file/d/1YnWt2OGCFL2y1m2...4VE/view?usp=sharing
But of course that maybe just good luck!

Anything you want testing please don't hesitate to ask. Would love to finally get this fixed.
Thanks again,
Steve

Hi Steve,
the first link does not work. It would be great having it, so that I can compare it with the other night whether the same happened.

Wolfgang

Sorry about the links.

Here is the first link where I lost 20 frames with build: 2020-04-25T20:01:00Z
drive.google.com/file/d/1lLtWx8fRklXCrw5...Vvz/view?usp=sharing

Here is the link with the same build where I intervened manually. This folder contains the kstars and phd2 logs also.
drive.google.com/drive/folders/1l3v5fU0D...jLfd2I-LCvLzcdCJrlRU

Here is last night's session which lost no frames with build: 2020-03-08T08:53:10Z
drive.google.com/file/d/1ZJKxGcGNUGuq3gV...QbT/view?usp=sharing

TIA,
Steve