https://bugs.kde.org/show_bug.cgi?id=410094

            Bug ID: 410094
           Summary: Kstars sometimes fails to wait for a slew to happen
                    before slew is regarded as complete on the mount tool.
           Product: kstars
           Version: git
          Platform: Compiled Sources
                OS: Linux
            Status: REPORTED
          Severity: normal
          Priority: NOR
         Component: general
          Assignee: mutla...@ikarustech.com
          Reporter: deathstal...@gmail.com
  Target Milestone: ---

SUMMARY
In some cases when using the mount model tool, KStars/Ekos has immediately
decided the slew is over. 

Ex: 
[2019-07-19T01:17:54.891 CDT INFO ][     org.kde.kstars.ekos.align] - "Slewing
to target coordinates: RA (00h 00m 00s) DEC ( 55° 51' 27\")."
[2019-07-19T01:17:55.064 CDT INFO ][     org.kde.kstars.ekos.align] - "Slew
complete. Solving Alignment Point. . ."
[2019-07-19T01:17:55.065 CDT INFO ][     org.kde.kstars.ekos.align] -
"Settling..."

This comes from Align::processNumber in align.cpp, which seems to only check if
the Equatorial coordinates show as IPS_OK, even if the mount has had no chance
to update the status. (In this case I had it as 200ms, you'll note that the
Slew to Slew Complete is less than that, and I think the default polling is
something like 500ms from the lx200 derived drivers.) So it seems that there
should be a minimum delay before it fires off, and/or a state change to/from
tracking/slewing before it registers.

Do note this is also kstars running on one machine, and gigabit ethernet to a
mini pc (also running the same OS) 

It's also possible that the driver isn't doing something right, but based on
what I can tell, the driver starts doing that immediately after a status update
finishes. (Which is a little talky, but I don't belive that's the issue here.)

Timeline which may be more clear:
2019-07-19T01:17:54.555 (Update, Mode: Tracking)
2019-07-19T01:17:54.870 (Command to sync sent to mount)
2019-07-19T01:17:54.891 (Ekos align module, says send to slew) 
2019-07-19T01:17:54.951 (Update, Mode: Tracking as Command not yet sent)
2019-07-19T01:17:55.064 (Ekos regards slew as complete)
2019-07-19T01:17:55.096 (Command to Goto sent to mount)
2019-07-19T01:17:55.292 (Update, Mode: Slewing)
2019-07-19T01:17:55.365 (Ekos mount registers status change)
2019-07-19T01:17:55.660 (Update, Mode: Slewing)


STEPS TO REPRODUCE
1. Keep Aligning and it will sometimes happen. It is not a regular thing. My
assumption is that there is the condition of it getting to that case only
sometimes. 

OBSERVED RESULT
Using the mount model, sometimes the Align module decides it's done with the
slew, even if the slew is far from complete. 

EXPECTED RESULT
Wait for the slew, solve, sync, slew, until everything is done. As it does,
some times. 


SOFTWARE/OS VERSIONS
Kstars: (roughly 3.3.2) Build: 2019-07-03T05:13:32Z

Operating System: Kubuntu 19.04
KDE Plasma Version: 5.15.4
KDE Frameworks Version: 5.56.0
Qt Version: 5.12.2
Kernel Version: 5.0.0-20-generic
OS Type: 64-bit
Processors: 8 × Intel® Core™ i7-4700HQ CPU @ 2.40GHz
Memory: 23.5 GiB of RAM



ADDITIONAL INFORMATION


More complete log section, if needed the full one can be attached, but it is
213MB


[2019-07-19T01:17:54.790 CDT INFO ][     org.kde.kstars.ekos.align] - "Solver
completed in 4 seconds."
[2019-07-19T01:17:54.798 CDT INFO ][     org.kde.kstars.ekos.align] - "Solution
coordinates: RA (18h 35m 21s) DEC ( 38° 51' 53\") Telescope Coordinates: RA
(18h 37m 35s) DEC ( 38° 48' 07\")"
[2019-07-19T01:17:54.798 CDT DEBG ][           org.kde.kstars.indi] -
ISD:Telescope: Syncing...
[2019-07-19T01:17:54.803 CDT DEBG ][           org.kde.kstars.indi] -
ISD:Telescope sending coords RA: "18h 35m 21s" ( 18.5893 ) DE: " 38° 51' 53\""
( 38.8649 )
[2019-07-19T01:17:54.803 CDT INFO ][     org.kde.kstars.ekos.align] - "Syncing
to RA (18h 35m 21s) DEC ( 38° 51' 53\")"
[2019-07-19T01:17:54.840 CDT INFO ][           org.kde.kstars.indi] - Nikon
DSLR DSC D5300 :  "[INFO] Saving device configuration... "
[2019-07-19T01:17:54.840 CDT DEBG ][           org.kde.kstars.indi] - LX200
OnStep : "[SCOPE] <setObjectRA> "
[2019-07-19T01:17:54.840 CDT DEBG ][           org.kde.kstars.indi] - LX200
OnStep : "[SCOPE] CMD <:Sr 18:35:21#> "
[2019-07-19T01:17:54.840 CDT INFO ][           org.kde.kstars.indi] - Nikon
DSLR DSC D5300 :  "[INFO] Device configuration saved. "
[2019-07-19T01:17:54.841 CDT DEBG ][           org.kde.kstars.indi] - Nikon
DSLR DSC D5300 : "[DEBUG] Configuration successfully saved. "
[2019-07-19T01:17:54.842 CDT INFO ][           org.kde.kstars.indi] - Nikon
DSLR DSC D5300 :  "[INFO] CCD FOV rotation updated to -107.446 degrees. "
[2019-07-19T01:17:54.853 CDT DEBG ][           org.kde.kstars.indi] - LX200
OnStep : "[SCOPE] CMD <:Sr 18:35:21#> successful. "
[2019-07-19T01:17:54.854 CDT DEBG ][           org.kde.kstars.indi] - LX200
OnStep : "[SCOPE] <setObjectDEC> "
[2019-07-19T01:17:54.854 CDT DEBG ][           org.kde.kstars.indi] - LX200
OnStep : "[SCOPE] CMD <:Sd +38:51:54#> "
[2019-07-19T01:17:54.870 CDT DEBG ][           org.kde.kstars.indi] - LX200
OnStep : "[SCOPE] CMD <:Sd +38:51:54#> successful. "
[2019-07-19T01:17:54.870 CDT DEBG ][           org.kde.kstars.indi] - LX200
OnStep : "[DEBUG] CMD <:CM#> "
[2019-07-19T01:17:54.870 CDT DEBG ][           org.kde.kstars.indi] - LX200
OnStep : "[SCOPE] CMD <:CM#> "
[2019-07-19T01:17:54.881 CDT DEBG ][           org.kde.kstars.indi] - LX200
OnStep : "[SCOPE] RES <N/A> "
[2019-07-19T01:17:54.882 CDT DEBG ][           org.kde.kstars.indi] - LX200
OnStep : "[DEBUG] RES <N/A> "
[2019-07-19T01:17:54.882 CDT INFO ][           org.kde.kstars.indi] - LX200
OnStep :  "[INFO] OnStep: Synchronization successful. "
[2019-07-19T01:17:54.882 CDT INFO ][     org.kde.kstars.ekos.align] - "Mount is
synced to solution coordinates. Astrometric solver is successful."
[2019-07-19T01:17:54.885 CDT DEBG ][           org.kde.kstars.indi] -
ISD:Telescope:  TRACK
[2019-07-19T01:17:54.890 CDT DEBG ][           org.kde.kstars.indi] -
ISD:Telescope sending coords RA: "00h 00m 00s" ( 9.8125e-05 ) DE: " 55° 51'
27\"" ( 55.8577 )
[2019-07-19T01:17:54.891 CDT INFO ][     org.kde.kstars.ekos.align] - "Slewing
to target coordinates: RA (00h 00m 00s) DEC ( 55° 51' 27\")."
[2019-07-19T01:17:54.926 CDT DEBG ][           org.kde.kstars.indi] - LX200
OnStep : "[SCOPE] CMD <:GR#> "
[2019-07-19T01:17:54.926 CDT DEBG ][           org.kde.kstars.indi] - LX200
OnStep : "[SCOPE] RES <18:37:35> "
[2019-07-19T01:17:54.951 CDT DEBG ][           org.kde.kstars.indi] - LX200
OnStep : "[SCOPE] VAL [18.6264] "
[2019-07-19T01:17:54.951 CDT DEBG ][           org.kde.kstars.indi] - LX200
OnStep : "[SCOPE] CMD <:GD#> "
[2019-07-19T01:17:54.951 CDT DEBG ][           org.kde.kstars.indi] - LX200
OnStep : "[SCOPE] RES <+38*48:07> "
[2019-07-19T01:17:54.951 CDT DEBG ][           org.kde.kstars.indi] - LX200
OnStep : "[SCOPE] VAL [38.8019] "
[2019-07-19T01:17:54.951 CDT DEBG ][           org.kde.kstars.indi] - LX200
OnStep : "[SCOPE] CMD <:GU#> "
[2019-07-19T01:17:54.951 CDT DEBG ][           org.kde.kstars.indi] - LX200
OnStep : "[SCOPE] RES <Nptza/ET270> "
[2019-07-19T01:17:54.951 CDT DEBG ][           org.kde.kstars.indi] - LX200
OnStep : "[SCOPE] CMD <:Gm#> "
[2019-07-19T01:17:54.951 CDT DEBG ][           org.kde.kstars.indi] - LX200
OnStep : "[SCOPE] RES <E> "
[2019-07-19T01:17:54.951 CDT DEBG ][           org.kde.kstars.indi] - LX200
OnStep : "[SCOPE] CMD <:%BD#> "
[2019-07-19T01:17:54.957 CDT DEBG ][           org.kde.kstars.indi] - LX200
OnStep : "[SCOPE] RES <0> "
[2019-07-19T01:17:54.957 CDT DEBG ][           org.kde.kstars.indi] - LX200
OnStep : "[SCOPE] CMD <:%BR#> "
[2019-07-19T01:17:54.966 CDT DEBG ][           org.kde.kstars.indi] - LX200
OnStep : "[SCOPE] RES <0> "
[2019-07-19T01:17:54.966 CDT DEBG ][           org.kde.kstars.indi] - LX200
OnStep : "[SCOPE] CMD <:GX90#> "
[2019-07-19T01:17:54.978 CDT DEBG ][           org.kde.kstars.indi] - LX200
OnStep : "[SCOPE] RES <1.00> "
[2019-07-19T01:17:54.979 CDT DEBG ][           org.kde.kstars.indi] - LX200
OnStep : "[DEBUG] Guide Rate: 1.000000 "
[2019-07-19T01:17:54.979 CDT DEBG ][           org.kde.kstars.indi] - LX200
OnStep : "[SCOPE] CMD <:GX95#> "
[2019-07-19T01:17:54.988 CDT DEBG ][           org.kde.kstars.indi] - LX200
OnStep : "[SCOPE] RES <1> "
[2019-07-19T01:17:54.989 CDT DEBG ][           org.kde.kstars.indi] - LX200
OnStep : "[SCOPE] CMD <:GX96#> "
[2019-07-19T01:17:54.998 CDT DEBG ][           org.kde.kstars.indi] - LX200
OnStep : "[SCOPE] RES <B> "
[2019-07-19T01:17:54.998 CDT DEBG ][           org.kde.kstars.indi] - LX200
OnStep : "[SCOPE] CMD <:GXE9#> "
[2019-07-19T01:17:55.009 CDT DEBG ][           org.kde.kstars.indi] - LX200
OnStep : "[SCOPE] RES <30> "
[2019-07-19T01:17:55.009 CDT DEBG ][           org.kde.kstars.indi] - LX200
OnStep : "[SCOPE] CMD <:GXEA#> "
[2019-07-19T01:17:55.019 CDT DEBG ][           org.kde.kstars.indi] - LX200
OnStep : "[SCOPE] RES <30> "
[2019-07-19T01:17:55.021 CDT DEBG ][           org.kde.kstars.indi] - LX200
OnStep : "[SCOPE] CMD <:A?#> "
[2019-07-19T01:17:55.029 CDT DEBG ][           org.kde.kstars.indi] - LX200
OnStep : "[SCOPE] RES <636> "
[2019-07-19T01:17:55.029 CDT DEBG ][           org.kde.kstars.indi] - LX200
OnStep : "[DEBUG] Align: max_stars: 6 current star: 3, align_stars 6 "
[2019-07-19T01:17:55.030 CDT DEBG ][           org.kde.kstars.indi] - LX200
OnStep : "[SCOPE] CMD <:GX02#> "
[2019-07-19T01:17:55.039 CDT DEBG ][           org.kde.kstars.indi] - LX200
OnStep : "[SCOPE] RES <0> "
[2019-07-19T01:17:55.039 CDT DEBG ][           org.kde.kstars.indi] - LX200
OnStep : "[SCOPE] CMD <:GX03#> "
[2019-07-19T01:17:55.048 CDT DEBG ][           org.kde.kstars.indi] - LX200
OnStep : "[SCOPE] RES <0> "
[2019-07-19T01:17:55.049 CDT DEBG ][           org.kde.kstars.indi] - LX200
OnStep : "[SCOPE] CMD <:$QZ?#> "
[2019-07-19T01:17:55.058 CDT DEBG ][           org.kde.kstars.indi] - LX200
OnStep : "[SCOPE] RES <I> "
[2019-07-19T01:17:55.064 CDT INFO ][     org.kde.kstars.ekos.align] - "Slew
complete. Solving Alignment Point. . ."
[2019-07-19T01:17:55.065 CDT INFO ][     org.kde.kstars.ekos.align] -
"Settling..."
[2019-07-19T01:17:55.065 CDT DEBG ][           org.kde.kstars.indi] - LX200
OnStep : "[SCOPE] <setObjectRA> "
[2019-07-19T01:17:55.066 CDT DEBG ][           org.kde.kstars.indi] - LX200
OnStep : "[SCOPE] CMD <:Sr 00:00:00#> "
[2019-07-19T01:17:55.096 CDT DEBG ][           org.kde.kstars.indi] - LX200
OnStep : "[SCOPE] CMD <:Sr 00:00:00#> successful. "
[2019-07-19T01:17:55.096 CDT DEBG ][           org.kde.kstars.indi] - LX200
OnStep : "[SCOPE] <setObjectDEC> "
[2019-07-19T01:17:55.096 CDT DEBG ][           org.kde.kstars.indi] - LX200
OnStep : "[SCOPE] CMD <:Sd +55:51:28#> "
[2019-07-19T01:17:55.096 CDT DEBG ][           org.kde.kstars.indi] - LX200
OnStep : "[SCOPE] CMD <:Sd +55:51:28#> successful. "
[2019-07-19T01:17:55.096 CDT DEBG ][           org.kde.kstars.indi] - LX200
OnStep : "[SCOPE] <Slew> "
[2019-07-19T01:17:55.096 CDT DEBG ][           org.kde.kstars.indi] - LX200
OnStep : "[SCOPE] CMD <:MS#> "
[2019-07-19T01:17:55.102 CDT DEBG ][           org.kde.kstars.indi] - LX200
OnStep : "[SCOPE] RES <0> "
[2019-07-19T01:17:55.103 CDT INFO ][           org.kde.kstars.indi] - LX200
OnStep :  "[INFO] Slewing to RA:  0:00:00 - DEC: 55:51:28 "
[2019-07-19T01:17:55.259 CDT DEBG ][           org.kde.kstars.indi] - LX200
OnStep : "[SCOPE] CMD <:GR#> "
[2019-07-19T01:17:55.275 CDT DEBG ][           org.kde.kstars.indi] - LX200
OnStep : "[SCOPE] RES <18:37:31> "
[2019-07-19T01:17:55.276 CDT DEBG ][           org.kde.kstars.indi] - LX200
OnStep : "[SCOPE] VAL [18.6253] "
[2019-07-19T01:17:55.276 CDT DEBG ][           org.kde.kstars.indi] - LX200
OnStep : "[SCOPE] CMD <:GD#> "
[2019-07-19T01:17:55.291 CDT DEBG ][           org.kde.kstars.indi] - LX200
OnStep : "[SCOPE] RES <+38*49:05> "
[2019-07-19T01:17:55.292 CDT DEBG ][           org.kde.kstars.indi] - LX200
OnStep : "[SCOPE] VAL [38.8181] "
[2019-07-19T01:17:55.292 CDT DEBG ][           org.kde.kstars.indi] - LX200
OnStep : "[SCOPE] CMD <:GU#> "
[2019-07-19T01:17:55.310 CDT DEBG ][           org.kde.kstars.indi] - LX200
OnStep : "[SCOPE] RES <nptza/ET270> "
[2019-07-19T01:17:55.310 CDT DEBG ][           org.kde.kstars.indi] - LX200
OnStep : "[SCOPE] CMD <:Gm#> "
[2019-07-19T01:17:55.317 CDT DEBG ][           org.kde.kstars.indi] - LX200
OnStep : "[SCOPE] RES <E> "
[2019-07-19T01:17:55.317 CDT DEBG ][           org.kde.kstars.indi] - LX200
OnStep : "[SCOPE] CMD <:%BD#> "
[2019-07-19T01:17:55.326 CDT DEBG ][           org.kde.kstars.indi] - LX200
OnStep : "[SCOPE] RES <0> "
[2019-07-19T01:17:55.326 CDT DEBG ][           org.kde.kstars.indi] - LX200
OnStep : "[SCOPE] CMD <:%BR#> "
[2019-07-19T01:17:55.335 CDT DEBG ][           org.kde.kstars.indi] - LX200
OnStep : "[SCOPE] RES <0> "
[2019-07-19T01:17:55.335 CDT DEBG ][           org.kde.kstars.indi] - LX200
OnStep : "[SCOPE] CMD <:GX90#> "
[2019-07-19T01:17:55.348 CDT DEBG ][           org.kde.kstars.indi] - LX200
OnStep : "[SCOPE] RES <1.00> "
[2019-07-19T01:17:55.348 CDT DEBG ][           org.kde.kstars.indi] - LX200
OnStep : "[DEBUG] Guide Rate: 1.000000 "
[2019-07-19T01:17:55.349 CDT DEBG ][           org.kde.kstars.indi] - LX200
OnStep : "[SCOPE] CMD <:GX95#> "
[2019-07-19T01:17:55.358 CDT DEBG ][           org.kde.kstars.indi] - LX200
OnStep : "[SCOPE] RES <1> "
[2019-07-19T01:17:55.358 CDT DEBG ][           org.kde.kstars.indi] - LX200
OnStep : "[SCOPE] CMD <:GX96#> "
[2019-07-19T01:17:55.365 CDT DEBG ][     org.kde.kstars.ekos.mount] - Mount
status changed from  3  to  2

-- 
You are receiving this mail because:
You are watching all bug changes.

Reply via email to