home-assistant / operating-system

:beginner: Home Assistant Operating System
Apache License 2.0
5.1k stars 994 forks source link

CH340 based devices (like POPP ZB-Stick) stopped working in Home Assistant OS 6.3 #1532

Closed TabakM4n closed 3 years ago

TabakM4n commented 3 years ago

Hardware Environment

Home Assistant OS release:

version core-2021.9.3
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.61
arch x86_64
timezone UTC
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 | 30.8 GB disk_used | 2.7 GB healthy | true supported | true board | ova supervisor_api | ok version_api | ok installed_addons | Visual Studio Code (3.6.2), Log Viewer (0.11.1)
Lovelace dashboards | 1 -- | -- resources | 0 mode | auto-gen

Supervisor logs:

[services.d] done.
[15:11:34] INFO: Starting local supervisor watchdog...
21-09-05 15:11:35 INFO (MainThread) [__main__] Initializing Supervisor setup
21-09-05 15:11:36 INFO (MainThread) [supervisor.bootstrap] Initializing Supervisor Sentry
21-09-05 18:11:36 INFO (MainThread) [supervisor.bootstrap] Seting up coresys for machine: qemux86-64
21-09-05 18:11:36 INFO (SyncWorker_0) [supervisor.docker.supervisor] Attaching to Supervisor homeassistant/amd64-hassio-supervisor with version 2021.09.0
21-09-05 18:11:36 INFO (MainThread) [supervisor.resolution.evaluate] Starting system evaluation with state CoreState.INITIALIZE
21-09-05 18:11:36 INFO (MainThread) [supervisor.resolution.evaluate] System evaluation complete
21-09-05 18:11:36 INFO (MainThread) [__main__] Setting up Supervisor
21-09-05 18:11:36 INFO (MainThread) [supervisor.api] Starting API on 172.30.32.2
21-09-05 18:11:36 INFO (MainThread) [supervisor.hardware.monitor] Started Supervisor hardware monitor
21-09-05 18:11:36 INFO (MainThread) [supervisor.dbus.manager] Load dbus interface io.hass.os
21-09-05 18:11:36 INFO (MainThread) [supervisor.dbus.manager] Load dbus interface org.freedesktop.systemd1
21-09-05 18:11:36 INFO (MainThread) [supervisor.dbus.manager] Load dbus interface org.freedesktop.login1
21-09-05 18:11:36 INFO (MainThread) [supervisor.dbus.manager] Load dbus interface org.freedesktop.hostname1
21-09-05 18:11:36 INFO (MainThread) [supervisor.dbus.manager] Load dbus interface org.freedesktop.timedate1
21-09-05 18:11:36 INFO (MainThread) [supervisor.dbus.manager] Load dbus interface org.freedesktop.NetworkManager
21-09-05 18:11:36 INFO (MainThread) [supervisor.dbus.manager] Load dbus interface de.pengutronix.rauc
21-09-05 18:11:36 INFO (MainThread) [supervisor.host.info] Updating local host information
21-09-05 18:11:36 INFO (MainThread) [supervisor.host.services] Updating service information
21-09-05 18:11:36 INFO (MainThread) [supervisor.host.network] Updating local network information
21-09-05 18:11:37 INFO (MainThread) [supervisor.host.sound] Updating PulseAudio information
21-09-05 18:11:37 INFO (MainThread) [supervisor.host.manager] Host information reload completed
21-09-05 18:11:37 INFO (MainThread) [supervisor.host.apparmor] Loading AppArmor Profiles: {'hassio-supervisor'}
21-09-05 18:11:37 INFO (MainThread) [supervisor.host.services] Reloading local service hassos-apparmor.service
21-09-05 18:11:37 INFO (SyncWorker_0) [supervisor.docker.interface] Attaching to ghcr.io/home-assistant/amd64-hassio-dns with version 2021.06.0
21-09-05 18:11:37 INFO (MainThread) [supervisor.plugins.dns] Starting CoreDNS plugin
21-09-05 18:11:37 INFO (SyncWorker_0) [supervisor.docker.dns] Starting DNS ghcr.io/home-assistant/amd64-hassio-dns with version 2021.06.0 - 172.30.32.3
21-09-05 18:11:37 INFO (MainThread) [supervisor.plugins.dns] Updated /etc/resolv.conf
21-09-05 18:11:38 INFO (SyncWorker_1) [supervisor.docker.interface] Attaching to ghcr.io/home-assistant/amd64-hassio-audio with version 2021.07.0
21-09-05 18:11:38 INFO (MainThread) [supervisor.plugins.audio] Starting Audio plugin
21-09-05 18:11:38 INFO (SyncWorker_1) [supervisor.docker.audio] Starting Audio ghcr.io/home-assistant/amd64-hassio-audio with version 2021.07.0 - 172.30.32.4
21-09-05 18:11:38 INFO (SyncWorker_0) [supervisor.docker.interface] Attaching to ghcr.io/home-assistant/amd64-hassio-cli with version 2021.08.1
21-09-05 18:11:38 INFO (MainThread) [supervisor.plugins.cli] Starting CLI plugin
21-09-05 18:11:39 INFO (SyncWorker_0) [supervisor.docker.cli] Starting CLI ghcr.io/home-assistant/amd64-hassio-cli with version 2021.08.1 - 172.30.32.5
21-09-05 18:11:39 INFO (SyncWorker_0) [supervisor.docker.interface] Attaching to ghcr.io/home-assistant/amd64-hassio-observer with version 2021.06.0
21-09-05 18:11:39 INFO (SyncWorker_0) [supervisor.docker.interface] Attaching to ghcr.io/home-assistant/amd64-hassio-multicast with version 2021.04.0
21-09-05 18:11:39 INFO (MainThread) [supervisor.plugins.multicast] Starting Multicast plugin
21-09-05 18:11:39 INFO (SyncWorker_1) [supervisor.docker.multicast] Starting Multicast ghcr.io/home-assistant/amd64-hassio-multicast with version 2021.04.0 - Host
21-09-05 18:11:39 INFO (MainThread) [supervisor.updater] Fetching update data from https://version.home-assistant.io/stable.json
21-09-05 18:11:41 INFO (MainThread) [supervisor.homeassistant.secrets] Loaded 1 Home Assistant secrets
21-09-05 18:11:41 INFO (SyncWorker_2) [supervisor.docker.interface] Attaching to ghcr.io/home-assistant/qemux86-64-homeassistant with version 2021.9.3
21-09-05 18:11:42 INFO (MainThread) [supervisor.hassos] Detect Home Assistant Operating System 6.3 / BootSlot B
21-09-05 18:11:42 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-05 18:11:42 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-05 18:11:42 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-05 18:11:42 INFO (MainThread) [supervisor.store.git] Loading add-on /data/addons/git/a0d7b954 repository
21-09-05 18:11:42 INFO (MainThread) [supervisor.store.git] Loading add-on /data/addons/core repository
21-09-05 18:11:42 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-05 18:11:42 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-05 18:11:42 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-05 18:11:42 INFO (MainThread) [supervisor.store] Loading add-ons from store: 63 all - 63 new - 0 remove
21-09-05 18:11:42 INFO (MainThread) [supervisor.addons] Found 2 installed add-ons
21-09-05 18:11:42 INFO (SyncWorker_2) [supervisor.docker.interface] Attaching to ghcr.io/hassio-addons/log-viewer/amd64 with version 0.11.1
21-09-05 18:11:42 INFO (SyncWorker_0) [supervisor.docker.interface] Attaching to ghcr.io/hassio-addons/vscode/amd64 with version 3.6.2
21-09-05 18:11:42 INFO (MainThread) [supervisor.backups.manager] Found 0 backup files
21-09-05 18:11:42 INFO (MainThread) [supervisor.discovery] Loaded 0 messages
21-09-05 18:11:42 INFO (MainThread) [supervisor.ingress] Loaded 0 ingress sessions
21-09-05 18:11:42 INFO (MainThread) [supervisor.resolution.check] Starting system checks with state CoreState.SETUP
21-09-05 18:11:42 INFO (MainThread) [supervisor.resolution.check] System checks complete
21-09-05 18:11:42 INFO (MainThread) [supervisor.resolution.evaluate] Starting system evaluation with state CoreState.SETUP
21-09-05 18:11:42 INFO (MainThread) [supervisor.resolution.evaluate] System evaluation complete
21-09-05 18:11:42 INFO (MainThread) [supervisor.jobs] 'ResolutionFixup.run_autofix' blocked from execution, system is not running - CoreState.SETUP
21-09-05 18:11:42 INFO (MainThread) [supervisor.resolution.evaluate] Starting system evaluation with state CoreState.SETUP
21-09-05 18:11:42 INFO (MainThread) [supervisor.resolution.evaluate] System evaluation complete
21-09-05 18:11:42 INFO (MainThread) [__main__] Running Supervisor
21-09-05 18:11:42 INFO (MainThread) [supervisor.hassos] Rauc: B - marked slot kernel.1 as good
21-09-05 18:11:42 INFO (MainThread) [supervisor.addons] Phase 'AddonStartup.INITIALIZE' starting 0 add-ons
21-09-05 18:11:42 INFO (MainThread) [supervisor.addons] Phase 'AddonStartup.SYSTEM' starting 0 add-ons
21-09-05 18:11:42 INFO (MainThread) [supervisor.addons] Phase 'AddonStartup.SERVICES' starting 1 add-ons
21-09-05 18:11:43 INFO (SyncWorker_0) [supervisor.docker.addon] Starting Docker add-on ghcr.io/hassio-addons/vscode/amd64 with version 3.6.2
21-09-05 18:11:48 INFO (MainThread) [supervisor.core] Start Home Assistant Core
21-09-05 18:11:48 INFO (SyncWorker_2) [supervisor.docker.interface] Starting homeassistant
21-09-05 18:11:48 INFO (MainThread) [supervisor.homeassistant.core] Wait until Home Assistant is ready
21-09-05 18:11:52 INFO (MainThread) [supervisor.resolution.evaluate] Starting system evaluation with state CoreState.STARTUP
21-09-05 18:11:52 INFO (MainThread) [supervisor.resolution.evaluate] System evaluation complete
21-09-05 18:11:53 INFO (MainThread) [supervisor.homeassistant.api] Updated Home Assistant API token
21-09-05 18:12:03 INFO (MainThread) [supervisor.homeassistant.core] Detect a running Home Assistant instance
21-09-05 18:12:03 INFO (MainThread) [supervisor.addons] Phase 'AddonStartup.APPLICATION' starting 1 add-ons
21-09-05 18:12:04 INFO (SyncWorker_0) [supervisor.docker.addon] Starting Docker add-on ghcr.io/hassio-addons/log-viewer/amd64 with version 0.11.1
21-09-05 18:12:09 INFO (MainThread) [supervisor.misc.tasks] All core tasks are scheduled
21-09-05 18:12:09 INFO (MainThread) [supervisor.core] Supervisor is up and running
21-09-05 18:12:09 INFO (MainThread) [supervisor.host.info] Updating local host information
21-09-05 18:12:09 INFO (MainThread) [supervisor.resolution.check] Starting system checks with state CoreState.RUNNING
21-09-05 18:12:09 INFO (MainThread) [supervisor.resolution.checks.base] Run check for IssueType.TRUST/ContextType.CORE
21-09-05 18:12:09 INFO (MainThread) [supervisor.host.services] Updating service information
21-09-05 18:12:09 INFO (MainThread) [supervisor.host.network] Updating local network information
21-09-05 18:12:10 INFO (MainThread) [supervisor.host.sound] Updating PulseAudio information
21-09-05 18:12:10 INFO (MainThread) [supervisor.host.manager] Host information reload completed
21-09-05 18:12:11 INFO (MainThread) [supervisor.resolution.checks.base] Run check for IssueType.FREE_SPACE/ContextType.SYSTEM
21-09-05 18:12:11 INFO (MainThread) [supervisor.resolution.module] Create new suggestion SuggestionType.CREATE_FULL_BACKUP - ContextType.SYSTEM / None
21-09-05 18:12:11 INFO (MainThread) [supervisor.resolution.checks.base] Run check for IssueType.TRUST/ContextType.SUPERVISOR
21-09-05 18:12:12 INFO (MainThread) [supervisor.resolution.checks.base] Run check for IssueType.PWNED/ContextType.ADDON
21-09-05 18:12:12 INFO (MainThread) [supervisor.resolution.checks.base] Run check for IssueType.TRUST/ContextType.PLUGIN
21-09-05 18:12:21 INFO (MainThread) [supervisor.resolution.checks.base] Run check for IssueType.SECURITY/ContextType.CORE
21-09-05 18:12:21 INFO (MainThread) [supervisor.resolution.check] System checks complete
21-09-05 18:12:21 INFO (MainThread) [supervisor.resolution.evaluate] Starting system evaluation with state CoreState.RUNNING
21-09-05 18:12:23 INFO (MainThread) [supervisor.resolution.evaluate] System evaluation complete
21-09-05 18:12:23 INFO (MainThread) [supervisor.resolution.fixup] Starting system autofix at state CoreState.RUNNING
21-09-05 18:12:23 INFO (MainThread) [supervisor.resolution.fixup] System autofix complete

Journal logs:

Kernel logs:

Description of problem:

Hello

With POPP ZA-STICK I created 4 installs of HAOS 2 on Proxmox with versions 6.2 and 6.3 2 on VM box under window with versions 6.2 and 6.3

When I try to add the integration using this turorial in both 6.2 versions ( under Proxmox and VM box) it worked perfectly fine

After trying the same in version 6.3 the integration does not work.

Furthermore I updated both 6.2 VMs to 6.3 and the integration stopped working.

Also I tried to downgrade both 6.3 to 6.2 and guess what - the integration started working again.

Log Provider Core:

 File "/usr/local/lib/python3.9/site-packages/bellows/zigbee/application.py", line 151, in startup
    status, node_type, nwk_params = await ezsp.getNetworkParameters()
  File "/usr/local/lib/python3.9/asyncio/tasks.py", line 494, in wait_for
    raise exceptions.TimeoutError() from exc
asyncio.exceptions.TimeoutError
2021-09-05 18:16:29 ERROR (MainThread) [zigpy.application] Couldn't start application
2021-09-05 18:16:29 ERROR (MainThread) [homeassistant.components.zha.core.gateway] Couldn't start EZSP = Silicon Labs EmberZNet protocol: Elelabs, HUSBZB-1, Telegesis coordinator
Traceback (most recent call last):
  File "/usr/local/lib/python3.9/asyncio/tasks.py", line 492, in wait_for
    fut.result()
asyncio.exceptions.CancelledError
The above exception was the direct cause of the following exception:
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/bellows/zigbee/application.py", line 151, in startup
    status, node_type, nwk_params = await ezsp.getNetworkParameters()
  File "/usr/local/lib/python3.9/asyncio/tasks.py", line 494, in wait_for
    raise exceptions.TimeoutError() from exc
asyncio.exceptions.TimeoutError
......
agners commented 3 years ago

Hm, other than the update to the latest stable Linux kernel I don't see a change which might influence ZB Stick behavior.

Is your OS 6.2 installation fully up-to-date (same Core/Supervisor version as the 6.3 installation?).

Can you also check the Host log (Supervisor -> System -> Host) and see if you see a difference?

TabakM4n commented 3 years ago

Everything is updated with the latest version. I see some differences in the host logs, but I do not know what I am searching for.

The logs from the same VM just switching between HA OS versions:

Logs 6.2 ``` [ 0.000000] Linux version 5.10.53 (builder@8b47b16fd37b) (x86_64-buildroot-linux-gnu-gcc.br_real (Buildroot -gf358c8ba-dirty) 9.3.0, GNU ld (GNU Binutils) 2.35.2) #1 SMP Wed Jul 28 20:42:41 UTC 2021 [ 0.000000] Command line: apparmor=1 security=apparmor console=ttyS0 console=tty1 bootchooser.active=A root=PARTUUID=8d3d53e3-6d49-4c38-8349-aff6859e82fd rootfstype=squashfs ro rootwait systemd.machine_id=f1b18bd3ca2b40d391fa721de534405b zram.enabled=1 zram.num_devices=3 [ 0.000000] [Firmware Bug]: TSC doesn't count with P0 frequency! [ 0.000000] x86/fpu: x87 FPU will use FXSAVE [ 0.000000] BIOS-provided physical RAM map: [ 0.000000] BIOS-e820: [mem 0x0000000000000000-0x000000000009ffff] usable [ 0.000000] BIOS-e820: [mem 0x0000000000100000-0x000000007d9b3fff] usable [ 0.000000] BIOS-e820: [mem 0x000000007d9b4000-0x000000007d9b4fff] ACPI data [ 0.000000] BIOS-e820: [mem 0x000000007d9b5000-0x000000007de90fff] usable [ 0.000000] BIOS-e820: [mem 0x000000007de91000-0x000000007df1afff] reserved [ 0.000000] BIOS-e820: [mem 0x000000007df1b000-0x000000007ef9afff] usable [ 0.000000] BIOS-e820: [mem 0x000000007ef9b000-0x000000007eff2fff] reserved [ 0.000000] BIOS-e820: [mem 0x000000007eff3000-0x000000007effafff] ACPI data [ 0.000000] BIOS-e820: [mem 0x000000007effb000-0x000000007effefff] ACPI NVS [ 0.000000] BIOS-e820: [mem 0x000000007efff000-0x000000007f46afff] usable [ 0.000000] BIOS-e820: [mem 0x000000007f46b000-0x000000007ffeffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000ffc00000-0x00000000ffffffff] reserved [ 0.000000] NX (Execute Disable) protection: active [ 0.000000] efi: EFI v2.70 by EDK II [ 0.000000] efi: ACPI=0x7effa000 ACPI 2.0=0x7effa014 SMBIOS=0x7effd000 [ 0.000000] SMBIOS 2.5 present. [ 0.000000] DMI: innotek GmbH VirtualBox/VirtualBox, BIOS VirtualBox 12/01/2006 [ 0.000000] Hypervisor detected: KVM [ 0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00 [ 0.000000] kvm-clock: cpu 0, msr 2450d001, primary cpu clock [ 0.000000] kvm-clock: using sched offset of 304453424651 cycles [ 0.000003] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns [ 0.000005] tsc: Detected 3593.254 MHz processor [ 0.002135] e820: update [mem 0x00000000-0x00000fff] usable ==> reserved [ 0.002137] e820: remove [mem 0x000a0000-0x000fffff] usable [ 0.002141] last_pfn = 0x7f46b max_arch_pfn = 0x400000000 [ 0.002276] MTRR default type: uncachable [ 0.002277] MTRR fixed ranges enabled: [ 0.002278] 00000-9FFFF write-back [ 0.002279] A0000-FFFFF uncachable [ 0.002279] MTRR variable ranges enabled: [ 0.002280] 0 base 000000000000 mask FFFF80000000 write-back [ 0.002281] 1 base 00007FFF0000 mask FFFFFFFF0000 uncachable [ 0.002281] 2 disabled [ 0.002282] 3 disabled [ 0.002282] 4 disabled [ 0.002282] 5 disabled [ 0.002282] 6 disabled [ 0.002283] 7 disabled [ 0.002291] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT [ 0.114110] check: Scanning 1 areas for low memory corruption [ 0.114444] Secure boot disabled [ 0.114449] ACPI: Early table checksum verification disabled [ 0.114454] ACPI: RSDP 0x000000007EFFA014 000024 (v02 VBOX ) [ 0.114457] ACPI: XSDT 0x000000007EFF90E8 000044 (v01 VBOX VBOXFACP 00000001 01000013) [ 0.114463] ACPI: FACP 0x000000007EFF7000 0000F4 (v04 VBOX VBOXFACP 00000001 ASL 00000061) [ 0.114469] ACPI: DSDT 0x000000007EFF4000 002325 (v02 VBOX VBOXBIOS 00000002 INTL 20100528) [ 0.114472] ACPI: FACS 0x000000007EFFE000 000040 [ 0.114474] ACPI: APIC 0x000000007EFF8000 00005C (v02 VBOX VBOXAPIC 00000001 ASL 00000061) [ 0.114477] ACPI: SSDT 0x000000007EFF3000 0001CC (v01 VBOX VBOXCPUT 00000002 INTL 20100528) [ 0.114480] ACPI: BGRT 0x000000007D9B4000 000038 (v01 INTEL EDK2 00000002 01000013) [ 0.114483] ACPI: Reserving FACP table memory at [mem 0x7eff7000-0x7eff70f3] [ 0.114485] ACPI: Reserving DSDT table memory at [mem 0x7eff4000-0x7eff6324] [ 0.114485] ACPI: Reserving FACS table memory at [mem 0x7effe000-0x7effe03f] [ 0.114486] ACPI: Reserving APIC table memory at [mem 0x7eff8000-0x7eff805b] [ 0.114487] ACPI: Reserving SSDT table memory at [mem 0x7eff3000-0x7eff31cb] [ 0.114488] ACPI: Reserving BGRT table memory at [mem 0x7d9b4000-0x7d9b4037] [ 0.114537] ACPI: Local APIC address 0xfee00000 [ 0.115539] No NUMA configuration found [ 0.115541] Faking a node at [mem 0x0000000000000000-0x000000007f46afff] [ 0.115544] NODE_DATA(0) allocated [mem 0x7f213000-0x7f217fff] [ 0.115767] Zone ranges: [ 0.115769] DMA [mem 0x0000000000001000-0x0000000000ffffff] [ 0.115771] DMA32 [mem 0x0000000001000000-0x000000007f46afff] [ 0.115772] Normal empty [ 0.115773] Device empty [ 0.115773] Movable zone start for each node [ 0.115774] Early memory node ranges [ 0.115775] node 0: [mem 0x0000000000001000-0x000000000009ffff] [ 0.115776] node 0: [mem 0x0000000000100000-0x000000007d9b3fff] [ 0.115776] node 0: [mem 0x000000007d9b5000-0x000000007de90fff] [ 0.115777] node 0: [mem 0x000000007df1b000-0x000000007ef9afff] [ 0.115778] node 0: [mem 0x000000007efff000-0x000000007f46afff] [ 0.115779] Initmem setup node 0 [mem 0x0000000000001000-0x000000007f46afff] [ 0.115780] On node 0 totalpages: 520987 [ 0.115781] DMA zone: 64 pages used for memmap [ 0.115782] DMA zone: 23 pages reserved [ 0.115782] DMA zone: 3999 pages, LIFO batch:0 [ 0.115783] DMA32 zone: 8082 pages used for memmap [ 0.115783] DMA32 zone: 516988 pages, LIFO batch:63 [ 0.115834] On node 0, zone DMA: 1 pages in unavailable ranges [ 0.116863] On node 0, zone DMA: 96 pages in unavailable ranges [ 0.250012] On node 0, zone DMA32: 1 pages in unavailable ranges [ 0.251204] On node 0, zone DMA32: 138 pages in unavailable ranges [ 0.251544] On node 0, zone DMA32: 100 pages in unavailable ranges [ 0.252443] On node 0, zone DMA32: 2965 pages in unavailable ranges [ 0.254650] ACPI: PM-Timer IO Port: 0x4008 [ 0.254654] ACPI: Local APIC address 0xfee00000 [ 0.254966] IOAPIC[0]: apic_id 2, version 32, address 0xfec00000, GSI 0-23 [ 0.254970] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) [ 0.254972] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 low level) [ 0.254974] ACPI: IRQ0 used by override. [ 0.254975] ACPI: IRQ9 used by override. [ 0.254976] Using ACPI (MADT) for SMP configuration information [ 0.254984] e820: update [mem 0x7e182000-0x7e19cfff] usable ==> reserved [ 0.255025] smpboot: Allowing 2 CPUs, 0 hotplug CPUs [ 0.255164] PM: hibernation: Registered nosave memory: [mem 0x00000000-0x00000fff] [ 0.255166] PM: hibernation: Registered nosave memory: [mem 0x000a0000-0x000fffff] [ 0.255167] PM: hibernation: Registered nosave memory: [mem 0x7d9b4000-0x7d9b4fff] [ 0.255168] PM: hibernation: Registered nosave memory: [mem 0x7de91000-0x7df1afff] [ 0.255169] PM: hibernation: Registered nosave memory: [mem 0x7e182000-0x7e19cfff] [ 0.255170] PM: hibernation: Registered nosave memory: [mem 0x7ef9b000-0x7eff2fff] [ 0.255171] PM: hibernation: Registered nosave memory: [mem 0x7eff3000-0x7effafff] [ 0.255171] PM: hibernation: Registered nosave memory: [mem 0x7effb000-0x7effefff] [ 0.255173] [mem 0x7fff0000-0xffbfffff] available for PCI devices [ 0.255174] Booting paravirtualized kernel on KVM [ 0.255177] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns [ 0.259226] setup_percpu: NR_CPUS:64 nr_cpumask_bits:64 nr_cpu_ids:2 nr_node_ids:1 [ 0.267544] percpu: Embedded 54 pages/cpu s182296 r8192 d30696 u1048576 [ 0.267553] pcpu-alloc: s182296 r8192 d30696 u1048576 alloc=1*2097152 [ 0.267554] pcpu-alloc: [0] 0 1 [ 0.267656] kvm-guest: PV spinlocks enabled [ 0.267661] PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) [ 0.267688] Built 1 zonelists, mobility grouping on. Total pages: 512818 [ 0.267690] Policy zone: DMA32 [ 0.267692] Kernel command line: apparmor=1 security=apparmor console=ttyS0 console=tty1 bootchooser.active=A root=PARTUUID=8d3d53e3-6d49-4c38-8349-aff6859e82fd rootfstype=squashfs ro rootwait systemd.machine_id=f1b18bd3ca2b40d391fa721de534405b zram.enabled=1 zram.num_devices=3 [ 0.272876] Dentry cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) [ 0.276552] Inode-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) [ 0.276613] mem auto-init: stack:off, heap alloc:off, heap free:off [ 0.279504] Memory: 1990012K/2083948K available (16395K kernel code, 2572K rwdata, 3912K rodata, 1396K init, 1096K bss, 93676K reserved, 0K cma-reserved) [ 0.279531] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1 [ 0.279583] rcu: Hierarchical RCU implementation. [ 0.279584] rcu: RCU event tracing is enabled. [ 0.279584] rcu: RCU restricting CPUs from NR_CPUS=64 to nr_cpu_ids=2. [ 0.279585] Tracing variant of Tasks RCU enabled. [ 0.279587] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. [ 0.279588] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=2 [ 0.280001] NR_IRQS: 4352, nr_irqs: 440, preallocated irqs: 16 [ 0.280440] random: get_random_bytes called from start_kernel+0x375/0x54c with crng_init=0 [ 0.280460] Console: colour dummy device 80x25 [ 0.280558] printk: console [tty1] enabled [ 0.520594] printk: console [ttyS0] enabled [ 0.522005] ACPI: Core revision 20200925 [ 0.523497] APIC: Switch to symmetric I/O mode setup [ 0.536399] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 [ 0.539226] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x33cb6ecbec8, max_idle_ns: 440795311068 ns [ 0.543565] Calibrating delay loop (skipped) preset value.. 7186.50 BogoMIPS (lpj=3593254) [ 0.544568] pid_max: default: 32768 minimum: 301 [ 0.547102] LSM: Security Framework initializing [ 0.547591] AppArmor: AppArmor initialized [ 0.548579] Mount-cache hash table entries: 4096 (order: 3, 32768 bytes, linear) [ 0.549571] Mountpoint-cache hash table entries: 4096 (order: 3, 32768 bytes, linear) [ 0.552212] Last level iTLB entries: 4KB 1024, 2MB 1024, 4MB 512 [ 0.552563] Last level dTLB entries: 4KB 2048, 2MB 2048, 4MB 1024, 1GB 0 [ 0.553567] Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization [ 0.554564] Spectre V2 : Mitigation: Full AMD retpoline [ 0.555563] Spectre V2 : Spectre v2 / SpectreRSB mitigation: Filling RSB on context switch [ 0.556564] Speculative Store Bypass: Mitigation: Speculative Store Bypass disabled via prctl [ 0.558788] Freeing SMP alternatives memory: 52K [ 0.665910] smpboot: CPU0: AMD Ryzen 5 3600 6-Core Processor (family: 0x17, model: 0x71, stepping: 0x0) [ 0.666560] Performance Events: PMU not available due to virtualization, using software events only. [ 0.666560] rcu: Hierarchical SRCU implementation. [ 0.666560] smp: Bringing up secondary CPUs ... [ 0.666560] x86: Booting SMP configuration: [ 0.666560] .... node #0, CPUs: #1 [ 0.262143] kvm-clock: cpu 1, msr 2450d041, secondary cpu clock [ 0.672560] TSC synchronization [CPU#0 -> CPU#1]: [ 0.672560] Measured 172332 cycles TSC warp between CPUs, turning off TSC clock. [ 0.672560] tsc: Marking TSC unstable due to check_tsc_sync_source failed [ 0.675703] smp: Brought up 1 node, 2 CPUs [ 0.676564] smpboot: Max logical packages: 1 [ 0.677564] smpboot: Total of 2 processors activated (14373.01 BogoMIPS) [ 0.679799] devtmpfs: initialized [ 0.681597] x86/mm: Memory block size: 128MB [ 0.684723] PM: Registering ACPI NVS region [mem 0x7effb000-0x7effefff] (16384 bytes) [ 0.685602] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.686568] futex hash table entries: 512 (order: 3, 32768 bytes, linear) [ 0.688752] PM: RTC time: 18:05:09, date: 2021-09-05 [ 0.689711] NET: Registered protocol family 16 [ 0.690702] audit: initializing netlink subsys (disabled) [ 0.691810] thermal_sys: Registered thermal governor 'step_wise' [ 0.691810] thermal_sys: Registered thermal governor 'user_space' [ 0.692567] audit: type=2000 audit(1630865413.719:1): state=initialized audit_enabled=0 res=1 [ 0.696569] cpuidle: using governor menu [ 0.701741] ACPI: bus type PCI registered [ 0.703810] PCI: Using configuration type 1 for base access [ 0.706563] PCI: Using configuration type 1 for extended access [ 0.710560] Kprobes globally optimized [ 0.711848] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.739687] ACPI: Added _OSI(Module Device) [ 0.740564] ACPI: Added _OSI(Processor Device) [ 0.741560] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.741566] ACPI: Added _OSI(Processor Aggregator Device) [ 0.742572] ACPI: Added _OSI(Linux-Dell-Video) [ 0.743564] ACPI: Added _OSI(Linux-Lenovo-NV-HDMI-Audio) [ 0.744567] ACPI: Added _OSI(Linux-HPI-Hybrid-Graphics) [ 0.746354] ACPI: 2 ACPI AML tables successfully acquired and loaded [ 0.748560] ACPI: Interpreter enabled [ 0.748572] ACPI: (supports S0 S5) [ 0.749560] ACPI: Using IOAPIC for interrupt routing [ 0.749844] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.750669] ACPI: Enabled 2 GPEs in block 00 to 07 [ 0.755264] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.755568] acpi PNP0A03:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] [ 0.758560] acpi PNP0A03:00: _OSC: platform does not support [PCIeCapability LTR] [ 0.759091] acpi PNP0A03:00: _OSC: not requesting control; platform does not support [PCIeCapability] [ 0.759564] acpi PNP0A03:00: _OSC: OS requested [PME PCIeCapability LTR] [ 0.760564] acpi PNP0A03:00: _OSC: platform willing to grant [PME] [ 0.761564] acpi PNP0A03:00: _OSC failed (AE_SUPPORT); disabling ASPM [ 0.762688] PCI host bridge to bus 0000:00 [ 0.763564] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.764564] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.765564] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.766564] pci_bus 0000:00: root bus resource [mem 0x80000000-0xfdffffff window] [ 0.767565] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.769726] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.771724] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.774156] pci 0000:00:02.0: [80ee:beef] type 00 class 0x030000 [ 0.776560] pci 0000:00:02.0: reg 0x10: [mem 0x80000000-0x80ffffff pref] [ 0.787645] pci 0000:00:02.0: BAR 0: assigned to efifb [ 0.789887] pci 0000:00:03.0: [1af4:1000] type 00 class 0x020000 [ 0.791008] pci 0000:00:03.0: reg 0x10: [io 0xc020-0xc03f] [ 0.793560] pci 0000:00:04.0: [80ee:cafe] type 00 class 0x088000 [ 0.795015] pci 0000:00:04.0: reg 0x10: [io 0xc000-0xc01f] [ 0.795833] pci 0000:00:04.0: reg 0x14: [mem 0x81000000-0x813fffff] [ 0.796821] pci 0000:00:04.0: reg 0x18: [mem 0x81410000-0x81413fff pref] [ 0.799560] pci 0000:00:05.0: [8086:2668] type 00 class 0x040300 [ 0.800186] pci 0000:00:05.0: reg 0x10: [mem 0x81414000-0x81417fff] [ 0.803560] pci 0000:00:07.0: [8086:7113] type 00 class 0x068000 [ 0.804972] pci 0000:00:07.0: quirk: [io 0x4000-0x403f] claimed by PIIX4 ACPI [ 0.805600] pci 0000:00:07.0: quirk: [io 0x4100-0x410f] claimed by PIIX4 SMB [ 0.807597] pci 0000:00:0c.0: [8086:1e31] type 00 class 0x0c0330 [ 0.808957] pci 0000:00:0c.0: reg 0x10: [mem 0x81400000-0x8140ffff] [ 0.811560] pci 0000:00:0d.0: [8086:2829] type 00 class 0x010601 [ 0.812009] pci 0000:00:0d.0: reg 0x10: [io 0xc058-0xc05f] [ 0.812815] pci 0000:00:0d.0: reg 0x14: [io 0xc064-0xc067] [ 0.813773] pci 0000:00:0d.0: reg 0x18: [io 0xc050-0xc057] [ 0.814813] pci 0000:00:0d.0: reg 0x1c: [io 0xc060-0xc063] [ 0.816701] pci 0000:00:0d.0: reg 0x20: [io 0xc040-0xc04f] [ 0.817764] pci 0000:00:0d.0: reg 0x24: [mem 0x81418000-0x81419fff] [ 0.821130] ACPI: PCI Interrupt Link [LNKA] (IRQs 5 9 10 *11) [ 0.822022] ACPI: PCI Interrupt Link [LNKB] (IRQs 5 9 10 *11) [ 0.822722] ACPI: PCI Interrupt Link [LNKC] (IRQs 5 9 *10 11) [ 0.823721] ACPI: PCI Interrupt Link [LNKD] (IRQs 5 9 *10 11) [ 0.825051] iommu: Default domain type: Translated [ 0.825702] pci 0000:00:02.0: vgaarb: setting as boot VGA device [ 0.826560] pci 0000:00:02.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none [ 0.826649] pci 0000:00:02.0: vgaarb: bridge control possible [ 0.827564] vgaarb: loaded [ 0.828640] SCSI subsystem initialized [ 0.830663] libata version 3.00 loaded. [ 0.830687] ACPI: bus type USB registered [ 0.831585] usbcore: registered new interface driver usbfs [ 0.832571] usbcore: registered new interface driver hub [ 0.833581] usbcore: registered new device driver usb [ 0.834585] mc: Linux media interface: v0.10 [ 0.835573] videodev: Linux video capture interface: v2.00 [ 0.836571] pps_core: LinuxPPS API ver. 1 registered [ 0.837564] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.838581] PTP clock support registered [ 0.841616] Registered efivars operations [ 0.842606] Advanced Linux Sound Architecture Driver Initialized. [ 0.843649] Bluetooth: Core ver 2.22 [ 0.844560] NET: Registered protocol family 31 [ 0.844565] Bluetooth: HCI device and connection manager initialized [ 0.845567] Bluetooth: HCI socket layer initialized [ 0.846565] Bluetooth: L2CAP socket layer initialized [ 0.847571] Bluetooth: SCO socket layer initialized [ 0.848570] NetLabel: Initializing [ 0.849560] NetLabel: domain hash size = 128 [ 0.849563] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.850585] NetLabel: unlabeled traffic allowed by default [ 0.852576] PCI: Using ACPI for IRQ routing [ 0.853564] PCI: pci_cache_line_size set to 64 bytes [ 0.854062] e820: reserve RAM buffer [mem 0x7d9b4000-0x7fffffff] [ 0.854064] e820: reserve RAM buffer [mem 0x7de91000-0x7fffffff] [ 0.854065] e820: reserve RAM buffer [mem 0x7e182000-0x7fffffff] [ 0.854065] e820: reserve RAM buffer [mem 0x7ef9b000-0x7fffffff] [ 0.854066] e820: reserve RAM buffer [mem 0x7f46b000-0x7fffffff] [ 0.854377] clocksource: Switched to clocksource kvm-clock [ 0.857830] VFS: Disk quotas dquot_6.6.0 [ 0.951015] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.953578] AppArmor: AppArmor Filesystem Enabled [ 0.955521] pnp: PnP ACPI init [ 0.956986] pnp 00:00: Plug and Play ACPI device, IDs PNP0303 (active) [ 0.957062] pnp 00:01: Plug and Play ACPI device, IDs PNP0f03 (active) [ 0.958424] pnp: PnP ACPI: found 2 devices [ 1.028311] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 1.031428] NET: Registered protocol family 2 [ 1.033041] IP idents hash table entries: 32768 (order: 6, 262144 bytes, linear) [ 1.036053] tcp_listen_portaddr_hash hash table entries: 1024 (order: 2, 16384 bytes, linear) [ 1.039095] TCP established hash table entries: 16384 (order: 5, 131072 bytes, linear) [ 1.041862] TCP bind hash table entries: 16384 (order: 6, 262144 bytes, linear) [ 1.044692] TCP: Hash tables configured (established 16384 bind 16384) [ 1.046977] UDP hash table entries: 1024 (order: 3, 32768 bytes, linear) [ 1.049225] UDP-Lite hash table entries: 1024 (order: 3, 32768 bytes, linear) [ 1.051822] NET: Registered protocol family 1 [ 1.055406] RPC: Registered named UNIX socket transport module. [ 1.057558] RPC: Registered udp transport module. [ 1.059326] RPC: Registered tcp transport module. [ 1.061355] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 1.063833] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 1.066206] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 1.068461] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 1.070937] pci_bus 0000:00: resource 7 [mem 0x80000000-0xfdffffff window] [ 1.073942] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 1.076204] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 1.078786] pci 0000:00:02.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff] [ 1.083761] PCI: CLS 0 bytes, default 64 [ 1.085964] platform rtc_cmos: registered platform RTC device (no PNP device found) [ 1.090461] check: Scanning for low memory corruption every 60 seconds [ 1.093282] Initialise system trusted keyrings [ 1.095745] workingset: timestamp_bits=40 max_order=19 bucket_order=0 [ 1.098929] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ 1.101576] NFS: Registering the id_resolver key type [ 1.103471] Key type id_resolver registered [ 1.105203] Key type id_legacy registered [ 1.107396] fuse: init (API version 7.32) [ 1.114407] Key type asymmetric registered [ 1.116313] Asymmetric key parser 'x509' registered [ 1.118022] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 248) [ 1.121223] io scheduler mq-deadline registered [ 1.122930] io scheduler kyber registered [ 1.125126] hv_vmbus: registering driver hyperv_fb [ 1.126886] efifb: probing for efifb [ 1.128201] efifb: framebuffer at 0x80000000, using 3072k, total 3072k [ 1.130728] efifb: mode is 1024x768x32, linelength=4096, pages=1 [ 1.133331] efifb: scrolling: redraw [ 1.134920] efifb: Truecolor: size=8:8:8:8, shift=24:16:8:0 [ 1.167686] Console: switching to colour frame buffer device 128x48 [ 1.200749] fb0: EFI VGA frame buffer device [ 1.203212] ACPI: AC Adapter [AC] (on-line) [ 1.205696] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 1.208901] ACPI: Power Button [PWRF] [ 1.211020] input: Sleep Button as /devices/LNXSYSTM:00/LNXSLPBN:00/input/input1 [ 1.214475] ACPI: Sleep Button [SLPF] [ 1.216364] ACPI: Video Device [GFX0] (multi-head: yes rom: no post: no) [ 1.219649] input: Video Bus as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0A03:00/LNXVIDEO:00/input/input2 [ 1.225974] virtio-pci 0000:00:03.0: virtio_pci: leaving for legacy driver [ 1.229247] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 1.236500] Non-volatile memory driver v1.3 [ 1.239574] Linux agpgart interface v0.103 [ 1.248943] loop: module loaded [ 1.251231] zram: Added device: zram0 [ 1.253737] zram: Added device: zram1 [ 1.256278] zram: Added device: zram2 [ 1.258563] Guest personality initialized and is inactive [ 1.261847] VMCI host device registered (name=vmci, major=10, minor=62) [ 1.264593] Initialized host personality [ 1.265563] VMware PVSCSI driver - version 1.0.7.0-k [ 1.265563] hv_vmbus: registering driver hv_storvsc [ 1.273386] ahci 0000:00:0d.0: version 3.0 [ 1.274492] ahci 0000:00:0d.0: SSS flag set, parallel bus scan disabled [ 1.278337] ahci 0000:00:0d.0: AHCI 0001.0100 32 slots 1 ports 3 Gbps 0x1 impl SATA mode [ 1.307461] ahci 0000:00:0d.0: flags: 64bit ncq stag only ccc [ 1.337810] scsi host0: ahci [ 1.364798] ata1: SATA max UDMA/133 abar m8192@0x81418000 port 0x81418100 irq 21 [ 1.394983] tun: Universal TUN/TAP device driver, 1.6 [ 1.425513] e100: Intel(R) PRO/100 Network Driver [ 1.453104] e100: Copyright(c) 1999-2006 Intel Corporation [ 1.481717] e1000: Intel(R) PRO/1000 Network Driver [ 1.509107] e1000: Copyright (c) 1999-2006 Intel Corporation. [ 1.536761] e1000e: Intel(R) PRO/1000 Network Driver [ 1.563777] e1000e: Copyright(c) 1999 - 2015 Intel Corporation. [ 1.592797] sky2: driver version 1.30 [ 1.619412] VMware vmxnet3 virtual NIC driver - version 1.5.0.0-k-NAPI [ 1.647549] hv_vmbus: registering driver hv_netvsc [ 1.675609] Fusion MPT base driver 3.04.20 [ 1.703112] Copyright (c) 1999-2008 LSI Corporation [ 1.722016] ata1: SATA link up 3.0 Gbps (SStatus 123 SControl 300) [ 1.730484] Fusion MPT SPI Host driver 3.04.20 [ 1.786899] Fusion MPT SAS Host driver 3.04.20 [ 1.787051] ata1.00: ATA-6: VBOX HARDDISK, 1.0, max UDMA/133 [ 1.812996] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver [ 1.833254] ata1.00: 67108864 sectors, multi 128: LBA48 NCQ (depth 32) [ 1.834121] ehci-pci: EHCI PCI platform driver [ 1.874081] ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver [ 1.902997] ohci-pci: OHCI PCI platform driver [ 1.903269] ata1.00: configured for UDMA/133 [ 1.903653] uhci_hcd: USB Universal Host Controller Interface driver [ 1.923030] scsi 0:0:0:0: Direct-Access ATA VBOX HARDDISK 1.0 PQ: 0 ANSI: 5 [ 1.951987] xhci_hcd 0000:00:0c.0: xHCI Host Controller [ 1.954601] sd 0:0:0:0: [sda] 67108864 512-byte logical blocks: (34.4 GB/32.0 GiB) [ 1.955213] xhci_hcd 0000:00:0c.0: new USB bus registered, assigned bus number 1 [ 1.991955] sd 0:0:0:0: [sda] Write Protect is off [ 1.992828] sd 0:0:0:0: Attached scsi generic sg0 type 0 [ 2.022816] sd 0:0:0:0: [sda] Mode Sense: 00 3a 00 00 [ 2.022846] sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA [ 2.248456] xhci_hcd 0000:00:0c.0: hcc params 0x04000000 hci version 0x100 quirks 0x000000000000b930 [ 2.280484] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 5.10 [ 2.309658] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1 [ 2.310411] usb usb1: Product: xHCI Host Controller [ 2.310411] usb usb1: Manufacturer: Linux 5.10.53 xhci-hcd [ 2.310411] usb usb1: SerialNumber: 0000:00:0c.0 [ 2.336888] hub 1-0:1.0: USB hub found [ 2.364051] sda: sda1 sda2 sda3 sda4 sda5 sda6 sda7 sda8 [ 2.363545] hub 1-0:1.0: 8 ports detected [ 2.392338] sd 0:0:0:0: [sda] Attached SCSI disk [ 2.416174] xhci_hcd 0000:00:0c.0: xHCI Host Controller [ 2.445793] xhci_hcd 0000:00:0c.0: new USB bus registered, assigned bus number 2 [ 2.474184] xhci_hcd 0000:00:0c.0: Host supports USB 3.0 SuperSpeed [ 2.475108] usb usb2: New USB device found, idVendor=1d6b, idProduct=0003, bcdDevice= 5.10 [ 2.475108] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1 [ 2.475108] usb usb2: Product: xHCI Host Controller [ 2.475108] usb usb2: Manufacturer: Linux 5.10.53 xhci-hcd [ 2.475108] usb usb2: SerialNumber: 0000:00:0c.0 [ 2.503489] hub 2-0:1.0: USB hub found [ 2.531060] hub 2-0:1.0: 6 ports detected [ 2.757101] usbcore: registered new interface driver usblp [ 2.785694] usbcore: registered new interface driver usb-storage [ 2.785694] usbcore: registered new interface driver usbserial_generic [ 2.785694] usbserial: USB Serial support registered for generic [ 2.785694] i8042: PNP: PS/2 Controller [PNP0303:PS2K,PNP0f03:PS2M] at 0x60,0x64 irq 1,12 [ 2.824527] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 2.825088] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 2.856578] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input3 [ 2.859424] rtc_cmos rtc_cmos: registered as rtc0 [ 2.890359] usb 1-1: new full-speed USB device number 2 using xhci_hcd [ 2.914200] rtc_cmos rtc_cmos: alarms up to one day, 114 bytes nvram [ 2.968130] device-mapper: ioctl: 4.43.0-ioctl (2020-10-01) initialised: dm-devel@redhat.com [ 2.998102] usbcore: registered new interface driver btusb [ 3.026836] EFI Variables Facility v0.08 2004-May-17 [ 3.056509] hid: raw HID events driver (C) Jiri Kosina [ 3.085294] usbcore: registered new interface driver usbhid [ 3.114710] usbhid: USB HID core driver [ 3.143732] IPVS: Registered protocols () [ 3.171903] IPVS: Connection hash table configured (size=4096, memory=64Kbytes) [ 3.203317] IPVS: ipvs loaded. [ 3.230378] IPVS: [rr] scheduler registered. [ 3.261514] NET: Registered protocol family 10 [ 3.291816] Segment Routing with IPv6 [ 3.320173] usb 1-1: New USB device found, idVendor=1a86, idProduct=7523, bcdDevice= 2.63 [ 3.339996] usb 1-1: New USB device strings: Mfr=0, Product=2, SerialNumber=0 [ 3.340964] usb 1-1: Product: USB2.0-Serial [ 3.398456] sit: IPv6, IPv4 and MPLS over IPv4 tunneling driver [ 3.430047] NET: Registered protocol family 17 [ 3.430063] Bridge firewalling registered [ 3.430123] Key type dns_resolver registered [ 3.517487] IPI shorthand broadcast: enabled [ 3.546966] sched_clock: Marking stable (3285467670, 261143073)->(3816714115, -270103372) [ 3.578998] registered taskstats version 1 [ 3.606846] Loading compiled-in X.509 certificates [ 3.635402] AppArmor: AppArmor sha1 policy hashing enabled [ 3.665589] PM: Magic number: 5:815:88 [ 3.692970] printk: console [netcon0] enabled [ 3.720943] netconsole: network logging started [ 3.750335] ALSA device list: [ 3.778571] No soundcards found. [ 4.000826] random: fast init done [ 4.031037] input: ImExPS/2 Generic Explorer Mouse as /devices/platform/i8042/serio1/input/input5 [ 4.061422] md: Waiting for all devices to be available before autodetect [ 4.088314] md: If you don't use raid, use raid=noautodetect [ 4.116195] md: Autodetecting RAID arrays. [ 4.143943] md: autorun ... [ 4.171100] md: ... autorun DONE. [ 4.204120] VFS: Mounted root (squashfs filesystem) readonly on device 8:3. [ 4.235567] devtmpfs: mounted [ 4.261644] Freeing unused kernel image (initmem) memory: 1396K [ 4.291447] Write protecting the kernel read-only data: 22528k [ 4.320325] Freeing unused kernel image (text/rodata gap) memory: 2036K [ 4.349959] Freeing unused kernel image (rodata/data gap) memory: 184K [ 4.378407] Run /sbin/init as init process [ 4.406846] with arguments: [ 4.406846] /sbin/init [ 4.406847] with environment: [ 4.406847] HOME=/ [ 4.406847] TERM=linux [ 4.524031] 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) [ 4.584033] systemd[1]: Detected virtualization oracle. [ 4.613523] systemd[1]: Detected architecture x86-64. [ 4.746111] systemd[1]: Set hostname to . [ 4.775550] systemd[1]: Installed transient /etc/machine-id file. [ 4.909855] systemd[1]: Queued start job for default target Multi-User System. [ 4.938528] random: systemd: uninitialized urandom read (16 bytes read) [ 4.969474] systemd[1]: Created slice system-ha\x2dcli.slice. [ 5.024406] random: systemd: uninitialized urandom read (16 bytes read) [ 5.054443] systemd[1]: Created slice system-modprobe.slice. [ 5.110271] random: systemd: uninitialized urandom read (16 bytes read) [ 5.140388] systemd[1]: Created slice system-systemd\x2dfsck.slice. [ 5.197264] systemd[1]: Created slice system-systemd\x2dgrowfs.slice. [ 5.251788] systemd[1]: Created slice User and Session Slice. [ 5.262229] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. [ 5.265781] systemd[1]: Started Forward Password Requests to Wall Directory Watch. [ 5.269267] systemd[1]: Reached target Local Encrypted Volumes. [ 5.272256] systemd[1]: Reached target HassOS hardware targets. [ 5.275210] systemd[1]: Reached target HassOS zram targets. [ 5.278095] systemd[1]: Reached target Paths. [ 5.280593] systemd[1]: Reached target Remote Encrypted Volumes. [ 5.283573] systemd[1]: Reached target Remote File Systems. [ 5.286476] systemd[1]: Reached target Slices. [ 5.289771] systemd[1]: Listening on D-Bus System Message Bus Socket. [ 5.297084] systemd[1]: Listening on Process Core Dump Socket. [ 5.351764] systemd[1]: Listening on Journal Audit Socket. [ 5.405715] systemd[1]: Listening on Journal Socket (/dev/log). [ 5.461422] systemd[1]: Listening on Journal Socket. [ 5.518144] systemd[1]: Listening on udev Control Socket. [ 5.574002] systemd[1]: Listening on udev Kernel Socket. [ 5.628784] systemd[1]: Mounting Huge Pages File System... [ 5.693623] systemd[1]: Mounting POSIX Message Queue File System... [ 5.753576] systemd[1]: Mounting Kernel Debug File System... [ 5.812887] systemd[1]: Mounting Kernel Trace File System... [ 5.873209] systemd[1]: Started D-Bus System Message Bus. [ 5.961001] systemd[1]: Starting Home Assistant OS Agent... [ 6.054897] systemd[1]: Condition check resulted in Create list of static device nodes for the current kernel being skipped. [ 6.089940] systemd[1]: Starting Load Kernel Module configfs... [ 6.150869] systemd[1]: Starting Load Kernel Module drm... [ 6.212154] systemd[1]: Starting Load Kernel Module fuse... [ 6.274567] systemd[1]: Starting Journal Service... [ 6.338072] systemd[1]: Starting Remount Root and Kernel File Systems... [ 6.400119] systemd[1]: Starting Wait Until Kernel Time Synchronized... [ 6.463939] systemd[1]: Starting Create Static Device Nodes in /dev... [ 6.531040] systemd[1]: Starting Coldplug All udev Devices... [ 6.597480] systemd[1]: Starting Disk Manager... [ 6.599058] random: crng init done [ 6.654646] random: 7 urandom warning(s) missed due to ratelimiting [ 6.704470] systemd[1]: Starting HassOS ZRAM swap... [ 6.745012] zram0: detected capacity change from 0 to 516673536 [ 6.813380] systemd[1]: Starting HassOS ZRAM tmp... [ 6.815729] zram2: detected capacity change from 0 to 16777216 [ 6.910630] systemd[1]: Starting HassOS ZRAM var... [ 6.913920] zram1: detected capacity change from 0 to 33554432 [ 6.994770] systemd[1]: Started Journal Service. [ 7.536092] audit: type=1334 audit(1630865420.562:2): prog-id=5 op=LOAD [ 7.564983] audit: type=1334 audit(1630865420.563:3): prog-id=6 op=LOAD [ 7.787486] virtio_net virtio0 enp0s3: renamed from eth0 [ 7.792275] usbcore: registered new interface driver ch341 [ 7.792281] usbserial: USB Serial support registered for ch341-uart [ 7.792287] ch341 1-1:1.0: ch341-uart converter detected [ 7.808160] usb 1-1: ch341-uart converter now attached to ttyUSB0 [ 8.057330] snd_hda_codec_generic hdaudioC0D0: autoconfig for Generic: line_outs=3 (0xc/0xf/0xb/0x0/0x0) type:speaker [ 8.057332] snd_hda_codec_generic hdaudioC0D0: speaker_outs=0 (0x0/0x0/0x0/0x0/0x0) [ 8.057333] snd_hda_codec_generic hdaudioC0D0: hp_outs=1 (0xa/0x0/0x0/0x0/0x0) [ 8.057333] snd_hda_codec_generic hdaudioC0D0: mono: mono_out=0x0 [ 8.057334] snd_hda_codec_generic hdaudioC0D0: dig-out=0x10/0x0 [ 8.057334] snd_hda_codec_generic hdaudioC0D0: inputs: [ 8.057335] snd_hda_codec_generic hdaudioC0D0: Mic=0xd [ 8.057335] snd_hda_codec_generic hdaudioC0D0: Line=0xe [ 8.057336] snd_hda_codec_generic hdaudioC0D0: CD=0x15 [ 8.057336] snd_hda_codec_generic hdaudioC0D0: dig-in=0x11 [ 8.243898] input: HDA Intel Mic as /devices/pci0000:00/0000:00:05.0/sound/card0/input6 [ 8.243922] input: HDA Intel Line as /devices/pci0000:00/0000:00:05.0/sound/card0/input7 [ 8.243940] input: HDA Intel Speaker Front as /devices/pci0000:00/0000:00:05.0/sound/card0/input8 [ 8.243957] input: HDA Intel Speaker CLFE as /devices/pci0000:00/0000:00:05.0/sound/card0/input9 [ 8.243975] input: HDA Intel Front Headphone as /devices/pci0000:00/0000:00:05.0/sound/card0/input10 [ 8.243992] input: HDA Intel SPDIF In as /devices/pci0000:00/0000:00:05.0/sound/card0/input11 [ 8.847779] Adding 504560k swap on /dev/zram0. Priority:-2 extents:1 across:504560k SS [ 8.925905] EXT4-fs (zram1): mounted filesystem without journal. Opts: nobarrier [ 8.925909] ext4 filesystem being mounted at /var supports timestamps until 2038 (0x7fffffff) [ 9.298711] EXT4-fs (zram2): mounted filesystem without journal. Opts: nobarrier [ 9.298716] ext4 filesystem being mounted at /tmp supports timestamps until 2038 (0x7fffffff) [ 9.397517] EXT4-fs (sda7): mounted filesystem with ordered data mode. Opts: (null) [ 9.397524] ext4 filesystem being mounted at /mnt/overlay supports timestamps until 2038 (0x7fffffff) [ 10.383074] EXT4-fs (sda8): mounted filesystem with ordered data mode. Opts: (null) [ 10.905120] EXT4-fs (sda8): resizing filesystem from 8210171 to 8210171 blocks [ 11.188046] systemd-journald[103]: Received client request to flush runtime journal. [ 12.081011] audit: type=1400 audit(1630865425.102:4): apparmor="STATUS" operation="profile_load" profile="unconfined" name="hassio-supervisor" pid=305 comm="apparmor_parser" [ 12.081013] audit: type=1400 audit(1630865425.102:4): apparmor="STATUS" operation="profile_load" profile="unconfined" name="hassio-supervisor///usr/bin/gdbus" pid=305 comm="apparmor_parser" [ 12.081014] audit: type=1400 audit(1630865425.102:4): apparmor="STATUS" operation="profile_load" profile="unconfined" name="hassio-supervisor///usr/bin/git" pid=305 comm="apparmor_parser" [ 12.203762] audit: type=1334 audit(1630865425.230:5): prog-id=7 op=LOAD [ 12.203765] audit: type=1334 audit(1630865425.231:6): prog-id=8 op=LOAD [ 13.076845] cfg80211: Loading compiled-in X.509 certificates for regulatory database [ 13.091825] cfg80211: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' [ 14.341822] audit: type=1334 audit(1630865427.369:7): prog-id=9 op=LOAD [ 14.342423] audit: type=1334 audit(1630865427.369:8): prog-id=10 op=LOAD [ 15.727205] audit: type=1400 audit(1630865428.751:9): apparmor="STATUS" operation="profile_load" profile="unconfined" name="docker-default" pid=365 comm="apparmor_parser" [ 16.149830] audit: type=1325 audit(1630865429.176:10): table=nat family=2 entries=5 op=xt_replace pid=387 subj==unconfined comm="iptables" [ 16.152513] audit: type=1325 audit(1630865429.179:11): table=filter family=2 entries=4 op=xt_replace pid=389 subj==unconfined comm="iptables" [ 16.154764] audit: type=1325 audit(1630865429.182:12): table=filter family=2 entries=6 op=xt_replace pid=391 subj==unconfined comm="iptables" [ 16.157236] audit: type=1325 audit(1630865429.184:13): table=filter family=2 entries=8 op=xt_replace pid=393 subj==unconfined comm="iptables" [ 16.159739] audit: type=1325 audit(1630865429.187:14): table=filter family=2 entries=10 op=xt_replace pid=395 subj==unconfined comm="iptables" [ 16.162236] audit: type=1325 audit(1630865429.189:15): table=filter family=2 entries=11 op=xt_replace pid=397 subj==unconfined comm="iptables" [ 16.162402] ------------[ cut here ]------------ [ 16.162403] netdevice: enp0s3: failed to disable LRO! [ 16.162471] WARNING: CPU: 1 PID: 348 at net/core/dev.c:1694 dev_disable_lro+0xad/0xf0 [ 16.162472] Modules linked in: cfg80211 snd_hda_codec_generic ledtrig_audio ch341 snd_hda_intel snd_intel_dspcfg snd_hda_codec snd_hwdep snd_hda_core snd_pcm [ 16.162479] CPU: 1 PID: 348 Comm: dockerd Not tainted 5.10.53 #1 [ 16.162480] Hardware name: innotek GmbH VirtualBox/VirtualBox, BIOS VirtualBox 12/01/2006 [ 16.162482] RIP: 0010:dev_disable_lro+0xad/0xf0 [ 16.162483] Code: 4f bb 74 14 be 25 00 00 00 48 89 df e8 8c 63 ac ff 48 85 c0 48 0f 44 eb 4c 89 e2 48 89 ee 48 c7 c7 c0 aa 53 bb e8 24 2d 23 00 <0f> 0b e9 6c ff ff ff 80 3d 5a 8b e2 00 00 49 c7 c4 c2 ef 4f bb 75 [ 16.162484] RSP: 0018:ffffa04800b2fd98 EFLAGS: 00010282 [ 16.162485] RAX: 0000000000000000 RBX: ffff8ab181db5000 RCX: 0000000000000027 [ 16.162485] RDX: 0000000000000027 RSI: 00000000ffffdfff RDI: ffff8ab1fd7188c8 [ 16.162486] RBP: ffff8ab181db5000 R08: ffff8ab1fd7188c0 R09: ffffa04800b2fbb8 [ 16.162486] R10: 0000000000000001 R11: 0000000000000001 R12: ffffffffbb45f067 [ 16.162487] R13: 0000000000000000 R14: ffffffffbb818190 R15: ffff8ab1812d90c8 [ 16.162488] FS: 00007fde55bd2640(0000) GS:ffff8ab1fd700000(0000) knlGS:0000000000000000 [ 16.162488] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033 [ 16.162489] CR2: 000000c000c45000 CR3: 0000000008162000 CR4: 00000000000106e0 [ 16.162490] Call Trace: [ 16.162495] devinet_sysctl_forward+0x1ac/0x1e0 [ 16.162499] proc_sys_call_handler+0x129/0x230 [ 16.162502] new_sync_write+0x11a/0x1b0 [ 16.162503] vfs_write+0x1bb/0x270 [ 16.162504] ksys_write+0x5a/0xd0 [ 16.162507] do_syscall_64+0x33/0x40 [ 16.162509] entry_SYSCALL_64_after_hwframe+0x44/0xa9 [ 16.162510] RIP: 0033:0x10cf2db [ 16.162511] Code: fa ff eb bd e8 26 46 fa ff e9 61 ff ff ff cc e8 fb 0a fa ff 48 8b 7c 24 10 48 8b 74 24 18 48 8b 54 24 20 48 8b 44 24 08 0f 05 <48> 3d 01 f0 ff ff 76 20 48 c7 44 24 28 ff ff ff ff 48 c7 44 24 30 [ 16.162512] RSP: 002b:000000c0009fe738 EFLAGS: 00000216 ORIG_RAX: 0000000000000001 [ 16.162513] RAX: ffffffffffffffda RBX: 000000c00005a000 RCX: 00000000010cf2db [ 16.162513] RDX: 0000000000000002 RSI: 000000c0009fe9de RDI: 000000000000000e [ 16.162513] RBP: 000000c0009fe788 R08: 000000c0009fe801 R09: 0000000000000004 [ 16.162514] R10: 00007fde3efcf940 R11: 0000000000000216 R12: 00000000000000f2 [ 16.162514] R13: 0000000000000000 R14: 00000000034a11f0 R15: 0000000000000000 [ 16.162515] ---[ end trace 6ada5c4c23dc000b ]--- [ 16.164411] audit: type=1325 audit(1630865429.191:16): table=filter family=2 entries=12 op=xt_replace pid=398 subj==unconfined comm="iptables" [ 16.174093] Initializing XFRM netlink socket [ 16.364056] hassio: port 1(veth8d5f43d) entered blocking state [ 16.364057] hassio: port 1(veth8d5f43d) entered disabled state [ 16.364122] device veth8d5f43d entered promiscuous mode [ 16.364461] hassio: port 1(veth8d5f43d) entered blocking state [ 16.364462] hassio: port 1(veth8d5f43d) entered forwarding state [ 16.364826] hassio: port 1(veth8d5f43d) entered disabled state [ 16.712144] cgroup: cgroup: disabling cgroup2 socket matching due to net_prio or net_cls activation [ 17.121861] eth0: renamed from veth2546652 [ 17.128779] IPv6: ADDRCONF(NETDEV_CHANGE): veth8d5f43d: link becomes ready [ 17.128847] hassio: port 1(veth8d5f43d) entered blocking state [ 17.128849] hassio: port 1(veth8d5f43d) entered forwarding state [ 17.128873] IPv6: ADDRCONF(NETDEV_CHANGE): hassio: link becomes ready [ 43.226907] docker0: port 1(vetha5bd6ff) entered blocking state [ 43.226909] docker0: port 1(vetha5bd6ff) entered disabled state [ 43.227227] device vetha5bd6ff entered promiscuous mode [ 43.227305] kauditd_printk_skb: 56 callbacks suppressed [ 43.227306] audit: type=1700 audit(1630865152.145:73): dev=vetha5bd6ff prom=256 old_prom=0 auid=4294967295 uid=0 gid=0 ses=4294967295 [ 43.262208] hassio: port 2(veth5e11be0) entered blocking state [ 43.262210] hassio: port 2(veth5e11be0) entered disabled state [ 43.262240] device veth5e11be0 entered promiscuous mode [ 43.262260] audit: type=1700 audit(1630865152.180:74): dev=veth5e11be0 prom=256 old_prom=0 auid=4294967295 uid=0 gid=0 ses=4294967295 [ 43.265729] hassio: port 2(veth5e11be0) entered blocking state [ 43.265731] hassio: port 2(veth5e11be0) entered forwarding state [ 43.639025] eth0: renamed from veth893bebf [ 43.643905] hassio: port 2(veth5e11be0) entered disabled state [ 43.643974] IPv6: ADDRCONF(NETDEV_CHANGE): vetha5bd6ff: link becomes ready [ 43.643989] docker0: port 1(vetha5bd6ff) entered blocking state [ 43.643989] docker0: port 1(vetha5bd6ff) entered forwarding state [ 43.644002] IPv6: ADDRCONF(NETDEV_CHANGE): docker0: link becomes ready [ 43.705873] audit: type=1325 audit(1630865152.623:75): table=nat family=2 entries=0 op=xt_register pid=917 subj==unconfined comm="iptables" [ 43.710735] audit: type=1325 audit(1630865152.628:76): table=filter family=2 entries=0 op=xt_register pid=918 subj==unconfined comm="iptables" [ 43.715111] audit: type=1325 audit(1630865152.633:77): table=nat family=2 entries=5 op=xt_replace pid=921 subj==unconfined comm="iptables" [ 43.716509] audit: type=1325 audit(1630865152.634:78): table=nat family=2 entries=7 op=xt_replace pid=922 subj==unconfined comm="iptables" [ 43.718991] audit: type=1325 audit(1630865152.636:79): table=nat family=2 entries=8 op=xt_replace pid=924 subj==unconfined comm="iptables" [ 43.720552] audit: type=1325 audit(1630865152.638:80): table=nat family=2 entries=10 op=xt_replace pid=925 subj==unconfined comm="iptables" [ 43.722371] audit: type=1325 audit(1630865152.640:81): table=nat family=2 entries=11 op=xt_replace pid=926 subj==unconfined comm="iptables" [ 43.724062] audit: type=1325 audit(1630865152.642:82): table=nat family=2 entries=12 op=xt_replace pid=927 subj==unconfined comm="iptables" [ 43.733816] eth1: renamed from vetha1c821b [ 43.745956] IPv6: ADDRCONF(NETDEV_CHANGE): veth5e11be0: link becomes ready [ 43.745974] hassio: port 2(veth5e11be0) entered blocking state [ 43.745975] hassio: port 2(veth5e11be0) entered forwarding state [ 46.742043] hassio: port 3(veth97e8b2a) entered blocking state [ 46.742046] hassio: port 3(veth97e8b2a) entered disabled state [ 46.742130] device veth97e8b2a entered promiscuous mode [ 47.161678] eth0: renamed from veth78b1fdb [ 47.167744] IPv6: ADDRCONF(NETDEV_CHANGE): veth97e8b2a: link becomes ready [ 47.167758] hassio: port 3(veth97e8b2a) entered blocking state [ 47.167758] hassio: port 3(veth97e8b2a) entered forwarding state [ 47.497174] hassio: port 4(veth7c239fd) entered blocking state [ 47.497176] hassio: port 4(veth7c239fd) entered disabled state [ 47.497222] device veth7c239fd entered promiscuous mode [ 47.497322] hassio: port 4(veth7c239fd) entered blocking state [ 47.497324] hassio: port 4(veth7c239fd) entered forwarding state [ 47.742343] hassio: port 4(veth7c239fd) entered disabled state [ 48.037236] eth0: renamed from veth694d5be [ 48.041802] IPv6: ADDRCONF(NETDEV_CHANGE): veth7c239fd: link becomes ready [ 48.041886] hassio: port 4(veth7c239fd) entered blocking state [ 48.041887] hassio: port 4(veth7c239fd) entered forwarding state [ 48.226755] hassio: port 5(vethcd3c745) entered blocking state [ 48.226757] hassio: port 5(vethcd3c745) entered disabled state [ 48.226827] device vethcd3c745 entered promiscuous mode [ 48.227290] hassio: port 5(vethcd3c745) entered blocking state [ 48.227291] hassio: port 5(vethcd3c745) entered forwarding state [ 48.487170] kauditd_printk_skb: 34 callbacks suppressed [ 48.487171] audit: type=1325 audit(1630865157.402:115): table=nat family=2 entries=0 op=xt_register pid=2395 subj==unconfined comm="iptables" [ 48.492395] audit: type=1325 audit(1630865157.410:116): table=filter family=2 entries=0 op=xt_register pid=2397 subj==unconfined comm="iptables" [ 48.503819] audit: type=1325 audit(1630865157.421:117): table=nat family=2 entries=5 op=xt_replace pid=2405 subj==unconfined comm="iptables" [ 48.508403] audit: type=1325 audit(1630865157.426:118): table=nat family=2 entries=7 op=xt_replace pid=2406 subj==unconfined comm="iptables" [ 48.513742] audit: type=1325 audit(1630865157.431:119): table=nat family=2 entries=8 op=xt_replace pid=2410 subj==unconfined comm="iptables" [ 48.515132] audit: type=1325 audit(1630865157.433:120): table=nat family=2 entries=10 op=xt_replace pid=2417 subj==unconfined comm="iptables" [ 48.516337] audit: type=1325 audit(1630865157.434:121): table=nat family=2 entries=11 op=xt_replace pid=2419 subj==unconfined comm="iptables" [ 48.517649] audit: type=1325 audit(1630865157.435:122): table=nat family=2 entries=12 op=xt_replace pid=2423 subj==unconfined comm="iptables" [ 48.518969] audit: type=1325 audit(1630865157.437:123): table=nat family=2 entries=13 op=xt_replace pid=2426 subj==unconfined comm="iptables" [ 48.521314] audit: type=1325 audit(1630865157.439:124): table=nat family=2 entries=14 op=xt_replace pid=2432 subj==unconfined comm="iptables" [ 48.540292] eth0: renamed from vethf7e59dc [ 48.547006] IPv6: ADDRCONF(NETDEV_CHANGE): vethcd3c745: link becomes ready [ 51.885703] hassio: port 6(veth48c6b3d) entered blocking state [ 51.885704] hassio: port 6(veth48c6b3d) entered disabled state [ 51.885784] device veth48c6b3d entered promiscuous mode [ 52.378166] eth0: renamed from vethd486142 [ 52.382062] IPv6: ADDRCONF(NETDEV_CHANGE): veth48c6b3d: link becomes ready [ 52.382078] hassio: port 6(veth48c6b3d) entered blocking state [ 52.382079] hassio: port 6(veth48c6b3d) entered forwarding state [ 68.710049] hassio: port 7(vethcc45cd9) entered blocking state [ 68.710051] hassio: port 7(vethcc45cd9) entered disabled state [ 68.714455] device vethcc45cd9 entered promiscuous mode [ 68.714495] kauditd_printk_skb: 11 callbacks suppressed [ 68.714496] audit: type=1700 audit(1630865177.628:136): dev=vethcc45cd9 prom=256 old_prom=0 auid=4294967295 uid=0 gid=0 ses=4294967295 [ 69.218486] audit: type=1325 audit(1630865178.136:137): table=nat family=2 entries=0 op=xt_register pid=3852 subj==unconfined comm="iptables" [ 69.223202] audit: type=1325 audit(1630865178.141:138): table=filter family=2 entries=0 op=xt_register pid=3853 subj==unconfined comm="iptables" [ 69.226908] audit: type=1325 audit(1630865178.145:139): table=nat family=2 entries=5 op=xt_replace pid=3856 subj==unconfined comm="iptables" [ 69.228051] audit: type=1325 audit(1630865178.146:140): table=nat family=2 entries=7 op=xt_replace pid=3857 subj==unconfined comm="iptables" [ 69.230246] audit: type=1325 audit(1630865178.148:141): table=nat family=2 entries=8 op=xt_replace pid=3859 subj==unconfined comm="iptables" [ 69.232294] audit: type=1325 audit(1630865178.150:142): table=nat family=2 entries=10 op=xt_replace pid=3860 subj==unconfined comm="iptables" [ 69.234243] audit: type=1325 audit(1630865178.151:143): table=nat family=2 entries=11 op=xt_replace pid=3861 subj==unconfined comm="iptables" [ 69.235987] audit: type=1325 audit(1630865178.154:144): table=nat family=2 entries=12 op=xt_replace pid=3862 subj==unconfined comm="iptables" [ 69.237280] audit: type=1325 audit(1630865178.154:145): table=nat family=2 entries=13 op=xt_replace pid=3863 subj==unconfined comm="iptables" [ 69.246905] eth0: renamed from vetha25d396 [ 69.251409] IPv6: ADDRCONF(NETDEV_CHANGE): vethcc45cd9: link becomes ready [ 69.251429] hassio: port 7(vethcc45cd9) entered blocking state [ 69.251429] hassio: port 7(vethcc45cd9) entered forwarding state ```
Logs 6.3 ``` [ 0.000000] Linux version 5.10.61 (builder@a284ecc27abd) (x86_64-buildroot-linux-gnu-gcc.br_real (Buildroot -gf0b9df90) 9.4.0, GNU ld (GNU Binutils) 2.35.2) #1 SMP Fri Sep 3 22:51:08 UTC 2021 [ 0.000000] Command line: apparmor=1 security=apparmor console=ttyS0 console=tty1 bootchooser.active=B root=PARTUUID=a3ec664e-32ce-4665-95ea-7ae90ce9aa20 rootfstype=squashfs ro rootwait systemd.machine_id=f1b18bd3ca2b40d391fa721de534405b zram.enabled=1 zram.num_devices=3 [ 0.000000] [Firmware Bug]: TSC doesn't count with P0 frequency! [ 0.000000] x86/fpu: x87 FPU will use FXSAVE [ 0.000000] BIOS-provided physical RAM map: [ 0.000000] BIOS-e820: [mem 0x0000000000000000-0x000000000009ffff] usable [ 0.000000] BIOS-e820: [mem 0x0000000000100000-0x000000007d9b3fff] usable [ 0.000000] BIOS-e820: [mem 0x000000007d9b4000-0x000000007d9b4fff] ACPI data [ 0.000000] BIOS-e820: [mem 0x000000007d9b5000-0x000000007de90fff] usable [ 0.000000] BIOS-e820: [mem 0x000000007de91000-0x000000007df1afff] reserved [ 0.000000] BIOS-e820: [mem 0x000000007df1b000-0x000000007ef9afff] usable [ 0.000000] BIOS-e820: [mem 0x000000007ef9b000-0x000000007eff2fff] reserved [ 0.000000] BIOS-e820: [mem 0x000000007eff3000-0x000000007effafff] ACPI data [ 0.000000] BIOS-e820: [mem 0x000000007effb000-0x000000007effefff] ACPI NVS [ 0.000000] BIOS-e820: [mem 0x000000007efff000-0x000000007f46afff] usable [ 0.000000] BIOS-e820: [mem 0x000000007f46b000-0x000000007ffeffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000ffc00000-0x00000000ffffffff] reserved [ 0.000000] NX (Execute Disable) protection: active [ 0.000000] efi: EFI v2.70 by EDK II [ 0.000000] efi: ACPI=0x7effa000 ACPI 2.0=0x7effa014 SMBIOS=0x7effd000 [ 0.000000] SMBIOS 2.5 present. [ 0.000000] DMI: innotek GmbH VirtualBox/VirtualBox, BIOS VirtualBox 12/01/2006 [ 0.000000] Hypervisor detected: KVM [ 0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00 [ 0.000000] kvm-clock: cpu 0, msr 4810d001, primary cpu clock [ 0.000000] kvm-clock: using sched offset of 3939578034 cycles [ 0.000003] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns [ 0.000005] tsc: Detected 3593.254 MHz processor [ 0.002448] e820: update [mem 0x00000000-0x00000fff] usable ==> reserved [ 0.002451] e820: remove [mem 0x000a0000-0x000fffff] usable [ 0.002454] last_pfn = 0x7f46b max_arch_pfn = 0x400000000 [ 0.002582] MTRR default type: uncachable [ 0.002583] MTRR fixed ranges enabled: [ 0.002584] 00000-9FFFF write-back [ 0.002584] A0000-FFFFF uncachable [ 0.002585] MTRR variable ranges enabled: [ 0.002586] 0 base 000000000000 mask FFFF80000000 write-back [ 0.002588] 1 base 00007FFF0000 mask FFFFFFFF0000 uncachable [ 0.002588] 2 disabled [ 0.002589] 3 disabled [ 0.002589] 4 disabled [ 0.002590] 5 disabled [ 0.002590] 6 disabled [ 0.002590] 7 disabled [ 0.002601] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT [ 0.002642] found SMP MP-table at [mem 0x0009fff0-0x0009ffff] [ 0.003014] check: Scanning 1 areas for low memory corruption [ 0.003498] Secure boot disabled [ 0.003502] ACPI: Early table checksum verification disabled [ 0.003506] ACPI: RSDP 0x000000007EFFA014 000024 (v02 VBOX ) [ 0.003510] ACPI: XSDT 0x000000007EFF90E8 000044 (v01 VBOX VBOXFACP 00000001 01000013) [ 0.003515] ACPI: FACP 0x000000007EFF7000 0000F4 (v04 VBOX VBOXFACP 00000001 ASL 00000061) [ 0.003520] ACPI: DSDT 0x000000007EFF4000 002325 (v02 VBOX VBOXBIOS 00000002 INTL 20100528) [ 0.003523] ACPI: FACS 0x000000007EFFE000 000040 [ 0.003525] ACPI: APIC 0x000000007EFF8000 00005C (v02 VBOX VBOXAPIC 00000001 ASL 00000061) [ 0.003528] ACPI: SSDT 0x000000007EFF3000 0001CC (v01 VBOX VBOXCPUT 00000002 INTL 20100528) [ 0.003530] ACPI: BGRT 0x000000007D9B4000 000038 (v01 INTEL EDK2 00000002 01000013) [ 0.003533] ACPI: Reserving FACP table memory at [mem 0x7eff7000-0x7eff70f3] [ 0.003534] ACPI: Reserving DSDT table memory at [mem 0x7eff4000-0x7eff6324] [ 0.003535] ACPI: Reserving FACS table memory at [mem 0x7effe000-0x7effe03f] [ 0.003536] ACPI: Reserving APIC table memory at [mem 0x7eff8000-0x7eff805b] [ 0.003537] ACPI: Reserving SSDT table memory at [mem 0x7eff3000-0x7eff31cb] [ 0.003538] ACPI: Reserving BGRT table memory at [mem 0x7d9b4000-0x7d9b4037] [ 0.003577] ACPI: Local APIC address 0xfee00000 [ 0.004708] No NUMA configuration found [ 0.004710] Faking a node at [mem 0x0000000000000000-0x000000007f46afff] [ 0.004713] NODE_DATA(0) allocated [mem 0x7f213000-0x7f217fff] [ 0.004923] Zone ranges: [ 0.004925] DMA [mem 0x0000000000001000-0x0000000000ffffff] [ 0.004926] DMA32 [mem 0x0000000001000000-0x000000007f46afff] [ 0.004927] Normal empty [ 0.004928] Device empty [ 0.004929] Movable zone start for each node [ 0.004930] Early memory node ranges [ 0.004931] node 0: [mem 0x0000000000001000-0x000000000009ffff] [ 0.004931] node 0: [mem 0x0000000000100000-0x000000007d9b3fff] [ 0.004932] node 0: [mem 0x000000007d9b5000-0x000000007de90fff] [ 0.004933] node 0: [mem 0x000000007df1b000-0x000000007ef9afff] [ 0.004933] node 0: [mem 0x000000007efff000-0x000000007f46afff] [ 0.004935] Initmem setup node 0 [mem 0x0000000000001000-0x000000007f46afff] [ 0.004936] On node 0 totalpages: 520987 [ 0.004936] DMA zone: 64 pages used for memmap [ 0.004937] DMA zone: 23 pages reserved [ 0.004937] DMA zone: 3999 pages, LIFO batch:0 [ 0.004938] DMA32 zone: 8082 pages used for memmap [ 0.004938] DMA32 zone: 516988 pages, LIFO batch:63 [ 0.005022] On node 0, zone DMA: 1 pages in unavailable ranges [ 0.005990] On node 0, zone DMA: 96 pages in unavailable ranges [ 0.172155] On node 0, zone DMA32: 1 pages in unavailable ranges [ 0.173275] On node 0, zone DMA32: 138 pages in unavailable ranges [ 0.173606] On node 0, zone DMA32: 100 pages in unavailable ranges [ 0.174329] On node 0, zone DMA32: 2965 pages in unavailable ranges [ 0.176218] ACPI: PM-Timer IO Port: 0xb008 [ 0.176222] ACPI: Local APIC address 0xfee00000 [ 0.176479] IOAPIC[0]: apic_id 2, version 32, address 0xfec00000, GSI 0-23 [ 0.176484] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) [ 0.176485] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 low level) [ 0.176486] ACPI: IRQ0 used by override. [ 0.176487] ACPI: IRQ9 used by override. [ 0.176488] Using ACPI (MADT) for SMP configuration information [ 0.176496] e820: update [mem 0x7e182000-0x7e19cfff] usable ==> reserved [ 0.176529] smpboot: Allowing 2 CPUs, 0 hotplug CPUs [ 0.176791] PM: hibernation: Registered nosave memory: [mem 0x00000000-0x00000fff] [ 0.176794] PM: hibernation: Registered nosave memory: [mem 0x000a0000-0x000fffff] [ 0.176795] PM: hibernation: Registered nosave memory: [mem 0x7d9b4000-0x7d9b4fff] [ 0.176796] PM: hibernation: Registered nosave memory: [mem 0x7de91000-0x7df1afff] [ 0.176797] PM: hibernation: Registered nosave memory: [mem 0x7e182000-0x7e19cfff] [ 0.176798] PM: hibernation: Registered nosave memory: [mem 0x7ef9b000-0x7eff2fff] [ 0.176798] PM: hibernation: Registered nosave memory: [mem 0x7eff3000-0x7effafff] [ 0.176799] PM: hibernation: Registered nosave memory: [mem 0x7effb000-0x7effefff] [ 0.176800] [mem 0x7fff0000-0xffbfffff] available for PCI devices [ 0.176801] Booting paravirtualized kernel on KVM [ 0.176804] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns [ 0.181575] setup_percpu: NR_CPUS:64 nr_cpumask_bits:64 nr_cpu_ids:2 nr_node_ids:1 [ 0.192752] percpu: Embedded 54 pages/cpu s182296 r8192 d30696 u1048576 [ 0.192762] pcpu-alloc: s182296 r8192 d30696 u1048576 alloc=1*2097152 [ 0.192763] pcpu-alloc: [0] 0 1 [ 0.192853] kvm-guest: PV spinlocks enabled [ 0.192857] PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) [ 0.192879] Built 1 zonelists, mobility grouping on. Total pages: 512818 [ 0.192881] Policy zone: DMA32 [ 0.192882] Kernel command line: apparmor=1 security=apparmor console=ttyS0 console=tty1 bootchooser.active=B root=PARTUUID=a3ec664e-32ce-4665-95ea-7ae90ce9aa20 rootfstype=squashfs ro rootwait systemd.machine_id=f1b18bd3ca2b40d391fa721de534405b zram.enabled=1 zram.num_devices=3 [ 0.199991] Dentry cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) [ 0.203917] Inode-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) [ 0.203977] mem auto-init: stack:off, heap alloc:off, heap free:off [ 0.207172] Memory: 1990012K/2083948K available (16395K kernel code, 2572K rwdata, 3916K rodata, 1392K init, 1096K bss, 93676K reserved, 0K cma-reserved) [ 0.207198] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1 [ 0.207248] rcu: Hierarchical RCU implementation. [ 0.207249] rcu: RCU event tracing is enabled. [ 0.207249] rcu: RCU restricting CPUs from NR_CPUS=64 to nr_cpu_ids=2. [ 0.207250] Tracing variant of Tasks RCU enabled. [ 0.207252] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. [ 0.207252] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=2 [ 0.207667] NR_IRQS: 4352, nr_irqs: 440, preallocated irqs: 16 [ 0.208091] random: get_random_bytes called from start_kernel+0x375/0x54c with crng_init=0 [ 0.208111] Console: colour dummy device 80x25 [ 0.208210] printk: console [tty1] enabled [ 0.445223] printk: console [ttyS0] enabled [ 0.446575] ACPI: Core revision 20200925 [ 0.448054] APIC: Switch to symmetric I/O mode setup [ 0.460406] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 [ 0.462636] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x33cb6ecbec8, max_idle_ns: 440795311068 ns [ 0.466299] Calibrating delay loop (skipped) preset value.. 7186.50 BogoMIPS (lpj=3593254) [ 0.467300] pid_max: default: 32768 minimum: 301 [ 0.469664] LSM: Security Framework initializing [ 0.470321] AppArmor: AppArmor initialized [ 0.471315] Mount-cache hash table entries: 4096 (order: 3, 32768 bytes, linear) [ 0.472303] Mountpoint-cache hash table entries: 4096 (order: 3, 32768 bytes, linear) [ 0.474569] Last level iTLB entries: 4KB 1024, 2MB 1024, 4MB 512 [ 0.475299] Last level dTLB entries: 4KB 2048, 2MB 2048, 4MB 1024, 1GB 0 [ 0.476302] Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization [ 0.477300] Spectre V2 : Mitigation: Full AMD retpoline [ 0.478299] Spectre V2 : Spectre v2 / SpectreRSB mitigation: Filling RSB on context switch [ 0.479299] Speculative Store Bypass: Mitigation: Speculative Store Bypass disabled via prctl [ 0.480522] Freeing SMP alternatives memory: 52K [ 0.592673] APIC calibration not consistent with PM-Timer: 105ms instead of 100ms [ 0.593296] APIC delta adjusted to PM-Timer: 6256931 (6598485) [ 0.593760] smpboot: CPU0: AMD Ryzen 5 3600 6-Core Processor (family: 0x17, model: 0x71, stepping: 0x0) [ 0.594389] Performance Events: PMU not available due to virtualization, using software events only. [ 0.595397] rcu: Hierarchical SRCU implementation. [ 0.597382] smp: Bringing up secondary CPUs ... [ 0.598388] x86: Booting SMP configuration: [ 0.599300] .... node #0, CPUs: #1 [ 0.257234] kvm-clock: cpu 1, msr 4810d041, secondary cpu clock [ 0.604296] TSC synchronization [CPU#0 -> CPU#1]: [ 0.604296] Measured 172324 cycles TSC warp between CPUs, turning off TSC clock. [ 0.604296] tsc: Marking TSC unstable due to check_tsc_sync_source failed [ 0.606448] smp: Brought up 1 node, 2 CPUs [ 0.607300] smpboot: Max logical packages: 1 [ 0.608296] smpboot: Total of 2 processors activated (14373.01 BogoMIPS) [ 0.609386] devtmpfs: initialized [ 0.611334] x86/mm: Memory block size: 128MB [ 0.613500] PM: Registering ACPI NVS region [mem 0x7effb000-0x7effefff] (16384 bytes) [ 0.616348] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.621303] futex hash table entries: 512 (order: 3, 32768 bytes, linear) [ 0.625299] PM: RTC time: 18:00:07, date: 2021-09-05 [ 0.627631] NET: Registered protocol family 16 [ 0.629404] audit: initializing netlink subsys (disabled) [ 0.633448] thermal_sys: Registered thermal governor 'step_wise' [ 0.633449] thermal_sys: Registered thermal governor 'user_space' [ 0.633303] audit: type=2000 audit(1630864812.565:1): state=initialized audit_enabled=0 res=1 [ 0.640302] cpuidle: using governor menu [ 0.642856] ACPI: bus type PCI registered [ 0.643530] PCI: Using configuration type 1 for base access [ 0.644296] PCI: Using configuration type 1 for extended access [ 0.646522] Kprobes globally optimized [ 0.647452] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.667413] ACPI: Added _OSI(Module Device) [ 0.669302] ACPI: Added _OSI(Processor Device) [ 0.671299] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.673299] ACPI: Added _OSI(Processor Aggregator Device) [ 0.675304] ACPI: Added _OSI(Linux-Dell-Video) [ 0.677302] ACPI: Added _OSI(Linux-Lenovo-NV-HDMI-Audio) [ 0.680299] ACPI: Added _OSI(Linux-HPI-Hybrid-Graphics) [ 0.683092] ACPI: 2 ACPI AML tables successfully acquired and loaded [ 0.688685] ACPI: Interpreter enabled [ 0.690307] ACPI: (supports S0 S5) [ 0.691299] ACPI: Using IOAPIC for interrupt routing [ 0.694361] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.698409] ACPI: Enabled 2 GPEs in block 00 to 07 [ 0.708651] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.711303] acpi PNP0A03:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] [ 0.716607] acpi PNP0A03:00: _OSC: platform does not support [PCIeCapability LTR] [ 0.720528] acpi PNP0A03:00: _OSC: not requesting control; platform does not support [PCIeCapability] [ 0.724300] acpi PNP0A03:00: _OSC: OS requested [PME PCIeCapability LTR] [ 0.727299] acpi PNP0A03:00: _OSC: platform willing to grant [PME] [ 0.729299] acpi PNP0A03:00: _OSC failed (AE_SUPPORT); disabling ASPM [ 0.732413] PCI host bridge to bus 0000:00 [ 0.733300] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.735300] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.738299] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.740303] pci_bus 0000:00: root bus resource [mem 0x80000000-0xfdffffff window] [ 0.743300] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.745863] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.749705] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.754330] pci 0000:00:02.0: [80ee:beef] type 00 class 0x030000 [ 0.757300] pci 0000:00:02.0: reg 0x10: [mem 0x80000000-0x80ffffff pref] [ 0.766304] pci 0000:00:02.0: BAR 0: assigned to efifb [ 0.770529] pci 0000:00:03.0: [1af4:1000] type 00 class 0x020000 [ 0.774699] pci 0000:00:03.0: reg 0x10: [io 0xc020-0xc03f] [ 0.782748] pci 0000:00:04.0: [80ee:cafe] type 00 class 0x088000 [ 0.787386] pci 0000:00:04.0: reg 0x10: [io 0xc000-0xc01f] [ 0.790542] pci 0000:00:04.0: reg 0x14: [mem 0x81000000-0x813fffff] [ 0.795331] pci 0000:00:04.0: reg 0x18: [mem 0x81410000-0x81413fff pref] [ 0.802327] pci 0000:00:05.0: [8086:2668] type 00 class 0x040300 [ 0.806592] pci 0000:00:05.0: reg 0x10: [mem 0x81414000-0x81417fff] [ 0.814805] pci 0000:00:07.0: [8086:7113] type 00 class 0x068000 [ 0.821634] pci 0000:00:07.0: quirk: [io 0xb000-0xb03f] claimed by PIIX4 ACPI [ 0.826328] pci 0000:00:07.0: quirk: [io 0x4100-0x410f] claimed by PIIX4 SMB [ 0.832521] pci 0000:00:0c.0: [8086:1e31] type 00 class 0x0c0330 [ 0.836696] pci 0000:00:0c.0: reg 0x10: [mem 0x81400000-0x8140ffff] [ 0.844764] pci 0000:00:0d.0: [8086:2829] type 00 class 0x010601 [ 0.849358] pci 0000:00:0d.0: reg 0x10: [io 0xc058-0xc05f] [ 0.853300] pci 0000:00:0d.0: reg 0x14: [io 0xc064-0xc067] [ 0.856527] pci 0000:00:0d.0: reg 0x18: [io 0xc050-0xc057] [ 0.860505] pci 0000:00:0d.0: reg 0x1c: [io 0xc060-0xc063] [ 0.864438] pci 0000:00:0d.0: reg 0x20: [io 0xc040-0xc04f] [ 0.868301] pci 0000:00:0d.0: reg 0x24: [mem 0x81418000-0x81419fff] [ 0.878546] ACPI: PCI Interrupt Link [LNKA] (IRQs 5 9 10 *11) [ 0.882661] ACPI: PCI Interrupt Link [LNKB] (IRQs 5 9 10 *11) [ 0.886466] ACPI: PCI Interrupt Link [LNKC] (IRQs 5 9 *10 11) [ 0.890469] ACPI: PCI Interrupt Link [LNKD] (IRQs 5 9 *10 11) [ 0.894440] iommu: Default domain type: Translated [ 0.897392] pci 0000:00:02.0: vgaarb: setting as boot VGA device [ 0.898296] pci 0000:00:02.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none [ 0.906301] pci 0000:00:02.0: vgaarb: bridge control possible [ 0.910299] vgaarb: loaded [ 0.911366] SCSI subsystem initialized [ 0.914386] libata version 3.00 loaded. [ 0.915299] ACPI: bus type USB registered [ 0.917313] usbcore: registered new interface driver usbfs [ 0.920307] usbcore: registered new interface driver hub [ 0.924306] usbcore: registered new device driver usb [ 0.927308] mc: Linux media interface: v0.10 [ 0.929305] videodev: Linux video capture interface: v2.00 [ 0.933308] pps_core: LinuxPPS API ver. 1 registered [ 0.936299] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.941303] PTP clock support registered [ 0.945348] Registered efivars operations [ 0.946332] Advanced Linux Sound Architecture Driver Initialized. [ 0.947373] Bluetooth: Core ver 2.22 [ 0.948296] NET: Registered protocol family 31 [ 0.948300] Bluetooth: HCI device and connection manager initialized [ 0.949301] Bluetooth: HCI socket layer initialized [ 0.950300] Bluetooth: L2CAP socket layer initialized [ 0.951307] Bluetooth: SCO socket layer initialized [ 0.952305] NetLabel: Initializing [ 0.953296] NetLabel: domain hash size = 128 [ 0.953298] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.954317] NetLabel: unlabeled traffic allowed by default [ 0.956366] PCI: Using ACPI for IRQ routing [ 0.957301] PCI: pci_cache_line_size set to 64 bytes [ 0.957824] e820: reserve RAM buffer [mem 0x7d9b4000-0x7fffffff] [ 0.957826] e820: reserve RAM buffer [mem 0x7de91000-0x7fffffff] [ 0.957827] e820: reserve RAM buffer [mem 0x7e182000-0x7fffffff] [ 0.957828] e820: reserve RAM buffer [mem 0x7ef9b000-0x7fffffff] [ 0.957829] e820: reserve RAM buffer [mem 0x7f46b000-0x7fffffff] [ 0.958134] clocksource: Switched to clocksource kvm-clock [ 1.028650] VFS: Disk quotas dquot_6.6.0 [ 1.028650] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 1.028650] AppArmor: AppArmor Filesystem Enabled [ 1.028650] pnp: PnP ACPI init [ 1.028650] pnp 00:00: Plug and Play ACPI device, IDs PNP0303 (active) [ 1.028650] pnp 00:01: Plug and Play ACPI device, IDs PNP0f03 (active) [ 1.028650] pnp: PnP ACPI: found 2 devices [ 1.102739] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 1.103696] NET: Registered protocol family 2 [ 1.103696] IP idents hash table entries: 32768 (order: 6, 262144 bytes, linear) [ 1.103696] tcp_listen_portaddr_hash hash table entries: 1024 (order: 2, 16384 bytes, linear) [ 1.103696] TCP established hash table entries: 16384 (order: 5, 131072 bytes, linear) [ 1.103696] TCP bind hash table entries: 16384 (order: 6, 262144 bytes, linear) [ 1.103696] TCP: Hash tables configured (established 16384 bind 16384) [ 1.103696] UDP hash table entries: 1024 (order: 3, 32768 bytes, linear) [ 1.103696] UDP-Lite hash table entries: 1024 (order: 3, 32768 bytes, linear) [ 1.103696] NET: Registered protocol family 1 [ 1.129474] RPC: Registered named UNIX socket transport module. [ 1.131727] RPC: Registered udp transport module. [ 1.133452] RPC: Registered tcp transport module. [ 1.135152] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 1.137427] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 1.139547] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 1.141649] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 1.143986] pci_bus 0000:00: resource 7 [mem 0x80000000-0xfdffffff window] [ 1.146752] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 1.148803] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 1.149727] pci 0000:00:02.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff] [ 1.149727] PCI: CLS 0 bytes, default 64 [ 1.149727] platform rtc_cmos: registered platform RTC device (no PNP device found) [ 1.161641] check: Scanning for low memory corruption every 60 seconds [ 1.164566] Initialise system trusted keyrings [ 1.167246] workingset: timestamp_bits=40 max_order=19 bucket_order=0 [ 1.170240] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ 1.173514] NFS: Registering the id_resolver key type [ 1.174438] Key type id_resolver registered [ 1.176529] Key type id_legacy registered [ 1.178424] fuse: init (API version 7.32) [ 1.187469] Key type asymmetric registered [ 1.188957] Asymmetric key parser 'x509' registered [ 1.190857] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 248) [ 1.193891] io scheduler mq-deadline registered [ 1.195480] io scheduler kyber registered [ 1.197427] hv_vmbus: registering driver hyperv_fb [ 1.199164] efifb: probing for efifb [ 1.200495] efifb: framebuffer at 0x80000000, using 3072k, total 3072k [ 1.202678] efifb: mode is 1024x768x32, linelength=4096, pages=1 [ 1.204754] efifb: scrolling: redraw [ 1.205724] efifb: Truecolor: size=8:8:8:8, shift=24:16:8:0 [ 1.210326] Console: switching to colour frame buffer device 128x48 [ 1.210326] fb0: EFI VGA frame buffer device [ 1.210326] ACPI: AC Adapter [AC] (on-line) [ 1.210326] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 1.210326] ACPI: Power Button [PWRF] [ 1.210326] input: Sleep Button as /devices/LNXSYSTM:00/LNXSLPBN:00/input/input1 [ 1.210326] ACPI: Sleep Button [SLPF] [ 1.210326] ACPI: Video Device [GFX0] (multi-head: yes rom: no post: no) [ 1.210326] input: Video Bus as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0A03:00/LNXVIDEO:00/input/input2 [ 1.257955] virtio-pci 0000:00:03.0: virtio_pci: leaving for legacy driver [ 1.262164] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 1.269519] Non-volatile memory driver v1.3 [ 1.272272] Linux agpgart interface v0.103 [ 1.285357] loop: module loaded [ 1.288307] zram: Added device: zram0 [ 1.290857] zram: Added device: zram1 [ 1.293332] zram: Added device: zram2 [ 1.295406] Guest personality initialized and is inactive [ 1.299452] VMCI host device registered (name=vmci, major=10, minor=62) [ 1.302148] Initialized host personality [ 1.304047] VMware PVSCSI driver - version 1.0.7.0-k [ 1.306254] hv_vmbus: registering driver hv_storvsc [ 1.310101] ahci 0000:00:0d.0: version 3.0 [ 1.311183] ahci 0000:00:0d.0: SSS flag set, parallel bus scan disabled [ 1.315021] ahci 0000:00:0d.0: AHCI 0001.0100 32 slots 1 ports 3 Gbps 0x1 impl SATA mode [ 1.345780] ahci 0000:00:0d.0: flags: 64bit ncq stag only ccc [ 1.373512] scsi host0: ahci [ 1.400352] ata1: SATA max UDMA/133 abar m8192@0x81418000 port 0x81418100 irq 21 [ 1.423067] tun: Universal TUN/TAP device driver, 1.6 [ 1.453619] e100: Intel(R) PRO/100 Network Driver [ 1.481595] e100: Copyright(c) 1999-2006 Intel Corporation [ 1.508818] e1000: Intel(R) PRO/1000 Network Driver [ 1.537286] e1000: Copyright (c) 1999-2006 Intel Corporation. [ 1.564697] e1000e: Intel(R) PRO/1000 Network Driver [ 1.591071] e1000e: Copyright(c) 1999 - 2015 Intel Corporation. [ 1.619461] sky2: driver version 1.30 [ 1.647150] VMware vmxnet3 virtual NIC driver - version 1.5.0.0-k-NAPI [ 1.677002] hv_vmbus: registering driver hv_netvsc [ 1.706359] Fusion MPT base driver 3.04.20 [ 1.734284] Copyright (c) 1999-2008 LSI Corporation [ 1.739193] ata1: SATA link up 3.0 Gbps (SStatus 123 SControl 300) [ 1.765499] Fusion MPT SPI Host driver 3.04.20 [ 1.780747] ata1.00: ATA-6: VBOX HARDDISK, 1.0, max UDMA/133 [ 1.791716] Fusion MPT SAS Host driver 3.04.20 [ 1.792594] ata1.00: 67108864 sectors, multi 128: LBA48 NCQ (depth 32) [ 1.813492] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver [ 1.843020] ata1.00: configured for UDMA/133 [ 1.868616] ehci-pci: EHCI PCI platform driver [ 1.868642] ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver [ 1.868650] ohci-pci: OHCI PCI platform driver [ 1.868658] uhci_hcd: USB Universal Host Controller Interface driver [ 1.874002] xhci_hcd 0000:00:0c.0: xHCI Host Controller [ 1.896405] scsi 0:0:0:0: Direct-Access ATA VBOX HARDDISK 1.0 PQ: 0 ANSI: 5 [ 1.919421] xhci_hcd 0000:00:0c.0: new USB bus registered, assigned bus number 1 [ 1.920318] scsi 0:0:0:0: Attached scsi generic sg0 type 0 [ 1.979300] sd 0:0:0:0: [sda] 67108864 512-byte logical blocks: (34.4 GB/32.0 GiB) [ 1.979300] sd 0:0:0:0: [sda] Write Protect is off [ 1.979300] sd 0:0:0:0: [sda] Mode Sense: 00 3a 00 00 [ 1.979300] sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA [ 2.154750] xhci_hcd 0000:00:0c.0: hcc params 0x04000000 hci version 0x100 quirks 0x000000000000b930 [ 2.164833] sda: sda1 sda2 sda3 sda4 sda5 sda6 sda7 sda8 [ 2.200310] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 5.10 [ 2.221594] sd 0:0:0:0: [sda] Attached SCSI disk [ 2.246512] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1 [ 2.279196] usb usb1: Product: xHCI Host Controller [ 2.307646] usb usb1: Manufacturer: Linux 5.10.61 xhci-hcd [ 2.337485] usb usb1: SerialNumber: 0000:00:0c.0 [ 2.365817] hub 1-0:1.0: USB hub found [ 2.395338] hub 1-0:1.0: 8 ports detected [ 2.426008] xhci_hcd 0000:00:0c.0: xHCI Host Controller [ 2.454980] xhci_hcd 0000:00:0c.0: new USB bus registered, assigned bus number 2 [ 2.487981] xhci_hcd 0000:00:0c.0: Host supports USB 3.0 SuperSpeed [ 2.517303] usb usb2: New USB device found, idVendor=1d6b, idProduct=0003, bcdDevice= 5.10 [ 2.518059] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1 [ 2.518059] usb usb2: Product: xHCI Host Controller [ 2.518059] usb usb2: Manufacturer: Linux 5.10.61 xhci-hcd [ 2.518059] usb usb2: SerialNumber: 0000:00:0c.0 [ 2.545695] hub 2-0:1.0: USB hub found [ 2.768602] hub 2-0:1.0: 6 ports detected [ 2.799712] usbcore: registered new interface driver usblp [ 2.835814] usbcore: registered new interface driver usb-storage [ 2.871254] usbcore: registered new interface driver usbserial_generic [ 2.885223] usb 1-1: new full-speed USB device number 2 using xhci_hcd [ 2.906252] usbserial: USB Serial support registered for generic [ 2.924946] i8042: PNP: PS/2 Controller [PNP0303:PS2K,PNP0f03:PS2M] at 0x60,0x64 irq 1,12 [ 2.955865] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 2.984448] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 3.017193] rtc_cmos rtc_cmos: registered as rtc0 [ 3.049868] rtc_cmos rtc_cmos: alarms up to one day, 114 bytes nvram [ 3.079020] device-mapper: ioctl: 4.43.0-ioctl (2020-10-01) initialised: dm-devel@redhat.com [ 3.109525] usbcore: registered new interface driver btusb [ 3.138498] EFI Variables Facility v0.08 2004-May-17 [ 3.144600] hid: raw HID events driver (C) Jiri Kosina [ 3.175774] usbcore: registered new interface driver usbhid [ 3.175823] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input3 [ 3.239554] usbhid: USB HID core driver [ 3.273283] IPVS: Registered protocols () [ 3.303273] IPVS: Connection hash table configured (size=4096, memory=64Kbytes) [ 3.315620] usb 1-1: New USB device found, idVendor=1a86, idProduct=7523, bcdDevice= 2.63 [ 3.340384] IPVS: ipvs loaded. [ 3.340874] usb 1-1: New USB device strings: Mfr=0, Product=2, SerialNumber=0 [ 3.371399] IPVS: [rr] scheduler registered. [ 3.399066] usb 1-1: Product: USB2.0-Serial [ 3.470855] NET: Registered protocol family 10 [ 3.472221] random: fast init done [ 3.505457] Segment Routing with IPv6 [ 3.533999] input: ImExPS/2 Generic Explorer Mouse as /devices/platform/i8042/serio1/input/input5 [ 3.610080] sit: IPv6, IPv4 and MPLS over IPv4 tunneling driver [ 3.644730] NET: Registered protocol family 17 [ 3.675081] Bridge firewalling registered [ 3.706835] Key type dns_resolver registered [ 3.736733] IPI shorthand broadcast: enabled [ 3.766691] sched_clock: Marking stable (3510265174, 256234597)->(3827060498, -60560727) [ 3.800503] registered taskstats version 1 [ 3.824155] Loading compiled-in X.509 certificates [ 3.826541] AppArmor: AppArmor sha1 policy hashing enabled [ 3.828884] PM: Magic number: 5:265:38 [ 3.830701] bdi 7:3: hash matches [ 3.832368] printk: console [netcon0] enabled [ 3.834360] netconsole: network logging started [ 3.839116] ALSA device list: [ 3.869863] No soundcards found. [ 3.898899] md: Waiting for all devices to be available before autodetect [ 3.930511] md: If you don't use raid, use raid=noautodetect [ 3.959140] md: Autodetecting RAID arrays. [ 3.988522] md: autorun ... [ 4.019111] md: ... autorun DONE. [ 4.051645] VFS: Mounted root (squashfs filesystem) readonly on device 8:5. [ 4.084967] devtmpfs: mounted [ 4.109710] Freeing unused kernel image (initmem) memory: 1392K [ 4.135444] Write protecting the kernel read-only data: 22528k [ 4.140760] Freeing unused kernel image (text/rodata gap) memory: 2036K [ 4.168239] Freeing unused kernel image (rodata/data gap) memory: 180K [ 4.193757] Run /sbin/init as init process [ 4.195555] with arguments: [ 4.195556] /sbin/init [ 4.195556] with environment: [ 4.195557] HOME=/ [ 4.195557] TERM=linux [ 4.292689] 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) [ 4.356376] systemd[1]: Detected virtualization oracle. [ 4.385549] systemd[1]: Detected architecture x86-64. [ 4.530303] systemd[1]: Set hostname to . [ 4.562087] systemd[1]: Installed transient /etc/machine-id file. [ 4.695853] systemd[1]: Queued start job for default target Multi-User System. [ 4.725710] random: systemd: uninitialized urandom read (16 bytes read) [ 4.756739] systemd[1]: Created slice system-ha\x2dcli.slice. [ 4.811831] random: systemd: uninitialized urandom read (16 bytes read) [ 4.841613] systemd[1]: Created slice system-modprobe.slice. [ 4.895635] random: systemd: uninitialized urandom read (16 bytes read) [ 4.903073] systemd[1]: Created slice system-systemd\x2dfsck.slice. [ 4.906474] systemd[1]: Created slice system-systemd\x2dgrowfs.slice. [ 4.909796] systemd[1]: Created slice User and Session Slice. [ 4.912899] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. [ 4.917638] systemd[1]: Started Forward Password Requests to Wall Directory Watch. [ 4.921335] systemd[1]: Reached target Local Encrypted Volumes. [ 4.924399] systemd[1]: Reached target HassOS hardware targets. [ 4.927380] systemd[1]: Reached target HassOS zram targets. [ 4.930335] systemd[1]: Reached target Paths. [ 4.932875] systemd[1]: Reached target Remote Encrypted Volumes. [ 4.936031] systemd[1]: Reached target Remote File Systems. [ 4.938917] systemd[1]: Reached target Slices. [ 4.942423] systemd[1]: Listening on D-Bus System Message Bus Socket. [ 4.947842] systemd[1]: Listening on Process Core Dump Socket. [ 4.951103] systemd[1]: Listening on Journal Audit Socket. [ 4.954100] systemd[1]: Listening on Journal Socket (/dev/log). [ 4.957363] systemd[1]: Listening on Journal Socket. [ 4.961910] systemd[1]: Listening on udev Control Socket. [ 4.964844] systemd[1]: Listening on udev Kernel Socket. [ 4.971674] systemd[1]: Mounting Huge Pages File System... [ 5.031366] systemd[1]: Mounting POSIX Message Queue File System... [ 5.091541] systemd[1]: Mounting Kernel Debug File System... [ 5.150752] systemd[1]: Mounting Kernel Trace File System... [ 5.209885] systemd[1]: Started D-Bus System Message Bus. [ 5.286776] systemd[1]: Starting Home Assistant OS Agent... [ 5.386832] systemd[1]: Condition check resulted in Create list of static device nodes for the current kernel being skipped. [ 5.420411] systemd[1]: Starting Load Kernel Module configfs... [ 5.479955] systemd[1]: Starting Load Kernel Module drm... [ 5.539347] systemd[1]: Starting Load Kernel Module fuse... [ 5.601635] systemd[1]: Starting Journal Service... [ 5.664170] systemd[1]: Starting Remount Root and Kernel File Systems... [ 5.724820] systemd[1]: Starting Wait Until Kernel Time Synchronized... [ 5.785050] systemd[1]: Starting Create Static Device Nodes in /dev... [ 5.851274] systemd[1]: Starting Coldplug All udev Devices... [ 5.918715] systemd[1]: Starting Disk Manager... [ 5.992227] systemd[1]: Starting HassOS ZRAM swap... [ 6.028075] zram0: detected capacity change from 0 to 516669440 [ 6.060474] random: crng init done [ 6.060475] random: 7 urandom warning(s) missed due to ratelimiting [ 6.153227] systemd[1]: Starting HassOS ZRAM tmp... [ 6.155199] zram2: detected capacity change from 0 to 16777216 [ 6.245829] systemd[1]: Starting HassOS ZRAM var... [ 6.247728] zram1: detected capacity change from 0 to 33554432 [ 6.332009] systemd[1]: Started Journal Service. [ 6.970177] audit: type=1334 audit(1630864818.901:2): prog-id=5 op=LOAD [ 6.999213] audit: type=1334 audit(1630864818.930:3): prog-id=6 op=LOAD [ 7.149335] virtio_net virtio0 enp0s3: renamed from eth0 [ 7.690136] usbcore: registered new interface driver ch341 [ 7.690142] usbserial: USB Serial support registered for ch341-uart [ 7.690149] ch341 1-1:1.0: ch341-uart converter detected [ 7.771459] usb 1-1: ch341-uart converter now attached to ttyUSB0 [ 7.814053] Adding 504556k swap on /dev/zram0. Priority:-2 extents:1 across:504556k SS [ 7.903336] EXT4-fs (zram1): mounted filesystem without journal. Opts: nobarrier [ 7.903340] ext4 filesystem being mounted at /var supports timestamps until 2038 (0x7fffffff) [ 7.916921] snd_hda_codec_generic hdaudioC0D0: autoconfig for Generic: line_outs=3 (0xc/0xf/0xb/0x0/0x0) type:speaker [ 7.916922] snd_hda_codec_generic hdaudioC0D0: speaker_outs=0 (0x0/0x0/0x0/0x0/0x0) [ 7.916924] snd_hda_codec_generic hdaudioC0D0: hp_outs=1 (0xa/0x0/0x0/0x0/0x0) [ 7.916924] snd_hda_codec_generic hdaudioC0D0: mono: mono_out=0x0 [ 7.916925] snd_hda_codec_generic hdaudioC0D0: dig-out=0x10/0x0 [ 7.916925] snd_hda_codec_generic hdaudioC0D0: inputs: [ 7.916926] snd_hda_codec_generic hdaudioC0D0: Mic=0xd [ 7.916927] snd_hda_codec_generic hdaudioC0D0: Line=0xe [ 7.916927] snd_hda_codec_generic hdaudioC0D0: CD=0x15 [ 7.916928] snd_hda_codec_generic hdaudioC0D0: dig-in=0x11 [ 7.988774] input: HDA Intel Mic as /devices/pci0000:00/0000:00:05.0/sound/card0/input6 [ 7.988797] input: HDA Intel Line as /devices/pci0000:00/0000:00:05.0/sound/card0/input7 [ 7.988814] input: HDA Intel Speaker Front as /devices/pci0000:00/0000:00:05.0/sound/card0/input8 [ 7.988831] input: HDA Intel Speaker CLFE as /devices/pci0000:00/0000:00:05.0/sound/card0/input9 [ 7.988848] input: HDA Intel Front Headphone as /devices/pci0000:00/0000:00:05.0/sound/card0/input10 [ 7.988865] input: HDA Intel SPDIF In as /devices/pci0000:00/0000:00:05.0/sound/card0/input11 [ 8.786782] EXT4-fs (zram2): mounted filesystem without journal. Opts: nobarrier [ 8.786786] ext4 filesystem being mounted at /tmp supports timestamps until 2038 (0x7fffffff) [ 8.879860] EXT4-fs (sda7): mounted filesystem with ordered data mode. Opts: (null) [ 8.879865] ext4 filesystem being mounted at /mnt/overlay supports timestamps until 2038 (0x7fffffff) [ 9.850921] EXT4-fs (sda8): mounted filesystem with ordered data mode. Opts: (null) [ 10.361037] EXT4-fs (sda8): resizing filesystem from 8210171 to 8210171 blocks [ 10.571947] systemd-journald[102]: Received client request to flush runtime journal. [ 11.604883] audit: type=1400 audit(1630864823.529:4): apparmor="STATUS" operation="profile_load" profile="unconfined" name="hassio-supervisor" pid=304 comm="apparmor_parser" [ 11.604886] audit: type=1400 audit(1630864823.529:4): apparmor="STATUS" operation="profile_load" profile="unconfined" name="hassio-supervisor///usr/bin/gdbus" pid=304 comm="apparmor_parser" [ 11.604888] audit: type=1400 audit(1630864823.529:4): apparmor="STATUS" operation="profile_load" profile="unconfined" name="hassio-supervisor///usr/bin/git" pid=304 comm="apparmor_parser" [ 11.781210] audit: type=1334 audit(1630864823.712:5): prog-id=7 op=LOAD [ 11.782839] audit: type=1334 audit(1630864823.713:6): prog-id=8 op=LOAD [ 12.752257] audit: type=1334 audit(1630864824.683:7): prog-id=9 op=LOAD [ 12.752540] audit: type=1334 audit(1630864824.684:8): prog-id=10 op=LOAD [ 13.066755] cfg80211: Loading compiled-in X.509 certificates for regulatory database [ 13.088886] cfg80211: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' [ 15.336351] audit: type=1400 audit(1630864827.264:9): apparmor="STATUS" operation="profile_load" profile="unconfined" name="docker-default" pid=364 comm="apparmor_parser" [ 15.803629] audit: type=1325 audit(1630864827.735:10): table=nat family=2 entries=5 op=xt_replace pid=392 subj==unconfined comm="iptables" [ 15.805882] audit: type=1325 audit(1630864827.736:11): table=filter family=2 entries=4 op=xt_replace pid=394 subj==unconfined comm="iptables" [ 15.807990] audit: type=1325 audit(1630864827.739:12): table=filter family=2 entries=6 op=xt_replace pid=396 subj==unconfined comm="iptables" [ 15.810201] audit: type=1325 audit(1630864827.741:13): table=filter family=2 entries=8 op=xt_replace pid=398 subj==unconfined comm="iptables" [ 15.812724] audit: type=1325 audit(1630864827.743:14): table=filter family=2 entries=10 op=xt_replace pid=400 subj==unconfined comm="iptables" [ 15.815278] audit: type=1325 audit(1630864827.746:15): table=filter family=2 entries=11 op=xt_replace pid=402 subj==unconfined comm="iptables" [ 15.818018] audit: type=1325 audit(1630864827.749:16): table=filter family=2 entries=12 op=xt_replace pid=403 subj==unconfined comm="iptables" [ 15.833387] Initializing XFRM netlink socket [ 16.048171] hassio: port 1(veth7617c1a) entered blocking state [ 16.048173] hassio: port 1(veth7617c1a) entered disabled state [ 16.048226] device veth7617c1a entered promiscuous mode [ 16.048343] hassio: port 1(veth7617c1a) entered blocking state [ 16.048344] hassio: port 1(veth7617c1a) entered forwarding state [ 16.048356] IPv6: ADDRCONF(NETDEV_CHANGE): hassio: link becomes ready [ 16.048725] hassio: port 1(veth7617c1a) entered disabled state [ 16.444744] cgroup: cgroup: disabling cgroup2 socket matching due to net_prio or net_cls activation [ 16.840845] eth0: renamed from veth3a5f0ac [ 16.846292] IPv6: ADDRCONF(NETDEV_CHANGE): veth7617c1a: link becomes ready [ 16.846315] hassio: port 1(veth7617c1a) entered blocking state [ 16.846316] hassio: port 1(veth7617c1a) entered forwarding state [ 42.606703] docker0: port 1(vethd60d95b) entered blocking state [ 42.606705] docker0: port 1(vethd60d95b) entered disabled state [ 42.606922] device vethd60d95b entered promiscuous mode [ 42.607096] kauditd_printk_skb: 56 callbacks suppressed [ 42.607097] audit: type=1700 audit(1630864850.797:73): dev=vethd60d95b prom=256 old_prom=0 auid=4294967295 uid=0 gid=0 ses=4294967295 [ 42.649558] hassio: port 2(veth59c6ff3) entered blocking state [ 42.649560] hassio: port 2(veth59c6ff3) entered disabled state [ 42.649698] device veth59c6ff3 entered promiscuous mode [ 42.649724] audit: type=1700 audit(1630864850.840:74): dev=veth59c6ff3 prom=256 old_prom=0 auid=4294967295 uid=0 gid=0 ses=4294967295 [ 42.650007] hassio: port 2(veth59c6ff3) entered blocking state [ 42.650008] hassio: port 2(veth59c6ff3) entered forwarding state [ 43.002589] audit: type=1334 audit(1630864851.193:75): prog-id=10 op=UNLOAD [ 43.002594] audit: type=1334 audit(1630864851.193:76): prog-id=9 op=UNLOAD [ 43.062111] eth0: renamed from vethb3d8751 [ 43.070512] hassio: port 2(veth59c6ff3) entered disabled state [ 43.070628] IPv6: ADDRCONF(NETDEV_CHANGE): vethd60d95b: link becomes ready [ 43.070642] docker0: port 1(vethd60d95b) entered blocking state [ 43.070643] docker0: port 1(vethd60d95b) entered forwarding state [ 43.070658] IPv6: ADDRCONF(NETDEV_CHANGE): docker0: link becomes ready [ 43.135473] audit: type=1325 audit(1630864851.325:77): table=nat family=2 entries=0 op=xt_register pid=922 subj==unconfined comm="iptables" [ 43.140122] audit: type=1325 audit(1630864851.330:78): table=filter family=2 entries=0 op=xt_register pid=923 subj==unconfined comm="iptables" [ 43.144645] audit: type=1325 audit(1630864851.334:79): table=nat family=2 entries=5 op=xt_replace pid=926 subj==unconfined comm="iptables" [ 43.145939] audit: type=1325 audit(1630864851.335:80): table=nat family=2 entries=7 op=xt_replace pid=927 subj==unconfined comm="iptables" [ 43.148480] audit: type=1325 audit(1630864851.338:81): table=nat family=2 entries=8 op=xt_replace pid=929 subj==unconfined comm="iptables" [ 43.149733] audit: type=1325 audit(1630864851.340:82): table=nat family=2 entries=10 op=xt_replace pid=930 subj==unconfined comm="iptables" [ 43.163273] eth1: renamed from veth232502e [ 43.167774] IPv6: ADDRCONF(NETDEV_CHANGE): veth59c6ff3: link becomes ready [ 43.167843] hassio: port 2(veth59c6ff3) entered blocking state [ 43.167844] hassio: port 2(veth59c6ff3) entered forwarding state [ 46.310459] hassio: port 3(veth9bd8e32) entered blocking state [ 46.310461] hassio: port 3(veth9bd8e32) entered disabled state [ 46.310524] device veth9bd8e32 entered promiscuous mode [ 46.759748] eth0: renamed from veth8ddbb0a [ 46.767895] IPv6: ADDRCONF(NETDEV_CHANGE): veth9bd8e32: link becomes ready [ 46.768191] hassio: port 3(veth9bd8e32) entered blocking state [ 46.768192] hassio: port 3(veth9bd8e32) entered forwarding state [ 47.369723] hassio: port 4(veth4cbb545) entered blocking state [ 47.369725] hassio: port 4(veth4cbb545) entered disabled state [ 47.369759] device veth4cbb545 entered promiscuous mode [ 47.809882] kauditd_printk_skb: 23 callbacks suppressed [ 47.809883] audit: type=1325 audit(1630864856.000:104): table=nat family=2 entries=0 op=xt_register pid=1914 subj==unconfined comm="iptables" [ 47.815213] audit: type=1325 audit(1630864856.005:105): table=filter family=2 entries=0 op=xt_register pid=1915 subj==unconfined comm="iptables" [ 47.818640] audit: type=1325 audit(1630864856.009:106): table=nat family=2 entries=5 op=xt_replace pid=1918 subj==unconfined comm="iptables" [ 47.819987] audit: type=1325 audit(1630864856.010:107): table=nat family=2 entries=7 op=xt_replace pid=1919 subj==unconfined comm="iptables" [ 47.822199] audit: type=1325 audit(1630864856.012:108): table=nat family=2 entries=8 op=xt_replace pid=1921 subj==unconfined comm="iptables" [ 47.823632] audit: type=1325 audit(1630864856.013:109): table=nat family=2 entries=10 op=xt_replace pid=1922 subj==unconfined comm="iptables" [ 47.825635] audit: type=1325 audit(1630864856.015:110): table=nat family=2 entries=11 op=xt_replace pid=1923 subj==unconfined comm="iptables" [ 47.827254] audit: type=1325 audit(1630864856.017:111): table=nat family=2 entries=12 op=xt_replace pid=1924 subj==unconfined comm="iptables" [ 47.828920] audit: type=1325 audit(1630864856.019:112): table=nat family=2 entries=13 op=xt_replace pid=1925 subj==unconfined comm="iptables" [ 47.830709] audit: type=1325 audit(1630864856.021:113): table=nat family=2 entries=14 op=xt_replace pid=1926 subj==unconfined comm="iptables" [ 47.836016] eth0: renamed from vethc6ac91d [ 47.840120] IPv6: ADDRCONF(NETDEV_CHANGE): veth4cbb545: link becomes ready [ 47.840135] hassio: port 4(veth4cbb545) entered blocking state [ 47.840136] hassio: port 4(veth4cbb545) entered forwarding state [ 48.088220] hassio: port 5(vethe113da0) entered blocking state [ 48.088222] hassio: port 5(vethe113da0) entered disabled state [ 48.088288] device vethe113da0 entered promiscuous mode [ 48.088522] hassio: port 5(vethe113da0) entered blocking state [ 48.088523] hassio: port 5(vethe113da0) entered forwarding state [ 48.370623] hassio: port 5(vethe113da0) entered disabled state [ 48.460758] eth0: renamed from veth584e88d [ 48.464875] IPv6: ADDRCONF(NETDEV_CHANGE): vethe113da0: link becomes ready [ 48.464892] hassio: port 5(vethe113da0) entered blocking state [ 48.464892] hassio: port 5(vethe113da0) entered forwarding state [ 52.114201] hassio: port 6(vethddf87e9) entered blocking state [ 52.114202] hassio: port 6(vethddf87e9) entered disabled state [ 52.114250] device vethddf87e9 entered promiscuous mode [ 52.590923] eth0: renamed from veth82bdfa9 [ 52.602781] IPv6: ADDRCONF(NETDEV_CHANGE): vethddf87e9: link becomes ready [ 52.602799] hassio: port 6(vethddf87e9) entered blocking state [ 52.602800] hassio: port 6(vethddf87e9) entered forwarding state [ 73.684293] hassio: port 7(veth7df5147) entered blocking state [ 73.684295] hassio: port 7(veth7df5147) entered disabled state [ 73.684330] device veth7df5147 entered promiscuous mode [ 73.684352] kauditd_printk_skb: 22 callbacks suppressed [ 73.684353] audit: type=1700 audit(1630864881.874:136): dev=veth7df5147 prom=256 old_prom=0 auid=4294967295 uid=0 gid=0 ses=4294967295 [ 74.046264] audit: type=1325 audit(1630864882.236:137): table=nat family=2 entries=0 op=xt_register pid=3862 subj==unconfined comm="iptables" [ 74.051265] audit: type=1325 audit(1630864882.241:138): table=filter family=2 entries=0 op=xt_register pid=3863 subj==unconfined comm="iptables" [ 74.055109] audit: type=1325 audit(1630864882.245:139): table=nat family=2 entries=5 op=xt_replace pid=3866 subj==unconfined comm="iptables" [ 74.056359] audit: type=1325 audit(1630864882.246:140): table=nat family=2 entries=7 op=xt_replace pid=3867 subj==unconfined comm="iptables" [ 74.058622] audit: type=1325 audit(1630864882.248:141): table=nat family=2 entries=8 op=xt_replace pid=3869 subj==unconfined comm="iptables" [ 74.059633] audit: type=1325 audit(1630864882.249:142): table=nat family=2 entries=10 op=xt_replace pid=3870 subj==unconfined comm="iptables" [ 74.060853] audit: type=1325 audit(1630864882.250:143): table=nat family=2 entries=11 op=xt_replace pid=3871 subj==unconfined comm="iptables" [ 74.062200] audit: type=1325 audit(1630864882.252:144): table=nat family=2 entries=12 op=xt_replace pid=3872 subj==unconfined comm="iptables" [ 74.064129] audit: type=1325 audit(1630864882.254:145): table=nat family=2 entries=13 op=xt_replace pid=3873 subj==unconfined comm="iptables" [ 74.073369] eth0: renamed from veth02444fb [ 74.077847] IPv6: ADDRCONF(NETDEV_CHANGE): veth7df5147: link becomes ready [ 74.077926] hassio: port 7(veth7df5147) entered blocking state [ 74.077927] hassio: port 7(veth7df5147) entered forwarding state [ 79.127385] kauditd_printk_skb: 3 callbacks suppressed [ 79.127387] audit: type=1334 audit(1630864887.318:149): prog-id=12 op=UNLOAD [ 79.127389] audit: type=1334 audit(1630864887.318:150): prog-id=11 op=UNLOAD [ 79.140387] audit: type=1334 audit(1630864887.330:151): prog-id=15 op=LOAD [ 79.140390] audit: type=1334 audit(1630864887.330:152): prog-id=16 op=LOAD [ 79.236801] audit: type=1334 audit(1630864887.427:153): prog-id=17 op=LOAD [ 79.237273] audit: type=1334 audit(1630864887.428:154): prog-id=18 op=LOAD [ 109.258256] audit: type=1334 audit(1630864917.450:155): prog-id=16 op=UNLOAD [ 109.258259] audit: type=1334 audit(1630864917.450:156): prog-id=15 op=UNLOAD [ 109.315814] audit: type=1334 audit(1630864917.507:157): prog-id=18 op=UNLOAD [ 109.315817] audit: type=1334 audit(1630864917.507:158): prog-id=17 op=UNLOAD ```

Let me know if you need anything more.

tjharman commented 3 years ago

I found with 6.3 that presses of my Zigbee Button (An Xiaomi Aqura button) sometimes doesn't work, sometimes is very delayed (I'll press it and between 0-10 seconds will laps before it works)

I have rolled back to 6.2 and all it working as expected again. My other Zigbee devices seemed to function OK (Xiaomi power plugs)

I use a Zig a Zig Ah stick.

I realise this is a pretty usless post with zero logs, only offered as another datapoint for the "something's changed in 6.3 that's impacting Zigbee"

My System: Phsyical Hardware running Proxmox 7.0 HAOS in a VM, with the Zigbee Stick passed through directly.

agners commented 3 years ago

@tjharman Make sure to check CPU load.

There is really nothing special with 6.3, just a regular kernel update. Make sure to downgrade to 6.2 to double check if it is really related to the OS. In you case it seems that the integration can detect the ZHA adapter, so i don't think its related to the OP's issue.

agners commented 3 years ago

@TabakM4n

The 6.2 log shows a network related stack trace, I am surprised that this does not lead to issues on 6.2 :sweat_smile:

In both 6.2 and 6.3 the Linux kernel seems to detect a ch341 based USB to serial adapter which I guess is the POPP ZB-Stick. It is attached as ttyUSB0 in both cases. What device are you referencing in the ZHA integration?

TabakM4n commented 3 years ago

@agners

In both cases:

  1. Enter Manually
  2. Radio type EZSP = Silicon Labs EmberZNet protocol: Elelabs, HUSBZB-1, Telegesis
  3. Serial device path /dev/ttyUSB0
  4. port speed 115200
  5. data flow control hardware

Exactly as it is specified in the PDF, except 5 because probably this option is added to home assistant after this tutorial was made. https://elelabs.com/wp-content/uploads/2020/07/ELU013_UG_11_HomeAssistant_Hassio_0.112.4.pdf

I see the device in Supervisor -> System -> Host -> Hardware

It connects in both versions and correctly pick up the /dev/serial/by-id/..... path but in 6.3 I get errors and I cannot add devices.

What is strange about it - is that when I add the integration in 6.3 and then downgrade to 6.2 -> the integration starts on boot without any problems.

pedrolamas commented 3 years ago

Kernel update is the reason for this problem, as described here: https://github.com/Koenkk/zigbee2mqtt/issues/8623#issuecomment-913202614

I experienced this issue myself with zigbee2mqtt and managed to confirm that previous kernels are working fine, but 5.10 and above can repro the problem with some Aqara devices (such as the door contact sensor)

tjharman commented 3 years ago

@pedrolamas Thank you so much. I shall bow out of this thread, sorry for the noise.

puddly commented 3 years ago

The kernel update seems to only affect the CH340 driver, but the Elelabs stick (and I think the POPP Zb-Stick) uses a Silicon Labs CP210x chip, which has its own driver. I don't think this was changed as well, was it?

puddly commented 3 years ago

Ignore my last comment, the Elelabs ELU013 / POPP ZB-Stick sticks definitely use the CH340 according to their docs:

image

This kernel change should break many CH340 devices, like the zzh! coordinator and most cheap Arduino and ESP8266/ESP32 dev kits.

agners commented 3 years ago

Linux 5.10.62 indeed fixes a CH340/CH341 related issue, specifically: https://bugzilla.kernel.org/show_bug.cgi?id=214131

I'll update the kernel to the latest version for 6.4.

agners commented 3 years ago

Only now reading @pedrolamas comment :see_no_evil: . But yeah that seems to be the culprit.

agners commented 3 years ago

For now, if you experience issues with CH340 devices, please revert back to 6.2 until 6.4 is out:

ha os update --version 6.2
bossgos commented 3 years ago

For now, if you experience issues with CH340 devices, please revert back to 6.2 until 6.4 is out:

ha os update --version 6.2

Today HASS OS 6.4 has been released. Is it still affected with this bug?

tjharman commented 3 years ago

Where do you see 6.4?

https://github.com/home-assistant/operating-system/releases

bossgos commented 3 years ago

Where do you see 6.4?

https://github.com/home-assistant/operating-system/releases

aww sorry my mistake... I was check Home Assistant Core :( with 4 at the end...

TabakM4n commented 3 years ago

I can confirm that after upgrading to HASS OS 6.4, the integration started working.

agners commented 3 years ago

This is addressed in HAOS 6.4 and newer (via kernel update PR #1536).