×
INDI Library v1.8.1 Released (09 Sep 2019)

Monthly maintenance release INDI v1.8.1

Scheduler hangs with last message 'Ekos communication status is 0 Starting Ekos'

2 weeks 3 days ago
ManifestStephanie
Fresh Boarder
Fresh Boarder
Posts: 1
More
Topic Author
Scheduler hangs with last message 'Ekos communication status is 0 Starting Ekos' #45364
Hi everybody,

I'm trying to run scheduler for a two panel mosaic and it hangs with the message 'No job running'. In the log, the last message is 'Ekos communication status is 0 Starting Ekos'.

I saw the previous thread about this issue and I tried dbus-monitor to see if dbus was working. The only message I saw (other than UI stuff) was:

method call time=1572845614.764144 sender=:1.39 -> destination=org.kde.kstars serial=205 path=/KStars/Ekos; interface=org.kde.kstars.Ekos; member=start

Any ideas on what is going on here or how to get better debugging would be appreciated!

Best,
Stephanie

RPi4
Raspian Buster
KStars version 3.3.5
rlancaste's script
Linux (armv7l) release 4.19.66-v7l+
QSI, Pegasus PPB, Nightcrawler Focuser, Paramount via TCP

Log from session:

[2019-11-03T21:33:31.756 PST INFO ][ org.kde.kstars.ekos.scheduler] - Scheduler is starting...
[2019-11-03T21:33:31.767 PST DEBG ][ org.kde.kstars.ekos.scheduler] - "Searching in path '/home/pi/IC1848-Part2/IC1848-Part2/Light/HA', files 'IC1848-Part2_Light_HA_300_secs*' for prefix 'IC1848-Part2_Light_HA_300_secs'..."
[2019-11-03T21:33:31.768 PST DEBG ][ org.kde.kstars.ekos.scheduler] - "Searching in path '/home/pi/IC1848-Part2/IC1848-Part2/Light/OIII', files 'IC1848-Part2_Light_OIII_300_secs*' for prefix 'IC1848-Part2_Light_OIII_300_secs'..."
[2019-11-03T21:33:31.768 PST DEBG ][ org.kde.kstars.ekos.scheduler] - "Searching in path '/home/pi/IC1848-Part2/IC1848-Part2/Light/SII', files 'IC1848-Part2_Light_SII_300_secs*' for prefix 'IC1848-Part2_Light_SII_300_secs'..."
[2019-11-03T21:33:31.770 PST DEBG ][ org.kde.kstars.ekos.scheduler] - "Searching in path '/home/pi/IC1848-Part1/IC1848-Part1/Light/HA', files 'IC1848-Part1_Light_HA_300_secs*' for prefix 'IC1848-Part1_Light_HA_300_secs'..."
[2019-11-03T21:33:31.771 PST DEBG ][ org.kde.kstars.ekos.scheduler] - "Searching in path '/home/pi/IC1848-Part1/IC1848-Part1/Light/OIII', files 'IC1848-Part1_Light_OIII_300_secs*' for prefix 'IC1848-Part1_Light_OIII_300_secs'..."
[2019-11-03T21:33:31.771 PST DEBG ][ org.kde.kstars.ekos.scheduler] - "Searching in path '/home/pi/IC1848-Part1/IC1848-Part1/Light/SII', files 'IC1848-Part1_Light_SII_300_secs*' for prefix 'IC1848-Part1_Light_SII_300_secs'..."
[2019-11-03T21:33:31.772 PST DEBG ][ org.kde.kstars.ekos.scheduler] - Frame map summary:
[2019-11-03T21:33:31.772 PST DEBG ][ org.kde.kstars.ekos.scheduler] - "/home/pi/IC1848-Part1/IC1848-Part1/Light/HA/IC1848-Part1_Light_HA_300_secs" : 0
[2019-11-03T21:33:31.772 PST DEBG ][ org.kde.kstars.ekos.scheduler] - "/home/pi/IC1848-Part1/IC1848-Part1/Light/OIII/IC1848-Part1_Light_OIII_300_secs" : 0
[2019-11-03T21:33:31.773 PST DEBG ][ org.kde.kstars.ekos.scheduler] - "/home/pi/IC1848-Part1/IC1848-Part1/Light/SII/IC1848-Part1_Light_SII_300_secs" : 0
[2019-11-03T21:33:31.773 PST DEBG ][ org.kde.kstars.ekos.scheduler] - "/home/pi/IC1848-Part2/IC1848-Part2/Light/HA/IC1848-Part2_Light_HA_300_secs" : 0
[2019-11-03T21:33:31.773 PST DEBG ][ org.kde.kstars.ekos.scheduler] - "/home/pi/IC1848-Part2/IC1848-Part2/Light/OIII/IC1848-Part2_Light_OIII_300_secs" : 0
[2019-11-03T21:33:31.774 PST DEBG ][ org.kde.kstars.ekos.scheduler] - "/home/pi/IC1848-Part2/IC1848-Part2/Light/SII/IC1848-Part2_Light_SII_300_secs" : 0
[2019-11-03T21:33:31.776 PST DEBG ][ org.kde.kstars.ekos.scheduler] - "Searching in path '/home/pi/IC1848-Part2/IC1848-Part2/Light/HA', files 'IC1848-Part2_Light_HA_300_secs*' for prefix 'IC1848-Part2_Light_HA_300_secs'..."
[2019-11-03T21:33:31.776 PST DEBG ][ org.kde.kstars.ekos.scheduler] - "Searching in path '/home/pi/IC1848-Part2/IC1848-Part2/Light/OIII', files 'IC1848-Part2_Light_OIII_300_secs*' for prefix 'IC1848-Part2_Light_OIII_300_secs'..."
[2019-11-03T21:33:31.777 PST DEBG ][ org.kde.kstars.ekos.scheduler] - "Searching in path '/home/pi/IC1848-Part2/IC1848-Part2/Light/SII', files 'IC1848-Part2_Light_SII_300_secs*' for prefix 'IC1848-Part2_Light_SII_300_secs'..."
[2019-11-03T21:33:31.779 PST DEBG ][ org.kde.kstars.ekos.scheduler] - "Searching in path '/home/pi/IC1848-Part1/IC1848-Part1/Light/HA', files 'IC1848-Part1_Light_HA_300_secs*' for prefix 'IC1848-Part1_Light_HA_300_secs'..."
[2019-11-03T21:33:31.779 PST DEBG ][ org.kde.kstars.ekos.scheduler] - "Searching in path '/home/pi/IC1848-Part1/IC1848-Part1/Light/OIII', files 'IC1848-Part1_Light_OIII_300_secs*' for prefix 'IC1848-Part1_Light_OIII_300_secs'..."
[2019-11-03T21:33:31.780 PST DEBG ][ org.kde.kstars.ekos.scheduler] - "Searching in path '/home/pi/IC1848-Part1/IC1848-Part1/Light/SII', files 'IC1848-Part1_Light_SII_300_secs*' for prefix 'IC1848-Part1_Light_SII_300_secs'..."
[2019-11-03T21:33:31.780 PST DEBG ][ org.kde.kstars.ekos.scheduler] - Frame map summary:
[2019-11-03T21:33:31.780 PST DEBG ][ org.kde.kstars.ekos.scheduler] - "/home/pi/IC1848-Part1/IC1848-Part1/Light/HA/IC1848-Part1_Light_HA_300_secs" : 0
[2019-11-03T21:33:31.781 PST DEBG ][ org.kde.kstars.ekos.scheduler] - "/home/pi/IC1848-Part1/IC1848-Part1/Light/OIII/IC1848-Part1_Light_OIII_300_secs" : 0
[2019-11-03T21:33:31.781 PST DEBG ][ org.kde.kstars.ekos.scheduler] - "/home/pi/IC1848-Part1/IC1848-Part1/Light/SII/IC1848-Part1_Light_SII_300_secs" : 0
[2019-11-03T21:33:31.781 PST DEBG ][ org.kde.kstars.ekos.scheduler] - "/home/pi/IC1848-Part2/IC1848-Part2/Light/HA/IC1848-Part2_Light_HA_300_secs" : 0
[2019-11-03T21:33:31.781 PST DEBG ][ org.kde.kstars.ekos.scheduler] - "/home/pi/IC1848-Part2/IC1848-Part2/Light/OIII/IC1848-Part2_Light_OIII_300_secs" : 0
[2019-11-03T21:33:31.782 PST DEBG ][ org.kde.kstars.ekos.scheduler] - "/home/pi/IC1848-Part2/IC1848-Part2/Light/SII/IC1848-Part2_Light_SII_300_secs" : 0
[2019-11-03T21:33:31.847 PST INFO ][ org.kde.kstars.ekos.scheduler] - "Job 'IC1848-Part2' 12x300\" sees 0 captures in output folder '/home/pi/IC1848-Part2/IC1848-Part2/Light/HA'."
[2019-11-03T21:33:31.848 PST INFO ][ org.kde.kstars.ekos.scheduler] - "Job 'IC1848-Part2' 12x300\" has completed 0/12 of its required captures in output folder '/home/pi/IC1848-Part2/IC1848-Part2/Light/HA'."
[2019-11-03T21:33:31.848 PST INFO ][ org.kde.kstars.ekos.scheduler] - "Job 'IC1848-Part2' 12x300\" requires a focus procedure."
[2019-11-03T21:33:31.848 PST INFO ][ org.kde.kstars.ekos.scheduler] - "Job 'IC1848-Part2' 12x300\" sees 0 captures in output folder '/home/pi/IC1848-Part2/IC1848-Part2/Light/OIII'."
[2019-11-03T21:33:31.849 PST INFO ][ org.kde.kstars.ekos.scheduler] - "Job 'IC1848-Part2' 12x300\" has completed 0/12 of its required captures in output folder '/home/pi/IC1848-Part2/IC1848-Part2/Light/OIII'."
[2019-11-03T21:33:31.849 PST INFO ][ org.kde.kstars.ekos.scheduler] - "Job 'IC1848-Part2' 12x300\" requires a focus procedure."
[2019-11-03T21:33:31.850 PST INFO ][ org.kde.kstars.ekos.scheduler] - "Job 'IC1848-Part2' 12x300\" sees 0 captures in output folder '/home/pi/IC1848-Part2/IC1848-Part2/Light/SII'."
[2019-11-03T21:33:31.850 PST INFO ][ org.kde.kstars.ekos.scheduler] - "Job 'IC1848-Part2' 12x300\" has completed 0/12 of its required captures in output folder '/home/pi/IC1848-Part2/IC1848-Part2/Light/SII'."
[2019-11-03T21:33:31.851 PST INFO ][ org.kde.kstars.ekos.scheduler] - "Job 'IC1848-Part2' 12x300\" requires a focus procedure."
[2019-11-03T21:33:31.858 PST INFO ][ org.kde.kstars.ekos.scheduler] - "Job 'IC1848-Part2' estimated to take 03h 24m 48s to complete."
[2019-11-03T21:33:31.865 PST INFO ][ org.kde.kstars.ekos.scheduler] - "Job 'IC1848-Part1' 12x300\" sees 0 captures in output folder '/home/pi/IC1848-Part1/IC1848-Part1/Light/HA'."
[2019-11-03T21:33:31.865 PST INFO ][ org.kde.kstars.ekos.scheduler] - "Job 'IC1848-Part1' 12x300\" has completed 0/12 of its required captures in output folder '/home/pi/IC1848-Part1/IC1848-Part1/Light/HA'."
[2019-11-03T21:33:31.866 PST INFO ][ org.kde.kstars.ekos.scheduler] - "Job 'IC1848-Part1' 12x300\" requires a focus procedure."
[2019-11-03T21:33:31.866 PST INFO ][ org.kde.kstars.ekos.scheduler] - "Job 'IC1848-Part1' 12x300\" sees 0 captures in output folder '/home/pi/IC1848-Part1/IC1848-Part1/Light/OIII'."
[2019-11-03T21:33:31.866 PST INFO ][ org.kde.kstars.ekos.scheduler] - "Job 'IC1848-Part1' 12x300\" has completed 0/12 of its required captures in output folder '/home/pi/IC1848-Part1/IC1848-Part1/Light/OIII'."
[2019-11-03T21:33:31.867 PST INFO ][ org.kde.kstars.ekos.scheduler] - "Job 'IC1848-Part1' 12x300\" requires a focus procedure."
[2019-11-03T21:33:31.867 PST INFO ][ org.kde.kstars.ekos.scheduler] - "Job 'IC1848-Part1' 12x300\" sees 0 captures in output folder '/home/pi/IC1848-Part1/IC1848-Part1/Light/SII'."
[2019-11-03T21:33:31.868 PST INFO ][ org.kde.kstars.ekos.scheduler] - "Job 'IC1848-Part1' 12x300\" has completed 0/12 of its required captures in output folder '/home/pi/IC1848-Part1/IC1848-Part1/Light/SII'."
[2019-11-03T21:33:31.868 PST INFO ][ org.kde.kstars.ekos.scheduler] - "Job 'IC1848-Part1' 12x300\" requires a focus procedure."
[2019-11-03T21:33:31.875 PST INFO ][ org.kde.kstars.ekos.scheduler] - "Job 'IC1848-Part1' estimated to take 03h 24m 48s to complete."
[2019-11-03T21:33:31.878 PST INFO ][ org.kde.kstars.ekos.scheduler] - Option to sort jobs based on priority and altitude is true
[2019-11-03T21:33:31.885 PST DEBG ][ org.kde.kstars.ekos.scheduler] - "Schedule attempt #1 for 12288-second job 'IC1848-Part2' on row #1 starting at 03/11/19 21:33, completing at 04/11/19 00:58."
[2019-11-03T21:33:31.886 PST DEBG ][ org.kde.kstars.ekos.scheduler] - "Job 'IC1848-Part2' altitude score is +62 at 03/11/19 21:33"
[2019-11-03T21:33:31.888 PST DEBG ][ org.kde.kstars.ekos.scheduler] - "Job 'IC1848-Part2' Moon separation score is +0 at 03/11/19 21:33"
[2019-11-03T21:33:31.888 PST INFO ][ org.kde.kstars.ekos.scheduler] - "Job 'IC1848-Part2' has a total score of +62 at 03/11/19 21:33."
[2019-11-03T21:33:31.893 PST DEBG ][ org.kde.kstars.ekos.scheduler] - "Job 'IC1848-Part2' on row #1 passed all checks after 1 attempts, will proceed at 03/11/19 21:33 for approximately 12288 seconds, marking scheduled"
[2019-11-03T21:33:31.897 PST DEBG ][ org.kde.kstars.ekos.scheduler] - "Schedule attempt #1 for 12288-second job 'IC1848-Part1' on row #2 starting at 03/11/19 21:33, completing at 04/11/19 00:58."
[2019-11-03T21:33:31.901 PST DEBG ][ org.kde.kstars.ekos.scheduler] - "Job 'IC1848-Part1' is scheduled to start at 04/11/19 01:03, 300 seconds after 04/11/19 00:58, in compliance with previous job completion requirement."
[2019-11-03T21:33:31.901 PST DEBG ][ org.kde.kstars.ekos.scheduler] - "Schedule attempt #2 for 12288-second job 'IC1848-Part1' on row #2 starting at 04/11/19 01:03, completing at 04/11/19 04:28."
[2019-11-03T21:33:31.905 PST DEBG ][ org.kde.kstars.ekos.scheduler] - "Job 'IC1848-Part1' altitude score is +60 at 03/11/19 21:33"
[2019-11-03T21:33:31.907 PST DEBG ][ org.kde.kstars.ekos.scheduler] - "Job 'IC1848-Part1' Moon separation score is +0 at 03/11/19 21:33"
[2019-11-03T21:33:31.908 PST INFO ][ org.kde.kstars.ekos.scheduler] - "Job 'IC1848-Part1' has a total score of +60 at 03/11/19 21:33."
[2019-11-03T21:33:31.912 PST DEBG ][ org.kde.kstars.ekos.scheduler] - "Job 'IC1848-Part1' on row #2 passed all checks after 2 attempts, will proceed at 04/11/19 01:03 for approximately 12288 seconds, marking scheduled"
[2019-11-03T21:33:31.913 PST INFO ][ org.kde.kstars.ekos.scheduler] - Ekos finished evaluating jobs, no job selection required.
[2019-11-03T21:33:31.914 PST INFO ][ org.kde.kstars.ekos.scheduler] - "Scheduler started."
[2019-11-03T21:33:31.916 PST DEBG ][ org.kde.kstars.ekos.scheduler] - Scheduler started.
[2019-11-03T21:33:32.863 PST DEBG ][ org.kde.kstars.ekos.scheduler] - Checking Park Wait State...
[2019-11-03T21:33:32.871 PST DEBG ][ org.kde.kstars.ekos.scheduler] - Frame map summary:
[2019-11-03T21:33:32.872 PST DEBG ][ org.kde.kstars.ekos.scheduler] - "/home/pi/IC1848-Part1/IC1848-Part1/Light/HA/IC1848-Part1_Light_HA_300_secs" : 0
[2019-11-03T21:33:32.872 PST DEBG ][ org.kde.kstars.ekos.scheduler] - "/home/pi/IC1848-Part1/IC1848-Part1/Light/OIII/IC1848-Part1_Light_OIII_300_secs" : 0
[2019-11-03T21:33:32.873 PST DEBG ][ org.kde.kstars.ekos.scheduler] - "/home/pi/IC1848-Part1/IC1848-Part1/Light/SII/IC1848-Part1_Light_SII_300_secs" : 0
[2019-11-03T21:33:32.873 PST DEBG ][ org.kde.kstars.ekos.scheduler] - "/home/pi/IC1848-Part2/IC1848-Part2/Light/HA/IC1848-Part2_Light_HA_300_secs" : 0
[2019-11-03T21:33:32.873 PST DEBG ][ org.kde.kstars.ekos.scheduler] - "/home/pi/IC1848-Part2/IC1848-Part2/Light/OIII/IC1848-Part2_Light_OIII_300_secs" : 0
[2019-11-03T21:33:32.874 PST DEBG ][ org.kde.kstars.ekos.scheduler] - "/home/pi/IC1848-Part2/IC1848-Part2/Light/SII/IC1848-Part2_Light_SII_300_secs" : 0
[2019-11-03T21:33:32.987 PST INFO ][ org.kde.kstars.ekos.scheduler] - Option to sort jobs based on priority and altitude is true
[2019-11-03T21:33:32.990 PST DEBG ][ org.kde.kstars.ekos.scheduler] - "Schedule attempt #1 for 12288-second job 'IC1848-Part2' on row #1 starting at 03/11/19 21:33, completing at 04/11/19 00:58."
[2019-11-03T21:33:32.991 PST DEBG ][ org.kde.kstars.ekos.scheduler] - "Job 'IC1848-Part2' altitude score is +62 at 03/11/19 21:33"
[2019-11-03T21:33:32.993 PST DEBG ][ org.kde.kstars.ekos.scheduler] - "Job 'IC1848-Part2' Moon separation score is +0 at 03/11/19 21:33"
[2019-11-03T21:33:32.993 PST INFO ][ org.kde.kstars.ekos.scheduler] - "Job 'IC1848-Part2' has a total score of +62 at 03/11/19 21:33."
[2019-11-03T21:33:32.999 PST DEBG ][ org.kde.kstars.ekos.scheduler] - "Job 'IC1848-Part2' on row #1 passed all checks after 1 attempts, will proceed at 03/11/19 21:33 for approximately 12288 seconds, marking scheduled"
[2019-11-03T21:33:33.000 PST DEBG ][ org.kde.kstars.ekos.scheduler] - "Schedule attempt #1 for 12288-second job 'IC1848-Part1' on row #2 starting at 04/11/19 01:03, completing at 04/11/19 04:28."
[2019-11-03T21:33:33.002 PST DEBG ][ org.kde.kstars.ekos.scheduler] - "Job 'IC1848-Part1' altitude score is +60 at 03/11/19 21:33"
[2019-11-03T21:33:33.005 PST DEBG ][ org.kde.kstars.ekos.scheduler] - "Job 'IC1848-Part1' Moon separation score is +0 at 03/11/19 21:33"
[2019-11-03T21:33:33.005 PST INFO ][ org.kde.kstars.ekos.scheduler] - "Job 'IC1848-Part1' has a total score of +60 at 03/11/19 21:33."
[2019-11-03T21:33:33.010 PST DEBG ][ org.kde.kstars.ekos.scheduler] - "Job 'IC1848-Part1' on row #2 passed all checks after 1 attempts, will proceed at 04/11/19 01:03 for approximately 12288 seconds, marking scheduled"
[2019-11-03T21:33:33.011 PST DEBG ][ org.kde.kstars.ekos.scheduler] - "Job 'IC1848-Part2' altitude score is +62 at 03/11/19 21:33"
[2019-11-03T21:33:33.013 PST DEBG ][ org.kde.kstars.ekos.scheduler] - "Job 'IC1848-Part2' Moon separation score is +0 at 03/11/19 21:33"
[2019-11-03T21:33:33.014 PST INFO ][ org.kde.kstars.ekos.scheduler] - "Job 'IC1848-Part2' has a total score of +62 at 03/11/19 21:33."
[2019-11-03T21:33:33.017 PST DEBG ][ org.kde.kstars.ekos.scheduler] - "Job 'IC1848-Part2' is selected for next observation with priority #10 and score 62."
[2019-11-03T21:33:33.814 PST DEBG ][ org.kde.kstars.ekos.scheduler] - "Checking Startup State (0)..."
[2019-11-03T21:33:33.815 PST DEBG ][ org.kde.kstars.ekos.scheduler] - Startup Idle. Starting startup process...
[2019-11-03T21:33:59.739 PST INFO ][ org.kde.kstars.ekos.scheduler] -
The google map can't be displayed, ask your administrator for more details
...

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

Time to create page: 0.320 seconds