×

INDI Library v2.0.6 is Released (02 Feb 2024)

Bi-monthly release with minor bug fixes and improvements

KStars memory leak and crash

  • Posts: 123
  • Thank you received: 13

Replied by Outta on topic KStars memory leak and crash

Thank you Jasem, I have turned sounds off, but unfortunately this has not solved the problem.

I do have this issue now 100% reproducible, but do not know how to handle debugging with this.

I think there is something to do with Canon EOS driver and Scheduler, that causers memory leak, and subsequently crashing of the whole Kstars.

I have cleaned everything I detected it is an issue and I have no use for, so here are some raw findings I found:
When scheduler is running this is normal behavior:
[2022-07-06T09:50:47.598 CEST DEBG ][ org.kde.kstars.ekos.scheduler] - Capture State "Image Received"
[2022-07-06T09:50:47.602 CEST DEBG ][ org.kde.kstars.ekos.scheduler] - Capture State "Capturing"

But in crash, after image is received we only have
[2022-07-06T09:50:47.598 CEST DEBG ][ org.kde.kstars.ekos.scheduler] - Capture State "Image Received"
So no capture.

Interesting thing comes when Scheduler is not running. In that case we get no crashes, and there is no logs from [ org.kde.kstars.ekos.scheduler] at all. So component we need to debug is org.kde.kstars.ekos.scheduler, in time between "Image Received" and "Capturing".
2022-07-06T09:50:47.108 CEST DEBG ][           org.kde.kstars.indi] - Canon DSLR EOS 550D : "[DEBUG] Upload complete "
[2022-07-06T09:50:47.111 CEST DEBG ][           org.kde.kstars.indi] - Image received. Mode: "Normal" Size: 3208320
[2022-07-06T09:50:47.117 CEST INFO ][           org.kde.kstars.indi] - "FITS" file saved to "/home/astroberry/Public/Light/ngc6818/Light/lg_Light_10_secs_2022-07-06T09-50-47_622.fits"
[2022-07-06T09:50:47.408 CEST INFO ][   org.kde.kstars.ekos.capture] - "Download Time: 0.30 s, New Download Time Estimate: 0.43 s."
[2022-07-06T09:50:47.411 CEST INFO ][   org.kde.kstars.ekos.capture] - "Received image 3 out of 300."
[2022-07-06T09:50:47.413 CEST INFO ][   org.kde.kstars.ekos.capture] - "Captured /home/astroberry/Public/Light/ngc6818/Light/lg_Light_10_secs_2022-07-06T09-50-47_622.fits"
[2022-07-06T09:50:47.594 CEST DEBG ][           org.kde.kstars.indi] - Requested exposure 10 closes match is 10.3
[2022-07-06T09:50:47.594 CEST INFO ][   org.kde.kstars.ekos.capture] - "Capturing 10.000-second  image..."
[2022-07-06T09:50:47.598 CEST DEBG ][ org.kde.kstars.ekos.scheduler] - Capture State "Image Received"
[2022-07-06T09:50:47.602 CEST DEBG ][ org.kde.kstars.ekos.scheduler] - Capture State "Capturing"
[2022-07-06T09:50:47.644 CEST INFO ][           org.kde.kstars.indi] - Canon DSLR EOS 550D :  "[INFO] Starting 10.3 seconds exposure. "
[2022-07-06T09:50:47.644 CEST DEBG ][           org.kde.kstars.indi] - Canon DSLR EOS 550D : "[DEBUG] Starting exposure (exptime: 10.3 secs, mirror lock: 0, force bulb: true, exposure index: 0) "
[2022-07-06T09:50:47.645 CEST DEBG ][           org.kde.kstars.indi] - Canon DSLR EOS 550D : "[DEBUG] Mutex locked "
[2022-07-06T09:50:47.645 CEST DEBG ][           org.kde.kstars.indi] - Canon DSLR EOS 550D : "[DEBUG] Setting radio/menu widget iso: 4 (800) "
[2022-07-06T09:50:47.653 CEST DEBG ][           org.kde.kstars.indi] - Canon DSLR EOS 550D : "[DEBUG] Setting new configuration OK. "
[2022-07-06T09:50:47.653 CEST DEBG ][           org.kde.kstars.indi] - Canon DSLR EOS 550D : "[DEBUG] Setting radio/menu widget imageformat: 7 (RAW) "
[2022-07-06T09:50:47.654 CEST DEBG ][           org.kde.kstars.indi] - Canon DSLR EOS 550D : "[DEBUG] Setting new configuration OK. "
[2022-07-06T09:50:47.654 CEST DEBG ][           org.kde.kstars.indi] - Canon DSLR EOS 550D : "[DEBUG] Using internal bulb widget:eosremoterelease "
[2022-07-06T09:50:47.654 CEST DEBG ][           org.kde.kstars.indi] - Canon DSLR EOS 550D : "[DEBUG] Setting radio/menu widget eosremoterelease: 2 (Press Full) "
[2022-07-06T09:50:47.770 CEST DEBG ][           org.kde.kstars.indi] - Canon DSLR EOS 550D : "[DEBUG] Setting new configuration OK. "
[2022-07-06T09:50:47.770 CEST DEBG ][           org.kde.kstars.indi] - Canon DSLR EOS 550D : "[DEBUG] Exposure started. "
[2022-07-06T09:50:47.771 CEST DEBG ][           org.kde.kstars.indi] - Canon DSLR EOS 550D : "[DEBUG] Time left: 10299 ms "
[2022-07-06T09:50:52.770 CEST DEBG ][           org.kde.kstars.indi] - Canon DSLR EOS 550D : "[DEBUG] Time left: 5299 ms "
[2022-07-06T09:50:57.771 CEST DEBG ][           org.kde.kstars.indi] - Canon DSLR EOS 550D : "[DEBUG] Time left: 299 ms "
[2022-07-06T09:50:58.041 CEST DEBG ][           org.kde.kstars.indi] - Canon DSLR EOS 550D : "[DEBUG] Reading exposure... "
[2022-07-06T09:50:58.070 CEST DEBG ][           org.kde.kstars.indi] - Canon DSLR EOS 550D : "[DEBUG] Time left: -1 ms "
[2022-07-06T09:50:58.071 CEST DEBG ][           org.kde.kstars.indi] - Canon DSLR EOS 550D : "[DEBUG] Closing internal shutter. "
[2022-07-06T09:50:58.071 CEST DEBG ][           org.kde.kstars.indi] - Canon DSLR EOS 550D : "[DEBUG] Using widget:eosremoterelease "
[2022-07-06T09:50:58.072 CEST DEBG ][           org.kde.kstars.indi] - Canon DSLR EOS 550D : "[DEBUG] Setting radio/menu widget eosremoterelease: 4 (Release Full) "
[2022-07-06T09:50:58.087 CEST DEBG ][           org.kde.kstars.indi] - Canon DSLR EOS 550D : "[DEBUG] Setting new configuration OK. "
[2022-07-06T09:50:58.087 CEST DEBG ][           org.kde.kstars.indi] - Canon DSLR EOS 550D : "[DEBUG] Exposure complete. "
[2022-07-06T09:51:00.136 CEST DEBG ][           org.kde.kstars.indi] - Canon DSLR EOS 550D : "[DEBUG] File added event completed. "
[2022-07-06T09:51:00.137 CEST DEBG ][           org.kde.kstars.indi] - Canon DSLR EOS 550D : "[DEBUG] Downloading image... Name: (capt0003.cr2) Folder: (/) Delete from SD card? (false) "
[2022-07-06T09:51:00.187 CEST DEBG ][           org.kde.kstars.indi] - Canon DSLR EOS 550D : "[DEBUG]  Downloaded 0x0 (preview 0x0) "
[2022-07-06T09:51:00.232 CEST DEBG ][           org.kde.kstars.indi] - Canon DSLR EOS 550D : "[DEBUG] Capture target is INTERNAL RAM. "
[2022-07-06T09:51:00.243 CEST DEBG ][           org.kde.kstars.indi] - Canon DSLR EOS 550D : "[DEBUG] Capture event completed. "
[2022-07-06T09:51:00.245 CEST INFO ][           org.kde.kstars.indi] - Canon DSLR EOS 550D :  "[INFO] Exposure done, downloading image... "
[2022-07-06T09:51:02.907 CEST DEBG ][           org.kde.kstars.indi] - Canon DSLR EOS 550D : "[DEBUG] read_libraw: raw_width: 5344 top_margin 51 left_margin 142 first_visible_pixel 272686 "
[2022-07-06T09:51:02.908 CEST DEBG ][           org.kde.kstars.indi] - Canon DSLR EOS 550D : "[DEBUG] read_libraw: rawdata.sizes.width: 5202 rawdata.sizes.height 3465 memsize 36049860 bayer_pattern RGGB "
[2022-07-06T09:51:02.960 CEST DEBG ][           org.kde.kstars.indi] - Canon DSLR EOS 550D : "[DEBUG] read_libraw: memsize (36049860) naxis (2) w (5202) h (3465) bpp (16) bayer pattern (RGGB) "
[2022-07-06T09:51:02.978 CEST DEBG ][           org.kde.kstars.indi] - Canon DSLR EOS 550D : "[DEBUG] Subframing... subFrameSize: 3200000 - oneFrameSize: 3200000 - subX: 1600 - subY: 1200 - subW: 1600 - subH: 1000 "
[2022-07-06T09:51:03.004 CEST DEBG ][           org.kde.kstars.indi] - Canon DSLR EOS 550D : "[DEBUG] Uploading file. Ext: fits, Size: 3208320, sendImage? Yes, saveImage? No "
[2022-07-06T09:51:03.081 CEST DEBG ][           org.kde.kstars.indi] - Canon DSLR EOS 550D : "[DEBUG] BLOB transfer took 0.07444 seconds "
[2022-07-06T09:51:03.082 CEST DEBG ][           org.kde.kstars.indi] - Canon DSLR EOS 550D : "[DEBUG] Upload complete "
[2022-07-06T09:51:03.952 CEST DEBG ][           org.kde.kstars.indi] - Image received. Mode: "Normal" Size: 3208320
[2022-07-06T09:51:03.962 CEST INFO ][           org.kde.kstars.indi] - "FITS" file saved to "/home/astroberry/Public/Light/ngc6818/Light/lg_Light_10_secs_2022-07-06T09-51-03_623.fits"
[2022-07-06T09:51:04.130 CEST INFO ][   org.kde.kstars.ekos.capture] - "Download Time: 0.18 s, New Download Time Estimate: 0.37 s."
[2022-07-06T09:51:04.133 CEST INFO ][   org.kde.kstars.ekos.capture] - "Received image 4 out of 300."
[2022-07-06T09:51:04.136 CEST INFO ][   org.kde.kstars.ekos.capture] - "Captured /home/astroberry/Public/Light/ngc6818/Light/lg_Light_10_secs_2022-07-06T09-51-03_623.fits"
[2022-07-06T09:51:04.310 CEST DEBG ][           org.kde.kstars.indi] - Requested exposure 10 closes match is 10.3
[2022-07-06T09:51:04.311 CEST INFO ][   org.kde.kstars.ekos.capture] - "Capturing 10.000-second  image..."
[2022-07-06T09:51:04.321 CEST DEBG ][ org.kde.kstars.ekos.scheduler] - Capture State "Image Received"
[2022-07-06T09:51:04.328 CEST DEBG ][ org.kde.kstars.ekos.scheduler] - Capture State "Capturing"
[2022-07-06T09:51:04.386 CEST INFO ][           org.kde.kstars.indi] - Canon DSLR EOS 550D :  "[INFO] Starting 10.3 seconds exposure. "
[2022-07-06T09:51:04.386 CEST DEBG ][           org.kde.kstars.indi] - Canon DSLR EOS 550D : "[DEBUG] Starting exposure (exptime: 10.3 secs, mirror lock: 0, force bulb: true, exposure index: 0) "
[2022-07-06T09:51:04.387 CEST DEBG ][           org.kde.kstars.indi] - Canon DSLR EOS 550D : "[DEBUG] Mutex locked "
[2022-07-06T09:51:04.387 CEST DEBG ][           org.kde.kstars.indi] - Canon DSLR EOS 550D : "[DEBUG] Setting radio/menu widget iso: 4 (800) "
[2022-07-06T09:51:04.387 CEST DEBG ][           org.kde.kstars.indi] - Canon DSLR EOS 550D : "[DEBUG] Setting new configuration OK. "
[2022-07-06T09:51:04.388 CEST DEBG ][           org.kde.kstars.indi] - Canon DSLR EOS 550D : "[DEBUG] Setting radio/menu widget imageformat: 7 (RAW) "
[2022-07-06T09:51:04.388 CEST DEBG ][           org.kde.kstars.indi] - Canon DSLR EOS 550D : "[DEBUG] Setting new configuration OK. "
[2022-07-06T09:51:04.388 CEST DEBG ][           org.kde.kstars.indi] - Canon DSLR EOS 550D : "[DEBUG] Using internal bulb widget:eosremoterelease "
[2022-07-06T09:51:04.389 CEST DEBG ][           org.kde.kstars.indi] - Canon DSLR EOS 550D : "[DEBUG] Setting radio/menu widget eosremoterelease: 2 (Press Full) "
[2022-07-06T09:51:04.450 CEST DEBG ][           org.kde.kstars.indi] - Canon DSLR EOS 550D : "[DEBUG] Setting new configuration OK. "
[2022-07-06T09:51:04.451 CEST DEBG ][           org.kde.kstars.indi] - Canon DSLR EOS 550D : "[DEBUG] Exposure started. "
[2022-07-06T09:51:04.451 CEST DEBG ][           org.kde.kstars.indi] - Canon DSLR EOS 550D : "[DEBUG] Time left: 10299 ms "
[2022-07-06T09:51:09.448 CEST DEBG ][           org.kde.kstars.indi] - Canon DSLR EOS 550D : "[DEBUG] Time left: 5299 ms "
[2022-07-06T09:51:15.737 CEST DEBG ][           org.kde.kstars.indi] - Canon DSLR EOS 550D : "[DEBUG] Time left: 299 ms "
[2022-07-06T09:51:15.739 CEST DEBG ][           org.kde.kstars.indi] - Canon DSLR EOS 550D : "[DEBUG] Reading exposure... "
[2022-07-06T09:51:15.740 CEST DEBG ][           org.kde.kstars.indi] - Canon DSLR EOS 550D : "[DEBUG] Time left: -1 ms "
[2022-07-06T09:51:15.741 CEST DEBG ][           org.kde.kstars.indi] - Canon DSLR EOS 550D : "[DEBUG] Closing internal shutter. "
[2022-07-06T09:51:15.742 CEST DEBG ][           org.kde.kstars.indi] - Canon DSLR EOS 550D : "[DEBUG] Using widget:eosremoterelease "
[2022-07-06T09:51:15.743 CEST DEBG ][           org.kde.kstars.indi] - Canon DSLR EOS 550D : "[DEBUG] Setting radio/menu widget eosremoterelease: 4 (Release Full) "
[2022-07-06T09:51:15.744 CEST DEBG ][           org.kde.kstars.indi] - Canon DSLR EOS 550D : "[DEBUG] Setting new configuration OK. "
[2022-07-06T09:51:15.745 CEST DEBG ][           org.kde.kstars.indi] - Canon DSLR EOS 550D : "[DEBUG] Exposure complete. "
[2022-07-06T09:51:17.036 CEST DEBG ][           org.kde.kstars.indi] - Canon DSLR EOS 550D : "[DEBUG] File added event completed. "
[2022-07-06T09:51:17.036 CEST DEBG ][           org.kde.kstars.indi] - Canon DSLR EOS 550D : "[DEBUG] Downloading image... Name: (capt0004.cr2) Folder: (/) Delete from SD card? (false) "
[2022-07-06T09:51:17.048 CEST DEBG ][           org.kde.kstars.indi] - Canon DSLR EOS 550D : "[DEBUG]  Downloaded 0x0 (preview 0x0) "
[2022-07-06T09:51:17.049 CEST DEBG ][           org.kde.kstars.indi] - Canon DSLR EOS 550D : "[DEBUG] Capture target is INTERNAL RAM. "
[2022-07-06T09:51:17.050 CEST DEBG ][           org.kde.kstars.indi] - Canon DSLR EOS 550D : "[DEBUG] Capture event completed. "
[2022-07-06T09:51:17.051 CEST INFO ][           org.kde.kstars.indi] - Canon DSLR EOS 550D :  "[INFO] Exposure done, downloading image... "
[2022-07-06T09:51:19.647 CEST DEBG ][           org.kde.kstars.indi] - Canon DSLR EOS 550D : "[DEBUG] read_libraw: raw_width: 5344 top_margin 51 left_margin 142 first_visible_pixel 272686 "
[2022-07-06T09:51:19.648 CEST DEBG ][           org.kde.kstars.indi] - Canon DSLR EOS 550D : "[DEBUG] read_libraw: rawdata.sizes.width: 5202 rawdata.sizes.height 3465 memsize 36049860 bayer_pattern RGGB "
[2022-07-06T09:51:19.694 CEST DEBG ][           org.kde.kstars.indi] - Canon DSLR EOS 550D : "[DEBUG] read_libraw: memsize (36049860) naxis (2) w (5202) h (3465) bpp (16) bayer pattern (RGGB) "
[2022-07-06T09:51:19.713 CEST DEBG ][           org.kde.kstars.indi] - Canon DSLR EOS 550D : "[DEBUG] Subframing... subFrameSize: 3200000 - oneFrameSize: 3200000 - subX: 1600 - subY: 1200 - subW: 1600 - subH: 1000 "
[2022-07-06T09:51:25.682 CEST DEBG ][           org.kde.kstars.indi] - Canon DSLR EOS 550D : "[DEBUG] Uploading file. Ext: fits, Size: 3208320, sendImage? Yes, saveImage? No "
[2022-07-06T09:51:27.700 CEST DEBG ][           org.kde.kstars.indi] - Canon DSLR EOS 550D : "[DEBUG] BLOB transfer took 0.0317382 seconds "
[2022-07-06T09:51:27.701 CEST DEBG ][           org.kde.kstars.indi] - Canon DSLR EOS 550D : "[DEBUG] Upload complete "
[2022-07-06T09:51:28.102 CEST DEBG ][           org.kde.kstars.indi] - Image received. Mode: "Normal" Size: 3208320
[2022-07-06T09:51:28.113 CEST INFO ][           org.kde.kstars.indi] - "FITS" file saved to "/home/astroberry/Public/Light/ngc6818/Light/lg_Light_10_secs_2022-07-06T09-51-28_624.fits"
[2022-07-06T09:51:28.275 CEST INFO ][   org.kde.kstars.ekos.capture] - "Download Time: 0.57 s, New Download Time Estimate: 0.41 s."
[2022-07-06T09:51:28.279 CEST INFO ][   org.kde.kstars.ekos.capture] - "Received image 5 out of 300."
[2022-07-06T09:51:28.281 CEST INFO ][   org.kde.kstars.ekos.capture] - "Captured /home/astroberry/Public/Light/ngc6818/Light/lg_Light_10_secs_2022-07-06T09-51-28_624.fits"
[2022-07-06T09:51:29.422 CEST DEBG ][ org.kde.kstars.ekos.scheduler] - Capture State "Image Received"

No scheduler:
[2022-07-06T10:32:51.782 CEST INFO ][   org.kde.kstars.ekos.capture] - "Download Time: 1.42 s, New Download Time Estimate: 1.42 s."
[2022-07-06T10:32:51.793 CEST INFO ][   org.kde.kstars.ekos.capture] - "Received image 1 out of 300."
[2022-07-06T10:32:51.798 CEST INFO ][   org.kde.kstars.ekos.capture] - "Captured /home/astroberry/Public/Light/ngc6818/Light/lg_Light_10_secs_2022-07-06T10-32-50_730.fits"
[2022-07-06T10:32:52.514 CEST DEBG ][           org.kde.kstars.indi] - Requested exposure 10 closes match is 10.3
[2022-07-06T10:32:52.516 CEST INFO ][   org.kde.kstars.ekos.capture] - "Capturing 10.000-second  image..."
[2022-07-06T10:32:52.727 CEST INFO ][           org.kde.kstars.indi] - Canon DSLR EOS 550D :  "[INFO] Starting 10.3 seconds exposure. "
[2022-07-06T10:32:52.728 CEST DEBG ][           org.kde.kstars.indi] - Canon DSLR EOS 550D : "[DEBUG] Starting exposure (exptime: 10.3 secs, mirror lock: 0, force bulb: true, exposure index: 0) "
[2022-07-06T10:32:52.730 CEST DEBG ][           org.kde.kstars.indi] - Canon DSLR EOS 550D : "[DEBUG] Mutex locked "
[2022-07-06T10:32:52.731 CEST DEBG ][           org.kde.kstars.indi] - Canon DSLR EOS 550D : "[DEBUG] Setting radio/menu widget iso: 4 (800) "
[2022-07-06T10:32:52.731 CEST DEBG ][           org.kde.kstars.indi] - Canon DSLR EOS 550D : "[DEBUG] Setting new configuration OK. "
[2022-07-06T10:32:52.731 CEST DEBG ][           org.kde.kstars.indi] - Canon DSLR EOS 550D : "[DEBUG] Setting radio/menu widget imageformat: 7 (RAW) "
[2022-07-06T10:32:52.731 CEST DEBG ][           org.kde.kstars.indi] - Canon DSLR EOS 550D : "[DEBUG] Setting new configuration OK. "
[2022-07-06T10:32:52.731 CEST DEBG ][           org.kde.kstars.indi] - Canon DSLR EOS 550D : "[DEBUG] Using internal bulb widget:eosremoterelease "
[2022-07-06T10:32:52.732 CEST DEBG ][           org.kde.kstars.indi] - Canon DSLR EOS 550D : "[DEBUG] Setting radio/menu widget eosremoterelease: 2 (Press Full) "
[2022-07-06T10:32:52.734 CEST DEBG ][           org.kde.kstars.indi] - Canon DSLR EOS 550D : "[DEBUG] Setting new configuration OK. "
[2022-07-06T10:32:52.734 CEST DEBG ][           org.kde.kstars.indi] - Canon DSLR EOS 550D : "[DEBUG] Exposure started. "
[2022-07-06T10:32:52.735 CEST DEBG ][           org.kde.kstars.indi] - Canon DSLR EOS 550D : "[DEBUG] Time left: 10299 ms "
[2022-07-06T10:32:57.693 CEST DEBG ][           org.kde.kstars.indi] - Canon DSLR EOS 550D : "[DEBUG] Time left: 5299 ms "
[2022-07-06T10:33:02.693 CEST DEBG ][           org.kde.kstars.indi] - Canon DSLR EOS 550D : "[DEBUG] Time left: 299 ms "
[2022-07-06T10:33:02.963 CEST DEBG ][           org.kde.kstars.indi] - Canon DSLR EOS 550D : "[DEBUG] Reading exposure... "
[2022-07-06T10:33:02.992 CEST DEBG ][           org.kde.kstars.indi] - Canon DSLR EOS 550D : "[DEBUG] Time left: -1 ms "
[2022-07-06T10:33:02.993 CEST DEBG ][           org.kde.kstars.indi] - Canon DSLR EOS 550D : "[DEBUG] Closing internal shutter. "
[2022-07-06T10:33:02.994 CEST DEBG ][           org.kde.kstars.indi] - Canon DSLR EOS 550D : "[DEBUG] Using widget:eosremoterelease "
[2022-07-06T10:33:02.994 CEST DEBG ][           org.kde.kstars.indi] - Canon DSLR EOS 550D : "[DEBUG] Setting radio/menu widget eosremoterelease: 4 (Release Full) "
[2022-07-06T10:33:03.009 CEST DEBG ][           org.kde.kstars.indi] - Canon DSLR EOS 550D : "[DEBUG] Setting new configuration OK. "
[2022-07-06T10:33:03.010 CEST DEBG ][           org.kde.kstars.indi] - Canon DSLR EOS 550D : "[DEBUG] Exposure complete. "
[2022-07-06T10:33:05.089 CEST DEBG ][           org.kde.kstars.indi] - Canon DSLR EOS 550D : "[DEBUG] File added event completed. "
[2022-07-06T10:33:05.091 CEST DEBG ][           org.kde.kstars.indi] - Canon DSLR EOS 550D : "[DEBUG] Downloading image... Name: (capt0001.cr2) Folder: (/) Delete from SD card? (false) "
[2022-07-06T10:33:05.148 CEST DEBG ][           org.kde.kstars.indi] - Canon DSLR EOS 550D : "[DEBUG]  Downloaded 0x0 (preview 0x0) "
[2022-07-06T10:33:05.181 CEST DEBG ][           org.kde.kstars.indi] - Canon DSLR EOS 550D : "[DEBUG] Capture target is INTERNAL RAM. "
[2022-07-06T10:33:05.190 CEST DEBG ][           org.kde.kstars.indi] - Canon DSLR EOS 550D : "[DEBUG] Capture event completed. "
[2022-07-06T10:33:05.191 CEST INFO ][           org.kde.kstars.indi] - Canon DSLR EOS 550D :  "[INFO] Exposure done, downloading image... "
[2022-07-06T10:33:09.822 CEST DEBG ][           org.kde.kstars.indi] - Canon DSLR EOS 550D : "[DEBUG] read_libraw: raw_width: 5344 top_margin 51 left_margin 142 first_visible_pixel 272686 "
[2022-07-06T10:33:11.054 CEST DEBG ][           org.kde.kstars.indi] - Canon DSLR EOS 550D : "[DEBUG] read_libraw: rawdata.sizes.width: 5202 rawdata.sizes.height 3465 memsize 36049860 bayer_pattern RGGB "
[2022-07-06T10:33:11.058 CEST DEBG ][           org.kde.kstars.indi] - Canon DSLR EOS 550D : "[DEBUG] read_libraw: memsize (36049860) naxis (2) w (5202) h (3465) bpp (16) bayer pattern (RGGB) "
[2022-07-06T10:33:13.496 CEST DEBG ][           org.kde.kstars.indi] - Canon DSLR EOS 550D : "[DEBUG] Subframing... subFrameSize: 3200000 - oneFrameSize: 3200000 - subX: 1600 - subY: 1200 - subW: 1600 - subH: 1000 "
[2022-07-06T10:33:13.591 CEST DEBG ][           org.kde.kstars.indi] - Canon DSLR EOS 550D : "[DEBUG] Uploading file. Ext: fits, Size: 3208320, sendImage? Yes, saveImage? No "
[2022-07-06T10:33:13.629 CEST DEBG ][           org.kde.kstars.indi] - Canon DSLR EOS 550D : "[DEBUG] BLOB transfer took 0.0748467 seconds "
[2022-07-06T10:33:13.630 CEST DEBG ][           org.kde.kstars.indi] - Canon DSLR EOS 550D : "[DEBUG] Upload complete "
[2022-07-06T10:33:13.710 CEST DEBG ][           org.kde.kstars.indi] - Image received. Mode: "Normal" Size: 3208320
[2022-07-06T10:33:13.719 CEST INFO ][           org.kde.kstars.indi] - "FITS" file saved to "/home/astroberry/Public/Light/ngc6818/Light/lg_Light_10_secs_2022-07-06T10-33-13_731.fits"
[2022-07-06T10:33:13.864 CEST INFO ][   org.kde.kstars.ekos.capture] - "Download Time: 0.23 s, New Download Time Estimate: 0.83 s."
[2022-07-06T10:33:15.116 CEST INFO ][   org.kde.kstars.ekos.capture] - "Received image 2 out of 300."
[2022-07-06T10:33:15.124 CEST INFO ][   org.kde.kstars.ekos.capture] - "Captured /home/astroberry/Public/Light/ngc6818/Light/lg_Light_10_secs_2022-07-06T10-33-13_731.fits"
[2022-07-06T10:33:15.331 CEST DEBG ][           org.kde.kstars.indi] - Requested exposure 10 closes match is 10.3
[2022-07-06T10:33:15.332 CEST INFO ][   org.kde.kstars.ekos.capture] - "Capturing 10.000-second  image..."
1 year 8 months ago #84104
The topic has been locked.
  • Posts: 19
  • Thank you received: 1
Another follow up from me - another crash noted:
Last lines of log file:
[2022-07-05T16:51:07.950 BST DEBG ][           org.kde.kstars.indi] - CCD Simulator : "[DEBUG] Upload complete "
[2022-07-05T16:51:07.951 BST DEBG ][           org.kde.kstars.indi] - Image received. Mode: "Normal" Size: 2629440
[2022-07-05T16:51:07.955 BST INFO ][           org.kde.kstars.indi] - "FITS" file saved to "/home/astroberry/Astro/Light/Luminance/Light_Luminance_1_secs_2022-07-05T16-51-07_19659.fits"
[2022-07-05T16:51:07.958 BST INFO ][   org.kde.kstars.ekos.capture] - "Download Time: 0.01 s, New Download Time Estimate: 0.05 s."
[2022-07-05T16:51:09.641 BST INFO ][   org.kde.kstars.ekos.capture] - "Received image 19642 out of 50000."
[2022-07-05T16:51:12.584 BST DEBG ][           org.kde.kstars.indi] - Telescope Simulator : "[SCOPE] mountToApparentRaDec 87.305623, 90.000000 to ha 87.305623, ra 74.096583, 90.000000 "
[2022-07-05T16:51:12.584 BST DEBG ][           org.kde.kstars.indi] - Telescope Simulator : "[SCOPE] HaAxis: 87.305623, ra 4.939772 "
[2022-07-05T16:51:12.585 BST DEBG ][           org.kde.kstars.indi] - Telescope Simulator : "[SCOPE] DecAxis: 90.000000, dec 90.000000 "
[2022-07-05T16:51:12.585 BST DEBG ][           org.kde.kstars.indi] - Telescope Simulator : "[SCOPE] Current RA:  4:56:23 Current DEC: 90:00:00 "
[2022-07-05T16:51:12.586 BST DEBG ][           org.kde.kstars.indi] - Telescope Simulator : "[SCOPE] mountToApparentRaDec 87.306670, 90.000000 to ha 87.306670, ra 74.096583, 90.000000 "
[2022-07-05T16:51:12.586 BST DEBG ][           org.kde.kstars.indi] - Telescope Simulator : "[SCOPE] HaAxis: 87.306670, ra 4.939772 "
[2022-07-05T16:51:12.587 BST DEBG ][           org.kde.kstars.indi] - Telescope Simulator : "[SCOPE] DecAxis: 90.000000, dec 90.000000 "
[2022-07-05T16:51:12.587 BST DEBG ][           org.kde.kstars.indi] - Telescope Simulator : "[SCOPE] Current RA:  4:56:23 Current DEC: 90:00:00 "
[2022-07-05T16:51:12.588 BST DEBG ][           org.kde.kstars.indi] - INDI Server:  "2022-07-05T15:51:05: Driver indi_simulator_ccd: ****Coordinates (-c) error in "
[2022-07-05T16:51:12.588 BST DEBG ][           org.kde.kstars.indi] - INDI Server:  ""
[2022-07-05T16:51:13.440 BST INFO ][   org.kde.kstars.ekos.capture] - "Capturing 1.000-second Luminance image..."
And memory statistics:

The vertical jumps in the middle of the X axis is in agreement with the last log inputs.
Data from seconds around this:
2022-07-05 16:51:07 Temp=41.8ºC. RAM parameters: Used: 18.6%; Free:  2.5%; Shared:  1.0%; Buffer:  0.7%; Cache: 78.2%; Available: 76.5%. Swap used:100.0%. µSD has 32.601% free space.
2022-07-05 16:51:08 Temp=41.3ºC. RAM parameters: Used: 18.4%; Free:  2.6%; Shared:  1.0%; Buffer:  0.7%; Cache: 78.3%; Available: 76.6%. Swap used:100.0%. µSD has 32.599% free space.
2022-07-05 16:51:10 Temp=41.3ºC. RAM parameters: Used: 18.4%; Free:  2.6%; Shared:  1.0%; Buffer:  0.7%; Cache: 78.3%; Available: 76.6%. Swap used:100.0%. µSD has 32.599% free space.
2022-07-05 16:51:11 Temp=41.8ºC. RAM parameters: Used: 18.4%; Free:  2.6%; Shared:  1.0%; Buffer:  0.7%; Cache: 78.3%; Available: 76.6%. Swap used:100.0%. µSD has 32.599% free space.
2022-07-05 16:51:13 Temp=42.8ºC. RAM parameters: Used: 18.4%; Free:  2.6%; Shared:  1.0%; Buffer:  0.7%; Cache: 78.3%; Available: 76.7%. Swap used:100.0%. µSD has 32.599% free space.
2022-07-05 16:51:14 Temp=41.8ºC. RAM parameters: Used: 18.5%; Free:  2.4%; Shared:  1.0%; Buffer:  0.7%; Cache: 78.3%; Available: 76.4%. Swap used:100.0%. µSD has 32.599% free space.
2022-07-05 16:51:15 Temp=41.3ºC. RAM parameters: Used:  6.6%; Free: 14.9%; Shared:  0.4%; Buffer:  0.7%; Cache: 77.8%; Available: 89.1%. Swap used: 99.9%. µSD has 32.600% free space.
2022-07-05 16:51:17 Temp=41.3ºC. RAM parameters: Used:  6.6%; Free: 14.9%; Shared:  0.4%; Buffer:  0.7%; Cache: 77.8%; Available: 89.1%. Swap used: 99.9%. µSD has 32.600% free space.
2022-07-05 16:51:18 Temp=41.8ºC. RAM parameters: Used:  6.6%; Free: 14.9%; Shared:  0.4%; Buffer:  0.7%; Cache: 77.8%; Available: 89.1%. Swap used: 99.9%. µSD has 32.600% free space.
2022-07-05 16:51:20 Temp=40.9ºC. RAM parameters: Used:  6.5%; Free: 14.9%; Shared:  0.4%; Buffer:  0.7%; Cache: 77.8%; Available: 89.1%. Swap used: 99.9%. µSD has 32.600% free space.
2022-07-05 16:51:21 Temp=41.3ºC. RAM parameters: Used:  6.6%; Free: 14.9%; Shared:  0.4%; Buffer:  0.7%; Cache: 77.8%; Available: 89.1%. Swap used: 99.9%. µSD has 32.600% free space.
2022-07-05 16:51:23 Temp=41.8ºC. RAM parameters: Used:  6.5%; Free: 14.9%; Shared:  0.4%; Buffer:  0.7%; Cache: 77.8%; Available: 89.1%. Swap used: 99.9%. µSD has 32.600% free space.
2022-07-05 16:51:24 Temp=42.3ºC. RAM parameters: Used:  6.6%; Free: 14.9%; Shared:  0.4%; Buffer:  0.7%; Cache: 77.8%; Available: 89.1%. Swap used: 99.9%. µSD has 32.600% free space.


RPi 4B, 4GB
VNC server, Conky & script detailing Memory usage running. Nothing else started from reboot.
KStars 3.5.9:
All notifications disabled
Limited resources mode on
FITS viewer etc off
Not running any scheduler
SIMUALTOR for Telescope (mount) and Camera in EKOS.
Restarting KStars after test (just after stopping data aquisition for above plot) and starting Indi reports 'another instance of INDI running...'

Hoping for a clear night tomorrow night to do real imaging (Steve, I'll be running internal plate solving and guiding so hope to report back with that too) with the cache-purging option which through various tests to date have shown longer runs possible without crashing. Watch this space!
1 year 8 months ago #84126
Attachments:
The topic has been locked.
  • Posts: 39
  • Thank you received: 9
Yep: this is backtrace of mine ( Astroberry on Raspi 4B 4GB, with KStars 3.5.8 )
free(): invalid pointer
 
Thread 12 "Thread (pooled)" received signal SIGABRT, Aborted.
[Switching to Thread 0xa41c8040 (LWP 26410)]
__GI_raise (sig=sig@entry=6) at ../sysdeps/unix/sysv/linux/raise.c:50
50	../sysdeps/unix/sysv/linux/raise.c: File o directory non esistente.
(gdb) bt
#0  __GI_raise (sig=sig@entry=6) at ../sysdeps/unix/sysv/linux/raise.c:50
#1  0xb38cb230 in __GI_abort () at abort.c:79
#2  0xb391b50c in __libc_message (action=action@entry=do_abort, fmt=<optimized out>) at ../sysdeps/posix/libc_fatal.c:181
#3  0xb3922034 in malloc_printerr (str=<optimized out>) at malloc.c:5341
#4  0xb3923d40 in _int_free (av=0xb39fe7d4 <main_arena>, p=0x9e0b01c7, have_lock=<optimized out>) at malloc.c:4165
#5  0xb56ccc90 in void std::vector<std::pair<int, double>, std::allocator<std::pair<int, double> > >::_M_realloc_insert<std::pair<int, double> >(__gnu_cxx::__normal_iterator<std::pair<int, double>*, std::vector<std::pair<int, double>, std::allocator<std::pair<int, double> > > >, std::pair<int, double>&&) () from /usr/lib/arm-linux-gnueabihf/libstellarsolver.so.2
#6  0xb56c4290 in InternalExtractorSolver::extractPartition(InternalExtractorSolver::ImageParams const&) () from /usr/lib/arm-linux-gnueabihf/libstellarsolver.so.2
#7  0xb56cbb70 in non-v[sub]irtual thunk to QtConcurrent::RunFunctionTask<QList<FITSImage::Star> >::run() () from /usr/lib/arm-linux-gnueabihf/libstellarsolver.so.2
#8  0xb425ef30 in ?? () from /usr/lib/arm-linux-gnueabihf/libQt5Core.so.5
#9  0xb4267b58 in ?? () from /usr/lib/arm-linux-gnueabihf/libQt5Core.so.5
#10 0xb5797494 in start_thread (arg=0xa41c8040) at pthread_create.c:486
#11 0xb398b568 in ?? () at ../sysdeps/unix/sysv/linux/arm/clone.S:73 from /lib/arm-linux-gnueabihf/libc.so.6
Backtrace stopped: previous frame identical to this frame (corrupt stack?)
(gdb)

I can confitm that guiding for enough time is able to trigger the crash alone, seems like Alacant said.

The following user(s) said Thank You: alacant
1 year 8 months ago #84134
The topic has been locked.
  • Posts: 969
  • Thank you received: 94
"...guiding for enough time is able to trigger the crash alone..."

Thanks for posting.

Important to note that the crash occurs when calling the internal sextract, from stellarsolver, for the guide frames.

PHD2 guides all night as long as you like; no crash. Its memory usage remains constant, start to finish.

HTH and clear skies.
1 year 8 months ago #84136
The topic has been locked.
  • Posts: 349
  • Thank you received: 107

Replied by nou on topic KStars memory leak and crash

I think I am getting hit by same crash. Backtrace from gdb is little bit different but it is some kind of memory corruption so exact crash is somehow random. Going to build debug version and try to reproduce and poke around a little.
<code>
#0 __pthread_kill_implementation (no_tid=0, signo=6, threadid=140488782902848) at ./nptl/pthread_kill.c:44
#1 __pthread_kill_internal (signo=6, threadid=140488782902848) at ./nptl/pthread_kill.c:78
#2 __GI___pthread_kill (threadid=140488782902848, signo=signo@entry=6) at ./nptl/pthread_kill.c:89
#3 0x00007fc63d148476 in __GI_raise (sig=sig@entry=6) at ../sysdeps/posix/raise.c:26
#4 0x00007fc63d12e7f3 in __GI_abort () at ./stdlib/abort.c:79
#5 0x00007fc63d18f6f6 in __libc_message (action=action@entry=do_abort, fmt=fmt@entry=0x7fc63d2e1b8c "%s\n") at ../sysdeps/posix/libc_fatal.c:155
#6 0x00007fc63d1a6d7c in malloc_printerr (str=str@entry=0x7fc63d2e4d40 "malloc(): unaligned tcache chunk detected") at ./malloc/malloc.c:5664
#7 0x00007fc63d1ab45c in tcache_get (tc_idx=<optimized out>) at ./malloc/malloc.c:3195
#8 __GI___libc_malloc (bytes=60) at ./malloc/malloc.c:3313
#9 0x00007fc63f3f3bd5 in SEP::addobjdeep(int, SEP::objliststruct*, SEP::objliststruct*, int) () at /usr/local/lib/libstellarsolver.so.2
#10 0x00007fc63f3ee328 in SEP::Deblend::deblend(SEP::objliststruct*, int, SEP::objliststruct*, int, double, int, SEP::Lutz*) () at /usr/local/lib/libstellarsolver.so.2
#11 0x00007fc63f3eec0c in SEP::Extract::sortit(SEP::infostruct*, SEP::objliststruct*, int, SEP::objliststruct*, int, double, double) () at /usr/local/lib/libstellarsolver.so.2
#12 0x00007fc63f3f1773 in SEP::Extract::sep_extract(SEP::sep_image*, float, int, int, float*, int, int, int, int, double, int, double, SEP::sep_catalog**) () at /usr/local/lib/libstellarsolver.so.2
#13 0x00007fc63f3ae1c2 in InternalExtractorSolver::extractPartition(InternalExtractorSolver::ImageParams const&) () at /usr/local/lib/libstellarsolver.so.2
#14 0x00007fc63f3ba532 in non-virtual thunk to QtConcurrent::RunFunctionTask<QList<FITSImage::Star> >::run() () at /usr/local/lib/libstellarsolver.so.2
#15 0x00007fc63d9b7f92 in () at /lib/x86_64-linux-gnu/libQt5Core.so.5
#16 0x00007fc63d9b4ca1 in () at /lib/x86_64-linux-gnu/libQt5Core.so.5
#17 0x00007fc63d19ab43 in start_thread (arg=<optimized out>) at ./nptl/pthread_create.c:442
#18 0x00007fc63d22ca00 in clone3 () at ../sysdeps/unix/sysv/linux/x86_64/clone3.S:81
</code>
Last edit: 1 year 8 months ago by nou.
1 year 8 months ago #84164
The topic has been locked.
is this on nightly? Please try to see if you get the crash on nightly.
1 year 8 months ago #84165
The topic has been locked.
  • Posts: 39
  • Thank you received: 9
I also had different BTs from different sessions

This is a segmentation fault
Thread 21 "Thread (pooled)" received signal SIGSEGV, Segmentation fault.
[Switching to Thread 0x98a0c040 (LWP 21872)]
tcache_get (tc_idx=7) at malloc.c:2934
2934	malloc.c: File o directory non esistente.
(gdb) bt
#0  tcache_get (tc_idx=7) at malloc.c:2934
#1  __GI___libc_malloc (bytes=64) at malloc.c:3042
#2  0xb56fa3bc in SEP::Extract::convert_to_catalog(SEP::objliststruct*, int*, SEP::sep_catalog*, int, int) ()
   from /usr/lib/arm-linux-gnueabihf/libstellarsolver.so.2
#3  0xb56fbce0 in SEP::Extract::sep_extract(SEP::sep_image*, float, int, int, float*, int, int, int, int, double, int, double, SEP::sep_catalog**) ()
   from /usr/lib/arm-linux-gnueabihf/libstellarsolver.so.2
#4  0xb56c41d4 in InternalExtractorSolver::extractPartition(InternalExtractorSolver::ImageParams const&) ()
   from /usr/lib/arm-linux-gnueabihf/libstellarsolver.so.2
#5  0xb56cbb70 in non-virtual thunk to QtConcurrent::RunFunctionTask<QList<FITSImage::Star> >::run() ()
   from /usr/lib/arm-linux-gnueabihf/libstellarsolver.so.2
#6  0xb425ef30 in ?? () from /usr/lib/arm-linux-gnueabihf/libQt5Core.so.5
#7  0xb4267b58 in ?? () from /usr/lib/arm-linux-gnueabihf/libQt5Core.so.5
#8  0xb5797494 in start_thread (arg=0x98a0c040) at pthread_create.c:486
#9  0xb398b568 in ?? () at ../sysdeps/unix/sysv/linux/arm/clone.S:73 from /lib/arm-linux-gnueabihf/libc.so.6
Backtrace stopped: previous frame identical to this frame (corrupt stack?)
(gdb)

This one is a bus error: in this case only guiding was active; previous one a complete scheduled session (focus, align, ecc) was running.
Thread 21 "Thread (pooled)" received signal SIGBUS, Bus error.
[Switching to Thread 0x98a0c040 (LWP 4602)]
0xb56c2028 in ?? () from /usr/lib/arm-linux-gnueabihf/libstellarsolver.so.2
(gdb) bt
#0  0xb56c2028 in ?? () from /usr/lib/arm-linux-gnueabihf/libstellarsolver.so.2
#1  0xb56c46dc in InternalExtractorSolver::extractPartition(InternalExtractorSolver::ImageParams const&) ()
   from /usr/lib/arm-linux-gnueabihf/libstellarsolver.so.2
Backtrace stopped: Cannot access memory at address 0x4f1a51f8
1 year 8 months ago #84166
The topic has been locked.
  • Posts: 349
  • Thank you received: 107

Replied by nou on topic KStars memory leak and crash

It is with quite recent build but with older version of stellarsolver. Will try with latest build of stellarsolver and kstars.
1 year 8 months ago #84174
The topic has been locked.
  • Posts: 9
  • Thank you received: 3
Hi,
I think I have the same issue as here. Crashes every night when scheduling. Doesn't crash doing lots of dark/flat frames without using the scheduler. It's only started crashing after I updates about 3 weeks ago, and hadn't updated previously since Feb 2022. That'll teach me that if it 'aint broke, don't fix it.
Has anyone had any progress on this since the last post over 3 weeks ago ?. I see there is a new indi 1.9.7 released a few days ago. Any fixes in this for this issue ?
Here's hoping :-)
1 year 7 months ago #84922
The topic has been locked.
  • Posts: 969
  • Thank you received: 94
'Crashes every night when scheduling.'

Hi
No logs, so can only guess.
Turning off partitioning in stellarsolver works for some systems.
Or you can use ASTAP for solving and PHD2 for guiding.
Cheers and HTH
Last edit: 1 year 7 months ago by alacant.
1 year 7 months ago #84924
The topic has been locked.
  • Posts: 9
  • Thank you received: 3
Thanks for the quick reply. Looked at logs and it's definitely an out of memory issue :-

[2022-08-01T03:48:48.746 CEST DEBG ][ org.kde.kstars.indi] - Image received. Mode: "Normal" Size: 40368960
[2022-08-01T03:48:48.939 CEST INFO ][ org.kde.kstars.indi] - "FITS" file saved to "/media/astroberry/4311-BCAE/EkosImages/East Veil Nebula/East_Veil_Nebula/Light/East_Veil_Nebula_Light_60_secs_2022-08-01T03-48-48_231.fits"
[2022-08-01T03:48:48.939 CEST DEBG ][ org.kde.kstars.fits] - Reading file buffer ( "38.5 MiB" )
[2022-08-01T03:48:49.703 CEST CRIT ][ org.kde.kstars.fits] - Debayed memory allocation failure. Required Memory: "115.5 MiB" Available system memory: 1.8101e+09
[2022-08-01T03:48:49.772 CEST INFO ][ org.kde.kstars.ekos.capture] - "Download Time: 1.92 s, New Download Time Estimate: 2.23 s."
[2022-08-01T03:48:49.779 CEST INFO ][ org.kde.kstars.ekos.capture] - "Received image 231 out of 290."
[2022-08-01T03:48:49.783 CEST INFO ][ org.kde.kstars.ekos.capture] - "Captured /media/astroberry/4311-BCAE/EkosImages/East Veil Nebula/East_Veil_Nebula/Light/East_Veil_Nebula_Light_60_secs_2022-08-01T03-48-48_231.fits"

I do use stellarsolver and PHD2 for guiding. I'll try your suggestions as soon as I can get some sky to work with.

hairbear.
1 year 7 months ago #84925
The topic has been locked.
  • Posts: 349
  • Thank you received: 107

Replied by nou on topic KStars memory leak and crash

After I upgraded stellarsolver crashing stopped for me.
1 year 7 months ago #84934
The topic has been locked.
Time to create page: 2.190 seconds