home-assistant / addons

:heavy_plus_sign: Docker add-ons for Home Assistant
https://home-assistant.io/hassio/
Apache License 2.0
1.51k stars 1.46k forks source link

Mosquito Broker and MQTT Integrations autodiscovery just works one time for all devices #2181

Closed gmlupatelli closed 2 years ago

gmlupatelli commented 2 years ago

The problem

MQTT integration just autodiscover new devices one time, for any client device. If client devices get rebooted all its entities will be unavailable forever.

This first happened when I update to core-2021.9.1. I had one ESP8266 led strip that was running FASTLedand and used MQTT to integrate with Home Assistant. It used to work flawlessly but after the update, the device and its entities became unavailable and never come back, no matter home many times I rebooted it, so I thought it was a client software issue and migrated it to ESPHome, and installed MQTT as I had no use for it.

After a few days I was working in integrating my 3D Printer with Home Assistant and one of the plugins that I am using just exposes itself via MQTT, so I installed the MQQT client in my Octoprint installation and reinstalled Mosquito Broker and MQTT Integration, using all the defaults.

Just after the installation, everything worked fine and I managed to get all the exposed Octoprint entities autodiscovered in Home Assistant, however, after I rebooted Octoprint (I just rebooted it without changing anything in it, I actually sent the wrong MQTT command that shutdown Octoprint while trying to just shut down the printer PSU), the related Home Assistant entities never became available again.

What I did to try to fix this issue: First I tried removing the MQTT Integration, rebooting Home Assistant, removing Mosquito Broker Add-On, rebooting Home Assistant again, and reinstalling everything again, but nothing changed.

Second I tried to reconfigure the Home Assistant Integration using a different user and password. I used a user that I created called mqttuser, however, nothing changed, even after restarting the server. Then I tried making this user a an administrator and once again restarting home assistant, however, nothing changed.

Last I tried adding my user to the Mosquito Broker configuration, see bellow, and nothing change, even after restarting Home Assistant.

Environment

System Health

version core-2021.9.6
installation_type Home Assistant OS
dev false
hassio true
docker true
user root
virtualenv false
python_version 3.9.6
os_name Linux
os_version 5.10.17-v8
arch aarch64
timezone America/Toronto
Home Assistant Community Store GitHub API | ok -- | -- Github API Calls Remaining | 4921 Installed Version | 1.15.2 Stage | running Available Repositories | 881 Installed Repositories | 12
Home Assistant Cloud logged_in | true -- | -- subscription_expiration | October 9, 2021, 8:00 PM relayer_connected | true remote_enabled | true remote_connected | true alexa_enabled | false google_enabled | true can_reach_cert_server | ok can_reach_cloud_auth | ok can_reach_cloud | ok
Home Assistant Supervisor host_os | Home Assistant OS 6.3 -- | -- update_channel | stable supervisor_version | supervisor-2021.09.0 docker_version | 20.10.7 disk_total | 58.0 GB disk_used | 8.7 GB healthy | true supported | true board | rpi4-64 supervisor_api | ok version_api | ok installed_addons | File editor (5.3.3), Terminal & SSH (9.1.3), Samba share (9.5.1), Network UPS Tools (0.8.0), Z-Wave JS (0.1.40), Check Home Assistant configuration (3.8.0), Home Assistant Google Drive Backup (0.105.1), ESPHome (2021.8.2), AirCast (3.1.1), Mosquitto broker (6.0.1)
Lovelace dashboards | 1 -- | -- resources | 7 views | 4 mode | storage
Spotify api_endpoint_reachable | ok -- | --

Problem-relevant configuration

Current Mosquito Broker Configuration:

logins: []
customize:
  active: false
  folder: mosquitto
certfile: fullchain.pem
keyfile: privkey.pem
require_certificate: false

Alternative Mosquito Broker Configuration that I tried:

logins:
  - username: mqttuser
    password: <super secure password>
customize:
  active: false
  folder: mosquitto
certfile: fullchain.pem
keyfile: privkey.pem
require_certificate: false

Current MQTT Integration configuration: image image

Alternative MQTT Integration configuration that I tried with the alternative Mosquito Broker Configuration: image The second screen configuration remained the same as the above.

Traceback/Error logs

Mosquito Broker Log

[s6-init] making user provided files available at /var/run/s6/etc...exited 0.
[s6-init] ensuring user provided files have correct perms...exited 0.
[fix-attrs.d] applying ownership & permissions fixes...
[fix-attrs.d] done.
[cont-init.d] executing container initialization scripts...
[cont-init.d] mosquitto.sh: executing... 
[10:45:26] INFO: Certificates found: SSL is available
[cont-init.d] mosquitto.sh: exited 0.
[cont-init.d] nginx.sh: executing... 
[cont-init.d] nginx.sh: exited 0.
[cont-init.d] done.
[services.d] starting services
[services.d] done.
[10:45:27] INFO: Starting NGINX for authentication handling...
[10:45:27] INFO: Starting mosquitto MQTT broker...
1631630727: mosquitto version 1.6.12 starting
1631630727: |-- *** auth-plug: startup
[10:45:28] INFO: Successfully send discovery information to Home Assistant.
1631630727: Config loaded from /etc/mosquitto/mosquitto.conf.
1631630727: Loading plugin: /usr/share/mosquitto/auth-plug.so
1631630727:  ├── Username/password checking enabled.
1631630727:  ├── TLS-PSK checking enabled.
1631630727:  └── Extended authentication not enabled.
1631630727: Opening ipv4 listen socket on port 1883.
1631630727: Opening ipv6 listen socket on port 1883.
1631630727: Opening websockets listen socket on port 1884.
1631630728: Opening ipv4 listen socket on port 8883.
1631630728: Opening ipv6 listen socket on port 8883.
1631630728: Opening websockets listen socket on port 8884.
1631630728: Warning: Mosquitto should not be run as root/administrator.
1631630728: mosquitto version 1.6.12 running
1631630728: New connection from 127.0.0.1 on port 1883.
1631630728: Socket error on client <unknown>, disconnecting.
1631630728: New connection from 172.30.32.1 on port 1883.
1631630728: New client connected from 172.30.32.1 as 0lbm8Tw6mfdrjZC5JBy79w (p2, c1, k60, u'homeassistant').
[10:45:29] INFO: Successfully send service information to the Supervisor.

MQTT Explorer This is the whole output of MQTT Explorer image [Update] For some reason when I turned MQTT logging on ad restarted the server, now I get the octoprint client device in MQTT Explorer

The MQTT client device IP is 192.168.86.202 (octoprint) appears in MQTT Explorer but not in Home Assistant. image

Supervisor logs:

Expand full log: ``` 21-09-14 08:01:17 INFO (MainThread) [supervisor.resolution.check] System checks complete 21-09-14 08:01:17 INFO (MainThread) [supervisor.resolution.evaluate] Starting system evaluation with state CoreState.RUNNING 21-09-14 08:01:17 INFO (MainThread) [supervisor.resolution.evaluate] System evaluation complete 21-09-14 08:01:17 INFO (MainThread) [supervisor.resolution.fixup] Starting system autofix at state CoreState.RUNNING 21-09-14 08:01:17 INFO (MainThread) [supervisor.resolution.fixup] System autofix complete 21-09-14 08:04:16 INFO (MainThread) [supervisor.homeassistant.api] Updated Home Assistant API token 21-09-14 08:34:16 INFO (MainThread) [supervisor.homeassistant.api] Updated Home Assistant API token 21-09-14 08:43:52 INFO (MainThread) [supervisor.host.info] Updating local host information 21-09-14 08:43:53 INFO (MainThread) [supervisor.host.services] Updating service information 21-09-14 08:43:53 INFO (MainThread) [supervisor.host.network] Updating local network information 21-09-14 08:43:56 INFO (MainThread) [supervisor.host.sound] Updating PulseAudio information 21-09-14 08:43:56 INFO (MainThread) [supervisor.host.manager] Host information reload completed 21-09-14 09:01:08 INFO (MainThread) [supervisor.api.middleware.security] /supervisor/info access from cebe7a76_hassio_google_drive_backup 21-09-14 09:01:08 INFO (MainThread) [supervisor.api.middleware.security] /backups access from cebe7a76_hassio_google_drive_backup 21-09-14 09:01:17 INFO (MainThread) [supervisor.resolution.check] Starting system checks with state CoreState.RUNNING 21-09-14 09:01:17 INFO (MainThread) [supervisor.resolution.checks.base] Run check for IssueType.SECURITY/ContextType.CORE 21-09-14 09:01:17 INFO (MainThread) [supervisor.resolution.checks.base] Run check for IssueType.TRUST/ContextType.PLUGIN 21-09-14 09:01:17 INFO (MainThread) [supervisor.resolution.checks.base] Run check for IssueType.TRUST/ContextType.CORE 21-09-14 09:01:17 INFO (MainThread) [supervisor.resolution.checks.base] Run check for IssueType.TRUST/ContextType.SUPERVISOR 21-09-14 09:01:18 INFO (MainThread) [supervisor.resolution.checks.base] Run check for IssueType.PWNED/ContextType.ADDON 21-09-14 09:01:18 INFO (MainThread) [supervisor.resolution.checks.base] Run check for IssueType.FREE_SPACE/ContextType.SYSTEM 21-09-14 09:01:18 INFO (MainThread) [supervisor.resolution.check] System checks complete 21-09-14 09:01:18 INFO (MainThread) [supervisor.resolution.evaluate] Starting system evaluation with state CoreState.RUNNING 21-09-14 09:01:18 INFO (MainThread) [supervisor.resolution.evaluate] System evaluation complete 21-09-14 09:01:18 INFO (MainThread) [supervisor.resolution.fixup] Starting system autofix at state CoreState.RUNNING 21-09-14 09:01:18 INFO (MainThread) [supervisor.resolution.fixup] System autofix complete 21-09-14 09:03:03 INFO (MainThread) [supervisor.updater] Fetching update data from https://version.home-assistant.io/stable.json 21-09-14 09:04:17 INFO (MainThread) [supervisor.homeassistant.api] Updated Home Assistant API token 21-09-14 09:34:17 INFO (MainThread) [supervisor.homeassistant.api] Updated Home Assistant API token 21-09-14 10:01:10 INFO (MainThread) [supervisor.api.middleware.security] /supervisor/info access from cebe7a76_hassio_google_drive_backup 21-09-14 10:01:10 INFO (MainThread) [supervisor.api.middleware.security] /backups access from cebe7a76_hassio_google_drive_backup 21-09-14 10:01:18 INFO (MainThread) [supervisor.resolution.check] Starting system checks with state CoreState.RUNNING 21-09-14 10:01:18 INFO (MainThread) [supervisor.resolution.checks.base] Run check for IssueType.SECURITY/ContextType.CORE 21-09-14 10:01:18 INFO (MainThread) [supervisor.resolution.checks.base] Run check for IssueType.TRUST/ContextType.PLUGIN 21-09-14 10:01:18 INFO (MainThread) [supervisor.resolution.checks.base] Run check for IssueType.TRUST/ContextType.CORE 21-09-14 10:01:18 INFO (MainThread) [supervisor.resolution.checks.base] Run check for IssueType.TRUST/ContextType.SUPERVISOR 21-09-14 10:01:18 INFO (MainThread) [supervisor.resolution.checks.base] Run check for IssueType.PWNED/ContextType.ADDON 21-09-14 10:01:18 INFO (MainThread) [supervisor.resolution.checks.base] Run check for IssueType.FREE_SPACE/ContextType.SYSTEM 21-09-14 10:01:18 INFO (MainThread) [supervisor.resolution.check] System checks complete 21-09-14 10:01:18 INFO (MainThread) [supervisor.resolution.evaluate] Starting system evaluation with state CoreState.RUNNING 21-09-14 10:01:18 INFO (MainThread) [supervisor.resolution.evaluate] System evaluation complete 21-09-14 10:01:18 INFO (MainThread) [supervisor.resolution.fixup] Starting system autofix at state CoreState.RUNNING 21-09-14 10:01:18 INFO (MainThread) [supervisor.resolution.fixup] System autofix complete 21-09-14 10:03:28 INFO (MainThread) [supervisor.store.git] Update add-on https://github.com/hassio-addons/repository repository 21-09-14 10:03:28 INFO (MainThread) [supervisor.store.git] Update add-on https://github.com/sabeechen/hassio-google-drive-backup repository 21-09-14 10:03:28 INFO (MainThread) [supervisor.store.git] Update add-on https://github.com/home-assistant/addons repository 21-09-14 10:03:29 WARNING (MainThread) [supervisor.addons.validate] Add-on config 'snapshot_exclude' is deprecated, 'backup_exclude' should be used instead. Please report this to the maintainer of AdGuard Home 21-09-14 10:03:29 WARNING (MainThread) [supervisor.addons.validate] Add-on config 'snapshot_exclude' is deprecated, 'backup_exclude' should be used instead. Please report this to the maintainer of ESPHome 21-09-14 10:03:29 WARNING (MainThread) [supervisor.addons.validate] Add-on config 'snapshot' is deprecated, 'backup' should be used instead. Please report this to the maintainer of UniFi Controller 21-09-14 10:03:30 WARNING (MainThread) [supervisor.addons.validate] Add-on config 'snapshot_exclude' is deprecated, 'backup_exclude' should be used instead. Please report this to the maintainer of AdGuard Home 21-09-14 10:03:30 WARNING (MainThread) [supervisor.addons.validate] Add-on config 'snapshot_exclude' is deprecated, 'backup_exclude' should be used instead. Please report this to the maintainer of ESPHome 21-09-14 10:03:30 WARNING (MainThread) [supervisor.addons.validate] Add-on config 'snapshot' is deprecated, 'backup' should be used instead. Please report this to the maintainer of UniFi Controller 21-09-14 10:03:30 INFO (MainThread) [supervisor.store] Loading add-ons from store: 64 all - 0 new - 0 remove 21-09-14 10:03:30 INFO (MainThread) [supervisor.store] Loading add-ons from store: 64 all - 0 new - 0 remove 21-09-14 10:04:18 INFO (MainThread) [supervisor.homeassistant.api] Updated Home Assistant API token 21-09-14 10:26:16 INFO (SyncWorker_3) [supervisor.docker.interface] Restarting ghcr.io/home-assistant/raspberrypi4-64-homeassistant 21-09-14 10:26:27 INFO (MainThread) [supervisor.homeassistant.core] Wait until Home Assistant is ready 21-09-14 10:26:36 INFO (MainThread) [supervisor.resolution.evaluate] Starting system evaluation with state CoreState.RUNNING 21-09-14 10:26:36 INFO (MainThread) [supervisor.resolution.evaluate] System evaluation complete 21-09-14 10:27:22 INFO (MainThread) [supervisor.homeassistant.core] Detect a running Home Assistant instance 21-09-14 10:28:34 INFO (MainThread) [supervisor.auth] Auth request from 'core_mosquitto' for 'mqttuser' 21-09-14 10:28:35 INFO (MainThread) [supervisor.auth] Successful login for 'mqttuser' 21-09-14 10:31:10 INFO (SyncWorker_6) [supervisor.docker.interface] Restarting ghcr.io/home-assistant/raspberrypi4-64-homeassistant 21-09-14 10:31:22 INFO (MainThread) [supervisor.homeassistant.core] Wait until Home Assistant is ready 21-09-14 10:31:31 INFO (MainThread) [supervisor.resolution.evaluate] Starting system evaluation with state CoreState.RUNNING 21-09-14 10:31:31 INFO (MainThread) [supervisor.resolution.evaluate] System evaluation complete 21-09-14 10:32:21 INFO (MainThread) [supervisor.homeassistant.core] Detect a running Home Assistant instance 21-09-14 10:34:18 INFO (MainThread) [supervisor.homeassistant.api] Updated Home Assistant API token ```

Core Log with the logger set to homeassistant.components.mqtt: debug

Expand full log: ``` 2021-09-14 10:31:28 WARNING (SyncWorker_1) [homeassistant.loader] We found a custom integration var which has not been tested by Home Assistant. This component might cause stability problems, be sure to disable it if you experience issues with Home Assistant 2021-09-14 10:31:28 WARNING (SyncWorker_0) [homeassistant.loader] We found a custom integration hacs which has not been tested by Home Assistant. This component might cause stability problems, be sure to disable it if you experience issues with Home Assistant 2021-09-14 10:31:28 WARNING (SyncWorker_2) [homeassistant.loader] We found a custom integration govee_ble_hci which has not been tested by Home Assistant. This component might cause stability problems, be sure to disable it if you experience issues with Home Assistant 2021-09-14 10:31:28 WARNING (SyncWorker_1) [homeassistant.loader] We found a custom integration smartthinq_sensors which has not been tested by Home Assistant. This component might cause stability problems, be sure to disable it if you experience issues with Home Assistant 2021-09-14 10:31:28 WARNING (SyncWorker_3) [homeassistant.loader] We found a custom integration googlewifi which has not been tested by Home Assistant. This component might cause stability problems, be sure to disable it if you experience issues with Home Assistant 2021-09-14 10:31:28 WARNING (SyncWorker_4) [homeassistant.loader] We found a custom integration govee which has not been tested by Home Assistant. This component might cause stability problems, be sure to disable it if you experience issues with Home Assistant 2021-09-14 10:31:37 INFO (Thread-3) [homeassistant.components.mqtt] Connected to MQTT server core-mosquitto:1883 (0) 2021-09-14 10:31:42 WARNING (MainThread) [homeassistant.setup] Setup of zone is taking over 10 seconds. 2021-09-14 10:31:42 WARNING (MainThread) [homeassistant.setup] Setup of group is taking over 10 seconds. 2021-09-14 10:31:43 DEBUG (MainThread) [homeassistant.components.mqtt] Subscribing to homeassistant/+/+/config, mid: 1 2021-09-14 10:31:43 ERROR (MainThread) [snitun.client.client_peer] Challenge/Response error with SniTun server 2021-09-14 10:31:43 ERROR (MainThread) [hass_nabucasa.remote] Connection problem to snitun server 2021-09-14 10:31:45 WARNING (MainThread) [homeassistant.setup] Setup of input_boolean is taking over 10 seconds. 2021-09-14 10:31:45 DEBUG (MainThread) [homeassistant.components.mqtt] Subscribing to homeassistant/+/+/+/config, mid: 2 2021-09-14 10:31:47 WARNING (MainThread) [homeassistant.setup] Setup of webostv is taking over 10 seconds. 2021-09-14 10:31:47 ERROR (SyncWorker_7) [custom_components.govee_ble_hci.sensor] Error during Bluetooth LE scan: Attribute hass is None for 2021-09-14 10:31:48 DEBUG (MainThread) [homeassistant.components.mqtt] Subscribing to tasmota/discovery/#, mid: 3 2021-09-14 10:31:51 WARNING (MainThread) [custom_components.govee] API is back online. 2021-09-14 10:31:53 WARNING (MainThread) [zigpy.zcl] Unknown cluster 57344 2021-09-14 10:31:53 WARNING (MainThread) [zigpy.zcl] Unknown cluster 57345 2021-09-14 10:31:53 WARNING (MainThread) [zigpy.zcl] Unknown cluster 57344 2021-09-14 10:31:53 WARNING (MainThread) [zigpy.zcl] Unknown cluster 57345 2021-09-14 10:31:55 WARNING (MainThread) [homeassistant.components.camera] Setup of camera platform environment_canada is taking over 10 seconds. 2021-09-14 10:32:18 DEBUG (MainThread) [homeassistant.components.mqtt] Transmitting message on homeassistant/status: 'online', mid: 4 2021-09-14 10:33:09 INFO (Thread-113) [homeassistant.components.mqtt] Connected to MQTT server core-mosquitto:1883 (0) 2021-09-14 10:33:09 DEBUG (MainThread) [homeassistant.components.mqtt] Unsubscribing from homeassistant/+/+/config, mid: 1 2021-09-14 10:33:09 DEBUG (MainThread) [homeassistant.components.mqtt] Unsubscribing from homeassistant/+/+/+/config, mid: 2 2021-09-14 10:33:09 DEBUG (MainThread) [homeassistant.components.mqtt] Unsubscribing from tasmota/discovery/#, mid: 3 2021-09-14 10:33:09 DEBUG (MainThread) [homeassistant.components.mqtt] Subscribing to homeassistant/+/+/config, mid: 4 2021-09-14 10:33:09 DEBUG (MainThread) [homeassistant.components.mqtt] Subscribing to homeassistant/+/+/+/config, mid: 5 2021-09-14 10:33:09 DEBUG (MainThread) [homeassistant.components.mqtt] Subscribing to homeassistant/+/+/+/config, mid: 6 2021-09-14 10:33:09 DEBUG (MainThread) [homeassistant.components.mqtt] Subscribing to homeassistant/+/+/config, mid: 7 2021-09-14 10:33:09 DEBUG (MainThread) [homeassistant.components.mqtt] Subscribing to tasmota/discovery/#, mid: 8 2021-09-14 10:33:11 DEBUG (MainThread) [homeassistant.components.mqtt] Transmitting message on homeassistant/status: 'online', mid: 9 ```

Additional information

Host Log:

[    0.000000] Booting Linux on physical CPU 0x0000000000 [0x410fd083]
[    0.000000] Linux version 5.10.17-v8 (builder@b967341857bd) (aarch64-buildroot-linux-gnu-gcc.br_real (Buildroot -gf0b9df90) 9.4.0, GNU ld (GNU Binutils) 2.35.2) #1 SMP PREEMPT Sat Sep 4 00:55:46 UTC 2021
[    0.000000] Machine model: Raspberry Pi 4 Model B Rev 1.2
[    0.000000] efi: UEFI not found.
[    0.000000] Reserved memory: created CMA memory pool at 0x000000002ac00000, size 64 MiB
[    0.000000] OF: reserved mem: initialized node linux,cma, compatible id shared-dma-pool
[    0.000000] Zone ranges:
[    0.000000]   DMA      [mem 0x0000000000000000-0x000000003fffffff]
[    0.000000]   DMA32    [mem 0x0000000040000000-0x00000000fbffffff]
[    0.000000]   Normal   empty
[    0.000000] Movable zone start for each node
[    0.000000] Early memory node ranges
[    0.000000]   node   0: [mem 0x0000000000000000-0x000000003b3fffff]
[    0.000000]   node   0: [mem 0x0000000040000000-0x00000000fbffffff]
[    0.000000] Initmem setup node 0 [mem 0x0000000000000000-0x00000000fbffffff]
[    0.000000] On node 0 totalpages: 1012736
[    0.000000]   DMA zone: 3792 pages used for memmap
[    0.000000]   DMA zone: 0 pages reserved
[    0.000000]   DMA zone: 242688 pages, LIFO batch:63
[    0.000000]   DMA32 zone: 12032 pages used for memmap
[    0.000000]   DMA32 zone: 770048 pages, LIFO batch:63
[    0.000000] percpu: Embedded 32 pages/cpu s91352 r8192 d31528 u131072
[    0.000000] pcpu-alloc: s91352 r8192 d31528 u131072 alloc=32*4096
[    0.000000] pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 
[    0.000000] Detected PIPT I-cache on CPU0
[    0.000000] CPU features: detected: Spectre-v2
[    0.000000] CPU features: detected: Spectre-v4
[    0.000000] CPU features: detected: ARM errata 1165522, 1319367, or 1530923
[    0.000000] Built 1 zonelists, mobility grouping on.  Total pages: 996912
[    0.000000] Kernel command line: zram.enabled=1 zram.num_devices=3 apparmor=1 security=apparmor rootwait systemd.machine_id=32f30157bf304c31a478077b70c0b794 cgroup_enable=memory fsck.repair=yes coherent_pool=1M 8250.nr_uarts=0 snd_bcm2835.enable_compat_alsa=0 snd_bcm2835.enable_hdmi=1 bcm2708_fb.fbwidth=0 bcm2708_fb.fbheight=0 bcm2708_fb.fbswap=1 smsc95xx.macaddr=DC:A6:32:AB:32:0E vc_mem.mem_base=0x3ec00000 vc_mem.mem_size=0x40000000  dwc_otg.lpm_enable=0 console=tty1 root=PARTUUID=8d3d53e3-6d49-4c38-8349-aff6859e82fd rootfstype=squashfs ro rauc.slot=A
[    0.000000] Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear)
[    0.000000] Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear)
[    0.000000] mem auto-init: stack:off, heap alloc:off, heap free:off
[    0.000000] software IO TLB: mapped [mem 0x0000000037400000-0x000000003b400000] (64MB)
[    0.000000] Memory: 3815580K/4050944K available (12096K kernel code, 1962K rwdata, 4160K rodata, 3840K init, 1261K bss, 169828K reserved, 65536K cma-reserved)
[    0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1
[    0.000000] ftrace: allocating 39239 entries in 154 pages
[    0.000000] ftrace: allocated 154 pages with 4 groups
[    0.000000] rcu: Preemptible hierarchical RCU implementation.
[    0.000000] rcu:     RCU event tracing is enabled.
[    0.000000] rcu:     RCU restricting CPUs from NR_CPUS=256 to nr_cpu_ids=4.
[    0.000000]  Trampoline variant of Tasks RCU enabled.
[    0.000000]  Rude variant of Tasks RCU enabled.
[    0.000000]  Tracing variant of Tasks RCU enabled.
[    0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies.
[    0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4
[    0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0
[    0.000000] GIC: Using split EOI/Deactivate mode
[    0.000000] random: get_random_bytes called from start_kernel+0x3b8/0x57c with crng_init=0
[    0.000000] arch_timer: cp15 timer(s) running at 54.00MHz (phys).
[    0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0xc743ce346, max_idle_ns: 440795203123 ns
[    0.000006] sched_clock: 56 bits at 54MHz, resolution 18ns, wraps every 4398046511102ns
[    0.000255] Console: colour dummy device 80x25
[    0.000808] printk: console [tty1] enabled
[    0.000870] Calibrating delay loop (skipped), value calculated using timer frequency.. 108.00 BogoMIPS (lpj=216000)
[    0.000912] pid_max: default: 32768 minimum: 301
[    0.001061] LSM: Security Framework initializing
[    0.001183] AppArmor: AppArmor initialized
[    0.001403] Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear)
[    0.001480] Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear)
[    0.004615] rcu: Hierarchical SRCU implementation.
[    0.005645] EFI services will not be available.
[    0.006161] smp: Bringing up secondary CPUs ...
[    0.007174] Detected PIPT I-cache on CPU1
[    0.007246] CPU1: Booted secondary processor 0x0000000001 [0x410fd083]
[    0.008416] Detected PIPT I-cache on CPU2
[    0.008463] CPU2: Booted secondary processor 0x0000000002 [0x410fd083]
[    0.009561] Detected PIPT I-cache on CPU3
[    0.009607] CPU3: Booted secondary processor 0x0000000003 [0x410fd083]
[    0.009757] smp: Brought up 1 node, 4 CPUs
[    0.009846] SMP: Total of 4 processors activated.
[    0.009870] CPU features: detected: 32-bit EL0 Support
[    0.009893] CPU features: detected: CRC32 instructions
[    0.009917] CPU features: detected: 32-bit EL1 Support
[    0.047487] CPU: All CPU(s) started at EL2
[    0.047572] alternatives: patching kernel code
[    0.049134] devtmpfs: initialized
[    0.059780] Enabled cp15_barrier support
[    0.059847] Enabled setend support
[    0.060055] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns
[    0.060142] futex hash table entries: 1024 (order: 4, 65536 bytes, linear)
[    0.062135] pinctrl core: initialized pinctrl subsystem
[    0.062925] DMI not present or invalid.
[    0.063328] NET: Registered protocol family 16
[    0.066640] DMA: preallocated 1024 KiB GFP_KERNEL pool for atomic allocations
[    0.066917] DMA: preallocated 1024 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations
[    0.067849] DMA: preallocated 1024 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations
[    0.067933] audit: initializing netlink subsys (disabled)
[    0.068327] audit: type=2000 audit(0.068:1): state=initialized audit_enabled=0 res=1
[    0.068802] thermal_sys: Registered thermal governor 'step_wise'
[    0.069257] cpuidle: using governor menu
[    0.069765] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers.
[    0.069950] ASID allocator initialised with 65536 entries
[    0.070095] Serial: AMBA PL011 UART driver
[    0.097693] bcm2835-mbox fe00b880.mailbox: mailbox enabled
[    0.104321] raspberrypi-firmware soc:firmware: Attached to firmware from 2021-05-27T14:02:15, variant start
[    0.108315] raspberrypi-firmware soc:firmware: Firmware hash is 7d9a298cda813f747b51fe17e1e417e7bf5ca94d
[    0.142594] bcm2835-dma fe007000.dma: DMA legacy API manager, dmachans=0x1
[    0.146482] vgaarb: loaded
[    0.146894] SCSI subsystem initialized
[    0.147135] usbcore: registered new interface driver usbfs
[    0.147203] usbcore: registered new interface driver hub
[    0.147288] usbcore: registered new device driver usb
[    0.147602] usb_phy_generic phy: supply vcc not found, using dummy regulator
[    0.147977] mc: Linux media interface: v0.10
[    0.149648] clocksource: Switched to clocksource arch_sys_counter
[    1.168474] VFS: Disk quotas dquot_6.6.0
[    1.168597] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)
[    1.168768] FS-Cache: Loaded
[    1.169023] CacheFiles: Loaded
[    1.169832] AppArmor: AppArmor Filesystem Enabled
[    1.180202] NET: Registered protocol family 2
[    1.181381] tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear)
[    1.181450] TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear)
[    1.181729] TCP bind hash table entries: 32768 (order: 7, 524288 bytes, linear)
[    1.182176] TCP: Hash tables configured (established 32768 bind 32768)
[    1.182422] UDP hash table entries: 2048 (order: 4, 65536 bytes, linear)
[    1.182507] UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear)
[    1.182862] NET: Registered protocol family 1
[    1.183776] RPC: Registered named UNIX socket transport module.
[    1.183805] RPC: Registered udp transport module.
[    1.183826] RPC: Registered tcp transport module.
[    1.183846] RPC: Registered tcp NFSv4.1 backchannel transport module.
[    1.183878] PCI: CLS 0 bytes, default 64
[    1.186352] hw perfevents: enabled with armv8_cortex_a72 PMU driver, 7 counters available
[    1.186645] kvm [1]: IPA Size Limit: 44 bits
[    1.187843] kvm [1]: vgic interrupt IRQ9
[    1.188138] kvm [1]: Hyp mode initialized successfully
[    1.190276] Initialise system trusted keyrings
[    1.190613] workingset: timestamp_bits=46 max_order=20 bucket_order=0
[    1.196758] zbud: loaded
[    1.198458] squashfs: version 4.0 (2009/01/31) Phillip Lougher
[    1.198722] FS-Cache: Netfs 'nfs' registered for caching
[    1.199367] NFS: Registering the id_resolver key type
[    1.199420] Key type id_resolver registered
[    1.199442] Key type id_legacy registered
[    1.199573] nfs4filelayout_init: NFSv4 File Layout Driver Registering...
[    1.200750] Key type asymmetric registered
[    1.200777] Asymmetric key parser 'x509' registered
[    1.200833] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 248)
[    1.201103] io scheduler mq-deadline registered
[    1.201128] io scheduler kyber registered
[    1.205177] brcm-pcie fd500000.pcie: host bridge /scb/pcie@7d500000 ranges:
[    1.205223] brcm-pcie fd500000.pcie:   No bus range found for /scb/pcie@7d500000, using [bus 00-ff]
[    1.205309] brcm-pcie fd500000.pcie:      MEM 0x0600000000..0x063fffffff -> 0x00c0000000
[    1.205411] brcm-pcie fd500000.pcie:   IB MEM 0x0000000000..0x00bfffffff -> 0x0400000000
[    1.255748] brcm-pcie fd500000.pcie: link up, 5.0 GT/s PCIe x1 (SSC)
[    1.256136] brcm-pcie fd500000.pcie: PCI host bridge to bus 0000:00
[    1.256169] pci_bus 0000:00: root bus resource [bus 00-ff]
[    1.256199] pci_bus 0000:00: root bus resource [mem 0x600000000-0x63fffffff] (bus address [0xc0000000-0xffffffff])
[    1.256299] pci 0000:00:00.0: [14e4:2711] type 01 class 0x060400
[    1.256548] pci 0000:00:00.0: PME# supported from D0 D3hot
[    1.260454] pci 0000:01:00.0: [1106:3483] type 00 class 0x0c0330
[    1.260592] pci 0000:01:00.0: reg 0x10: [mem 0x00000000-0x00000fff 64bit]
[    1.261025] pci 0000:01:00.0: PME# supported from D0 D3cold
[    1.264701] pci 0000:00:00.0: BAR 8: assigned [mem 0x600000000-0x6000fffff]
[    1.264738] pci 0000:01:00.0: BAR 0: assigned [mem 0x600000000-0x600000fff 64bit]
[    1.264790] pci 0000:00:00.0: PCI bridge to [bus 01]
[    1.264824] pci 0000:00:00.0:   bridge window [mem 0x600000000-0x6000fffff]
[    1.265480] bcm2708_fb soc:fb: Unable to determine number of FBs. Disabling driver.
[    1.265528] bcm2708_fb: probe of soc:fb failed with error -2
[    1.272315] iproc-rng200 fe104000.rng: hwrng registered
[    1.272662] vc-mem: phys_addr:0x00000000 mem_base=0x3ec00000 mem_size:0x40000000(1024 MiB)
[    1.273545] gpiomem-bcm2835 fe200000.gpiomem: Initialised: Registers at 0xfe200000
[    1.274030] cacheinfo: Unable to detect cache hierarchy for CPU 0
[    1.284889] brd: module loaded
[    1.296966] loop: module loaded
[    1.297568] zram: Added device: zram0
[    1.298209] zram: Added device: zram1
[    1.298721] zram: Added device: zram2
[    1.300243] Loading iSCSI transport class v2.0-870.
[    1.303224] libphy: Fixed MDIO Bus: probed
[    1.304106] tun: Universal TUN/TAP device driver, 1.6
[    1.304956] bcmgenet fd580000.ethernet: GENET 5.0 EPHY: 0x0000
[    1.317708] libphy: bcmgenet MII bus: probed
[    1.365776] unimac-mdio unimac-mdio.-19: Broadcom UniMAC MDIO bus
[    1.366850] usbcore: registered new interface driver r8152
[    1.366930] usbcore: registered new interface driver lan78xx
[    1.368517] pci 0000:00:00.0: enabling device (0000 -> 0002)
[    1.368567] xhci_hcd 0000:01:00.0: enabling device (0000 -> 0002)
[    1.368675] xhci_hcd 0000:01:00.0: xHCI Host Controller
[    1.368718] xhci_hcd 0000:01:00.0: new USB bus registered, assigned bus number 1
[    1.369473] xhci_hcd 0000:01:00.0: hcc params 0x002841eb hci version 0x100 quirks 0x0000030000000890
[    1.370483] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 5.10
[    1.370518] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[    1.370547] usb usb1: Product: xHCI Host Controller
[    1.370571] usb usb1: Manufacturer: Linux 5.10.17-v8 xhci-hcd
[    1.370595] usb usb1: SerialNumber: 0000:01:00.0
[    1.371287] hub 1-0:1.0: USB hub found
[    1.371394] hub 1-0:1.0: 1 port detected
[    1.372024] xhci_hcd 0000:01:00.0: xHCI Host Controller
[    1.372060] xhci_hcd 0000:01:00.0: new USB bus registered, assigned bus number 2
[    1.372098] xhci_hcd 0000:01:00.0: Host supports USB 3.0 SuperSpeed
[    1.372552] usb usb2: New USB device found, idVendor=1d6b, idProduct=0003, bcdDevice= 5.10
[    1.372584] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[    1.372611] usb usb2: Product: xHCI Host Controller
[    1.372634] usb usb2: Manufacturer: Linux 5.10.17-v8 xhci-hcd
[    1.372657] usb usb2: SerialNumber: 0000:01:00.0
[    1.373293] hub 2-0:1.0: USB hub found
[    1.373399] hub 2-0:1.0: 4 ports detected
[    1.374968] dwc_otg: version 3.00a 10-AUG-2012 (platform bus)
[    1.375233] dwc_otg: FIQ enabled
[    1.375245] dwc_otg: NAK holdoff enabled
[    1.375255] dwc_otg: FIQ split-transaction FSM enabled
[    1.375269] Module dwc_common_port init
[    1.375781] usbcore: registered new interface driver uas
[    1.375889] usbcore: registered new interface driver usb-storage
[    1.376024] usbcore: registered new interface driver usbserial_generic
[    1.376079] usbserial: USB Serial support registered for generic
[    1.376298] mousedev: PS/2 mouse device common for all mice
[    1.378238] bcm2835-wdt bcm2835-wdt: Broadcom BCM2835 watchdog timer
[    1.381399] sdhci: Secure Digital Host Controller Interface driver
[    1.381430] sdhci: Copyright(c) Pierre Ossman
[    1.382133] mmc-bcm2835 fe300000.mmcnr: could not get clk, deferring probe
[    1.382643] sdhci-pltfm: SDHCI platform and OF driver helper
[    1.388349] ledtrig-cpu: registered to indicate activity on CPUs
[    1.388468] hid: raw HID events driver (C) Jiri Kosina
[    1.388555] usbcore: registered new interface driver usbhid
[    1.388565] usbhid: USB HID core driver
[    1.392644] IPVS: Registered protocols (TCP, UDP, SCTP, AH, ESP)
[    1.392761] IPVS: Connection hash table configured (size=4096, memory=64Kbytes)
[    1.392870] IPVS: ipvs loaded.
[    1.392881] IPVS: [rr] scheduler registered.
[    1.393384] NET: Registered protocol family 10
[    1.394132] Segment Routing with IPv6
[    1.394192] NET: Registered protocol family 17
[    1.394255] Bridge firewalling registered
[    1.394346] Key type dns_resolver registered
[    1.394896] registered taskstats version 1
[    1.394921] Loading compiled-in X.509 certificates
[    1.395207] Key type ._fscrypt registered
[    1.395223] Key type .fscrypt registered
[    1.395235] Key type fscrypt-provisioning registered
[    1.395310] AppArmor: AppArmor sha1 policy hashing enabled
[    1.402329] uart-pl011 fe201000.serial: there is not valid maps for state default
[    1.402556] uart-pl011 fe201000.serial: cts_event_workaround enabled
[    1.402641] fe201000.serial: ttyAMA0 at MMIO 0xfe201000 (irq = 21, base_baud = 0) is a PL011 rev2
[    1.409057] bcm2835-power bcm2835-power: Broadcom BCM2835 power domains driver
[    1.409505] mmc-bcm2835 fe300000.mmcnr: mmc_debug:0 mmc_debug2:0
[    1.409518] mmc-bcm2835 fe300000.mmcnr: DMA channel allocated
[    1.439863] printk: console [netcon0] enabled
[    1.439897] netconsole: network logging started
[    1.439958] of_cfs_init
[    1.440086] of_cfs_init: OK
[    1.452997] mmc1: queuing unknown CIS tuple 0x80 (2 bytes)
[    1.454607] mmc1: queuing unknown CIS tuple 0x80 (3 bytes)
[    1.456176] mmc1: queuing unknown CIS tuple 0x80 (3 bytes)
[    1.459078] mmc1: queuing unknown CIS tuple 0x80 (7 bytes)
[    1.460724] mmc1: queuing unknown CIS tuple 0x80 (3 bytes)
[    1.476017] mmc0: SDHCI controller on fe340000.emmc2 [fe340000.emmc2] using ADMA
[    1.476939] Waiting for root device PARTUUID=8d3d53e3-6d49-4c38-8349-aff6859e82fd...
[    1.494287] random: fast init done
[    1.522300] mmc1: new high speed SDIO card at address 0001
[    1.573600] mmc0: new ultra high speed DDR50 SDXC card at address 0001
[    1.574101] mmcblk0: mmc0:0001 EC2QT 59.6 GiB
[    1.578794]  mmcblk0: p1 p2 p3 p4 p5 p6 p7 p8
[    1.589585] VFS: Mounted root (squashfs filesystem) readonly on device 179:3.
[    1.590969] devtmpfs: mounted
[    1.596504] Freeing unused kernel memory: 3840K
[    1.605715] Run /sbin/init as init process
[    1.605732]   with arguments:
[    1.605740]     /sbin/init
[    1.605747]   with environment:
[    1.605754]     HOME=/
[    1.605760]     TERM=linux
[    1.705693] usb 1-1: new high-speed USB device number 2 using xhci_hcd
[    1.854168] systemd[1]: System time before build time, advancing clock.
[    1.856366] usb 1-1: New USB device found, idVendor=2109, idProduct=3431, bcdDevice= 4.21
[    1.856393] usb 1-1: New USB device strings: Mfr=0, Product=1, SerialNumber=0
[    1.856409] usb 1-1: Product: USB2.0 Hub
[    1.857824] hub 1-1:1.0: USB hub found
[    1.858178] hub 1-1:1.0: 4 ports detected
[    1.909278] systemd[1]: systemd 247 running in system mode. (+PAM -AUDIT -SELINUX -IMA +APPARMOR -SMACK -SYSVINIT -UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS -ACL -XZ -LZ4 -ZSTD -SECCOMP +BLKID -ELFUTILS +KMOD -IDN2 -IDN +PCRE2 default-hierarchy=hybrid)
[    1.910043] systemd[1]: Detected architecture arm64.
[    1.943156] systemd[1]: Set hostname to <homeassistant>.
[    1.943577] systemd[1]: Installed transient /etc/machine-id file.
[    1.982072] usb 2-1: new SuperSpeed Gen 1 USB device number 2 using xhci_hcd
[    2.002936] usb 2-1: New USB device found, idVendor=1058, idProduct=2620, bcdDevice=10.20
[    2.002975] usb 2-1: New USB device strings: Mfr=2, Product=3, SerialNumber=1
[    2.002993] usb 2-1: Product: Elements 2620
[    2.003010] usb 2-1: Manufacturer: Western Digital
[    2.003026] usb 2-1: SerialNumber: 5758363244323145414A3750
[    2.005763] usb-storage 2-1:1.0: USB Mass Storage device detected
[    2.006364] scsi host0: usb-storage 2-1:1.0
[    2.153675] usb 1-1.3: new full-speed USB device number 3 using xhci_hcd
[    2.260947] usb 1-1.3: New USB device found, idVendor=051d, idProduct=0002, bcdDevice= 0.90
[    2.260977] usb 1-1.3: New USB device strings: Mfr=1, Product=2, SerialNumber=3
[    2.260989] usb 1-1.3: Product: Back-UPS ES 850G2 FW:931.a10.D USB FW:a10
[    2.261000] usb 1-1.3: Manufacturer: American Power Conversion
[    2.261012] usb 1-1.3: SerialNumber: 4B2039P10751  
[    2.276195] hid-generic 0003:051D:0002.0001: hiddev96,hidraw0: USB HID v1.00 Device [American Power Conversion Back-UPS ES 850G2 FW:931.a10.D USB FW:a10] on usb-0000:01:00.0-1.3/input0
[    2.322772] systemd[1]: Queued start job for default target Multi-User System.
[    2.323672] random: systemd: uninitialized urandom read (16 bytes read)
[    2.352272] systemd[1]: Created slice system-ha\x2dcli.slice.
[    2.352682] random: systemd: uninitialized urandom read (16 bytes read)
[    2.353681] usb 1-1.4: new full-speed USB device number 4 using xhci_hcd
[    2.354331] systemd[1]: Created slice system-modprobe.slice.
[    2.354573] random: systemd: uninitialized urandom read (16 bytes read)
[    2.356148] systemd[1]: Created slice system-systemd\x2dfsck.slice.
[    2.358185] systemd[1]: Created slice system-systemd\x2dgrowfs.slice.
[    2.359831] systemd[1]: Created slice User and Session Slice.
[    2.360250] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.
[    2.360582] systemd[1]: Started Forward Password Requests to Wall Directory Watch.
[    2.360897] systemd[1]: Reached target Local Encrypted Volumes.
[    2.361102] systemd[1]: Reached target HassOS zram targets.
[    2.361307] systemd[1]: Reached target Paths.
[    2.361483] systemd[1]: Reached target Remote Encrypted Volumes.
[    2.361666] systemd[1]: Reached target Remote File Systems.
[    2.361842] systemd[1]: Reached target Slices.
[    2.363970] systemd[1]: Listening on D-Bus System Message Bus Socket.
[    2.367112] systemd[1]: Listening on Process Core Dump Socket.
[    2.368673] systemd[1]: Listening on Journal Audit Socket.
[    2.369183] systemd[1]: Listening on Journal Socket (/dev/log).
[    2.369730] systemd[1]: Listening on Journal Socket.
[    2.371506] systemd[1]: Listening on udev Control Socket.
[    2.371982] systemd[1]: Listening on udev Kernel Socket.
[    2.372483] systemd[1]: Condition check resulted in Huge Pages File System being skipped.
[    2.376164] systemd[1]: Mounting POSIX Message Queue File System...
[    2.380853] systemd[1]: Mounting Kernel Debug File System...
[    2.385532] systemd[1]: Mounting Kernel Trace File System...
[    2.389879] systemd[1]: Started D-Bus System Message Bus.
[    2.399295] systemd[1]: Starting Home Assistant OS Agent...
[    2.411119] systemd[1]: Starting Create list of static device nodes for the current kernel...
[    2.415450] systemd[1]: Starting Load Kernel Module configfs...
[    2.419707] systemd[1]: Starting Load Kernel Module drm...
[    2.423940] systemd[1]: Starting Load Kernel Module fuse...
[    2.431244] systemd[1]: Starting Journal Service...
[    2.435957] systemd[1]: Starting Remount Root and Kernel File Systems...
[    2.440482] systemd[1]: Starting Wait Until Kernel Time Synchronized...
[    2.444809] systemd[1]: Starting Coldplug All udev Devices...
[    2.448902] systemd[1]: Starting Disk Manager...
[    2.453528] systemd[1]: Starting HassOS ZRAM swap...
[    2.458295] systemd[1]: Starting HassOS ZRAM tmp...
[    2.463099] usb 1-1.4: New USB device found, idVendor=10c4, idProduct=8a2a, bcdDevice= 1.00
[    2.463159] usb 1-1.4: New USB device strings: Mfr=1, Product=2, SerialNumber=5
[    2.463171] usb 1-1.4: Product: HubZ Smart Home Controller
[    2.463237] usb 1-1.4: Manufacturer: Silicon Labs
[    2.463247] usb 1-1.4: SerialNumber: 31400254
[    2.463810] systemd[1]: Starting HassOS ZRAM var...
[    2.471166] systemd[1]: Mounted POSIX Message Queue File System.
[    2.471760] systemd[1]: Mounted Kernel Debug File System.
[    2.472172] systemd[1]: Mounted Kernel Trace File System.
[    2.475173] systemd[1]: Finished Create list of static device nodes for the current kernel.
[    2.476298] systemd[1]: modprobe@configfs.service: Succeeded.
[    2.477883] systemd[1]: Finished Load Kernel Module configfs.
[    2.481083] systemd[1]: Finished Remount Root and Kernel File Systems.
[    2.485241] systemd[1]: Mounting Kernel Configuration File System...
[    2.489670] systemd[1]: Starting Create Static Device Nodes in /dev...
[    2.496007] systemd[1]: Mounted Kernel Configuration File System.
[    2.552203] fuse: init (API version 7.32)
[    2.558608] systemd[1]: modprobe@fuse.service: Succeeded.
[    2.560400] systemd[1]: Finished Load Kernel Module fuse.
[    2.566151] systemd[1]: Mounting FUSE Control File System...
[    2.576086] systemd[1]: Mounted FUSE Control File System.
[    2.595435] systemd[1]: Started Journal Service.
[    2.655927] zram2: detected capacity change from 0 to 16777216
[    2.655990] zram1: detected capacity change from 0 to 33554432
[    2.661528] zram0: detected capacity change from 0 to 994549760
[    2.761872] audit: type=1334 audit(1612279787.903:2): prog-id=5 op=LOAD
[    2.762022] audit: type=1334 audit(1612279787.903:3): prog-id=6 op=LOAD
[    3.027388] usbcore: registered new interface driver cp210x
[    3.027455] usbserial: USB Serial support registered for cp210x
[    3.029603] cp210x 1-1.4:1.0: cp210x converter detected
[    3.038292] scsi 0:0:0:0: Direct-Access     WD       Elements 2620    1020 PQ: 0 ANSI: 6
[    3.039177] sd 0:0:0:0: [sda] Very big device. Trying to use READ CAPACITY(16).
[    3.039451] sd 0:0:0:0: [sda] 9767475200 512-byte logical blocks: (5.00 TB/4.55 TiB)
[    3.039469] sd 0:0:0:0: [sda] 4096-byte physical blocks
[    3.040075] sd 0:0:0:0: [sda] Write Protect is off
[    3.040101] sd 0:0:0:0: [sda] Mode Sense: 47 00 10 08
[    3.040784] sd 0:0:0:0: [sda] No Caching mode page found
[    3.040822] sd 0:0:0:0: [sda] Assuming drive cache: write through
[    3.042248] usb 1-1.4: cp210x converter now attached to ttyUSB0
[    3.042444] cp210x 1-1.4:1.1: cp210x converter detected
[    3.058407] usb 1-1.4: cp210x converter now attached to ttyUSB1
[    3.141052] vc_sm_cma: module is from the staging directory, the quality is unknown, you have been warned.
[    3.142464] bcm2835_vc_sm_cma_probe: Videocore shared memory driver
[    3.142497] [vc_sm_connected_init]: start
[    3.143014] [vc_sm_connected_init]: installed successfully
[    3.224013]  sda: sda1
[    3.226359] sd 0:0:0:0: [sda] Attached SCSI disk
[    3.363358] rpivid-mem feb00000.hevc-decoder: rpivid-hevcmem initialised: Registers at 0xfeb00000 length 0x00010000
[    3.363737] rpivid-mem feb10000.rpivid-local-intc: rpivid-intcmem initialised: Registers at 0xfeb10000 length 0x00001000
[    3.363966] rpivid-mem feb20000.h264-decoder: rpivid-h264mem initialised: Registers at 0xfeb20000 length 0x00010000
[    3.364193] rpivid-mem feb30000.vp9-decoder: rpivid-vp9mem initialised: Registers at 0xfeb30000 length 0x00010000
[    3.607026] FAT-fs (mmcblk0p1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck.
[    3.656183] videodev: Linux video capture interface: v2.00
[    3.668581] EXT4-fs (mmcblk0p7): mounted filesystem with ordered data mode. Opts: (null)
[    3.668706] ext4 filesystem being mounted at /mnt/overlay supports timestamps until 2038 (0x7fffffff)
[    3.674049] bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned.
[    3.678545] bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned.
[    3.679962] bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned.
[    3.682112] snd_bcm2835: module is from the staging directory, the quality is unknown, you have been warned.
[    3.685616] bcm2835_v4l2: module is from the staging directory, the quality is unknown, you have been warned.
[    3.685620] bcm2835_codec: module is from the staging directory, the quality is unknown, you have been warned.
[    3.685820] bcm2835_isp: module is from the staging directory, the quality is unknown, you have been warned.
[    3.688457] bcm2835_audio bcm2835_audio: card created with 8 channels
[    3.690604] bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video13
[    3.695975] bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video14
[    3.698620] bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video15
[    3.698971] bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video16
[    3.699004] bcm2835-isp bcm2835-isp: Register output node 0 with media controller
[    3.699020] bcm2835-isp bcm2835-isp: Register capture node 1 with media controller
[    3.699038] bcm2835-isp bcm2835-isp: Register capture node 2 with media controller
[    3.699058] bcm2835-isp bcm2835-isp: Register capture node 3 with media controller
[    3.699262] bcm2835-isp bcm2835-isp: Loaded V4L2 bcm2835-isp
[    3.699596] bcm2835-codec bcm2835-codec: Device registered as /dev/video10
[    3.699634] bcm2835-codec bcm2835-codec: Loaded V4L2 decode
[    3.707150] bcm2835-codec bcm2835-codec: Device registered as /dev/video11
[    3.707206] bcm2835-codec bcm2835-codec: Loaded V4L2 encode
[    3.711451] bcm2835-codec bcm2835-codec: Device registered as /dev/video12
[    3.711494] bcm2835-codec bcm2835-codec: Loaded V4L2 isp
[    3.769876] Adding 971236k swap on /dev/zram0.  Priority:-2 extents:1 across:971236k SSFS
[    3.837745] cfg80211: Loading compiled-in X.509 certificates for regulatory database
[    3.865026] cfg80211: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'
[    3.952805] brcmfmac: F1 signature read @0x18000000=0x15264345
[    3.957270] brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6
[    3.958171] usbcore: registered new interface driver brcmfmac
[    3.992134] brcmfmac mmc1:0001:1: Direct firmware load for brcm/brcmfmac43455-sdio.raspberrypi,4-model-b.txt failed with error -2
[    4.097624] EXT4-fs (mmcblk0p8): mounted filesystem with ordered data mode. Opts: (null)
[    4.136845] EXT4-fs (mmcblk0p8): resizing filesystem from 15451899 to 15451899 blocks
[    4.199366] random: crng init done
[    4.199379] random: 7 urandom warning(s) missed due to ratelimiting
[    4.202797] brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6
[    4.212221] brcmfmac: brcmf_c_preinit_dcmds: Firmware: BCM4345/6 wl0: Jan  4 2021 19:56:29 version 7.45.229 (617f1f5 CY) FWID 01-2dbd9d2e
[    4.306211] EXT4-fs (zram2): mounted filesystem without journal. Opts: nobarrier
[    4.306249] ext4 filesystem being mounted at /tmp supports timestamps until 2038 (0x7fffffff)
[    4.318316] EXT4-fs (zram1): mounted filesystem without journal. Opts: nobarrier
[    4.318358] ext4 filesystem being mounted at /var supports timestamps until 2038 (0x7fffffff)
[    4.490340] systemd-journald[118]: Received client request to flush runtime journal.
[    4.500478] systemd-journald[118]: File /var/log/journal/32f30157bf304c31a478077b70c0b794/system.journal corrupted or uncleanly shut down, renaming and replacing.
[    4.968163] exFAT-fs (sda1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck.
[    5.348139] audit: type=1334 audit(1631509188.111:4): prog-id=7 op=LOAD
[    5.351841] audit: type=1334 audit(1631509188.115:5): prog-id=8 op=LOAD
[    5.886029] audit: type=1400 audit(1631509188.643:6): apparmor="STATUS" operation="profile_load" profile="unconfined" name="hassio-supervisor" pid=403 comm="apparmor_parser"
[    5.886052] audit: type=1400 audit(1631509188.643:6): apparmor="STATUS" operation="profile_load" profile="unconfined" name="hassio-supervisor///usr/bin/gdbus" pid=403 comm="apparmor_parser"
[    5.886065] audit: type=1400 audit(1631509188.643:6): apparmor="STATUS" operation="profile_load" profile="unconfined" name="hassio-supervisor///usr/bin/git" pid=403 comm="apparmor_parser"
[    6.019401] Bluetooth: Core ver 2.22
[    6.019497] NET: Registered protocol family 31
[    6.019502] Bluetooth: HCI device and connection manager initialized
[    6.019517] Bluetooth: HCI socket layer initialized
[    6.019526] Bluetooth: L2CAP socket layer initialized
[    6.019541] Bluetooth: SCO socket layer initialized
[    6.105288] uart-pl011 fe201000.serial: no DMA platform data
[    6.257430] audit: type=1334 audit(1631509189.019:7): prog-id=9 op=LOAD
[    6.257505] audit: type=1334 audit(1631509189.019:8): prog-id=10 op=LOAD
[    6.500228] bcmgenet fd580000.ethernet: configuring instance for external RGMII (RX delay)
[    6.500457] bcmgenet fd580000.ethernet eth0: Link is Down
[    6.550990] brcmfmac: brcmf_cfg80211_set_power_mgmt: power save enabled
[    6.698799] Bluetooth: HCI UART driver ver 2.3
[    6.698813] Bluetooth: HCI UART protocol H4 registered
[    6.698878] Bluetooth: HCI UART protocol Three-wire (H5) registered
[    6.699022] Bluetooth: HCI UART protocol Broadcom registered
[    6.823737] Bluetooth: BNEP (Ethernet Emulation) ver 1.3
[    6.823749] Bluetooth: BNEP filters: protocol multicast
[    6.823764] Bluetooth: BNEP socket layer initialized
[    6.840572] NET: Registered protocol family 38
[    6.865943] cryptd: max_cpu_qlen set to 1000
[   10.589780] bcmgenet fd580000.ethernet eth0: Link is Up - 1Gbps/Full - flow control rx/tx
[   10.589813] IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready
[   14.478271] audit: type=1400 audit(1631509197.239:9): apparmor="STATUS" operation="profile_load" profile="unconfined" name="docker-default" pid=516 comm="apparmor_parser"
[   16.829784] audit: type=1325 audit(1631509199.571:10): table=nat family=2 entries=5 op=xt_replace pid=540 subj==unconfined comm="iptables"
[   16.853723] audit: type=1325 audit(1631509199.599:11): table=filter family=2 entries=4 op=xt_replace pid=542 subj==unconfined comm="iptables"
[   16.881732] audit: type=1325 audit(1631509199.623:12): table=filter family=2 entries=6 op=xt_replace pid=544 subj==unconfined comm="iptables"
[   16.905782] audit: type=1325 audit(1631509199.651:13): table=filter family=2 entries=8 op=xt_replace pid=546 subj==unconfined comm="iptables"
[   16.945735] audit: type=1325 audit(1631509199.679:14): table=filter family=2 entries=10 op=xt_replace pid=548 subj==unconfined comm="iptables"
[   16.969742] audit: type=1325 audit(1631509199.715:15): table=filter family=2 entries=11 op=xt_replace pid=550 subj==unconfined comm="iptables"
[   16.993777] audit: type=1325 audit(1631509199.735:16): table=filter family=2 entries=12 op=xt_replace pid=551 subj==unconfined comm="iptables"
[   17.016686] Initializing XFRM netlink socket
[   17.097948] audit: type=1325 audit(1631509199.827:17): table=nat family=2 entries=7 op=xt_replace pid=559 subj==unconfined comm="iptables"
[   17.121727] audit: type=1325 audit(1631509199.871:18): table=nat family=2 entries=8 op=xt_replace pid=561 subj==unconfined comm="iptables"
[   18.499076] hassio: port 1(veth2eb4758) entered blocking state
[   18.499089] hassio: port 1(veth2eb4758) entered disabled state
[   18.499637] device veth2eb4758 entered promiscuous mode
[   18.506153] hassio: port 1(veth2eb4758) entered blocking state
[   18.506177] hassio: port 1(veth2eb4758) entered forwarding state
[   18.507648] hassio: port 1(veth2eb4758) entered disabled state
[   19.290885] cgroup: cgroup: disabling cgroup2 socket matching due to net_prio or net_cls activation
[   19.661803] kauditd_printk_skb: 44 callbacks suppressed
[   19.661813] audit: type=1325 audit(1631509202.411:63): table=nat family=2 entries=0 op=xt_register pid=739 subj==unconfined comm="iptables"
[   19.709829] audit: type=1325 audit(1631509202.443:64): table=filter family=2 entries=0 op=xt_register pid=740 subj==unconfined comm="iptables"
[   19.753799] audit: type=1325 audit(1631509202.491:65): table=nat family=2 entries=5 op=xt_replace pid=743 subj==unconfined comm="iptables"
[   19.781778] audit: type=1325 audit(1631509202.523:66): table=nat family=2 entries=7 op=xt_replace pid=744 subj==unconfined comm="iptables"
[   19.813784] audit: type=1325 audit(1631509202.559:67): table=nat family=2 entries=8 op=xt_replace pid=746 subj==unconfined comm="iptables"
[   19.849793] audit: type=1325 audit(1631509202.583:68): table=nat family=2 entries=10 op=xt_replace pid=747 subj==unconfined comm="iptables"
[   19.894062] audit: type=1325 audit(1631509202.627:69): table=nat family=2 entries=11 op=xt_replace pid=748 subj==unconfined comm="iptables"
[   19.941843] audit: type=1325 audit(1631509202.671:70): table=nat family=2 entries=12 op=xt_replace pid=749 subj==unconfined comm="iptables"
[   19.973861] audit: type=1325 audit(1631509202.715:71): table=nat family=2 entries=13 op=xt_replace pid=750 subj==unconfined comm="iptables"
[   20.001888] audit: type=1325 audit(1631509202.747:72): table=nat family=2 entries=14 op=xt_replace pid=751 subj==unconfined comm="iptables"
[   20.048162] eth0: renamed from vethc223dcd
[   20.071643] IPv6: ADDRCONF(NETDEV_CHANGE): veth2eb4758: link becomes ready
[   20.071840] hassio: port 1(veth2eb4758) entered blocking state
[   20.071855] hassio: port 1(veth2eb4758) entered forwarding state
[   20.072096] IPv6: ADDRCONF(NETDEV_CHANGE): hassio: link becomes ready
[   37.227835] docker0: port 1(vetha7b901b) entered blocking state
[   37.227849] docker0: port 1(vetha7b901b) entered disabled state
[   37.228089] device vetha7b901b entered promiscuous mode
[   37.228157] audit: type=1700 audit(1631509241.779:73): dev=vetha7b901b prom=256 old_prom=0 auid=4294967295 uid=0 gid=0 ses=4294967295
[   37.257930] audit: type=1334 audit(1631509241.811:74): prog-id=10 op=UNLOAD
[   37.257956] audit: type=1334 audit(1631509241.811:75): prog-id=9 op=UNLOAD
[   37.318800] hassio: port 2(veth8f27025) entered blocking state
[   37.318821] hassio: port 2(veth8f27025) entered disabled state
[   37.319047] device veth8f27025 entered promiscuous mode
[   37.319136] audit: type=1700 audit(1631509241.871:76): dev=veth8f27025 prom=256 old_prom=0 auid=4294967295 uid=0 gid=0 ses=4294967295
[   37.319507] hassio: port 2(veth8f27025) entered blocking state
[   37.319521] hassio: port 2(veth8f27025) entered forwarding state
[   37.843516] eth0: renamed from veth1969d29
[   37.877092] hassio: port 2(veth8f27025) entered disabled state
[   37.877330] IPv6: ADDRCONF(NETDEV_CHANGE): vetha7b901b: link becomes ready
[   37.877526] docker0: port 1(vetha7b901b) entered blocking state
[   37.877542] docker0: port 1(vetha7b901b) entered forwarding state
[   37.877865] IPv6: ADDRCONF(NETDEV_CHANGE): docker0: link becomes ready
[   38.013770] audit: type=1325 audit(1631509242.547:77): table=nat family=2 entries=0 op=xt_register pid=1104 subj==unconfined comm="iptables"
[   38.045769] audit: type=1325 audit(1631509242.575:78): table=filter family=2 entries=0 op=xt_register pid=1107 subj==unconfined comm="iptables"
[   38.085808] audit: type=1325 audit(1631509242.607:79): table=nat family=2 entries=5 op=xt_replace pid=1111 subj==unconfined comm="iptables"
[   38.113839] audit: type=1325 audit(1631509242.643:80): table=nat family=2 entries=7 op=xt_replace pid=1112 subj==unconfined comm="iptables"
[   38.145844] audit: type=1325 audit(1631509242.675:81): table=nat family=2 entries=8 op=xt_replace pid=1114 subj==unconfined comm="iptables"
[   38.185802] audit: type=1325 audit(1631509242.707:82): table=nat family=2 entries=10 op=xt_replace pid=1115 subj==unconfined comm="iptables"
[   38.347639] eth1: renamed from veth10a4903
[   38.367000] IPv6: ADDRCONF(NETDEV_CHANGE): veth8f27025: link becomes ready
[   38.367139] hassio: port 2(veth8f27025) entered blocking state
[   38.367149] hassio: port 2(veth8f27025) entered forwarding state
[   44.802391] kauditd_printk_skb: 4 callbacks suppressed
[   44.802397] audit: type=1334 audit(1631509249.355:87): prog-id=11 op=LOAD
[   44.802813] audit: type=1334 audit(1631509249.355:88): prog-id=12 op=LOAD
[   45.011337] audit: type=1334 audit(1631509249.563:89): prog-id=13 op=LOAD
[   45.011419] audit: type=1334 audit(1631509249.563:90): prog-id=14 op=LOAD
[   47.332604] audit: type=1400 audit(1631509251.879:91): apparmor="STATUS" operation="profile_replace" info="same as current profile, skipping" profile="unconfined" name="hassio-supervisor" pid=1790 comm="apparmor_parser"
[   47.333088] audit: type=1400 audit(1631509251.879:91): apparmor="STATUS" operation="profile_replace" info="same as current profile, skipping" profile="unconfined" name="hassio-supervisor///usr/bin/gdbus" pid=1790 comm="apparmor_parser"
[   47.333330] audit: type=1400 audit(1631509251.879:91): apparmor="STATUS" operation="profile_replace" info="same as current profile, skipping" profile="unconfined" name="hassio-supervisor///usr/bin/git" pid=1790 comm="apparmor_parser"
[   48.533587] hassio: port 3(vethbd24b78) entered blocking state
[   48.533608] hassio: port 3(vethbd24b78) entered disabled state
[   48.534163] device vethbd24b78 entered promiscuous mode
[   48.534234] audit: type=1700 audit(1631509253.083:92): dev=vethbd24b78 prom=256 old_prom=0 auid=4294967295 uid=0 gid=0 ses=4294967295
[   49.177814] audit: type=1325 audit(1631509253.715:93): table=nat family=2 entries=0 op=xt_register pid=1877 subj==unconfined comm="iptables"
[   49.217817] audit: type=1325 audit(1631509253.743:94): table=filter family=2 entries=0 op=xt_register pid=1878 subj==unconfined comm="iptables"
[   49.575555] eth0: renamed from vethf5e2976
[   49.607650] IPv6: ADDRCONF(NETDEV_CHANGE): vethbd24b78: link becomes ready
[   49.607856] hassio: port 3(vethbd24b78) entered blocking state
[   49.607873] hassio: port 3(vethbd24b78) entered forwarding state
[   50.883128] hassio: port 4(veth7498157) entered blocking state
[   50.883142] hassio: port 4(veth7498157) entered disabled state
[   50.883423] device veth7498157 entered promiscuous mode
[   50.884665] kauditd_printk_skb: 8 callbacks suppressed
[   50.884670] audit: type=1700 audit(1631509255.435:103): dev=veth7498157 prom=256 old_prom=0 auid=4294967295 uid=0 gid=0 ses=4294967295
[   51.597781] audit: type=1325 audit(1631509256.131:104): table=nat family=2 entries=0 op=xt_register pid=2204 subj==unconfined comm="iptables"
[   51.633784] audit: type=1325 audit(1631509256.167:105): table=filter family=2 entries=0 op=xt_register pid=2206 subj==unconfined comm="iptables"
[   51.669933] audit: type=1325 audit(1631509256.199:106): table=nat family=2 entries=5 op=xt_replace pid=2209 subj==unconfined comm="iptables"
[   51.705836] audit: type=1325 audit(1631509256.227:107): table=nat family=2 entries=7 op=xt_replace pid=2210 subj==unconfined comm="iptables"
[   51.741833] audit: type=1325 audit(1631509256.271:108): table=nat family=2 entries=8 op=xt_replace pid=2216 subj==unconfined comm="iptables"
[   51.769781] audit: type=1325 audit(1631509256.299:109): table=nat family=2 entries=10 op=xt_replace pid=2217 subj==unconfined comm="iptables"
[   51.793901] audit: type=1325 audit(1631509256.327:110): table=nat family=2 entries=11 op=xt_replace pid=2218 subj==unconfined comm="iptables"
[   51.821820] audit: type=1325 audit(1631509256.351:111): table=nat family=2 entries=12 op=xt_replace pid=2219 subj==unconfined comm="iptables"
[   51.841851] audit: type=1325 audit(1631509256.379:112): table=nat family=2 entries=13 op=xt_replace pid=2220 subj==unconfined comm="iptables"
[   51.915866] eth0: renamed from veth7c3bc12
[   51.938825] IPv6: ADDRCONF(NETDEV_CHANGE): veth7498157: link becomes ready
[   51.938965] hassio: port 4(veth7498157) entered blocking state
[   51.938974] hassio: port 4(veth7498157) entered forwarding state
[   53.042514] hassio: port 5(veth80cc62f) entered blocking state
[   53.042527] hassio: port 5(veth80cc62f) entered disabled state
[   53.042846] device veth80cc62f entered promiscuous mode
[   54.015117] eth0: renamed from veth7fc6773
[   54.036087] IPv6: ADDRCONF(NETDEV_CHANGE): veth80cc62f: link becomes ready
[   54.036260] hassio: port 5(veth80cc62f) entered blocking state
[   54.036273] hassio: port 5(veth80cc62f) entered forwarding state
[   56.235371] Bluetooth: RFCOMM TTY layer initialized
[   56.235402] Bluetooth: RFCOMM socket layer initialized
[   56.235433] Bluetooth: RFCOMM ver 1.11
[   64.108860] hassio: port 6(veth566204a) entered blocking state
[   64.108878] hassio: port 6(veth566204a) entered disabled state
[   64.109099] device veth566204a entered promiscuous mode
[   64.109168] kauditd_printk_skb: 12 callbacks suppressed
[   64.109172] audit: type=1700 audit(1631509268.659:125): dev=veth566204a prom=256 old_prom=0 auid=4294967295 uid=0 gid=0 ses=4294967295
[   64.253937] audit: type=1325 audit(1631509268.783:126): table=nat family=2 entries=15 op=xt_replace pid=3316 subj==unconfined comm="iptables"
[   64.297808] audit: type=1325 audit(1631509268.819:127): table=filter family=2 entries=30 op=xt_replace pid=3318 subj==unconfined comm="iptables"
[   64.337802] audit: type=1325 audit(1631509268.867:128): table=nat family=2 entries=16 op=xt_replace pid=3320 subj==unconfined comm="iptables"
[   64.925849] audit: type=1325 audit(1631509269.459:129): table=nat family=2 entries=0 op=xt_register pid=3393 subj==unconfined comm="iptables"
[   64.961831] audit: type=1325 audit(1631509269.491:130): table=filter family=2 entries=0 op=xt_register pid=3394 subj==unconfined comm="iptables"
[   65.005845] audit: type=1325 audit(1631509269.531:131): table=nat family=2 entries=5 op=xt_replace pid=3397 subj==unconfined comm="iptables"
[   65.025770] audit: type=1325 audit(1631509269.563:132): table=nat family=2 entries=7 op=xt_replace pid=3398 subj==unconfined comm="iptables"
[   65.057836] audit: type=1325 audit(1631509269.587:133): table=nat family=2 entries=8 op=xt_replace pid=3400 subj==unconfined comm="iptables"
[   65.085738] audit: type=1325 audit(1631509269.619:134): table=nat family=2 entries=10 op=xt_replace pid=3401 subj==unconfined comm="iptables"
[   65.232449] eth0: renamed from vethe0ac450
[   65.247281] IPv6: ADDRCONF(NETDEV_CHANGE): veth566204a: link becomes ready
[   65.247451] hassio: port 6(veth566204a) entered blocking state
[   65.247464] hassio: port 6(veth566204a) entered forwarding state
[   66.570594] hassio: port 7(vetha5fcd2a) entered blocking state
[   66.570610] hassio: port 7(vetha5fcd2a) entered disabled state
[   66.570834] device vetha5fcd2a entered promiscuous mode
[   68.135032] eth0: renamed from vethab9b8d8
[   68.152015] IPv6: ADDRCONF(NETDEV_CHANGE): vetha5fcd2a: link becomes ready
[   68.152165] hassio: port 7(vetha5fcd2a) entered blocking state
[   68.152175] hassio: port 7(vetha5fcd2a) entered forwarding state
[   75.541947] kauditd_printk_skb: 27 callbacks suppressed
[   75.541955] audit: type=1334 audit(1631509280.096:162): prog-id=12 op=UNLOAD
[   75.541984] audit: type=1334 audit(1631509280.096:163): prog-id=11 op=UNLOAD
[   75.582401] audit: type=1334 audit(1631509280.136:164): prog-id=14 op=UNLOAD
[   75.582427] audit: type=1334 audit(1631509280.136:165): prog-id=13 op=UNLOAD
[   77.474526] hassio: port 8(veth0636018) entered blocking state
[   77.474542] hassio: port 8(veth0636018) entered disabled state
[   77.474791] device veth0636018 entered promiscuous mode
[   77.475062] audit: type=1700 audit(1631509282.028:166): dev=veth0636018 prom=256 old_prom=0 auid=4294967295 uid=0 gid=0 ses=4294967295
[   77.585550] audit: type=1325 audit(1631509282.116:167): table=nat family=2 entries=25 op=xt_replace pid=4768 subj==unconfined comm="iptables"
[   77.617645] audit: type=1325 audit(1631509282.152:168): table=filter family=2 entries=35 op=xt_replace pid=4770 subj==unconfined comm="iptables"
[   77.673557] audit: type=1325 audit(1631509282.184:169): table=nat family=2 entries=26 op=xt_replace pid=4775 subj==unconfined comm="iptables"
[   78.325592] audit: type=1325 audit(1631509282.852:170): table=nat family=2 entries=0 op=xt_register pid=4861 subj==unconfined comm="iptables"
[   78.365730] audit: type=1325 audit(1631509282.896:171): table=filter family=2 entries=0 op=xt_register pid=4868 subj==unconfined comm="iptables"
[   78.666899] eth0: renamed from veth85a95ca
[   78.687607] IPv6: ADDRCONF(NETDEV_CHANGE): veth0636018: link becomes ready
[   78.687752] hassio: port 8(veth0636018) entered blocking state
[   78.687761] hassio: port 8(veth0636018) entered forwarding state
[  159.325608] hassio: port 9(vethafa6902) entered blocking state
[  159.325622] hassio: port 9(vethafa6902) entered disabled state
[  159.326526] device vethafa6902 entered promiscuous mode
[  159.326603] kauditd_printk_skb: 8 callbacks suppressed
[  159.326608] audit: type=1700 audit(1631509363.875:180): dev=vethafa6902 prom=256 old_prom=0 auid=4294967295 uid=0 gid=0 ses=4294967295
[  160.042430] audit: type=1325 audit(1631509364.571:181): table=nat family=2 entries=0 op=xt_register pid=6067 subj==unconfined comm="iptables"
[  160.078398] audit: type=1325 audit(1631509364.603:182): table=filter family=2 entries=0 op=xt_register pid=6068 subj==unconfined comm="iptables"
[  160.143034] audit: type=1325 audit(1631509364.659:183): table=nat family=2 entries=5 op=xt_replace pid=6072 subj==unconfined comm="iptables"
[  160.170443] audit: type=1325 audit(1631509364.699:184): table=nat family=2 entries=7 op=xt_replace pid=6073 subj==unconfined comm="iptables"
[  160.206380] audit: type=1325 audit(1631509364.731:185): table=nat family=2 entries=8 op=xt_replace pid=6075 subj==unconfined comm="iptables"
[  160.222381] audit: type=1325 audit(1631509364.763:186): table=nat family=2 entries=10 op=xt_replace pid=6076 subj==unconfined comm="iptables"
[  160.250534] audit: type=1325 audit(1631509364.783:187): table=nat family=2 entries=11 op=xt_replace pid=6077 subj==unconfined comm="iptables"
[  160.278456] audit: type=1325 audit(1631509364.811:188): table=nat family=2 entries=12 op=xt_replace pid=6078 subj==unconfined comm="iptables"
[  160.306412] audit: type=1325 audit(1631509364.843:189): table=nat family=2 entries=13 op=xt_replace pid=6079 subj==unconfined comm="iptables"
[  160.378873] eth0: renamed from vethdd52610
[  160.412830] IPv6: ADDRCONF(NETDEV_CHANGE): vethafa6902: link becomes ready
[  160.413000] hassio: port 9(vethafa6902) entered blocking state
[  160.413009] hassio: port 9(vethafa6902) entered forwarding state
[  161.805322] hassio: port 10(veth8018e20) entered blocking state
[  161.805336] hassio: port 10(veth8018e20) entered disabled state
[  161.806092] device veth8018e20 entered promiscuous mode
[  163.185464] eth0: renamed from veth31b22b0
[  163.208777] IPv6: ADDRCONF(NETDEV_CHANGE): veth8018e20: link becomes ready
[  163.208938] hassio: port 10(veth8018e20) entered blocking state
[  163.208948] hassio: port 10(veth8018e20) entered forwarding state
[  176.484558] kauditd_printk_skb: 15 callbacks suppressed
[  176.484565] audit: type=1334 audit(1631509381.034:205): prog-id=15 op=LOAD
[  176.485046] audit: type=1334 audit(1631509381.034:206): prog-id=16 op=LOAD
[  177.114174] audit: type=1334 audit(1631509381.662:207): prog-id=17 op=LOAD
[  177.114957] audit: type=1334 audit(1631509381.662:208): prog-id=18 op=LOAD
[  207.047098] audit: type=1334 audit(1631509411.598:209): prog-id=16 op=UNLOAD
[  207.047129] audit: type=1334 audit(1631509411.598:210): prog-id=15 op=UNLOAD
[  207.655655] audit: type=1334 audit(1631509412.206:211): prog-id=18 op=UNLOAD
[  207.655695] audit: type=1334 audit(1631509412.206:212): prog-id=17 op=UNLOAD
[ 7776.574886] audit: type=1334 audit(1631516981.010:213): prog-id=19 op=LOAD
[ 7776.575067] audit: type=1334 audit(1631516981.010:214): prog-id=20 op=LOAD
[ 7776.933545] audit: type=1334 audit(1631516981.370:215): prog-id=21 op=LOAD
[ 7776.933600] audit: type=1334 audit(1631516981.370:216): prog-id=22 op=LOAD
[ 7806.949045] audit: type=1334 audit(1631517011.385:217): prog-id=20 op=UNLOAD
[ 7806.949073] audit: type=1334 audit(1631517011.385:218): prog-id=19 op=UNLOAD
[ 7807.305360] audit: type=1334 audit(1631517011.741:219): prog-id=22 op=UNLOAD
[ 7807.305410] audit: type=1334 audit(1631517011.741:220): prog-id=21 op=UNLOAD
[15380.267158] audit: type=1334 audit(1631524584.588:221): prog-id=23 op=LOAD
[15380.267326] audit: type=1334 audit(1631524584.588:222): prog-id=24 op=LOAD
[15380.620533] audit: type=1334 audit(1631524584.940:223): prog-id=25 op=LOAD
[15380.621472] audit: type=1334 audit(1631524584.944:224): prog-id=26 op=LOAD
[15410.642519] audit: type=1334 audit(1631524614.964:225): prog-id=24 op=UNLOAD
[15410.642556] audit: type=1334 audit(1631524614.964:226): prog-id=23 op=UNLOAD
[15410.986093] audit: type=1334 audit(1631524615.308:227): prog-id=26 op=UNLOAD
[15410.986137] audit: type=1334 audit(1631524615.308:228): prog-id=25 op=UNLOAD
[22983.982170] audit: type=1334 audit(1631532188.189:229): prog-id=27 op=LOAD
[22983.982400] audit: type=1334 audit(1631532188.189:230): prog-id=28 op=LOAD
[22984.332911] audit: type=1334 audit(1631532188.541:231): prog-id=29 op=LOAD
[22984.333403] audit: type=1334 audit(1631532188.541:232): prog-id=30 op=LOAD
[23014.358240] audit: type=1334 audit(1631532218.565:233): prog-id=28 op=UNLOAD
[23014.358275] audit: type=1334 audit(1631532218.565:234): prog-id=27 op=UNLOAD
[23014.656521] audit: type=1334 audit(1631532218.865:235): prog-id=30 op=UNLOAD
[23014.656552] audit: type=1334 audit(1631532218.865:236): prog-id=29 op=UNLOAD
[28849.517266] audit: type=1325 audit(1631538053.608:237): table=nat family=2 entries=29 op=xt_replace pid=16220 subj==unconfined comm="iptables"
[28849.553231] audit: type=1325 audit(1631538053.648:238): table=filter family=2 entries=37 op=xt_replace pid=16223 subj==unconfined comm="iptables"
[28849.593212] audit: type=1325 audit(1631538053.688:239): table=nat family=2 entries=28 op=xt_replace pid=16225 subj==unconfined comm="iptables"
[28849.669792] hassio: port 8(veth0636018) entered disabled state
[28849.670278] veth85a95ca: renamed from eth0
[28849.785039] hassio: port 8(veth0636018) entered disabled state
[28849.787527] device veth0636018 left promiscuous mode
[28849.787761] audit: type=1700 audit(1631538053.880:240): dev=veth0636018 prom=0 old_prom=256 auid=4294967295 uid=0 gid=0 ses=4294967295
[28849.788714] hassio: port 8(veth0636018) entered disabled state
[28849.966754] audit: type=1325 audit(1631538054.084:241): table=nat family=2 entries=15 op=xt_unregister pid=15982 subj==unconfined comm="kworker/u8:1"
[28849.966897] audit: type=1325 audit(1631538054.084:242): table=filter family=2 entries=4 op=xt_unregister pid=15982 subj==unconfined comm="kworker/u8:1"
[28857.110051] hassio: port 8(veth91b13a8) entered blocking state
[28857.110069] hassio: port 8(veth91b13a8) entered disabled state
[28857.110313] device veth91b13a8 entered promiscuous mode
[28857.110389] audit: type=1700 audit(1631538061.228:243): dev=veth91b13a8 prom=256 old_prom=0 auid=4294967295 uid=0 gid=0 ses=4294967295
[28857.245342] audit: type=1325 audit(1631538061.340:244): table=nat family=2 entries=27 op=xt_replace pid=16237 subj==unconfined comm="iptables"
[28857.273436] audit: type=1325 audit(1631538061.376:245): table=filter family=2 entries=36 op=xt_replace pid=16239 subj==unconfined comm="iptables"
[28857.309366] audit: type=1325 audit(1631538061.404:246): table=nat family=2 entries=28 op=xt_replace pid=16241 subj==unconfined comm="iptables"
[28857.893316] audit: type=1325 audit(1631538061.984:247): table=nat family=2 entries=0 op=xt_register pid=16317 subj==unconfined comm="iptables"
[28857.929284] audit: type=1325 audit(1631538062.024:248): table=filter family=2 entries=0 op=xt_register pid=16319 subj==unconfined comm="iptables"
[28857.965415] audit: type=1325 audit(1631538062.064:249): table=nat family=2 entries=5 op=xt_replace pid=16322 subj==unconfined comm="iptables"
[28857.989393] audit: type=1325 audit(1631538062.088:250): table=nat family=2 entries=7 op=xt_replace pid=16323 subj==unconfined comm="iptables"
[28858.017252] audit: type=1325 audit(1631538062.116:251): table=nat family=2 entries=8 op=xt_replace pid=16325 subj==unconfined comm="iptables"
[28858.049245] audit: type=1325 audit(1631538062.140:252): table=nat family=2 entries=10 op=xt_replace pid=16326 subj==unconfined comm="iptables"
[28858.192722] eth0: renamed from vethcb99cdd
[28858.215065] IPv6: ADDRCONF(NETDEV_CHANGE): veth91b13a8: link becomes ready
[28858.215270] hassio: port 8(veth91b13a8) entered blocking state
[28858.215284] hassio: port 8(veth91b13a8) entered forwarding state
[30587.748445] kauditd_printk_skb: 4 callbacks suppressed
[30587.748452] audit: type=1334 audit(1631539791.842:257): prog-id=31 op=LOAD
[30587.748656] audit: type=1334 audit(1631539791.842:258): prog-id=32 op=LOAD
[30588.116506] audit: type=1334 audit(1631539792.210:259): prog-id=33 op=LOAD
[30588.117031] audit: type=1334 audit(1631539792.210:260): prog-id=34 op=LOAD
[30618.131796] audit: type=1334 audit(1631539822.225:261): prog-id=32 op=UNLOAD
[30618.131820] audit: type=1334 audit(1631539822.225:262): prog-id=31 op=UNLOAD
[30618.476469] audit: type=1334 audit(1631539822.569:263): prog-id=34 op=UNLOAD
[30618.476489] audit: type=1334 audit(1631539822.569:264): prog-id=33 op=UNLOAD
[34915.012554] audit: type=1325 audit(1631544119.001:265): table=nat family=2 entries=29 op=xt_replace pid=19567 subj==unconfined comm="iptables"
[34915.048547] audit: type=1325 audit(1631544119.053:266): table=filter family=2 entries=37 op=xt_replace pid=19580 subj==unconfined comm="iptables"
[34915.084566] audit: type=1325 audit(1631544119.089:267): table=nat family=2 entries=28 op=xt_replace pid=19582 subj==unconfined comm="iptables"
[34915.128558] audit: type=1325 audit(1631544119.133:268): table=nat family=2 entries=27 op=xt_replace pid=19585 subj==unconfined comm="iptables"
[34915.164535] audit: type=1325 audit(1631544119.169:269): table=filter family=2 entries=36 op=xt_replace pid=19587 subj==unconfined comm="iptables"
[34915.192488] audit: type=1325 audit(1631544119.205:270): table=nat family=2 entries=26 op=xt_replace pid=19589 subj==unconfined comm="iptables"
[34915.244551] audit: type=1325 audit(1631544119.237:271): table=nat family=2 entries=25 op=xt_replace pid=19591 subj==unconfined comm="iptables"
[34915.276543] audit: type=1325 audit(1631544119.285:272): table=filter family=2 entries=35 op=xt_replace pid=19593 subj==unconfined comm="iptables"
[34915.312513] audit: type=1325 audit(1631544119.317:273): table=nat family=2 entries=24 op=xt_replace pid=19595 subj==unconfined comm="iptables"
[34915.360505] audit: type=1325 audit(1631544119.361:274): table=nat family=2 entries=23 op=xt_replace pid=19597 subj==unconfined comm="iptables"
[34915.482950] hassio: port 7(vetha5fcd2a) entered disabled state
[34915.483377] vethab9b8d8: renamed from eth0
[34915.620389] hassio: port 7(vetha5fcd2a) entered disabled state
[34915.621588] device vetha5fcd2a left promiscuous mode
[34915.622021] hassio: port 7(vetha5fcd2a) entered disabled state
[34917.593520] hassio: port 7(veth5577ed8) entered blocking state
[34917.593536] hassio: port 7(veth5577ed8) entered disabled state
[34917.593808] device veth5577ed8 entered promiscuous mode
[34919.137409] eth0: renamed from veth220dc48
[34919.157554] IPv6: ADDRCONF(NETDEV_CHANGE): veth5577ed8: link becomes ready
[34919.157669] hassio: port 7(veth5577ed8) entered blocking state
[34919.157676] hassio: port 7(veth5577ed8) entered forwarding state
[35058.559049] kauditd_printk_skb: 28 callbacks suppressed
[35058.559061] audit: type=1325 audit(1631544262.563:303): table=nat family=2 entries=29 op=xt_replace pid=20261 subj==unconfined comm="iptables"
[35058.598278] audit: type=1325 audit(1631544262.595:304): table=filter family=2 entries=37 op=xt_replace pid=20263 subj==unconfined comm="iptables"
[35058.630191] audit: type=1325 audit(1631544262.639:305): table=nat family=2 entries=28 op=xt_replace pid=20265 subj==unconfined comm="iptables"
[35058.670259] audit: type=1325 audit(1631544262.675:306): table=nat family=2 entries=27 op=xt_replace pid=20267 subj==unconfined comm="iptables"
[35058.710297] audit: type=1325 audit(1631544262.707:307): table=filter family=2 entries=36 op=xt_replace pid=20269 subj==unconfined comm="iptables"
[35058.766174] audit: type=1325 audit(1631544262.747:308): table=nat family=2 entries=26 op=xt_replace pid=20271 subj==unconfined comm="iptables"
[35058.806534] audit: type=1325 audit(1631544262.807:309): table=nat family=2 entries=25 op=xt_replace pid=20275 subj==unconfined comm="iptables"
[35058.842198] audit: type=1325 audit(1631544262.847:310): table=filter family=2 entries=35 op=xt_replace pid=20277 subj==unconfined comm="iptables"
[35058.882295] audit: type=1325 audit(1631544262.883:311): table=nat family=2 entries=24 op=xt_replace pid=20279 subj==unconfined comm="iptables"
[35058.926342] audit: type=1325 audit(1631544262.923:312): table=nat family=2 entries=23 op=xt_replace pid=20282 subj==unconfined comm="iptables"
[35059.055821] hassio: port 7(veth5577ed8) entered disabled state
[35059.056246] veth220dc48: renamed from eth0
[35059.194103] hassio: port 7(veth5577ed8) entered disabled state
[35059.195785] device veth5577ed8 left promiscuous mode
[35059.196274] hassio: port 7(veth5577ed8) entered disabled state
[35060.934790] hassio: port 7(vethba448d6) entered blocking state
[35060.934805] hassio: port 7(vethba448d6) entered disabled state
[35060.935023] device vethba448d6 entered promiscuous mode
[35062.495470] eth0: renamed from vethe8ff2b3
[35062.515245] IPv6: ADDRCONF(NETDEV_CHANGE): vethba448d6: link becomes ready
[35062.515372] hassio: port 7(vethba448d6) entered blocking state
[35062.515379] hassio: port 7(vethba448d6) entered forwarding state
[36323.031426] kauditd_printk_skb: 28 callbacks suppressed
[36323.031438] audit: type=1325 audit(1631545527.009:341): table=nat family=2 entries=29 op=xt_replace pid=21078 subj==unconfined comm="iptables"
[36323.063897] audit: type=1325 audit(1631545527.049:342): table=filter family=2 entries=37 op=xt_replace pid=21080 subj==unconfined comm="iptables"
[36323.103877] audit: type=1325 audit(1631545527.085:343): table=nat family=2 entries=28 op=xt_replace pid=21082 subj==unconfined comm="iptables"
[36323.155846] audit: type=1325 audit(1631545527.133:344): table=nat family=2 entries=27 op=xt_replace pid=21084 subj==unconfined comm="iptables"
[36323.188044] audit: type=1325 audit(1631545527.177:345): table=filter family=2 entries=36 op=xt_replace pid=21086 subj==unconfined comm="iptables"
[36323.231830] audit: type=1325 audit(1631545527.213:346): table=nat family=2 entries=26 op=xt_replace pid=21088 subj==unconfined comm="iptables"
[36323.275935] audit: type=1325 audit(1631545527.257:347): table=nat family=2 entries=25 op=xt_replace pid=21091 subj==unconfined comm="iptables"
[36323.307849] audit: type=1325 audit(1631545527.297:348): table=filter family=2 entries=35 op=xt_replace pid=21093 subj==unconfined comm="iptables"
[36323.348102] audit: type=1325 audit(1631545527.329:349): table=nat family=2 entries=24 op=xt_replace pid=21095 subj==unconfined comm="iptables"
[36323.399978] audit: type=1325 audit(1631545527.377:350): table=nat family=2 entries=23 op=xt_replace pid=21100 subj==unconfined comm="iptables"
[36323.547363] hassio: port 7(vethba448d6) entered disabled state
[36323.548173] vethe8ff2b3: renamed from eth0
[36323.631733] hassio: port 7(vethba448d6) entered disabled state
[36323.632940] device vethba448d6 left promiscuous mode
[36323.632971] hassio: port 7(vethba448d6) entered disabled state
[36325.534001] hassio: port 7(vethe7cb0e7) entered blocking state
[36325.534016] hassio: port 7(vethe7cb0e7) entered disabled state
[36325.534520] device vethe7cb0e7 entered promiscuous mode
[36327.009006] eth0: renamed from vethad9d1f5
[36327.033132] IPv6: ADDRCONF(NETDEV_CHANGE): vethe7cb0e7: link becomes ready
[36327.033283] hassio: port 7(vethe7cb0e7) entered blocking state
[36327.033293] hassio: port 7(vethe7cb0e7) entered forwarding state
[36468.222016] kauditd_printk_skb: 28 callbacks suppressed
[36468.222024] audit: type=1325 audit(1631545672.203:379): table=nat family=2 entries=29 op=xt_replace pid=21789 subj==unconfined comm="iptables"
[36468.262099] audit: type=1325 audit(1631545672.239:380): table=filter family=2 entries=37 op=xt_replace pid=21796 subj==unconfined comm="iptables"
[36468.293994] audit: type=1325 audit(1631545672.279:381): table=nat family=2 entries=28 op=xt_replace pid=21798 subj==unconfined comm="iptables"
[36468.330282] audit: type=1325 audit(1631545672.315:382): table=nat family=2 entries=27 op=xt_replace pid=21803 subj==unconfined comm="iptables"
[36468.370005] audit: type=1325 audit(1631545672.351:383): table=filter family=2 entries=36 op=xt_replace pid=21805 subj==unconfined comm="iptables"
[36468.406034] audit: type=1325 audit(1631545672.391:384): table=nat family=2 entries=26 op=xt_replace pid=21807 subj==unconfined comm="iptables"
[36468.450070] audit: type=1325 audit(1631545672.431:385): table=nat family=2 entries=25 op=xt_replace pid=21811 subj==unconfined comm="iptables"
[36468.482098] audit: type=1325 audit(1631545672.467:386): table=filter family=2 entries=35 op=xt_replace pid=21813 subj==unconfined comm="iptables"
[36468.518010] audit: type=1325 audit(1631545672.503:387): table=nat family=2 entries=24 op=xt_replace pid=21815 subj==unconfined comm="iptables"
[36468.570095] audit: type=1325 audit(1631545672.539:388): table=nat family=2 entries=23 op=xt_replace pid=21818 subj==unconfined comm="iptables"
[36468.730213] hassio: port 7(vethe7cb0e7) entered disabled state
[36468.731075] vethad9d1f5: renamed from eth0
[36468.825870] hassio: port 7(vethe7cb0e7) entered disabled state
[36468.827164] device vethe7cb0e7 left promiscuous mode
[36468.827195] hassio: port 7(vethe7cb0e7) entered disabled state
[36470.597808] hassio: port 7(vetha5d1e10) entered blocking state
[36470.597824] hassio: port 7(vetha5d1e10) entered disabled state
[36470.598391] device vetha5d1e10 entered promiscuous mode
[36472.147963] eth0: renamed from vethe9b5ccb
[36472.169207] IPv6: ADDRCONF(NETDEV_CHANGE): vetha5d1e10: link becomes ready
[36472.169360] hassio: port 7(vetha5d1e10) entered blocking state
[36472.169370] hassio: port 7(vetha5d1e10) entered forwarding state
[38191.495842] kauditd_printk_skb: 28 callbacks suppressed
[38191.495848] audit: type=1334 audit(1631547395.478:417): prog-id=35 op=LOAD
[38191.496397] audit: type=1334 audit(1631547395.478:418): prog-id=36 op=LOAD
[38191.857624] audit: type=1334 audit(1631547395.838:419): prog-id=37 op=LOAD
[38191.858113] audit: type=1334 audit(1631547395.838:420): prog-id=38 op=LOAD
[38221.863916] audit: type=1334 audit(1631547425.845:421): prog-id=36 op=UNLOAD
[38221.863950] audit: type=1334 audit(1631547425.845:422): prog-id=35 op=UNLOAD
[38222.196498] audit: type=1334 audit(1631547426.177:423): prog-id=38 op=UNLOAD
[38222.196543] audit: type=1334 audit(1631547426.177:424): prog-id=37 op=UNLOAD
[45795.245517] audit: type=1334 audit(1631554999.112:425): prog-id=39 op=LOAD
[45795.245651] audit: type=1334 audit(1631554999.112:426): prog-id=40 op=LOAD
[45795.618770] audit: type=1334 audit(1631554999.484:427): prog-id=41 op=LOAD
[45795.619158] audit: type=1334 audit(1631554999.484:428): prog-id=42 op=LOAD
[45825.614564] audit: type=1334 audit(1631555029.479:429): prog-id=40 op=UNLOAD
[45825.614586] audit: type=1334 audit(1631555029.479:430): prog-id=39 op=UNLOAD
[45825.965791] audit: type=1334 audit(1631555029.831:431): prog-id=42 op=UNLOAD
[45825.965816] audit: type=1334 audit(1631555029.831:432): prog-id=41 op=UNLOAD
[53399.004254] audit: type=1334 audit(1631562602.761:433): prog-id=43 op=LOAD
[53399.004461] audit: type=1334 audit(1631562602.761:434): prog-id=44 op=LOAD
[53399.367187] audit: type=1334 audit(1631562603.121:435): prog-id=45 op=LOAD
[53399.367579] audit: type=1334 audit(1631562603.121:436): prog-id=46 op=LOAD
[53429.380579] audit: type=1334 audit(1631562633.137:437): prog-id=44 op=UNLOAD
[53429.380611] audit: type=1334 audit(1631562633.137:438): prog-id=43 op=UNLOAD
[53429.717782] audit: type=1334 audit(1631562633.472:439): prog-id=46 op=UNLOAD
[53429.717828] audit: type=1334 audit(1631562633.472:440): prog-id=45 op=UNLOAD
[61002.851450] audit: type=1334 audit(1631570206.484:441): prog-id=47 op=LOAD
[61002.851642] audit: type=1334 audit(1631570206.484:442): prog-id=48 op=LOAD
[61003.212131] audit: type=1334 audit(1631570206.844:443): prog-id=49 op=LOAD
[61003.212280] audit: type=1334 audit(1631570206.844:444): prog-id=50 op=LOAD
[61033.205158] audit: type=1334 audit(1631570236.840:445): prog-id=48 op=UNLOAD
[61033.205194] audit: type=1334 audit(1631570236.840:446): prog-id=47 op=UNLOAD
[61033.585396] audit: type=1334 audit(1631570237.220:447): prog-id=50 op=UNLOAD
[61033.585429] audit: type=1334 audit(1631570237.220:448): prog-id=49 op=UNLOAD
[68606.681512] audit: type=1334 audit(1631577810.201:449): prog-id=51 op=LOAD
[68606.681614] audit: type=1334 audit(1631577810.201:450): prog-id=52 op=LOAD
[68607.047384] audit: type=1334 audit(1631577810.565:451): prog-id=53 op=LOAD
[68607.047465] audit: type=1334 audit(1631577810.565:452): prog-id=54 op=LOAD
[68637.056549] audit: type=1334 audit(1631577840.576:453): prog-id=52 op=UNLOAD
[68637.056576] audit: type=1334 audit(1631577840.576:454): prog-id=51 op=UNLOAD
[68637.400439] audit: type=1334 audit(1631577840.920:455): prog-id=54 op=UNLOAD
[68637.400463] audit: type=1334 audit(1631577840.920:456): prog-id=53 op=UNLOAD
[76210.441709] audit: type=1334 audit(1631585413.847:457): prog-id=55 op=LOAD
[76210.441941] audit: type=1334 audit(1631585413.847:458): prog-id=56 op=LOAD
[76210.846794] audit: type=1334 audit(1631585414.251:459): prog-id=57 op=LOAD
[76210.847200] audit: type=1334 audit(1631585414.251:460): prog-id=58 op=LOAD
[76240.863331] audit: type=1334 audit(1631585444.267:461): prog-id=56 op=UNLOAD
[76240.863371] audit: type=1334 audit(1631585444.267:462): prog-id=55 op=UNLOAD
[76241.207231] audit: type=1334 audit(1631585444.611:463): prog-id=58 op=UNLOAD
[76241.207278] audit: type=1334 audit(1631585444.611:464): prog-id=57 op=UNLOAD
[83814.334006] audit: type=1334 audit(1631593017.622:465): prog-id=59 op=LOAD
[83814.334404] audit: type=1334 audit(1631593017.626:466): prog-id=60 op=LOAD
[83814.727047] audit: type=1334 audit(1631593018.018:467): prog-id=61 op=LOAD
[83814.727560] audit: type=1334 audit(1631593018.018:468): prog-id=62 op=LOAD
[83844.747520] audit: type=1334 audit(1631593048.038:469): prog-id=60 op=UNLOAD
[83844.747555] audit: type=1334 audit(1631593048.038:470): prog-id=59 op=UNLOAD
[83845.103438] audit: type=1334 audit(1631593048.394:471): prog-id=62 op=UNLOAD
[83845.103473] audit: type=1334 audit(1631593048.394:472): prog-id=61 op=UNLOAD
[91418.230231] audit: type=1334 audit(1631600621.407:473): prog-id=63 op=LOAD
[91418.230415] audit: type=1334 audit(1631600621.407:474): prog-id=64 op=LOAD
[91418.618323] audit: type=1334 audit(1631600621.795:475): prog-id=65 op=LOAD
[91418.618831] audit: type=1334 audit(1631600621.795:476): prog-id=66 op=LOAD
[91448.633882] audit: type=1334 audit(1631600651.811:477): prog-id=64 op=UNLOAD
[91448.633910] audit: type=1334 audit(1631600651.811:478): prog-id=63 op=UNLOAD
[91448.987567] audit: type=1334 audit(1631600652.163:479): prog-id=66 op=UNLOAD
[91448.987606] audit: type=1334 audit(1631600652.163:480): prog-id=65 op=UNLOAD
[99022.117711] audit: type=1334 audit(1631608225.180:481): prog-id=67 op=LOAD
[99022.118042] audit: type=1334 audit(1631608225.180:482): prog-id=68 op=LOAD
[99022.483453] audit: type=1334 audit(1631608225.544:483): prog-id=69 op=LOAD
[99022.483694] audit: type=1334 audit(1631608225.544:484): prog-id=70 op=LOAD
[99052.497729] audit: type=1334 audit(1631608255.559:485): prog-id=68 op=UNLOAD
[99052.497754] audit: type=1334 audit(1631608255.559:486): prog-id=67 op=UNLOAD
[99052.830242] audit: type=1334 audit(1631608255.891:487): prog-id=70 op=UNLOAD
[99052.830264] audit: type=1334 audit(1631608255.891:488): prog-id=69 op=UNLOAD
[106625.946946] audit: type=1334 audit(1631615828.893:489): prog-id=71 op=LOAD
[106625.947143] audit: type=1334 audit(1631615828.893:490): prog-id=72 op=LOAD
[106626.337723] audit: type=1334 audit(1631615829.285:491): prog-id=73 op=LOAD
[106626.337820] audit: type=1334 audit(1631615829.285:492): prog-id=74 op=LOAD
[106656.332372] audit: type=1334 audit(1631615859.281:493): prog-id=72 op=UNLOAD
[106656.332409] audit: type=1334 audit(1631615859.281:494): prog-id=71 op=UNLOAD
[106656.684002] audit: type=1334 audit(1631615859.633:495): prog-id=74 op=UNLOAD
[106656.684036] audit: type=1334 audit(1631615859.633:496): prog-id=73 op=UNLOAD
[114229.820678] audit: type=1334 audit(1631623432.646:497): prog-id=75 op=LOAD
[114229.820879] audit: type=1334 audit(1631623432.646:498): prog-id=76 op=LOAD
[114230.217906] audit: type=1334 audit(1631623433.042:499): prog-id=77 op=LOAD
[114230.218530] audit: type=1334 audit(1631623433.042:500): prog-id=78 op=LOAD
[114260.236037] audit: type=1334 audit(1631623463.066:501): prog-id=76 op=UNLOAD
[114260.236064] audit: type=1334 audit(1631623463.066:502): prog-id=75 op=UNLOAD
[114260.563397] audit: type=1334 audit(1631623463.394:503): prog-id=78 op=UNLOAD
[114260.564049] audit: type=1334 audit(1631623463.394:504): prog-id=77 op=UNLOAD

If there is anything else more I can provide, let me know. But explains how I get it like I was 5 years old.....lol.

gmlupatelli commented 2 years ago

More information:

I did some debugging with a new device that I just configured using ESPHome, and now I can't even make it work for the first time. Unless I am doing something wrong with its configuration (I never used ESPHome and MQTT), now MQTT autodiscover isn't working at all.

Debugging:

I got an ESP8299 and installed ESPHome in it and configure it to use MQTT. First I made sure that I got everything working with the API, and then I changed it to use MQTT.

Here is the ESP home configuration:

esphome:
  name: temp-device
  platform: ESP8266
  board: esp01_1m

# Enable logging
logger:
  level: DEBUG

# Enable Home Assistant API
# api:

# Enable MQTT
mqtt:
  broker: homeassistant

ota:
  password: "xxxxxxxxxxxxxxxxxxxxxxxxxx"

wifi:
  ssid: "Skynet"
  password: !secret wifi_password

  # Enable fallback hotspot (captive portal) in case wifi connection fails
  ap:
    ssid: "Temp-Device Fallback Hotspot"
    password: "xxxxxxxxxxxxxxxxxxx"

captive_portal:

# Device Configuration
light:
  - platform: binary
    name: "Onboard LED"
    output: light_output

output:
  - id: light_output
    platform: gpio
    pin: GPIO2
    inverted: true

However I can't see the device in MQTT Explorer or in Home assistant.

Mosquito Broker Log

1631630727: mosquitto version 1.6.12 starting
1631630727: |-- *** auth-plug: startup
[10:45:28] INFO: Successfully send discovery information to Home Assistant.
1631630727: Config loaded from /etc/mosquitto/mosquitto.conf.
1631630727: Loading plugin: /usr/share/mosquitto/auth-plug.so
1631630727:  ├── Username/password checking enabled.
1631630727:  ├── TLS-PSK checking enabled.
1631630727:  └── Extended authentication not enabled.
1631630727: Opening ipv4 listen socket on port 1883.
1631630727: Opening ipv6 listen socket on port 1883.
1631630727: Opening websockets listen socket on port 1884.
1631630728: Opening ipv4 listen socket on port 8883.
1631630728: Opening ipv6 listen socket on port 8883.
1631630728: Opening websockets listen socket on port 8884.
1631630728: Warning: Mosquitto should not be run as root/administrator.
1631630728: mosquitto version 1.6.12 running
1631630728: New connection from 127.0.0.1 on port 1883.
1631630728: Socket error on client <unknown>, disconnecting.
1631630728: New connection from 172.30.32.1 on port 1883.
1631630728: New client connected from 172.30.32.1 as 0lbm8Tw6mfdrjZC5JBy79w (p2, c1, k60, u'homeassistant').
[10:45:29] INFO: Successfully send service information to the Supervisor.
1631630728: New connection from 192.168.86.45 on port 1883.
{"result": "ok", "data": {}}1631630728: New client connected from 192.168.86.45 as mqtt-explorer-36d9a907 (p2, c1, k60, u'mqttuser').
1631630735: New connection from 192.168.86.202 on port 1883.
1631630735: New client connected from 192.168.86.202 as 3vnd5l2wjZWpHDqDN67hUY (p1, c1, k60).
1631630826: New connection from 172.30.32.2 on port 1883.
1631630826: Socket error on client <unknown>, disconnecting.
1631630946: New connection from 172.30.32.2 on port 1883.
1631630946: Socket error on client <unknown>, disconnecting.
1631631066: New connection from 172.30.32.2 on port 1883.
1631631066: Socket error on client <unknown>, disconnecting.
1631631186: New connection from 172.30.32.2 on port 1883.
1631631186: Socket error on client <unknown>, disconnecting.
1631631306: New connection from 172.30.32.2 on port 1883.
1631631306: Socket error on client <unknown>, disconnecting.
1631631426: New connection from 172.30.32.2 on port 1883.
1631631426: Socket error on client <unknown>, disconnecting.
1631631546: New connection from 172.30.32.2 on port 1883.
1631631546: Socket error on client <unknown>, disconnecting.
1631631614: New connection from 192.168.86.54 on port 1883.
1631631614: New client connected from 192.168.86.54 as temp-device-c45bbe485d5e (p2, c1, k15).
1631631666: New connection from 172.30.32.2 on port 1883.
1631631666: Socket error on client <unknown>, disconnecting.
1631631786: New connection from 172.30.32.2 on port 1883.
1631631786: Socket error on client <unknown>, disconnecting.
1631631906: New connection from 172.30.32.2 on port 1883.
1631631906: Socket error on client <unknown>, disconnecting.
1631632026: New connection from 172.30.32.2 on port 1883.
1631632026: Socket error on client <unknown>, disconnecting.
1631632146: New connection from 172.30.32.2 on port 1883.
1631632146: Socket error on client <unknown>, disconnecting.
1631632266: New connection from 172.30.32.2 on port 1883.
1631632266: Socket error on client <unknown>, disconnecting.
1631632386: New connection from 172.30.32.2 on port 1883.
1631632386: Socket error on client <unknown>, disconnecting.
1631632410: New connection from 172.30.32.1 on port 1883.
1631632410: New client connected from 172.30.32.1 as auto-0CDACE3B-400F-C10E-5C56-8DB4D45E5680 (p2, c1, k60).
1631632424: New connection from 192.168.86.54 on port 1883.
1631632424: Client temp-device-c45bbe485d5e already connected, closing old connection.
1631632424: New client connected from 192.168.86.54 as temp-device-c45bbe485d5e (p2, c1, k15).
1631632486: Socket error on client auto-0CDACE3B-400F-C10E-5C56-8DB4D45E5680, disconnecting.
1631632495: New connection from 172.30.32.1 on port 1883.
1631632495: New client connected from 172.30.32.1 as auto-149016AF-D2E2-08C2-4AB7-DA37B4214AD5 (p2, c1, k60).
1631632506: New connection from 172.30.32.2 on port 1883.
1631632506: Socket error on client <unknown>, disconnecting.
1631632528: Saving in-memory database to /data/mosquitto.db.
1631632626: New connection from 172.30.32.2 on port 1883.
1631632626: Socket error on client <unknown>, disconnecting.
1631632746: New connection from 172.30.32.2 on port 1883.
1631632746: Socket error on client <unknown>, disconnecting.
1631632866: New connection from 172.30.32.2 on port 1883.
1631632866: Socket error on client <unknown>, disconnecting.
1631632986: New connection from 172.30.32.2 on port 1883.
1631632986: Socket error on client <unknown>, disconnecting.
1631633064: New connection from 192.168.86.54 on port 1883.
1631633064: Client temp-device-c45bbe485d5e already connected, closing old connection.
1631633064: New client connected from 192.168.86.54 as temp-device-c45bbe485d5e (p2, c1, k15).
1631633106: New connection from 172.30.32.2 on port 1883.
1631633106: Socket error on client <unknown>, disconnecting.
1631633226: New connection from 172.30.32.2 on port 1883.
1631633226: Socket error on client <unknown>, disconnecting.
1631633346: New connection from 172.30.32.2 on port 1883.
1631633346: Socket error on client <unknown>, disconnecting.
1631633459: Client mqtt-explorer-36d9a907 has exceeded timeout, disconnecting.
1631633466: New connection from 172.30.32.2 on port 1883.
1631633466: Socket error on client <unknown>, disconnecting.
1631633483: Socket error on client auto-149016AF-D2E2-08C2-4AB7-DA37B4214AD5, disconnecting.
1631633586: New connection from 172.30.32.2 on port 1883.
1631633586: Socket error on client <unknown>, disconnecting.
1631633706: New connection from 172.30.32.2 on port 1883.
1631633706: Socket error on client <unknown>, disconnecting.
1631633826: New connection from 172.30.32.2 on port 1883.
1631633826: Socket error on client <unknown>, disconnecting.
1631633946: New connection from 172.30.32.2 on port 1883.
1631633946: Socket error on client <unknown>, disconnecting.
1631634066: New connection from 172.30.32.2 on port 1883.
1631634066: Socket error on client <unknown>, disconnecting.
1631634186: New connection from 172.30.32.2 on port 1883.
1631634186: Socket error on client <unknown>, disconnecting.
1631634306: New connection from 172.30.32.2 on port 1883.

192.168.86.54 is the client device IP

Client Device Log

INFO Reading configuration /config/esphome/temp-device.yaml...
INFO Starting log output from temp-device/debug
INFO Connected to MQTT broker!

Core Log

2021-09-14 10:27:22 WARNING (MainThread) [homeassistant.components.automation.all_hs210_extra_nudge] Integration - HS210 "Extra Nudge" Upstairs Hallway: Already running
2021-09-14 10:27:22 WARNING (MainThread) [homeassistant.components.automation.integration_hs210_extra_nudge_kitchen_hallway] Integration - HS210 "Extra Nudge" Kitchen Hallway: Already running
2021-09-14 10:27:22 WARNING (MainThread) [homeassistant.components.automation.integration_hs210_extra_nudge_front_door_hallway] Integration - HS210 "Extra Nudge" Front Door Hallway: Already running
2021-09-14 10:27:22 WARNING (MainThread) [homeassistant.components.automation.integration_hs210_extra_nudge_basement_hallway] Integration - HS210 "Extra Nudge" Basement Hallway: Already running
2021-09-14 10:27:22 WARNING (MainThread) [homeassistant.components.automation.all_hs210_extra_nudge] Integration - HS210 "Extra Nudge" Upstairs Hallway: Already running
2021-09-14 10:27:22 WARNING (MainThread) [homeassistant.components.automation.integration_hs210_extra_nudge_kitchen_hallway] Integration - HS210 "Extra Nudge" Kitchen Hallway: Already running
2021-09-14 10:27:22 WARNING (MainThread) [homeassistant.components.automation.integration_hs210_extra_nudge_front_door_hallway] Integration - HS210 "Extra Nudge" Front Door Hallway: Already running
2021-09-14 10:27:22 WARNING (MainThread) [homeassistant.components.automation.integration_hs210_extra_nudge_basement_hallway] Integration - HS210 "Extra Nudge" Basement Hallway: Already running
2021-09-14 10:27:23 WARNING (MainThread) [homeassistant.components.automation.all_hs210_extra_nudge] Integration - HS210 "Extra Nudge" Upstairs Hallway: Already running
2021-09-14 10:27:23 WARNING (MainThread) [homeassistant.components.automation.integration_hs210_extra_nudge_kitchen_hallway] Integration - HS210 "Extra Nudge" Kitchen Hallway: Already running
2021-09-14 10:27:23 WARNING (MainThread) [homeassistant.components.automation.integration_hs210_extra_nudge_front_door_hallway] Integration - HS210 "Extra Nudge" Front Door Hallway: Already running
2021-09-14 10:27:23 WARNING (MainThread) [homeassistant.components.automation.integration_hs210_extra_nudge_basement_hallway] Integration - HS210 "Extra Nudge" Basement Hallway: Already running
2021-09-14 10:27:23 WARNING (MainThread) [homeassistant.components.automation.all_hs210_extra_nudge] Integration - HS210 "Extra Nudge" Upstairs Hallway: Already running
2021-09-14 10:27:23 WARNING (MainThread) [homeassistant.components.automation.integration_hs210_extra_nudge_kitchen_hallway] Integration - HS210 "Extra Nudge" Kitchen Hallway: Already running
2021-09-14 10:27:23 WARNING (MainThread) [homeassistant.components.automation.integration_hs210_extra_nudge_front_door_hallway] Integration - HS210 "Extra Nudge" Front Door Hallway: Already running
2021-09-14 10:27:23 WARNING (MainThread) [homeassistant.components.automation.integration_hs210_extra_nudge_basement_hallway] Integration - HS210 "Extra Nudge" Basement Hallway: Already running
2021-09-14 10:27:23 DEBUG (MainThread) [homeassistant.components.mqtt] Transmitting message on homeassistant/status: 'online', mid: 4
2021-09-14 10:27:36 WARNING (MainThread) [homeassistant.components.automation.all_hs210_extra_nudge] Integration - HS210 "Extra Nudge" Upstairs Hallway: Already running
2021-09-14 10:27:36 WARNING (MainThread) [homeassistant.components.automation.integration_hs210_extra_nudge_kitchen_hallway] Integration - HS210 "Extra Nudge" Kitchen Hallway: Already running
2021-09-14 10:27:36 WARNING (MainThread) [homeassistant.components.automation.integration_hs210_extra_nudge_front_door_hallway] Integration - HS210 "Extra Nudge" Front Door Hallway: Already running
2021-09-14 10:27:36 WARNING (MainThread) [homeassistant.components.automation.integration_hs210_extra_nudge_basement_hallway] Integration - HS210 "Extra Nudge" Basement Hallway: Already running
2021-09-14 10:27:36 WARNING (MainThread) [homeassistant.components.automation.all_hs210_extra_nudge] Integration - HS210 "Extra Nudge" Upstairs Hallway: Already running
2021-09-14 10:27:36 WARNING (MainThread) [homeassistant.components.automation.integration_hs210_extra_nudge_kitchen_hallway] Integration - HS210 "Extra Nudge" Kitchen Hallway: Already running
2021-09-14 10:27:36 WARNING (MainThread) [homeassistant.components.automation.integration_hs210_extra_nudge_front_door_hallway] Integration - HS210 "Extra Nudge" Front Door Hallway: Already running
2021-09-14 10:27:36 WARNING (MainThread) [homeassistant.components.automation.integration_hs210_extra_nudge_basement_hallway] Integration - HS210 "Extra Nudge" Basement Hallway: Already running
2021-09-14 10:27:36 WARNING (MainThread) [homeassistant.components.automation.all_hs210_extra_nudge] Integration - HS210 "Extra Nudge" Upstairs Hallway: Already running
2021-09-14 10:27:36 WARNING (MainThread) [homeassistant.components.automation.integration_hs210_extra_nudge_kitchen_hallway] Integration - HS210 "Extra Nudge" Kitchen Hallway: Already running
2021-09-14 10:27:36 WARNING (MainThread) [homeassistant.components.automation.integration_hs210_extra_nudge_front_door_hallway] Integration - HS210 "Extra Nudge" Front Door Hallway: Already running
2021-09-14 10:27:36 WARNING (MainThread) [homeassistant.components.automation.integration_hs210_extra_nudge_basement_hallway] Integration - HS210 "Extra Nudge" Basement Hallway: Already running
2021-09-14 10:27:36 WARNING (MainThread) [homeassistant.components.automation.all_hs210_extra_nudge] Integration - HS210 "Extra Nudge" Upstairs Hallway: Already running
2021-09-14 10:27:36 WARNING (MainThread) [homeassistant.components.automation.integration_hs210_extra_nudge_kitchen_hallway] Integration - HS210 "Extra Nudge" Kitchen Hallway: Already running
2021-09-14 10:27:36 WARNING (MainThread) [homeassistant.components.automation.integration_hs210_extra_nudge_front_door_hallway] Integration - HS210 "Extra Nudge" Front Door Hallway: Already running
2021-09-14 10:27:36 WARNING (MainThread) [homeassistant.components.automation.integration_hs210_extra_nudge_basement_hallway] Integration - HS210 "Extra Nudge" Basement Hallway: Already running
[cont-finish.d] executing container finish scripts...
[cont-finish.d] done.
[s6-finish] waiting for services.
2021-09-14 10:31:13 ERROR (MainThread) [homeassistant] Error doing job: Future exception was never retrieved
Traceback (most recent call last):
  File "/usr/local/lib/python3.9/concurrent/futures/thread.py", line 52, in run
    result = self.fn(*self.args, **self.kwargs)
TypeError: stop_scanning() takes 1 positional argument but 2 were given
2021-09-14 10:31:15 ERROR (MainThread) [homeassistant.components.hassio.handler] Client error on /homeassistant/restart request Server disconnected
s6-svscanctl: fatal: unable to control /var/run/s6/services: supervisor not listening
[s6-finish] sending all processes the TERM signal.
[s6-finish] sending all processes the KILL signal and exiting.
[s6-init] making user provided files available at /var/run/s6/etc...exited 0.
[s6-init] ensuring user provided files have correct perms...exited 0.
[fix-attrs.d] applying ownership & permissions fixes...
[fix-attrs.d] done.
[cont-init.d] executing container initialization scripts...
[cont-init.d] done.
[services.d] starting services
[services.d] done.
2021-09-14 10:31:28 WARNING (SyncWorker_1) [homeassistant.loader] We found a custom integration var which has not been tested by Home Assistant. This component might cause stability problems, be sure to disable it if you experience issues with Home Assistant
2021-09-14 10:31:28 WARNING (SyncWorker_0) [homeassistant.loader] We found a custom integration hacs which has not been tested by Home Assistant. This component might cause stability problems, be sure to disable it if you experience issues with Home Assistant
2021-09-14 10:31:28 WARNING (SyncWorker_2) [homeassistant.loader] We found a custom integration govee_ble_hci which has not been tested by Home Assistant. This component might cause stability problems, be sure to disable it if you experience issues with Home Assistant
2021-09-14 10:31:28 WARNING (SyncWorker_1) [homeassistant.loader] We found a custom integration smartthinq_sensors which has not been tested by Home Assistant. This component might cause stability problems, be sure to disable it if you experience issues with Home Assistant
2021-09-14 10:31:28 WARNING (SyncWorker_3) [homeassistant.loader] We found a custom integration googlewifi which has not been tested by Home Assistant. This component might cause stability problems, be sure to disable it if you experience issues with Home Assistant
2021-09-14 10:31:28 WARNING (SyncWorker_4) [homeassistant.loader] We found a custom integration govee which has not been tested by Home Assistant. This component might cause stability problems, be sure to disable it if you experience issues with Home Assistant
2021-09-14 10:31:37 INFO (Thread-3) [homeassistant.components.mqtt] Connected to MQTT server core-mosquitto:1883 (0)
2021-09-14 10:31:42 WARNING (MainThread) [homeassistant.setup] Setup of zone is taking over 10 seconds.
2021-09-14 10:31:42 WARNING (MainThread) [homeassistant.setup] Setup of group is taking over 10 seconds.
2021-09-14 10:31:43 DEBUG (MainThread) [homeassistant.components.mqtt] Subscribing to homeassistant/+/+/config, mid: 1
2021-09-14 10:31:43 ERROR (MainThread) [snitun.client.client_peer] Challenge/Response error with SniTun server
2021-09-14 10:31:43 ERROR (MainThread) [hass_nabucasa.remote] Connection problem to snitun server
2021-09-14 10:31:45 WARNING (MainThread) [homeassistant.setup] Setup of input_boolean is taking over 10 seconds.
2021-09-14 10:31:45 DEBUG (MainThread) [homeassistant.components.mqtt] Subscribing to homeassistant/+/+/+/config, mid: 2
2021-09-14 10:31:47 WARNING (MainThread) [homeassistant.setup] Setup of webostv is taking over 10 seconds.
2021-09-14 10:31:47 ERROR (SyncWorker_7) [custom_components.govee_ble_hci.sensor] Error during Bluetooth LE scan: Attribute hass is None for <Entity Bedroom Enviroment temp: 23.06>
2021-09-14 10:31:48 DEBUG (MainThread) [homeassistant.components.mqtt] Subscribing to tasmota/discovery/#, mid: 3
2021-09-14 10:31:51 WARNING (MainThread) [custom_components.govee] API is back online.
2021-09-14 10:31:53 WARNING (MainThread) [zigpy.zcl] Unknown cluster 57344
2021-09-14 10:31:53 WARNING (MainThread) [zigpy.zcl] Unknown cluster 57345
2021-09-14 10:31:53 WARNING (MainThread) [zigpy.zcl] Unknown cluster 57344
2021-09-14 10:31:53 WARNING (MainThread) [zigpy.zcl] Unknown cluster 57345
2021-09-14 10:31:55 WARNING (MainThread) [homeassistant.components.camera] Setup of camera platform environment_canada is taking over 10 seconds.
2021-09-14 10:32:18 DEBUG (MainThread) [homeassistant.components.mqtt] Transmitting message on homeassistant/status: 'online', mid: 4
2021-09-14 10:33:09 INFO (Thread-113) [homeassistant.components.mqtt] Connected to MQTT server core-mosquitto:1883 (0)
2021-09-14 10:33:09 DEBUG (MainThread) [homeassistant.components.mqtt] Unsubscribing from homeassistant/+/+/config, mid: 1
2021-09-14 10:33:09 DEBUG (MainThread) [homeassistant.components.mqtt] Unsubscribing from homeassistant/+/+/+/config, mid: 2
2021-09-14 10:33:09 DEBUG (MainThread) [homeassistant.components.mqtt] Unsubscribing from tasmota/discovery/#, mid: 3
2021-09-14 10:33:09 DEBUG (MainThread) [homeassistant.components.mqtt] Subscribing to homeassistant/+/+/config, mid: 4
2021-09-14 10:33:09 DEBUG (MainThread) [homeassistant.components.mqtt] Subscribing to homeassistant/+/+/+/config, mid: 5
2021-09-14 10:33:09 DEBUG (MainThread) [homeassistant.components.mqtt] Subscribing to homeassistant/+/+/+/config, mid: 6
2021-09-14 10:33:09 DEBUG (MainThread) [homeassistant.components.mqtt] Subscribing to homeassistant/+/+/config, mid: 7
2021-09-14 10:33:09 DEBUG (MainThread) [homeassistant.components.mqtt] Subscribing to tasmota/discovery/#, mid: 8
2021-09-14 10:33:11 DEBUG (MainThread) [homeassistant.components.mqtt] Transmitting message on homeassistant/status: 'online', mid: 9
2021-09-14 10:45:20 WARNING (Thread-113) [homeassistant.components.mqtt] Disconnected from MQTT server core-mosquitto:1883 (1)
2021-09-14 10:45:28 INFO (Thread-113) [homeassistant.components.mqtt] Connected to MQTT server core-mosquitto:1883 (0)
2021-09-14 10:45:28 DEBUG (MainThread) [homeassistant.components.mqtt] Subscribing to homeassistant/+/+/+/config, mid: 10
2021-09-14 10:45:28 DEBUG (MainThread) [homeassistant.components.mqtt] Subscribing to homeassistant/+/+/config, mid: 11
2021-09-14 10:45:28 DEBUG (MainThread) [homeassistant.components.mqtt] Subscribing to tasmota/discovery/#, mid: 12
2021-09-14 10:45:30 DEBUG (MainThread) [homeassistant.components.mqtt] Transmitting message on homeassistant/status: 'online', mid: 13
2021-09-14 10:58:31 WARNING (MainThread) [homeassistant.components.esphome] Can't connect to ESPHome API for temp-device (192.168.86.54): Timeout while connecting to ('192.168.86.54', 6053)

This log includes the test device reflash transition from API to MQTT configuration

gmlupatelli commented 2 years ago

I set up a fresh server in a new Raspberry PI and was able to better debug the issue I am having and as it doesn't have anything to do with autodiscovery I will close this bug and create a new one.