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

Monthly maintenance release INDI v1.8.1

The Mysteries of RPi4

1 week 3 days ago 1 week 3 days ago by El Corazon.
El Corazon
Platinum Boarder
Platinum Boarder
Posts: 408
Karma: 3
More
Topic Author
The Mysteries of RPi4 #45660
Random Pi4 crashes, reason completely unclear.

One event earlier this morning involved gphoto2. No idea what triggered it. Here the log from another event last night. It seems to involve a problem with DSLR capture (Nikon D3300).



[2019-11-10T04:25:04.111 CST DEBG ][ org.kde.kstars.ekos.guide] - ################## FINISH PROCESSING ##################
[2019-11-10T04:25:04.111 CST DEBG ][ org.kde.kstars.ekos.guide] - Capturing frame...
[2019-11-10T04:25:04.471 CST DEBG ][ org.kde.kstars.indi] - Nikon DSLR DSC D3300 : "[DEBUG] Event timed out #1, retrying... "
[2019-11-10T04:25:04.896 CST DEBG ][ org.kde.kstars.ekos.scheduler] - Checking job stage for "M 42" startup 2 "10/11/19 03:12" state 3
[2019-11-10T04:25:04.896 CST DEBG ][ org.kde.kstars.ekos.scheduler] - Checking Park Wait State...
[2019-11-10T04:25:05.010 CST DEBG ][ org.kde.kstars.indi] - Nikon DSLR DSC D3300 : "[DEBUG] File added event completed. "
[2019-11-10T04:25:05.011 CST DEBG ][ org.kde.kstars.indi] - Nikon DSLR DSC D3300 : "[DEBUG] Downloading image... Name: (DSC_2654.NEF) Folder: (/store_00010001/DCIM/100D3300) Delete from SD card? (false) "
[2019-11-10T04:25:05.476 CST INFO ][ org.kde.kstars.fits] - Loading FITS file "/tmp/fitsng2889.fits"
[2019-11-10T04:25:05.550 CST DEBG ][ org.kde.kstars.ekos.guide] - Received guide frame.
[2019-11-10T04:25:05.551 CST DEBG ][ org.kde.kstars.ekos.guide] - Tracking Square QRect(318,327 32x32)
[2019-11-10T04:25:05.551 CST DEBG ][ org.kde.kstars.ekos.guide] - ################## BEGIN PROCESSING ##################
[2019-11-10T04:25:05.551 CST DEBG ][ org.kde.kstars.ekos.guide] - Star X : 334.949 Y : 342.659
[2019-11-10T04:25:05.551 CST DEBG ][ org.kde.kstars.ekos.guide] - Reticle X : 334.986 Y : 343.183
[2019-11-10T04:25:05.551 CST DEBG ][ org.kde.kstars.ekos.guide] - Star RA: 2159.01 DEC: 2208.7
[2019-11-10T04:25:05.552 CST DEBG ][ org.kde.kstars.ekos.guide] - Reticle RA: 2159.24 DEC: 2212.08
[2019-11-10T04:25:05.552 CST DEBG ][ org.kde.kstars.ekos.guide] -
> BEFORE ROTATION Diff RA: -0.23712 DEC: 3.37903
[2019-11-10T04:25:05.552 CST DEBG ][ org.kde.kstars.ekos.guide] -
> AFTER ROTATION Diff RA: 3.3678 DEC: 0.363308
[2019-11-10T04:25:05.552 CST DEBG ][ org.kde.kstars.ekos.guide] - RA channel ticks: 23 DEC channel ticks: 23
[2019-11-10T04:25:05.552 CST DEBG ][ org.kde.kstars.ekos.guide] - Processing Axes
[2019-11-10T04:25:05.552 CST DEBG ][ org.kde.kstars.ekos.guide] - At # 23 drift[ 0 ][ 23 ] = 3.3678 , t_delta: 3.3678
[2019-11-10T04:25:05.552 CST DEBG ][ org.kde.kstars.ekos.guide] - delta [ 0 ]= 3.3678
[2019-11-10T04:25:05.552 CST DEBG ][ org.kde.kstars.ekos.guide] - drift_integral[ 0 ]= -0.61646
[2019-11-10T04:25:05.553 CST DEBG ][ org.kde.kstars.ekos.guide] - pulse_length [ 0 ]= 448
[2019-11-10T04:25:05.553 CST DEBG ][ org.kde.kstars.ekos.guide] - Direction : Decrease RA
[2019-11-10T04:25:05.553 CST DEBG ][ org.kde.kstars.ekos.guide] - At # 23 drift[ 1 ][ 23 ] = 0.363308 , t_delta: 0.363308
[2019-11-10T04:25:05.553 CST DEBG ][ org.kde.kstars.ekos.guide] - delta [ 1 ]= 0.363308
[2019-11-10T04:25:05.553 CST DEBG ][ org.kde.kstars.ekos.guide] - drift_integral[ 1 ]= -0.211686
[2019-11-10T04:25:05.553 CST DEBG ][ org.kde.kstars.ekos.guide] - pulse_length [ 1 ]= 48
[2019-11-10T04:25:05.553 CST DEBG ][ org.kde.kstars.ekos.guide] - Direction : NO DIR
[2019-11-10T04:25:05.553 CST DEBG ][ org.kde.kstars.ekos.guide] - ################## FINISH PROCESSING ##################
[2019-11-10T04:25:05.908 CST DEBG ][ org.kde.kstars.ekos.scheduler] - Checking job stage for "M 42" startup 2 "10/11/19 03:12" state 3
[2019-11-10T04:25:05.909 CST DEBG ][ org.kde.kstars.ekos.scheduler] - Checking Park Wait State...
[2019-11-10T04:25:05.912 CST DEBG ][ org.kde.kstars.ekos.guide] - Capturing frame...
[2019-11-10T04:25:06.230 CST DEBG ][ org.kde.kstars.indi] - Nikon DSLR DSC D3300 : "[DEBUG] Downloaded 0x0 (preview 0x0) "
[2019-11-10T04:25:06.231 CST DEBG ][ org.kde.kstars.indi] - Nikon DSLR DSC D3300 : "[DEBUG] Capture target is SD Card. "
[2019-11-10T04:25:06.232 CST DEBG ][ org.kde.kstars.indi] - Nikon DSLR DSC D3300 : "[DEBUG] Exposure done, downloading image... "
[2019-11-10T04:25:06.242 CST DEBG ][ org.kde.kstars.indi] - Nikon DSLR DSC D3300 : "[DEBUG] Uploading file. Ext: NEF, Size: 18757442, sendImage? Yes, saveImage? No "
[2019-11-10T04:25:06.510 CST INFO ][ org.kde.kstars.fits] - Loading FITS file "/tmp/fitstT2889.fits"
[2019-11-10T04:25:06.558 CST DEBG ][ org.kde.kstars.ekos.guide] - Received guide frame.
[2019-11-10T04:25:06.559 CST DEBG ][ org.kde.kstars.ekos.guide] - Tracking Square QRect(318,326 32x32)
[2019-11-10T04:25:06.559 CST DEBG ][ org.kde.kstars.ekos.guide] - ################## BEGIN PROCESSING ##################
[2019-11-10T04:25:06.560 CST DEBG ][ org.kde.kstars.ekos.guide] - Star X : 335.031 Y : 342.862
[2019-11-10T04:25:06.560 CST DEBG ][ org.kde.kstars.ekos.guide] - Reticle X : 334.986 Y : 343.183
[2019-11-10T04:25:06.560 CST DEBG ][ org.kde.kstars.ekos.guide] - Star RA: 2159.53 DEC: 2210.01
[2019-11-10T04:25:06.560 CST DEBG ][ org.kde.kstars.ekos.guide] - Reticle RA: 2159.24 DEC: 2212.08
[2019-11-10T04:25:06.560 CST DEBG ][ org.kde.kstars.ekos.guide] -
> BEFORE ROTATION Diff RA: 0.28936 DEC: 2.06859
[2019-11-10T04:25:06.560 CST DEBG ][ org.kde.kstars.ekos.guide] -
> AFTER ROTATION Diff RA: 2.07796 DEC: -0.211806
[2019-11-10T04:25:06.560 CST DEBG ][ org.kde.kstars.ekos.guide] - RA channel ticks: 24 DEC channel ticks: 24
[2019-11-10T04:25:06.561 CST DEBG ][ org.kde.kstars.ekos.guide] - Processing Axes
[2019-11-10T04:25:06.561 CST DEBG ][ org.kde.kstars.ekos.guide] - At # 24 drift[ 0 ][ 24 ] = 2.07796 , t_delta: 2.07796
[2019-11-10T04:25:06.561 CST DEBG ][ org.kde.kstars.ekos.guide] - delta [ 0 ]= 2.07796
[2019-11-10T04:25:06.561 CST DEBG ][ org.kde.kstars.ekos.guide] - drift_integral[ 0 ]= -0.567973
[2019-11-10T04:25:06.561 CST DEBG ][ org.kde.kstars.ekos.guide] - pulse_length [ 0 ]= 276
[2019-11-10T04:25:06.561 CST DEBG ][ org.kde.kstars.ekos.guide] - Direction : Decrease RA
[2019-11-10T04:25:06.561 CST DEBG ][ org.kde.kstars.ekos.guide] - At # 24 drift[ 1 ][ 24 ] = -0.211806 , t_delta: -0.211806
[2019-11-10T04:25:06.561 CST DEBG ][ org.kde.kstars.ekos.guide] - delta [ 1 ]= -0.211806
[2019-11-10T04:25:06.561 CST DEBG ][ org.kde.kstars.ekos.guide] - drift_integral[ 1 ]= -0.192995
[2019-11-10T04:25:06.561 CST DEBG ][ org.kde.kstars.ekos.guide] - pulse_length [ 1 ]= 28
[2019-11-10T04:25:06.561 CST DEBG ][ org.kde.kstars.ekos.guide] - Direction : NO DIR
[2019-11-10T04:25:06.562 CST DEBG ][ org.kde.kstars.ekos.guide] - ################## FINISH PROCESSING ##################
[2019-11-10T04:25:06.664 CST DEBG ][ org.kde.kstars.indi] - Nikon DSLR DSC D3300 : "[DEBUG] BLOB transfer took 0.243871 seconds "
[2019-11-10T04:25:06.664 CST DEBG ][ org.kde.kstars.indi] - Nikon DSLR DSC D3300 : "[DEBUG] Upload complete "
[2019-11-10T04:25:06.845 CST INFO ][ org.kde.kstars.indi] - "NEF" file saved to "/home/tas/Pictures/OrionNebula/M42/Light/M42_Light_30_secs_2019-11-10T04-25-06_106.nef"
[2019-11-10T04:25:07.824 CST INFO ][ org.kde.kstars.fits] - Loading FITS file "/tmp/kstars/M42_Light_30_secs_2019-11-10T04-25-06_106.nef.Nx2889.fits"
[2019-11-10T04:25:07.825 CST DEBG ][ org.kde.kstars.ekos.guide] - Capturing frame...
[2019-11-10T04:25:07.929 CST DEBG ][ org.kde.kstars.ekos.scheduler] - Checking job stage for "M 42" startup 2 "10/11/19 03:12" state 3
[2019-11-10T04:25:07.930 CST DEBG ][ org.kde.kstars.ekos.scheduler] - Checking Park Wait State...
[2019-11-10T04:25:08.044 CST DEBG ][ org.kde.kstars.ekos.scheduler] - Checking job stage for "M 42" startup 2 "10/11/19 03:12" state 3
[2019-11-10T04:25:08.044 CST DEBG ][ org.kde.kstars.ekos.scheduler] - Checking Park Wait State...
[2019-11-10T04:25:08.646 CST DEBG ][ org.kde.kstars.fits] - FITHistogram: JMIndex 0
[2019-11-10T04:25:08.680 CST INFO ][ org.kde.kstars.fits] - Loading FITS file "/tmp/kstars/M42_Light_30_secs_2019-11-10T04-25-06_106.nef.Nx2889.fits"
[2019-11-10T04:25:08.680 CST INFO ][ org.kde.kstars.ekos.capture] - "Received image 106 out of 500."
[2019-11-10T04:25:08.688 CST INFO ][ org.kde.kstars.ekos.capture] - "Capturing 30.000-second image..."
[2019-11-10T04:25:08.700 CST DEBG ][ org.kde.kstars.ekos.scheduler] - Capture State "Image Received"
[2019-11-10T04:25:08.702 CST DEBG ][ org.kde.kstars.ekos.scheduler] - Capture State "Capturing"
[2019-11-10T04:25:08.724 CST INFO ][ org.kde.kstars.indi] - Nikon DSLR DSC D3300 : "[INFO] Starting 30 seconds exposure. "
[2019-11-10T04:25:08.808 CST DEBG ][ org.kde.kstars.indi] - Nikon DSLR DSC D3300 : "[DEBUG] Starting exposure (exptime: 30 secs, mirror lock: 0) "
[2019-11-10T04:25:08.808 CST DEBG ][ org.kde.kstars.indi] - Nikon DSLR DSC D3300 : "[DEBUG] Mutex locked "
[2019-11-10T04:25:08.808 CST DEBG ][ org.kde.kstars.indi] - Nikon DSLR DSC D3300 : "[DEBUG] Setting radio/menu widget iso: 2 (400) "
[2019-11-10T04:25:08.808 CST DEBG ][ org.kde.kstars.indi] - Nikon DSLR DSC D3300 : "[DEBUG] Setting new configuration OK. "
[2019-11-10T04:25:08.809 CST DEBG ][ org.kde.kstars.indi] - Nikon DSLR DSC D3300 : "[DEBUG] Setting radio/menu widget imagequality: 3 (NEF (Raw)) "
[2019-11-10T04:25:08.809 CST DEBG ][ org.kde.kstars.indi] - Nikon DSLR DSC D3300 : "[DEBUG] Setting new configuration OK. "
[2019-11-10T04:25:08.809 CST DEBG ][ org.kde.kstars.indi] - Nikon DSLR DSC D3300 : "[DEBUG] Using internal bulb widget:bulb "
[2019-11-10T04:25:08.809 CST DEBG ][ org.kde.kstars.indi] - Nikon DSLR DSC D3300 : "[DEBUG] Setting toggle widget bulb: 1 "

End of log!

I have no idea what triggered the following crash. Note that the log file is truncated in mid-word:

[2019-11-10T00:16:01.299 CST DEBG ][ org.kde.kstars.ekos.guide] - ################## FINISH PROCESSING ##################
[2019-11-10T00:16:01.494 CST DEBG ][ org.kde.kstars.ekos.guide] - Capturing frame...
[2019-11-10T00:16:02.809 CST INFO ][ org.kde.kstars.fits] - Loading FITS file "/tmp/fitsV18133.fits"
[2019-11-10T00:16:02.852 CST DEBG ][ org.kde.kstars.ekos.guide] - Received guide frame.
[2019-11-10T00:16:02.852 CST DEBG ][ org.kde.kstars.ekos.guide] - Tracking Square QRect(1094,339 32x32)
[2019-11-10T00:16:02.853 CST DEBG ][ org.kde.kstars.ekos.guide] - ################## BEGIN PROCESSING ##################
[2019-11-10T00:16:02.853 CST DEBG ][ org.kde.kstars.ekos.guide] - Star X : 1110.77 Y : 354.696
[2019-11-10T00:16:02.853 CST DEBG ][ org.kde.kstars.ekos.guide] - Reticle X : 1110.61 Y : 354.782
[2019-11-10T00:16:02.853 CST DEBG ][ org.kde.kstars.ekos.guide] - Star RA: 7159.8 DEC: 2286.29
[2019-11-10T00:16:02.853 CST DEBG ][ org.kde.kstars.ekos.guide] - Reticle RA: 7158.76 DEC: 2286.84
[2019-11-10T00:16:02.853 CST DEBG ][ org.kde.kstars.ekos.guide] -
> BEFORE ROTATION Diff RA: 1.04255 DEC: 0.550809
[2019-11-10T00:16:02.853 CST DEBG ][ org.kde.kstars.ekos.guide] -
> AFTER ROTATION Diff RA: 0.411834 DEC: -1.10485
[2019-11-10T00:16:02.853 CST DEBG ][ org.kde.kstars.ekos.guide] - RA channel ticks: 19 DEC channel ticks: 19
[2019-11-10T00:16:02.853 CST DEBG ][ org.kde.kstars.ekos.guide] - Processing Axes
[2019-11-10T00:16:02.854 CST DEBG ][ org.kde.kstars.ekos.guide] - At # 19 drift[ 0 ][ 19 ] = 0.411834 , t_delta: 0.411834
[2019-11-10T00:16:02.854 CST DEBG ][ org.kde.kstars.ekos.guide] - delta [ 0 ]= 0.411834
[2019-11-10T00:16:02.854 CST DEBG ][ org.kde.kstars.ekos.guide] - drift_integral[ 0 ]= -0.567538
[2019-11-10T00:16:02.854 CST DEBG ][ org.kde.kstars.ekos.guide] - pulse_length [ 0 ]= 54
[2019-11-10T00:16:02.854 CST DEBG ][ org.kde.kstars.ekos.guide] - Direction : NO DIR
[2019-11-10T00:16:02.854 CST DEBG ][ org.kde.kstars.ekos.guide] - At # 19 drift[ 1 ][ 19 ] = -1.10485 , t_delta: -1.10485
[2019-11-10T00:16:02.854 CST DEBG ][ org.kde.kstars.ekos.guide] - delta [ 1 ]= -1.10485
[2019-11-10T00:16:02.854 CST DEBG ][ org.kde.kstars.ekos.guide] - drift_integral[ 1 ]= 0.23113
[2019-11-10T00:16:02.854 CST DEBG ][ org.kde.kstars.ekos.guide] - pulse_length [ 1 ]= 147
[2019-11-10T00:16:02.854 CST DEBG ][ org.kde.kstars.ekos.guide] - Direction : Decrease DEC
[2019-11-10T00:16:02.854 CST DEBG ][ org.kde.kstars.ekos.guide] - ################## FINISH PROCESSING ##################
[2019-11-10T00:16:02.855 CST DEBG ][ org.kde.kstars.ekos.guide] - Capturing frame...
[2019-11-10T00:16:03.825 CST INFO ][ org.kde.kstars.fits] - Loading FITS file "/tmp/fitsf18133.fits"
[2019-11-10T00:16:03.889 CST DEBG ][ org.kde.kstars.ekos.guide] - Received guide frame.
[2019-11-10T00:16:03.890 CST DEBG ][ org.kde.kstars.ekos.guide] - Tracking Square QRect(1094,338 32x32)
[2019-11-10T00:16:03.891 CST DEBG ][ org.kde.kstars.ekos.guide] - ################## BEGIN PROCESSING ##################
[2019-11-10T00:16:03.891 CST DEBG ][ org.kde.kstars.ekos.guide] - Star X : 1110.97 Y : 354.697
[2019-11-10T00:16:03.892 CST DEBG ][ org.kde.kstars.ekos.guide] - Reticle X : 1110.61 Y : 354.782
[2019-11-10T00:16:03.893 CST DEBG ][ org.kde.kstars.ekos.guide] - Star RA: 7161.05 DEC: 2286.29
[2019-11-10T00:16:03.894 CST DEBG ][ org.kde.kstars.ekos.guide] - Reticle RA: 7158.76 DEC: 2286.84
[2019-11-10T00:16:03.895 CST DEBG ][ org.kde.kstars.ekos.guide] -
> BEFORE ROTATION Diff RA: 2.2922 DEC: 0.549244
[2019-11-10T00:16:03.896 CST DEBG ][ org.kde.kstars.ekos.guide] -
> AFTER ROTATION Diff RA: 0.249207 DEC: -2.34387
[2019-11-10T00:16:03.896 CST DEBG ][ org.kde.kstars.ekos.guide] - RA channel ticks: 20 DEC channel ticks: 20
[2019-11-10T00:16:03.896 CST DEBG ][ org.kde.kstars.ekos.guide] - Processing Axes
[2019-11-10T00:16:03.896 CST DEBG ][ org.kde.kstars.ekos.guide] - At # 20 drift[ 0 ][ 20 ] = 0.249207 , t_delta: 0.249207
[2019-11-10T00:16:03.896 CST DEBG ][ org.kde.kstars.ekos.guide] - delta [ 0 ]= 0.249207
[2019-11-10T00:16:03.896 CST DEBG ][ org.kde.kstars.ekos.guide] - drift_integral[ 0 ]= -0.469601
[2019-11-10T00:16:03.896 CST DEBG ][ org.kde.kstars.ekos.guide] - pulse_length [ 0 ]= 33
[2019-11-10T00:16:03.897 CST DEBG ][ org.kde.kstars.ekos.guide] - Direction : NO DIR
[2019-11-10T00:16:03.897 CST DEBG ][ org.kde.kstars.ekos.guide] - At # 20 drift[ 1 ][ 20 ] = -2.34387 , t_delta: -2.34387
[2019-11-10T00:16:03.897 CST DEBG ][ org.kde.kstars.ekos.guide] - delta [ 1 ]= -2.34387
[2019-11-10T00:16:03.897 CST DEBG ][ org.kde.kstars.ekos.guide] - drift_integral[ 1 ]= 0.0689959
[2019-11-10T00:16:03.897 CST DEBG ][ org.kde.kstars.ekos.guide] - pulse_length [ 1 ]= 312
[2019-11-10T00:16:03.897 CST DEBG ][ org.kde.kstars.ekos.guide] - Direction : Decrease DEC
[2019-11-10T00:16:03.897 CST DEBG ][ org.kde.kstars.ekos.guid

End of log!

In both cases, I could still ping the Pi4, so it was connected to the wireless network, but I could no longer connect via ssh. I was completely unreponsive.

gdb backtrace was on, but yielded no stack. Everything was wiped.

Atlas Pro AZ-EQ, ASI1600MM-Pro, ASI120MM-S, ES102ED, WO-Z61, Nikon D3300, ASI-EFW, ZWO LRGB,Ha,O3,S2 filter set

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

1 week 3 days ago
universalmaster
Expert Boarder
Expert Boarder
Posts: 125
More
The Mysteries of RPi4 #45675
Often when linux becomes unresponsive (even via ssh), it is because memory usage is high and it is constantly swapping. Try turning off swap space, then it may just kill the "offending" application instead of swapping. Running 'swapoff -a' may do the trick (until next reboot I guess).

I have also read somewhere, than the RPI4 can easily overheat, especially in a closed case. If you have a closed case, try running without the top cover to increase heat dissipation.

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

1 week 3 days ago
El Corazon
Platinum Boarder
Platinum Boarder
Posts: 408
Karma: 3
More
Topic Author
The Mysteries of RPi4 #45680
Swap can be easily turned off permanently by commenting out the corresponding line in /etc/fstab
Overheating was surely not the problem. The CPU is cooled by a case fan and it was pretty cool outside during the night.
But I will try with swap turned off/zRAM enabled.
Thanks
Jo

Atlas Pro AZ-EQ, ASI1600MM-Pro, ASI120MM-S, ES102ED, WO-Z61, Nikon D3300, ASI-EFW, ZWO LRGB,Ha,O3,S2 filter set

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

Time to create page: 0.681 seconds