bardi
May 27, 2024, 8:34am
1
After a few days, my Z-Wave devices are all marked as ‘Unavailable’. Checking the Z-Wave logs I see:
2024-05-27T07:37:52.762Z CNTRLR received version info:
controller type: Static Controller
library version: Z-Wave 4.54
2024-05-27T07:37:52.764Z CNTRLR querying protocol version info...
2024-05-27T07:37:52.822Z CNTRLR received protocol version info:
protocol type: Z-Wave
protocol version: 4.54.0
2024-05-27T07:37:52.827Z CNTRLR querying controller capabilities...
2024-05-27T07:37:52.888Z CNTRLR received controller capabilities:
controller role: primary
is the SUC: true
started this network: true
SIS is present: true
was real primary: true
2024-05-27T07:37:52.893Z CNTRLR supported Z-Wave features:
2024-05-27T07:37:52.898Z CNTRLR Performing soft reset...
2024-05-27T07:37:52.926Z CNTRLR Waiting for the controller to reconnect...
2024-05-27T07:38:11.772Z CNTRLR Re-opening serial port...
[07:38:15] INFO: Service restart after closing
[09:38:17] ERROR: Got unexpected response from the API: Invalid configuration data for the add-on
[09:38:21] INFO: Successfully send discovery information to Home Assistant.
2024-05-27T07:38:29.067Z DRIVER ███████╗ ██╗ ██╗ █████╗ ██╗ ██╗ ███████╗ ██╗ ███████╗
╚══███╔╝ ██║ ██║ ██╔══██╗ ██║ ██║ ██╔════╝ ██║ ██╔════╝
███╔╝ █████╗ ██║ █╗ ██║ ███████║ ██║ ██║ █████╗ ██║ ███████╗
███╔╝ ╚════╝ ██║███╗██║ ██╔══██║ ╚██╗ ██╔╝ ██╔══╝ ██ ██║ ╚════██║
███████╗ ╚███╔███╔╝ ██║ ██║ ╚████╔╝ ███████╗ ╚█████╔╝ ███████║
╚══════╝ ╚══╝╚══╝ ╚═╝ ╚═╝ ╚═══╝ ╚══════╝ ╚════╝ ╚══════╝
2024-05-27T07:38:29.081Z DRIVER version 12.5.5
2024-05-27T07:38:29.082Z DRIVER
2024-05-27T07:38:38.191Z DRIVER Failed to open the serial port: Error: No such file or directory, cannot open
null
Unable to start driver ZWaveError: Failed to open the serial port: Error: No such file or directory, cannot open null (ZW0100)
at Driver.openSerialport (/usr/src/node_modules/zwave-js/src/lib/driver/Driver.ts:1319:9)
at Immediate.<anonymous> (/usr/src/node_modules/zwave-js/src/lib/driver/Driver.ts:1154:5) {
code: 100,
context: undefined,
transactionSource: undefined
}
[07:38:39] WARNING: Halt add-on
s6-rc: info: service legacy-services: stopping
s6-rc: info: service legacy-services successfully stopped
s6-rc: info: service legacy-cont-init: stopping
s6-rc: info: service legacy-cont-init successfully stopped
s6-rc: info: service fix-attrs: stopping
s6-rc: info: service fix-attrs successfully stopped
s6-rc: info: service s6rc-oneshot-runner: stopping
s6-rc: info: service s6rc-oneshot-runner successfully stopped
It seems that, for some reason, a soft-reset is done after which it fails to open the serial port. I also see an ‘invalid configuration data’ message. When I reboot my RPi 3B+ Z-Wave is restored again (for a few days).
Any thoughts on what could be wrong? Anything I could try?
bardi
May 27, 2024, 8:44am
2
dmesg shows an out-of-memory error occurred:
[466674.123956]
[466674.123962] WARN::dwc_otg_hcd_urb_dequeue:638: Timed out waiting for FSM NP transfer to complete on 1
[466674.124561]
[466674.124568] WARN::dwc_otg_hcd_urb_dequeue:638: Timed out waiting for FSM NP transfer to complete on 3
[466674.125620]
[466674.125628] WARN::dwc_otg_hcd_urb_dequeue:638: Timed out waiting for FSM NP transfer to complete on 7
[466674.126045]
[466674.126050] WARN::dwc_otg_hcd_urb_dequeue:638: Timed out waiting for FSM NP transfer to complete on 1
...
[466674.318724]
[466674.318728] WARN::dwc_otg_hcd_urb_dequeue:638: Timed out waiting for FSM NP transfer to complete on 6
[466674.319298]
[466674.319304] WARN::dwc_otg_hcd_urb_dequeue:638: Timed out waiting for FSM NP transfer to complete on 7
[466674.319499] usb 1-1.1.3: USB disconnect, device number 24
[466674.319947]
[466674.319951] WARN::dwc_otg_hcd_urb_dequeue:638: Timed out waiting for FSM NP transfer to complete on 6
[466674.320383]
[466674.320389] WARN::dwc_otg_hcd_urb_dequeue:638: Timed out waiting for FSM NP transfer to complete on 1
[466674.508769] usb 1-1.1.3: new full-speed USB device number 25 using dwc_otg
[466674.600953] usb 1-1.1.3: New USB device found, idVendor=0658, idProduct=0200, bcdDevice= 0.00
[466674.600983] usb 1-1.1.3: New USB device strings: Mfr=0, Product=0, SerialNumber=0
[466674.603901] cdc_acm 1-1.1.3:1.0: ttyACM0: USB ACM device
[468258.218483] kswapd0 invoked oom-killer: gfp_mask=0xcc0(GFP_KERNEL), order=0, oom_score_adj=0
[468258.218548] CPU: 3 PID: 55 Comm: kswapd0 Tainted: G C 6.1.73-haos-raspi #1
[468258.218560] Hardware name: Raspberry Pi 3 Model B Plus Rev 1.3 (DT)
[468258.218567] Call trace:
[468258.218574] dump_backtrace.part.0+0xe8/0x100
[468258.218598] show_stack+0x20/0x30
[468258.218606] dump_stack_lvl+0x68/0x84
[468258.218616] dump_stack+0x18/0x34
[468258.218623] dump_header+0x50/0x214
[468258.218631] oom_kill_process+0x2bc/0x2c0
[468258.218648] out_of_memory+0xec/0x360
[468258.218659] balance_pgdat+0x788/0x930
[468258.218667] kswapd+0x20c/0x500
[468258.218673] kthread+0x114/0x120
[468258.218685] ret_from_fork+0x10/0x20
[468258.218696] Mem-Info:
[468258.218704] active_anon:74684 inactive_anon:70294 isolated_anon:0
[468258.218704] active_file:7113 inactive_file:1609 isolated_file:0
[468258.218704] unevictable:0 dirty:170 writeback:0
[468258.218704] slab_reclaimable:13974 slab_unreclaimable:13668
[468258.218704] mapped:7962 shmem:16 pagetables:3632
[468258.218704] sec_pagetables:0 bounce:0
[468258.218704] kernel_misc_reclaimable:0
[468258.218704] free:15135 free_pcp:388 free_cma:367
[468258.218727] Node 0 active_anon:298736kB inactive_anon:281176kB active_file:28452kB inactive_file:6436kB unevictable:0kB isolated(anon):0kB isolated(file):0kB mapped:31848kB dirty:680kB writeback:0kB shmem:64kB writeback_tmp:0kB kernel_stack:11888kB pagetables:14528kB sec_pagetables:0kB all_unreclaimable? no
[468258.218745] DMA free:60540kB boost:36864kB min:53248kB low:57344kB high:61440kB reserved_highatomic:4096KB active_anon:298640kB inactive_anon:280832kB active_file:28424kB inactive_file:6312kB unevictable:0kB writepending:680kB present:970752kB managed:930356kB mlocked:0kB bounce:0kB free_pcp:1552kB local_pcp:488kB free_cma:1468kB
[468258.218764] lowmem_reserve[]: 0 0 0 0
[468258.218782] DMA: 4538*4kB (UMEHC) 1251*8kB (UMEHC) 762*16kB (UMEHC) 353*32kB (UMEH) 117*64kB (UMEH) 9*128kB (UMH) 0*256kB 0*512kB 0*1024kB 0*2048kB 0*4096kB = 60288kB
[468258.218844] 10410 total pagecache pages
[468258.218849] 1687 pages in swap cache
[468258.218853] Free swap = 136kB
[468258.218858] Total swap = 307348kB
[468258.218863] 242688 pages RAM
[468258.218866] 0 pages HighMem/MovableOnly
[468258.218871] 10099 pages reserved
[468258.218874] 16384 pages cma reserved
[468258.218879] Tasks state (memory values in pages):
[468258.218883] [ pid ] uid tgid total_vm rss pgtables_bytes swapents oom_score_adj name
[468258.218930] [ 134] 0 134 8703 761 98304 140 -250 systemd-journal
[468258.218944] [ 139] 0 139 96336 198 110592 360 0 udisksd
[468258.218956] [ 154] 0 154 3224 141 57344 299 -1000 systemd-udevd
[468258.218969] [ 156] 100 156 1557 0 45056 133 -900 dbus-broker-lau
[468258.218984] [ 157] 100 157 852 89 32768 109 -900 dbus-broker
[468258.219012] [ 231] 0 231 290473 454 147456 236 0 os-agent
[468258.219045] [ 395] 103 395 2919 174 49152 199 0 systemd-resolve
[468258.219065] [ 412] 0 412 60695 324 94208 334 0 NetworkManager
[468258.219081] [ 417] 0 417 58199 13 77824 293 0 rauc
[468258.219095] [ 421] 0 421 821 6 36864 51 0 rpcbind
[468258.219106] [ 423] 0 423 2532 36 49152 147 0 systemd-logind
[468258.219118] [ 424] 0 424 2249 24 49152 153 0 wpa_supplicant
[468258.219130] [ 430] 0 430 1652 69 40960 56 0 bluetoothd
[468258.219142] [ 446] 0 446 369121 2893 245760 901 -999 containerd
[468258.219206] [ 455] 0 455 672 19 36864 18 0 dropbear
[468258.219232] [ 505] 104 505 20939 36 61440 126 0 systemd-timesyn
[468258.219246] [ 515] 0 515 727341 5740 516096 2364 -400 dockerd
[468258.219260] [ 734] 0 734 288888 0 122880 163 -400 docker-proxy
[468258.219272] [ 741] 0 741 307336 0 131072 194 -400 docker-proxy
[468258.219285] [ 759] 0 759 383862 629 192512 263 -998 containerd-shim
[468258.219299] [ 779] 0 779 179555 628 94208 235 -300 observer
[468258.219312] [ 846] 0 846 294087 909 176128 351 0 docker
[468258.219326] [ 847] 0 847 836 1 36864 35 0 hassos-cli
[468258.219342] [ 895] 0 895 383862 657 200704 239 -998 containerd-shim
[468258.219362] [ 916] 0 916 106 1 28672 11 -300 s6-svscan
[468258.219380] [ 969] 0 969 52 1 28672 5 -300 s6-supervise
[468258.219395] [ 972] 0 972 48 0 24576 3 -300 s6-linux-init-s
[468258.219410] [ 978] 0 978 52 1 28672 5 -300 s6-supervise
[468258.219464] [ 979] 0 979 52 1 28672 4 -300 s6-supervise
[468258.219482] [ 987] 0 987 49 1 28672 5 -300 s6-ipcserverd
[468258.219496] [ 1033] 0 1033 52 1 28672 4 -300 s6-supervise
[468258.219511] [ 1034] 0 1034 52 1 28672 5 -300 s6-supervise
[468258.219530] [ 1037] 0 1037 1131 127 36864 413 -300 bash
[468258.219548] [ 1036] 0 1036 68164 9018 573440 9666 -300 python3
[468258.219563] [ 1178] 0 1178 383926 638 188416 293 -998 containerd-shim
[468258.219578] [ 1199] 0 1199 106 1 28672 12 0 s6-svscan
[468258.219593] [ 1245] 0 1245 52 1 28672 5 0 s6-supervise
[468258.219609] [ 1247] 0 1247 48 0 24576 2 0 s6-linux-init-s
[468258.219623] [ 1254] 0 1254 52 1 28672 4 0 s6-supervise
[468258.219636] [ 1255] 0 1255 52 1 28672 4 0 s6-supervise
[468258.219649] [ 1263] 0 1263 49 1 28672 5 0 s6-ipcserverd
[468258.219672] [ 1308] 0 1308 349431 916 196608 352 0 docker
[468258.219687] [ 1328] 0 1328 659 0 32768 58 0 cli.sh
[468258.219699] [ 1350] 0 1350 383862 584 192512 262 -998 containerd-shim
[468258.219713] [ 1370] 0 1370 106 1 28672 12 -300 s6-svscan
[468258.219728] [ 1409] 0 1409 52 1 28672 6 -300 s6-supervise
[468258.219741] [ 1411] 0 1411 48 0 24576 2 -300 s6-linux-init-s
[468258.219756] [ 1418] 0 1418 52 1 28672 5 -300 s6-supervise
[468258.219769] [ 1419] 0 1419 52 1 28672 4 -300 s6-supervise
[468258.219783] [ 1427] 0 1427 49 1 28672 6 -300 s6-ipcserverd
[468258.219796] [ 1468] 0 1468 52 1 28672 5 -300 s6-supervise
[468258.219808] [ 1470] 0 1470 311037 5790 217088 577 -300 coredns
[468258.219820] [ 1500] 0 1500 383926 591 192512 244 -998 containerd-shim
[468258.219833] [ 1521] 0 1521 106 1 28672 13 0 s6-svscan
[468258.219845] [ 1568] 0 1568 52 1 28672 5 0 s6-supervise
[468258.219858] [ 1571] 0 1571 48 0 24576 2 0 s6-linux-init-s
[468258.219871] [ 1577] 0 1577 52 1 28672 5 0 s6-supervise
[468258.219884] [ 1578] 0 1578 52 1 28672 5 0 s6-supervise
[468258.219896] [ 1579] 0 1579 52 1 28672 4 0 s6-supervise
[468258.219914] [ 1580] 0 1580 52 1 28672 4 0 s6-supervise
[468258.219929] [ 1588] 0 1588 49 1 28672 6 0 s6-ipcserverd
[468258.219943] [ 1599] 0 1599 383878 626 192512 245 -998 containerd-shim
[468258.219955] [ 1635] 0 1635 106 1 28672 13 0 s6-svscan
[468258.219968] [ 1665] 0 1665 1121 1 36864 513 0 bash
[468258.219983] [ 1679] 0 1679 52 1 28672 4 0 s6-supervise
[468258.219995] [ 1686] 0 1686 48 0 24576 2 0 s6-linux-init-s
[468258.220007] [ 1690] 0 1690 1633 0 45056 123 0 udevadm
[468258.220020] [ 1701] 0 1701 52 1 28672 5 0 s6-supervise
[468258.220032] [ 1702] 0 1702 52 1 28672 3 0 s6-supervise
[468258.220047] [ 1710] 0 1710 49 1 28672 5 0 s6-ipcserverd
[468258.220059] [ 1748] 0 1748 52 1 28672 5 0 s6-supervise
[468258.220071] [ 1750] 0 1750 226 33 32768 3 0 mdns-repeater
[468258.220083] [ 1882] 0 1882 24562 38 102400 810 0 pulseaudio
[468258.220095] [ 1955] 0 1955 525 0 32768 111 0 rlwrap
[468258.220108] [ 1956] 0 1956 426 0 28672 12 0 cat
[468258.220120] [ 1974] 0 1974 288888 0 118784 173 -400 docker-proxy
[468258.220132] [ 1981] 0 1981 307336 0 122880 203 -400 docker-proxy
[468258.220191] [ 1995] 0 1995 307336 0 131072 173 -400 docker-proxy
[468258.220216] [ 2002] 0 2002 288888 0 122880 171 -400 docker-proxy
[468258.220229] [ 2017] 0 2017 307336 0 135168 189 -400 docker-proxy
[468258.220240] [ 2025] 0 2025 307336 0 131072 192 -400 docker-proxy
[468258.220253] [ 2041] 0 2041 288888 0 122880 182 -400 docker-proxy
[468258.220265] [ 2048] 0 2048 307336 0 131072 187 -400 docker-proxy
[468258.220278] [ 2068] 0 2068 402374 573 200704 270 -998 containerd-shim
[468258.220290] [ 2088] 0 2088 50 1 28672 4 200 s6-svscan
[468258.220302] [ 2128] 0 2128 51 1 28672 3 200 s6-supervise
[468258.220314] [ 2130] 0 2130 48 0 24576 3 200 s6-linux-init-s
[468258.220327] [ 2137] 0 2137 51 1 28672 3 200 s6-supervise
[468258.220339] [ 2138] 0 2138 51 1 28672 4 200 s6-supervise
[468258.220350] [ 2146] 0 2146 45 0 24576 3 200 s6-ipcserverd
[468258.220364] [ 2217] 0 2217 402374 651 208896 262 -998 containerd-shim
[468258.220376] [ 2243] 0 2243 106 1 28672 12 200 s6-svscan
[468258.220387] [ 2285] 0 2285 432 1 36864 19 200 rc.init
[468258.220399] [ 2286] 0 2286 52 1 28672 5 200 s6-supervise
[468258.220414] [ 2288] 0 2288 48 0 24576 3 200 s6-linux-init-s
[468258.220428] [ 2295] 0 2295 52 1 28672 5 200 s6-supervise
[468258.220441] [ 2296] 0 2296 52 1 28672 3 200 s6-supervise
[468258.220457] [ 2304] 0 2304 49 1 28672 5 200 s6-ipcserverd
[468258.220469] [ 2357] 0 2357 1132 119 32768 421 200 bash
[468258.220480] [ 2383] 0 2383 402374 871 212992 193 -998 containerd-shim
[468258.220492] [ 2404] 0 2404 106 0 28672 25 200 s6-svscan
[468258.220504] [ 2458] 0 2458 52 1 28672 5 200 s6-supervise
[468258.220515] [ 2460] 0 2460 48 0 24576 2 200 s6-linux-init-s
[468258.220528] [ 2478] 0 2478 52 1 28672 5 200 s6-supervise
[468258.220539] [ 2479] 0 2479 52 1 28672 5 200 s6-supervise
[468258.220553] [ 2480] 0 2480 52 1 28672 3 200 s6-supervise
[468258.220565] [ 2481] 0 2481 52 1 28672 5 200 s6-supervise
[468258.220576] [ 2501] 0 2501 49 1 28672 5 200 s6-ipcserverd
[468258.220589] [ 2586] 0 2586 383926 705 192512 228 -998 containerd-shim
[468258.220601] [ 2621] 0 2621 106 0 28672 27 200 s6-svscan
[468258.220613] [ 2756] 0 2756 52 1 28672 5 200 s6-supervise
[468258.220626] [ 2759] 0 2759 48 0 24576 2 200 s6-linux-init-s
[468258.220637] [ 2788] 0 2788 52 1 28672 5 200 s6-supervise
[468258.220652] [ 2790] 0 2790 52 1 28672 4 200 s6-supervise
[468258.220664] [ 2802] 0 2802 402310 615 204800 264 -998 containerd-shim
[468258.220677] [ 2813] 0 2813 49 1 28672 5 200 s6-ipcserverd
[468258.220689] [ 2829] 0 2829 51 1 28672 3 200 s6-supervise
[468258.220702] [ 2830] 0 2830 51 1 28672 3 200 s6-supervise
[468258.220714] [ 2832] 0 2832 388080 2161 249856 2048 200 mosquitto
[468258.220727] [ 2842] 0 2842 2046 0 49152 201 200 nginx
[468258.220740] [ 2851] 0 2851 106 1 32768 12 200 s6-svscan
[468258.220753] [ 2896] 0 2896 2046 233 45056 154 200 nginx
[468258.220768] [ 2975] 0 2975 12371 86 126976 508 200 smbd
[468258.220780] [ 2984] 0 2984 8855 116 102400 402 200 nmbd
[468258.220799] [ 3004] 0 3004 52 1 28672 5 200 s6-supervise
[468258.220813] [ 3010] 0 3010 48 0 24576 3 200 s6-linux-init-s
[468258.220827] [ 3061] 0 3061 52 1 28672 5 200 s6-supervise
[468258.220840] [ 3062] 0 3062 52 1 28672 5 200 s6-supervise
[468258.220852] [ 3063] 0 3063 52 1 28672 4 200 s6-supervise
[468258.220864] [ 3065] 0 3065 52 1 32768 4 200 s6-supervise
[468258.220876] [ 3082] 0 3082 49 1 28672 5 200 s6-ipcserverd
[468258.220888] [ 3369] 0 3369 11839 13 118784 579 200 smbd-notifyd
[468258.220900] [ 3370] 0 3370 11845 57 118784 540 200 smbd-cleanupd
[468258.220913] [ 3649] 0 3649 1767 15 45056 133 200 ttyd
[468258.220930] [ 3651] 0 3651 1563 0 40960 202 200 sshd
[468258.220944] [ 3855] 0 3855 52 1 28672 6 200 s6-supervise
[468258.220956] [ 3963] 0 3963 383878 647 188416 268 -998 containerd-shim
[468258.220969] [ 3984] 0 3984 106 1 28672 13 -300 s6-svscan
[468258.220982] [ 4005] 0 4005 52 1 28672 5 -300 s6-supervise
[468258.220994] [ 4007] 0 4007 48 0 24576 2 -300 s6-linux-init-s
[468258.221007] [ 4014] 0 4014 52 1 28672 5 -300 s6-supervise
[468258.221020] [ 4015] 0 4015 52 1 32768 4 -300 s6-supervise
[468258.221033] [ 4023] 0 4023 49 1 28672 6 -300 s6-ipcserverd
[468258.221046] [ 4054] 0 4054 52 1 28672 5 -300 s6-supervise
[468258.221058] [ 4056] 0 4056 305736 83677 2166784 44182 -300 python3
[468258.221070] [ 4165] 0 4165 402374 863 204800 195 -998 containerd-shim
[468258.221082] [ 4186] 0 4186 106 1 32768 12 200 s6-svscan
[468258.221095] [ 4228] 0 4228 52 1 28672 5 200 s6-supervise
[468258.221107] [ 4230] 0 4230 48 0 24576 2 200 s6-linux-init-s
[468258.221119] [ 4237] 0 4237 52 1 28672 5 200 s6-supervise
[468258.221132] [ 4238] 0 4238 52 1 28672 4 200 s6-supervise
[468258.221177] [ 4239] 0 4239 52 1 28672 3 200 s6-supervise
[468258.221199] [ 4247] 0 4247 49 1 28672 5 200 s6-ipcserverd
[468258.221211] [ 4267] 0 4267 8799 4220 98304 828 200 hass-configurat
[468258.221228] [ 4306] 0 4306 325848 0 147456 176 -400 docker-proxy
[468258.221242] [ 4322] 0 4322 325848 0 139264 188 -400 docker-proxy
[468258.221256] [ 4366] 0 4366 402374 592 200704 250 -998 containerd-shim
[468258.221270] [ 4387] 0 4387 106 1 28672 11 200 s6-svscan
[468258.221291] [ 4428] 0 4428 52 1 28672 5 200 s6-supervise
[468258.221307] [ 4431] 0 4431 48 0 24576 3 200 s6-linux-init-s
[468258.221320] [ 4439] 0 4439 52 1 28672 4 200 s6-supervise
[468258.221332] [ 4440] 0 4440 52 1 28672 4 200 s6-supervise
[468258.221346] [ 4441] 0 4441 52 1 28672 6 200 s6-supervise
[468258.221358] [ 4442] 0 4442 52 1 32768 5 200 s6-supervise
[468258.221371] [ 4450] 0 4450 49 1 28672 5 200 s6-ipcserverd
[468258.221383] [ 4470] 0 4470 4534 1 49152 339 200 nginx
[468258.221396] [ 4475] 0 4475 430 10 28672 4 200 crond
[468258.221408] [ 17514] 0 17514 4705 19 69632 132 -300 git
[468258.221421] [ 17545] 0 17545 4708 21 65536 128 -300 git
[468258.221433] [ 17600] 0 17600 4703 12 65536 137 -300 git
[468258.221446] [ 224955] 100 224955 4748 237 53248 260 200 nginx
[468258.221461] [ 538750] 0 538750 430 0 32768 16 200 sh
[468258.221473] [ 538757] 0 538757 2351 0 45056 196 200 curl
[468258.221485] [ 541680] 0 541680 10713 126 110592 434 200 samba-dcerpcd
[468258.221498] [ 541695] 0 541695 12765 221 131072 411 200 rpcd_classic
[468258.221516] [ 547070] 0 547070 80675 14718 356352 64 200 node
[468258.221544] [ 548547] 0 548547 426 1 36864 0 -300 sleep
[468258.221560] [ 548651] 0 548651 426 1 32768 0 200 sleep
[468258.221573] [ 548664] 0 548664 430 1 32768 0 200 sh
[468258.221586] [ 548670] 0 548670 2351 213 49152 0 200 curl
[468258.221598] oom-kill:constraint=CONSTRAINT_NONE,nodemask=(null),cpuset=/,mems_allowed=0,global_oom,task_memcg=/system.slice/docker-8ceee91db296664d522db060ce8df6fb35fcef8ce5f8b227767e4811ec33a5f8.scope,task=node,pid=547070,uid=0
[468258.221794] Out of memory: Killed process 547070 (node) total-vm:322700kB, anon-rss:57960kB, file-rss:912kB, shmem-rss:0kB, UID:0 pgtables:348kB oom_score_adj:200
[468294.443431]
[468294.443457] WARN::dwc_otg_hcd_urb_dequeue:638: Timed out waiting for FSM NP transfer to complete on 1
[468294.443899]
[468294.443906] WARN::dwc_otg_hcd_urb_dequeue:638: Timed out waiting for FSM NP transfer to complete on 5
[468294.445360]
[468294.445379] WARN::dwc_otg_hcd_urb_dequeue:638: Timed out waiting for FSM NP transfer to complete on 4
[468294.445819]
...
[468294.798659] WARN::dwc_otg_hcd_urb_dequeue:638: Timed out waiting for FSM NP transfer to complete on 4
[468294.799072]
[468294.799075] WARN::dwc_otg_hcd_urb_dequeue:638: Timed out waiting for FSM NP transfer to complete on 2
[468294.799757]
[468294.799761] WARN::dwc_otg_hcd_urb_dequeue:638: Timed out waiting for FSM NP transfer to complete on 7
[468294.800307]
[468294.800310] WARN::dwc_otg_hcd_urb_dequeue:638: Timed out waiting for FSM NP transfer to complete on 6
[468294.800744]
[468294.800748] WARN::dwc_otg_hcd_urb_dequeue:638: Timed out waiting for FSM NP transfer to complete on 0
[468294.800862] usb 1-1.1.3: USB disconnect, device number 25
[468294.987973] usb 1-1.1.3: new full-speed USB device number 26 using dwc_otg
[468295.079363] usb 1-1.1.3: New USB device found, idVendor=0658, idProduct=0200, bcdDevice= 0.00
[468295.079394] usb 1-1.1.3: New USB device strings: Mfr=0, Product=0, SerialNumber=0
[468295.081576] cdc_acm 1-1.1.3:1.0: ttyACM1: USB ACM device
[468370.420453] audit: type=1334 audit(1716795549.890:1026): prog-id=54 op=UNLOAD
[468372.579325] hassio: port 8(veth0da8c51) entered disabled state
[468372.580068] veth07f15c2: renamed from eth0
[468372.817408] hassio: port 8(veth0da8c51) entered disabled state
[468372.820258] device veth0da8c51 left promiscuous mode
[468372.820334] hassio: port 8(veth0da8c51) entered disabled state
[468372.820405] audit: type=1700 audit(1716795552.286:1027): dev=veth0da8c51 prom=0 old_prom=256 auid=4294967295 uid=0 gid=0 ses=4294967295
[468372.838835] audit: type=1300 audit(1716795552.286:1027): arch=c00000b7 syscall=206 success=yes exit=32 a0=c a1=400152cbe0 a2=20 a3=0 items=0 ppid=1 pid=515 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="dockerd" exe="/usr/bin/dockerd" subj=unconfined key=(null)
[468372.838896] audit: type=1327 audit(1716795552.286:1027): proctitle=2F7573722F62696E2F646F636B657264002D480066643A2F2F002D2D636F6E7461696E6572643D2F72756E2F636F6E7461696E6572642F636F6E7461696E6572642E736F636B
[468373.122703] audit: type=1334 audit(1716795552.593:1028): prog-id=57 op=UNLOAD
[471004.337396] audit: type=1334 audit(1716798183.823:1029): prog-id=445 op=LOAD
[471669.079760] audit: type=1334 audit(1716798848.508:1030): prog-id=446 op=LOAD
[471669.080810] audit: type=1334 audit(1716798848.509:1031): prog-id=447 op=LOAD
[471669.081070] audit: type=1334 audit(1716798848.509:1032): prog-id=448 op=LOAD
[471670.196952] audit: type=1334 audit(1716798849.625:1033): prog-id=449 op=LOAD
[471670.197679] audit: type=1334 audit(1716798849.626:1034): prog-id=450 op=LOAD
[471670.197808] audit: type=1334 audit(1716798849.626:1035): prog-id=451 op=LOAD
[471700.244887] audit: type=1334 audit(1716798879.673:1036): prog-id=448 op=UNLOAD
[471700.244925] audit: type=1334 audit(1716798879.673:1037): prog-id=447 op=UNLOAD
[471700.244946] audit: type=1334 audit(1716798879.673:1038): prog-id=446 op=UNLOAD
[471700.974087] audit: type=1334 audit(1716798880.402:1039): prog-id=451 op=UNLOAD
[471700.974126] audit: type=1334 audit(1716798880.402:1040): prog-id=450 op=UNLOAD
[471700.974141] audit: type=1334 audit(1716798880.402:1041): prog-id=449 op=UNLOAD
bardi
June 10, 2024, 7:14am
3
I replaced my micro SD card of my RPi 3B+ and also upgraded my ZWave dongle from an Aeotec Gen 5 to Gen 7, for now I haven’t seen this issue popping up again. If it stays like this for the coming days, I’ll mark this as ‘solution’…
The OOM refers to the RAM in the RPI not the sdcard. Monitor how much of the 2GB you are using. If you are close (e.g within 15%), get a PI with more memory.
Gen7 sticks have a known firmware flaw that causes controller jammed issues. Most folks are reverting to a Gen 5 stick while they wait for a fix (it’s been about a year now. )