Absturz bei Netzwerklast

[schubi82]

Neenee, ich bin sehr geduldig
Die Webmasken von iobroker und Homematic sind dann nicht mehr erreichbar und ssh ist auch nicht möglich.
Heute Nacht gab es ein automatisches Update des Fritzbox-OS, heute morgen hat er dann nicht mehr reagiert.
WLAN sollte aus sein, komme zumindest nicht mehr auf die WLAN-Optionen. Glaube das war mal für das Homematic-Funkmodul notwendig.
Maus und Tastatur kann ich anschließen, Monitor allerdings nicht. Denkst Du, man könnte dann mehr sehen?
Zeitlich passen die letzten Logeinträge nicht ganz mit dem Update der Fritzbox (ca. 2:30 Uhr) zusammen, sehen aber seltsam aus:

syslog:

Apr  9 02:03:55 raspberrypi systemd-udevd[142]: rpi_rf_mod:blue: Worker [20522] processing SEQNUM=1189224 is taking a long time
Apr  9 02:28:56 raspberrypi systemd-udevd[142]: rpi_rf_mod:blue: Worker [20522] processing SEQNUM=1189224 killed
Apr  9 02:52:47 raspberrypi systemd-udevd[142]: Worker [20522] terminated by signal 9 (KILL)
Apr  9 03:13:29 raspberrypi rngd[322]: stats: bits received from HRNG source: 12060064
Apr  9 03:39:38 raspberrypi systemd-udevd[142]: rpi_rf_mod:blue: Worker [20522] failed
Apr  9 04:03:30 raspberrypi rngd[322]: stats: bits sent to kernel pool: 11997920
Apr  9 04:29:10 raspberrypi systemd-udevd[142]: rpi_rf_mod:blue: Worker [20527] processing SEQNUM=1189225 killed
Apr  9 04:48:35 raspberrypi rngd[322]: stats: entropy added to kernel pool: 11997920
Apr  9 05:01:36 raspberrypi kernel: [1449854.064764] raw-uart raw-uart: generic_raw_uart_handle_rx_char(): rx fifo full.

wiederholt sich mehrfach

Apr  9 07:17:33 raspberrypi kernel: [1457832.510453] eq3loop: eq3loop_write_master() retrun error:
Apr  9 07:17:45 raspberrypi kernel: [1458011.638945] eq3loop: eq3loop_write_master() mmd_bidcos: not enought space in the buffers. free space = 12, required space = 24

kernel endet ähnlich, hier ist beim Fritzbox-Update natürlich das Netzwerk weggebrochen (Zeitzone um eine Stunde falsch):

Apr  9 01:32:38 raspberrypi kernel: [1437323.102010] br0: port 1(eth0) entered disabled state
Apr  9 01:32:40 raspberrypi kernel: [1437324.853860] smsc95xx 1-1.1:1.0 eth0: link up, 100Mbps, full-duplex, lpa 0xC1E1
Apr  9 01:32:40 raspberrypi kernel: [1437324.854952] br0: port 1(eth0) entered blocking state
Apr  9 01:32:40 raspberrypi kernel: [1437324.854962] br0: port 1(eth0) entered forwarding state
Apr  9 05:01:36 raspberrypi kernel: [1449854.064764] raw-uart raw-uart: generic_raw_uart_handle_rx_char(): rx fifo full.

Im iobroker-Log ist das der letzte Eintrag, 20 min davor nur Infos:

2020-04-09 01:57:53.551  - error: hm-rpc.1 (23390) init error: Error: response timeout

Ich denke der Absturz war dann eher um 5:01 (also eigentlich 6:01), was mit der Aufstehzeit meiner Frau übereinstimmt. Sie gibt Alexa dann den Befehl zum Rolläden hochfahren, was heute morgen nicht funktioniert hat. Um 7:17 (8:17) habe ich dann ca. neu gestartet.

[wendy2702]

@schubi82 sagte in Absturz bei Netzwerklast:

bidcos: not enought space in the buffers. free space = 12, required space = 24

Könnte mir vorstellen das es damit zusammen hängt. Gibt auch ein Issue bei z.B. Raspberrymatic dazu:

https://github.com/jens-maus/RaspberryMatic/issues/703

Ist das PiVCCU 2 oder 3 ?

Welche SW ?

jens-maus created this issue in jens-maus/RaspberryMatic

closed eq3loop: eq3loop_write_master() mmd_hmip: not enought space in the buffers #703

[schubi82]

Hmm, aber der kommt ja erst nach dem Neustart... Auffällig ist ja eher

raw-uart raw-uart: generic_raw_uart_handle_rx_char(): rx fifo full.

Was auch immer das ist... ich habe pivccu3 in der Version 3.51.6-36 installiert.
Ich mach nochmal ein sudo apt update && sudo apt upgrade auf die 3.51.6-39.

[wendy2702]

Ich würde Monitor/Tastatur anschließen um genau beobachten/gucken zu können was passiert wenn nicht mehr per Netzwerk erreichbar.

Welche Auslastung hat der PI denn so?

[schubi82]

Mach ich, danke.
Dümpelt bei unter 5% CPU rum bei ca. 60 MB freiem RAM.

[wendy2702]

unter 5% ?

wo abgelesen?

[schubi82]

Mit top...dachte ich...

pi@raspberrypi:/var/log $ top
top - 13:29:27 up  6:06,  1 user,  load average: 1.32, 1.21, 0.95
Tasks: 146 total,   2 running, 144 sleeping,   0 stopped,   0 zombie
%Cpu(s):   7.3/1.2     8[||||||||                                                                                            ]
MiB Mem :    926.1 total,    119.6 free,    567.6 used,    238.9 buff/cache
MiB Swap:    100.0 total,      3.2 free,     96.8 used.    300.2 avail Mem

  PID USER      PR  NI    VIRT    RES    SHR S  %CPU  %MEM     TIME+ COMMAND
  534 iobroker  20   0  183812  59660  10180 S  16.2   6.3  24:13.73 iobroker.js-con
 1561 iobroker  20   0  195400  55084   7816 S   5.3   5.8   4:04.71 io.javascript.0
13278 root      20   0    1208   1012    664 R   4.3   0.1   0:00.13 bash
  648 iobroker  20   0  177172  57984  10732 S   2.6   6.1   2:19.82 io.admin.0
 1774 iobroker  20   0  154108  33164   7512 S   1.0   3.5   3:24.71 io.scenes.0
 1900 iobroker  20   0  189828  68348   7372 S   1.0   7.2   3:32.95 node-red
    9 root      20   0       0      0      0 S   0.3   0.0   0:02.74 ksoftirqd/0
  141 root      20   0   18056   3060   2476 S   0.3   0.3   0:11.36 systemd-udevd
 1391 iobroker  20   0  147556  20852   7336 S   0.3   2.2   1:11.28 io.denon.0
 1426 iobroker  20   0  154140  22248   7824 S   0.3   2.3   0:37.67 io.alexa2.0
 1712 iobroker  20   0  147868  25448   7392 S   0.3   2.7   0:28.94 io.node-red.0
 8003 root      20   0   30004  12596   4048 S   0.3   1.3   0:01.95 ReGaHss.communi
 8095 root      20   0    1120    912    664 S   0.3   0.1   0:00.25 bash
 8941 pi        20   0   10320   2708   2316 R   0.3   0.3   0:01.77 top
    1 root      20   0   34784   6380   5116 S   0.0   0.7   0:11.08 systemd
    2 root      20   0       0      0      0 S   0.0   0.0   0:00.03 kthreadd
    3 root       0 -20       0      0      0 I   0.0   0.0   0:00.00 rcu_gp
    4 root       0 -20       0      0      0 I   0.0   0.0   0:00.00 rcu_par_gp
    8 root       0 -20       0      0      0 I   0.0   0.0   0:00.00 mm_percpu_wq
   10 root      20   0       0      0      0 I   0.0   0.0   0:12.44 rcu_sched
   11 root      20   0       0      0      0 I   0.0   0.0   0:00.00 rcu_bh
   12 root      rt   0       0      0      0 S   0.0   0.0   0:00.37 migration/0
   13 root      20   0       0      0      0 S   0.0   0.0   0:00.00 cpuhp/0
   14 root      20   0       0      0      0 S   0.0   0.0   0:00.00 cpuhp/1
   15 root      rt   0       0      0      0 S   0.0   0.0   0:00.44 migration/1
   16 root      20   0       0      0      0 S   0.0   0.0   0:01.00 ksoftirqd/1
   19 root      20   0       0      0      0 S   0.0   0.0   0:00.00 cpuhp/2
   20 root      rt   0       0      0      0 S   0.0   0.0   0:00.42 migration/2
   21 root      20   0       0      0      0 S   0.0   0.0   0:01.56 ksoftirqd/2
   24 root      20   0       0      0      0 S   0.0   0.0   0:00.00 cpuhp/3
   25 root      rt   0       0      0      0 S   0.0   0.0   0:00.47 migration/3

[wendy2702]

@schubi82 sagte in Absturz bei Netzwerklast:

MiB Mem : 926.1 total, 119.6 free, 567.6 used, 238.9 buff/cache MiB Swap: 100.0 total, 3.2 free, 96.8 used. 300.2 avail Mem

Vielleicht geht dir auch der Speicher aus wenn er fast vollständig swapped.

[schubi82]

Möglich. Hab mal etwas von Redis gelesen, wäre das evtl. eine Option?

[wendy2702]

Glaube nicht da du dadurch genug Speicher gewinnst.

Ich würde ioBroker auf etwas anderes umziehen.

[schubi82]

Alles klar, danke Dir!