×

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

Bi-monthly release with minor bug fixes and improvements

Ekos Scheduler did not wake up

  • Posts: 24
  • Thank you received: 0
Very good point. I thought maybe it was obvious to some of you. I will enable verbose logs and re-try tonight. Thank you!
1 year 1 month ago #91590

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

  • Posts: 1226
  • Thank you received: 566
Lee,

Now that you mention it, there is one thing I'd suggest you try.

The original scheduler algorithm could definitely do something like you're describing (it tries very hard to "complete" the higher priority job before starting the 2nd job, even if the higher priority one can't run). I added the "Greedy" algorithm which schedules jobs more like the way you'd expect (i.e. when one job can run and the other can't, it runs the one can run--even if it is "lower priority").

Please make sure you are using the "Greedy" scheduler algorithm. I honestly don't know of many situations where one would want to run the "classic scheduling algorithm". I've been tempted to remove the classic scheme, but never have. Perhaps I should.

Hy

1 year 1 month ago #91591
Attachments:

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

  • Posts: 24
  • Thank you received: 0
You know that might be it. I love the greedy scheduler and always enable it, but maybe since job 2 was second in the list ... I'll try making it the priority tonight, with verbose logs enabled, and see what happens.

Thanks!!
1 year 1 month ago #91592

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

  • Posts: 1226
  • Thank you received: 566
If you were in fact using the greedy scheduler, then it should have done both no matter which job was higher priority. Do you have a log from last night?
1 year 1 month ago #91593

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

  • Posts: 24
  • Thank you received: 0
I have one, but there are about 100K lines about the mount debugging between the park command when it went to sleep, and when I woke it up in the AM. I've been debugging mount commands, but I will turn those off tonight. I will upload that log if there is a way to do that without polluting the chat. I don't see a way to upload the file independently of the chat. Is there?
1 year 1 month ago #91594

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

  • Posts: 1226
  • Thank you received: 566
I believe you can attach the file to this thread. Another way is to upload it (google drive or dropbox for example) and add the public link to it here.
1 year 1 month ago #91595

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

  • Posts: 24
  • Thank you received: 0
Ah. If I use reply instead of quick reply it is an option. But unfortunately its size exceeds that allowed by this site. So I have temporarily assigned it to this public dropbox. Please let me know when you have it so that I can clean it out.

www.dropbox.com/s/ovqxlnj2e4cj9f2/log_20-42-15.txt?dl=0
1 year 1 month ago #91596

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

  • Posts: 1226
  • Thank you received: 566
Got it, feel free to remove.
1 year 1 month ago #91597

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

  • Posts: 1226
  • Thank you received: 566
Lee,

You log is fine. It shows the lines I need to see, and I don't understand why it didn't wake up at 2:33am on 4/1. It was planning to wake up then, but nothing happened between 23:39 and 6:16 when it woke up due to pre-dawn.
[2023-03-31T23:39:01.613 MDT INFO ][ org.kde.kstars.ekos.scheduler] - "Cygnus-Part_27\t04/02  02:37 --> 02:52 \tjob completion"
[2023-03-31T23:39:01.615 MDT INFO ][ org.kde.kstars.ekos.scheduler] - "Orion-Part_61\t04/01  20:51 --> 21:36 \tjob completion"
[2023-03-31T23:39:01.617 MDT INFO ][ org.kde.kstars.ekos.scheduler] - "Cygnus-Part_27\t04/01  03:54 --> 05:00 \ttwilight"
[2023-03-31T23:39:01.619 MDT INFO ][ org.kde.kstars.ekos.scheduler] - "Cygnus-Part_26\t04/01  02:33 --> 03:53 \tjob completion"
[2023-03-31T23:39:01.621 MDT INFO ][ org.kde.kstars.ekos.scheduler] - "Greedy Scheduler plan for the next 48 hours starting Fri Mar 31 23:39:01 2023 (0.04)s:"
[2023-03-31T23:39:01.630 MDT INFO ][     org.kde.kstars.ekos.guide] - "PHD2: Guiding started."
[2023-03-31T23:39:01.635 MDT INFO ][     org.kde.kstars.ekos.guide] - "Autoguiding started."
[2023-03-31T23:39:02.585 MDT INFO ][ org.kde.kstars.ekos.scheduler] - "Job 'Cygnus-Part_26' scheduled for execution at 01/04 02:33. Observatory scheduled for shutdown until next job is ready."
[2023-03-31T23:39:02.591 MDT INFO ][ org.kde.kstars.ekos.scheduler] - Starting shutdown process...
[2023-03-31T23:39:03.567 MDT DEBG ][           org.kde.kstars.indi] - ISD:Telescope: Parking... 
...
[2023-03-31T23:39:27.586 MDT INFO ][ org.kde.kstars.ekos.scheduler] - "Mount parked."
[2023-04-01T06:16:46.980 MDT INFO ][ org.kde.kstars.ekos.scheduler] - Scheduler is stopping...
Just a wild guess that there's something in all those logging interactions with the mount, but I doubt that's it.
I suppose try and run without all that indi/mount logging.
If you want, please share your .esl and .esq files with me and I'll see if I can re-recreate the issue in the simulator.

Hy
1 year 1 month ago #91598

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

  • Posts: 24
  • Thank you received: 0
That would not surprise me (retired hardware programmer from academia and NASA, et al). I will disable that logging and re-run tonight exactly from yesterday's schedule.esl
1 year 1 month ago #91599

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

  • Posts: 1226
  • Thank you received: 566
Lee,

Please share .esq and .esl files. I really doubt that's it. Here's something else...I put together a toy schedule that ran one job (one image capture) and then slept waiting to wake up several hours later. In my (successful) simulation here's what happened:
[2023-04-01T16:14:31.476 PDT INFO ][ org.kde.kstars.ekos.scheduler] - "Job 'Capella' is complete."
...
[2023-04-01T16:14:32.966 PDT INFO ][ org.kde.kstars.ekos.scheduler] - "Job 'Alioth' scheduled for execution at 01/04 21:47. Observatory scheduled for shutdown until next job is ready."
[2023-04-01T16:14:32.967 PDT DEBG ][ org.kde.kstars.ekos.scheduler] - Checking shutdown state...
[2023-04-01T16:14:32.967 PDT INFO ][ org.kde.kstars.ekos.scheduler] - Starting shutdown process...
[2023-04-01T16:14:33.965 PDT DEBG ][ org.kde.kstars.ekos.scheduler] - Checking shutdown state...
[2023-04-01T16:14:33.966 PDT INFO ][ org.kde.kstars.ekos.scheduler] - "Parking mount in progress..."
...
[2023-04-01T16:14:45.694 PDT INFO ][           org.kde.kstars.indi] - Telescope Simulator :  "[INFO] Mount is parked. "
[2023-04-01T16:14:45.695 PDT INFO ][     org.kde.kstars.ekos.align] - Target coordinates updated to JNow RA: "03h 46m 02s" DE: " 90° 00' 00\""
[2023-04-01T16:14:45.695 PDT INFO ][           org.kde.kstars.indi] - Telescope Simulator :  "[INFO] Telescope slew is complete. Parked "
[2023-04-01T16:14:45.716 PDT DEBG ][     org.kde.kstars.ekos.mount] - Mount status changed from  "Parking"  to  "Parked"
[2023-04-01T16:14:45.965 PDT DEBG ][ org.kde.kstars.ekos.scheduler] - Checking shutdown state...
[2023-04-01T16:14:45.965 PDT INFO ][ org.kde.kstars.ekos.scheduler] - "Mount parked."
[2023-04-01T16:14:46.965 PDT DEBG ][ org.kde.kstars.ekos.scheduler] - Checking shutdown state...
[2023-04-01T16:14:47.965 PDT DEBG ][ org.kde.kstars.ekos.scheduler] - Checking shutdown state...
[2023-04-01T16:14:48.967 PDT DEBG ][ org.kde.kstars.ekos.scheduler] - Checking shutdown state...
[2023-04-01T16:14:48.967 PDT INFO ][ org.kde.kstars.ekos.scheduler] - Disconnecting INDI...
[2023-04-01T16:14:48.968 PDT DEBG ][           org.kde.kstars.ekos] - Disconnecting  "Telescope Simulator"
[2023-04-01T16:14:48.968 PDT DEBG ][           org.kde.kstars.ekos] - Disconnecting  "CCD Simulator"
[2023-04-01T16:14:48.968 PDT DEBG ][           org.kde.kstars.ekos] - Disconnecting  "Focuser Simulator"
[2023-04-01T16:14:48.968 PDT DEBG ][           org.kde.kstars.ekos] - Disconnecting  "Guide Simulator"
[2023-04-01T16:14:48.968 PDT INFO ][           org.kde.kstars.ekos] - "Disconnecting INDI devices..."
[2023-04-01T16:14:49.010 PDT INFO ][           org.kde.kstars.indi] - Telescope Simulator :  "[INFO] Telescope simulator is offline. "
[2023-04-01T16:14:49.011 PDT DEBG ][           org.kde.kstars.ekos] - "Telescope Simulator"  is disconnected.
[2023-04-01T16:14:49.011 PDT INFO ][           org.kde.kstars.ekos] - "Telescope Simulator is disconnected."
[2023-04-01T16:14:49.013 PDT DEBG ][           org.kde.kstars.ekos] - "CCD Simulator"  is disconnected.
[2023-04-01T16:14:49.013 PDT INFO ][           org.kde.kstars.ekos] - "CCD Simulator is disconnected."
[2023-04-01T16:14:49.019 PDT DEBG ][           org.kde.kstars.ekos] - "Focuser Simulator"  is disconnected.
[2023-04-01T16:14:49.019 PDT INFO ][           org.kde.kstars.ekos] - "Focuser Simulator is disconnected."
[2023-04-01T16:14:49.024 PDT DEBG ][           org.kde.kstars.ekos] - "Guide Simulator"  is disconnected.
[2023-04-01T16:14:49.025 PDT INFO ][           org.kde.kstars.ekos] - "Guide Simulator is disconnected."
[2023-04-01T16:14:49.028 PDT DEBG ][ org.kde.kstars.ekos.scheduler] - Scheduler INDI status is 0
[2023-04-01T16:14:49.965 PDT DEBG ][ org.kde.kstars.ekos.scheduler] - Checking shutdown state...
[2023-04-01T16:14:49.965 PDT INFO ][ org.kde.kstars.ekos.scheduler] - "INDI devices disconnected."
[2023-04-01T16:14:49.967 PDT INFO ][ org.kde.kstars.ekos.scheduler] - Stopping Ekos...
[2023-04-01T16:14:49.975 PDT DEBG ][           org.kde.kstars.ekos] - Resetting Ekos Manager...
[2023-04-01T16:14:49.993 PDT INFO ][           org.kde.kstars.ekos] - "INDI services stopped."
[2023-04-01T16:14:50.006 PDT DEBG ][ org.kde.kstars.ekos.scheduler] - Scheduler Ekos status is 0
[2023-04-01T16:14:50.965 PDT DEBG ][ org.kde.kstars.ekos.scheduler] - Checking shutdown state...
[2023-04-01T16:14:50.965 PDT INFO ][ org.kde.kstars.ekos.scheduler] - "Ekos stopped."
[2023-04-01T16:14:50.967 PDT INFO ][ org.kde.kstars.ekos.scheduler] - "Shutdown complete."
[2023-04-01T16:14:50.967 PDT INFO ][ org.kde.kstars.ekos.scheduler] - Scheduler is stopping...

Note all the stuff that happens after "Mount Parked". Your log had none of that. Your scheduler didn't do anything after "Mount Parked", and thus probably didn't shutdown properly and thus (my guess is) probably didn't set its timer to wake up.

You say you are debugging the mount. Do you think there might be some communications issue that might be confusing INDI and somehow causing it to hang?
Hy
1 year 1 month ago #91600

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

  • Posts: 1226
  • Thank you received: 566
Lee,

Since you're a retired NASA programmer, perhaps you can join in and help debug this.

After the scheduler gets the "PARK_PARKED" message from INDI
invent.kde.org/education/kstars/-/blob/m.../scheduler.cpp#L6344

There's a complex state machine that the scheduler works through, waking up every second to check on things.

In any case, please make sure that verbose logging is enabled.
Perhaps that's why you were missing all those other messages, not sure.
I'm happy to look again at the verbose log tomorrow.

Hy
1 year 1 month ago #91601

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

Time to create page: 1.405 seconds