home-assistant / operating-system

:beginner: Home Assistant Operating System
Apache License 2.0
4.85k stars 966 forks source link

Conbee II ZHA broken after HA OS update 7.5 #1795

Closed satyambaba closed 2 years ago

satyambaba commented 2 years ago

Describe the issue you are experiencing

ZHA using Conbee II has stopped working after the home assistant OS update 7.5. I'm on HA Blue (core-2022.3.5). I unplugged and re-plugged the stick (with and without the USB extension chord), rebooted the HA Blue machine several times, reloaded the ZHA integration, but to no avail.

Error is - Retrying setup: [Errno 2] could not open port /dev/serial/by-id/usb-dresden_elektronik_ingenieurtechnik_GmbH_ConBee_II

What operating system image do you use?

odroid-n2 (Hardkernel ODROID-N2/N2+)

What version of Home Assistant Operating System is installed?

7.5

Did you upgrade the Operating System.

Yes

Steps to reproduce the issue

  1. Just after the update to HA OS 7.5

Anything in the Supervisor logs that might be useful for us?

.

Anything in the Host logs that might be useful for us?

2022-03-17 05:24:59 DEBUG (SyncWorker_3) [miio.miioprotocol] 10.0.0.70:54321 >>: {'id': 41, 'method': 'set_properties', 'params': [{'did': 'aqi_realtime_update_duration', 'siid': 13, 'piid': 9, 'value': 5}]}
2022-03-17 05:24:59 ERROR (MainThread) [zigpy.application] Couldn't start application
2022-03-17 05:24:59 ERROR (MainThread) [homeassistant.components.zha.core.gateway] Couldn't start deCONZ = dresden elektronik deCONZ protocol: ConBee I/II, RaspBee I/II coordinator
Traceback (most recent call last):
  File "/usr/local/lib/python3.9/site-packages/serial/serialposix.py", line 322, in open
    self.fd = os.open(self.portstr, os.O_RDWR | os.O_NOCTTY | os.O_NONBLOCK)
FileNotFoundError: [Errno 2] No such file or directory: '/dev/serial/by-id/usb-dresden_elektronik_ingenieurtechnik_GmbH_ConBee_II_DE2436216-if00'
During handling of the above exception, another exception occurred:
Traceback (most recent call last):
  File "/usr/src/homeassistant/homeassistant/components/zha/core/gateway.py", line 152, in async_initialize
    self.application_controller = await app_controller_cls.new(
  File "/usr/local/lib/python3.9/site-packages/zigpy/application.py", line 69, in new
    await app.startup(auto_form)
  File "/usr/local/lib/python3.9/site-packages/zigpy_deconz/zigbee/application.py", line 66, in startup
    await self._api.connect()
  File "/usr/local/lib/python3.9/site-packages/zigpy_deconz/api.py", line 253, in connect
    self._uart = await zigpy_deconz.uart.connect(self._config, self)
  File "/usr/local/lib/python3.9/site-packages/zigpy_deconz/uart.py", line 142, in connect
    _, protocol = await serial_asyncio.create_serial_connection(
  File "/usr/local/lib/python3.9/site-packages/serial_asyncio/__init__.py", line 448, in create_serial_connection
    serial_instance = serial.serial_for_url(*args, **kwargs)
  File "/usr/local/lib/python3.9/site-packages/serial/__init__.py", line 90, in serial_for_url
    instance.open()
  File "/usr/local/lib/python3.9/site-packages/serial/serialposix.py", line 325, in open
    raise SerialException(msg.errno, "could not open port {}: {}".format(self._port, msg))
serial.serialutil.SerialException: [Errno 2] could not open port /dev/serial/by-id/usb-dresden_elektronik_ingenieurtechnik_GmbH_ConBee_II_DE2436216-if00: [Errno 2] No such file or directory: '/dev/serial/by-id/usb-dresden_elektronik_ingenieurtechnik_GmbH_ConBee_II_DE2436216-if00'
2022-03-17 05:24:59 WARNING (MainThread) [homeassistant.config_entries] Config entry 'ConBee II, s/n: DE2436216 - dresden elektronik ingenieurtechnik GmbH' for zha integration not ready yet: [Errno 2] could not open port /dev/serial/by-id/usb-dresden_elektronik_ingenieurtechnik_GmbH_ConBee_II_DE2436216-if00: [Errno 2] No such file or directory: '/dev/serial/by-id/usb-dresden_elektronik_ingenieurtechnik_GmbH_ConBee_II_DE2436216-if00'; Retrying in background

System Health information

System Health

version core-2022.3.5
installation_type Home Assistant OS
dev false
hassio true
docker true
user root
virtualenv false
python_version 3.9.9
os_name Linux
os_version 5.10.103
arch aarch64
timezone Asia/Calcutta
Home Assistant Community Store GitHub API | ok -- | -- GitHub Content | ok GitHub Web | ok GitHub API Calls Remaining | 3249 Installed Version | 1.23.0 Stage | running Available Repositories | 1084 Downloaded Repositories | 99
Home Assistant Cloud logged_in | true -- | -- subscription_expiration | April 1, 2022, 5:30 AM relayer_connected | true remote_enabled | true remote_connected | true alexa_enabled | true google_enabled | true remote_server | ap-southeast-1-0.ui.nabu.casa can_reach_cert_server | ok can_reach_cloud_auth | ok can_reach_cloud | ok
Home Assistant Supervisor host_os | Home Assistant OS 7.5 -- | -- update_channel | stable supervisor_version | supervisor-2022.03.4 docker_version | 20.10.9 disk_total | 113.9 GB disk_used | 44.4 GB healthy | true supported | true board | odroid-n2 supervisor_api | ok version_api | ok installed_addons | Studio Code Server (4.1.0), SSH & Web Terminal (10.1.0), FTP (4.5.0), Frigate NVR (3.1), Mosquitto broker (6.0.1), Assistant Relay (0.7.4), Eufy Security Add-on (0.8.4), RTSP Simple Server Add-on (v0.17.6), deCONZ (6.12.0), Spotify Connect (0.11.0), Samba share (9.5.1), AppDaemon (0.8.2), Plex Media Server (2.7.6), Google Assistant SDK (2.5.0), Home Assistant Google Drive Backup (0.105.2)
Lovelace dashboards | 4 -- | -- resources | 69 views | 18 mode | storage
Spotify api_endpoint_reachable | ok -- | --

Additional information

No response

satyambaba commented 2 years ago

It started working when I rolled back to HA OS 7.4

agners commented 2 years ago

Does un-/replugging the device help? Can you check if you see the device under Supervisor System -> Host -> 3 dot menu -> Hardware? Also, can you share the host logs (dmesg)?

In a quick try, a Conbee II seems to be correctly recognized here

# ha os info
board: odroid-n2
boot: B
data_disk: /dev/mmcblk1p4
update_available: true
version: "7.5"
version_latest: 7.5
# dmesg | tail
[249417.811651] audit: type=1334 audit(1647496672.914:431): prog-id=147 op=UNLOAD
[249418.135745] audit: type=1334 audit(1647496673.238:432): prog-id=150 op=UNLOAD
[249418.135756] audit: type=1334 audit(1647496673.238:433): prog-id=149 op=UNLOAD
[256773.164879] usb 1-1.4: new full-speed USB device number 3 using xhci-hcd
[256773.334195] cdc_acm 1-1.4:1.0: ttyACM0: USB ACM device
[256773.334595] usbcore: registered new interface driver cdc_acm
[256773.334597] cdc_acm: USB Abstract Control Model driver for USB modems and ISDN adapters
[256780.812873] usb 1-1.4: USB disconnect, device number 3
[256781.092554] usb 1-1.4: new full-speed USB device number 4 using xhci-hcd
[256781.260898] cdc_acm 1-1.4:1.0: ttyACM0: USB ACM device
satyambaba commented 2 years ago

Does un-/replugging the device help? Can you check if you see the device under Supervisor System -> Host -> 3 dot menu -> Hardware? Also, can you share the host logs (dmesg)?

In a quick try, a Conbee II seems to be correctly recognized here

# ha os info
board: odroid-n2
boot: B
data_disk: /dev/mmcblk1p4
update_available: true
version: "7.5"
version_latest: 7.5
# dmesg | tail
[249417.811651] audit: type=1334 audit(1647496672.914:431): prog-id=147 op=UNLOAD
[249418.135745] audit: type=1334 audit(1647496673.238:432): prog-id=150 op=UNLOAD
[249418.135756] audit: type=1334 audit(1647496673.238:433): prog-id=149 op=UNLOAD
[256773.164879] usb 1-1.4: new full-speed USB device number 3 using xhci-hcd
[256773.334195] cdc_acm 1-1.4:1.0: ttyACM0: USB ACM device
[256773.334595] usbcore: registered new interface driver cdc_acm
[256773.334597] cdc_acm: USB Abstract Control Model driver for USB modems and ISDN adapters
[256780.812873] usb 1-1.4: USB disconnect, device number 3
[256781.092554] usb 1-1.4: new full-speed USB device number 4 using xhci-hcd
[256781.260898] cdc_acm 1-1.4:1.0: ttyACM0: USB ACM device

No, un-/replugging the device doesn't help. I can't see the Conbee II stick under the hardware menu. Below is the host logs -

2022-03-17 05:24:59 DEBUG (SyncWorker_3) [miio.miioprotocol] 10.0.0.70:54321 >>: {'id': 41, 'method': 'set_properties', 'params': [{'did': 'aqi_realtime_update_duration', 'siid': 13, 'piid': 9, 'value': 5}]}
2022-03-17 05:24:59 ERROR (MainThread) [zigpy.application] Couldn't start application
2022-03-17 05:24:59 ERROR (MainThread) [homeassistant.components.zha.core.gateway] Couldn't start deCONZ = dresden elektronik deCONZ protocol: ConBee I/II, RaspBee I/II coordinator
Traceback (most recent call last):
  File "/usr/local/lib/python3.9/site-packages/serial/serialposix.py", line 322, in open
    self.fd = os.open(self.portstr, os.O_RDWR | os.O_NOCTTY | os.O_NONBLOCK)
FileNotFoundError: [Errno 2] No such file or directory: '/dev/serial/by-id/usb-dresden_elektronik_ingenieurtechnik_GmbH_ConBee_II_DE2436216-if00'
During handling of the above exception, another exception occurred:
Traceback (most recent call last):
  File "/usr/src/homeassistant/homeassistant/components/zha/core/gateway.py", line 152, in async_initialize
    self.application_controller = await app_controller_cls.new(
  File "/usr/local/lib/python3.9/site-packages/zigpy/application.py", line 69, in new
    await app.startup(auto_form)
  File "/usr/local/lib/python3.9/site-packages/zigpy_deconz/zigbee/application.py", line 66, in startup
    await self._api.connect()
  File "/usr/local/lib/python3.9/site-packages/zigpy_deconz/api.py", line 253, in connect
    self._uart = await zigpy_deconz.uart.connect(self._config, self)
  File "/usr/local/lib/python3.9/site-packages/zigpy_deconz/uart.py", line 142, in connect
    _, protocol = await serial_asyncio.create_serial_connection(
  File "/usr/local/lib/python3.9/site-packages/serial_asyncio/__init__.py", line 448, in create_serial_connection
    serial_instance = serial.serial_for_url(*args, **kwargs)
  File "/usr/local/lib/python3.9/site-packages/serial/__init__.py", line 90, in serial_for_url
    instance.open()
  File "/usr/local/lib/python3.9/site-packages/serial/serialposix.py", line 325, in open
    raise SerialException(msg.errno, "could not open port {}: {}".format(self._port, msg))
serial.serialutil.SerialException: [Errno 2] could not open port /dev/serial/by-id/usb-dresden_elektronik_ingenieurtechnik_GmbH_ConBee_II_DE2436216-if00: [Errno 2] No such file or directory: '/dev/serial/by-id/usb-dresden_elektronik_ingenieurtechnik_GmbH_ConBee_II_DE2436216-if00'
2022-03-17 05:24:59 WARNING (MainThread) [homeassistant.config_entries] Config entry 'ConBee II, s/n: DE2436216 - dresden elektronik ingenieurtechnik GmbH' for zha integration not ready yet: [Errno 2] could not open port /dev/serial/by-id/usb-dresden_elektronik_ingenieurtechnik_GmbH_ConBee_II_DE2436216-if00: [Errno 2] No such file or directory: '/dev/serial/by-id/usb-dresden_elektronik_ingenieurtechnik_GmbH_ConBee_II_DE2436216-if00'; Retrying in background
agners commented 2 years ago

Do you use an extension cable? The front plate of the Blue is known to be a bit too thick, and some USB devices don't make proper connection. See also https://github.com/home-assistant/operating-system/issues/1602#issuecomment-958994083.

satyambaba commented 2 years ago

Do you use an extension cable? The front plate of the Blue is known to be a bit too thick, and some USB devices don't make proper connection. See also #1602 (comment).

Yes, I use extension cable. However everything was working fine, until the update. Also, when I rolled back to 7.4 it started working again.

agners commented 2 years ago

Yes, I use extension cable. However everything was working fine, until the update. Also, when I rolled back to 7.4 it started working again.

Yeah, that is weird. However, I can't reproduce this on my ODROID-N2, so it might be just a bit unreliable, or really a weird interaction with your device <-> OS 7.5...

Can you share logs from Configuration -> Add-ons, Backup & Supervisor -> System -> Log Provider "Host"? Ideally after upgrade to 7.5, and also after unplugging/re-plugging the device.

mbrennan commented 2 years ago

The same issue happened to me after upgrading to Home Assistant Operating System to 8.0. My Conbee II doesn't seem to be recognized. I can't see any other devices in the hardware section (recently moved around in Home Assistant Core), under Settings -> System -> Hardware. The only thing that's listed there is "Raspberry Pi 4 rpi4-64".

I've tried rebooting the host and unplugging and replugging in my Conbee II stick. I'm not using an extension cable.

How can I roll back the Home Assistant OS to the previous version?

agners commented 2 years ago

@mbrennan use the following command in the Terminal add-on:

ha os update --version 7.6

That said, I doubt that this is related to the OS version. Can you post the Host logs (dmesg)?

mbrennan commented 2 years ago

This is copy/pasted from System -> Logs with the 'Host' drop down:

[    0.000000] Booting Linux on physical CPU 0x0000000000 [0x410fd083]
[    0.000000] Linux version 5.15.32-v8 (builder@6a275e347639) (aarch64-buildroot-linux-gnu-gcc.br_real (Buildroot -gee5615946) 9.4.0, GNU ld (GNU Binutils) 2.36.1) #1 SMP PREEMPT Wed May 11 12:09:58 UTC 2022
[    0.000000] random: fast init done
[    0.000000] Machine model: Raspberry Pi 4 Model B Rev 1.4
[    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-0x00000000ffffffff]
[    0.000000]   Normal   [mem 0x0000000100000000-0x00000001ffffffff]
[    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]   node   0: [mem 0x0000000100000000-0x00000001ffffffff]
[    0.000000] Initmem setup node 0 [mem 0x0000000000000000-0x00000001ffffffff]
[    0.000000] percpu: Embedded 28 pages/cpu s77272 r8192 d29224 u114688
[    0.000000] pcpu-alloc: s77272 r8192 d29224 u114688 alloc=28*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: Spectre-BHB
[    0.000000] CPU features: detected: ARM errata 1165522, 1319367, or 1530923
[    0.000000] Built 1 zonelists, mobility grouping on.  Total pages: 2028848
[    0.000000] Kernel command line: zram.enabled=1 zram.num_devices=3 apparmor=1 security=apparmor rootwait systemd.machine_id=3cc6162a61744f4a836fdb5e2e401e41 cgroup_enable=memory fsck.repair=yes coherent_pool=1M 8250.nr_uarts=1 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=E4:5F:01:5E:2A:53 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] cgroup: Enabling memory control group subsystem
[    0.000000] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, linear)
[    0.000000] Inode-cache hash table entries: 524288 (order: 10, 4194304 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: 7929060K/8245248K available (12608K kernel code, 2008K rwdata, 4388K rodata, 3968K init, 985K bss, 250652K reserved, 65536K cma-reserved)
[    0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1
[    0.000000] ftrace: allocating 40879 entries in 160 pages
[    0.000000] ftrace: allocated 160 pages with 2 groups
[    0.000000] trace event string verifier disabled
[    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] Root IRQ handler: gic_handle_irq
[    0.000000] GIC: Using split EOI/Deactivate mode
[    0.000000] random: get_random_bytes called from start_kernel+0x4fc/0x6e8 with crng_init=1
[    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.000001] sched_clock: 56 bits at 54MHz, resolution 18ns, wraps every 4398046511102ns
[    0.000257] Console: colour dummy device 80x25
[    0.000841] printk: console [tty1] enabled
[    0.000928] Calibrating delay loop (skipped), value calculated using timer frequency.. 108.00 BogoMIPS (lpj=216000)
[    0.000968] pid_max: default: 32768 minimum: 301
[    0.001104] LSM: Security Framework initializing
[    0.001247] AppArmor: AppArmor initialized
[    0.001480] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, linear)
[    0.001609] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, linear)
[    0.004936] rcu: Hierarchical SRCU implementation.
[    0.006066] EFI services will not be available.
[    0.006622] smp: Bringing up secondary CPUs ...
[    0.007665] Detected PIPT I-cache on CPU1
[    0.007745] CPU1: Booted secondary processor 0x0000000001 [0x410fd083]
[    0.008945] Detected PIPT I-cache on CPU2
[    0.008995] CPU2: Booted secondary processor 0x0000000002 [0x410fd083]
[    0.010133] Detected PIPT I-cache on CPU3
[    0.010182] CPU3: Booted secondary processor 0x0000000003 [0x410fd083]
[    0.010327] smp: Brought up 1 node, 4 CPUs
[    0.010419] SMP: Total of 4 processors activated.
[    0.010440] CPU features: detected: 32-bit EL0 Support
[    0.010459] CPU features: detected: 32-bit EL1 Support
[    0.010481] CPU features: detected: CRC32 instructions
[    0.046184] CPU: All CPU(s) started at EL2
[    0.046282] alternatives: patching kernel code
[    0.048373] devtmpfs: initialized
[    0.059626] Enabled cp15_barrier support
[    0.059692] Enabled setend support
[    0.059892] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns
[    0.059940] futex hash table entries: 1024 (order: 4, 65536 bytes, linear)
[    0.062030] pinctrl core: initialized pinctrl subsystem
[    0.062873] DMI not present or invalid.
[    0.063391] NET: Registered PF_NETLINK/PF_ROUTE protocol family
[    0.067201] DMA: preallocated 1024 KiB GFP_KERNEL pool for atomic allocations
[    0.067520] DMA: preallocated 1024 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations
[    0.068472] DMA: preallocated 1024 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations
[    0.068568] audit: initializing netlink subsys (disabled)
[    0.068872] audit: type=2000 audit(0.068:1): state=initialized audit_enabled=0 res=1
[    0.069364] thermal_sys: Registered thermal governor 'step_wise'
[    0.069609] cpuidle: using governor menu
[    0.070088] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers.
[    0.070286] ASID allocator initialised with 65536 entries
[    0.070461] Serial: AMBA PL011 UART driver
[    0.080249] bcm2835-mbox fe00b880.mailbox: mailbox enabled
[    0.096274] raspberrypi-firmware soc:firmware: Attached to firmware from 2022-03-24T13:19:26, variant start
[    0.100292] raspberrypi-firmware soc:firmware: Firmware hash is e5a963efa66a1974127860b42e913d2374139ff5
[    0.151310] bcm2835-dma fe007000.dma: DMA legacy API manager, dmachans=0x1
[    0.155388] vgaarb: loaded
[    0.155807] SCSI subsystem initialized
[    0.156022] usbcore: registered new interface driver usbfs
[    0.156088] usbcore: registered new interface driver hub
[    0.156175] usbcore: registered new device driver usb
[    0.156576] usb_phy_generic phy: supply vcc not found, using dummy regulator
[    0.157034] mc: Linux media interface: v0.10
[    0.157139] pps_core: LinuxPPS API ver. 1 registered
[    0.157161] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>
[    0.157198] PTP clock support registered
[    0.158783] clocksource: Switched to clocksource arch_sys_counter
[    0.247340] VFS: Disk quotas dquot_6.6.0
[    0.247464] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)
[    0.247654] FS-Cache: Loaded
[    0.247908] CacheFiles: Loaded
[    0.248671] AppArmor: AppArmor Filesystem Enabled
[    0.259368] NET: Registered PF_INET protocol family
[    0.260347] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, linear)
[    0.266360] tcp_listen_portaddr_hash hash table entries: 4096 (order: 4, 65536 bytes, linear)
[    0.266490] TCP established hash table entries: 65536 (order: 7, 524288 bytes, linear)
[    0.267014] TCP bind hash table entries: 65536 (order: 8, 1048576 bytes, linear)
[    0.267997] TCP: Hash tables configured (established 65536 bind 65536)
[    0.268337] UDP hash table entries: 4096 (order: 5, 131072 bytes, linear)
[    0.268515] UDP-Lite hash table entries: 4096 (order: 5, 131072 bytes, linear)
[    0.268948] NET: Registered PF_UNIX/PF_LOCAL protocol family
[    0.269998] RPC: Registered named UNIX socket transport module.
[    0.270031] RPC: Registered udp transport module.
[    0.270052] RPC: Registered tcp transport module.
[    0.270072] RPC: Registered tcp NFSv4.1 backchannel transport module.
[    0.270102] PCI: CLS 0 bytes, default 64
[    0.272737] hw perfevents: enabled with armv8_cortex_a72 PMU driver, 7 counters available
[    0.273113] kvm [1]: IPA Size Limit: 44 bits
[    0.274337] kvm [1]: vgic interrupt IRQ9
[    0.274610] kvm [1]: Hyp mode initialized successfully
[    1.173127] Initialise system trusted keyrings
[    1.173505] workingset: timestamp_bits=46 max_order=21 bucket_order=0
[    1.180238] zbud: loaded
[    1.182022] squashfs: version 4.0 (2009/01/31) Phillip Lougher
[    1.182311] FS-Cache: Netfs 'nfs' registered for caching
[    1.183063] NFS: Registering the id_resolver key type
[    1.183127] Key type id_resolver registered
[    1.183148] Key type id_legacy registered
[    1.183263] nfs4filelayout_init: NFSv4 File Layout Driver Registering...
[    1.183289] nfs4flexfilelayout_init: NFSv4 Flexfile Layout Driver Registering...
[    1.184567] Key type asymmetric registered
[    1.184594] Asymmetric key parser 'x509' registered
[    1.184685] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 246)
[    1.184953] io scheduler mq-deadline registered
[    1.184978] io scheduler kyber registered
[    1.192348] brcm-pcie fd500000.pcie: host bridge /scb/pcie@7d500000 ranges:
[    1.192416] brcm-pcie fd500000.pcie:   No bus range found for /scb/pcie@7d500000, using [bus 00-ff]
[    1.192519] brcm-pcie fd500000.pcie:      MEM 0x0600000000..0x063fffffff -> 0x00c0000000
[    1.192617] brcm-pcie fd500000.pcie:   IB MEM 0x0000000000..0x01ffffffff -> 0x0400000000
[    1.256879] brcm-pcie fd500000.pcie: link up, 5.0 GT/s PCIe x1 (SSC)
[    1.257244] brcm-pcie fd500000.pcie: PCI host bridge to bus 0000:00
[    1.257276] pci_bus 0000:00: root bus resource [bus 00-ff]
[    1.257305] pci_bus 0000:00: root bus resource [mem 0x600000000-0x63fffffff] (bus address [0xc0000000-0xffffffff])
[    1.257409] pci 0000:00:00.0: [14e4:2711] type 01 class 0x060400
[    1.257670] pci 0000:00:00.0: PME# supported from D0 D3hot
[    1.261830] pci 0000:01:00.0: [1106:3483] type 00 class 0x0c0330
[    1.261970] pci 0000:01:00.0: reg 0x10: [mem 0x00000000-0x00000fff 64bit]
[    1.262409] pci 0000:01:00.0: PME# supported from D0 D3hot
[    1.278249] pci 0000:00:00.0: BAR 8: assigned [mem 0x600000000-0x6000fffff]
[    1.278290] pci 0000:01:00.0: BAR 0: assigned [mem 0x600000000-0x600000fff 64bit]
[    1.278345] pci 0000:00:00.0: PCI bridge to [bus 01]
[    1.278378] pci 0000:00:00.0:   bridge window [mem 0x600000000-0x6000fffff]
[    1.279321] bcm2708_fb soc:fb: Unable to determine number of FBs. Disabling driver.
[    1.279355] bcm2708_fb: probe of soc:fb failed with error -2
[    1.284606] Serial: 8250/16550 driver, 1 ports, IRQ sharing enabled
[    1.287140] iproc-rng200 fe104000.rng: hwrng registered
[    1.287636] vc-mem: phys_addr:0x00000000 mem_base=0x3ec00000 mem_size:0x40000000(1024 MiB)
[    1.288923] gpiomem-bcm2835 fe200000.gpiomem: Initialised: Registers at 0xfe200000
[    1.289361] cacheinfo: Unable to detect cache hierarchy for CPU 0
[    1.300419] brd: module loaded
[    1.309923] loop: module loaded
[    1.310477] zram: Added device: zram0
[    1.311119] zram: Added device: zram1
[    1.311621] zram: Added device: zram2
[    1.312306] Loading iSCSI transport class v2.0-870.
[    1.317153] tun: Universal TUN/TAP device driver, 1.6
[    1.318089] bcmgenet fd580000.ethernet: GENET 5.0 EPHY: 0x0000
[    1.378942] unimac-mdio unimac-mdio.-19: Broadcom UniMAC MDIO bus
[    1.427425] pci 0000:00:00.0: enabling device (0000 -> 0002)
[    1.427620] xhci_hcd 0000:01:00.0: xHCI Host Controller
[    1.427664] xhci_hcd 0000:01:00.0: new USB bus registered, assigned bus number 1
[    1.428439] xhci_hcd 0000:01:00.0: hcc params 0x002841eb hci version 0x100 quirks 0x00003c0000000890
[    1.429790] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 5.15
[    1.429828] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[    1.429857] usb usb1: Product: xHCI Host Controller
[    1.429880] usb usb1: Manufacturer: Linux 5.15.32-v8 xhci-hcd
[    1.429904] usb usb1: SerialNumber: 0000:01:00.0
[    1.430611] hub 1-0:1.0: USB hub found
[    1.430718] hub 1-0:1.0: 1 port detected
[    1.431423] xhci_hcd 0000:01:00.0: xHCI Host Controller
[    1.431459] xhci_hcd 0000:01:00.0: new USB bus registered, assigned bus number 2
[    1.431505] xhci_hcd 0000:01:00.0: Host supports USB 3.0 SuperSpeed
[    1.431924] usb usb2: New USB device found, idVendor=1d6b, idProduct=0003, bcdDevice= 5.15
[    1.431959] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[    1.431986] usb usb2: Product: xHCI Host Controller
[    1.432009] usb usb2: Manufacturer: Linux 5.15.32-v8 xhci-hcd
[    1.432032] usb usb2: SerialNumber: 0000:01:00.0
[    1.432669] hub 2-0:1.0: USB hub found
[    1.432746] hub 2-0:1.0: 4 ports detected
[    1.434270] dwc_otg: version 3.00a 10-AUG-2012 (platform bus)
[    1.434545] dwc_otg: FIQ enabled
[    1.434556] dwc_otg: NAK holdoff enabled
[    1.434566] dwc_otg: FIQ split-transaction FSM enabled
[    1.434584] Module dwc_common_port init
[    1.435173] usbcore: registered new interface driver uas
[    1.435280] usbcore: registered new interface driver usb-storage
[    1.435414] usbcore: registered new interface driver usbserial_generic
[    1.435462] usbserial: USB Serial support registered for generic
[    1.435725] mousedev: PS/2 mouse device common for all mice
[    1.435870] Driver for 1-wire Dallas network protocol.
[    1.440609] sdhci: Secure Digital Host Controller Interface driver
[    1.440643] sdhci: Copyright(c) Pierre Ossman
[    1.441206] sdhci-pltfm: SDHCI platform and OF driver helper
[    1.446701] ledtrig-cpu: registered to indicate activity on CPUs
[    1.446968] hid: raw HID events driver (C) Jiri Kosina
[    1.447047] usbcore: registered new interface driver usbhid
[    1.447057] usbhid: USB HID core driver
[    1.447215] ashmem: initialized
[    1.454201] IPVS: Registered protocols (TCP, UDP, SCTP, AH, ESP)
[    1.454266] IPVS: Connection hash table configured (size=4096, memory=64Kbytes)
[    1.454390] IPVS: ipvs loaded.
[    1.454400] IPVS: [rr] scheduler registered.
[    1.454740] NET: Registered PF_INET6 protocol family
[    1.455709] Segment Routing with IPv6
[    1.455817] In-situ OAM (IOAM) with IPv6
[    1.455901] NET: Registered PF_PACKET protocol family
[    1.455958] Bridge firewalling registered
[    1.456048] Key type dns_resolver registered
[    1.456941] registered taskstats version 1
[    1.456968] Loading compiled-in X.509 certificates
[    1.457326] Key type ._fscrypt registered
[    1.457340] Key type .fscrypt registered
[    1.457351] Key type fscrypt-provisioning registered
[    1.457415] AppArmor: AppArmor sha1 policy hashing enabled
[    1.464027] uart-pl011 fe201000.serial: there is not valid maps for state default
[    1.464200] uart-pl011 fe201000.serial: cts_event_workaround enabled
[    1.464309] fe201000.serial: ttyAMA0 at MMIO 0xfe201000 (irq = 17, base_baud = 0) is a PL011 rev2
[    1.471150] bcm2835-wdt bcm2835-wdt: Broadcom BCM2835 watchdog timer
[    1.471441] bcm2835-power bcm2835-power: Broadcom BCM2835 power domains driver
[    1.472156] mmc-bcm2835 fe300000.mmcnr: mmc_debug:0 mmc_debug2:0
[    1.472174] mmc-bcm2835 fe300000.mmcnr: DMA channel allocated
[    1.500977] printk: console [netcon0] enabled
[    1.501023] netconsole: network logging started
[    1.501086] of_cfs_init
[    1.501213] of_cfs_init: OK
[    1.536660] mmc0: SDHCI controller on fe340000.mmc [fe340000.mmc] using ADMA
[    1.536984] Waiting for root device PARTUUID=8d3d53e3-6d49-4c38-8349-aff6859e82fd...
[    1.594279] mmc1: new high speed SDIO card at address 0001
[    1.637673] mmc0: new ultra high speed DDR50 SDXC card at address aaaa
[    1.638196] mmcblk0: mmc0:aaaa SP128 119 GiB 
[    1.644986]  mmcblk0: p1 p2 p3 p4 p5 p6 p7 p8
[    1.645733] mmcblk0: mmc0:aaaa SP128 119 GiB
[    1.650491] VFS: Mounted root (squashfs filesystem) readonly on device 179:3.
[    1.651930] devtmpfs: mounted
[    1.657975] Freeing unused kernel memory: 3968K
[    1.666870] Run /sbin/init as init process
[    1.666885]   with arguments:
[    1.666890]     /sbin/init
[    1.666896]   with environment:
[    1.666901]     HOME=/
[    1.666906]     TERM=linux
[    1.686832] usb 1-1: new high-speed USB device number 2 using xhci_hcd
[    1.837513] usb 1-1: New USB device found, idVendor=2109, idProduct=3431, bcdDevice= 4.21
[    1.837557] usb 1-1: New USB device strings: Mfr=0, Product=1, SerialNumber=0
[    1.837575] usb 1-1: Product: USB2.0 Hub
[    1.839400] hub 1-1:1.0: USB hub found
[    1.839687] hub 1-1:1.0: 4 ports detected
[    1.894913] systemd[1]: System time before build time, advancing clock.
[    1.943626] systemd[1]: systemd 250 running in system mode (+PAM -AUDIT -SELINUX +APPARMOR -IMA -SMACK -SECCOMP +GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL -ELFUTILS -FIDO2 -IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE -BZIP2 -LZ4 -XZ +ZLIB -ZSTD -BPF_FRAMEWORK -XKBCOMMON -UTMP -SYSVINIT default-hierarchy=hybrid)
[    1.944249] systemd[1]: Detected architecture arm64.
[    1.976652] systemd[1]: Hostname set to <homeassistant>.
[    1.976918] systemd[1]: Installed transient /etc/machine-id file.
[    2.138785] usb 1-1.1: new full-speed USB device number 3 using xhci_hcd
[    2.250156] usb 1-1.1: New USB device found, idVendor=051d, idProduct=0003, bcdDevice= 1.06
[    2.250196] usb 1-1.1: New USB device strings: Mfr=1, Product=2, SerialNumber=3
[    2.250211] usb 1-1.1: Product: Smart-UPS X 2000 FW:UPS 08.1 / ID=01003
[    2.250224] usb 1-1.1: Manufacturer: American Power Conversion 
[    2.250236] usb 1-1.1: SerialNumber: AS2142364844    
[    2.261653] hid-generic 0003:051D:0003.0001: hiddev96,hidraw0: USB HID v1.00 Device [American Power Conversion  Smart-UPS X 2000 FW:UPS 08.1 / ID=01003] on usb-0000:01:00.0-1.1/input0
[    2.346868] usb 1-1.3: new full-speed USB device number 4 using xhci_hcd
[    2.413259] systemd[1]: Queued start job for default target Multi-User System.
[    2.414227] random: systemd: uninitialized urandom read (16 bytes read)
[    2.444932] systemd[1]: Created slice Slice /system/ha-cli.
[    2.445349] random: systemd: uninitialized urandom read (16 bytes read)
[    2.447398] systemd[1]: Created slice Slice /system/modprobe.
[    2.447654] random: systemd: uninitialized urandom read (16 bytes read)
[    2.449545] systemd[1]: Created slice Slice /system/systemd-fsck.
[    2.452013] systemd[1]: Created slice Slice /system/systemd-growfs.
[    2.453884] systemd[1]: Created slice User and Session Slice.
[    2.454306] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.
[    2.454645] systemd[1]: Started Forward Password Requests to Wall Directory Watch.
[    2.455122] systemd[1]: Reached target Local Encrypted Volumes.
[    2.455336] systemd[1]: Reached target HassOS zram targets.
[    2.455505] systemd[1]: Reached target Local Integrity Protected Volumes.
[    2.455729] systemd[1]: Reached target Path Units.
[    2.455906] systemd[1]: Reached target Remote Encrypted Volumes.
[    2.456078] systemd[1]: Reached target Remote File Systems.
[    2.456247] systemd[1]: Reached target Remote Verity Protected Volumes.
[    2.456412] systemd[1]: Reached target Slice Units.
[    2.456627] systemd[1]: Reached target Local Verity Protected Volumes.
[    2.460056] systemd[1]: Listening on D-Bus System Message Bus Socket.
[    2.464464] systemd[1]: Listening on Process Core Dump Socket.
[    2.465396] systemd[1]: Listening on Journal Audit Socket.
[    2.466104] systemd[1]: Listening on Journal Socket (/dev/log).
[    2.466753] systemd[1]: Listening on Journal Socket.
[    2.470069] systemd[1]: Listening on udev Control Socket.
[    2.470890] systemd[1]: Listening on udev Kernel Socket.
[    2.471535] systemd[1]: Huge Pages File System was skipped because of a failed condition check (ConditionPathExists=/sys/kernel/mm/hugepages).
[    2.475633] systemd[1]: Mounting POSIX Message Queue File System...
[    2.476443] usb 1-1.3: New USB device found, idVendor=2550, idProduct=8761, bcdDevice= 2.00
[    2.476470] usb 1-1.3: New USB device strings: Mfr=1, Product=2, SerialNumber=3
[    2.476481] usb 1-1.3: Product: Bluetooth Radio
[    2.476491] usb 1-1.3: Manufacturer: Realtek
[    2.476500] usb 1-1.3: SerialNumber: 00E04C239987
[    2.480307] systemd[1]: Mounting Kernel Debug File System...
[    2.484895] systemd[1]: Mounting Kernel Trace File System...
[    2.489608] systemd[1]: Started D-Bus System Message Bus.
[    2.500616] systemd[1]: Starting Home Assistant OS Agent...
[    2.510852] systemd[1]: Starting Create List of Static Device Nodes...
[    2.515446] systemd[1]: Starting Load Kernel Module configfs...
[    2.520151] systemd[1]: Starting Load Kernel Module drm...
[    2.525192] systemd[1]: Starting Load Kernel Module fuse...
[    2.532070] systemd[1]: Starting Journal Service...
[    2.536571] systemd[1]: Starting Generate network units from Kernel command line...
[    2.541024] systemd[1]: Starting Remount Root and Kernel File Systems...
[    2.545975] systemd[1]: Starting Wait Until Kernel Time Synchronized...
[    2.551625] systemd[1]: Starting Coldplug All udev Devices...
[    2.557288] systemd[1]: Starting Disk Manager...
[    2.561922] systemd[1]: Starting HassOS ZRAM swap...
[    2.566803] usb 1-1.4: new full-speed USB device number 5 using xhci_hcd
[    2.567210] systemd[1]: Starting HassOS ZRAM tmp...
[    2.572195] systemd[1]: Starting HassOS ZRAM var...
[    2.580243] systemd[1]: Mounted POSIX Message Queue File System.
[    2.580933] systemd[1]: Mounted Kernel Debug File System.
[    2.581510] systemd[1]: Mounted Kernel Trace File System.
[    2.583451] systemd[1]: Finished Create List of Static Device Nodes.
[    2.584853] systemd[1]: modprobe@configfs.service: Deactivated successfully.
[    2.585741] systemd[1]: Finished Load Kernel Module configfs.
[    2.587562] systemd[1]: Finished Generate network units from Kernel command line.
[    2.589354] systemd[1]: Finished Remount Root and Kernel File Systems.
[    2.590153] systemd[1]: Reached target Preparation for Network.
[    2.594868] systemd[1]: Mounting Kernel Configuration File System...
[    2.601500] systemd[1]: Starting Create Static Device Nodes in /dev...
[    2.608803] systemd[1]: Mounted Kernel Configuration File System.
[    2.674115] usb 1-1.4: New USB device found, idVendor=10c4, idProduct=ea60, bcdDevice= 1.00
[    2.674148] usb 1-1.4: New USB device strings: Mfr=1, Product=2, SerialNumber=3
[    2.674161] usb 1-1.4: Product: Zooz ZST10 700 Z-Wave Stick
[    2.674172] usb 1-1.4: Manufacturer: Silicon Labs
[    2.674181] usb 1-1.4: SerialNumber: 1a2421bd98e9eb11b01fa08787c335f3
[    2.690989] systemd[1]: Started Journal Service.
[    2.747127] zram2: detected capacity change from 0 to 32768
[    2.749352] zram1: detected capacity change from 0 to 65536
[    2.753782] zram0: detected capacity change from 0 to 3999288
[    2.766040] fuse: init (API version 7.34)
[    2.932602] audit: type=1334 audit(1646984014.031:2): prog-id=5 op=LOAD
[    2.932706] audit: type=1334 audit(1646984014.031:3): prog-id=6 op=LOAD
[    3.200381] usbcore: registered new interface driver cp210x
[    3.200683] usbserial: USB Serial support registered for cp210x
[    3.200816] cp210x 1-1.4:1.0: cp210x converter detected
[    3.217398] usb 1-1.4: cp210x converter now attached to ttyUSB0
[    3.374677] Bluetooth: Core ver 2.22
[    3.374792] NET: Registered PF_BLUETOOTH protocol family
[    3.374804] Bluetooth: HCI device and connection manager initialized
[    3.374831] Bluetooth: HCI socket layer initialized
[    3.374845] Bluetooth: L2CAP socket layer initialized
[    3.374866] Bluetooth: SCO socket layer initialized
[    3.400446] usbcore: registered new interface driver btusb
[    3.401221] vc_sm_cma: module is from the staging directory, the quality is unknown, you have been warned.
[    3.403755] Bluetooth: hci0: RTL: examining hci_ver=0a hci_rev=000b lmp_ver=0a lmp_subver=8761
[    3.406746] Bluetooth: hci0: RTL: rom_version status=0 version=1
[    3.407020] Bluetooth: hci0: RTL: loading rtl_bt/rtl8761bu_fw.bin
[    3.417552] Bluetooth: hci0: RTL: loading rtl_bt/rtl8761bu_config.bin
[    3.417809] Bluetooth: hci0: RTL: cfg_sz 6, total sz 27814
[    3.423804] bcm2835_vc_sm_cma_probe: Videocore shared memory driver
[    3.423848] [vc_sm_connected_init]: start
[    3.431359] [vc_sm_connected_init]: installed successfully
[    3.444546] videodev: Linux video capture interface: v2.00
[    3.463610] bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned.
[    3.464211] bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned.
[    3.464464] bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned.
[    3.495971] bcm2835_v4l2: module is from the staging directory, the quality is unknown, you have been warned.
[    3.496049] bcm2835_codec: module is from the staging directory, the quality is unknown, you have been warned.
[    3.497745] rpivid-mem feb00000.hevc-decoder: rpivid-hevcmem initialised: Registers at 0xfeb00000 length 0x00010000
[    3.498135] bcm2835_isp: module is from the staging directory, the quality is unknown, you have been warned.
[    3.498141] rpivid-mem feb10000.rpivid-local-intc: rpivid-intcmem initialised: Registers at 0xfeb10000 length 0x00001000
[    3.509276] bcm2835-codec bcm2835-codec: Device registered as /dev/video10
[    3.509383] bcm2835-codec bcm2835-codec: Loaded V4L2 decode
[    3.517506] bcm2835-codec bcm2835-codec: Device registered as /dev/video11
[    3.517521] rpivid-mem feb20000.h264-decoder: rpivid-h264mem initialised: Registers at 0xfeb20000 length 0x00010000
[    3.517565] bcm2835-codec bcm2835-codec: Loaded V4L2 encode
[    3.518489] rpivid-mem feb30000.vp9-decoder: rpivid-vp9mem initialised: Registers at 0xfeb30000 length 0x00010000
[    3.523860] bcm2835-codec bcm2835-codec: Device registered as /dev/video12
[    3.523906] bcm2835-codec bcm2835-codec: Loaded V4L2 isp
[    3.535780] bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video13
[    3.536118] bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video14
[    3.536373] bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video15
[    3.536604] bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video16
[    3.536626] bcm2835-isp bcm2835-isp: Register output node 0 with media controller
[    3.536642] bcm2835-isp bcm2835-isp: Register capture node 1 with media controller
[    3.536655] bcm2835-isp bcm2835-isp: Register capture node 2 with media controller
[    3.536668] bcm2835-isp bcm2835-isp: Register capture node 3 with media controller
[    3.537509] bcm2835-codec bcm2835-codec: Device registered as /dev/video18
[    3.537568] bcm2835-codec bcm2835-codec: Loaded V4L2 image_fx
[    3.544038] bcm2835-codec bcm2835-codec: Device registered as /dev/video31
[    3.544098] bcm2835-codec bcm2835-codec: Loaded V4L2 encode_image
[    3.554392] bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video20
[    3.555356] bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video21
[    3.555699] bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video22
[    3.558333] bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video23
[    3.558381] bcm2835-isp bcm2835-isp: Register output node 0 with media controller
[    3.558408] bcm2835-isp bcm2835-isp: Register capture node 1 with media controller
[    3.558425] bcm2835-isp bcm2835-isp: Register capture node 2 with media controller
[    3.558442] bcm2835-isp bcm2835-isp: Register capture node 3 with media controller
[    3.558639] bcm2835-isp bcm2835-isp: Loaded V4L2 bcm2835-isp
[    3.575792] cfg80211: Loading compiled-in X.509 certificates for regulatory database
[    3.587774] cfg80211: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'
[    3.690109] snd_bcm2835: module is from the staging directory, the quality is unknown, you have been warned.
[    3.692777] bcm2835_audio bcm2835_audio: card created with 8 channels
[    3.833824] Bluetooth: hci0: RTL: fw version 0x09a98a6b
[    3.868895] brcmfmac: F1 signature read @0x18000000=0x15264345
[    3.888457] brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6
[    3.891700] usbcore: registered new interface driver brcmfmac
[    3.892066] brcmfmac mmc1:0001:1: Direct firmware load for brcm/brcmfmac43455-sdio.raspberrypi,4-model-b.bin failed with error -2
[    4.123360] brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6
[    4.127507] brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6
[    4.134841] brcmfmac: brcmf_c_preinit_dcmds: Firmware: BCM4345/6 wl0: Nov  1 2021 00:37:25 version 7.45.241 (1a2f2fa CY) FWID 01-703fd60
[    4.153049] EXT4-fs (mmcblk0p7): mounted filesystem with ordered data mode. Opts: (null). Quota mode: none.
[    4.153109] ext4 filesystem being mounted at /mnt/overlay supports timestamps until 2038 (0x7fffffff)
[    4.394301] EXT4-fs (mmcblk0p8): mounted filesystem with ordered data mode. Opts: (null). Quota mode: none.
[    4.447625] EXT4-fs (mmcblk0p8): resizing filesystem from 31038715 to 31038715 blocks
[    5.834527] Adding 1999640k swap on /dev/zram0.  Priority:-2 extents:1 across:1999640k SSFS
[    6.916661] EXT4-fs (zram2): mounted filesystem without journal. Opts: nobarrier. Quota mode: none.
[    6.922988] EXT4-fs (zram1): mounted filesystem without journal. Opts: nobarrier. Quota mode: none.
[    7.039162] systemd-journald[126]: Received client request to flush runtime journal.
[    7.049087] systemd-journald[126]: File /var/log/journal/3cc6162a61744f4a836fdb5e2e401e41/system.journal corrupted or uncleanly shut down, renaming and replacing.
[    7.723364] systemd-journald[126]: Oldest entry in /var/log/journal/3cc6162a61744f4a836fdb5e2e401e41/system.journal is older than the configured file retention duration (1month), suggesting rotation.
[    7.723391] systemd-journald[126]: /var/log/journal/3cc6162a61744f4a836fdb5e2e401e41/system.journal: Journal header limits reached or header out-of-date, rotating.
[    7.958106] audit: type=1334 audit(1652961499.231:4): prog-id=7 op=LOAD
[    7.958222] audit: type=1334 audit(1652961499.231:5): prog-id=8 op=LOAD
[    8.182857] random: crng init done
[    8.182876] random: 7 urandom warning(s) missed due to ratelimiting
[    8.381377] audit: type=1334 audit(1652961499.655:6): prog-id=9 op=LOAD
[    8.381494] audit: type=1334 audit(1652961499.655:7): prog-id=10 op=LOAD
[    8.420152] Bluetooth: BNEP (Ethernet Emulation) ver 1.3
[    8.420172] Bluetooth: BNEP filters: protocol multicast
[    8.420188] Bluetooth: BNEP socket layer initialized
[    8.440363] NET: Registered PF_ALG protocol family
[    8.736697] bcmgenet fd580000.ethernet: configuring instance for external RGMII (RX delay)
[    8.736934] bcmgenet fd580000.ethernet eth0: Link is Down
[    8.778754] brcmfmac: brcmf_cfg80211_set_power_mgmt: power save enabled
[   12.830941] bcmgenet fd580000.ethernet eth0: Link is Up - 1Gbps/Full - flow control off
[   12.830982] IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready
[   31.710789] cam-dummy-reg: disabling
[   38.409254] audit: type=1400 audit(1652961595.919:8): apparmor="STATUS" operation="profile_load" profile="unconfined" name="hassio-supervisor" pid=447 comm="apparmor_parser"
[   38.409284] audit: type=1400 audit(1652961595.919:8): apparmor="STATUS" operation="profile_load" profile="unconfined" name="hassio-supervisor///usr/bin/gdbus" pid=447 comm="apparmor_parser"
[   38.409300] audit: type=1400 audit(1652961595.919:8): apparmor="STATUS" operation="profile_load" profile="unconfined" name="hassio-supervisor///usr/bin/git" pid=447 comm="apparmor_parser"
[   38.409314] audit: type=1300 audit(1652961595.919:8): arch=c00000b7 syscall=64 success=yes exit=38459 a0=6 a1=55b6f3fc60 a2=963b a3=0 items=0 ppid=446 pid=447 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="apparmor_parser" exe="/usr/sbin/apparmor_parser" subj==unconfined key=(null)
[   38.409330] audit: type=1327 audit(1652961595.919:8): proctitle=61707061726D6F725F706172736572002D72002D57002D4C002F6D6E742F646174612F73757065727669736F722F61707061726D6F722F6361636865002F6D6E742F646174612F73757065727669736F722F61707061726D6F722F68617373696F2D73757065727669736F72
[   40.221891] audit: type=1400 audit(1652961597.739:9): apparmor="STATUS" operation="profile_load" profile="unconfined" name="docker-default" pid=470 comm="apparmor_parser"
[   40.221927] audit: type=1300 audit(1652961597.739:9): arch=c00000b7 syscall=64 success=yes exit=7945 a0=4 a1=5592a73f00 a2=1f09 a3=0 items=0 ppid=469 pid=470 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="apparmor_parser" exe="/usr/sbin/apparmor_parser" subj==unconfined key=(null)
[   40.221944] audit: type=1327 audit(1652961597.739:9): proctitle=61707061726D6F725F706172736572002D4B72002F6D6E742F646174612F646F636B65722F746D702F646F636B65722D64656661756C7433383833353631333436
[   41.284617] audit: type=1325 audit(1652961598.803:10): table=nat family=2 entries=0 op=xt_register pid=474 subj==unconfined comm="iptables"
[   41.284689] audit: type=1300 audit(1652961598.803:10): arch=c00000b7 syscall=209 success=yes exit=0 a0=4 a1=0 a2=40 a3=7ffb7257b0 items=0 ppid=448 pid=474 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="iptables" exe="/usr/sbin/xtables-legacy-multi" subj==unconfined key=(null)
[   41.439563] Initializing XFRM netlink socket
[   42.000025] hassio: port 1(veth7adec9c) entered blocking state
[   42.000055] hassio: port 1(veth7adec9c) entered disabled state
[   42.000325] device veth7adec9c entered promiscuous mode
[   42.000767] hassio: port 1(veth7adec9c) entered blocking state
[   42.000782] hassio: port 1(veth7adec9c) entered forwarding state
[   42.000964] hassio: port 1(veth7adec9c) entered disabled state
[   43.141000] eth0: renamed from veth62a97a7
[   43.156191] IPv6: ADDRCONF(NETDEV_CHANGE): veth7adec9c: link becomes ready
[   43.156326] hassio: port 1(veth7adec9c) entered blocking state
[   43.156340] hassio: port 1(veth7adec9c) entered forwarding state
[   43.156495] IPv6: ADDRCONF(NETDEV_CHANGE): hassio: link becomes ready
[   45.156162] docker0: port 1(veth58d5559) entered blocking state
[   45.156190] docker0: port 1(veth58d5559) entered disabled state
[   45.156597] device veth58d5559 entered promiscuous mode
[   45.156686] kauditd_printk_skb: 193 callbacks suppressed
[   45.156695] audit: type=1700 audit(1652961602.675:75): dev=veth58d5559 prom=256 old_prom=0 auid=4294967295 uid=0 gid=0 ses=4294967295
[   45.156959] audit: type=1300 audit(1652961602.675:75): arch=c00000b7 syscall=206 success=yes exit=40 a0=f a1=4000a18f00 a2=28 a3=0 items=0 ppid=1 pid=448 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="dockerd" exe="/usr/bin/dockerd" subj==unconfined key=(null)
[   45.156988] audit: type=1327 audit(1652961602.675:75): proctitle=2F7573722F62696E2F646F636B657264002D480066643A2F2F002D2D6370752D72742D72756E74696D653D393530303030002D2D73746F726167652D6472697665723D6F7665726C617932002D2D6C6F672D6472697665723D6A6F75726E616C64002D2D6C6F672D6F7074007461673D7B7B2E496D6167654E616D657D7D2F7B
[   45.237299] hassio: port 2(vethe09c35f) entered blocking state
[   45.237325] hassio: port 2(vethe09c35f) entered disabled state
[   45.237546] device vethe09c35f entered promiscuous mode
[   45.237641] audit: type=1700 audit(1652961602.755:76): dev=vethe09c35f prom=256 old_prom=0 auid=4294967295 uid=0 gid=0 ses=4294967295
[   45.238303] audit: type=1300 audit(1652961602.755:76): arch=c00000b7 syscall=206 success=yes exit=40 a0=f a1=4000c823c0 a2=28 a3=0 items=0 ppid=1 pid=448 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="dockerd" exe="/usr/bin/dockerd" subj==unconfined key=(null)
[   45.238332] audit: type=1327 audit(1652961602.755:76): proctitle=2F7573722F62696E2F646F636B657264002D480066643A2F2F002D2D6370752D72742D72756E74696D653D393530303030002D2D73746F726167652D6472697665723D6F7665726C617932002D2D6C6F672D6472697665723D6A6F75726E616C64002D2D6C6F672D6F7074007461673D7B7B2E496D6167654E616D657D7D2F7B
[   45.241951] hassio: port 2(vethe09c35f) entered blocking state
[   45.241975] hassio: port 2(vethe09c35f) entered forwarding state
[   45.779890] eth0: renamed from veth1e957d5
[   45.804990] hassio: port 2(vethe09c35f) entered disabled state
[   45.805260] IPv6: ADDRCONF(NETDEV_CHANGE): veth58d5559: link becomes ready
[   45.805457] docker0: port 1(veth58d5559) entered blocking state
[   45.805477] docker0: port 1(veth58d5559) entered forwarding state
[   45.805716] IPv6: ADDRCONF(NETDEV_CHANGE): docker0: link becomes ready
[   45.933731] audit: type=1325 audit(1652961603.451:77): table=nat family=2 entries=0 op=xt_register pid=1050 subj==unconfined comm="iptables"
[   45.933761] audit: type=1300 audit(1652961603.451:77): arch=c00000b7 syscall=209 success=yes exit=0 a0=4 a1=0 a2=40 a3=7fdc1f4b40 items=0 ppid=1043 pid=1050 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="iptables" exe="/usr/sbin/xtables-legacy-multi" subj==unconfined key=(null)
[   45.933778] audit: type=1327 audit(1652961603.451:77): proctitle=2F7573722F7362696E2F69707461626C6573002D2D77616974002D74006E6174002D4C002D6E
[   45.954487] audit: type=1325 audit(1652961603.471:78): table=filter family=2 entries=0 op=xt_register pid=1057 subj==unconfined comm="iptables"
[   46.036485] eth1: renamed from veth1c37094
[   46.060204] IPv6: ADDRCONF(NETDEV_CHANGE): vethe09c35f: link becomes ready
[   46.060365] hassio: port 2(vethe09c35f) entered blocking state
[   46.060380] hassio: port 2(vethe09c35f) entered forwarding state
[   52.206946] kauditd_printk_skb: 26 callbacks suppressed
[   52.206960] audit: type=1334 audit(1652961609.727:87): prog-id=0 op=UNLOAD
[   52.206989] audit: type=1334 audit(1652961609.727:88): prog-id=0 op=UNLOAD
[   52.626392] audit: type=1334 audit(1652961610.143:89): prog-id=11 op=LOAD
[   52.626581] audit: type=1334 audit(1652961610.143:90): prog-id=12 op=LOAD
[   52.854853] audit: type=1334 audit(1652961610.371:91): prog-id=13 op=LOAD
[   52.854960] audit: type=1334 audit(1652961610.375:92): prog-id=14 op=LOAD
[   53.770112] audit: type=1400 audit(1652961611.279:93): apparmor="STATUS" operation="profile_replace" info="same as current profile, skipping" profile="unconfined" name="hassio-supervisor" pid=1428 comm="apparmor_parser"
[   53.770152] audit: type=1400 audit(1652961611.279:93): apparmor="STATUS" operation="profile_replace" info="same as current profile, skipping" profile="unconfined" name="hassio-supervisor///usr/bin/gdbus" pid=1428 comm="apparmor_parser"
[   53.770177] audit: type=1400 audit(1652961611.279:93): apparmor="STATUS" operation="profile_replace" info="same as current profile, skipping" profile="unconfined" name="hassio-supervisor///usr/bin/git" pid=1428 comm="apparmor_parser"
[   53.770206] audit: type=1300 audit(1652961611.279:93): arch=c00000b7 syscall=64 success=yes exit=38459 a0=6 a1=5593dc8c60 a2=963b a3=0 items=0 ppid=1427 pid=1428 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="apparmor_parser" exe="/usr/sbin/apparmor_parser" subj==unconfined key=(null)
[   54.628720] hassio: port 3(veth9f65da5) entered blocking state
[   54.628742] hassio: port 3(veth9f65da5) entered disabled state
[   54.628967] device veth9f65da5 entered promiscuous mode
[   55.317620] eth0: renamed from veth682e02b
[   55.335949] IPv6: ADDRCONF(NETDEV_CHANGE): veth9f65da5: link becomes ready
[   55.336076] hassio: port 3(veth9f65da5) entered blocking state
[   55.336087] hassio: port 3(veth9f65da5) entered forwarding state
[   56.304531] hassio: port 4(veth351be67) entered blocking state
[   56.304552] hassio: port 4(veth351be67) entered disabled state
[   56.304740] device veth351be67 entered promiscuous mode
[   57.121747] eth0: renamed from veth79d39d3
[   57.135956] IPv6: ADDRCONF(NETDEV_CHANGE): veth351be67: link becomes ready
[   57.136095] hassio: port 4(veth351be67) entered blocking state
[   57.136108] hassio: port 4(veth351be67) entered forwarding state
[   57.972604] hassio: port 5(veth9c4d0d4) entered blocking state
[   57.972623] hassio: port 5(veth9c4d0d4) entered disabled state
[   57.973330] device veth9c4d0d4 entered promiscuous mode
[   57.973393] kauditd_printk_skb: 67 callbacks suppressed
[   57.973400] audit: type=1700 audit(1652961615.491:116): dev=veth9c4d0d4 prom=256 old_prom=0 auid=4294967295 uid=0 gid=0 ses=4294967295
[   57.973490] audit: type=1300 audit(1652961615.491:116): arch=c00000b7 syscall=206 success=yes exit=40 a0=f a1=4001326720 a2=28 a3=0 items=0 ppid=1 pid=448 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="dockerd" exe="/usr/bin/dockerd" subj==unconfined key=(null)
[   57.973508] audit: type=1327 audit(1652961615.491:116): proctitle=2F7573722F62696E2F646F636B657264002D480066643A2F2F002D2D6370752D72742D72756E74696D653D393530303030002D2D73746F726167652D6472697665723D6F7665726C617932002D2D6C6F672D6472697665723D6A6F75726E616C64002D2D6C6F672D6F7074007461673D7B7B2E496D6167654E616D657D7D2F7B
[   58.627615] audit: type=1325 audit(1652961616.147:117): table=nat family=2 entries=0 op=xt_register pid=2112 subj==unconfined comm="iptables"
[   58.627652] audit: type=1300 audit(1652961616.147:117): arch=c00000b7 syscall=209 success=yes exit=0 a0=4 a1=0 a2=40 a3=7fd3da5260 items=0 ppid=2100 pid=2112 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="iptables" exe="/usr/sbin/xtables-legacy-multi" subj==unconfined key=(null)
[   58.627670] audit: type=1327 audit(1652961616.147:117): proctitle=2F7573722F7362696E2F69707461626C6573002D2D77616974002D74006E6174002D4C002D6E
[   58.638297] audit: type=1325 audit(1652961616.155:118): table=filter family=2 entries=0 op=xt_register pid=2113 subj==unconfined comm="iptables"
[   58.638334] audit: type=1300 audit(1652961616.155:118): arch=c00000b7 syscall=209 success=yes exit=0 a0=4 a1=0 a2=40 a3=7fcf1ea790 items=0 ppid=2100 pid=2113 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="iptables" exe="/usr/sbin/xtables-legacy-multi" subj==unconfined key=(null)
[   58.638353] audit: type=1327 audit(1652961616.155:118): proctitle=2F7573722F7362696E2F69707461626C6573002D2D77616974002D4C002D6E
[   58.651883] audit: type=1325 audit(1652961616.171:119): table=nat family=2 entries=5 op=xt_replace pid=2116 subj==unconfined comm="iptables"
[   58.721940] eth0: renamed from veth3a1bb6f
[   58.738494] IPv6: ADDRCONF(NETDEV_CHANGE): veth9c4d0d4: link becomes ready
[   58.738658] hassio: port 5(veth9c4d0d4) entered blocking state
[   58.738672] hassio: port 5(veth9c4d0d4) entered forwarding state
[   66.823514] Bluetooth: RFCOMM TTY layer initialized
[   66.823550] Bluetooth: RFCOMM socket layer initialized
[   66.823568] Bluetooth: RFCOMM ver 1.11
[   69.947507] hassio: port 6(veth4b4724a) entered blocking state
[   69.947532] hassio: port 6(veth4b4724a) entered disabled state
[   69.947738] device veth4b4724a entered promiscuous mode
[   69.947809] kauditd_printk_skb: 23 callbacks suppressed
[   69.947817] audit: type=1700 audit(1652961627.467:127): dev=veth4b4724a prom=256 old_prom=0 auid=4294967295 uid=0 gid=0 ses=4294967295
[   69.947911] audit: type=1300 audit(1652961627.467:127): arch=c00000b7 syscall=206 success=yes exit=40 a0=f a1=4000d89f50 a2=28 a3=0 items=0 ppid=1 pid=448 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="dockerd" exe="/usr/bin/dockerd" subj==unconfined key=(null)
[   69.947930] audit: type=1327 audit(1652961627.467:127): proctitle=2F7573722F62696E2F646F636B657264002D480066643A2F2F002D2D6370752D72742D72756E74696D653D393530303030002D2D73746F726167652D6472697665723D6F7665726C617932002D2D6C6F672D6472697665723D6A6F75726E616C64002D2D6C6F672D6F7074007461673D7B7B2E496D6167654E616D657D7D2F7B
[   70.569743] audit: type=1325 audit(1652961628.087:128): table=nat family=2 entries=0 op=xt_register pid=2916 subj==unconfined comm="iptables"
[   70.569777] audit: type=1300 audit(1652961628.087:128): arch=c00000b7 syscall=209 success=yes exit=0 a0=4 a1=0 a2=40 a3=7ff300e8e0 items=0 ppid=2908 pid=2916 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="iptables" exe="/usr/sbin/xtables-legacy-multi" subj==unconfined key=(null)
[   70.569792] audit: type=1327 audit(1652961628.087:128): proctitle=2F7573722F7362696E2F69707461626C6573002D2D77616974002D74006E6174002D4C002D6E
[   70.582349] audit: type=1325 audit(1652961628.099:129): table=filter family=2 entries=0 op=xt_register pid=2917 subj==unconfined comm="iptables"
[   70.582389] audit: type=1300 audit(1652961628.099:129): arch=c00000b7 syscall=209 success=yes exit=0 a0=4 a1=0 a2=40 a3=7ff060cf00 items=0 ppid=2908 pid=2917 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="iptables" exe="/usr/sbin/xtables-legacy-multi" subj==unconfined key=(null)
[   70.582410] audit: type=1327 audit(1652961628.099:129): proctitle=2F7573722F7362696E2F69707461626C6573002D2D77616974002D4C002D6E
[   70.602494] audit: type=1325 audit(1652961628.119:130): table=nat family=2 entries=5 op=xt_replace pid=2920 subj==unconfined comm="iptables"
[   70.677765] eth0: renamed from veth5da7cc6
[   70.700857] IPv6: ADDRCONF(NETDEV_CHANGE): veth4b4724a: link becomes ready
[   70.701061] hassio: port 6(veth4b4724a) entered blocking state
[   70.701079] hassio: port 6(veth4b4724a) entered forwarding state
[   71.475461] hassio: port 7(vethf3cc781) entered blocking state
[   71.475480] hassio: port 7(vethf3cc781) entered disabled state
[   71.475677] device vethf3cc781 entered promiscuous mode
[   71.476031] hassio: port 7(vethf3cc781) entered blocking state
[   71.476043] hassio: port 7(vethf3cc781) entered forwarding state
[   71.476387] hassio: port 7(vethf3cc781) entered disabled state
[   72.408174] eth0: renamed from veth5cb722a
[   72.436494] IPv6: ADDRCONF(NETDEV_CHANGE): vethf3cc781: link becomes ready
[   72.436742] hassio: port 7(vethf3cc781) entered blocking state
[   72.436762] hassio: port 7(vethf3cc781) entered forwarding state
[   78.497290] hassio: port 8(veth1eedfad) entered blocking state
[   78.497312] hassio: port 8(veth1eedfad) entered disabled state
[   78.497517] device veth1eedfad entered promiscuous mode
[   78.497890] hassio: port 8(veth1eedfad) entered blocking state
[   78.497906] hassio: port 8(veth1eedfad) entered forwarding state
[   78.502929] kauditd_printk_skb: 92 callbacks suppressed
[   78.502940] audit: type=1700 audit(1652961636.015:161): dev=veth1eedfad prom=256 old_prom=0 auid=4294967295 uid=0 gid=0 ses=4294967295
[   78.502963] audit: type=1300 audit(1652961636.015:161): arch=c00000b7 syscall=206 success=yes exit=40 a0=f a1=400135b320 a2=28 a3=0 items=0 ppid=1 pid=448 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="dockerd" exe="/usr/bin/dockerd" subj==unconfined key=(null)
[   78.502979] audit: type=1327 audit(1652961636.015:161): proctitle=2F7573722F62696E2F646F636B657264002D480066643A2F2F002D2D6370752D72742D72756E74696D653D393530303030002D2D73746F726167652D6472697665723D6F7665726C617932002D2D6C6F672D6472697665723D6A6F75726E616C64002D2D6C6F672D6F7074007461673D7B7B2E496D6167654E616D657D7D2F7B
[   78.503124] hassio: port 8(veth1eedfad) entered disabled state
[   79.379235] audit: type=1325 audit(1652961636.899:162): table=nat family=2 entries=0 op=xt_register pid=4069 subj==unconfined comm="iptables"
[   79.379274] audit: type=1300 audit(1652961636.899:162): arch=c00000b7 syscall=209 success=yes exit=0 a0=4 a1=0 a2=40 a3=7fde5b9520 items=0 ppid=4057 pid=4069 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="iptables" exe="/usr/sbin/xtables-legacy-multi" subj==unconfined key=(null)
[   79.379291] audit: type=1327 audit(1652961636.899:162): proctitle=2F7573722F7362696E2F69707461626C6573002D2D77616974002D74006E6174002D4C002D6E
[   79.396139] audit: type=1325 audit(1652961636.915:163): table=filter family=2 entries=0 op=xt_register pid=4070 subj==unconfined comm="iptables"
[   79.396181] audit: type=1300 audit(1652961636.915:163): arch=c00000b7 syscall=209 success=yes exit=0 a0=4 a1=0 a2=40 a3=7feb3652c0 items=0 ppid=4057 pid=4070 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="iptables" exe="/usr/sbin/xtables-legacy-multi" subj==unconfined key=(null)
[   79.396197] audit: type=1327 audit(1652961636.915:163): proctitle=2F7573722F7362696E2F69707461626C6573002D2D77616974002D4C002D6E
[   79.413960] audit: type=1325 audit(1652961636.931:164): table=nat family=2 entries=5 op=xt_replace pid=4073 subj==unconfined comm="iptables"
[   79.516603] eth0: renamed from veth27422f9
[   79.548211] IPv6: ADDRCONF(NETDEV_CHANGE): veth1eedfad: link becomes ready
[   79.548337] hassio: port 8(veth1eedfad) entered blocking state
[   79.548347] hassio: port 8(veth1eedfad) entered forwarding state
[   81.756042] hassio: port 9(vethc004342) entered blocking state
[   81.756067] hassio: port 9(vethc004342) entered disabled state
[   81.756292] device vethc004342 entered promiscuous mode
[   82.899593] eth0: renamed from veth23e31b4
[   82.916420] IPv6: ADDRCONF(NETDEV_CHANGE): vethc004342: link becomes ready
[   82.916591] hassio: port 9(vethc004342) entered blocking state
[   82.916605] hassio: port 9(vethc004342) entered forwarding state
[  159.161960] hassio: port 10(vethcb42c26) entered blocking state
[  159.161979] hassio: port 10(vethcb42c26) entered disabled state
[  159.163160] device vethcb42c26 entered promiscuous mode
[  159.163242] kauditd_printk_skb: 60 callbacks suppressed
[  159.163248] audit: type=1700 audit(1652961716.678:187): dev=vethcb42c26 prom=256 old_prom=0 auid=4294967295 uid=0 gid=0 ses=4294967295
[  159.163357] audit: type=1300 audit(1652961716.678:187): arch=c00000b7 syscall=206 success=yes exit=40 a0=f a1=400155be00 a2=28 a3=0 items=0 ppid=1 pid=448 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="dockerd" exe="/usr/bin/dockerd" subj==unconfined key=(null)
[  159.163380] audit: type=1327 audit(1652961716.678:187): proctitle=2F7573722F62696E2F646F636B657264002D480066643A2F2F002D2D6370752D72742D72756E74696D653D393530303030002D2D73746F726167652D6472697665723D6F7665726C617932002D2D6C6F672D6472697665723D6A6F75726E616C64002D2D6C6F672D6F7074007461673D7B7B2E496D6167654E616D657D7D2F7B
[  159.163612] hassio: port 10(vethcb42c26) entered blocking state
[  159.163626] hassio: port 10(vethcb42c26) entered forwarding state
[  159.165248] hassio: port 10(vethcb42c26) entered disabled state
[  160.029776] audit: type=1325 audit(1652961717.546:188): table=nat family=2 entries=0 op=xt_register pid=6907 subj==unconfined comm="iptables"
[  160.029829] audit: type=1300 audit(1652961717.546:188): arch=c00000b7 syscall=209 success=yes exit=0 a0=4 a1=0 a2=40 a3=7ffad770b0 items=0 ppid=6892 pid=6907 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="iptables" exe="/usr/sbin/xtables-legacy-multi" subj==unconfined key=(null)
[  160.029846] audit: type=1327 audit(1652961717.546:188): proctitle=2F7573722F7362696E2F69707461626C6573002D2D77616974002D74006E6174002D4C002D6E
[  160.054835] audit: type=1325 audit(1652961717.570:189): table=filter family=2 entries=0 op=xt_register pid=6908 subj==unconfined comm="iptables"
[  160.054885] audit: type=1300 audit(1652961717.570:189): arch=c00000b7 syscall=209 success=yes exit=0 a0=4 a1=0 a2=40 a3=7fdbbf8720 items=0 ppid=6892 pid=6908 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="iptables" exe="/usr/sbin/xtables-legacy-multi" subj==unconfined key=(null)
[  160.054902] audit: type=1327 audit(1652961717.570:189): proctitle=2F7573722F7362696E2F69707461626C6573002D2D77616974002D4C002D6E
[  160.078899] audit: type=1325 audit(1652961717.594:190): table=nat family=2 entries=5 op=xt_replace pid=6911 subj==unconfined comm="iptables"
[  160.222751] eth0: renamed from veth9b718b5
[  160.254598] IPv6: ADDRCONF(NETDEV_CHANGE): vethcb42c26: link becomes ready
[  160.254765] hassio: port 10(vethcb42c26) entered blocking state
[  160.254780] hassio: port 10(vethcb42c26) entered forwarding state
[  166.780031] kauditd_printk_skb: 23 callbacks suppressed
[  166.780047] audit: type=1334 audit(1652961724.293:198): prog-id=15 op=LOAD
[  166.782027] audit: type=1334 audit(1652961724.297:199): prog-id=16 op=LOAD
[  167.584955] audit: type=1334 audit(1652961725.101:200): prog-id=17 op=LOAD
[  167.585268] audit: type=1334 audit(1652961725.101:201): prog-id=18 op=LOAD
[  197.644588] audit: type=1334 audit(1652961755.161:202): prog-id=0 op=UNLOAD
[  197.644627] audit: type=1334 audit(1652961755.161:203): prog-id=0 op=UNLOAD
[  198.424763] audit: type=1334 audit(1652961755.941:204): prog-id=0 op=UNLOAD
[  198.424834] audit: type=1334 audit(1652961755.941:205): prog-id=0 op=UNLOAD
agners commented 2 years ago

Hm, a Conbee II should enumerate with something like this:

[196811.525811] usb 5-4.3.4: new full-speed USB device number 117 using xhci_hcd
[196811.651043] usb 5-4.3.4: New USB device found, idVendor=1cf1, idProduct=0030, bcdDevice= 1.00
[196811.651051] usb 5-4.3.4: New USB device strings: Mfr=1, Product=2, SerialNumber=3
[196811.651053] usb 5-4.3.4: Product: ConBee II
[196811.651054] usb 5-4.3.4: Manufacturer: dresden elektronik ingenieurtechnik GmbH
[196811.651056] usb 5-4.3.4: SerialNumber: DE1963366
[196811.690120] cdc_acm 5-4.3.4:1.0: ttyACM2: USB ACM device

I don't see such device in your kernel log, that means the Linux kernel itself does not enumerate/see that device. Can you try a different USB port? It seems as if that port or the device is dead.

mbrennan commented 2 years ago

Sorry, I realized it's not a Conbee II stick, it's a Raspbee II integrated device onboard. Everything was working fine prior to the upgrade. After the upgrade it stopped working. Downgrading to 7.6 doesn't seem to help either.

agners commented 2 years ago

@mbrennan ok, this odes not belong here then. I created a new issue #1931, let's continue discussing there.

roessi commented 2 years ago

I made some Research last night. It looks like kernel version 5.15.41 is causing the problem On my Odroid n2 the 5V of the USB port are switched off. No idea if this can be fixed with a setting in the linux config, or if it's a driver problem. I don't know enough about linux for that, but as a result, no USB device is recognized on the Odroid.

thomasklein commented 2 years ago

+1 with the conbee II issue also running HA on odroid-n2 (Hardkernel ODROID-N2/N2+)

Home Assistant Core 2022.6.0
Home Assistant Supervisor 2022.05.3
Home Assistant OS 8.1

The supervisor logs show me:

22-06-01 23:01:28 WARNING (MainThread) [supervisor.addons.options] Option 'vnc_password' does not exist in the schema for deCONZ (core_deconz)

The host logs don't mention Product: ConBee II, however I found:

 1.423326] USB_PWR_EN: supplied by 5V
[    1.427009] VDDAO_1V8: supplied by VDDAO_3V3
[    5.046507] phy-g12a-usb3-pcie: probe of ff646000.phy failed with error -5

I wonder if that is related.

Edit: Even downgrading to 7.4. or 7.6. didn't solve the issue.

thomasklein commented 2 years ago

@roessi were you able to advance on it?

roessi commented 2 years ago

No I changed my hardware to an intel nuc.

Mit freundlichen Grüßen Rudi Rösler

Am 05.06.2022 um 22:49 schrieb Thomas Klein @.***>:



@roessihttps://nam12.safelinks.protection.outlook.com/?url=https%3A%2F%2Fgithub.com%2Froessi&data=05%7C01%7C%7C7c63750c81e74a14374208da4734ee3f%7C84df9e7fe9f640afb435aaaaaaaaaaaa%7C1%7C0%7C637900589899281078%7CUnknown%7CTWFpbGZsb3d8eyJWIjoiMC4wLjAwMDAiLCJQIjoiV2luMzIiLCJBTiI6Ik1haWwiLCJXVCI6Mn0%3D%7C3000%7C%7C%7C&sdata=zAZfMxfRdh%2Bjc8LTxEyB2XzIhOboQiue%2B%2Bakz41Ta0w%3D&reserved=0 were you able to advance on it?

— Reply to this email directly, view it on GitHubhttps://nam12.safelinks.protection.outlook.com/?url=https%3A%2F%2Fgithub.com%2Fhome-assistant%2Foperating-system%2Fissues%2F1795%23issuecomment-1146881163&data=05%7C01%7C%7C7c63750c81e74a14374208da4734ee3f%7C84df9e7fe9f640afb435aaaaaaaaaaaa%7C1%7C0%7C637900589899281078%7CUnknown%7CTWFpbGZsb3d8eyJWIjoiMC4wLjAwMDAiLCJQIjoiV2luMzIiLCJBTiI6Ik1haWwiLCJXVCI6Mn0%3D%7C3000%7C%7C%7C&sdata=xiLWi3r9hl%2F94i2UpLVrzrhFL%2B2k7ZcfShcp%2BKvYjTk%3D&reserved=0, or unsubscribehttps://nam12.safelinks.protection.outlook.com/?url=https%3A%2F%2Fgithub.com%2Fnotifications%2Funsubscribe-auth%2FAK3OPISC24DLASXJE5KOSKDVNUHGXANCNFSM5Q5LDSGA&data=05%7C01%7C%7C7c63750c81e74a14374208da4734ee3f%7C84df9e7fe9f640afb435aaaaaaaaaaaa%7C1%7C0%7C637900589899281078%7CUnknown%7CTWFpbGZsb3d8eyJWIjoiMC4wLjAwMDAiLCJQIjoiV2luMzIiLCJBTiI6Ik1haWwiLCJXVCI6Mn0%3D%7C3000%7C%7C%7C&sdata=W3fvUCczULn1%2BZap6aHYkCeQlhpZlERzHISjfVXk5H8%3D&reserved=0. You are receiving this because you were mentioned.Message ID: @.***>

github-actions[bot] commented 2 years ago

There hasn't been any activity on this issue recently. To keep our backlog manageable we have to clean old issues, as many of them have already been resolved with the latest updates. Please make sure to update to the latest Home Assistant OS version and check if that solves the issue. Let us know if that works for you by adding a comment 👍 This issue has now been marked as stale and will be closed if no further activity occurs. Thank you for your contributions.