Absturz FHEM bei Aufruf SVG (Daten in MariaDB)

presskopf · 15 Januar 2021, 15:17:49

Hallo zusammen,

ich habe hin und wieder - nicht spontan reproduzierbar - einen FHEM-Absturz (FHEM-Task komplett weg) beim Aufrufen eines SVG Plots.
Es scheint meiner Einschätzung eine Interaktion zwischen SVG - logdb (MariaDB) zu sein, daher hier.
Im folgenden der betreffende FHEM-log-Abschnitt, syslog und mysql-error.log und die List von den beteiligten Devices.

Habe ich irgendwo Käse konfiguriert?

Viele Grüße
Matthias

FHEM-log

Code Auswählen


2021.01.15 09:11:40 1: PERL WARNING: Argument "set_on" isn't numeric in numeric gt (>) at ./FHEM/98_SVG.pm line 1582.
2021.01.15 09:11:40 1: PERL WARNING: Argument "on" isn't numeric in numeric gt (>) at ./FHEM/98_SVG.pm line 1582.
2021.01.15 09:11:40 1: PERL WARNING: Argument "set_off" isn't numeric in numeric gt (>) at ./FHEM/98_SVG.pm line 1582.
2021.01.15 09:11:40 1: PERL WARNING: Argument "off" isn't numeric in numeric gt (>) at ./FHEM/98_SVG.pm line 1582.
2021.01.15 09:11:40 1: PERL WARNING: Argument "set_on" isn't numeric in subtraction (-) at ./FHEM/98_SVG.pm line 1940.
2021.01.15 09:11:41 1: PERL WARNING: Argument "on" isn't numeric in subtraction (-) at ./FHEM/98_SVG.pm line 1940.
2021.01.15 09:11:41 1: PERL WARNING: Argument "set_off" isn't numeric in subtraction (-) at ./FHEM/98_SVG.pm line 1940.
2021.01.15 09:11:41 1: PERL WARNING: Argument "off" isn't numeric in subtraction (-) at ./FHEM/98_SVG.pm line 1940.
2021.01.15 09:11:41 1: PERL WARNING: Argument "off" isn't numeric in sprintf at ./FHEM/98_SVG.pm line 2210.
2021.01.15 09:11:41 3: DbLog: Error in inline function: <:>, Error: syntax error at (eval 655667) line 1, near ":"

2021.01.15 09:11:41 3: DbLog: Error in inline function: <:>, Error: syntax error at (eval 655668) line 1, near ":"

2021.01.15 09:11:41 3: DbLog: Error in inline function: <:>, Error: syntax error at (eval 655669) line 1, near ":"

2021.01.15 09:11:41 3: DbLog: Error in inline function: <:>, Error: syntax error at (eval 655670) line 1, near ":"

2021.01.15 09:11:41 3: DbLog: Error in inline function: <:>, Error: syntax error at (eval 655671) line 1, near ":"

2021.01.15 09:11:41 3: DbLog: Error in inline function: <:>, Error: syntax error at (eval 655672) line 1, near ":"

2021.01.15 09:11:41 3: DbLog: Error in inline function: <:>, Error: syntax error at (eval 655673) line 1, near ":"

2021.01.15 09:11:41 3: DbLog: Error in inline function: <:>, Error: syntax error at (eval 655674) line 1, near ":"
....

Ein paar Sekunden später sieht man dann folgendes im syslog, das ich aber nicht so recht verstehe.
[code]Jan 15 09:11:55 raspi-4 systemd[1]: mariadb.service: Main process exited, code=killed, status=9/KILL
Jan 15 09:11:55 raspi-4 systemd[1]: mariadb.service: Failed with result 'signal'.
Jan 15 09:11:54 raspi-4 kernel: [999837.742020] sshd invoked oom-killer: gfp_mask=0x100cca(GFP_HIGHUSER_MOVABLE), order=0, oom_score_adj=-1000
Jan 15 09:11:55 raspi-4 kernel: [999837.742035] CPU: 0 PID: 23598 Comm: sshd Tainted: G C 5.4.79-v7+ #1373
Jan 15 09:11:55 raspi-4 kernel: [999837.742038] Hardware name: BCM2835
Jan 15 09:11:55 raspi-4 kernel: [999837.742041] Backtrace:
Jan 15 09:11:55 raspi-4 kernel: [999837.742057] [<8010dfa4>] (dump_backtrace) from [<8010e324>] (show_stack+0x20/0x24)
Jan 15 09:11:55 raspi-4 kernel: [999837.742063] r7:ffffffff r6:00000000 r5:60000113 r4:80e97f70
Jan 15 09:11:55 raspi-4 kernel: [999837.742073] [<8010e304>] (show_stack) from [<80901174>] (dump_stack+0xd8/0x11c)
Jan 15 09:11:55 raspi-4 kernel: [999837.742082] [<8090109c>] (dump_stack) from [<8028ceac>] (dump_header+0x64/0x1fc)
Jan 15 09:11:55 raspi-4 kernel: [999837.742090] r10:00000000 r9:80e05084 r8:80e056a4 r7:80bbc1a0 r6:a9541b00 r5:a972cc40
Jan 15 09:11:55 raspi-4 kernel: [999837.742093] r4:87b8bd40 r3:fa9b2146
Jan 15 09:11:55 raspi-4 kernel: [999837.742100] [<8028ce48>] (dump_header) from [<8028c268>] (oom_kill_process+0x178/0x184)
Jan 15 09:11:55 raspi-4 kernel: [999837.742105] r7:80bbc1a0 r6:87b8bd40 r5:00000558 r4:a972cc40
Jan 15 09:11:55 raspi-4 kernel: [999837.742112] [<8028c0f0>] (oom_kill_process) from [<8028ccfc>] (out_of_memory+0x27c/0x33c)
Jan 15 09:11:55 raspi-4 kernel: [999837.742117] r7:80e08200 r6:87b8bd40 r5:80e04f88 r4:a972cc40
Jan 15 09:11:55 raspi-4 kernel: [999837.742125] [<8028ca80>] (out_of_memory) from [<802d87d4>] (__alloc_pages_nodemask+0xc00/0x1270)
Jan 15 09:11:55 raspi-4 kernel: [999837.742131] r7:00000000 r6:00000000 r5:00400040 r4:87b8a000
Jan 15 09:11:55 raspi-4 kernel: [999837.742140] [<802d7bd4>] (__alloc_pages_nodemask) from [<80286854>] (pagecache_get_page+0x140/0x37c)
Jan 15 09:11:55 raspi-4 kernel: [999837.742147] r10:00000069 r9:80e058fc r8:b58ac1c0 r7:00100cca r6:80e04f88 r5:00000044
Jan 15 09:11:55 raspi-4 kernel: [999837.742150] r4:e3940001
Jan 15 09:11:55 raspi-4 kernel: [999837.742158] [<80286714>] (pagecache_get_page) from [<80287118>] (filemap_fault+0x688/0xa9c)
Jan 15 09:11:55 raspi-4 kernel: [999837.742165] r10:00000069 r9:b58ac1c0 r8:80e04f88 r7:87b8bebc r6:b3f9bb40 r5:b3f9bb40
Jan 15 09:11:55 raspi-4 kernel: [999837.742168] r4:b58ac0b0
Jan 15 09:11:55 raspi-4 kernel: [999837.742178] [<80286a90>] (filemap_fault) from [<803e2788>] (ext4_filemap_fault+0x38/0x4c)
Jan 15 09:11:55 raspi-4 kernel: [999837.742185] r10:80283d2c r9:87b8bebc r8:00000040 r7:769b1000 r6:b23ab8a0 r5:87b8bebc
Jan 15 09:11:55 raspi-4 kernel: [999837.742187] r4:b58ac098
Jan 15 09:11:55 raspi-4 kernel: [999837.742195] [<803e2750>] (ext4_filemap_fault) from [<802bd358>] (__do_fault+0x5c/0x178)
Jan 15 09:11:55 raspi-4 kernel: [999837.742199] r5:87b8bebc r4:80e04f88
Jan 15 09:11:55 raspi-4 kernel: [999837.742205] [<802bd2fc>] (__do_fault) from [<802c1e74>] (handle_mm_fault+0x8b4/0xc88)
Jan 15 09:11:55 raspi-4 kernel: [999837.742211] r8:00000040 r7:769b1000 r6:80e04f88 r5:00000d73 r4:00000000
Jan 15 09:11:55 raspi-4 kernel: [999837.742220] [<802c15c0>] (handle_mm_fault) from [<809208c4>] (do_page_fault+0x150/0x380)
Jan 15 09:11:55 raspi-4 kernel: [999837.742226] r10:87379640 r9:b23ab8a0 r8:80000007 r7:769b1db8 r6:87379600 r5:afed8000
Jan 15 09:11:55 raspi-4 kernel: [999837.742229] r4:87b8bfb0
Jan 15 09:11:55 raspi-4 kernel: [999837.742236] [<80920774>] (do_page_fault) from [<80115fc0>] (do_PrefetchAbort+0x48/0x9c)
Jan 15 09:11:55 raspi-4 kernel: [999837.742243] r10:76a92000 r9:00000001 r8:87b8bfb0 r7:769b1db8 r6:80920774 r5:00000007
Jan 15 09:11:55 raspi-4 kernel: [999837.742246] r4:80e0aa84
Jan 15 09:11:55 raspi-4 kernel: [999837.742253] [<80115f78>] (do_PrefetchAbort) from [<80101fa4>] (ret_from_exception+0x0/0x1c)
Jan 15 09:11:55 raspi-4 kernel: [999837.742257] Exception stack(0x87b8bfb0 to 0x87b8bff8)
Jan 15 09:11:55 raspi-4 kernel: [999837.742262] bfa0: 02083170 0008f4ee 00000001 769b1db8
Jan 15 09:11:55 raspi-4 kernel: [999837.742269] bfc0: 02083170 00000001 0008f4ec 0008f4ee 76a90b1c 00000001 76a92000 7e81517c
Jan 15 09:11:55 raspi-4 kernel: [999837.742274] bfe0: 00000025 7e814c88 76989684 769b1db8 20000010 ffffffff
Jan 15 09:11:55 raspi-4 kernel: [999837.742280] r8:10c5387d r7:10c5383d r6:ffffffff r5:20000010 r4:769b1db8
Jan 15 09:11:55 raspi-4 kernel: [999837.742303] Mem-Info:
Jan 15 09:11:55 raspi-4 kernel: [999837.742317] active_anon:110145 inactive_anon:110188 isolated_anon:0
Jan 15 09:11:55 raspi-4 kernel: [999837.742317] active_file:289 inactive_file:394 isolated_file:32
Jan 15 09:11:55 raspi-4 kernel: [999837.742317] unevictable:0 dirty:0 writeback:0 unstable:0
Jan 15 09:11:55 raspi-4 kernel: [999837.742317] slab_reclaimable:2996 slab_unreclaimable:3847
Jan 15 09:11:55 raspi-4 kernel: [999837.742317] mapped:1179 shmem:5552 pagetables:1550 bounce:0
Jan 15 09:11:55 raspi-4 kernel: [999837.742317] free:3920 free_pcp:229 free_cma:43
Jan 15 09:11:55 raspi-4 kernel: [999837.742328] Node 0 active_anon:440580kB inactive_anon:440752kB active_file:1156kB inactive_file:1576kB unevictable:0kB isolated(anon):0kB isolated(file):0kB mapped:4716kB dirty:0kB writeback:0kB shmem:22208kB writeback_tmp:0kB unstable:0kB all_unreclaimable? no
Jan 15 09:11:55 raspi-4 kernel: [999837.742342] Normal free:15680kB min:16384kB low:20480kB high:24576kB active_anon:440580kB inactive_anon:440752kB active_file:1156kB inactive_file:1708kB unevictable:0kB writepending:0kB present:970752kB managed:947036kB mlocked:0kB kernel_stack:1784kB pagetables:6200kB bounce:0kB free_pcp:916kB local_pcp:376kB free_cma:172kB
Jan 15 09:11:55 raspi-4 kernel: [999837.742346] lowmem_reserve[]: 0 0
Jan 15 09:11:55 raspi-4 kernel: [999837.742355] Normal: 472*4kB (UEC) 440*8kB (UEC) 232*16kB (UE) 89*32kB (UE) 38*64kB (UME) 10*128kB (UM) 0*256kB 0*512kB 0*1024kB 0*2048kB 0*4096kB = 15680kB
Jan 15 09:11:55 raspi-4 kernel: [999837.742396] 15277 total pagecache pages
Jan 15 09:11:55 raspi-4 kernel: [999837.742402] 9005 pages in swap cache
Jan 15 09:11:55 raspi-4 kernel: [999837.742408] Swap cache stats: add 106561, delete 97556, find 1088673/1102568
Jan 15 09:11:55 raspi-4 kernel: [999837.742413] Free swap = 0kB
Jan 15 09:11:55 raspi-4 kernel: [999837.742417] Total swap = 102396kB
Jan 15 09:11:55 raspi-4 kernel: [999837.742422] 242688 pages RAM
Jan 15 09:11:55 raspi-4 kernel: [999837.742427] 0 pages HighMem/MovableOnly
Jan 15 09:11:55 raspi-4 kernel: [999837.742431] 5929 pages reserved
Jan 15 09:11:55 raspi-4 kernel: [999837.742436] 16384 pages cma reserved
Jan 15 09:11:55 raspi-4 kernel: [999837.742441] Tasks state (memory values in pages):
Jan 15 09:11:55 raspi-4 kernel: [999837.742446] [ pid ] uid tgid total_vm rss pgtables_bytes swapents oom_score_adj name
Jan 15 09:11:55 raspi-4 kernel: [999837.742467] [ 103] 0 103 10322 1072 75776 139 0 systemd-journal
Jan 15 09:11:55 raspi-4 kernel: [999837.742475] [ 133] 0 133 1139 2 12288 51 0 blkmapd
Jan 15 09:11:55 raspi-4 kernel: [999837.742484] [ 145] 0 145 4668 12 18432 212 -1000 systemd-udevd
Jan 15 09:11:55 raspi-4 kernel: [999837.742493] [ 404] 0 404 786 2 10240 55 0 rpc.idmapd
Jan 15 09:11:55 raspi-4 kernel: [999837.742501] [ 415] 103 415 1644 6 16384 81 0 rpcbind
Jan 15 09:11:55 raspi-4 kernel: [999837.742510] [ 422] 0 422 1979 50 12288 172 0 dhclient
Jan 15 09:11:55 raspi-4 kernel: [999837.742518] [ 424] 106 424 1639 58 14336 54 0 avahi-daemon
Jan 15 09:11:55 raspi-4 kernel: [999837.742527] [ 425] 105 425 1800 73 16384 59 -900 dbus-daemon
Jan 15 09:11:55 raspi-4 kernel: [999837.742535] [ 430] 65534 430 1243 5 14336 48 0 thd
Jan 15 09:11:55 raspi-4 kernel: [999837.742543] [ 431] 0 431 6465 164 26624 149 0 rsyslogd
Jan 15 09:11:55 raspi-4 kernel: [999837.742552] [ 432] 0 432 3371 30 20480 148 0 systemd-logind
Jan 15 09:11:55 raspi-4 kernel: [999837.742560] [ 433] 0 433 2686 12 18432 89 0 wpa_supplicant
Jan 15 09:11:55 raspi-4 kernel: [999837.742568] [ 437] 0 437 2944 11 20480 143 0 alsactl
Jan 15 09:11:55 raspi-4 kernel: [999837.742576] [ 467] 0 467 470 10 10240 9 0 susvd
Jan 15 09:11:55 raspi-4 kernel: [999837.742584] [ 486] 106 486 1606 4 12288 71 0 avahi-daemon
Jan 15 09:11:55 raspi-4 kernel: [999837.742592] [ 487] 0 487 2838 19 20480 122 0 wpa_supplicant
Jan 15 09:11:55 raspi-4 kernel: [999837.742601] [ 616] 107 616 2181 32 16384 88 0 ntpd
Jan 15 09:11:55 raspi-4 kernel: [999837.742609] [ 618] 0 618 2733 31 20480 118 -1000 sshd
Jan 15 09:11:55 raspi-4 kernel: [999837.742617] [ 625] 0 625 7908 0 40960 6245 0 rpc.mountd
Jan 15 09:11:55 raspi-4 kernel: [999837.742627] [ 717] 0 717 2074 16 16384 40 0 cron
Jan 15 09:11:55 raspi-4 kernel: [999837.742635] [ 733] 0 733 1164 0 14336 33 0 agetty
Jan 15 09:11:55 raspi-4 kernel: [999837.742644] [ 751] 65534 751 674 26 12288 16 0 noip2
Jan 15 09:11:55 raspi-4 kernel: [999837.742652] [ 808] 0 808 31711 93 49152 222 0 pilight-daemon
Jan 15 09:11:55 raspi-4 kernel: [999837.742660] [ 1052] 113 1052 3677 14 22528 149 0 exim4
Jan 15 09:11:55 raspi-4 kernel: [999837.742669] [ 17283] 0 17283 9578 79 30720 94 0 polkitd
Jan 15 09:11:55 raspi-4 kernel: [999837.742677] [ 24137] 999 24137 29815 18431 126976 5271 0 perl
Jan 15 09:11:55 raspi-4 kernel: [999837.742685] [ 24151] 999 24151 45242 5377 92160 2416 0 python3
Jan 15 09:11:55 raspi-4 kernel: [999837.742693] [ 24153] 999 24153 51773 14631 409600 1268 0 node
Jan 15 09:11:55 raspi-4 kernel: [999837.742721] [ 24174] 999 24174 38049 0 147456 1545 0 node
Jan 15 09:11:55 raspi-4 kernel: [999837.742729] [ 13213] 109 13213 181342 33695 247808 0 0 mysqld
Jan 15 09:11:55 raspi-4 kernel: [999837.742741] [ 23579] 0 23579 3093 196 20480 0 0 sshd
Jan 15 09:11:55 raspi-4 kernel: [999837.742749] [ 23580] 104 23580 2766 157 18432 0 0 sshd
Jan 15 09:11:55 raspi-4 kernel: [999837.742757] [ 23582] 999 23582 29654 21782 126976 1766 0 perl
Jan 15 09:11:55 raspi-4 kernel: [999837.742765] [ 23583] 999 23583 29737 20917 126976 2714 0 perl
Jan 15 09:11:55 raspi-4 kernel: [999837.742774] [ 23584] 999 23584 29654 21819 126976 1729 0 perl
Jan 15 09:11:55 raspi-4 kernel: [999837.742782] [ 23585] 999 23585 29737 20219 126976 3412 0 perl
Jan 15 09:11:55 raspi-4 kernel: [999837.742790] [ 23586] 999 23586 29814 19705 126976 3996 0 perl
Jan 15 09:11:55 raspi-4 kernel: [999837.742799] [ 23587] 999 23587 29737 20634 126976 2997 0 perl
Jan 15 09:11:55 raspi-4 kernel: [999837.742807] [ 23591] 999 23591 29737 21440 126976 2191 0 perl
Jan 15 09:11:55 raspi-4 kernel: [999837.742815] [ 23593] 999 23593 29815 18460 126976 5242 0 perl
Jan 15 09:11:55 raspi-4 kernel: [999837.742823] [ 23594] 999 23594 29815 18501 126976 5201 0 perl
Jan 15 09:11:55 raspi-4 kernel: [999837.742832] [ 23595] 999 23595 29815 18564 126976 5154 0 perl
Jan 15 09:11:55 raspi-4 kernel: [999837.742840] [ 23596] 999 23596 29815 19040 126976 4662 0 perl
Jan 15 09:11:55 raspi-4 kernel: [999837.742848] [ 23597] 999 23597 29879 18534 126976 5208 0 perl
Jan 15 09:11:55 raspi-4 kernel: [999837.742856] [ 23598] 0 23598 2733 34 16384 115 -1000 sshd
Jan 15 09:11:55 raspi-4 kernel: [999837.742862] oom-kill:constraint=CONSTRAINT_NONE,nodemask=(null),cpuset=/,mems_allowed=0,global_oom,task_memcg=/,task=mysqld,pid=13213,uid=109
Jan 15 09:11:55 raspi-4 kernel: [999837.742993] Out of memory: Killed process 13213 (mysqld) total-vm:725368kB, anon-rss:134780kB, file-rss:0kB, shmem-rss:0kB, UID:109 pgtables:242kB oom_score_adj:0
Jan 15 09:11:55 raspi-4 kernel: [999837.793664] oom_reaper: reaped process 13213 (mysqld), now anon-rss:0kB, file-rss:0kB, shmem-rss:0kB
Jan 15 09:12:01 raspi-4 kernel: [999844.792174] perl invoked oom-killer: gfp_mask=0x100cca(GFP_HIGHUSER_MOVABLE), order=0, oom_score_adj=0
Jan 15 09:12:06 raspi-4 kernel: [999844.792191] CPU: 0 PID: 23582 Comm: perl Tainted: G C 5.4.79-v7+ #1373
Jan 15 09:12:06 raspi-4 kernel: [999844.792194] Hardware name: BCM2835
Jan 15 09:12:06 raspi-4 kernel: [999844.792197] Backtrace:
Jan 15 09:12:06 raspi-4 kernel: [999844.792213] [<8010dfa4>] (dump_backtrace) from [<8010e324>] (show_stack+0x20/0x24)
Jan 15 09:12:06 raspi-4 kernel: [999844.792220] r7:ffffffff r6:00000000 r5:60000113 r4:80e97f70
Jan 15 09:12:06 raspi-4 kernel: [999844.792230] [<8010e304>] (show_stack) from [<80901174>] (dump_stack+0xd8/0x11c)
Jan 15 09:12:06 raspi-4 kernel: [999844.792239] [<8090109c>] (dump_stack) from [<8028ceac>] (dump_header+0x64/0x1fc)
Jan 15 09:12:06 raspi-4 kernel: [999844.792246] r10:00000000 r9:80e05084 r8:80e056a4 r7:80bbc1a0 r6:a9540000 r5:b3e2db80
Jan 15 09:12:06 raspi-4 kernel: [999844.792250] r4:95f79d40 r3:fa9b2146
Jan 15 09:12:06 raspi-4 kernel: [999844.792256] [<8028ce48>] (dump_header) from [<8028c268>] (oom_kill_process+0x178/0x184)
Jan 15 09:12:06 raspi-4 kernel: [999844.792262] r7:80bbc1a0 r6:95f79d40 r5:00000558 r4:b3e2db80
Jan 15 09:12:06 raspi-4 kernel: [999844.792268] [<8028c0f0>] (oom_kill_process) from [<8028ccfc>] (out_of_memory+0x27c/0x33c)
Jan 15 09:12:06 raspi-4 kernel: [999844.792274] r7:80e08200 r6:95f79d40 r5:80e04f88 r4:b3e2db80
Jan 15 09:12:06 raspi-4 kernel: [999844.792282] [<8028ca80>] (out_of_memory) from [<802d87d4>] (__alloc_pages_nodemask+0xc00/0x1270)
Jan 15 09:12:06 raspi-4 kernel: [999844.792287] r7:00000000 r6:00000000 r5:00400040 r4:95f78000
Jan 15 09:12:06 raspi-4 kernel: [999844.792297] [<802d7bd4>] (__alloc_pages_nodemask) from [<80286854>] (pagecache_get_page+0x140/0x37c)
Jan 15 09:12:06 raspi-4 kernel: [999844.792304] r10:00000017 r9:80e058fc r8:b58ac1c0 r7:00100cca r6:80e04f88 r5:00000044
Jan 15 09:12:06 raspi-4 kernel: [999844.792306] r4:14f40003
Jan 15 09:12:06 raspi-4 kernel: [999844.792315] [<80286714>] (pagecache_get_page) from [<80287118>] (filemap_fault+0x688/0xa9c)
Jan 15 09:12:06 raspi-4 kernel: [999844.792321] r10:00000017 r9:b58ac1c0 r8:80e04f88 r7:95f79ebc r6:b5572cc0 r5:b5572cc0
Jan 15 09:12:06 raspi-4 kernel: [999844.792324] r4:b58ac0b0
Jan 15 09:12:06 raspi-4 kernel: [999844.792334] [<80286a90>] (filemap_fault) from [<803e2788>] (ext4_filemap_fault+0x38/0x4c)
Jan 15 09:12:06 raspi-4 kernel: [999844.792341] r10:80283d2c r9:95f79ebc r8:00000040 r7:76d39000 r6:b3115240 r5:95f79ebc
Jan 15 09:12:06 raspi-4 kernel: [999844.792344] r4:b58ac098
Jan 15 09:12:06 raspi-4 kernel: [999844.792352] [<803e2750>] (ext4_filemap_fault) from [<802bd358>] (__do_fault+0x5c/0x178)
Jan 15 09:12:06 raspi-4 kernel: [999844.792355] r5:95f79ebc r4:80e04f88
Jan 15 09:12:06 raspi-4 kernel: [999844.792362] [<802bd2fc>] (__do_fault) from [<802c1e74>] (handle_mm_fault+0x8b4/0xc88)
Jan 15 09:12:06 raspi-4 kernel: [999844.792368] r8:00000040 r7:76d39000 r6:80e04f88 r5:00000d73 r4:0000001d
Jan 15 09:12:06 raspi-4 kernel: [999844.792375] [<802c15c0>] (handle_mm_fault) from [<809208c4>] (do_page_fault+0x150/0x380)
Jan 15 09:12:06 raspi-4 kernel: [999844.792382] r10:87379c40 r9:b3115240 r8:80000007 r7:76d39af0 r6:87379c00 r5:b4e7cc40
Jan 15 09:12:06 raspi-4 kernel: [999844.792385] r4:95f79fb0
Jan 15 09:12:06 raspi-4 kernel: [999844.792392] [<80920774>] (do_page_fault) from [<80115fc0>] (do_PrefetchAbort+0x48/0x9c)
Jan 15 09:12:06 raspi-4 kernel: [999844.792398] r10:00000000 r9:00000020 r8:95f79fb0 r7:76d39af0 r6:80920774 r5:00000007
Jan 15 09:12:06 raspi-4 kernel: [999844.792401] r4:80e0aa84
Jan 15 09:12:06 raspi-4 kernel: [999844.792408] [<80115f78>] (do_PrefetchAbort) from [<80101fa4>] (ret_from_exception+0x0/0x1c)
Jan 15 09:12:06 raspi-4 kernel: [999844.792412] Exception stack(0x95f79fb0 to 0x95f79ff8)
Jan 15 09:12:06 raspi-4 kernel: [999844.792417] 9fa0: ffffffe0 00000021 00000000 000da61c
Jan 15 09:12:06 raspi-4 kernel: [999844.792424] 9fc0: 76e6c000 76e6c7d4 002d4ee8 00000000 03766468 00000020 00000000 0000018d
Jan 15 09:12:06 raspi-4 kernel: [999844.792429] 9fe0: 002d5080 7ec37948 76d91a18 76d39af0 20000010 ffffffff
Jan 15 09:12:06 raspi-4 kernel: [999844.792435] r8:10c5387d r7:10c5383d r6:ffffffff r5:20000010 r4:76d39af0
Jan 15 09:12:06 raspi-4 kernel: [999844.792445] Mem-Info:
Jan 15 09:12:06 raspi-4 kernel: [999844.792459] active_anon:110338 inactive_anon:110395 isolated_anon:0
Jan 15 09:12:06 raspi-4 kernel: [999844.792459] active_file:97 inactive_file:203 isolated_file:0
Jan 15 09:12:06 raspi-4 kernel: [999844.792459] unevictable:0 dirty:0 writeback:0 unstable:0
Jan 15 09:12:06 raspi-4 kernel: [999844.792459] slab_reclaimable:2958 slab_unreclaimable:3843
Jan 15 09:12:06 raspi-4 kernel: [999844.792459] mapped:992 shmem:5550 pagetables:1412 bounce:0
Jan 15 09:12:06 raspi-4 kernel: [999844.792459] free:4078 free_pcp:272 free_cma:379
Jan 15 09:12:06 raspi-4 kernel: [999844.792469] Node 0 active_anon:441352kB inactive_anon:441580kB active_file:388kB inactive_file:812kB unevictable:0kB isolated(anon):0kB isolated(file):0kB mapped:3968kB dirty:0kB writeback:0kB shmem:22200kB writeback_tmp:0kB unstable:0kB all_unreclaimable? yes
Jan 15 09:12:06 raspi-4 kernel: [999844.792483] Normal free:16312kB min:16384kB low:20480kB high:24576kB active_anon:441352kB inactive_anon:441580kB active_file:388kB inactive_file:812kB unevictable:0kB writepending:0kB present:970752kB managed:947036kB mlocked:0kB kernel_stack:1480kB pagetables:5648kB bounce:0kB free_pcp:1088kB local_pcp:224kB free_cma:1516kB
Jan 15 09:12:06 raspi-4 kernel: [999844.792487] lowmem_reserve[]: 0 0
Jan 15 09:12:06 raspi-4 kernel: [999844.792504] Normal: 406*4kB (UEC) 494*8kB (UMEC) 285*16kB (UMEC) 107*32kB (UMEC) 35*64kB (UE) 4*128kB (U) 0*256kB 0*512kB 0*1024kB 0*2048kB 0*4096kB = 16312kB
Jan 15 09:12:06 raspi-4 kernel: [999844.792545] 13214 total pagecache pages
Jan 15 09:12:06 raspi-4 kernel: [999844.792552] 7347 pages in swap cache
Jan 15 09:12:06 raspi-4 kernel: [999844.792558] Swap cache stats: add 107068, delete 99721, find 1095789/1110090
Jan 15 09:12:06 raspi-4 kernel: [999844.792562] Free swap = 0kB
Jan 15 09:12:06 raspi-4 kernel: [999844.792567] Total swap = 102396kB
Jan 15 09:12:06 raspi-4 kernel: [999844.792571] 242688 pages RAM
Jan 15 09:12:06 raspi-4 kernel: [999844.792576] 0 pages HighMem/MovableOnly
Jan 15 09:12:06 raspi-4 kernel: [999844.792581] 5929 pages reserved
Jan 15 09:12:06 raspi-4 kernel: [999844.792585] 16384 pages cma reserved
Jan 15 09:12:06 raspi-4 kernel: [999844.792590] Tasks state (memory values in pages):
Jan 15 09:12:06 raspi-4 kernel: [999844.792595] [ pid ] uid tgid total_vm rss pgtables_bytes swapents oom_score_adj name
Jan 15 09:12:06 raspi-4 kernel: [999844.792615] [ 103] 0 103 10322 1040 75776 137 0 systemd-journal
Jan 15 09:12:06 raspi-4 kernel: [999844.792623] [ 133] 0 133 1139 2 12288 51 0 blkmapd
Jan 15 09:12:06 raspi-4 kernel: [999844.792640] [ 145] 0 145 4668 12 18432 212 -1000 systemd-udevd
Jan 15 09:12:06 raspi-4 kernel: [999844.792651] [ 404] 0 404 786 2 10240 55 0 rpc.idmapd
Jan 15 09:12:06 raspi-4 kernel: [999844.792659] [ 415] 103 415 1644 6 16384 81 0 rpcbind
Jan 15 09:12:06 raspi-4 kernel: [999844.792667] [ 422] 0 422 1979 50 12288 172 0 dhclient
Jan 15 09:12:06 raspi-4 kernel: [999844.792675] [ 424] 106 424 1639 58 14336 54 0 avahi-daemon
Jan 15 09:12:06 raspi-4 kernel: [999844.792684] [ 425] 105 425 1800 75 16384 59 -900 dbus-daemon
Jan 15 09:12:06 raspi-4 kernel: [999844.792694] [ 430] 65534 430 1243 5 14336 48 0 thd
Jan 15 09:12:06 raspi-4 kernel: [999844.792702] [ 431] 0 431 6465 182 26624 133 0 rsyslogd
Jan 15 09:12:06 raspi-4 kernel: [999844.792710] [ 432] 0 432 3371 30 20480 148 0 systemd-logind
Jan 15 09:12:06 raspi-4 kernel: [999844.792718] [ 433] 0 433 2686 12 18432 89 0 wpa_supplicant
Jan 15 09:12:06 raspi-4 kernel: [999844.792726] [ 437] 0 437 2944 11 20480 143 0 alsactl
Jan 15 09:12:06 raspi-4 kernel: [999844.792734] [ 467] 0 467 470 10 10240 9 0 susvd
Jan 15 09:12:06 raspi-4 kernel: [999844.792742] [ 486] 106 486 1606 4 12288 71 0 avahi-daemon
Jan 15 09:12:06 raspi-4 kernel: [999844.792750] [ 487] 0 487 2838 19 20480 122 0 wpa_supplicant
Jan 15 09:12:06 raspi-4 kernel: [999844.792758] [ 616] 107 616 2181 32 16384 88 0 ntpd
Jan 15 09:12:06 raspi-4 kernel: [999844.792767] [ 618] 0 618 2733 31 20480 118 -1000 sshd
Jan 15 09:12:06 raspi-4 kernel: [999844.792775] [ 625] 0 625 7908 0 40960 6245 0 rpc.mountd
Jan 15 09:12:06 raspi-4 kernel: [999844.792785] [ 717] 0 717 2074 14 16384 40 0 cron
Jan 15 09:12:06 raspi-4 kernel: [999844.792793] [ 733] 0 733 1164 0 14336 33 0 agetty
Jan 15 09:12:06 raspi-4 kernel: [999844.792801] [ 751] 65534 751 674 26 12288 16 0 noip2
Jan 15 09:12:06 raspi-4 kernel: [999844.792809] [ 808] 0 808 31711 93 49152 222 0 pilight-daemon
Jan 15 09:12:06 raspi-4 kernel: [999844.792817] [ 1052] 113 1052 3677 14 22528 149 0 exim4
Jan 15 09:12:06 raspi-4 kernel: [999844.792825] [ 17283] 0 17283 9578 79 30720 94 0 polkitd
Jan 15 09:12:06 raspi-4 kernel: [999844.792833] [ 24137] 999 24137 29815 18399 126976 5303 0 perl
Jan 15 09:12:06 raspi-4 kernel: [999844.792841] [ 24151] 999 24151 45242 5388 92160 2405 0 python3
Jan 15 09:12:06 raspi-4 kernel: [999844.792849] [ 24153] 999 24153 51773 14631 409600 1268 0 node
Jan 15 09:12:06 raspi-4 kernel: [999844.792858] [ 24174] 999 24174 38049 0 147456 1545 0 node
Jan 15 09:12:06 raspi-4 kernel: [999844.792869] [ 23582] 999 23582 29654 21937 126976 1611 0 perl
Jan 15 09:12:06 raspi-4 kernel: [999844.792877] [ 23583] 999 23583 29737 21047 126976 2584 0 perl
Jan 15 09:12:06 raspi-4 kernel: [999844.792885] [ 23584] 999 23584 29654 21937 126976 1611 0 perl
Jan 15 09:12:06 raspi-4 kernel: [999844.792893] [ 23585] 999 23585 29737 21037 126976 2594 0 perl
Jan 15 09:12:06 raspi-4 kernel: [999844.792901] [ 23586] 999 23586 29737 20629 126976 3002 0 perl
Jan 15 09:12:06 raspi-4 kernel: [999844.792909] [ 23587] 999 23587 29737 21046 126976 2585 0 perl
Jan 15 09:12:06 raspi-4 kernel: [999844.792917] [ 23591] 999 23591 29654 21934 126976 1614 0 perl
Jan 15 09:12:06 raspi-4 kernel: [999844.792925] [ 23593] 999 23593 30340 18923 129024 5274 0 perl
Jan 15 09:12:06 raspi-4 kernel: [999844.792933] [ 23594] 999 23594 29814 19342 126976 4359 0 perl
Jan 15 09:12:06 raspi-4 kernel: [999844.792941] [ 23595] 999 23595 29814 19286 126976 4415 0 perl
Jan 15 09:12:06 raspi-4 kernel: [999844.792949] [ 23596] 999 23596 29737 20377 126976 3254 0 perl
Jan 15 09:12:06 raspi-4 kernel: [999844.792957] [ 23597] 999 23597 29878 19325 126976 4416 0 perl
Jan 15 09:12:06 raspi-4 kernel: [999844.792965] [ 23598] 0 23598 2649 135 18432 0 0 sshd
Jan 15 09:12:06 raspi-4 kernel: [999844.792971] oom-kill:constraint=CONSTRAINT_NONE,nodemask=(null),cpuset=/,mems_allowed=0,global_oom,task_memcg=/,task=perl,pid=23593,uid=999
Jan 15 09:12:06 raspi-4 kernel: [999844.793013] Out of memory: Killed process 23593 (perl) total-vm:121360kB, anon-rss:75692kB, file-rss:0kB, shmem-rss:0kB, UID:999 pgtables:126kB oom_score_adj:0
Jan 15 09:12:06 raspi-4 kernel: [999844.812062] oom_reaper: reaped process 23593 (perl), now anon-rss:0kB, file-rss:0kB, shmem-rss:0kB
Jan 15 09:12:06 raspi-4 kernel: [999849.745004] systemd-udevd invoked oom-killer: gfp_mask=0x100cca(GFP_HIGHUSER_MOVABLE), order=0, oom_score_adj=-1000
Jan 15 09:12:06 raspi-4 kernel: [999849.745017] CPU: 0 PID: 145 Comm: systemd-udevd Tainted: G C 5.4.79-v7+ #1373
Jan 15 09:12:06 raspi-4 kernel: [999849.745019] Hardware name: BCM2835
Jan 15 09:12:06 raspi-4 kernel: [999849.745023] Backtrace:
Jan 15 09:12:06 raspi-4 kernel: [999849.745039] [<8010dfa4>] (dump_backtrace) from [<8010e324>] (show_stack+0x20/0x24)
Jan 15 09:12:06 raspi-4 kernel: [999849.745045] r7:ffffffff r6:00000000 r5:60000113 r4:80e97f70
Jan 15 09:12:06 raspi-4 kernel: [999849.745055] [<8010e304>] (show_stack) from [<80901174>] (dump_stack+0xd8/0x11c)
Jan 15 09:12:06 raspi-4 kernel: [999849.745065] [<8090109c>] (dump_stack) from [<8028ceac>] (dump_header+0x64/0x1fc)
Jan 15 09:12:06 raspi-4 kernel: [999849.745072] r10:00000000 r9:80e05084 r8:80e056a4 r7:80bbc1a0 r6:b3e11b00 r5:b23d5b80
Jan 15 09:12:06 raspi-4 kernel: [999849.745075] r4:b30ebd40 r3:fa9b2146
Jan 15 09:12:06 raspi-4 kernel: [999849.745082] [<8028ce48>] (dump_header) from [<8028c268>] (oom_kill_process+0x178/0x184)
Jan 15 09:12:06 raspi-4 kernel: [999849.745088] r7:80bbc1a0 r6:b30ebd40 r5:00000558 r4:b23d5b80
Jan 15 09:12:06 raspi-4 kernel: [999849.745094] [<8028c0f0>] (oom_kill_process) from [<8028ccfc>] (out_of_memory+0x27c/0x33c)
Jan 15 09:12:06 raspi-4 kernel: [999849.745099] r7:80e08200 r6:b30ebd40 r5:80e04f88 r4:b23d5b80
Jan 15 09:12:06 raspi-4 kernel: [999849.745108] [<8028ca80>] (out_of_memory) from [<802d87d4>] (__alloc_pages_nodemask+0xc00/0x1270)
Jan 15 09:12:06 raspi-4 kernel: [999849.745113] r7:00000000 r6:00000000 r5:00400100 r4:b30ea000
Jan 15 09:12:06 raspi-4 kernel: [999849.745123] [<802d7bd4>] (__alloc_pages_nodemask) from [<80286854>] (pagecache_get_page+0x140/0x37c)
Jan 15 09:12:06 raspi-4 kernel: [999849.745129] r10:00000006 r9:80e058fc r8:b59c1f20 r7:00100cca r6:80e04f88 r5:00000044
Jan 15 09:12:06 raspi-4 kernel: [999849.745132] r4:36380001
Jan 15 09:12:06 raspi-4 kernel: [999849.745140] [<80286714>] (pagecache_get_page) from [<80287118>] (filemap_fault+0x688/0xa9c)
Jan 15 09:12:06 raspi-4 kernel: [999849.745147] r10:00000006 r9:b59c1f20 r8:80e04f88 r7:b30ebebc r6:b3e75540 r5:b3e75540
Jan 15 09:12:06 raspi-4 kernel: [999849.745150] r4:b59c1e10
Jan 15 09:12:06 raspi-4 kernel: [999849.745160] [<80286a90>] (filemap_fault) from [<803e2788>] (ext4_filemap_fault+0x38/0x4c)
Jan 15 09:12:06 raspi-4 kernel: [999849.745167] r10:80283d2c r9:b30ebebc r8:00000040 r7:004c5000 r6:b3f9a840 r5:b30ebebc
Jan 15 09:12:06 raspi-4 kernel: [999849.745169] r4:b59c1df8
Jan 15 09:12:06 raspi-4 kernel: [999849.745178] [<803e2750>] (ext4_filemap_fault) from [<802bd358>] (__do_fault+0x5c/0x178)
Jan 15 09:12:06 raspi-4 kernel: [999849.745181] r5:b30ebebc r4:80e04f88
Jan 15 09:12:06 raspi-4 kernel: [999849.745188] [<802bd2fc>] (__do_fault) from [<802c1e74>] (handle_mm_fault+0x8b4/0xc88)
Jan 15 09:12:06 raspi-4 kernel: [999849.745194] r8:00000040 r7:004c5000 r6:80e04f88 r5:00000d73 r4:00000000
Jan 15 09:12:06 raspi-4 kernel: [999849.745201] [<802c15c0>] (handle_mm_fault) from [<809208c4>] (do_page_fault+0x150/0x380)
Jan 15 09:12:06 raspi-4 kernel: [999849.745208] r10:b4d90640 r9:b3f9a840 r8:80000007 r7:004c5b70 r6:b4d90600 r5:b4d74c40
Jan 15 09:12:06 raspi-4 kernel: [999849.745211] r4:b30ebfb0
Jan 15 09:12:06 raspi-4 kernel: [999849.745218] [<80920774>] (do_page_fault) from [<80115fc0>] (do_PrefetchAbort+0x48/0x9c)
Jan 15 09:12:06 raspi-4 kernel: [999849.745225] r10:7ef73918 r9:7ef73918 r8:b30ebfb0 r7:004c5b70 r6:80920774 r5:00000007
Jan 15 09:12:06 raspi-4 kernel: [999849.745228] r4:80e0aa84
Jan 15 09:12:06 raspi-4 kernel: [999849.745235] [<80115f78>] (do_PrefetchAbort) from [<80101fa4>] (ret_from_exception+0x0/0x1c)
Jan 15 09:12:06 raspi-4 kernel: [999849.745239] Exception stack(0xb30ebfb0 to 0xb30ebff8)
Jan 15 09:12:06 raspi-4 kernel: [999849.745244] bfa0: 0000000b 7ef738f0 00000008 00000000
Jan 15 09:12:06 raspi-4 kernel: [999849.745250] bfc0: 76f3c968 00000000 76f3c968 0000000b 00000000 7ef73918 7ef73918 7ef73a94
Jan 15 09:12:06 raspi-4 kernel: [999849.745256] bfe0: 00558b54 7ef738e8 004d7474 004c5b70 60000010 ffffffff
Jan 15 09:12:06 raspi-4 kernel: [999849.745262] r8:10c5387d r7:10c5383d r6:ffffffff r5:60000010 r4:004c5b70
Jan 15 09:12:06 raspi-4 kernel: [999849.745303] Mem-Info:
Jan 15 09:12:06 raspi-4 kernel: [999849.745317] active_anon:110388 inactive_anon:110414 isolated_anon:0
Jan 15 09:12:06 raspi-4 kernel: [999849.745317] active_file:113 inactive_file:348 isolated_file:0
Jan 15 09:12:06 raspi-4 kernel: [999849.745317] unevictable:0 dirty:0 writeback:0 unstable:0
Jan 15 09:12:06 raspi-4 kernel: [999849.745317] slab_reclaimable:2958 slab_unreclaimable:3844
Jan 15 09:12:06 raspi-4 kernel: [999849.745317] mapped:1008 shmem:5550 pagetables:1349 bounce:0
Jan 15 09:12:06 raspi-4 kernel: [999849.745317] free:4042 free_pcp:225 free_cma:375
Jan 15 09:12:06 raspi-4 kernel: [999849.745328] Node 0 active_anon:441552kB inactive_anon:441656kB active_file:452kB inactive_file:1392kB unevictable:0kB isolated(anon):0kB isolated(file):128kB mapped:4032kB dirty:0kB writeback:0kB shmem:22200kB writeback_tmp:0kB unstable:0kB all_unreclaimable? yes
Jan 15 09:12:06 raspi-4 kernel: [999849.745342] Normal free:16168kB min:16384kB low:20480kB high:24576kB active_anon:441552kB inactive_anon:441656kB active_file:52kB inactive_file:1444kB unevictable:0kB writepending:0kB present:970752kB managed:947036kB mlocked:0kB kernel_stack:1472kB pagetables:5396kB bounce:0kB free_pcp:900kB local_pcp:316kB free_cma:1500kB
Jan 15 09:12:06 raspi-4 kernel: [999849.745347] lowmem_reserve[]: 0 0
Jan 15 09:12:06 raspi-4 kernel: [999849.745356] Normal: 367*4kB (UEC) 479*8kB (UEC) 274*16kB (UEC) 107*32kB (UEC) 37*64kB (UE) 4*128kB (U) 0*256kB 0*512kB 0*1024kB 0*2048kB 0*4096kB = 15988kB
Jan 15 09:12:06 raspi-4 kernel: [999849.745411] 12035 total pagecache pages
Jan 15 09:12:06 raspi-4 kernel: [999849.745418] 5963 pages in swap cache
Jan 15 09:12:06 raspi-4 kernel: [999849.745424] Swap cache stats: add 107088, delete 101125, find 1097088/1111397
Jan 15 09:12:06 raspi-4 kernel: [999849.745428] Free swap = 0kB
Jan 15 09:12:06 raspi-4 kernel: [999849.745433] Total swap = 102396kB
Jan 15 09:12:06 raspi-4 kernel: [999849.745438] 242688 pages RAM
Jan 15 09:12:06 raspi-4 kernel: [999849.745442] 0 pages HighMem/MovableOnly
Jan 15 09:12:06 raspi-4 kernel: [999849.745447] 5929 pages reserved
Jan 15 09:12:06 raspi-4 kernel: [999849.745451] 16384 pages cma reserved
Jan 15 09:12:06 raspi-4 kernel: [999849.745456] Tasks state (memory values in pages):
Jan 15 09:12:06 raspi-4 kernel: [999849.745461] [ pid ] uid tgid total_vm rss pgtables_bytes swapents oom_score_adj name
Jan 15 09:12:06 raspi-4 kernel: [999849.745483] [ 103] 0 103 10322 1041 75776 137 0 systemd-journal
Jan 15 09:12:06 raspi-4 kernel: [999849.745498] [ 133] 0 133 1139 2 12288 51 0 blkmapd
Jan 15 09:12:06 raspi-4 kernel: [999849.745506] [ 145] 0 145 4668 12 18432 212 -1000 systemd-udevd
Jan 15 09:12:06 raspi-4 kernel: [999849.745517] [ 404] 0 404 786 2 10240 55 0 rpc.idmapd
Jan 15 09:12:06 raspi-4 kernel: [999849.745525] [ 415] 103 415 1644 6 16384 81 0 rpcbind
Jan 15 09:12:06 raspi-4 kernel: [999849.745533] [ 422] 0 422 1979 50 12288 172 0 dhclient
Jan 15 09:12:06 raspi-4 kernel: [999849.745542] [ 424] 106 424 1639 58 14336 54 0 avahi-daemon
Jan 15 09:12:06 raspi-4 kernel: [999849.745550] [ 425] 105 425 1800 75 16384 59 -900 dbus-daemon
Jan 15 09:12:06 raspi-4 kernel: [999849.745559] [ 430] 65534 430 1243 5 14336 48 0 thd
Jan 15 09:12:06 raspi-4 kernel: [999849.745567] [ 431] 0 431 6465 194 26624 121 0 rsyslogd
Jan 15 09:12:06 raspi-4 kernel: [999849.745575] [ 432] 0 432 3371 30 20480 148 0 systemd-logind
Jan 15 09:12:06 raspi-4 kernel: [999849.745584] [ 433] 0 433 2686 12 18432 89 0 wpa_supplicant
Jan 15 09:12:06 raspi-4 kernel: [999849.745592] [ 437] 0 437 2944 11 20480 143 0 alsactl
Jan 15 09:12:06 raspi-4 kernel: [999849.745600] [ 467] 0 467 470 10 10240 9 0 susvd
Jan 15 09:12:06 raspi-4 kernel: [999849.745609] [ 486] 106 486 1606 4 12288 71 0 avahi-daemon
Jan 15 09:12:06 raspi-4 kernel: [999849.745616] [ 487] 0 487 2838 19 20480 122 0 wpa_supplicant
Jan 15 09:12:06 raspi-4 kernel: [999849.745624] [ 616] 107 616 2181 32 16384 88 0 ntpd
Jan 15 09:12:06 raspi-4 kernel: [999849.745642] [ 618] 0 618 2733 31 20480 118 -1000 sshd
Jan 15 09:12:06 raspi-4 kernel: [999849.745651] [ 625] 0 625 7908 0 40960 6245 0 rpc.mountd
Jan 15 09:12:06 raspi-4 kernel: [999849.745661] [ 717] 0 717 2074 14 16384 40 0 cron
Jan 15 09:12:06 raspi-4 kernel: [999849.745669] [ 733] 0 733 1164 0 14336 33 0 agetty
Jan 15 09:12:06 raspi-4 kernel: [999849.745678] [ 751] 65534 751 674 26 12288 16 0 noip2
Jan 15 09:12:06 raspi-4 kernel: [999849.745687] [ 808] 0 808 31711 93 49152 222 0 pilight-daemon
Jan 15 09:12:06 raspi-4 kernel: [999849.745695] [ 1052] 113 1052 3677 14 22528 149 0 exim4
Jan 15 09:12:06 raspi-4 kernel: [999849.745705] [ 17283] 0 17283 9578 79 30720 94 0 polkitd
Jan 15 09:12:06 raspi-4 kernel: [999849.745713] [ 24137] 999 24137 29815 18395 126976 5307 0 perl
Jan 15 09:12:06 raspi-4 kernel: [999849.745722] [ 24151] 999 24151 45242 5388 92160 2405 0 python3
Jan 15 09:12:06 raspi-4 kernel: [999849.745730] [ 24153] 999 24153 51773 14631 409600 1268 0 node
Jan 15 09:12:06 raspi-4 kernel: [999849.745738] [ 24174] 999 24174 38049 0 147456 1545 0 node
Jan 15 09:12:06 raspi-4 kernel: [999849.745751] [ 23582] 999 23582 29654 21939 126976 1609 0 perl
Jan 15 09:12:06 raspi-4 kernel: [999849.745759] [ 23583] 999 23583 29737 21080 126976 2551 0 perl
Jan 15 09:12:06 raspi-4 kernel: [999849.745769] [ 23584] 999 23584 29654 21935 126976 1613 0 perl
Jan 15 09:12:06 raspi-4 kernel: [999849.745777] [ 23585] 999 23585 29737 21051 126976 2580 0 perl
Jan 15 09:12:06 raspi-4 kernel: [999849.745785] [ 23586] 999 23586 29737 20914 126976 2717 0 perl
Jan 15 09:12:06 raspi-4 kernel: [999849.745793] [ 23587] 999 23587 29737 21065 126976 2566 0 perl
Jan 15 09:12:06 raspi-4 kernel: [999849.745801] [ 23591] 999 23591 29654 21941 126976 1607 0 perl
Jan 15 09:12:06 raspi-4 kernel: [999849.745809] [ 23594] 999 23594 29814 19477 126976 4224 0 perl
Jan 15 09:12:06 raspi-4 kernel: [999849.745817] [ 23595] 999 23595 29814 19464 126976 4237 0 perl
Jan 15 09:12:06 raspi-4 kernel: [999849.745826] [ 23596] 999 23596 29737 20422 126976 3209 0 perl
Jan 15 09:12:06 raspi-4 kernel: [999849.745834] [ 23597] 999 23597 29878 19897 126976 3844 0 perl
Jan 15 09:12:06 raspi-4 kernel: [999849.745842] [ 23598] 0 23598 2649 135 18432 0 0 sshd
Jan 15 09:12:06 raspi-4 kernel: [999849.745848] oom-kill:constraint=CONSTRAINT_NONE,nodemask=(null),cpuset=/,mems_allowed=0,global_oom,task_memcg=/,task=perl,pid=23597,uid=999
Jan 15 09:12:06 raspi-4 kernel: [999849.745883] Out of memory: Killed process 23597 (perl) total-vm:119512kB, anon-rss:79588kB, file-rss:0kB, shmem-rss:0kB, UID:999 pgtables:124kB oom_score_adj:0
Jan 15 09:12:06 raspi-4 kernel: [999849.770899] oom_reaper: reaped process 23597 (perl), now anon-rss:0kB, file-rss:0kB, shmem-rss:0kB
Jan 15 09:12:18 raspi-4 kernel: [999862.129506] perl invoked oom-killer: gfp_mask=0x100cca(GFP_HIGHUSER_MOVABLE), order=0, oom_score_adj=0
Jan 15 09:12:19 raspi-4 kernel: [999862.129524] CPU: 2 PID: 23583 Comm: perl Tainted: G C 5.4.79-v7+ #1373
Jan 15 09:12:19 raspi-4 kernel: [999862.129528] Hardware name: BCM2835
Jan 15 09:12:19 raspi-4 kernel: [999862.129533] Backtrace:
Jan 15 09:12:19 raspi-4 kernel: [999862.129554] [<8010dfa4>] (dump_backtrace) from [<8010e324>] (show_stack+0x20/0x24)
Jan 15 09:12:19 raspi-4 kernel: [999862.129564] r7:ffffffff r6:00000000 r5:60000113 r4:80e97f70
Jan 15 09:12:19 raspi-4 kernel: [999862.129578] [<8010e304>] (show_stack) from [<80901174>] (dump_stack+0xd8/0x11c)
Jan 15 09:12:19 raspi-4 kernel: [999862.129591] [<8090109c>] (dump_stack) from [<8028ceac>] (dump_header+0x64/0x1fc)
Jan 15 09:12:19 raspi-4 kernel: [999862.129602] r10:00000000 r9:80e05084 r8:80e056a4 r7:80bbc1a0 r6:a9543c00 r5:b20fadc0
Jan 15 09:12:19 raspi-4 kernel: [999862.129607] r4:957f7d40 r3:fa9b2146
Jan 15 09:12:19 raspi-4 kernel: [999862.129618] [<8028ce48>] (dump_header) from [<8028c268>] (oom_kill_process+0x178/0x184)
Jan 15 09:12:19 raspi-4 kernel: [999862.129626] r7:80bbc1a0 r6:957f7d40 r5:00000558 r4:b20fadc0
Jan 15 09:12:19 raspi-4 kernel: [999862.129636] [<8028c0f0>] (oom_kill_process) from [<8028ccfc>] (out_of_memory+0x27c/0x33c)
Jan 15 09:12:19 raspi-4 kernel: [999862.129644] r7:80e08200 r6:957f7d40 r5:80e04f88 r4:b20fadc0
Jan 15 09:12:19 raspi-4 kernel: [999862.129656] [<8028ca80>] (out_of_memory) from [<802d87d4>] (__alloc_pages_nodemask+0xc00/0x1270)
Jan 15 09:12:19 raspi-4 kernel: [999862.129664] r7:00000000 r6:00000000 r5:00400040 r4:957f6000
Jan 15 09:12:19 raspi-4 kernel: [999862.129677] [<802d7bd4>] (__alloc_pages_nodemask) from [<80286854>] (pagecache_get_page+0x140/0x37c)
Jan 15 09:12:19 raspi-4 kernel: [999862.129688] r10:000000a8 r9:80e058fc r8:b1a61c30 r7:00100cca r6:80e04f88 r5:00000044
Jan 15 09:12:19 raspi-4 kernel: [999862.129692] r4:8e800003
Jan 15 09:12:19 raspi-4 kernel: [999862.129704] [<80286714>] (pagecache_get_page) from [<80287118>] (filemap_fault+0x688/0xa9c)
Jan 15 09:12:19 raspi-4 kernel: [999862.129715] r10:000000a8 r9:b1a61c30 r8:80e04f88 r7:957f7ebc r6:b5572480 r5:b5572480
Jan 15 09:12:19 raspi-4 kernel: [999862.129719] r4:b1a61b20
Jan 15 09:12:19 raspi-4 kernel: [999862.129734] [<80286a90>] (filemap_fault) from [<803e2788>] (ext4_filemap_fault+0x38/0x4c)
Jan 15 09:12:19 raspi-4 kernel: [999862.129744] r10:80283d2c r9:957f7ebc r8:00000040 r7:000b8000 r6:b3d89960 r5:957f7ebc
Jan 15 09:12:19 raspi-4 kernel: [999862.129748] r4:b1a61b08
Jan 15 09:12:19 raspi-4 kernel: [999862.129761] [<803e2750>] (ext4_filemap_fault) from [<802bd358>] (__do_fault+0x5c/0x178)
Jan 15 09:12:19 raspi-4 kernel: [999862.129767] r5:957f7ebc r4:80e04f88
Jan 15 09:12:19 raspi-4 kernel: [999862.129776] [<802bd2fc>] (__do_fault) from [<802c1e74>] (handle_mm_fault+0x8b4/0xc88)
Jan 15 09:12:19 raspi-4 kernel: [999862.129785] r8:00000040 r7:000b8000 r6:80e04f88 r5:00000d73 r4:000000af
Jan 15 09:12:19 raspi-4 kernel: [999862.129796] [<802c15c0>] (handle_mm_fault) from [<809208c4>] (do_page_fault+0x150/0x380)
Jan 15 09:12:19 raspi-4 kernel: [999862.129807] r10:87378240 r9:b3d89960 r8:80000007 r7:000b85b0 r6:87378200 r5:b4e7db80
Jan 15 09:12:19 raspi-4 kernel: [999862.129811] r4:957f7fb0
Jan 15 09:12:19 raspi-4 kernel: [999862.129823] [<80920774>] (do_page_fault) from [<80115fc0>] (do_PrefetchAbort+0x48/0x9c)
Jan 15 09:12:19 raspi-4 kernel: [999862.129833] r10:04736ee0 r9:002d6268 r8:957f7fb0 r7:000b85b0 r6:80920774 r5:00000007
Jan 15 09:12:19 raspi-4 kernel: [999862.129837] r4:80e0aa84
Jan 15 09:12:19 raspi-4 kernel: [999862.129848] [<80115f78>] (do_PrefetchAbort) from [<80101fa4>] (ret_from_exception+0x0/0x1c)
Jan 15 09:12:19 raspi-4 kernel: [999862.129854] Exception stack(0x957f7fb0 to 0x957f7ff8)
Jan 15 09:12:19 raspi-4 kernel: [999862.129861] 7fa0: 046fbeb0 00000000 00000016 00000018
Jan 15 09:12:19 raspi-4 kernel: [999862.129871] 7fc0: 00000000 0474d210 00000003 019c0150 04736ee0 002d6268 04736ee0 00191e70
Jan 15 09:12:19 raspi-4 kernel: [999862.129879] 7fe0: 00000000 7ec378d0 000e6ab8 000b85b0 60000010 ffffffff
Jan 15 09:12:19 raspi-4 kernel: [999862.129889] r8:10c5387d r7:10c5383d r6:ffffffff r5:60000010 r4:000b85b0
Jan 15 09:12:19 raspi-4 kernel: [999862.129894] Mem-Info:
Jan 15 09:12:19 raspi-4 kernel: [999862.129914] active_anon:110216 inactive_anon:110262 isolated_anon:0
Jan 15 09:12:19 raspi-4 kernel: [999862.129914] active_file:294 inactive_file:378 isolated_file:0
Jan 15 09:12:19 raspi-4 kernel: [999862.129914] unevictable:0 dirty:0 writeback:0 unstable:0
Jan 15 09:12:19 raspi-4 kernel: [999862.129914] slab_reclaimable:2958 slab_unreclaimable:3844
Jan 15 09:12:19 raspi-4 kernel: [999862.129914] mapped:1198 shmem:5550 pagetables:1295 bounce:0
Jan 15 09:12:19 raspi-4 kernel: [999862.129914] free:4093 free_pcp:298 free_cma:305
Jan 15 09:12:19 raspi-4 kernel: [999862.129930] Node 0 active_anon:440864kB inactive_anon:441048kB active_file:1176kB inactive_file:1512kB unevictable:0kB isolated(anon):0kB isolated(file):128kB mapped:4792kB dirty:0kB writeback:0kB shmem:22200kB writeback_tmp:0kB unstable:0kB all_unreclaimable? yes
Jan 15 09:12:19 raspi-4 kernel: [999862.129950] Normal free:16372kB min:16384kB low:20480kB high:24576kB active_anon:440864kB inactive_anon:441048kB active_file:976kB inactive_file:1864kB unevictable:0kB writepending:0kB present:970752kB managed:947036kB mlocked:0kB kernel_stack:1472kB pagetables:5180kB bounce:0kB free_pcp:1192kB local_pcp:680kB free_cma:1220kB
Jan 15 09:12:19 raspi-4 kernel: [999862.129956] lowmem_reserve[]: 0 0
Jan 15 09:12:19 raspi-4 kernel: [999862.129970] Normal: 353*4kB (UEC) 396*8kB (UE) 271*16kB (UEC) 117*32kB (UEC) 38*64kB (UE) 8*128kB (UEC) 1*256kB (E) 0*512kB 0*1024kB 0*2048kB 0*4096kB = 16372kB
Jan 15 09:12:19 raspi-4 kernel: [999862.130033] 12291 total pagecache pages
Jan 15 09:12:19 raspi-4 kernel: [999862.130043] 6012 pages in swap cache
Jan 15 09:12:19 raspi-4 kernel: [999862.130052] Swap cache stats: add 107159, delete 101147, find 1100741/1115109
Jan 15 09:12:19 raspi-4 kernel: [999862.130058] Free swap = 0kB
Jan 15 09:12:19 raspi-4 kernel: [999862.130065] Total swap = 102396kB
Jan 15 09:12:19 raspi-4 kernel: [999862.130072] 242688 pages RAM
Jan 15 09:12:19 raspi-4 kernel: [999862.130079] 0 pages HighMem/MovableOnly
Jan 15 09:12:19 raspi-4 kernel: [999862.130086] 5929 pages reserved
Jan 15 09:12:19 raspi-4 kernel: [999862.130092] 16384 pages cma reserved
Jan 15 09:12:19 raspi-4 kernel: [999862.130100] Tasks state (memory values in pages):
Jan 15 09:12:19 raspi-4 kernel: [999862.130107] [ pid ] uid tgid total_vm rss pgtables_bytes swapents oom_score_adj name
Jan 15 09:12:19 raspi-4 kernel: [999862.130135] [ 103] 0 103 10322 1041 75776 137 0 systemd-journal
Jan 15 09:12:19 raspi-4 kernel: [999862.130147] [ 133] 0 133 1139 2 12288 51 0 blkmapd
Jan 15 09:12:19 raspi-4 kernel: [999862.130160] [ 145] 0 145 4668 12 18432 212 -1000 systemd-udevd
Jan 15 09:12:19 raspi-4 kernel: [999862.130174] [ 404] 0 404 786 2 10240 55 0 rpc.idmapd
Jan 15 09:12:19 raspi-4 kernel: [999862.130187] [ 415] 103 415 1644 6 16384 81 0 rpcbind
Jan 15 09:12:19 raspi-4 kernel: [999862.130199] [ 422] 0 422 1979 50 12288 &

amenomade · 16 Januar 2021, 00:03:02

Ich würde zuerst die Perl Warnings korrigieren. Der Rest könnte eine Konzequenz sein.
Du machst Vergleiche oder Kalkulationen auf einem Reading, das "on" oder "set_on" als Wert hat.

presskopf · 16 Januar 2021, 14:19:44

Komisch, meine ganzen log- und Device-Angaben hats irgendwie hier zerschossen.

Danke, habe es im SVG gefixt!
Der Aufruf generiert nun keine Perl-Fehlermeldungen mehr.

Den Rest beobachte ich erst mal und berichte hier, falls es das zu gewesen sein scheint.

LG
Matthias

presskopf · 16 Januar 2021, 18:00:49

Mir ist nun folgendes aufgefallen:
Beim Anzeigen eines einzelnen SVG läuft alles i.O.
Wenn ich mir den ganzen Raum SVG mit einigen Plots anschaue, dann habe ich folgende Meldung im Log. Manchmal überspringt die Anzeige am Bildschirm auch einen Plot:

Code Auswählen

DbLog logdb - Error: DBI connect('database=fhem;host=localhost;port=3306','fhemuser',...) failed: Can't connect to local MySQL server through socket '/var/run/mysqld/mysqld.sock' (111) at ./FHEM/93_DbLog.pm line 2357.

Könnte es vielleicht sein, dass ich dem mysql-Server mehr Speicher geben muss? Oder kommt er mit den vielen Abfragen nicht klar?

presskopf · 19 Januar 2021, 13:24:42

Ich habe nun mal einen Plot, welcher auf detaillierte also umfangreiche Jahresdaten zurückgreift, aus dem Raum SVG rausgenommen.
Nun gibt es keine Probleme mehr. Anscheinend gab es einfach zu viele parallele Anfragen an den Mysql-Server und das hat sogar Fhem zum Absturz gebracht.

Könnte man dem Datenbankserver nicht einfach mehr Ressourcen freigeben, wenn das auf einem Raspberrry 3B+ denn möglich ist?

presskopf · 13 Februar 2021, 01:42:28

Nun denn, da habe ich wohl den Bottleneck doch noch gefunden und der ist profaner als anfangs gedacht.
Ich habe bei den Anfragen der SVG einfach mal CPU- und RAM-Auslastung verfolgt.
Das war gnadenlos am Limit - insbesondere das RAM. Nicht verwunderlich, da der Swap auch nur auf 100 MB gestellt war.
Ich habe nun den Swap auf 2 GB gestellt. Jegliche Anfrage wird nun ohne Anstand bedient.

Mit der guten Maria hatte das ganze nur sehr indirekt zu tun....

FHEM Forum

Absturz FHEM bei Aufruf SVG (Daten in MariaDB)

presskopf

amenomade

presskopf

presskopf

presskopf

presskopf