home-assistant / operating-system

:beginner: Home Assistant Operating System
Apache License 2.0
4.89k stars 971 forks source link

Home Assistant OS is not responsive to modprobe.d blacklist configuration #1545

Open asdfghjkai opened 3 years ago

asdfghjkai commented 3 years ago

Hardware Environment

Home Assistant OS release:

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 Europe/London
Home Assistant Community Store GitHub API | ok -- | -- Github API Calls Remaining | 4923 Installed Version | 1.14.1 Stage | running Available Repositories | 953 Installed Repositories | 11
Home Assistant Cloud logged_in | false -- | -- 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 | 219.4 GB disk_used | 27.2 GB healthy | true supported | true board | rpi4-64 supervisor_api | ok version_api | ok installed_addons | File editor (5.3.3), Check Home Assistant configuration (3.8.0), AirCast (3.1.1), Samba share (9.5.1), Mosquitto broker (6.0.1), Nextcloud Backup (0.16.2), SSH & Web Terminal (9.0.1), Home Panel (2.2.0), Portainer (2.0.0), zigbee2mqttassistant (0.3.157), Zigbee2mqtt (1.21.1-1), Git pull (7.13.1), MariaDB (2.4.0), Grafana (7.0.2), InfluxDB (4.1.1), Wmbusmeters (W-MBus to MQTT) (0.2.19)
Lovelace dashboards | 3 -- | -- resources | 4 views | 17 mode | storage

Supervisor logs:

``` 21-09-13 20:19:22 INFO (SyncWorker_0) [supervisor.docker.interface] Attaching to ghcr.io/home-assistant/aarch64-hassio-observer with version 2021.06.0 21-09-13 20:19:22 INFO (SyncWorker_0) [supervisor.docker.interface] Attaching to homeassistant/aarch64-hassio-multicast with version 2021.04.0 21-09-13 20:19:22 INFO (MainThread) [supervisor.plugins.multicast] Starting Multicast plugin 21-09-13 20:19:23 INFO (SyncWorker_0) [supervisor.docker.multicast] Starting Multicast homeassistant/aarch64-hassio-multicast with version 2021.04.0 - Host 21-09-13 20:19:23 INFO (MainThread) [supervisor.updater] Fetching update data from https://version.home-assistant.io/stable.json 21-09-13 20:19:26 INFO (SyncWorker_0) [supervisor.docker.interface] Attaching to ghcr.io/home-assistant/raspberrypi4-64-homeassistant with version 2021.9.6 21-09-13 20:19:26 INFO (MainThread) [supervisor.homeassistant.secrets] Loaded 5 Home Assistant secrets 21-09-13 20:19:26 INFO (MainThread) [supervisor.hassos] Detect Home Assistant Operating System 6.3 / BootSlot A 21-09-13 20:19:27 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-13 20:19:27 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-13 20:19:27 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-13 20:19:28 INFO (MainThread) [supervisor.store.git] Loading add-on /data/addons/git/45df7312 repository 21-09-13 20:19:28 INFO (MainThread) [supervisor.store.git] Loading add-on /data/addons/git/a0d7b954 repository 21-09-13 20:19:28 INFO (MainThread) [supervisor.store.git] Loading add-on /data/addons/git/806b11b5 repository 21-09-13 20:19:28 INFO (MainThread) [supervisor.store.git] Loading add-on /data/addons/core repository 21-09-13 20:19:28 INFO (MainThread) [supervisor.store.git] Loading add-on /data/addons/git/229cc4d7 repository 21-09-13 20:19:28 INFO (MainThread) [supervisor.store.git] Loading add-on /data/addons/git/cada3e3b repository 21-09-13 20:19: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-13 20:19: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-13 20:19: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-13 20:19:29 INFO (MainThread) [supervisor.store] Loading add-ons from store: 69 all - 69 new - 0 remove 21-09-13 20:19:29 INFO (MainThread) [supervisor.addons] Found 16 installed add-ons 21-09-13 20:19:30 INFO (SyncWorker_5) [supervisor.docker.interface] Attaching to homeassistant/aarch64-addon-mariadb with version 2.4.0 21-09-13 20:19:30 INFO (SyncWorker_6) [supervisor.docker.interface] Attaching to homeassistant/aarch64-addon-mosquitto with version 6.0.1 21-09-13 20:19:30 INFO (SyncWorker_7) [supervisor.docker.interface] Attaching to ghcr.io/sebclem/hassio-nextcloud-backup/aarch64 with version 0.16.2 21-09-13 20:19:30 INFO (SyncWorker_3) [supervisor.docker.interface] Attaching to ghcr.io/hassio-addons/influxdb/aarch64 with version 4.1.1 21-09-13 20:19:30 INFO (SyncWorker_0) [supervisor.docker.interface] Attaching to zigbee2mqtt/zigbee2mqtt-aarch64 with version 1.21.1-1 21-09-13 20:19:30 INFO (SyncWorker_2) [supervisor.docker.interface] Attaching to homeassistant/aarch64-addon-git_pull with version 7.13.1 21-09-13 20:19:30 INFO (SyncWorker_4) [supervisor.docker.interface] Attaching to ghcr.io/hassio-addons/grafana/aarch64 with version 7.0.2 21-09-13 20:19:30 INFO (SyncWorker_1) [supervisor.docker.interface] Attaching to ghcr.io/hassio-addons/ssh/aarch64 with version 9.0.1 21-09-13 20:19:30 INFO (SyncWorker_0) [supervisor.docker.interface] Attaching to ghcr.io/hassio-addons/portainer/aarch64 with version 2.0.0 21-09-13 20:19:30 INFO (SyncWorker_5) [supervisor.docker.interface] Attaching to homeassistant/aarch64-addon-check_config with version 3.8.0 21-09-13 20:19:30 INFO (SyncWorker_6) [supervisor.docker.interface] Attaching to ghcr.io/hassio-addons/aircast/aarch64 with version 3.1.1 21-09-13 20:19:30 INFO (SyncWorker_3) [supervisor.docker.interface] Attaching to ghcr.io/hassio-addons/home-panel/aarch64 with version 2.2.0 21-09-13 20:19:30 INFO (SyncWorker_7) [supervisor.docker.interface] Attaching to homeassistant/aarch64-addon-configurator with version 5.3.3 21-09-13 20:19:30 INFO (SyncWorker_1) [supervisor.docker.interface] Attaching to homeassistant/aarch64-addon-samba with version 9.5.1 21-09-13 20:19:30 INFO (SyncWorker_2) [supervisor.docker.interface] Attaching to carldebilly/zigbee2mqttassistant with version 172 21-09-13 20:19:30 INFO (SyncWorker_4) [supervisor.docker.interface] Attaching to cada3e3b/aarch64-addon-wmbusmeters with version 0.2.19 21-09-13 20:19:30 INFO (MainThread) [supervisor.backups.manager] Found 91 backup files 21-09-13 20:19:32 INFO (MainThread) [supervisor.discovery] Loaded 1 messages 21-09-13 20:19:32 INFO (MainThread) [supervisor.ingress] Loaded 0 ingress sessions 21-09-13 20:19:32 INFO (MainThread) [supervisor.resolution.check] Starting system checks with state CoreState.SETUP 21-09-13 20:19:32 INFO (MainThread) [supervisor.resolution.check] System checks complete 21-09-13 20:19:32 INFO (MainThread) [supervisor.resolution.evaluate] Starting system evaluation with state CoreState.SETUP 21-09-13 20:19:32 INFO (MainThread) [supervisor.resolution.evaluate] System evaluation complete 21-09-13 20:19:32 INFO (MainThread) [supervisor.jobs] 'ResolutionFixup.run_autofix' blocked from execution, system is not running - CoreState.SETUP 21-09-13 20:19:32 INFO (MainThread) [supervisor.resolution.evaluate] Starting system evaluation with state CoreState.SETUP 21-09-13 20:19:33 INFO (MainThread) [supervisor.resolution.evaluate] System evaluation complete 21-09-13 20:19:33 INFO (MainThread) [__main__] Running Supervisor 21-09-13 20:19:33 INFO (MainThread) [supervisor.hassos] Rauc: A - marked slot kernel.0 as good 21-09-13 20:19:33 INFO (MainThread) [supervisor.addons] Phase 'AddonStartup.INITIALIZE' starting 0 add-ons 21-09-13 20:19:33 INFO (MainThread) [supervisor.addons] Phase 'AddonStartup.SYSTEM' starting 2 add-ons 21-09-13 20:19:35 INFO (SyncWorker_5) [supervisor.docker.addon] Starting Docker add-on homeassistant/aarch64-addon-mosquitto with version 6.0.1 21-09-13 20:19:36 INFO (SyncWorker_4) [supervisor.docker.addon] Starting Docker add-on homeassistant/aarch64-addon-mariadb with version 2.4.0 21-09-13 20:19:39 INFO (MainThread) [supervisor.services.modules.mqtt] Set core_mosquitto as service provider for mqtt 21-09-13 20:19:41 INFO (MainThread) [supervisor.addons] Phase 'AddonStartup.SERVICES' starting 4 add-ons 21-09-13 20:19:43 INFO (SyncWorker_0) [supervisor.docker.addon] Starting Docker add-on ghcr.io/hassio-addons/aircast/aarch64 with version 3.1.1 21-09-13 20:19:44 WARNING (SyncWorker_2) [supervisor.docker.addon] Portainer running with disabled protected mode! 21-09-13 20:19:44 INFO (SyncWorker_2) [supervisor.docker.interface] Cleaning addon_a0d7b954_portainer application 21-09-13 20:19:45 INFO (MainThread) [supervisor.services.modules.mysql] Set core_mariadb as service provider for MySQL 21-09-13 20:19:46 INFO (SyncWorker_2) [supervisor.docker.addon] Starting Docker add-on ghcr.io/hassio-addons/portainer/aarch64 with version 2.0.0 21-09-13 20:19:47 INFO (SyncWorker_6) [supervisor.docker.addon] Starting Docker add-on ghcr.io/hassio-addons/grafana/aarch64 with version 7.0.2 21-09-13 20:19:49 INFO (SyncWorker_4) [supervisor.docker.addon] Starting Docker add-on ghcr.io/hassio-addons/influxdb/aarch64 with version 4.1.1 21-09-13 20:19:54 INFO (MainThread) [supervisor.core] Start Home Assistant Core 21-09-13 20:19:54 INFO (SyncWorker_0) [supervisor.docker.interface] Starting homeassistant 21-09-13 20:19:55 INFO (MainThread) [supervisor.homeassistant.core] Wait until Home Assistant is ready 21-09-13 20:19:56 INFO (MainThread) [supervisor.api.middleware.security] /dns/info access from a0d7b954_influxdb 21-09-13 20:20:13 INFO (MainThread) [supervisor.resolution.evaluate] Starting system evaluation with state CoreState.STARTUP 21-09-13 20:20:13 INFO (MainThread) [supervisor.resolution.evaluate] System evaluation complete 21-09-13 20:20:15 INFO (MainThread) [supervisor.homeassistant.api] Updated Home Assistant API token 21-09-13 20:21:28 INFO (MainThread) [supervisor.homeassistant.core] Detect a running Home Assistant instance 21-09-13 20:21:28 INFO (MainThread) [supervisor.addons] Phase 'AddonStartup.APPLICATION' starting 4 add-ons 21-09-13 20:21:30 INFO (SyncWorker_7) [supervisor.docker.addon] Starting Docker add-on ghcr.io/sebclem/hassio-nextcloud-backup/aarch64 with version 0.16.2 21-09-13 20:21:32 INFO (SyncWorker_5) [supervisor.docker.addon] Starting Docker add-on ghcr.io/hassio-addons/home-panel/aarch64 with version 2.2.0 21-09-13 20:21:35 INFO (SyncWorker_2) [supervisor.docker.addon] Starting Docker add-on zigbee2mqtt/zigbee2mqtt-aarch64 with version 1.21.1-1 21-09-13 20:21:38 INFO (SyncWorker_4) [supervisor.docker.addon] Starting Docker add-on cada3e3b/aarch64-addon-wmbusmeters with version 0.2.19 21-09-13 20:21:43 INFO (MainThread) [supervisor.misc.tasks] All core tasks are scheduled 21-09-13 20:21:43 INFO (MainThread) [supervisor.core] Supervisor is up and running 21-09-13 20:21:43 INFO (MainThread) [supervisor.host.info] Updating local host information 21-09-13 20:21:43 INFO (MainThread) [supervisor.updater] Fetching update data from https://version.home-assistant.io/stable.json 21-09-13 20:21:43 INFO (MainThread) [supervisor.resolution.check] Starting system checks with state CoreState.RUNNING 21-09-13 20:21:43 INFO (MainThread) [supervisor.resolution.checks.base] Run check for IssueType.SECURITY/ContextType.CORE 21-09-13 20:21:43 INFO (MainThread) [supervisor.resolution.checks.base] Run check for IssueType.TRUST/ContextType.PLUGIN 21-09-13 20:21:44 INFO (MainThread) [supervisor.host.services] Updating service information 21-09-13 20:21:45 INFO (MainThread) [supervisor.host.network] Updating local network information 21-09-13 20:21:52 INFO (MainThread) [supervisor.host.sound] Updating PulseAudio information 21-09-13 20:21:53 INFO (MainThread) [supervisor.host.manager] Host information reload completed 21-09-13 20:21:56 INFO (MainThread) [supervisor.api.middleware.security] /snapshots access from 229cc4d7_nextcloud_backup 21-09-13 20:21:57 INFO (MainThread) [supervisor.resolution.checks.base] Run check for IssueType.TRUST/ContextType.CORE 21-09-13 20:21:58 INFO (MainThread) [supervisor.resolution.checks.base] Run check for IssueType.TRUST/ContextType.SUPERVISOR 21-09-13 20:22:00 INFO (MainThread) [supervisor.resolution.checks.base] Run check for IssueType.PWNED/ContextType.ADDON 21-09-13 20:22:00 WARNING (MainThread) [supervisor.addons.options] Option 'interface' does not exist in the schema for Samba share (core_samba) 21-09-13 20:22:00 INFO (MainThread) [supervisor.resolution.checks.base] Run check for IssueType.FREE_SPACE/ContextType.SYSTEM 21-09-13 20:22:00 INFO (MainThread) [supervisor.resolution.check] System checks complete 21-09-13 20:22:00 INFO (MainThread) [supervisor.resolution.evaluate] Starting system evaluation with state CoreState.RUNNING 21-09-13 20:22:03 INFO (MainThread) [supervisor.resolution.evaluate] System evaluation complete 21-09-13 20:22:03 INFO (MainThread) [supervisor.resolution.fixup] Starting system autofix at state CoreState.RUNNING 21-09-13 20:22:03 INFO (MainThread) [supervisor.resolution.fixup] System autofix complete ```

Journal logs:

Lots of these. I think one of my thermostat's is not playing too well with z2m

``` Aug 22 21:22:00 homeassistant ce70fc21def0[475]: 2021-08-22 22:22:00 WARNING (MainThread) [homeassistant.helpers.template] Template variable warning: dict object has no element Undefined when rendering '{% set values = {'idle':'off','heat':'heating','cool':'cooling','fan only':'fan'} %}{{ values[value_json.running_state] }}' Aug 22 21:22:00 homeassistant ce70fc21def0[475]: 2021-08-22 22:22:00 WARNING (MainThread) [homeassistant.helpers.template] Template variable warning: dict object has no element Undefined when rendering '{% set values = {'idle':'off','heat':'heating','cool':'cooling','fan only':'fan'} %}{{ values[value_json.running_state] }}' Aug 22 21:22:00 homeassistant ce70fc21def0[475]: 2021-08-22 22:22:00 WARNING (MainThread) [homeassistant.helpers.template] Template variable warning: dict object has no element Undefined when rendering '{% set values = {'idle':'off','heat':'heating','cool':'cooling','fan only':'fan'} %}{{ values[value_json.running_state] }}' Aug 22 21:22:09 homeassistant ce70fc21def0[475]: 2021-08-22 22:22:09 WARNING (MainThread) [homeassistant.helpers.template] Template variable warning: dict object has no element Undefined when rendering '{% set values = {'idle':'off','heat':'heating','cool':'cooling','fan only':'fan'} %}{{ values[value_json.running_state] }}' ```

Kernel logs:

``` [ 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.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-0x000000003b2fffff] [ 0.000000] node 0: [mem 0x0000000040000000-0x00000000fbffffff] [ 0.000000] node 0: [mem 0x0000000100000000-0x00000001ffffffff] [ 0.000000] Zeroed struct page in unavailable ranges: 256 pages [ 0.000000] Initmem setup node 0 [mem 0x0000000000000000-0x00000001ffffffff] [ 0.000000] On node 0 totalpages: 2061056 [ 0.000000] DMA zone: 3788 pages used for memmap [ 0.000000] DMA zone: 0 pages reserved [ 0.000000] DMA zone: 242432 pages, LIFO batch:63 [ 0.000000] DMA32 zone: 12288 pages used for memmap [ 0.000000] DMA32 zone: 770048 pages, LIFO batch:63 [ 0.000000] Normal zone: 16384 pages used for memmap [ 0.000000] Normal zone: 1048576 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: 2028596 [ 0.000000] Kernel command line: zram.enabled=1 zram.num_devices=3 apparmor=1 security=apparmor rootwait systemd.machine_id=890efcc406614ddd8026823e6fb2355f 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=1440 bcm2708_fb.fbheight=900 bcm2708_fb.fbswap=1 smsc95xx.macaddr=DC:A6:32:C7:AF:05 vc_mem.mem_base=0x3eb00000 vc_mem.mem_size=0x3ff00000 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: 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 0x0000000037300000-0x000000003b300000] (64MB) [ 0.000000] Memory: 7928972K/8244224K available (12096K kernel code, 1962K rwdata, 4160K rodata, 3840K init, 1261K bss, 249716K 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.000258] Console: colour dummy device 80x25 [ 0.000836] printk: console [tty1] enabled [ 0.000900] Calibrating delay loop (skipped), value calculated using timer frequency.. 108.00 BogoMIPS (lpj=216000) [ 0.000942] pid_max: default: 32768 minimum: 301 [ 0.001092] LSM: Security Framework initializing [ 0.001211] AppArmor: AppArmor initialized [ 0.001467] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, linear) [ 0.001579] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, linear) [ 0.004704] rcu: Hierarchical SRCU implementation. [ 0.005757] EFI services will not be available. [ 0.006281] smp: Bringing up secondary CPUs ... [ 0.007287] Detected PIPT I-cache on CPU1 [ 0.007358] CPU1: Booted secondary processor 0x0000000001 [0x410fd083] [ 0.008537] Detected PIPT I-cache on CPU2 [ 0.008584] CPU2: Booted secondary processor 0x0000000002 [0x410fd083] [ 0.009683] Detected PIPT I-cache on CPU3 [ 0.009729] CPU3: Booted secondary processor 0x0000000003 [0x410fd083] [ 0.009880] smp: Brought up 1 node, 4 CPUs [ 0.009970] SMP: Total of 4 processors activated. [ 0.009994] CPU features: detected: 32-bit EL0 Support [ 0.010018] CPU features: detected: CRC32 instructions [ 0.010041] CPU features: detected: 32-bit EL1 Support [ 0.047650] CPU: All CPU(s) started at EL2 [ 0.047733] alternatives: patching kernel code [ 0.049651] devtmpfs: initialized [ 0.060273] Enabled cp15_barrier support [ 0.060339] Enabled setend support [ 0.060547] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns [ 0.060594] futex hash table entries: 1024 (order: 4, 65536 bytes, linear) [ 0.062642] pinctrl core: initialized pinctrl subsystem [ 0.063438] DMI not present or invalid. [ 0.063849] NET: Registered protocol family 16 [ 0.067157] DMA: preallocated 1024 KiB GFP_KERNEL pool for atomic allocations [ 0.067432] DMA: preallocated 1024 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.068404] DMA: preallocated 1024 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.068488] audit: initializing netlink subsys (disabled) [ 0.068874] audit: type=2000 audit(0.068:1): state=initialized audit_enabled=0 res=1 [ 0.069349] thermal_sys: Registered thermal governor 'step_wise' [ 0.069806] cpuidle: using governor menu [ 0.070309] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. [ 0.070497] ASID allocator initialised with 65536 entries [ 0.070641] Serial: AMBA PL011 UART driver [ 0.098418] bcm2835-mbox fe00b880.mailbox: mailbox enabled [ 0.104312] raspberrypi-firmware soc:firmware: Attached to firmware from 2021-05-27T14:02:15, variant start [ 0.108332] raspberrypi-firmware soc:firmware: Firmware hash is 7d9a298cda813f747b51fe17e1e417e7bf5ca94d [ 0.142563] bcm2835-dma fe007000.dma: DMA legacy API manager, dmachans=0x1 [ 0.146382] vgaarb: loaded [ 0.146792] SCSI subsystem initialized [ 0.147031] usbcore: registered new interface driver usbfs [ 0.147097] usbcore: registered new interface driver hub [ 0.147183] usbcore: registered new device driver usb [ 0.147498] usb_phy_generic phy: supply vcc not found, using dummy regulator [ 0.147877] mc: Linux media interface: v0.10 [ 0.149571] clocksource: Switched to clocksource arch_sys_counter [ 1.168807] VFS: Disk quotas dquot_6.6.0 [ 1.168926] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 1.169096] FS-Cache: Loaded [ 1.169349] CacheFiles: Loaded [ 1.170156] AppArmor: AppArmor Filesystem Enabled [ 1.180572] NET: Registered protocol family 2 [ 1.181470] tcp_listen_portaddr_hash hash table entries: 4096 (order: 4, 65536 bytes, linear) [ 1.181607] TCP established hash table entries: 65536 (order: 7, 524288 bytes, linear) [ 1.182106] TCP bind hash table entries: 65536 (order: 8, 1048576 bytes, linear) [ 1.182903] TCP: Hash tables configured (established 65536 bind 65536) [ 1.183228] UDP hash table entries: 4096 (order: 5, 131072 bytes, linear) [ 1.183367] UDP-Lite hash table entries: 4096 (order: 5, 131072 bytes, linear) [ 1.183794] NET: Registered protocol family 1 [ 1.184727] RPC: Registered named UNIX socket transport module. [ 1.184754] RPC: Registered udp transport module. [ 1.184775] RPC: Registered tcp transport module. [ 1.184795] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 1.184826] PCI: CLS 0 bytes, default 64 [ 1.187324] hw perfevents: enabled with armv8_cortex_a72 PMU driver, 7 counters available [ 1.187627] kvm [1]: IPA Size Limit: 44 bits [ 1.188804] kvm [1]: vgic interrupt IRQ9 [ 1.189083] kvm [1]: Hyp mode initialized successfully [ 1.191170] Initialise system trusted keyrings [ 1.191513] workingset: timestamp_bits=46 max_order=21 bucket_order=0 [ 1.197717] zbud: loaded [ 1.199395] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ 1.199660] FS-Cache: Netfs 'nfs' registered for caching [ 1.200325] NFS: Registering the id_resolver key type [ 1.200375] Key type id_resolver registered [ 1.200398] Key type id_legacy registered [ 1.200524] nfs4filelayout_init: NFSv4 File Layout Driver Registering... [ 1.201750] Key type asymmetric registered [ 1.201778] Asymmetric key parser 'x509' registered [ 1.201835] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 248) [ 1.202112] io scheduler mq-deadline registered [ 1.202138] io scheduler kyber registered [ 1.206052] brcm-pcie fd500000.pcie: host bridge /scb/pcie@7d500000 ranges: [ 1.206100] brcm-pcie fd500000.pcie: No bus range found for /scb/pcie@7d500000, using [bus 00-ff] [ 1.206186] brcm-pcie fd500000.pcie: MEM 0x0600000000..0x063fffffff -> 0x00c0000000 [ 1.206288] brcm-pcie fd500000.pcie: IB MEM 0x0000000000..0x00bfffffff -> 0x0400000000 [ 1.271668] brcm-pcie fd500000.pcie: link up, 5.0 GT/s PCIe x1 (SSC) [ 1.272047] brcm-pcie fd500000.pcie: PCI host bridge to bus 0000:00 [ 1.272080] pci_bus 0000:00: root bus resource [bus 00-ff] [ 1.272110] pci_bus 0000:00: root bus resource [mem 0x600000000-0x63fffffff] (bus address [0xc0000000-0xffffffff]) [ 1.272209] pci 0000:00:00.0: [14e4:2711] type 01 class 0x060400 [ 1.272460] pci 0000:00:00.0: PME# supported from D0 D3hot [ 1.276628] pci 0000:01:00.0: [1106:3483] type 00 class 0x0c0330 [ 1.276765] pci 0000:01:00.0: reg 0x10: [mem 0x00000000-0x00000fff 64bit] [ 1.277198] pci 0000:01:00.0: PME# supported from D0 D3hot [ 1.293038] pci 0000:00:00.0: BAR 8: assigned [mem 0x600000000-0x6000fffff] [ 1.293075] pci 0000:01:00.0: BAR 0: assigned [mem 0x600000000-0x600000fff 64bit] [ 1.293127] pci 0000:00:00.0: PCI bridge to [bus 01] [ 1.293161] pci 0000:00:00.0: bridge window [mem 0x600000000-0x6000fffff] [ 1.293833] bcm2708_fb soc:fb: FB found 1 display(s) [ 1.336530] Console: switching to colour frame buffer device 180x56 [ 1.344423] bcm2708_fb soc:fb: Registered framebuffer for display 0, size 1440x900 [ 1.351465] iproc-rng200 fe104000.rng: hwrng registered [ 1.351871] vc-mem: phys_addr:0x00000000 mem_base=0x3eb00000 mem_size:0x3ff00000(1023 MiB) [ 1.352832] gpiomem-bcm2835 fe200000.gpiomem: Initialised: Registers at 0xfe200000 [ 1.353314] cacheinfo: Unable to detect cache hierarchy for CPU 0 [ 1.364365] brd: module loaded [ 1.376835] loop: module loaded [ 1.377443] zram: Added device: zram0 [ 1.378136] zram: Added device: zram1 [ 1.378686] zram: Added device: zram2 [ 1.380253] Loading iSCSI transport class v2.0-870. [ 1.383396] libphy: Fixed MDIO Bus: probed [ 1.384360] tun: Universal TUN/TAP device driver, 1.6 [ 1.385260] bcmgenet fd580000.ethernet: GENET 5.0 EPHY: 0x0000 [ 1.397632] libphy: bcmgenet MII bus: probed [ 1.445740] unimac-mdio unimac-mdio.-19: Broadcom UniMAC MDIO bus [ 1.446913] usbcore: registered new interface driver r8152 [ 1.447040] usbcore: registered new interface driver lan78xx [ 1.495768] pci 0000:00:00.0: enabling device (0000 -> 0002) [ 1.496010] xhci_hcd 0000:01:00.0: xHCI Host Controller [ 1.496109] xhci_hcd 0000:01:00.0: new USB bus registered, assigned bus number 1 [ 1.496915] xhci_hcd 0000:01:00.0: hcc params 0x002841eb hci version 0x100 quirks 0x0000030000000890 [ 1.498339] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 5.10 [ 1.498444] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1 [ 1.498531] usb usb1: Product: xHCI Host Controller [ 1.498596] usb usb1: Manufacturer: Linux 5.10.17-v8 xhci-hcd [ 1.498668] usb usb1: SerialNumber: 0000:01:00.0 [ 1.499412] hub 1-0:1.0: USB hub found [ 1.499554] hub 1-0:1.0: 1 port detected [ 1.500230] xhci_hcd 0000:01:00.0: xHCI Host Controller [ 1.500310] xhci_hcd 0000:01:00.0: new USB bus registered, assigned bus number 2 [ 1.502766] xhci_hcd 0000:01:00.0: Host supports USB 3.0 SuperSpeed [ 1.505507] usb usb2: New USB device found, idVendor=1d6b, idProduct=0003, bcdDevice= 5.10 [ 1.507905] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1 [ 1.510319] usb usb2: Product: xHCI Host Controller [ 1.512635] usb usb2: Manufacturer: Linux 5.10.17-v8 xhci-hcd [ 1.514957] usb usb2: SerialNumber: 0000:01:00.0 [ 1.517923] hub 2-0:1.0: USB hub found [ 1.520251] hub 2-0:1.0: 4 ports detected [ 1.524108] dwc_otg: version 3.00a 10-AUG-2012 (platform bus) [ 1.526667] dwc_otg: FIQ enabled [ 1.526679] dwc_otg: NAK holdoff enabled [ 1.526690] dwc_otg: FIQ split-transaction FSM enabled [ 1.526704] Module dwc_common_port init [ 1.527253] usbcore: registered new interface driver uas [ 1.529670] usbcore: registered new interface driver usb-storage [ 1.532008] usbcore: registered new interface driver usbserial_generic [ 1.534236] usbserial: USB Serial support registered for generic [ 1.536622] mousedev: PS/2 mouse device common for all mice [ 1.540720] bcm2835-wdt bcm2835-wdt: Broadcom BCM2835 watchdog timer [ 1.546451] sdhci: Secure Digital Host Controller Interface driver [ 1.548693] sdhci: Copyright(c) Pierre Ossman [ 1.551761] mmc-bcm2835 fe300000.mmcnr: could not get clk, deferring probe [ 1.554431] sdhci-pltfm: SDHCI platform and OF driver helper [ 1.557872] ledtrig-cpu: registered to indicate activity on CPUs [ 1.559131] hid: raw HID events driver (C) Jiri Kosina [ 1.560335] usbcore: registered new interface driver usbhid [ 1.561429] usbhid: USB HID core driver [ 1.567865] IPVS: Registered protocols (TCP, UDP, SCTP, AH, ESP) [ 1.569414] IPVS: Connection hash table configured (size=4096, memory=64Kbytes) [ 1.571079] IPVS: ipvs loaded. [ 1.572621] IPVS: [rr] scheduler registered. [ 1.574779] NET: Registered protocol family 10 [ 1.576794] Segment Routing with IPv6 [ 1.577996] NET: Registered protocol family 17 [ 1.579109] Bridge firewalling registered [ 1.580236] Key type dns_resolver registered [ 1.581740] registered taskstats version 1 [ 1.582861] Loading compiled-in X.509 certificates [ 1.584272] Key type ._fscrypt registered [ 1.585386] Key type .fscrypt registered [ 1.586484] Key type fscrypt-provisioning registered [ 1.587572] AppArmor: AppArmor sha1 policy hashing enabled [ 1.593367] uart-pl011 fe201000.serial: there is not valid maps for state default [ 1.594652] uart-pl011 fe201000.serial: cts_event_workaround enabled [ 1.595741] fe201000.serial: ttyAMA0 at MMIO 0xfe201000 (irq = 21, base_baud = 0) is a PL011 rev2 [ 1.601085] bcm2835-power bcm2835-power: Broadcom BCM2835 power domains driver [ 1.602653] mmc-bcm2835 fe300000.mmcnr: mmc_debug:0 mmc_debug2:0 [ 1.603724] mmc-bcm2835 fe300000.mmcnr: DMA channel allocated [ 1.637811] printk: console [netcon0] enabled [ 1.639781] netconsole: network logging started [ 1.641814] of_cfs_init [ 1.643709] of_cfs_init: OK [ 1.657365] mmc1: queuing unknown CIS tuple 0x80 (2 bytes) [ 1.660413] mmc1: queuing unknown CIS tuple 0x80 (3 bytes) [ 1.663319] mmc1: queuing unknown CIS tuple 0x80 (3 bytes) [ 1.667591] mmc1: queuing unknown CIS tuple 0x80 (7 bytes) [ 1.670573] mmc1: queuing unknown CIS tuple 0x80 (3 bytes) [ 1.671991] mmc0: SDHCI controller on fe340000.emmc2 [fe340000.emmc2] using ADMA [ 1.673851] Waiting for root device PARTUUID=8d3d53e3-6d49-4c38-8349-aff6859e82fd... [ 1.698341] random: fast init done [ 1.737225] mmc1: new high speed SDIO card at address 0001 [ 1.833598] usb 1-1: new high-speed USB device number 2 using xhci_hcd [ 1.984215] usb 1-1: New USB device found, idVendor=2109, idProduct=3431, bcdDevice= 4.21 [ 1.986396] usb 1-1: New USB device strings: Mfr=0, Product=1, SerialNumber=0 [ 1.988548] usb 1-1: Product: USB2.0 Hub [ 1.992641] hub 1-1:1.0: USB hub found [ 1.994945] hub 1-1:1.0: 4 ports detected [ 2.289604] usb 1-1.1: new full-speed USB device number 3 using xhci_hcd [ 2.398763] usb 1-1.1: New USB device found, idVendor=1cf1, idProduct=0030, bcdDevice= 1.00 [ 2.400954] usb 1-1.1: New USB device strings: Mfr=1, Product=2, SerialNumber=3 [ 2.403196] usb 1-1.1: Product: ConBee II [ 2.405397] usb 1-1.1: Manufacturer: dresden elektronik ingenieurtechnik GmbH [ 2.407644] usb 1-1.1: SerialNumber: DE2234802 [ 2.493606] usb 1-1.3: new high-speed USB device number 4 using xhci_hcd [ 2.595394] usb 1-1.3: New USB device found, idVendor=13fd, idProduct=5900, bcdDevice= 9.10 [ 2.597682] usb 1-1.3: New USB device strings: Mfr=1, Product=2, SerialNumber=3 [ 2.599915] usb 1-1.3: Product: External [ 2.602138] usb 1-1.3: Manufacturer: Generic [ 2.604319] usb 1-1.3: SerialNumber: 2117E59BC2C1 [ 2.612009] scsi host0: uas [ 2.653296] scsi 0:0:0:0: Direct-Access Generic External 0910 PQ: 0 ANSI: 6 [ 2.658276] sd 0:0:0:0: [sda] 468862127 512-byte logical blocks: (240 GB/224 GiB) [ 2.660278] sd 0:0:0:0: [sda] Write Protect is off [ 2.661943] sd 0:0:0:0: [sda] Mode Sense: 47 00 00 08 [ 2.662518] sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA [ 2.664633] sd 0:0:0:0: [sda] Optimal transfer size 33553920 bytes [ 2.672433] sda: sda1 sda2 sda3 sda4 sda5 sda6 sda7 sda8 [ 2.677886] sd 0:0:0:0: [sda] Attached SCSI disk [ 2.685687] VFS: Mounted root (squashfs filesystem) readonly on device 8:3. [ 2.688579] devtmpfs: mounted [ 2.695626] Freeing unused kernel memory: 3840K [ 2.705592] Run /sbin/init as init process [ 2.706760] with arguments: [ 2.706765] /sbin/init [ 2.706770] with environment: [ 2.706775] HOME=/ [ 2.706780] TERM=linux [ 3.046545] systemd[1]: System time before build time, advancing clock. [ 3.104291] 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) [ 3.108739] systemd[1]: Detected architecture arm64. [ 3.155570] systemd[1]: Set hostname to . [ 3.157778] systemd[1]: Installed transient /etc/machine-id file. [ 3.571526] systemd[1]: Queued start job for default target Multi-User System. [ 3.573739] random: systemd: uninitialized urandom read (16 bytes read) [ 3.602341] systemd[1]: Created slice system-ha\x2dcli.slice. [ 3.605116] random: systemd: uninitialized urandom read (16 bytes read) [ 3.608094] systemd[1]: Created slice system-modprobe.slice. [ 3.610800] random: systemd: uninitialized urandom read (16 bytes read) [ 3.613661] systemd[1]: Created slice system-systemd\x2dfsck.slice. [ 3.618479] systemd[1]: Created slice system-systemd\x2dgrowfs.slice. [ 3.622867] systemd[1]: Created slice User and Session Slice. [ 3.625992] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. [ 3.629012] systemd[1]: Started Forward Password Requests to Wall Directory Watch. [ 3.632066] systemd[1]: Reached target Local Encrypted Volumes. [ 3.635092] systemd[1]: Reached target HassOS zram targets. [ 3.638039] systemd[1]: Reached target Paths. [ 3.640920] systemd[1]: Reached target Remote Encrypted Volumes. [ 3.643883] systemd[1]: Reached target Remote File Systems. [ 3.646767] systemd[1]: Reached target Slices. [ 3.651045] systemd[1]: Listening on D-Bus System Message Bus Socket. [ 3.657264] systemd[1]: Listening on Process Core Dump Socket. [ 3.661984] systemd[1]: Listening on Journal Audit Socket. [ 3.665785] systemd[1]: Listening on Journal Socket (/dev/log). [ 3.669286] systemd[1]: Listening on Journal Socket. [ 3.673853] systemd[1]: Listening on udev Control Socket. [ 3.677262] systemd[1]: Listening on udev Kernel Socket. [ 3.680558] systemd[1]: Condition check resulted in Huge Pages File System being skipped. [ 3.685097] systemd[1]: Mounting POSIX Message Queue File System... [ 3.692368] systemd[1]: Mounting Kernel Debug File System... [ 3.699238] systemd[1]: Mounting Kernel Trace File System... [ 3.706181] systemd[1]: Started D-Bus System Message Bus. [ 3.717762] systemd[1]: Starting Home Assistant OS Agent... [ 3.734207] systemd[1]: Starting Create list of static device nodes for the current kernel... [ 3.742917] systemd[1]: Starting Load Kernel Module configfs... [ 3.749997] systemd[1]: Starting Load Kernel Module drm... [ 3.757024] systemd[1]: Starting Load Kernel Module fuse... [ 3.766099] systemd[1]: Starting Journal Service... [ 3.773345] systemd[1]: Starting Remount Root and Kernel File Systems... [ 3.780812] systemd[1]: Starting Wait Until Kernel Time Synchronized... [ 3.788041] systemd[1]: Starting Coldplug All udev Devices... [ 3.796672] systemd[1]: Starting Disk Manager... [ 3.805078] systemd[1]: Starting HassOS ZRAM swap... [ 3.811754] systemd[1]: Starting HassOS ZRAM tmp... [ 3.819569] systemd[1]: Starting HassOS ZRAM var... [ 3.828685] systemd[1]: Mounted POSIX Message Queue File System. [ 3.832233] systemd[1]: Mounted Kernel Debug File System. [ 3.835935] systemd[1]: Mounted Kernel Trace File System. [ 3.841849] systemd[1]: Finished Create list of static device nodes for the current kernel. [ 3.845806] systemd[1]: modprobe@configfs.service: Succeeded. [ 3.848985] systemd[1]: Finished Load Kernel Module configfs. [ 3.854779] systemd[1]: Finished Remount Root and Kernel File Systems. [ 3.862556] systemd[1]: Mounting Kernel Configuration File System... [ 3.869918] systemd[1]: Starting Create Static Device Nodes in /dev... [ 3.876030] systemd[1]: Mounted Kernel Configuration File System. [ 3.919345] systemd[1]: Started Journal Service. [ 4.125675] fuse: init (API version 7.32) [ 4.135583] zram2: detected capacity change from 0 to 16777216 [ 4.136333] zram1: detected capacity change from 0 to 33554432 [ 4.150179] zram0: detected capacity change from 0 to 2047578112 [ 4.158416] audit: type=1334 audit(1612279788.107:2): prog-id=5 op=LOAD [ 4.160337] audit: type=1334 audit(1612279788.107:3): prog-id=6 op=LOAD [ 4.574404] vc_sm_cma: module is from the staging directory, the quality is unknown, you have been warned. [ 4.577084] bcm2835_vc_sm_cma_probe: Videocore shared memory driver [ 4.592408] [vc_sm_connected_init]: start [ 4.605635] rpivid-mem feb00000.hevc-decoder: rpivid-hevcmem initialised: Registers at 0xfeb00000 length 0x00010000 [ 4.608299] rpivid-mem feb10000.rpivid-local-intc: rpivid-intcmem initialised: Registers at 0xfeb10000 length 0x00001000 [ 4.615988] rpivid-mem feb20000.h264-decoder: rpivid-h264mem initialised: Registers at 0xfeb20000 length 0x00010000 [ 4.620502] [vc_sm_connected_init]: installed successfully [ 4.625955] rpivid-mem feb30000.vp9-decoder: rpivid-vp9mem initialised: Registers at 0xfeb30000 length 0x00010000 [ 4.644632] videodev: Linux video capture interface: v2.00 [ 4.661801] snd_bcm2835: module is from the staging directory, the quality is unknown, you have been warned. [ 4.671442] bcm2835_audio bcm2835_audio: card created with 4 channels [ 4.683438] bcm2835_audio bcm2835_audio: card created with 4 channels [ 4.697835] bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned. [ 4.697839] bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned. [ 4.702597] bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned. [ 4.727474] bcm2835_isp: module is from the staging directory, the quality is unknown, you have been warned. [ 4.729467] bcm2835_v4l2: module is from the staging directory, the quality is unknown, you have been warned. [ 4.732674] bcm2835_codec: module is from the staging directory, the quality is unknown, you have been warned. [ 4.751927] bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video13 [ 4.754903] bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video14 [ 4.760555] bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video15 [ 4.769359] bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video16 [ 4.777660] bcm2835-isp bcm2835-isp: Register output node 0 with media controller [ 4.777674] bcm2835-codec bcm2835-codec: Device registered as /dev/video10 [ 4.779313] bcm2835-isp bcm2835-isp: Register capture node 1 with media controller [ 4.779323] bcm2835-isp bcm2835-isp: Register capture node 2 with media controller [ 4.779330] bcm2835-isp bcm2835-isp: Register capture node 3 with media controller [ 4.780897] bcm2835-isp bcm2835-isp: Loaded V4L2 bcm2835-isp [ 4.781509] bcm2835-codec bcm2835-codec: Loaded V4L2 decode [ 4.797036] bcm2835-codec bcm2835-codec: Device registered as /dev/video11 [ 4.798723] bcm2835-codec bcm2835-codec: Loaded V4L2 encode [ 4.808197] bcm2835-codec bcm2835-codec: Device registered as /dev/video12 [ 4.809966] bcm2835-codec bcm2835-codec: Loaded V4L2 isp [ 4.827136] cdc_acm 1-1.1:1.0: ttyACM0: USB ACM device [ 4.827707] cfg80211: Loading compiled-in X.509 certificates for regulatory database [ 4.833340] usbcore: registered new interface driver cdc_acm [ 4.837543] cdc_acm: USB Abstract Control Model driver for USB modems and ISDN adapters [ 4.878076] cfg80211: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' [ 4.909632] brcmfmac: F1 signature read @0x18000000=0x15264345 [ 4.915244] brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6 [ 4.919569] usbcore: registered new interface driver brcmfmac [ 4.940812] brcmfmac mmc1:0001:1: Direct firmware load for brcm/brcmfmac43455-sdio.raspberrypi,4-model-b.txt failed with error -2 [ 5.150155] brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6 [ 5.158342] brcmfmac: brcmf_c_preinit_dcmds: Firmware: BCM4345/6 wl0: Jan 4 2021 19:56:29 version 7.45.229 (617f1f5 CY) FWID 01-2dbd9d2e [ 5.249304] FAT-fs (sda1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck. [ 5.281674] Adding 1999584k swap on /dev/zram0. Priority:-2 extents:1 across:1999584k SSFS [ 5.398561] EXT4-fs (sda7): mounted filesystem with ordered data mode. Opts: (null) [ 5.400025] ext4 filesystem being mounted at /mnt/overlay supports timestamps until 2038 (0x7fffffff) [ 5.468425] random: crng init done [ 5.469911] random: 7 urandom warning(s) missed due to ratelimiting [ 5.564849] EXT4-fs (zram2): mounted filesystem without journal. Opts: nobarrier [ 5.566487] ext4 filesystem being mounted at /tmp supports timestamps until 2038 (0x7fffffff) [ 5.570784] EXT4-fs (zram1): mounted filesystem without journal. Opts: nobarrier [ 5.572185] ext4 filesystem being mounted at /var supports timestamps until 2038 (0x7fffffff) [ 5.933699] EXT4-fs (sda8): mounted filesystem with ordered data mode. Opts: (null) [ 5.995963] EXT4-fs (sda8): resizing filesystem from 58429329 to 58429329 blocks [ 6.043783] systemd-journald[114]: Received client request to flush runtime journal. [ 6.071622] systemd-journald[114]: File /var/log/journal/890efcc406614ddd8026823e6fb2355f/system.journal corrupted or uncleanly shut down, renaming and replacing. [ 7.216092] audit: type=1334 audit(1612279791.163:4): prog-id=7 op=LOAD [ 7.216187] audit: type=1334 audit(1612279791.163:5): prog-id=8 op=LOAD [ 7.446098] audit: type=1400 audit(1612279791.387:6): apparmor="STATUS" operation="profile_load" profile="unconfined" name="hassio-supervisor" pid=372 comm="apparmor_parser" [ 7.446127] audit: type=1400 audit(1612279791.387:6): apparmor="STATUS" operation="profile_load" profile="unconfined" name="hassio-supervisor///usr/bin/gdbus" pid=372 comm="apparmor_parser" [ 7.446142] audit: type=1400 audit(1612279791.387:6): apparmor="STATUS" operation="profile_load" profile="unconfined" name="hassio-supervisor///usr/bin/git" pid=372 comm="apparmor_parser" [ 7.748945] Bluetooth: Core ver 2.22 [ 7.749049] NET: Registered protocol family 31 [ 7.749055] Bluetooth: HCI device and connection manager initialized [ 7.749073] Bluetooth: HCI socket layer initialized [ 7.749082] Bluetooth: L2CAP socket layer initialized [ 7.749097] Bluetooth: SCO socket layer initialized [ 7.786059] systemd-journald[114]: File /var/log/journal/890efcc406614ddd8026823e6fb2355f/user-1003.journal corrupted or uncleanly shut down, renaming and replacing. [ 7.906734] uart-pl011 fe201000.serial: no DMA platform data [ 7.952291] audit: type=1334 audit(1612279791.899:7): prog-id=9 op=LOAD [ 7.952363] audit: type=1334 audit(1612279791.899:8): prog-id=10 op=LOAD [ 7.989879] systemd-journald[114]: File /var/log/journal/890efcc406614ddd8026823e6fb2355f/user-1001.journal corrupted or uncleanly shut down, renaming and replacing. [ 8.221991] bcmgenet fd580000.ethernet: configuring instance for external RGMII (RX delay) [ 8.222245] bcmgenet fd580000.ethernet eth0: Link is Down [ 8.270247] brcmfmac: brcmf_cfg80211_set_power_mgmt: power save enabled [ 8.574063] Bluetooth: HCI UART driver ver 2.3 [ 8.574077] Bluetooth: HCI UART protocol H4 registered [ 8.574143] Bluetooth: HCI UART protocol Three-wire (H5) registered [ 8.574283] Bluetooth: HCI UART protocol Broadcom registered [ 8.708685] Bluetooth: BNEP (Ethernet Emulation) ver 1.3 [ 8.708701] Bluetooth: BNEP filters: protocol multicast [ 8.708716] Bluetooth: BNEP socket layer initialized [ 8.725370] NET: Registered protocol family 38 [ 8.752543] cryptd: max_cpu_qlen set to 1000 [ 12.317703] bcmgenet fd580000.ethernet eth0: Link is Up - 1Gbps/Full - flow control off [ 12.317735] IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready [ 16.827083] audit: type=1400 audit(1612279800.771:9): apparmor="STATUS" operation="profile_load" profile="unconfined" name="docker-default" pid=483 comm="apparmor_parser" [ 18.457672] audit: type=1325 audit(1612279802.391:10): table=nat family=2 entries=5 op=xt_replace pid=505 subj==unconfined comm="iptables" [ 18.493675] audit: type=1325 audit(1612279802.411:11): table=filter family=2 entries=4 op=xt_replace pid=507 subj==unconfined comm="iptables" [ 18.529674] audit: type=1325 audit(1612279802.447:12): table=filter family=2 entries=6 op=xt_replace pid=509 subj==unconfined comm="iptables" [ 18.557661] audit: type=1325 audit(1612279802.483:13): table=filter family=2 entries=8 op=xt_replace pid=511 subj==unconfined comm="iptables" [ 18.581712] audit: type=1325 audit(1612279802.511:14): table=filter family=2 entries=10 op=xt_replace pid=513 subj==unconfined comm="iptables" [ 18.617650] audit: type=1325 audit(1612279802.535:15): table=filter family=2 entries=11 op=xt_replace pid=515 subj==unconfined comm="iptables" [ 18.649644] audit: type=1325 audit(1612279802.567:16): table=filter family=2 entries=12 op=xt_replace pid=516 subj==unconfined comm="iptables" [ 18.668120] Initializing XFRM netlink socket [ 18.733737] audit: type=1325 audit(1612279802.659:17): table=nat family=2 entries=7 op=xt_replace pid=524 subj==unconfined comm="iptables" [ 18.765775] audit: type=1325 audit(1612279802.687:18): table=nat family=2 entries=8 op=xt_replace pid=526 subj==unconfined comm="iptables" [ 20.134651] hassio: port 1(veth8754d69) entered blocking state [ 20.134665] hassio: port 1(veth8754d69) entered disabled state [ 20.134856] device veth8754d69 entered promiscuous mode [ 20.135180] hassio: port 1(veth8754d69) entered blocking state [ 20.135189] hassio: port 1(veth8754d69) entered forwarding state [ 20.135735] hassio: port 1(veth8754d69) entered disabled state [ 20.864259] cgroup: cgroup: disabling cgroup2 socket matching due to net_prio or net_cls activation [ 21.635983] eth0: renamed from veth266c6d4 [ 21.654775] IPv6: ADDRCONF(NETDEV_CHANGE): veth8754d69: link becomes ready [ 21.654882] hassio: port 1(veth8754d69) entered blocking state [ 21.654890] hassio: port 1(veth8754d69) entered forwarding state [ 21.655012] IPv6: ADDRCONF(NETDEV_CHANGE): hassio: link becomes ready [ 31.709634] vcc-sd: disabling [ 38.625167] docker0: port 1(veth07f0aaa) entered blocking state [ 38.625186] docker0: port 1(veth07f0aaa) entered disabled state [ 38.625623] device veth07f0aaa entered promiscuous mode [ 38.625709] kauditd_printk_skb: 54 callbacks suppressed [ 38.625715] audit: type=1700 audit(1631560747.083:73): dev=veth07f0aaa prom=256 old_prom=0 auid=4294967295 uid=0 gid=0 ses=4294967295 [ 38.678607] hassio: port 2(veth935d577) entered blocking state [ 38.678621] hassio: port 2(veth935d577) entered disabled state [ 38.678797] device veth935d577 entered promiscuous mode [ 38.678873] audit: type=1700 audit(1631560747.139:74): dev=veth935d577 prom=256 old_prom=0 auid=4294967295 uid=0 gid=0 ses=4294967295 [ 38.679398] hassio: port 2(veth935d577) entered blocking state [ 38.679408] hassio: port 2(veth935d577) entered forwarding state [ 39.174732] eth0: renamed from vethe529e5f [ 39.211837] hassio: port 2(veth935d577) entered disabled state [ 39.212095] IPv6: ADDRCONF(NETDEV_CHANGE): veth07f0aaa: link becomes ready [ 39.212283] docker0: port 1(veth07f0aaa) entered blocking state [ 39.212298] docker0: port 1(veth07f0aaa) entered forwarding state [ 39.212580] IPv6: ADDRCONF(NETDEV_CHANGE): docker0: link becomes ready [ 39.273771] audit: type=1334 audit(1631560747.735:75): prog-id=10 op=UNLOAD [ 39.273799] audit: type=1334 audit(1631560747.735:76): prog-id=9 op=UNLOAD [ 39.354053] audit: type=1325 audit(1631560747.787:77): table=nat family=2 entries=0 op=xt_register pid=1066 subj==unconfined comm="iptables" [ 39.393680] audit: type=1325 audit(1631560747.831:78): table=filter family=2 entries=0 op=xt_register pid=1073 subj==unconfined comm="iptables" [ 39.429680] audit: type=1325 audit(1631560747.867:79): table=nat family=2 entries=5 op=xt_replace pid=1079 subj==unconfined comm="iptables" [ 39.465744] audit: type=1325 audit(1631560747.895:80): table=nat family=2 entries=7 op=xt_replace pid=1080 subj==unconfined comm="iptables" [ 39.505787] audit: type=1325 audit(1631560747.935:81): table=nat family=2 entries=8 op=xt_replace pid=1083 subj==unconfined comm="iptables" [ 39.545723] audit: type=1325 audit(1631560747.975:82): table=nat family=2 entries=10 op=xt_replace pid=1084 subj==unconfined comm="iptables" [ 39.683474] eth1: renamed from vetha13fca4 [ 39.711588] IPv6: ADDRCONF(NETDEV_CHANGE): veth935d577: link becomes ready [ 39.711786] hassio: port 2(veth935d577) entered blocking state [ 39.711803] hassio: port 2(veth935d577) entered forwarding state [ 46.618046] kauditd_printk_skb: 4 callbacks suppressed [ 46.618052] audit: type=1334 audit(1631560755.079:87): prog-id=11 op=LOAD [ 46.618334] audit: type=1334 audit(1631560755.079:88): prog-id=12 op=LOAD [ 46.824068] audit: type=1334 audit(1631560755.283:89): prog-id=13 op=LOAD [ 46.824376] audit: type=1334 audit(1631560755.283:90): prog-id=14 op=LOAD [ 49.212603] audit: type=1400 audit(1631560757.663:91): apparmor="STATUS" operation="profile_replace" info="same as current profile, skipping" profile="unconfined" name="hassio-supervisor" pid=1764 comm="apparmor_parser" [ 49.212627] audit: type=1400 audit(1631560757.663:91): apparmor="STATUS" operation="profile_replace" info="same as current profile, skipping" profile="unconfined" name="hassio-supervisor///usr/bin/gdbus" pid=1764 comm="apparmor_parser" [ 49.212641] audit: type=1400 audit(1631560757.663:91): apparmor="STATUS" operation="profile_replace" info="same as current profile, skipping" profile="unconfined" name="hassio-supervisor///usr/bin/git" pid=1764 comm="apparmor_parser" [ 50.012974] hassio: port 3(veth49f870e) entered blocking state [ 50.012992] hassio: port 3(veth49f870e) entered disabled state [ 50.013363] device veth49f870e entered promiscuous mode [ 50.013442] audit: type=1700 audit(1631560758.471:92): dev=veth49f870e prom=256 old_prom=0 auid=4294967295 uid=0 gid=0 ses=4294967295 [ 50.549750] audit: type=1325 audit(1631560758.995:93): table=nat family=2 entries=0 op=xt_register pid=1834 subj==unconfined comm="iptables" [ 50.589730] audit: type=1325 audit(1631560759.023:94): table=filter family=2 entries=0 op=xt_register pid=1835 subj==unconfined comm="iptables" [ 50.916317] eth0: renamed from veth8e7037e [ 50.939700] IPv6: ADDRCONF(NETDEV_CHANGE): veth49f870e: link becomes ready [ 50.939936] hassio: port 3(veth49f870e) entered blocking state [ 50.939951] hassio: port 3(veth49f870e) entered forwarding state [ 51.404213] hassio: port 4(vethed1a75b) entered blocking state [ 51.404235] hassio: port 4(vethed1a75b) entered disabled state [ 51.404510] device vethed1a75b entered promiscuous mode [ 51.977887] kauditd_printk_skb: 9 callbacks suppressed [ 51.977897] audit: type=1325 audit(1631560760.415:104): table=nat family=2 entries=0 op=xt_register pid=2149 subj==unconfined comm="iptables" [ 52.025791] audit: type=1325 audit(1631560760.455:105): table=filter family=2 entries=0 op=xt_register pid=2150 subj==unconfined comm="iptables" [ 52.073707] audit: type=1325 audit(1631560760.511:106): table=nat family=2 entries=5 op=xt_replace pid=2153 subj==unconfined comm="iptables" [ 52.101720] audit: type=1325 audit(1631560760.539:107): table=nat family=2 entries=7 op=xt_replace pid=2155 subj==unconfined comm="iptables" [ 52.149783] audit: type=1325 audit(1631560760.575:108): table=nat family=2 entries=8 op=xt_replace pid=2157 subj==unconfined comm="iptables" [ 52.181751] audit: type=1325 audit(1631560760.615:109): table=nat family=2 entries=10 op=xt_replace pid=2158 subj==unconfined comm="iptables" [ 52.217959] audit: type=1325 audit(1631560760.651:110): table=nat family=2 entries=11 op=xt_replace pid=2159 subj==unconfined comm="iptables" [ 52.273782] audit: type=1325 audit(1631560760.687:111): table=nat family=2 entries=12 op=xt_replace pid=2160 subj==unconfined comm="iptables" [ 52.297859] audit: type=1325 audit(1631560760.739:112): table=nat family=2 entries=13 op=xt_replace pid=2169 subj==unconfined comm="iptables" [ 52.329748] audit: type=1325 audit(1631560760.763:113): table=nat family=2 entries=14 op=xt_replace pid=2170 subj==unconfined comm="iptables" [ 52.379767] eth0: renamed from vethe8a4697 [ 52.407085] IPv6: ADDRCONF(NETDEV_CHANGE): vethed1a75b: link becomes ready [ 52.407261] hassio: port 4(vethed1a75b) entered blocking state [ 52.407272] hassio: port 4(vethed1a75b) entered forwarding state [ 52.825425] hassio: port 5(veth295a8a3) entered blocking state [ 52.825440] hassio: port 5(veth295a8a3) entered disabled state [ 52.825707] device veth295a8a3 entered promiscuous mode [ 52.826118] hassio: port 5(veth295a8a3) entered blocking state [ 52.826131] hassio: port 5(veth295a8a3) entered forwarding state [ 53.437793] hassio: port 5(veth295a8a3) entered disabled state [ 53.791372] eth0: renamed from vethe40670b [ 53.824440] IPv6: ADDRCONF(NETDEV_CHANGE): veth295a8a3: link becomes ready [ 53.824666] hassio: port 5(veth295a8a3) entered blocking state [ 53.824681] hassio: port 5(veth295a8a3) entered forwarding state [ 56.875060] Bluetooth: RFCOMM TTY layer initialized [ 56.875085] Bluetooth: RFCOMM socket layer initialized [ 56.875108] Bluetooth: RFCOMM ver 1.11 [ 65.487352] hassio: port 6(vetha9bc972) entered blocking state [ 65.487366] hassio: port 6(vetha9bc972) entered disabled state [ 65.487590] device vetha9bc972 entered promiscuous mode [ 65.487685] kauditd_printk_skb: 11 callbacks suppressed [ 65.487690] audit: type=1700 audit(1631560773.947:125): dev=vetha9bc972 prom=256 old_prom=0 auid=4294967295 uid=0 gid=0 ses=4294967295 [ 65.556064] audit: type=1325 audit(1631560773.995:126): table=nat family=2 entries=15 op=xt_replace pid=3411 subj==unconfined comm="iptables" [ 65.597991] audit: type=1325 audit(1631560774.027:127): table=filter family=2 entries=30 op=xt_replace pid=3413 subj==unconfined comm="iptables" [ 65.633747] audit: type=1325 audit(1631560774.075:128): table=nat family=2 entries=16 op=xt_replace pid=3417 subj==unconfined comm="iptables" [ 65.685869] audit: type=1325 audit(1631560774.123:129): table=nat family=2 entries=17 op=xt_replace pid=3436 subj==unconfined comm="iptables" [ 65.721847] audit: type=1325 audit(1631560774.163:130): table=filter family=2 entries=31 op=xt_replace pid=3438 subj==unconfined comm="iptables" [ 65.765784] audit: type=1325 audit(1631560774.199:131): table=nat family=2 entries=18 op=xt_replace pid=3440 subj==unconfined comm="iptables" [ 65.813978] audit: type=1325 audit(1631560774.259:132): table=nat family=2 entries=19 op=xt_replace pid=3456 subj==unconfined comm="iptables" [ 65.861819] audit: type=1325 audit(1631560774.287:133): table=filter family=2 entries=32 op=xt_replace pid=3458 subj==unconfined comm="iptables" [ 65.905822] audit: type=1325 audit(1631560774.339:134): table=nat family=2 entries=20 op=xt_replace pid=3460 subj==unconfined comm="iptables" [ 66.907007] eth0: renamed from veth56eb999 [ 66.940063] IPv6: ADDRCONF(NETDEV_CHANGE): vetha9bc972: link becomes ready [ 66.940298] hassio: port 6(vetha9bc972) entered blocking state [ 66.940315] hassio: port 6(vetha9bc972) entered forwarding state [ 67.432896] hassio: port 7(veth633b112) entered blocking state [ 67.432910] hassio: port 7(veth633b112) entered disabled state [ 67.433119] device veth633b112 entered promiscuous mode [ 67.435286] hassio: port 7(veth633b112) entered blocking state [ 67.435300] hassio: port 7(veth633b112) entered forwarding state [ 67.965931] hassio: port 7(veth633b112) entered disabled state [ 68.317457] eth0: renamed from vethfec94fc [ 68.346629] IPv6: ADDRCONF(NETDEV_CHANGE): veth633b112: link becomes ready [ 68.346754] hassio: port 7(veth633b112) entered blocking state [ 68.346761] hassio: port 7(veth633b112) entered forwarding state [ 76.595639] hassio: port 8(veth622c5f4) entered blocking state [ 76.595651] hassio: port 8(veth622c5f4) entered disabled state [ 76.595862] device veth622c5f4 entered promiscuous mode [ 76.595925] kauditd_printk_skb: 24 callbacks suppressed [ 76.595928] audit: type=1700 audit(1631560785.059:159): dev=veth622c5f4 prom=256 old_prom=0 auid=4294967295 uid=0 gid=0 ses=4294967295 [ 77.233868] audit: type=1325 audit(1631560785.675:160): table=nat family=2 entries=0 op=xt_register pid=4786 subj==unconfined comm="iptables" [ 77.285860] audit: type=1325 audit(1631560785.715:161): table=filter family=2 entries=0 op=xt_register pid=4789 subj==unconfined comm="iptables" [ 77.337884] audit: type=1325 audit(1631560785.763:162): table=nat family=2 entries=5 op=xt_replace pid=4794 subj==unconfined comm="iptables" [ 77.373857] audit: type=1334 audit(1631560785.839:163): prog-id=12 op=UNLOAD [ 77.373887] audit: type=1334 audit(1631560785.839:164): prog-id=11 op=UNLOAD [ 77.373964] audit: type=1325 audit(1631560785.807:165): table=nat family=2 entries=7 op=xt_replace pid=4795 subj==unconfined comm="iptables" [ 77.405910] audit: type=1325 audit(1631560785.847:166): table=nat family=2 entries=8 op=xt_replace pid=4799 subj==unconfined comm="iptables" [ 77.422908] audit: type=1334 audit(1631560785.887:167): prog-id=14 op=UNLOAD [ 77.422939] audit: type=1334 audit(1631560785.887:168): prog-id=13 op=UNLOAD [ 77.631511] eth0: renamed from veth920d42b [ 77.651518] IPv6: ADDRCONF(NETDEV_CHANGE): veth622c5f4: link becomes ready [ 77.651684] hassio: port 8(veth622c5f4) entered blocking state [ 77.651695] hassio: port 8(veth622c5f4) entered forwarding state [ 78.258492] hassio: port 9(vethbd34fa6) entered blocking state [ 78.258509] hassio: port 9(vethbd34fa6) entered disabled state [ 78.258764] device vethbd34fa6 entered promiscuous mode [ 78.259844] hassio: port 9(vethbd34fa6) entered blocking state [ 78.259864] hassio: port 9(vethbd34fa6) entered forwarding state [ 78.654524] hassio: port 9(vethbd34fa6) entered disabled state [ 79.279432] eth0: renamed from veth63f001b [ 79.312912] IPv6: ADDRCONF(NETDEV_CHANGE): vethbd34fa6: link becomes ready [ 79.313060] hassio: port 9(vethbd34fa6) entered blocking state [ 79.313070] hassio: port 9(vethbd34fa6) entered forwarding state [ 79.835980] hassio: port 10(veth7cadfd5) entered blocking state [ 79.835994] hassio: port 10(veth7cadfd5) entered disabled state [ 79.836202] device veth7cadfd5 entered promiscuous mode [ 81.015266] eth0: renamed from veth3a2b25f [ 81.043186] IPv6: ADDRCONF(NETDEV_CHANGE): veth7cadfd5: link becomes ready [ 81.043395] hassio: port 10(veth7cadfd5) entered blocking state [ 81.043405] hassio: port 10(veth7cadfd5) entered forwarding state [ 180.652147] hassio: port 11(veth5fd635f) entered blocking state [ 180.652160] hassio: port 11(veth5fd635f) entered disabled state [ 180.652413] device veth5fd635f entered promiscuous mode [ 180.652470] kauditd_printk_skb: 30 callbacks suppressed [ 180.652474] audit: type=1700 audit(1631560889.114:199): dev=veth5fd635f prom=256 old_prom=0 auid=4294967295 uid=0 gid=0 ses=4294967295 [ 181.631363] audit: type=1325 audit(1631560890.074:200): table=nat family=2 entries=0 op=xt_register pid=7062 subj==unconfined comm="iptables" [ 181.659485] audit: type=1325 audit(1631560890.106:201): table=filter family=2 entries=0 op=xt_register pid=7064 subj==unconfined comm="iptables" [ 181.695650] audit: type=1325 audit(1631560890.138:202): table=nat family=2 entries=5 op=xt_replace pid=7067 subj==unconfined comm="iptables" [ 181.723570] audit: type=1325 audit(1631560890.166:203): table=nat family=2 entries=7 op=xt_replace pid=7068 subj==unconfined comm="iptables" [ 181.751511] audit: type=1325 audit(1631560890.194:204): table=nat family=2 entries=8 op=xt_replace pid=7070 subj==unconfined comm="iptables" [ 181.791450] audit: type=1325 audit(1631560890.218:205): table=nat family=2 entries=10 op=xt_replace pid=7071 subj==unconfined comm="iptables" [ 181.831671] audit: type=1325 audit(1631560890.258:206): table=nat family=2 entries=11 op=xt_replace pid=7072 subj==unconfined comm="iptables" [ 181.859577] audit: type=1325 audit(1631560890.298:207): table=nat family=2 entries=12 op=xt_replace pid=7073 subj==unconfined comm="iptables" [ 181.891622] audit: type=1325 audit(1631560890.326:208): table=nat family=2 entries=13 op=xt_replace pid=7074 subj==unconfined comm="iptables" [ 181.945354] eth0: renamed from vethf85743c [ 181.965822] IPv6: ADDRCONF(NETDEV_CHANGE): veth5fd635f: link becomes ready [ 181.966253] hassio: port 11(veth5fd635f) entered blocking state [ 181.966263] hassio: port 11(veth5fd635f) entered forwarding state [ 182.633490] hassio: port 12(veth81625ff) entered blocking state [ 182.633505] hassio: port 12(veth81625ff) entered disabled state [ 182.633855] device veth81625ff entered promiscuous mode [ 182.635086] hassio: port 12(veth81625ff) entered blocking state [ 182.635098] hassio: port 12(veth81625ff) entered forwarding state [ 182.976004] hassio: port 12(veth81625ff) entered disabled state [ 184.010117] eth0: renamed from vetha9ed5e5 [ 184.038313] IPv6: ADDRCONF(NETDEV_CHANGE): veth81625ff: link becomes ready [ 184.038463] hassio: port 12(veth81625ff) entered blocking state [ 184.038471] hassio: port 12(veth81625ff) entered forwarding state [ 184.785771] hassio: port 13(vethc218dc0) entered blocking state [ 184.785789] hassio: port 13(vethc218dc0) entered disabled state [ 184.786189] device vethc218dc0 entered promiscuous mode [ 184.790719] hassio: port 13(vethc218dc0) entered blocking state [ 184.790734] hassio: port 13(vethc218dc0) entered forwarding state [ 185.055611] hassio: port 13(vethc218dc0) entered disabled state [ 186.363509] kauditd_printk_skb: 16 callbacks suppressed [ 186.363516] audit: type=1325 audit(1631560894.806:225): table=nat family=2 entries=0 op=xt_register pid=7713 subj==unconfined comm="iptables" [ 186.403646] audit: type=1325 audit(1631560894.842:226): table=filter family=2 entries=0 op=xt_register pid=7714 subj==unconfined comm="iptables" [ 186.444385] audit: type=1325 audit(1631560894.886:227): table=nat family=2 entries=5 op=xt_replace pid=7722 subj==unconfined comm="iptables" [ 186.463538] audit: type=1325 audit(1631560894.910:228): table=nat family=2 entries=7 op=xt_replace pid=7725 subj==unconfined comm="iptables" [ 186.496286] audit: type=1325 audit(1631560894.934:229): table=nat family=2 entries=8 op=xt_replace pid=7729 subj==unconfined comm="iptables" [ 186.511695] audit: type=1325 audit(1631560894.962:230): table=nat family=2 entries=10 op=xt_replace pid=7730 subj==unconfined comm="iptables" [ 186.540439] audit: type=1325 audit(1631560894.982:231): table=nat family=2 entries=11 op=xt_replace pid=7731 subj==unconfined comm="iptables" [ 186.587573] audit: type=1325 audit(1631560895.022:232): table=nat family=2 entries=12 op=xt_replace pid=7732 subj==unconfined comm="iptables" [ 186.623527] audit: type=1325 audit(1631560895.058:233): table=nat family=2 entries=13 op=xt_replace pid=7737 subj==unconfined comm="iptables" [ 186.652541] audit: type=1325 audit(1631560895.094:234): table=nat family=2 entries=14 op=xt_replace pid=7739 subj==unconfined comm="iptables" [ 186.702240] eth0: renamed from veth9f802df [ 186.718283] IPv6: ADDRCONF(NETDEV_CHANGE): vethc218dc0: link becomes ready [ 186.718563] hassio: port 13(vethc218dc0) entered blocking state [ 186.718577] hassio: port 13(vethc218dc0) entered forwarding state [ 187.568442] hassio: port 14(vethc872e4b) entered blocking state [ 187.568457] hassio: port 14(vethc872e4b) entered disabled state [ 187.575596] device vethc872e4b entered promiscuous mode [ 187.577407] hassio: port 14(vethc872e4b) entered blocking state [ 187.577428] hassio: port 14(vethc872e4b) entered forwarding state [ 187.719719] hassio: port 14(vethc872e4b) entered disabled state [ 189.444693] eth0: renamed from veth4819618 [ 189.461912] IPv6: ADDRCONF(NETDEV_CHANGE): vethc872e4b: link becomes ready [ 189.462067] hassio: port 14(vethc872e4b) entered blocking state [ 189.462076] hassio: port 14(vethc872e4b) entered forwarding state [ 194.794482] kauditd_printk_skb: 11 callbacks suppressed [ 194.794491] audit: type=1334 audit(1631560903.253:246): prog-id=15 op=LOAD [ 194.794852] audit: type=1334 audit(1631560903.253:247): prog-id=16 op=LOAD [ 195.549145] audit: type=1334 audit(1631560904.009:248): prog-id=17 op=LOAD [ 195.549445] audit: type=1334 audit(1631560904.009:249): prog-id=18 op=LOAD [ 225.372373] audit: type=1334 audit(1631560933.833:250): prog-id=16 op=UNLOAD [ 225.372412] audit: type=1334 audit(1631560933.833:251): prog-id=15 op=UNLOAD [ 226.324472] audit: type=1334 audit(1631560934.785:252): prog-id=18 op=UNLOAD [ 226.324507] audit: type=1334 audit(1631560934.785:253): prog-id=17 op=UNLOAD [ 390.059282] veth920d42b: renamed from eth0 [ 390.079318] hassio: port 8(veth622c5f4) entered disabled state [ 390.189929] hassio: port 8(veth622c5f4) entered disabled state [ 390.191570] device veth622c5f4 left promiscuous mode [ 390.191628] audit: type=1700 audit(1631561098.586:254): dev=veth622c5f4 prom=0 old_prom=256 auid=4294967295 uid=0 gid=0 ses=4294967295 [ 390.191923] hassio: port 8(veth622c5f4) entered disabled state [ 390.380614] audit: type=1325 audit(1631561098.838:255): table=nat family=2 entries=15 op=xt_unregister pid=377 subj==unconfined comm="kworker/u8:2" [ 390.380655] audit: type=1325 audit(1631561098.838:256): table=filter family=2 entries=4 op=xt_unregister pid=377 subj==unconfined comm="kworker/u8:2" ```

Description of problem: I'm trying to use a dvb tuner as an SDR to communicate (or listen) to responses from my home water meter. The details do not matter too much, the main issue is that the tuner is (correctly) identified by the OS as a DVB tuner and as such loads the appropriate module. Because I do not want it to do this, I followed the instructions here to add an entry to modprobe.d, to blacklist the kernel module in question. For what it's worth, it's dvb_usb_rtl28xxu.

This upon restart had no effect, and I started digging down towards the os build, and busybox, which has a number of configurations around modprobe here including toggles to enable blacklisting. I confirmed I would need to enable CONFIG_MODPROBE as well as CONFIG_FEATURE_MODPROBE_BLACKLIST which I found within the source here

I built a 6.3 image (at the time) from source using the instructions provided, and with this subtle tweak, and then tested on my RPi - the same symptoms.

Question is, what else needs to be changed, is there anyway to integrate this as part of the standard build, or is the idea bust and I should just purchase a proper radio dongle.

I was hoping the above would provide a solution and I could raise a PR, but unfortunately not. Happy to try alternative options and build from source to confirm/deny if any proposed fixes work.

agners commented 3 years ago

The modprobe binary used in HAOS is not from the busybox package but from the kmod package (BR2_PACKAGE_KMOD). Afaict this gets selected implicitly somehow. This is the tool which comes from kernel.org and I think blacklist is a feature enabled by default.

Are you sure you blacklist the module correctly? From what I can tell the module filename is dvb-usb-rtl28xxu.ko, so I'd guess you need to use blacklist dvb-usb-rtl28xxu to blacklist that module. Although, I read on the Arch Linux Wiki that - and _ can be used interchangeably, so I guess its not that.

agners commented 3 years ago

Maybe another module is depending on it, which causes it to get loaded? There is a work around using a install instruction documented on the Arch Linux wiki: https://wiki.archlinux.org/title/Kernel_module#Blacklisting

asdfghjkai commented 3 years ago

Thanks for letting me know I was going down the rabbit hole! I think the blacklist 'should' have worked, given it looks for the name of the loaded module (Which uses underscores versus the filename (Which likely uses hyphens to stand out) - also to stick with convention.

Let me take a look. I think I found the module it that depends on it (And blocked that also) but looks like no others. I'll try the arch work around and report back

`

cat /etc/modprobe.d/blacklist-dvb-2.conf

blacklist dvb_usb_rtl28xxu blacklist dvb_usb_v2#

lsmod | grep dvb

dvb_usb_rtl28xxu 32768 1 dvb_usb_v2 32768 1 dvb_usb_rtl28xxu

`

Cheers @agners

asdfghjkai commented 3 years ago

Apologes, still no good. Still loading?!? Restarted betweenb

cat /etc/modprobe.d/blacklist-dvb-2.conf install dvb_usb_rtl28xxu /bin/true

lsmod | grep dvb dvb_usb_rtl28xxu 32768 1 dvb_usb_v2 32768 1 dvb_usb_rtl28xxu

ls /dev | grep dvb dvb

agners commented 3 years ago

@asdfghjkai how do you access the OS shell?

asdfghjkai commented 3 years ago

@agners One of two methods

  1. Interactively by typing login on the device, and accessing like that
  2. Remotely (After creating an SSH key pair and placing it on my CONFIG usb). Using ssh root@10.0.100.9 -p 22222

In this case, not using the SSH addon because I wanted to access the underlying OS before it passes anything through to the HA container.

agners commented 3 years ago

@asdfghjkai this Reddit post lists multiple blacklist lines, maybe that helps?

blacklist dvb_usb_rtl28xxu
blacklist rtl2832
blacklist rtl2830

Maybe journalctl is saying something?

journalctl -b 1 | grep modprobe

Can you also try the following: Have the blacklist setup the way you think it should work. Plug out the DVB tuner. Boot Home Assistant completely, make sure the modules are not loaded, plug-in the DVB tuner, check if the modules are loaded now (it might be that the blacklist directory gets mounted too late, bascially modprobe loading the modules too early).

asdfghjkai commented 3 years ago

@agners Just have that ago, same symptoms

cat /etc/modprobe.d/blacklist-dvb-2.conf blacklist dvb_usb_rtl28xxu blacklist rtl2832 blacklist rtl2830

dvb_usb_rtl28xxu 32768 1 dvb_usb_v2 32768 1 dvb_usb_rtl28xxu

Also nothing from journalctl :(

Just tried the second method Restarted not connected, lsmod | grep dvb returns nothing, good, no sign of it in /dev also. Good so far

Plug in the device and it doesn't load any drivers. This I think will suffice for now, the integration is able to sense the raw device and work with it. Still not quite right, but I think it will have to do

agners commented 3 years ago

Plug in the device and it doesn't load any drivers. This I think will suffice for now, the integration is able to sense the raw device and work with it. Still not quite right, but I think it will have to do

So it did not load the drivers correct?

Hm, that means that our mount is too late for blacklist to work. I'll have a closer look at that, but it will take me some time to debug.

asdfghjkai commented 3 years ago

Correct. Didn’t load anything so instead the integration is just using the raw usb device (not even mapped to a serial device, but hey it works)

Let me know if I can help out at all, test builds or so on and I’ll let you know

agners commented 3 years ago

Indeed it seems that blacklist module is currently not working. The reason is that the /etc/modprobe.d mount point is loaded after systemd-udevd, hence the module is loaded before systemd-udevd has a chance to read the config files from /etc/modprobe.d. I tried to reverse the start order (start systemd-udevd after /etc/modprobe.d is mounted), but that seems not to work. From what I can tell systemd-udevd is really essential to get anything going in terms of mounting etc. so basically we cannot mount before systemd-udevd is running, a chicken-egg problem.

From this comment it seems that mount /etc as part of the regular system boot seems not officially supported by systemd. I'd guess bind mount parts of /etc is somewhat an edge case.

I think the only proper way to fix this would be to mount /etc in full (bind mount all sub directories) in a initramfs is probably the proper solution for this. But this would mean a major change for HAOS...

However, there is good news also: There is a work around: You can add module_blacklist=dvb_usb_rtl28xxu to /mnt/boot/cmdline.txt, which tells the kernel on a low level to ignore loading a module with that name.

@asdfghjkai can you try this approach?

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.

HarryJHolmes commented 2 years ago

However, there is good news also: There is a work around: You can add module_blacklist=dvb_usb_rtl28xxu to /mnt/boot/cmdline.txt, which tells the kernel on a low level to ignore loading a module with that name.

That doesn't seem to work. Do you know of any other workarounds?

agners commented 2 years ago

It does work, see official Linux kernel documentation of module_blacklist at https://www.kernel.org/doc/html/v4.14/admin-guide/kernel-parameters.html.

Did you reboot after setting that parameter? Do you have that driver/card in question? Can you share your host logs (dmesg)?