danielwelch / hassio-zigbee2mqtt

Hass.io add-on for zigbee2mqtt
Apache License 2.0
566 stars 189 forks source link

Possible memory leak when there are 20+ devices connected #475

Closed ivanovd closed 3 years ago

ivanovd commented 3 years ago

zigbee2mqtt add-on version (if edge, please report commit hash): 1.16.1

Operating environment (HassOS, Virtual Machine, Device/platform running Home Assistant):

System Health

Home Assistant Core Integration

version: 0.118.2
installation_type: Home Assistant Supervised
dev: false
hassio: true
docker: true
virtualenv: false
python_version: 3.8.6
os_name: Linux
os_version: 5.4.72-v7+
arch: armv7l
timezone: Europe/Sofia

Home Assistant Cloud

logged_in: false
can_reach_cert_server: ok
can_reach_cloud_auth: ok
can_reach_cloud: ok

Hass.io

host_os: Raspbian GNU/Linux 10 (buster)
update_channel: stable
supervisor_version: 2020.11.0
docker_version: 19.03.13
disk_total: 109.7 GB
disk_used: 17.7 GB
healthy: true
supported: failed to load: Unsupported
supervisor_api: ok
version_api: ok
installed_addons: ESPHome (1.15.3), File editor (5.2.0), Samba share (9.3.0), Duck DNS (1.12.4), Mosquitto broker (5.1), Home Assistant Google Drive Backup (0.102.0), Node-RED (7.2.10), Zigbee2mqtt (1.16.1), zigbee2mqttassistant (0.3.157)

Lovelace

dashboards: 1
mode: storage
views: 8
resources: 4

Description of problem:

I have noticed that when I connect 20+ devices to my ZigBee network, this crashes the observer. The watchdog restarts the observer, however, after that the ZigBee2MQTT cannot connect to MQTT, even though MQTT is running. You have to restart the ZigBee2MQTT add-on so that it starts communicating with the MQTT broker. It started happening recently. After upgrading to HA 1.116 I believe. As soon as I drop the number of devices in the ZigBee network, the system is rock solid and no more OOM errors and crashes.

Your entire configuration from the frontend (with sensitive fields redacted):

Not relevant

Your logs from Home Assistant

Nov 22 10:03:09 raspberrypi hassio-supervisor[1438]: #033[32m20-11-22 08:03:09 INFO (MainThread) [supervisor.homeassistant.api] Updated Home Assistant API token#033[0m
Nov 22 10:03:30 raspberrypi hassio-supervisor[1438]: #033[32m20-11-22 08:03:30 INFO (MainThread) [supervisor.updater] Fetching update data from https://version.home-assistant.io/stable.json#033[0m
Nov 22 10:04:51 raspberrypi hassio-supervisor[1438]: #033[32m20-11-22 08:04:51 INFO (MainThread) [supervisor.api.security] /snapshots access from cebe7a76_hassio_google_drive_backup#033[0m
Nov 22 10:09:50 raspberrypi hassio-supervisor[1438]: #033[33m20-11-22 08:09:44 WARNING (MainThread) [supervisor.misc.tasks] Watchdog/Application found a problem with observer plugin!#033[0m
Nov 22 10:10:46 raspberrypi hassio-supervisor[1438]: #033[31m20-11-22 08:10:35 ERROR (MainThread) [supervisor.homeassistant.api] Error on call https://172.30.32.1:8123/api/config: #033[0m
Nov 22 10:11:02 raspberrypi hassio-supervisor[1438]: #033[32m20-11-22 08:10:49 INFO (SyncWorker_4) [supervisor.docker.interface] Stopping hassio_observer application#033[0m
Nov 22 10:11:14 raspberrypi hassio-supervisor[1438]: #033[32m20-11-22 08:11:08 INFO (MainThread) [supervisor.api.proxy] Home Assistant WebSocket API connection is closed#033[0m
Nov 22 10:12:51 raspberrypi containerd[436]: time="2020-11-22T10:12:30.758460001+02:00" level=error msg="get state for 1c880931ddeceffe12ad318d356fd57f22981a9a8b6b2d158a1e9c49b1254035" error="context deadline exceeded: unknown"
Nov 22 10:12:58 raspberrypi containerd[436]: time="2020-11-22T10:12:49.648297122+02:00" level=warning msg="unknown status" status=0
Nov 22 10:17:36 raspberrypi CRON[8264]: (root) CMD (   cd / && run-parts --report /etc/cron.hourly)
Nov 22 10:17:59 raspberrypi dockerd[665]: time="2020-11-22T10:17:47.971840123+02:00" level=info msg="Container 1c880931ddeceffe12ad318d356fd57f22981a9a8b6b2d158a1e9c49b1254035 failed to exit within 10 seconds of signal 15 - using the force"
Nov 22 10:18:34 raspberrypi hassio-supervisor[1438]: #033[31m20-11-22 08:18:25 ERROR (MainThread) [supervisor.homeassistant.api] Error on call https://172.30.32.1:8123/api/config: #033[0m
Nov 22 10:20:59 raspberrypi containerd[436]: time="2020-11-22T10:20:50.978778220+02:00" level=error msg="get state for 1c880931ddeceffe12ad318d356fd57f22981a9a8b6b2d158a1e9c49b1254035" error="context deadline exceeded: unknown"
Nov 22 10:20:59 raspberrypi containerd[436]: time="2020-11-22T10:20:56.487855044+02:00" level=warning msg="unknown status" status=0
Nov 22 10:21:30 raspberrypi kernel: [58868.244159] bash invoked oom-killer: gfp_mask=0x400dc0(GFP_KERNEL_ACCOUNT|__GFP_ZERO), order=0, oom_score_adj=0
Nov 22 10:21:30 raspberrypi kernel: [58868.244174] CPU: 1 PID: 8824 Comm: bash Tainted: G         C        5.4.72-v7+ #1356
Nov 22 10:21:30 raspberrypi kernel: [58868.244177] Hardware name: BCM2835
Nov 22 10:21:30 raspberrypi kernel: [58868.244179] Backtrace: 
Nov 22 10:21:30 raspberrypi kernel: [58868.244195] [<8010e1c0>] (dump_backtrace) from [<8010e538>] (show_stack+0x20/0x24)
Nov 22 10:21:31 raspberrypi kernel: [58868.244200]  r7:ffffffff r6:00000000 r5:60000113 r4:80e996f0
Nov 22 10:21:31 raspberrypi kernel: [58868.244209] [<8010e518>] (show_stack) from [<8090e830>] (dump_stack+0xd4/0x118)
Nov 22 10:21:31 raspberrypi kernel: [58868.244218] [<8090e75c>] (dump_stack) from [<8028df9c>] (dump_header+0x64/0x1f8)
Nov 22 10:21:31 raspberrypi kernel: [58868.244224]  r10:00400dc0 r9:b5e68000 r8:00000000 r7:00000558 r6:80bbbb00 r5:883dbd00
Nov 22 10:21:31 raspberrypi kernel: [58868.244227]  r4:b5e69d18 r3:00000000
Nov 22 10:21:31 raspberrypi kernel: [58868.244233] [<8028df38>] (dump_header) from [<8028d334>] (oom_kill_process+0x17c/0x188)
Nov 22 10:21:31 raspberrypi kernel: [58868.244237]  r7:00000558 r6:80bbbb00 r5:b5e69d18 r4:883dbd00
Nov 22 10:21:31 raspberrypi kernel: [58868.244243] [<8028d1b8>] (oom_kill_process) from [<8028dd9c>] (out_of_memory+0x244/0x344)
Nov 22 10:21:31 raspberrypi kernel: [58868.244248]  r7:80e04fa8 r6:80e08200 r5:b5e69d18 r4:883dbd00
Nov 22 10:21:31 raspberrypi kernel: [58868.244255] [<8028db58>] (out_of_memory) from [<802da420>] (__alloc_pages_nodemask+0xbe8/0x121c)
Nov 22 10:21:31 raspberrypi kernel: [58868.244259]  r6:80e9b3c8 r5:0000220d r4:00000000
Nov 22 10:21:31 raspberrypi kernel: [58868.244266] [<802d9838>] (__alloc_pages_nodemask) from [<802bfb74>] (__pte_alloc+0x34/0x17c)
Nov 22 10:21:31 raspberrypi kernel: [58868.244271]  r10:00001fb8 r9:00002017 r8:00000081 r7:00000000 r6:92aa9fb8 r5:84836e00
Nov 22 10:21:31 raspberrypi kernel: [58868.244274]  r4:00000000
Nov 22 10:21:31 raspberrypi kernel: [58868.244279] [<802bfb40>] (__pte_alloc) from [<802c337c>] (handle_mm_fault+0x454/0xc98)
Nov 22 10:21:31 raspberrypi kernel: [58868.244286]  r9:00002017 r8:00000081 r7:00000000 r6:00000081 r5:824a12a0 r4:00000000
Nov 22 10:21:31 raspberrypi kernel: [58868.244294] [<802c2f28>] (handle_mm_fault) from [<802bd25c>] (__get_user_pages+0x188/0x4d8)
Nov 22 10:21:31 raspberrypi kernel: [58868.244300]  r10:00001fb8 r9:00002017 r8:00000081 r7:00000000 r6:84836e00 r5:7efffff2
Nov 22 10:21:31 raspberrypi kernel: [58868.244303]  r4:824a12a0
Nov 22 10:21:31 raspberrypi kernel: [58868.244310] [<802bd0d4>] (__get_user_pages) from [<802bd704>] (get_user_pages_remote+0x158/0x2a0)
Nov 22 10:21:31 raspberrypi kernel: [58868.244316]  r10:84836e40 r9:ae64bd00 r8:84836e00 r7:00000000 r6:00000001 r5:b5e69ef0
Nov 22 10:21:31 raspberrypi kernel: [58868.244318]  r4:7efffff2
Nov 22 10:21:31 raspberrypi kernel: [58868.244326] [<802bd5ac>] (get_user_pages_remote) from [<8030f68c>] (copy_strings+0x214/0x3a0)
Nov 22 10:21:31 raspberrypi kernel: [58868.244331]  r10:00000000 r9:00000000 r8:b57eb010 r7:7efffff2 r6:00000000 r5:0000000a
Nov 22 10:21:31 raspberrypi kernel: [58868.244334]  r4:84876000
Nov 22 10:21:31 raspberrypi kernel: [58868.244339] [<8030f478>] (copy_strings) from [<8030f854>] (copy_strings_kernel+0x3c/0x54)
Nov 22 10:21:31 raspberrypi kernel: [58868.244345]  r10:824a12a0 r9:00000000 r8:00a32bf8 r7:00000001 r6:84876000 r5:7f000000
Nov 22 10:21:31 raspberrypi kernel: [58868.244347]  r4:ffffe000
Nov 22 10:21:31 raspberrypi kernel: [58868.244352] [<8030f818>] (copy_strings_kernel) from [<80310028>] (__do_execve_file+0x56c/0x854)
Nov 22 10:21:31 raspberrypi kernel: [58868.244355]  r5:00000100 r4:b57eb000
Nov 22 10:21:31 raspberrypi kernel: [58868.244361] [<8030fabc>] (__do_execve_file) from [<80310e60>] (sys_execve+0x44/0x4c)
Nov 22 10:21:31 raspberrypi kernel: [58868.244367]  r10:0000000b r9:b5e68000 r8:801011c4 r7:0000000b r6:000fcef8 r5:00a34ef8
Nov 22 10:21:31 raspberrypi kernel: [58868.244369]  r4:00a32bf8
Nov 22 10:21:31 raspberrypi kernel: [58868.244375] [<80310e1c>] (sys_execve) from [<80101000>] (ret_fast_syscall+0x0/0x28)
Nov 22 10:21:31 raspberrypi kernel: [58868.244378] Exception stack(0xb5e69fa8 to 0xb5e69ff0)
Nov 22 10:21:31 raspberrypi kernel: [58868.244384] 9fa0:                   001042a0 00a34eb8 00a34eb8 00a32bf8 00a34ef8 c65f0000
Nov 22 10:21:31 raspberrypi kernel: [58868.244389] 9fc0: 001042a0 00a34eb8 000fcef8 0000000b 00a33e50 00a32bf8 00a34ef8 001042a8
Nov 22 10:21:31 raspberrypi kernel: [58868.244393] 9fe0: 76e7e9a0 7ebc5974 000402dc 76e7e9ac
Nov 22 10:21:31 raspberrypi kernel: [58868.244396]  r5:00a34eb8 r4:001042a0
Nov 22 10:21:31 raspberrypi kernel: [58868.244399] Mem-Info:
Nov 22 10:21:31 raspberrypi kernel: [58868.244413] active_anon:98885 inactive_anon:98922 isolated_anon:0
Nov 22 10:21:31 raspberrypi kernel: [58868.244413]  active_file:2478 inactive_file:2525 isolated_file:56
Nov 22 10:21:31 raspberrypi kernel: [58868.244413]  unevictable:4 dirty:11 writeback:0 unstable:0
Nov 22 10:21:31 raspberrypi kernel: [58868.244413]  slab_reclaimable:7143 slab_unreclaimable:7572
Nov 22 10:21:31 raspberrypi kernel: [58868.244413]  mapped:5492 shmem:1221 pagetables:3117 bounce:0
Nov 22 10:21:31 raspberrypi kernel: [58868.244413]  free:8643 free_pcp:1303 free_cma:547
Nov 22 10:21:31 raspberrypi kernel: [58868.244424] Node 0 active_anon:395540kB inactive_anon:395688kB active_file:9912kB inactive_file:10100kB unevictable:16kB isolated(anon):0kB isolated(file):224kB mapped:21968kB dirty:44kB writeback:0kB shmem:4884kB writeback_tmp:0kB unstable:0kB all_unreclaimable? no
Nov 22 10:21:31 raspberrypi kernel: [58868.244437] Normal free:34572kB min:53248kB low:57344kB high:61440kB active_anon:395688kB inactive_anon:395488kB active_file:10680kB inactive_file:10824kB unevictable:16kB writepending:44kB present:970752kB managed:947028kB mlocked:16kB kernel_stack:6240kB pagetables:12468kB bounce:0kB free_pcp:5212kB local_pcp:1308kB free_cma:2188kB
Nov 22 10:21:31 raspberrypi kernel: [58868.244441] lowmem_reserve[]: 0 0
Nov 22 10:21:31 raspberrypi kernel: [58868.244449] Normal: 3676*4kB (UMEC) 1087*8kB (UMEC) 498*16kB (UMEC) 71*32kB (UMEC) 29*64kB (UE) 1*128kB (E) 0*256kB 0*512kB 0*1024kB 0*2048kB 0*4096kB = 35624kB
Nov 22 10:21:31 raspberrypi kernel: [58868.244484] 9635 total pagecache pages
Nov 22 10:21:31 raspberrypi kernel: [58868.244490] 3373 pages in swap cache
Nov 22 10:21:31 raspberrypi kernel: [58868.244495] Swap cache stats: add 28989, delete 25616, find 36095/37520
Nov 22 10:21:31 raspberrypi kernel: [58868.244499] Free swap  = 0kB
Nov 22 10:21:31 raspberrypi kernel: [58868.244503] Total swap = 102396kB
Nov 22 10:21:31 raspberrypi kernel: [58868.244508] 242688 pages RAM
Nov 22 10:21:31 raspberrypi kernel: [58868.244511] 0 pages HighMem/MovableOnly
Nov 22 10:21:31 raspberrypi kernel: [58868.244516] 5931 pages reserved
Nov 22 10:21:31 raspberrypi kernel: [58868.244520] 16384 pages cma reserved
Nov 22 10:21:31 raspberrypi kernel: [58868.244524] Tasks state (memory values in pages):
Nov 22 10:21:31 raspberrypi kernel: [58868.244528] [  pid  ]   uid  tgid total_vm      rss pgtables_bytes swapents oom_score_adj name
Nov 22 10:21:31 raspberrypi kernel: [58868.244556] [    105]     0   105     8314      739    88064      150             0 systemd-journal
Nov 22 10:21:31 raspberrypi kernel: [58868.244564] [    142]     0   142     4625      101    18432      114         -1000 systemd-udevd
Nov 22 10:21:31 raspberrypi kernel: [58868.244575] [    284]   100   284     5603       18    22528      145             0 systemd-timesyn
Nov 22 10:21:31 raspberrypi kernel: [58868.244583] [    324]     0   324     1987       14    14336       32             0 cron
Nov 22 10:21:31 raspberrypi kernel: [58868.244591] [    329] 65534   329     1080        5    14336       39             0 thd
Nov 22 10:21:31 raspberrypi kernel: [58868.244599] [    330]     0   330      923       37    10240       17             0 alsactl
Nov 22 10:21:31 raspberrypi kernel: [58868.244607] [    331]   104   331     1703      138    16384       48          -900 dbus-daemon
Nov 22 10:21:31 raspberrypi kernel: [58868.244615] [    343]   108   343     1538      138    16384       30             0 avahi-daemon
Nov 22 10:21:31 raspberrypi kernel: [58868.244623] [    344]     0   344    16111      544    38912      244             0 udisksd
Nov 22 10:21:31 raspberrypi kernel: [58868.244631] [    349]     0   349     6378      111    22528      103             0 rsyslogd
Nov 22 10:21:31 raspberrypi kernel: [58868.244640] [    351]     0   351     3260       33    20480      142             0 systemd-logind
Nov 22 10:21:31 raspberrypi kernel: [58868.244648] [    352]     0   352     2685       72    18432       88             0 wpa_supplicant
Nov 22 10:21:31 raspberrypi kernel: [58868.244657] [    353]     0   353     6914       23    16384       12             0 rngd
Nov 22 10:21:31 raspberrypi kernel: [58868.244665] [    389]   108   389     1443       14    12288       49             0 avahi-daemon
Nov 22 10:21:31 raspberrypi kernel: [58868.244673] [    399]     0   399      924      313    12288       48             0 dhcpcd
Nov 22 10:21:31 raspberrypi kernel: [58868.244681] [    423]     0   423     8024      120    38912      263             0 nmbd
Nov 22 10:21:31 raspberrypi kernel: [58868.244689] [    429]     0   429     2157        0    16384       56             0 vncserver-x11-s
Nov 22 10:21:31 raspberrypi kernel: [58868.244697] [    431]     0   431    11066        0    45056     1375             0 unattended-upgr
Nov 22 10:21:31 raspberrypi kernel: [58868.244705] [    436]     0   436   273369     1854   129024     2526             0 containerd
Nov 22 10:21:31 raspberrypi kernel: [58868.244713] [    439]   111   439     2190      117    20480       87             0 mosquitto
Nov 22 10:21:31 raspberrypi kernel: [58868.244721] [    440]     0   440     9376       25    26624      138             0 lightdm
Nov 22 10:21:31 raspberrypi kernel: [58868.244728] [    441]     0   441     9491       79    28672       88             0 polkitd
Nov 22 10:21:31 raspberrypi kernel: [58868.244736] [    445]     0   445     9138      371    40960      510             0 vncserver-x11-c
Nov 22 10:21:31 raspberrypi kernel: [58868.244744] [    455]     0   455     2751       61    18432      103             0 wpa_supplicant
Nov 22 10:21:31 raspberrypi kernel: [58868.244752] [    467]     0   467    46481      377   137216     2634             0 Xorg
Nov 22 10:21:31 raspberrypi kernel: [58868.244760] [    468]     0   468     1405        2    16384      111             0 login
Nov 22 10:21:31 raspberrypi kernel: [58868.244767] [    474]     0   474     2681        0    18432      144         -1000 sshd
Nov 22 10:21:31 raspberrypi kernel: [58868.244775] [    562]     0   562      534        1    10240       29             0 hciattach
Nov 22 10:21:31 raspberrypi kernel: [58868.244784] [    566]     0   566     2452       43    18432       60             0 bluetoothd
Nov 22 10:21:31 raspberrypi kernel: [58868.244792] [    569]     0   569     6677        0    24576      115             0 bluealsa
Nov 22 10:21:31 raspberrypi kernel: [58868.244801] [    649]  1000   649     3745      153    26624      156             0 systemd
Nov 22 10:21:31 raspberrypi kernel: [58868.244809] [    650]  1000   650     4213       36    24576      386             0 (sd-pam)
Nov 22 10:21:31 raspberrypi kernel: [58868.244816] [    665]     0   665   271532     4256   155648     3971          -500 dockerd
Nov 22 10:21:31 raspberrypi kernel: [58868.244824] [    691]  1000   691     2123        3    18432      226             0 bash
Nov 22 10:21:31 raspberrypi kernel: [58868.244832] [    756]     0   756     7477       16    26624      156             0 lightdm
Nov 22 10:21:31 raspberrypi kernel: [58868.244840] [    760]  1000   760    13728       55    49152      301             0 lxsession
Nov 22 10:21:31 raspberrypi kernel: [58868.244848] [    768]  1000   768     1636        2    14336      106             0 dbus-daemon
Nov 22 10:21:31 raspberrypi kernel: [58868.244856] [    912]  1000   912     1130       10    12288       62             0 ssh-agent
Nov 22 10:21:31 raspberrypi kernel: [58868.244863] [    970]     0   970     2870        2    16384      117             0 vncagent
Nov 22 10:21:31 raspberrypi kernel: [58868.244871] [    973]  1000   973    10899        2    32768      163             0 gvfsd
Nov 22 10:21:31 raspberrypi kernel: [58868.244879] [    988]     0   988    12120      341    55296      359             0 smbd
Nov 22 10:21:31 raspberrypi kernel: [58868.244887] [    990]  1000   990    13628        0    28672      168             0 gvfsd-fuse
Nov 22 10:21:31 raspberrypi kernel: [58868.244895] [   1005]  1000  1005    15637        0    45056      507             0 openbox
Nov 22 10:21:31 raspberrypi kernel: [58868.244903] [   1009]  1000  1009    11876        2    40960      276             0 lxpolkit
Nov 22 10:21:31 raspberrypi kernel: [58868.244911] [   1011]  1000  1011    37386      615    90112      818             0 lxpanel
Nov 22 10:21:31 raspberrypi kernel: [58868.244919] [   1012]  1000  1012    20424        0    57344      926             0 pcmanfm
Nov 22 10:21:31 raspberrypi kernel: [58868.244927] [   1017]  1000  1017     1130        1    12288       71             0 ssh-agent
Nov 22 10:21:31 raspberrypi kernel: [58868.244935] [   1073]  1000  1073     8006      164    40960      379             0 vncserverui
Nov 22 10:21:31 raspberrypi kernel: [58868.244943] [   1081]     0  1081    11357       58    51200      413             0 smbd-notifyd
Nov 22 10:21:31 raspberrypi kernel: [58868.244951] [   1082]     0  1082    11358       83    49152      390             0 cleanupd
Nov 22 10:21:31 raspberrypi kernel: [58868.244959] [   1084]  1000  1084    19924      193    45056      162             0 gvfs-udisks2-vo
Nov 22 10:21:31 raspberrypi kernel: [58868.244967] [   1087]     0  1087    12088       80    51200      407             0 lpqd
Nov 22 10:21:31 raspberrypi kernel: [58868.244975] [   1090]  1000  1090    10463        0    30720      164             0 gvfs-gphoto2-vo
Nov 22 10:21:31 raspberrypi kernel: [58868.244983] [   1091]  1000  1091     8003      192    38912      263             0 vncserverui
Nov 22 10:21:31 raspberrypi kernel: [58868.244990] [   1095]  1000  1095    10072        0    32768      123             0 gvfs-goa-volume
Nov 22 10:21:31 raspberrypi kernel: [58868.244998] [   1099]  1000  1099    10071        0    30720      135             0 gvfs-mtp-volume
Nov 22 10:21:31 raspberrypi kernel: [58868.245006] [   1106]  1000  1106     6611        2    24576      119             0 menu-cached
Nov 22 10:21:31 raspberrypi kernel: [58868.245014] [   1108]  1000  1108    14053        0    38912      202             0 gvfs-afc-volume
Nov 22 10:21:31 raspberrypi kernel: [58868.245022] [   1116]  1000  1116    13294       99    34816      122             0 gvfsd-trash
Nov 22 10:21:31 raspberrypi kernel: [58868.245030] [   1438]     0  1438      485        2    10240       18             0 hassio-supervis
Nov 22 10:21:31 raspberrypi kernel: [58868.245038] [   1465]     0  1465   233244     2258   100352     2714             0 docker
Nov 22 10:21:31 raspberrypi kernel: [58868.245046] [   1514]     0  1514   199998      223    18432        2             1 containerd-shim
Nov 22 10:21:31 raspberrypi kernel: [58868.245054] [   1552]     0  1552       50        0     6144        4             0 s6-svscan
Nov 22 10:21:31 raspberrypi kernel: [58868.245062] [   1707]     0  1707       50        0     8192        2             0 s6-supervise
Nov 22 10:21:31 raspberrypi kernel: [58868.245070] [   1876]     0  1876      859      107    10240       40         -1000 udevd
Nov 22 10:21:31 raspberrypi kernel: [58868.245078] [   1961]     0  1961       50        0     8192        2             0 s6-supervise
Nov 22 10:21:31 raspberrypi kernel: [58868.245086] [   1962]     0  1962       50        0     6144        2             0 s6-supervise
Nov 22 10:21:31 raspberrypi kernel: [58868.245094] [   1964]     0  1964    23748     7449   102400      179             0 python3
Nov 22 10:21:31 raspberrypi kernel: [58868.245102] [   1966]     0  1966      703      183    12288      120             0 bash
Nov 22 10:21:31 raspberrypi kernel: [58868.245110] [   2114]     0  2114   199998      163    18432       24             1 containerd-shim
Nov 22 10:21:31 raspberrypi kernel: [58868.245117] [   2132]     0  2132       50        1     8192        0             0 s6-svscan
Nov 22 10:21:31 raspberrypi kernel: [58868.245126] [   2244]     0  2244       50        1     6144        0             0 s6-supervise
Nov 22 10:21:31 raspberrypi kernel: [58868.245134] [   2422]     0  2422       50        1     6144        0             0 s6-supervise
Nov 22 10:21:31 raspberrypi kernel: [58868.245141] [   2425]     0  2425   207376     2273    53248        0             0 coredns
Nov 22 10:21:31 raspberrypi kernel: [58868.245149] [   2457]     0  2457   199998      205    18432        0             1 containerd-shim
Nov 22 10:21:31 raspberrypi kernel: [58868.245157] [   2475]     0  2475       50        1     8192        0             0 s6-svscan
Nov 22 10:21:31 raspberrypi kernel: [58868.245165] [   2596]     0  2596       50        1     6144        0             0 s6-supervise
Nov 22 10:21:31 raspberrypi kernel: [58868.245173] [   2818]     0  2818   200174      233    20480        0             1 containerd-shim
Nov 22 10:21:31 raspberrypi kernel: [58868.245181] [   2911]     0  2911       50        1     8192        0             0 s6-svscan
Nov 22 10:21:31 raspberrypi kernel: [58868.245188] [   3085]     0  3085       46        2     4096        0             0 foreground
Nov 22 10:21:31 raspberrypi kernel: [58868.245196] [   3086]     0  3086       50        1     6144        0             0 s6-supervise
Nov 22 10:21:31 raspberrypi kernel: [58868.245204] [   3101]     0  3101       45        1     4096        0             0 foreground
Nov 22 10:21:31 raspberrypi kernel: [58868.245212] [   3259]     0  3259   215539      294    36864        0          -500 docker-proxy
Nov 22 10:21:31 raspberrypi kernel: [58868.245219] [   3302]     0  3302      323        1     6144        0             0 sleep
Nov 22 10:21:31 raspberrypi kernel: [58868.245227] [   3310]     0  3310   199998      216    18432        0             1 containerd-shim
Nov 22 10:21:31 raspberrypi kernel: [58868.245235] [   3617]     0  3617     1979      185    14336        0         -1000 udevd
Nov 22 10:21:31 raspberrypi kernel: [58868.245243] [   3688]     0  3688       50        1     8192        0             0 s6-supervise
Nov 22 10:21:31 raspberrypi kernel: [58868.245250] [   3695]     0  3695    20171      544    24576        0             0 pulseaudio
Nov 22 10:21:31 raspberrypi kernel: [58868.245258] [   3734]     0  3734   200174      174    16384        0             1 containerd-shim
Nov 22 10:21:31 raspberrypi kernel: [58868.245266] [   3768]     0  3768       50        1     8192        0             0 s6-svscan
Nov 22 10:21:31 raspberrypi kernel: [58868.245274] [   3808]     0  3808       50        1     6144        0             0 s6-supervise
Nov 22 10:21:31 raspberrypi kernel: [58868.245282] [   3984]     0  3984       50        1     8192        0             0 s6-supervise
Nov 22 10:21:31 raspberrypi kernel: [58868.245290] [   3988]     0  3988      178       10    12288        0             0 mdns-repeater
Nov 22 10:21:31 raspberrypi kernel: [58868.245298] [   4067]     0  4067   215539      291    43008        0          -500 docker-proxy
Nov 22 10:21:31 raspberrypi kernel: [58868.245306] [   4081]     0  4081   213410      294    34816        0          -500 docker-proxy
Nov 22 10:21:31 raspberrypi kernel: [58868.245314] [   4094]     0  4094   217652      468    43008        0          -500 docker-proxy
Nov 22 10:21:31 raspberrypi kernel: [58868.245322] [   4107]     0  4107   215539      292    43008        0          -500 docker-proxy
Nov 22 10:21:31 raspberrypi kernel: [58868.245330] [   4115]     0  4115   199998      224    20480        0             1 containerd-shim
Nov 22 10:21:31 raspberrypi kernel: [58868.245338] [   4132]     0  4132      179        8     8192        0             0 docker-init
Nov 22 10:21:31 raspberrypi kernel: [58868.245346] [   4213]     0  4213      679      285    10240        0             0 bash
Nov 22 10:21:31 raspberrypi kernel: [58868.245354] [   4297]     0  4297      823       56    10240        0             0 socat
Nov 22 10:21:31 raspberrypi kernel: [58868.245361] [   4298]     0  4298     7597     6775    40960        0             0 mosquitto
Nov 22 10:21:31 raspberrypi kernel: [58868.245369] [   4318]     0  4318   199998      175    18432        0             1 containerd-shim
Nov 22 10:21:31 raspberrypi kernel: [58868.245378] [   4334]     0  4334       50        1     8192        0             0 s6-svscan
Nov 22 10:21:31 raspberrypi kernel: [58868.245386] [   4379]     0  4379       50        1     6144        0             0 s6-supervise
Nov 22 10:21:31 raspberrypi kernel: [58868.245394] [   4596]     0  4596   199998      191    18432        0             1 containerd-shim
Nov 22 10:21:31 raspberrypi kernel: [58868.245402] [   4620]     0  4620      179        8     8192        0             0 docker-init
Nov 22 10:21:31 raspberrypi kernel: [58868.245412] [   4731]     0  4731       50        1     8192        0             0 s6-svscan
Nov 22 10:21:31 raspberrypi kernel: [58868.245420] [   4775]     0  4775       46        2     4096        0             0 foreground
Nov 22 10:21:31 raspberrypi kernel: [58868.245428] [   4776]     0  4776       50        1     6144        0             0 s6-supervise
Nov 22 10:21:31 raspberrypi kernel: [58868.245436] [   4787]     0  4787       45        1     4096        0             0 foreground
Nov 22 10:21:31 raspberrypi kernel: [58868.245444] [   4928]     0  4928      740      338    10240        0             0 bash
Nov 22 10:21:31 raspberrypi kernel: [58868.245453] [   5219]     0  5219       50        1     6144        0             0 s6-supervise
Nov 22 10:21:31 raspberrypi kernel: [58868.245461] [   5221]     0  5221       50        1     8192        0             0 s6-supervise
Nov 22 10:21:31 raspberrypi kernel: [58868.245469] [   5223]     0  5223     8445      412    40960        0             0 smbd
Nov 22 10:21:31 raspberrypi kernel: [58868.245477] [   5224]     0  5224     5508      307    30720        0             0 nmbd
Nov 22 10:21:31 raspberrypi kernel: [58868.245485] [   5253]     0  5253     7991      384    38912        0             0 smbd-notifyd
Nov 22 10:21:31 raspberrypi kernel: [58868.245493] [   5255]     0  5255     7992      384    38912        0             0 cleanupd
Nov 22 10:21:31 raspberrypi kernel: [58868.245501] [   5316]     0  5316   199998      182    18432        0             1 containerd-shim
Nov 22 10:21:31 raspberrypi kernel: [58868.245509] [   5335]     0  5335       50        1     6144        0             0 s6-svscan
Nov 22 10:21:31 raspberrypi kernel: [58868.245517] [   5382]     0  5382       50        1     8192        0             0 s6-supervise
Nov 22 10:21:31 raspberrypi kernel: [58868.245525] [   5542]     0  5542     2931      147    14336        0         -1000 udevd
Nov 22 10:21:31 raspberrypi kernel: [58868.245535] [   5766]     0  5766       50        1     8192        0             0 s6-supervise
Nov 22 10:21:31 raspberrypi kernel: [58868.245543] [   5770]     0  5770   146925    99109   692224        0             0 python3
Nov 22 10:21:31 raspberrypi kernel: [58868.245551] [   7079]     0  7079   199998      187    18432        0             1 containerd-shim
Nov 22 10:21:31 raspberrypi kernel: [58868.245560] [   7097]     0  7097      179        8     6144        0             0 docker-init
Nov 22 10:21:31 raspberrypi kernel: [58868.245568] [   7111]     0  7111       50        1     8192        0             0 s6-svscan
Nov 22 10:21:31 raspberrypi kernel: [58868.245576] [   7145]     0  7145       50        1     6144        0             0 s6-supervise
Nov 22 10:21:31 raspberrypi kernel: [58868.245584] [   7348]     0  7348   200174      191    20480        0             1 containerd-shim
Nov 22 10:21:31 raspberrypi kernel: [58868.245593] [   7394]     0  7394       50        1     8192        0             0 s6-svscan
Nov 22 10:21:31 raspberrypi kernel: [58868.245603] [   7622]     0  7622       50        1     8192        0             0 s6-supervise
Nov 22 10:21:31 raspberrypi kernel: [58868.245612] [   7816]     0  7816       50        1     8192        0             0 s6-supervise
Nov 22 10:21:31 raspberrypi kernel: [58868.245620] [   7818]     0  7818     4739     3023    28672        0             0 hass-configurat
Nov 22 10:21:31 raspberrypi kernel: [58868.245629] [   7888]     0  7888   213234      292    34816        0          -500 docker-proxy
Nov 22 10:21:31 raspberrypi kernel: [58868.245637] [   7909]     0  7909   199998      180    16384        0             1 containerd-shim
Nov 22 10:21:31 raspberrypi kernel: [58868.245645] [   7937]     0  7937      179        8     6144        0             0 docker-init
Nov 22 10:21:31 raspberrypi kernel: [58868.245653] [   8085]     0  8085       50        1     6144        0             0 s6-supervise
Nov 22 10:21:31 raspberrypi kernel: [58868.245664] [   8087]     0  8087       50        1     6144        0             0 s6-supervise
Nov 22 10:21:31 raspberrypi kernel: [58868.245674] [   8088]     0  8088    13615     4048    47104        0             0 esphome
Nov 22 10:21:31 raspberrypi kernel: [58868.245682] [   8090]     0  8090     1464      122    14336        0             0 nginx
Nov 22 10:21:31 raspberrypi kernel: [58868.245690] [   8139]     0  8139     7300     4045    36864        0             0 python3
Nov 22 10:21:31 raspberrypi kernel: [58868.245697] [   8179]     0  8179   199998      190    16384        0             1 containerd-shim
Nov 22 10:21:31 raspberrypi kernel: [58868.245706] [   8196]     0  8196       50        1     6144        0             0 s6-svscan
Nov 22 10:21:31 raspberrypi kernel: [58868.245714] [   8240]     0  8240       50        1     8192        0             0 s6-supervise
Nov 22 10:21:31 raspberrypi kernel: [58868.245722] [   8494]     0  8494   215539      289    40960        0          -500 docker-proxy
Nov 22 10:21:31 raspberrypi kernel: [58868.245730] [   8502]     0  8502   199998      231    16384        0             1 containerd-shim
Nov 22 10:21:31 raspberrypi kernel: [58868.245738] [   8525]     0  8525      179        8     6144        0             0 docker-init
Nov 22 10:21:31 raspberrypi kernel: [58868.245746] [   8615]     0  8615       50        1     8192        0             0 s6-svscan
Nov 22 10:21:31 raspberrypi kernel: [58868.245755] [   8659]     0  8659       46        2     4096        0             0 foreground
Nov 22 10:21:31 raspberrypi kernel: [58868.245763] [   8660]     0  8660       50        1     8192        0             0 s6-supervise
Nov 22 10:21:31 raspberrypi kernel: [58868.245771] [   8673]     0  8673       45        1     4096        0             0 foreground
Nov 22 10:21:31 raspberrypi kernel: [58868.245779] [   8817]     0  8817      435       34    10240        0             0 run.sh
Nov 22 10:21:32 raspberrypi kernel: [58868.245788] [   8855]     0  8855   200174      179    18432        0             1 containerd-shim
Nov 22 10:21:32 raspberrypi kernel: [58868.245797] [   8881]     0  8881      179        8     8192        0             0 docker-init
Nov 22 10:21:32 raspberrypi kernel: [58868.245805] [   8947]     0  8947     1511      163    16384        0             0 nginx
Nov 22 10:21:32 raspberrypi kernel: [58868.245814] [   9008]     0  9008   170549     6992   192512        0             0 dotnet
Nov 22 10:21:32 raspberrypi kernel: [58868.245821] [   9108]     0  9108    32643     5231   190464        0             0 node
Nov 22 10:21:32 raspberrypi kernel: [58868.245829] [   9219]     0  9219    30516     3153   176128        0             0 node
Nov 22 10:21:32 raspberrypi kernel: [58868.245836] [   9303]     0  9303    35016     7683   282624        0             0 node
Nov 22 10:21:32 raspberrypi kernel: [58868.245844] [   9422]     0  9422       50        1     6144        0             0 s6-supervise
Nov 22 10:21:32 raspberrypi kernel: [58868.245852] [   9423]     0  9423       50        1     8192        0             0 s6-supervise
Nov 22 10:21:32 raspberrypi kernel: [58868.245860] [   9426]     0  9426     3820      172    14336        0             0 nginx
Nov 22 10:21:32 raspberrypi kernel: [58868.245868] [   9429]     0  9429    29207     1841   139264        0             0 node
Nov 22 10:21:32 raspberrypi kernel: [58868.245876] [   9490]     0  9490    45108    13451   391168        0             0 node
Nov 22 10:21:32 raspberrypi kernel: [58868.245884] [  10682]     0 10682     3852      172    12288        0             0 nginx
Nov 22 10:21:32 raspberrypi kernel: [58868.245893] [   3639]     0  3639     2084      532    16384        0             0 bash
Nov 22 10:21:32 raspberrypi kernel: [58868.245901] [   3718]     0  3718     2018      138    14336        0             0 bash
Nov 22 10:21:32 raspberrypi kernel: [58868.245909] [   3719]     0  3719     2018      435    14336        0             0 bash
Nov 22 10:21:32 raspberrypi kernel: [58868.245917] [   3720]     0  3720     2060      506    14336        0             0 bash
Nov 22 10:21:32 raspberrypi kernel: [58868.245924] [   3721]     0  3721     2058      573    14336        0             0 bash
Nov 22 10:21:32 raspberrypi kernel: [58868.245932] [   3722]     0  3722     2018      144    14336        0             0 bash
Nov 22 10:21:32 raspberrypi kernel: [58868.245940] [   3723]     0  3723     2051      570    14336        0             0 bash
Nov 22 10:21:32 raspberrypi kernel: [58868.245948] [   3725]     0  3725     2018      138    14336        0             0 bash
Nov 22 10:21:32 raspberrypi kernel: [58868.245956] [   3753]     0  3753     2018      138    12288        0             0 bash
Nov 22 10:21:32 raspberrypi kernel: [58868.245965] [   3755]     0  3755     1258       84    14336        0             0 mosquitto_sub
Nov 22 10:21:32 raspberrypi kernel: [58868.245976] [   8456]     0  8456      323        1     8192        0             0 sleep
Nov 22 10:21:32 raspberrypi kernel: [58868.245984] [   8470]     0  8470   216767     3409    57344        0             1 containerd
Nov 22 10:21:32 raspberrypi kernel: [58868.245992] [   8637]     0  8637     2060      181    12288        0             0 bash
Nov 22 10:21:32 raspberrypi kernel: [58868.246000] [   8646]     0  8646     1682       24    14336        0             0 timeout
Nov 22 10:21:32 raspberrypi kernel: [58868.246008] [   8667]     0  8667      718      185    12288        0             0 btmon
Nov 22 10:21:32 raspberrypi kernel: [58868.246016] [   8712]     0  8712      436       40    10240        0             0 auth_srv.sh
Nov 22 10:21:32 raspberrypi kernel: [58868.246024] [   8736]     0  8736      436       39     6144        0             0 auth_srv.sh
Nov 22 10:21:32 raspberrypi kernel: [58868.246032] [   8753]     0  8753     2058      179    12288        0             0 bash
Nov 22 10:21:32 raspberrypi kernel: [58868.246040] [   8757]     0  8757     1682       23    16384        0             0 timeout
Nov 22 10:21:32 raspberrypi kernel: [58868.246048] [   8762]     0  8762      436       39     8192        0             0 auth_srv.sh
Nov 22 10:21:32 raspberrypi kernel: [58868.246056] [   8768]     0  8768      612      122    10240        0             0 hcidump
Nov 22 10:21:32 raspberrypi kernel: [58868.246064] [   8774]     0  8774      436       37     8192        0             0 auth_srv.sh
Nov 22 10:21:32 raspberrypi kernel: [58868.246072] [   8787]     0  8787      436       29    10240        0             0 auth_srv.sh
Nov 22 10:21:32 raspberrypi kernel: [58868.246081] [   8802]     0  8802     1614       16    14336        0             0 sleep
Nov 22 10:21:32 raspberrypi kernel: [58868.246088] [   8804]     0  8804      436       26     8192        0             0 auth_srv.sh
Nov 22 10:21:32 raspberrypi kernel: [58868.246096] [   8806]     0  8806      469      168    10240        0             0 jq
Nov 22 10:21:32 raspberrypi kernel: [58868.246104] [   8807]     0  8807      469      179    10240        0             0 jq
Nov 22 10:21:32 raspberrypi kernel: [58868.246111] [   8808]     0  8808      469      171    10240        0             0 jq
Nov 22 10:21:32 raspberrypi kernel: [58868.246119] [   8809]     0  8809      463      164     8192        0             0 jq
Nov 22 10:21:32 raspberrypi kernel: [58868.246127] [   8810]     0  8810      316        1     8192        0             0 date
Nov 22 10:21:32 raspberrypi kernel: [58868.246134] [   8811]     0  8811      469      185     8192        0             0 jq
Nov 22 10:21:32 raspberrypi kernel: [58868.246143] [   8818]     0  8818      828       56    10240        0             0 socat
Nov 22 10:21:32 raspberrypi kernel: [58868.246152] [   8819]     0  8819      823       56     8192        0             0 socat
Nov 22 10:21:32 raspberrypi kernel: [58868.246161] [   8820]     0  8820      290        1     6144        0             0 auth_srv.sh
Nov 22 10:21:32 raspberrypi kernel: [58868.246168] [   8821]     0  8821      208        1     6144        0             0 jq
Nov 22 10:21:32 raspberrypi kernel: [58868.246176] [   8822]     0  8822     2018      374    12288        0             0 bash
Nov 22 10:21:32 raspberrypi kernel: [58868.246184] [   8824]     0  8824     2018      413    12288        0             0 bash
Nov 22 10:21:32 raspberrypi kernel: [58868.246191] [   8826]     0  8826      117       57     8192        0             0 date
Nov 22 10:21:32 raspberrypi kernel: [58868.246199] [   8827]     0  8827     2058      466    12288        0             0 bash
Nov 22 10:21:32 raspberrypi kernel: [58868.246207] [   8829]     0  8829     2058      179    10240        0             0 bash
Nov 22 10:21:32 raspberrypi kernel: [58868.246215] [   8830]     0  8830      113       57     8192        0             0 bc
Nov 22 10:21:32 raspberrypi kernel: [58868.246221] oom-kill:constraint=CONSTRAINT_NONE,nodemask=(null),cpuset=/,mems_allowed=0,global_oom,task_memcg=/,task=python3,pid=5770,uid=0
Nov 22 10:21:32 raspberrypi kernel: [58868.246412] Out of memory: Killed process 5770 (python3) total-vm:587700kB, anon-rss:396436kB, file-rss:0kB, shmem-rss:0kB, UID:0 pgtables:676kB oom_score_adj:0
Nov 22 10:21:32 raspberrypi kernel: [58868.410100] oom_reaper: reaped process 5770 (python3), now anon-rss:0kB, file-rss:0kB, shmem-rss:0kB
Nov 22 10:21:40 raspberrypi containerd[436]: time="2020-11-22T10:21:40.432328106+02:00" level=info msg="shim reaped" id=1c880931ddeceffe12ad318d356fd57f22981a9a8b6b2d158a1e9c49b1254035
Nov 22 10:21:40 raspberrypi dockerd[665]: time="2020-11-22T10:21:40.486658115+02:00" level=info msg="ignoring event" module=libcontainerd namespace=moby topic=/tasks/delete type="*events.TaskDelete"
Nov 22 10:21:40 raspberrypi kernel: [58881.157611] vetha9f0bdc: renamed from eth0
Nov 22 10:21:41 raspberrypi dhcpcd[399]: vethefcf55f: carrier lost
Nov 22 10:21:41 raspberrypi kernel: [58881.202273] hassio: port 5(vethefcf55f) entered disabled state
Nov 22 10:21:41 raspberrypi dhcpcd[399]: vethefcf55f: deleting address fe80::5bea:41d9:7849:3606
Nov 22 10:21:41 raspberrypi avahi-daemon[343]: Withdrawing address record for fe80::5bea:41d9:7849:3606 on vethefcf55f.
Nov 22 10:21:41 raspberrypi avahi-daemon[343]: Leaving mDNS multicast group on interface vethefcf55f.IPv6 with address fe80::5bea:41d9:7849:3606.
Nov 22 10:21:41 raspberrypi avahi-daemon[343]: Interface vethefcf55f.IPv6 no longer relevant for mDNS.
Nov 22 10:21:41 raspberrypi avahi-daemon[343]: Withdrawing address record for 169.254.3.180 on vethefcf55f.
Nov 22 10:21:41 raspberrypi avahi-daemon[343]: Leaving mDNS multicast group on interface vethefcf55f.IPv4 with address 169.254.3.180.
Nov 22 10:21:41 raspberrypi avahi-daemon[343]: Interface vethefcf55f.IPv4 no longer relevant for mDNS.
Nov 22 10:21:41 raspberrypi dhcpcd[399]: vethefcf55f: deleting route to 169.254.0.0/16
Nov 22 10:21:41 raspberrypi kernel: [58881.519174] hassio: port 5(vethefcf55f) entered disabled state
Nov 22 10:21:41 raspberrypi kernel: [58881.539602] device vethefcf55f left promiscuous mode
Nov 22 10:21:41 raspberrypi kernel: [58881.539620] hassio: port 5(vethefcf55f) entered disabled state
Nov 22 10:21:41 raspberrypi systemd[1]: run-docker-netns-17329f311b72.mount: Succeeded.
Nov 22 10:21:41 raspberrypi systemd[649]: run-docker-netns-17329f311b72.mount: Succeeded.
Nov 22 10:21:42 raspberrypi systemd[649]: var-lib-docker-overlay2-4d57c4f542ebd3a02b6b9ea70d852562395b82d6fa1d967ec86e8e4721e4452e-merged.mount: Succeeded.
Nov 22 10:21:42 raspberrypi systemd[1]: var-lib-docker-overlay2-4d57c4f542ebd3a02b6b9ea70d852562395b82d6fa1d967ec86e8e4721e4452e-merged.mount: Succeeded.
Nov 22 10:21:42 raspberrypi dhcpcd[399]: vethefcf55f: removing interface
Nov 22 10:21:42 raspberrypi hassio-supervisor[1438]: #033[32m20-11-22 08:21:42 INFO (SyncWorker_4) [supervisor.docker.interface] Cleaning hassio_observer application#033[0m
Nov 22 10:21:42 raspberrypi hassio-supervisor[1438]: #033[32m20-11-22 08:21:42 INFO (MainThread) [supervisor.plugins.observer] Starting observer plugin#033[0m
Nov 22 10:21:43 raspberrypi systemd[1]: var-lib-docker-overlay2-82c2a13763d4300916cc33f79769191819bd1fc379e3a9b63d80c7791a94167a\x2dinit-merged.mount: Succeeded.
Nov 22 10:21:43 raspberrypi systemd[649]: var-lib-docker-overlay2-82c2a13763d4300916cc33f79769191819bd1fc379e3a9b63d80c7791a94167a\x2dinit-merged.mount: Succeeded.
Nov 22 10:21:43 raspberrypi systemd[1]: var-lib-docker-overlay2-82c2a13763d4300916cc33f79769191819bd1fc379e3a9b63d80c7791a94167a-merged.mount: Succeeded.
Nov 22 10:21:43 raspberrypi systemd[649]: var-lib-docker-overlay2-82c2a13763d4300916cc33f79769191819bd1fc379e3a9b63d80c7791a94167a-merged.mount: Succeeded.
Nov 22 10:21:43 raspberrypi kernel: [58883.755611] hassio: port 5(vethd2f2101) entered blocking state
Nov 22 10:21:43 raspberrypi kernel: [58883.755621] hassio: port 5(vethd2f2101) entered disabled state
Nov 22 10:21:43 raspberrypi kernel: [58883.755870] device vethd2f2101 entered promiscuous mode
Nov 22 10:21:43 raspberrypi dhcpcd[399]: vethd2f2101: waiting for carrier
Nov 22 10:21:43 raspberrypi dhcpcd[399]: veth25c2b90: waiting for carrier
Nov 22 10:21:43 raspberrypi kernel: [58883.934582] IPv6: ADDRCONF(NETDEV_CHANGE): veth25c2b90: link becomes ready
Nov 22 10:21:43 raspberrypi kernel: [58883.934824] IPv6: ADDRCONF(NETDEV_CHANGE): vethd2f2101: link becomes ready
Nov 22 10:21:43 raspberrypi kernel: [58883.934910] hassio: port 5(vethd2f2101) entered blocking state
Nov 22 10:21:43 raspberrypi kernel: [58883.934918] hassio: port 5(vethd2f2101) entered forwarding state
Nov 22 10:21:43 raspberrypi dhcpcd[399]: vethd2f2101: waiting for carrier
Nov 22 10:21:43 raspberrypi dhcpcd[399]: veth25c2b90: waiting for carrier
Nov 22 10:21:43 raspberrypi dhcpcd[399]: veth25c2b90: carrier acquired
Nov 22 10:21:43 raspberrypi dhcpcd[399]: veth25c2b90: IAID db:d5:78:e9
Nov 22 10:21:43 raspberrypi dhcpcd[399]: veth25c2b90: adding address fe80::414c:c218:5bbb:312a
Nov 22 10:21:43 raspberrypi avahi-daemon[343]: Joining mDNS multicast group on interface veth25c2b90.IPv6 with address fe80::414c:c218:5bbb:312a.
Nov 22 10:21:43 raspberrypi avahi-daemon[343]: New relevant interface veth25c2b90.IPv6 for mDNS.
Nov 22 10:21:43 raspberrypi avahi-daemon[343]: Registering new address record for fe80::414c:c218:5bbb:312a on veth25c2b90.*.
Nov 22 10:21:43 raspberrypi dhcpcd[399]: vethd2f2101: carrier acquired
Nov 22 10:21:43 raspberrypi dhcpcd[399]: vethd2f2101: IAID f3:f8:08:af
Nov 22 10:21:43 raspberrypi dhcpcd[399]: vethd2f2101: adding address fe80::76a0:aee6:aa0c:a22c
Nov 22 10:21:43 raspberrypi avahi-daemon[343]: Joining mDNS multicast group on interface vethd2f2101.IPv6 with address fe80::76a0:aee6:aa0c:a22c.
Nov 22 10:21:43 raspberrypi avahi-daemon[343]: New relevant interface vethd2f2101.IPv6 for mDNS.
Nov 22 10:21:43 raspberrypi avahi-daemon[343]: Registering new address record for fe80::76a0:aee6:aa0c:a22c on vethd2f2101.*.
Nov 22 10:21:44 raspberrypi dhcpcd[399]: vethd2f2101: soliciting an IPv6 router
Nov 22 10:21:44 raspberrypi containerd[436]: time="2020-11-22T10:21:44.156874268+02:00" level=info msg="shim containerd-shim started" address=/containerd-shim/b9a9a67915e3848a9139704560c281fe645852893e76b54db18639a72f29e4de.sock debug=false pid=10308
Nov 22 10:21:44 raspberrypi systemd[649]: run-docker-runtime\x2drunc-moby-9b3746fb1d99f5a4fbe60026f8d8fa12d9f1c7158d07f09fbc5a46454c9d17cd-runc.yuCD0w.mount: Succeeded.
Nov 22 10:21:44 raspberrypi systemd[1]: run-docker-runtime\x2drunc-moby-9b3746fb1d99f5a4fbe60026f8d8fa12d9f1c7158d07f09fbc5a46454c9d17cd-runc.yuCD0w.mount: Succeeded.
Nov 22 10:21:44 raspberrypi dhcpcd[399]: veth25c2b90: soliciting an IPv6 router
Nov 22 10:21:44 raspberrypi dhcpcd[399]: vethd2f2101: soliciting a DHCP lease
Nov 22 10:21:44 raspberrypi dhcpcd[399]: veth25c2b90: soliciting a DHCP lease
Nov 22 10:21:49 raspberrypi dhcpcd[399]: vethd2f2101: probing for an IPv4LL address
Nov 22 10:21:49 raspberrypi dhcpcd[399]: veth25c2b90: probing for an IPv4LL address
Nov 22 10:21:50 raspberrypi dhcpcd[399]: veth25c2b90: carrier lost
Nov 22 10:21:50 raspberrypi avahi-daemon[343]: Interface veth25c2b90.IPv6 no longer relevant for mDNS.
Nov 22 10:21:50 raspberrypi avahi-daemon[343]: Leaving mDNS multicast group on interface veth25c2b90.IPv6 with address fe80::414c:c218:5bbb:312a.
Nov 22 10:21:50 raspberrypi dhcpcd[399]: veth25c2b90: deleting address fe80::414c:c218:5bbb:312a
Nov 22 10:21:50 raspberrypi avahi-daemon[343]: Withdrawing address record for fe80::414c:c218:5bbb:312a on veth25c2b90.
Nov 22 10:21:50 raspberrypi kernel: [58890.267680] hassio: port 5(vethd2f2101) entered disabled state
Nov 22 10:21:50 raspberrypi kernel: [58890.269256] eth0: renamed from veth25c2b90
Nov 22 10:21:50 raspberrypi kernel: [58890.337062] hassio: port 5(vethd2f2101) entered blocking state
Nov 22 10:21:50 raspberrypi kernel: [58890.337073] hassio: port 5(vethd2f2101) entered forwarding state
Nov 22 10:21:50 raspberrypi dhcpcd[399]: veth25c2b90: removing interface
Nov 22 10:21:50 raspberrypi containerd[436]: time="2020-11-22T10:21:50.263380877+02:00" level=warning msg="OOM monitoring failed" error="cgroups: memory cgroup not supported on this system"
Nov 22 10:21:50 raspberrypi dhcpcd[399]: vethd2f2101: carrier lost
Nov 22 10:21:50 raspberrypi dhcpcd[399]: vethd2f2101: deleting address fe80::76a0:aee6:aa0c:a22c
Nov 22 10:21:50 raspberrypi avahi-daemon[343]: Withdrawing address record for fe80::76a0:aee6:aa0c:a22c on vethd2f2101.
Nov 22 10:21:50 raspberrypi avahi-daemon[343]: Leaving mDNS multicast group on interface vethd2f2101.IPv6 with address fe80::76a0:aee6:aa0c:a22c.
Nov 22 10:21:50 raspberrypi avahi-daemon[343]: Interface vethd2f2101.IPv6 no longer relevant for mDNS.
Nov 22 10:21:50 raspberrypi hassio-supervisor[1438]: #033[32m20-11-22 08:21:50 INFO (SyncWorker_0) [supervisor.docker.observer] Starting Observer homeassistant/armv7-hassio-observer with version 2020.10.1 - 172.30.32.6#033[0m
Nov 22 10:21:50 raspberrypi dhcpcd[399]: vethd2f2101: carrier acquired
Nov 22 10:21:50 raspberrypi dhcpcd[399]: vethd2f2101: IAID f3:f8:08:af
Nov 22 10:21:50 raspberrypi dhcpcd[399]: vethd2f2101: adding address fe80::76a0:aee6:aa0c:a22c
Nov 22 10:21:50 raspberrypi avahi-daemon[343]: Joining mDNS multicast group on interface vethd2f2101.IPv6 with address fe80::76a0:aee6:aa0c:a22c.
Nov 22 10:21:50 raspberrypi avahi-daemon[343]: New relevant interface vethd2f2101.IPv6 for mDNS.
Nov 22 10:21:50 raspberrypi avahi-daemon[343]: Registering new address record for fe80::76a0:aee6:aa0c:a22c on vethd2f2101.*.
Nov 22 10:21:50 raspberrypi dhcpcd[399]: vethd2f2101: soliciting a DHCP lease
Nov 22 10:21:50 raspberrypi dhcpcd[399]: vethd2f2101: soliciting an IPv6 router
Nov 22 10:21:55 raspberrypi dhcpcd[399]: vethd2f2101: probing for an IPv4LL address
Nov 22 10:22:00 raspberrypi dhcpcd[399]: vethd2f2101: using IPv4LL address 169.254.237.104
Nov 22 10:22:00 raspberrypi avahi-daemon[343]: Joining mDNS multicast group on interface vethd2f2101.IPv4 with address 169.254.237.104.
Nov 22 10:22:00 raspberrypi avahi-daemon[343]: New relevant interface vethd2f2101.IPv4 for mDNS.
Nov 22 10:22:00 raspberrypi avahi-daemon[343]: Registering new address record for 169.254.237.104 on vethd2f2101.IPv4.
Nov 22 10:22:00 raspberrypi dhcpcd[399]: vethd2f2101: adding route to 169.254.0.0/16
Nov 22 10:22:03 raspberrypi dhcpcd[399]: vethd2f2101: no IPv6 Routers available
Nov 22 10:23:00 raspberrypi hassio-supervisor[1438]: #033[32m20-11-22 08:23:00 INFO (MainThread) [supervisor.api.proxy] Home Assistant WebSocket API request initialize#033[0m
Nov 22 10:23:00 raspberrypi hassio-supervisor[1438]: #033[32m20-11-22 08:23:00 INFO (MainThread) [supervisor.api.proxy] WebSocket access from a0d7b954_nodered#033[0m
ciotlosm commented 3 years ago

I recommend trying without zigbee2mqttassistant and using the built-in frontend. Also you could try: https://github.com/zigbee2mqtt/hassio-zigbee2mqtt that uses automatic MQTT discovery and see if that works on restarts.

ivanovd commented 3 years ago

I uninstalled zigbee2mqttassistant and that didn't make a difference. It still crashes with OOM at least once in every 24 hours:

[58868.246215] [   8830]     0  8830      113       57     8192        0             0 bc
[58868.246221] oom-kill:constraint=CONSTRAINT_NONE,nodemask=(null),cpuset=/,mems_allowed=0,global_oom,task_memcg=/,task=python3,pid=5770,uid=0
[58868.246412] Out of memory: Killed process 5770 (python3) total-vm:587700kB, anon-rss:396436kB, file-rss:0kB, shmem-rss:0kB, UID:0 pgtables:676kB oom_score_adj:0
[58868.410100] oom_reaper: reaped process 5770 (python3), now anon-rss:0kB, file-rss:0kB, shmem-rss:0kB

I am wondering what can be causing this? MQTT discovery is just a workaround. I'd like to understand why having a lot of ZigBee traffic crashes the home assistant observer.

Any ideas where should I start troubleshooting from?

ciotlosm commented 3 years ago

You would probably get better support if you posted here: https://github.com/koenkk/zigbee2mqtt You could try to setup some parameters to monitor your processes and save those in some influx or so to see how the graphs look when memory is being consumed.

I have no such problems using z2m with more than 20 devices but I'm using HassOS (the beta on RPI4 with USB boot, from SSD).

ivanovd commented 3 years ago

Yeah, the issue is with RPi3b+ which has only 1 GB of RAM. However, RAM shouldn't be an issue since most of the time the RAM is 99% free and the CPU usage is at 2-3%

Screenshot 2020-11-23 151155
ciotlosm commented 3 years ago

Interesting. I've been running my setup on a RPi3b with the same number of devices as on the RPI4 with no problems, but might have switched before the frontend was added to z2m. Could you try with the frontend disabled and see how that behaves?

ivanovd commented 3 years ago

Okay, I did some troubleshooting. And noticed that the HA frontend reports the RAM usage of the container, not the host. The RAM usage of the host (Raspbian) is about 80-85% and the SWAP was at 100%. I am experimenting now with the size of the swap (I'm on SSD) and will see what's the correlation between the crashes of the observer and the ram/swap usage.

I will keep you updated. Thanks for the ideas and the input.

ivanovd commented 3 years ago

I have some progress to report. It's been running for more than 20 hours straight with no OOM errors and no hangs. RAM usage is between 65% and 80% and the SWAP is almost always full with some 5% drops from time to time.

Here is what I did:

In the future if I see spikes in RAM usage I will increase the SWAP size to 1 GB instead of the default 100 MB. In my case that is safe since I use SSD HDD, but I do not recommend that for SD Card users.

I think that from 0.106 onwards HA became more memory hungry and therefore the OOM errors, since the default SWAP of Raspbian is only 100 MB. I have found similar problems on other forums with a lot of other users experiencing the same scenario after ver. 0.116.