home-assistant / operating-system

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

Potential filesystem corruption during failed HassOS upgrade from 3.10 --> 3.12 #615

Closed adamfeldman closed 4 years ago

adamfeldman commented 4 years ago

HassOS release with the issue: Start: 3.10 Target: 3.12

Supervisor version: 214 Supervisor logs:

CLICK ME

``` 20-03-30 14:26:07 INFO (SyncWorker_0) [supervisor.docker.supervisor] Attach to Supervisor homeassistant/amd64-hassio-supervisor with version 214 20-03-30 14:26:07 INFO (SyncWorker_0) [supervisor.docker.supervisor] Connect Supervisor to hassio Network 20-03-30 14:26:07 INFO (SyncWorker_1) [supervisor.docker.interface] Cleanup images: ['homeassistant/amd64-hassio-supervisor:213'] 20-03-30 14:26:08 INFO (MainThread) [__main__] Setup Supervisor 20-03-30 14:26:08 INFO (MainThread) [supervisor.utils.gdbus] Connect to dbus: org.freedesktop.systemd1 - /org/freedesktop/systemd1 20-03-30 14:26:08 INFO (MainThread) [supervisor.utils.gdbus] Connect to dbus: org.freedesktop.hostname1 - /org/freedesktop/hostname1 20-03-30 14:26:08 INFO (MainThread) [supervisor.utils.gdbus] Connect to dbus: de.pengutronix.rauc - / 20-03-30 14:26:08 INFO (MainThread) [supervisor.utils.gdbus] Connect to dbus: org.freedesktop.NetworkManager - /org/freedesktop/NetworkManager/DnsManager 20-03-30 14:26:08 INFO (MainThread) [supervisor.host.info] Update local host information 20-03-30 14:26:08 INFO (MainThread) [supervisor.utils.gdbus] Call org.freedesktop.DBus.Properties.GetAll on /org/freedesktop/hostname1 20-03-30 14:26:08 INFO (MainThread) [supervisor.host.services] Update service information 20-03-30 14:26:08 INFO (MainThread) [supervisor.utils.gdbus] Call org.freedesktop.systemd1.Manager.ListUnits on /org/freedesktop/systemd1 20-03-30 14:26:08 INFO (MainThread) [supervisor.host.network] Update local network DNS information 20-03-30 14:26:08 INFO (MainThread) [supervisor.utils.gdbus] Call org.freedesktop.DBus.Properties.GetAll on /org/freedesktop/NetworkManager/DnsManager 20-03-30 14:26:08 INFO (MainThread) [supervisor.host.sound] Update PulseAudio information Failed to load cookie file from cookie: No such file or directory 20-03-30 14:26:08 INFO (MainThread) [supervisor.host.apparmor] Load AppArmor Profiles: {'hassio-supervisor'} 20-03-30 14:26:08 INFO (MainThread) [supervisor.host.services] Reload local service hassos-apparmor.service 20-03-30 14:26:08 INFO (MainThread) [supervisor.utils.gdbus] Call org.freedesktop.systemd1.Manager.ReloadOrRestartUnit on /org/freedesktop/systemd1 20-03-30 14:26:08 INFO (SyncWorker_1) [supervisor.docker.interface] Attach to homeassistant/amd64-hassio-dns with version 3 20-03-30 14:26:08 INFO (SyncWorker_2) [supervisor.docker.interface] Attach to homeassistant/amd64-hassio-audio with version 9 20-03-30 14:26:08 INFO (SyncWorker_5) [supervisor.docker.interface] Attach to homeassistant/amd64-hassio-cli with version 24 20-03-30 14:26:08 INFO (MainThread) [supervisor.misc.forwarder] Start DNS port forwarding to 172.30.32.3 20-03-30 14:26:08 INFO (SyncWorker_0) [supervisor.docker.interface] Restart homeassistant/amd64-hassio-dns 20-03-30 14:26:12 INFO (MainThread) [supervisor.dns] Updated /etc/resolv.conf 20-03-30 14:26:12 INFO (SyncWorker_5) [supervisor.docker.interface] Attach to homeassistant/qemux86-64-homeassistant with version 0.107.7 20-03-30 14:26:12 INFO (MainThread) [supervisor.utils.gdbus] Call org.freedesktop.DBus.Properties.GetAll on / 20-03-30 14:26:12 INFO (MainThread) [supervisor.hassos] Detect HassOS 3.10 / BootSlot B 20-03-30 14:26:12 WARNING (MainThread) [supervisor.store.data] Can't read /data/addons/git/d8ef7a72/smartthings/config.json: required key not provided @ data['arch']. Got None 20-03-30 14:26:12 INFO (MainThread) [supervisor.store.git] Load add-on /data/addons/git/a0d7b954 repository 20-03-30 14:26:12 INFO (MainThread) [supervisor.store.git] Load add-on /data/addons/git/d8ef7a72 repository 20-03-30 14:26:12 INFO (MainThread) [supervisor.store.git] Load add-on /data/addons/core repository 20-03-30 14:26:13 WARNING (MainThread) [supervisor.store.data] Can't read /data/addons/git/d8ef7a72/smartthings/config.json: required key not provided @ data['arch']. Got None 20-03-30 14:26:13 INFO (MainThread) [supervisor.store] Load add-ons from store: 65 all - 65 new - 0 remove 20-03-30 14:26:13 INFO (MainThread) [supervisor.addons] Found 17 installed add-ons 20-03-30 14:26:13 INFO (SyncWorker_8) [supervisor.docker.interface] Attach to homeassistant/amd64-addon-letsencrypt with version 4.3 20-03-30 14:26:13 INFO (SyncWorker_17) [supervisor.docker.interface] Attach to homeassistant/amd64-addon-check_config with version 3.1 20-03-30 14:26:13 INFO (SyncWorker_15) [supervisor.docker.interface] Attach to hassioaddons/vscode-amd64 with version 2.1.0 20-03-30 14:26:13 INFO (SyncWorker_6) [supervisor.docker.interface] Attach to hassioaddons/pi-hole-amd64 with version 4.1.1 20-03-30 14:26:13 INFO (SyncWorker_10) [supervisor.docker.interface] Attach to homeassistant/amd64-addon-samba with version 9.0 20-03-30 14:26:13 INFO (SyncWorker_16) [supervisor.docker.interface] Attach to homeassistant/amd64-addon-ssh with version 8.5.3 20-03-30 14:26:13 INFO (SyncWorker_11) [supervisor.docker.interface] Attach to hassioaddons/node-red-amd64 with version 6.1.4 20-03-30 14:26:13 INFO (SyncWorker_19) [supervisor.docker.interface] Attach to hassioaddons/ssh-amd64 with version 7.3.1 20-03-30 14:26:13 INFO (SyncWorker_3) [supervisor.docker.interface] Attach to homeassistant/amd64-addon-mosquitto with version 5.1 20-03-30 14:26:13 INFO (SyncWorker_4) [supervisor.docker.interface] Attach to hassioaddons/log-viewer-amd64 with version 0.8.0 20-03-30 14:26:13 INFO (SyncWorker_12) [supervisor.docker.interface] Attach to hassioaddons/aircast-amd64 with version 2.3.0 20-03-30 14:26:13 INFO (SyncWorker_14) [supervisor.docker.interface] Attach to hassioaddons/influxdb-amd64 with version 3.6.1 20-03-30 14:26:13 INFO (SyncWorker_7) [supervisor.docker.interface] Attach to homeassistant/amd64-addon-deconz with version 5.1 20-03-30 14:26:13 INFO (SyncWorker_9) [supervisor.docker.interface] Attach to homeassistant/amd64-addon-cec_scan with version 2.4 20-03-30 14:26:13 INFO (SyncWorker_17) [supervisor.docker.interface] Attach to hassioaddons/ide-amd64 with version 2.2.0 20-03-30 14:26:13 INFO (SyncWorker_13) [supervisor.docker.interface] Attach to hassioaddons/grafana-amd64 with version 4.2.1 20-03-30 14:26:13 INFO (SyncWorker_15) [supervisor.docker.interface] Attach to afelds/amd64-hassio-smartthings-mqtt-bridge with version 20180714 20-03-30 14:26:13 INFO (MainThread) [supervisor.updater] Fetch update data from https://version.home-assistant.io/stable.json 20-03-30 14:26:13 INFO (MainThread) [supervisor.snapshots] Found 6 snapshot files 20-03-30 14:26:13 ERROR (MainThread) [supervisor.snapshots.snapshot] Can't read snapshot tarfile /data/backup/._Hass_io_0_94_1_pre_hassos_migration.tar: invalid header 20-03-30 14:26:13 INFO (MainThread) [supervisor.discovery] Load 2 messages 20-03-30 14:26:13 INFO (MainThread) [supervisor.ingress] Load 0 ingress session 20-03-30 14:26:13 INFO (MainThread) [supervisor.secrets] Load Home Assistant secrets: 3 20-03-30 14:26:13 INFO (MainThread) [__main__] Run Supervisor 20-03-30 14:26:13 INFO (MainThread) [supervisor.api] Start API on 172.30.32.2 20-03-30 14:26:13 INFO (MainThread) [supervisor.utils.gdbus] Call de.pengutronix.rauc.Installer.Mark on / 20-03-30 14:26:13 INFO (MainThread) [supervisor.hassos] Rauc: B - marked slot kernel.1 as good 20-03-30 14:26:13 INFO (MainThread) [supervisor.addons] Phase 'initialize' start 0 add-ons 20-03-30 14:26:13 INFO (MainThread) [supervisor.addons] Phase 'system' start 1 add-ons 20-03-30 14:26:13 WARNING (MainThread) [supervisor.addons.addon] a0d7b954_pi-hole already running! 20-03-30 14:26:18 INFO (MainThread) [supervisor.addons] Phase 'services' start 5 add-ons 20-03-30 14:26:18 WARNING (MainThread) [supervisor.addons.validate] Unknown options ssl 20-03-30 14:26:18 WARNING (MainThread) [supervisor.addons.validate] Unknown options certfile 20-03-30 14:26:18 WARNING (MainThread) [supervisor.addons.validate] Unknown options keyfile 20-03-30 14:26:18 WARNING (MainThread) [supervisor.addons.validate] Unknown options disable_telemetry 20-03-30 14:26:19 INFO (SyncWorker_12) [supervisor.docker.addon] Start Docker add-on hassioaddons/aircast-amd64 with version 2.3.0 20-03-30 14:26:20 INFO (SyncWorker_3) [supervisor.docker.addon] Start Docker add-on hassioaddons/influxdb-amd64 with version 3.6.1 20-03-30 14:26:20 INFO (SyncWorker_7) [supervisor.docker.addon] Start Docker add-on hassioaddons/grafana-amd64 with version 4.2.1 20-03-30 14:26:20 INFO (SyncWorker_11) [supervisor.docker.addon] Start Docker add-on hassioaddons/vscode-amd64 with version 2.1.0 20-03-30 14:26:20 INFO (SyncWorker_2) [supervisor.docker.addon] Start Docker add-on homeassistant/amd64-addon-ssh with version 8.5.3 20-03-30 14:26:21 INFO (MainThread) [supervisor.api.security] /host/info access from a0d7b954_aircast 20-03-30 14:26:21 INFO (MainThread) [supervisor.api.security] /host/info access from a0d7b954_grafana 20-03-30 14:26:21 INFO (MainThread) [supervisor.api.security] /host/info access from a0d7b954_influxdb 20-03-30 14:26:22 INFO (MainThread) [supervisor.api.security] /host/info access from a0d7b954_vscode 20-03-30 14:26:22 INFO (MainThread) [supervisor.api.security] /dns/info access from a0d7b954_influxdb 20-03-30 14:26:25 INFO (SyncWorker_3) [supervisor.docker.interface] Start homeassistant/qemux86-64-homeassistant 20-03-30 14:27:01 INFO (MainThread) [supervisor.homeassistant] Detect a running Home Assistant instance 20-03-30 14:27:01 INFO (MainThread) [supervisor.addons] Phase 'application' start 0 add-ons 20-03-30 14:27:01 INFO (MainThread) [supervisor.tasks] All core tasks are scheduled 20-03-30 14:27:01 INFO (MainThread) [supervisor.hwmon] Started Supervisor hardware monitor 20-03-30 14:27:01 INFO (MainThread) [supervisor.core] Supervisor is up and running 20-03-30 14:27:01 INFO (MainThread) [supervisor.host.info] Update local host information 20-03-30 14:27:01 INFO (MainThread) [supervisor.utils.gdbus] Call org.freedesktop.DBus.Properties.GetAll on /org/freedesktop/hostname1 20-03-30 14:27:01 INFO (MainThread) [supervisor.updater] Fetch update data from https://version.home-assistant.io/stable.json 20-03-30 14:27:01 INFO (MainThread) [supervisor.host.services] Update service information 20-03-30 14:27:01 INFO (MainThread) [supervisor.utils.gdbus] Call org.freedesktop.systemd1.Manager.ListUnits on /org/freedesktop/systemd1 20-03-30 14:27:02 INFO (MainThread) [supervisor.host.network] Update local network DNS information 20-03-30 14:27:02 INFO (MainThread) [supervisor.utils.gdbus] Call org.freedesktop.DBus.Properties.GetAll on /org/freedesktop/NetworkManager/DnsManager 20-03-30 14:27:02 INFO (MainThread) [supervisor.host.sound] Update PulseAudio information Failed to load cookie file from cookie: No such file or directory 20-03-30 14:28:34 INFO (MainThread) [supervisor.api.proxy] Home Assistant WebSocket API request initialize 20-03-30 14:28:35 INFO (MainThread) [supervisor.api.proxy] WebSocket access from a0d7b954_vscode 20-03-30 14:28:35 INFO (MainThread) [supervisor.homeassistant] Updated Home Assistant API token 20-03-30 14:28:35 INFO (MainThread) [supervisor.api.proxy] Home Assistant WebSocket API request running 20-03-30 14:28:57 INFO (MainThread) [supervisor.api.security] /supervisor/logs access from a0d7b954_vscode ```

Journal logs: (journalctl was not an available command within my HassOS (3.10). I could run it on the Proxmox host but I am unsure what value that would provide.)

Kernel logs:

CLICK ME

``` [ 0.000000] Linux version 4.19.102 (builder@9e55c4863a2a) (gcc version 7.4.0 (Buildroot 2019.02.9-gfc11e52c)) #1 SMP Sun Feb 9 23:16:32 UTC 2020 [ 0.000000] Command line: apparmor=1 security=apparmor console=tty1 bootchooser.active=B root=PARTUUID=a3ec664e-32ce-4665-95ea-7ae90ce9aa20 rootfstype=squashfs ro rootwait zram.enabled=1 zram.num_devices=3 [ 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-0x00000000007fffff] usable [ 0.000000] BIOS-e820: [mem 0x0000000000800000-0x0000000000807fff] ACPI NVS [ 0.000000] BIOS-e820: [mem 0x0000000000808000-0x000000000080ffff] usable [ 0.000000] BIOS-e820: [mem 0x0000000000810000-0x00000000008fffff] ACPI NVS [ 0.000000] BIOS-e820: [mem 0x0000000000900000-0x00000000beab1fff] usable [ 0.000000] BIOS-e820: [mem 0x00000000beab2000-0x00000000beab9fff] ACPI NVS [ 0.000000] BIOS-e820: [mem 0x00000000beaba000-0x00000000beabafff] ACPI data [ 0.000000] BIOS-e820: [mem 0x00000000beabb000-0x00000000beabcfff] usable [ 0.000000] BIOS-e820: [mem 0x00000000beabd000-0x00000000beabdfff] ACPI data [ 0.000000] BIOS-e820: [mem 0x00000000beabe000-0x00000000beac1fff] ACPI NVS [ 0.000000] BIOS-e820: [mem 0x00000000beac2000-0x00000000beadafff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000beadb000-0x00000000beafafff] usable [ 0.000000] BIOS-e820: [mem 0x00000000beafb000-0x00000000beb1afff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000beb1b000-0x00000000bfb9afff] usable [ 0.000000] BIOS-e820: [mem 0x00000000bfb9b000-0x00000000bfbf2fff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000bfbf3000-0x00000000bfbfafff] ACPI data [ 0.000000] BIOS-e820: [mem 0x00000000bfbfb000-0x00000000bfbfefff] ACPI NVS [ 0.000000] BIOS-e820: [mem 0x00000000bfbff000-0x00000000bff3ffff] usable [ 0.000000] BIOS-e820: [mem 0x00000000bff40000-0x00000000bff5ffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000bff60000-0x00000000bfffffff] ACPI NVS [ 0.000000] BIOS-e820: [mem 0x0000000100000000-0x000000013fffffff] usable [ 0.000000] NX (Execute Disable) protection: active [ 0.000000] e820: update [mem 0xbc3fb018-0xbc404857] usable ==> usable [ 0.000000] e820: update [mem 0xbc3fb018-0xbc404857] usable ==> usable [ 0.000000] e820: update [mem 0xbc3bf018-0xbc3fa457] usable ==> usable [ 0.000000] e820: update [mem 0xbc3bf018-0xbc3fa457] usable ==> usable [ 0.000000] extended physical RAM map: [ 0.000000] reserve setup_data: [mem 0x0000000000000000-0x000000000009ffff] usable [ 0.000000] reserve setup_data: [mem 0x0000000000100000-0x00000000007fffff] usable [ 0.000000] reserve setup_data: [mem 0x0000000000800000-0x0000000000807fff] ACPI NVS [ 0.000000] reserve setup_data: [mem 0x0000000000808000-0x000000000080ffff] usable [ 0.000000] reserve setup_data: [mem 0x0000000000810000-0x00000000008fffff] ACPI NVS [ 0.000000] reserve setup_data: [mem 0x0000000000900000-0x00000000bc3bf017] usable [ 0.000000] reserve setup_data: [mem 0x00000000bc3bf018-0x00000000bc3fa457] usable [ 0.000000] reserve setup_data: [mem 0x00000000bc3fa458-0x00000000bc3fb017] usable [ 0.000000] reserve setup_data: [mem 0x00000000bc3fb018-0x00000000bc404857] usable [ 0.000000] reserve setup_data: [mem 0x00000000bc404858-0x00000000beab1fff] usable [ 0.000000] reserve setup_data: [mem 0x00000000beab2000-0x00000000beab9fff] ACPI NVS [ 0.000000] reserve setup_data: [mem 0x00000000beaba000-0x00000000beabafff] ACPI data [ 0.000000] reserve setup_data: [mem 0x00000000beabb000-0x00000000beabcfff] usable [ 0.000000] reserve setup_data: [mem 0x00000000beabd000-0x00000000beabdfff] ACPI data [ 0.000000] reserve setup_data: [mem 0x00000000beabe000-0x00000000beac1fff] ACPI NVS [ 0.000000] reserve setup_data: [mem 0x00000000beac2000-0x00000000beadafff] reserved [ 0.000000] reserve setup_data: [mem 0x00000000beadb000-0x00000000beafafff] usable [ 0.000000] reserve setup_data: [mem 0x00000000beafb000-0x00000000beb1afff] reserved [ 0.000000] reserve setup_data: [mem 0x00000000beb1b000-0x00000000bfb9afff] usable [ 0.000000] reserve setup_data: [mem 0x00000000bfb9b000-0x00000000bfbf2fff] reserved [ 0.000000] reserve setup_data: [mem 0x00000000bfbf3000-0x00000000bfbfafff] ACPI data [ 0.000000] reserve setup_data: [mem 0x00000000bfbfb000-0x00000000bfbfefff] ACPI NVS [ 0.000000] reserve setup_data: [mem 0x00000000bfbff000-0x00000000bff3ffff] usable [ 0.000000] reserve setup_data: [mem 0x00000000bff40000-0x00000000bff5ffff] reserved [ 0.000000] reserve setup_data: [mem 0x00000000bff60000-0x00000000bfffffff] ACPI NVS [ 0.000000] reserve setup_data: [mem 0x0000000100000000-0x000000013fffffff] usable [ 0.000000] efi: EFI v2.70 by EDK II [ 0.000000] efi: SMBIOS=0xbfbcc000 ACPI=0xbfbfa000 ACPI 2.0=0xbfbfa014 MEMATTR=0xbed0e298 [ 0.000000] SMBIOS 2.8 present. [ 0.000000] DMI: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 0.0.0 02/06/2015 [ 0.000000] Hypervisor detected: KVM [ 0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00 [ 0.000000] kvm-clock: cpu 0, msr 10fb91001, primary cpu clock [ 0.000000] kvm-clock: using sched offset of 7529137363 cycles [ 0.000004] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns [ 0.000008] tsc: Detected 1497.600 MHz processor [ 0.000189] e820: update [mem 0x00000000-0x00000fff] usable ==> reserved [ 0.000191] e820: remove [mem 0x000a0000-0x000fffff] usable [ 0.000202] last_pfn = 0x140000 max_arch_pfn = 0x400000000 [ 0.000246] MTRR default type: write-back [ 0.000247] MTRR fixed ranges enabled: [ 0.000248] 00000-9FFFF write-back [ 0.000250] A0000-FFFFF uncachable [ 0.000251] MTRR variable ranges enabled: [ 0.000252] 0 base 00C0000000 mask FFC0000000 uncachable [ 0.000254] 1 base 0800000000 mask F800000000 uncachable [ 0.000254] 2 disabled [ 0.000255] 3 disabled [ 0.000256] 4 disabled [ 0.000256] 5 disabled [ 0.000257] 6 disabled [ 0.000257] 7 disabled [ 0.000274] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WC UC- UC [ 0.000283] last_pfn = 0xbff40 max_arch_pfn = 0x400000000 [ 0.007224] Scanning 1 areas for low memory corruption [ 0.007291] BRK [0x10fe01000, 0x10fe01fff] PGTABLE [ 0.007295] BRK [0x10fe02000, 0x10fe02fff] PGTABLE [ 0.007296] BRK [0x10fe03000, 0x10fe03fff] PGTABLE [ 0.007394] BRK [0x10fe04000, 0x10fe04fff] PGTABLE [ 0.007397] BRK [0x10fe05000, 0x10fe05fff] PGTABLE [ 0.007714] BRK [0x10fe06000, 0x10fe06fff] PGTABLE [ 0.007949] BRK [0x10fe07000, 0x10fe07fff] PGTABLE [ 0.007963] BRK [0x10fe08000, 0x10fe08fff] PGTABLE [ 0.008041] BRK [0x10fe09000, 0x10fe09fff] PGTABLE [ 0.008472] BRK [0x10fe0a000, 0x10fe0afff] PGTABLE [ 0.008690] BRK [0x10fe0b000, 0x10fe0bfff] PGTABLE [ 0.008901] Secure boot disabled [ 0.008919] ACPI: Early table checksum verification disabled [ 0.008925] ACPI: RSDP 0x00000000BFBFA014 000024 (v02 BOCHS ) [ 0.008930] ACPI: XSDT 0x00000000BFBF90E8 00004C (v01 BOCHS BXPCFACP 00000001 01000013) [ 0.008938] ACPI: FACP 0x00000000BFBF5000 000074 (v01 BOCHS BXPCFACP 00000001 BXPC 00000001) [ 0.008945] ACPI: DSDT 0x00000000BFBF6000 002330 (v01 BOCHS BXPCDSDT 00000001 BXPC 00000001) [ 0.008950] ACPI: FACS 0x00000000BFBFC000 000040 [ 0.008954] ACPI: APIC 0x00000000BFBF4000 000090 (v01 BOCHS BXPCAPIC 00000001 BXPC 00000001) [ 0.008958] ACPI: SSDT 0x00000000BFBF3000 0000CA (v01 BOCHS VMGENID 00000001 BXPC 00000001) [ 0.008962] ACPI: HPET 0x00000000BEABA000 000038 (v01 BOCHS BXPCHPET 00000001 BXPC 00000001) [ 0.008966] ACPI: BGRT 0x00000000BEABD000 000038 (v01 INTEL EDK2 00000002 01000013) [ 0.008976] ACPI: Local APIC address 0xfee00000 [ 0.009320] No NUMA configuration found [ 0.009322] Faking a node at [mem 0x0000000000000000-0x000000013fffffff] [ 0.009328] NODE_DATA(0) allocated [mem 0x13fffc000-0x13fffffff] [ 0.011022] Zone ranges: [ 0.011029] DMA [mem 0x0000000000001000-0x0000000000ffffff] [ 0.011032] DMA32 [mem 0x0000000001000000-0x00000000ffffffff] [ 0.011034] Normal [mem 0x0000000100000000-0x000000013fffffff] [ 0.011036] Movable zone start for each node [ 0.011037] Early memory node ranges [ 0.011039] node 0: [mem 0x0000000000001000-0x000000000009ffff] [ 0.011040] node 0: [mem 0x0000000000100000-0x00000000007fffff] [ 0.011042] node 0: [mem 0x0000000000808000-0x000000000080ffff] [ 0.011043] node 0: [mem 0x0000000000900000-0x00000000beab1fff] [ 0.011045] node 0: [mem 0x00000000beabb000-0x00000000beabcfff] [ 0.011046] node 0: [mem 0x00000000beadb000-0x00000000beafafff] [ 0.011048] node 0: [mem 0x00000000beb1b000-0x00000000bfb9afff] [ 0.011049] node 0: [mem 0x00000000bfbff000-0x00000000bff3ffff] [ 0.011051] node 0: [mem 0x0000000100000000-0x000000013fffffff] [ 0.011791] Reserved but unavailable: 97 pages [ 0.011796] Initmem setup node 0 [mem 0x0000000000001000-0x000000013fffffff] [ 0.011803] On node 0 totalpages: 1047868 [ 0.011805] DMA zone: 59 pages used for memmap [ 0.011806] DMA zone: 1815 pages reserved [ 0.011807] DMA zone: 3751 pages, LIFO batch:0 [ 0.011981] DMA32 zone: 12219 pages used for memmap [ 0.011982] DMA32 zone: 781973 pages, LIFO batch:63 [ 0.058024] Normal zone: 4096 pages used for memmap [ 0.058027] Normal zone: 262144 pages, LIFO batch:63 [ 0.074203] ACPI: PM-Timer IO Port: 0xb008 [ 0.074209] ACPI: Local APIC address 0xfee00000 [ 0.074218] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) [ 0.074296] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 [ 0.074300] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) [ 0.074303] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) [ 0.074305] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) [ 0.074307] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) [ 0.074309] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) [ 0.074311] ACPI: IRQ0 used by override. [ 0.074312] ACPI: IRQ5 used by override. [ 0.074313] ACPI: IRQ9 used by override. [ 0.074314] ACPI: IRQ10 used by override. [ 0.074314] ACPI: IRQ11 used by override. [ 0.074316] Using ACPI (MADT) for SMP configuration information [ 0.074319] ACPI: HPET id: 0x8086a201 base: 0xfed00000 [ 0.074328] smpboot: Allowing 4 CPUs, 0 hotplug CPUs [ 0.074375] PM: Registered nosave memory: [mem 0x00000000-0x00000fff] [ 0.074378] PM: Registered nosave memory: [mem 0x000a0000-0x000fffff] [ 0.074381] PM: Registered nosave memory: [mem 0x00800000-0x00807fff] [ 0.074383] PM: Registered nosave memory: [mem 0x00810000-0x008fffff] [ 0.074386] PM: Registered nosave memory: [mem 0xbc3bf000-0xbc3bffff] [ 0.074389] PM: Registered nosave memory: [mem 0xbc3fa000-0xbc3fafff] [ 0.074391] PM: Registered nosave memory: [mem 0xbc3fb000-0xbc3fbfff] [ 0.074394] PM: Registered nosave memory: [mem 0xbc404000-0xbc404fff] [ 0.074397] PM: Registered nosave memory: [mem 0xbeab2000-0xbeab9fff] [ 0.074398] PM: Registered nosave memory: [mem 0xbeaba000-0xbeabafff] [ 0.074401] PM: Registered nosave memory: [mem 0xbeabd000-0xbeabdfff] [ 0.074402] PM: Registered nosave memory: [mem 0xbeabe000-0xbeac1fff] [ 0.074404] PM: Registered nosave memory: [mem 0xbeac2000-0xbeadafff] [ 0.074407] PM: Registered nosave memory: [mem 0xbeafb000-0xbeb1afff] [ 0.074410] PM: Registered nosave memory: [mem 0xbfb9b000-0xbfbf2fff] [ 0.074411] PM: Registered nosave memory: [mem 0xbfbf3000-0xbfbfafff] [ 0.074413] PM: Registered nosave memory: [mem 0xbfbfb000-0xbfbfefff] [ 0.074416] PM: Registered nosave memory: [mem 0xbff40000-0xbff5ffff] [ 0.074417] PM: Registered nosave memory: [mem 0xbff60000-0xbfffffff] [ 0.074419] PM: Registered nosave memory: [mem 0xc0000000-0xffffffff] [ 0.074422] [mem 0xc0000000-0xffffffff] available for PCI devices [ 0.074425] Booting paravirtualized kernel on KVM [ 0.074429] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns [ 0.301913] random: get_random_bytes called from start_kernel+0x8f/0x4bc with crng_init=0 [ 0.301932] setup_percpu: NR_CPUS:64 nr_cpumask_bits:64 nr_cpu_ids:4 nr_node_ids:1 [ 0.303097] percpu: Embedded 44 pages/cpu s141976 r8192 d30056 u524288 [ 0.303116] pcpu-alloc: s141976 r8192 d30056 u524288 alloc=1*2097152 [ 0.303118] pcpu-alloc: [0] 0 1 2 3 [ 0.303173] KVM setup async PF for cpu 0 [ 0.303186] kvm-stealtime: cpu 0, msr 13ba160c0 [ 0.303197] PV qspinlock hash table entries: 256 (order: 0, 4096 bytes) [ 0.303205] Built 1 zonelists, mobility grouping on. Total pages: 1029679 [ 0.303207] Policy zone: Normal [ 0.303211] Kernel command line: apparmor=1 security=apparmor console=tty1 bootchooser.active=B root=PARTUUID=a3ec664e-32ce-4665-95ea-7ae90ce9aa20 rootfstype=squashfs ro rootwait zram.enabled=1 zram.num_devices=3 [ 0.336816] Calgary: detecting Calgary via BIOS EBDA area [ 0.336820] Calgary: Unable to locate Rio Grande table in EBDA - bailing! [ 0.357051] Memory: 4006564K/4191472K available (14348K kernel code, 1482K rwdata, 3280K rodata, 1452K init, 2604K bss, 184908K reserved, 0K cma-reserved) [ 0.357915] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 [ 0.357926] Kernel/User page tables isolation: enabled [ 0.358852] rcu: Hierarchical RCU implementation. [ 0.358857] rcu: RCU event tracing is enabled. [ 0.358859] rcu: RCU restricting CPUs from NR_CPUS=64 to nr_cpu_ids=4. [ 0.358862] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 [ 0.359163] NR_IRQS: 4352, nr_irqs: 456, preallocated irqs: 16 [ 0.359391] Console: colour dummy device 80x25 [ 0.359725] console [tty1] enabled [ 0.359749] ACPI: Core revision 20180810 [ 0.360236] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns [ 0.360408] hpet clockevent registered [ 0.360444] APIC: Switch to symmetric I/O mode setup [ 0.362262] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 [ 0.362312] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x159647815e3, max_idle_ns: 440795269835 ns [ 0.362335] Calibrating delay loop (skipped) preset value.. 2995.20 BogoMIPS (lpj=1497600) [ 0.362341] pid_max: default: 32768 minimum: 301 [ 0.363539] Security Framework initialized [ 0.363583] AppArmor: AppArmor initialized [ 0.365476] Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes) [ 0.367242] Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes) [ 0.367362] Mount-cache hash table entries: 8192 (order: 4, 65536 bytes) [ 0.367381] Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes) [ 0.367804] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.367810] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.367814] Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization [ 0.367820] Spectre V2 : Mitigation: Full generic retpoline [ 0.367823] Spectre V2 : Spectre v2 / SpectreRSB mitigation: Filling RSB on context switch [ 0.367827] Speculative Store Bypass: Vulnerable [ 0.367870] MDS: Vulnerable: Clear CPU buffers attempted, no microcode [ 0.368070] Freeing SMP alternatives memory: 48K [ 0.473520] smpboot: CPU0: Intel Common KVM processor (family: 0xf, model: 0x6, stepping: 0x1) [ 0.473721] Performance Events: unsupported Netburst CPU model 6 no PMU driver, software events only. [ 0.473788] rcu: Hierarchical SRCU implementation. [ 0.473985] smp: Bringing up secondary CPUs ... [ 0.474145] x86: Booting SMP configuration: [ 0.474150] .... node #0, CPUs: #1 [ 0.002980] kvm-clock: cpu 1, msr 10fb91041, secondary cpu clock [ 0.486352] KVM setup async PF for cpu 1 [ 0.486370] kvm-stealtime: cpu 1, msr 13ba960c0 [ 0.486503] #2 [ 0.002980] kvm-clock: cpu 2, msr 10fb91081, secondary cpu clock [ 0.498364] KVM setup async PF for cpu 2 [ 0.498386] kvm-stealtime: cpu 2, msr 13bb160c0 [ 0.498490] #3 [ 0.002980] kvm-clock: cpu 3, msr 10fb910c1, secondary cpu clock [ 0.511349] KVM setup async PF for cpu 3 [ 0.511367] kvm-stealtime: cpu 3, msr 13bb960c0 [ 0.511377] smp: Brought up 1 node, 4 CPUs [ 0.511377] smpboot: Max logical packages: 1 [ 0.511377] smpboot: Total of 4 processors activated (11980.80 BogoMIPS) [ 0.512331] devtmpfs: initialized [ 0.513534] PM: Registering ACPI NVS region [mem 0x00800000-0x00807fff] (32768 bytes) [ 0.513534] PM: Registering ACPI NVS region [mem 0x00810000-0x008fffff] (983040 bytes) [ 0.513534] PM: Registering ACPI NVS region [mem 0xbeab2000-0xbeab9fff] (32768 bytes) [ 0.513534] PM: Registering ACPI NVS region [mem 0xbeabe000-0xbeac1fff] (16384 bytes) [ 0.513534] PM: Registering ACPI NVS region [mem 0xbfbfb000-0xbfbfefff] (16384 bytes) [ 0.513534] PM: Registering ACPI NVS region [mem 0xbff60000-0xbfffffff] (655360 bytes) [ 0.513709] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.513724] futex hash table entries: 1024 (order: 4, 65536 bytes) [ 0.514017] RTC time: 14:25:29, date: 03/30/20 [ 0.514456] NET: Registered protocol family 16 [ 0.514674] audit: initializing netlink subsys (disabled) [ 0.514730] audit: type=2000 audit(1585578330.792:1): state=initialized audit_enabled=0 res=1 [ 0.515365] cpuidle: using governor menu [ 0.515667] ACPI: bus type PCI registered [ 0.515844] PCI: Using configuration type 1 for base access [ 0.530071] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.530528] ACPI: Added _OSI(Module Device) [ 0.530537] ACPI: Added _OSI(Processor Device) [ 0.530540] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.530543] ACPI: Added _OSI(Processor Aggregator Device) [ 0.530546] ACPI: Added _OSI(Linux-Dell-Video) [ 0.530550] ACPI: Added _OSI(Linux-Lenovo-NV-HDMI-Audio) [ 0.532094] ACPI: 2 ACPI AML tables successfully acquired and loaded [ 0.533647] ACPI: Interpreter enabled [ 0.533674] ACPI: (supports S0 S3 S4 S5) [ 0.533678] ACPI: Using IOAPIC for interrupt routing [ 0.533727] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.534016] ACPI: Enabled 3 GPEs in block 00 to 0F [ 0.543750] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.543765] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI] [ 0.543774] acpi PNP0A03:00: _OSC failed (AE_NOT_FOUND); disabling ASPM [ 0.543787] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.543851] PCI host bridge to bus 0000:00 [ 0.543858] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.543862] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.543866] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.543870] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] [ 0.543875] pci_bus 0000:00: root bus resource [mem 0x800000000-0x87fffffff window] [ 0.543880] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.543941] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.544528] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.545246] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.550333] pci 0000:00:01.1: reg 0x20: [io 0xd320-0xd32f] [ 0.552277] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.552289] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.552293] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.552296] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.552637] pci 0000:00:01.2: [8086:7020] type 00 class 0x0c0300 [ 0.558089] pci 0000:00:01.2: reg 0x20: [io 0xd300-0xd31f] [ 0.559666] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.560412] pci 0000:00:01.3: quirk: [io 0xb000-0xb03f] claimed by PIIX4 ACPI [ 0.560434] pci 0000:00:01.3: quirk: [io 0xb100-0xb10f] claimed by PIIX4 SMB [ 0.560754] pci 0000:00:02.0: [1234:1111] type 00 class 0x030000 [ 0.562354] pci 0000:00:02.0: reg 0x10: [mem 0xc0000000-0xc0ffffff pref] [ 0.566350] pci 0000:00:02.0: reg 0x18: [mem 0xc1444000-0xc1444fff] [ 0.574347] pci 0000:00:02.0: reg 0x30: [mem 0xffff0000-0xffffffff pref] [ 0.574430] pci 0000:00:02.0: BAR 0: assigned to efifb [ 0.574801] pci 0000:00:03.0: [1af4:1002] type 00 class 0x00ff00 [ 0.576334] pci 0000:00:03.0: reg 0x10: [io 0xd2e0-0xd2ff] [ 0.582990] pci 0000:00:03.0: reg 0x20: [mem 0x800000000-0x800003fff 64bit pref] [ 0.585119] pci 0000:00:07.0: [8086:2922] type 00 class 0x010601 [ 0.593333] pci 0000:00:07.0: reg 0x20: [io 0xd2c0-0xd2df] [ 0.594334] pci 0000:00:07.0: reg 0x24: [mem 0xc1443000-0xc1443fff] [ 0.596616] pci 0000:00:08.0: [1af4:1003] type 00 class 0x078000 [ 0.598333] pci 0000:00:08.0: reg 0x10: [io 0xd200-0xd23f] [ 0.600334] pci 0000:00:08.0: reg 0x14: [mem 0xc1442000-0xc1442fff] [ 0.607334] pci 0000:00:08.0: reg 0x20: [mem 0x800004000-0x800007fff 64bit pref] [ 0.610139] pci 0000:00:12.0: [1af4:1000] type 00 class 0x020000 [ 0.611333] pci 0000:00:12.0: reg 0x10: [io 0xd2a0-0xd2bf] [ 0.615249] pci 0000:00:12.0: reg 0x14: [mem 0xc1441000-0xc1441fff] [ 0.620334] pci 0000:00:12.0: reg 0x20: [mem 0x800008000-0x80000bfff 64bit pref] [ 0.622335] pci 0000:00:12.0: reg 0x30: [mem 0xfffc0000-0xffffffff pref] [ 0.623337] pci 0000:00:1d.0: [8086:2934] type 00 class 0x0c0300 [ 0.627647] pci 0000:00:1d.0: reg 0x20: [io 0xd280-0xd29f] [ 0.629453] pci 0000:00:1d.1: [8086:2935] type 00 class 0x0c0300 [ 0.632643] pci 0000:00:1d.1: reg 0x20: [io 0xd260-0xd27f] [ 0.634378] pci 0000:00:1d.2: [8086:2936] type 00 class 0x0c0300 [ 0.638935] pci 0000:00:1d.2: reg 0x20: [io 0xd240-0xd25f] [ 0.640636] pci 0000:00:1d.7: [8086:293a] type 00 class 0x0c0320 [ 0.641329] pci 0000:00:1d.7: reg 0x10: [mem 0xc1440000-0xc1440fff] [ 0.645395] pci 0000:00:1e.0: [1b36:0001] type 01 class 0x060400 [ 0.647334] pci 0000:00:1e.0: reg 0x10: [mem 0x80000d000-0x80000d0ff 64bit] [ 0.650836] pci 0000:00:1f.0: [1b36:0001] type 01 class 0x060400 [ 0.652984] pci 0000:00:1f.0: reg 0x10: [mem 0x80000c000-0x80000c0ff 64bit] [ 0.655461] pci_bus 0000:01: extended config space not accessible [ 0.655778] pci 0000:00:1e.0: PCI bridge to [bus 01] [ 0.655804] pci 0000:00:1e.0: bridge window [io 0xd000-0xdfff] [ 0.655825] pci 0000:00:1e.0: bridge window [mem 0xc1200000-0xc13fffff] [ 0.656505] pci_bus 0000:02: extended config space not accessible [ 0.656818] pci 0000:00:1f.0: PCI bridge to [bus 02] [ 0.656844] pci 0000:00:1f.0: bridge window [io 0xc000-0xcfff] [ 0.656864] pci 0000:00:1f.0: bridge window [mem 0xc1000000-0xc11fffff] [ 0.660600] ACPI: PCI Interrupt Link [LNKA] (IRQs 5 10 *11) [ 0.660767] ACPI: PCI Interrupt Link [LNKB] (IRQs 5 10 *11) [ 0.660909] ACPI: PCI Interrupt Link [LNKC] (IRQs 5 *10 11) [ 0.661050] ACPI: PCI Interrupt Link [LNKD] (IRQs 5 *10 11) [ 0.661129] ACPI: PCI Interrupt Link [LNKS] (IRQs *9) [ 0.661876] pci 0000:00:02.0: vgaarb: setting as boot VGA device [ 0.661885] pci 0000:00:02.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none [ 0.661894] pci 0000:00:02.0: vgaarb: bridge control possible [ 0.661897] vgaarb: loaded [ 0.662043] SCSI subsystem initialized [ 0.662081] libata version 3.00 loaded. [ 0.662375] ACPI: bus type USB registered [ 0.662418] usbcore: registered new interface driver usbfs [ 0.662418] usbcore: registered new interface driver hub [ 0.662518] usbcore: registered new device driver usb [ 0.662518] pps_core: LinuxPPS API ver. 1 registered [ 0.662518] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.662518] PTP clock support registered [ 0.662567] EDAC MC: Ver: 3.0.0 [ 0.663379] Registered efivars operations [ 0.663464] Advanced Linux Sound Architecture Driver Initialized. [ 0.663486] PCI: Using ACPI for IRQ routing [ 0.663491] PCI: pci_cache_line_size set to 64 bytes [ 0.663545] pci 0000:00:01.1: can't claim BAR 4 [io 0xd320-0xd32f]: address conflict with PCI Bus 0000:01 [io 0xd000-0xdfff] [ 0.663566] pci 0000:00:01.2: can't claim BAR 4 [io 0xd300-0xd31f]: address conflict with PCI Bus 0000:01 [io 0xd000-0xdfff] [ 0.663604] pci 0000:00:03.0: can't claim BAR 0 [io 0xd2e0-0xd2ff]: address conflict with PCI Bus 0000:01 [io 0xd000-0xdfff] [ 0.663624] pci 0000:00:07.0: can't claim BAR 4 [io 0xd2c0-0xd2df]: address conflict with PCI Bus 0000:01 [io 0xd000-0xdfff] [ 0.663643] pci 0000:00:08.0: can't claim BAR 0 [io 0xd200-0xd23f]: address conflict with PCI Bus 0000:01 [io 0xd000-0xdfff] [ 0.663663] pci 0000:00:12.0: can't claim BAR 0 [io 0xd2a0-0xd2bf]: address conflict with PCI Bus 0000:01 [io 0xd000-0xdfff] [ 0.663683] pci 0000:00:1d.0: can't claim BAR 4 [io 0xd280-0xd29f]: address conflict with PCI Bus 0000:01 [io 0xd000-0xdfff] [ 0.663702] pci 0000:00:1d.1: can't claim BAR 4 [io 0xd260-0xd27f]: address conflict with PCI Bus 0000:01 [io 0xd000-0xdfff] [ 0.663721] pci 0000:00:1d.2: can't claim BAR 4 [io 0xd240-0xd25f]: address conflict with PCI Bus 0000:01 [io 0xd000-0xdfff] [ 0.663721] e820: reserve RAM buffer [mem 0x00810000-0x008fffff] [ 0.663721] e820: reserve RAM buffer [mem 0xbc3bf018-0xbfffffff] [ 0.663721] e820: reserve RAM buffer [mem 0xbc3fb018-0xbfffffff] [ 0.663721] e820: reserve RAM buffer [mem 0xbeab2000-0xbfffffff] [ 0.663721] e820: reserve RAM buffer [mem 0xbeabd000-0xbfffffff] [ 0.663721] e820: reserve RAM buffer [mem 0xbeafb000-0xbfffffff] [ 0.663721] e820: reserve RAM buffer [mem 0xbfb9b000-0xbfffffff] [ 0.663721] e820: reserve RAM buffer [mem 0xbff40000-0xbfffffff] [ 0.663721] Bluetooth: Core ver 2.22 [ 0.663721] NET: Registered protocol family 31 [ 0.663721] Bluetooth: HCI device and connection manager initialized [ 0.663721] Bluetooth: HCI socket layer initialized [ 0.663721] Bluetooth: L2CAP socket layer initialized [ 0.663721] Bluetooth: SCO socket layer initialized [ 0.663721] NetLabel: Initializing [ 0.663721] NetLabel: domain hash size = 128 [ 0.663721] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.663721] NetLabel: unlabeled traffic allowed by default [ 0.664555] HPET: 3 timers in total, 0 timers will be used for per-cpu timer [ 0.664598] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.664604] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 0.668381] clocksource: Switched to clocksource kvm-clock [ 0.687140] VFS: Disk quotas dquot_6.6.0 [ 0.687171] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.687364] AppArmor: AppArmor Filesystem Enabled [ 0.687407] pnp: PnP ACPI init [ 0.687508] pnp 00:00: Plug and Play ACPI device, IDs PNP0b00 (active) [ 0.687579] pnp 00:01: Plug and Play ACPI device, IDs PNP0303 (active) [ 0.687632] pnp 00:02: Plug and Play ACPI device, IDs PNP0f13 (active) [ 0.687648] pnp 00:03: [dma 2] [ 0.687677] pnp 00:03: Plug and Play ACPI device, IDs PNP0700 (active) [ 0.688232] pnp: PnP ACPI: found 4 devices [ 0.698718] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 0.698739] pci 0000:00:02.0: can't claim BAR 6 [mem 0xffff0000-0xffffffff pref]: no compatible bridge window [ 0.698745] pci 0000:00:12.0: can't claim BAR 6 [mem 0xfffc0000-0xffffffff pref]: no compatible bridge window [ 0.700432] pci 0000:00:12.0: BAR 6: assigned [mem 0xc1400000-0xc143ffff pref] [ 0.700445] pci 0000:00:02.0: BAR 6: assigned [mem 0xc1450000-0xc145ffff pref] [ 0.700451] pci 0000:00:08.0: BAR 0: assigned [io 0x1000-0x103f] [ 0.701068] pci 0000:00:01.2: BAR 4: assigned [io 0x1040-0x105f] [ 0.701752] pci 0000:00:03.0: BAR 0: assigned [io 0x1060-0x107f] [ 0.702383] pci 0000:00:07.0: BAR 4: assigned [io 0x1080-0x109f] [ 0.703004] pci 0000:00:12.0: BAR 0: assigned [io 0x10a0-0x10bf] [ 0.703656] pci 0000:00:1d.0: BAR 4: assigned [io 0x10c0-0x10df] [ 0.704264] pci 0000:00:1d.1: BAR 4: assigned [io 0x10e0-0x10ff] [ 0.704920] pci 0000:00:1d.2: BAR 4: assigned [io 0x1400-0x141f] [ 0.705591] pci 0000:00:01.1: BAR 4: assigned [io 0x1420-0x142f] [ 0.706167] pci 0000:00:1e.0: PCI bridge to [bus 01] [ 0.706187] pci 0000:00:1e.0: bridge window [io 0xd000-0xdfff] [ 0.707302] pci 0000:00:1e.0: bridge window [mem 0xc1200000-0xc13fffff] [ 0.710002] pci 0000:00:1f.0: PCI bridge to [bus 02] [ 0.711369] pci 0000:00:1f.0: bridge window [io 0xc000-0xcfff] [ 0.712476] pci 0000:00:1f.0: bridge window [mem 0xc1000000-0xc11fffff] [ 0.714581] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 0.714584] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 0.714586] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 0.714588] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] [ 0.714590] pci_bus 0000:00: resource 8 [mem 0x800000000-0x87fffffff window] [ 0.714594] pci_bus 0000:01: resource 0 [io 0xd000-0xdfff] [ 0.714596] pci_bus 0000:01: resource 1 [mem 0xc1200000-0xc13fffff] [ 0.714598] pci_bus 0000:02: resource 0 [io 0xc000-0xcfff] [ 0.714599] pci_bus 0000:02: resource 1 [mem 0xc1000000-0xc11fffff] [ 0.714800] NET: Registered protocol family 2 [ 0.715019] tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes) [ 0.715054] TCP established hash table entries: 32768 (order: 6, 262144 bytes) [ 0.715184] TCP bind hash table entries: 32768 (order: 7, 524288 bytes) [ 0.715419] TCP: Hash tables configured (established 32768 bind 32768) [ 0.715506] UDP hash table entries: 2048 (order: 4, 65536 bytes) [ 0.715546] UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes) [ 0.715639] NET: Registered protocol family 1 [ 0.716057] RPC: Registered named UNIX socket transport module. [ 0.716067] RPC: Registered udp transport module. [ 0.716070] RPC: Registered tcp transport module. [ 0.716072] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.716430] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 0.716457] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.716478] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 0.753843] PCI Interrupt Link [LNKD] enabled at IRQ 10 [ 0.790902] pci 0000:00:01.2: quirk_usb_early_handoff+0x0/0x690 took 72637 usecs [ 0.791010] pci 0000:00:02.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff] [ 0.827692] PCI Interrupt Link [LNKA] enabled at IRQ 11 [ 0.864576] pci 0000:00:1d.0: quirk_usb_early_handoff+0x0/0x690 took 71764 usecs [ 0.901040] PCI Interrupt Link [LNKB] enabled at IRQ 11 [ 0.937897] pci 0000:00:1d.1: quirk_usb_early_handoff+0x0/0x690 took 71547 usecs [ 0.974519] PCI Interrupt Link [LNKC] enabled at IRQ 10 [ 1.012056] pci 0000:00:1d.2: quirk_usb_early_handoff+0x0/0x690 took 72362 usecs [ 1.085451] pci 0000:00:1d.7: quirk_usb_early_handoff+0x0/0x690 took 71606 usecs [ 1.085516] PCI: CLS 0 bytes, default 64 [ 1.085588] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) [ 1.085594] software IO TLB: mapped [mem 0xb7f1e000-0xbbf1e000] (64MB) [ 1.085758] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x159647815e3, max_idle_ns: 440795269835 ns [ 1.086944] Scanning for low memory corruption every 60 seconds [ 1.087853] Initialise system trusted keyrings [ 1.087966] workingset: timestamp_bits=40 max_order=20 bucket_order=0 [ 1.091346] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ 1.091740] NFS: Registering the id_resolver key type [ 1.091756] Key type id_resolver registered [ 1.091758] Key type id_legacy registered [ 1.094547] Key type asymmetric registered [ 1.094556] Asymmetric key parser 'x509' registered [ 1.094596] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 251) [ 1.094695] io scheduler noop registered [ 1.094700] io scheduler deadline registered [ 1.094767] io scheduler cfq registered (default) [ 1.094772] io scheduler mq-deadline registered [ 1.094775] io scheduler kyber registered [ 1.095063] hv_vmbus: registering driver hyperv_fb [ 1.095076] efifb: probing for efifb [ 1.095100] efifb: framebuffer at 0xc0000000, using 1876k, total 1875k [ 1.095104] efifb: mode is 800x600x32, linelength=3200, pages=1 [ 1.095106] efifb: scrolling: redraw [ 1.095110] efifb: Truecolor: size=8:8:8:8, shift=24:16:8:0 [ 1.098259] Console: switching to colour frame buffer device 100x37 [ 1.100187] fb0: EFI VGA frame buffer device [ 1.100500] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 1.100589] ACPI: Power Button [PWRF] [ 1.213914] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 1.214790] Non-volatile memory driver v1.3 [ 1.214875] Linux agpgart interface v0.103 [ 1.218897] loop: module loaded [ 1.219156] zram: Added device: zram0 [ 1.219346] zram: Added device: zram1 [ 1.219507] zram: Added device: zram2 [ 1.219572] Guest personality initialized and is inactive [ 1.219650] VMCI host device registered (name=vmci, major=10, minor=62) [ 1.220491] Initialized host personality [ 1.221508] VMware PVSCSI driver - version 1.0.7.0-k [ 1.222423] hv_vmbus: registering driver hv_storvsc [ 1.223458] ahci 0000:00:07.0: version 3.0 [ 1.260982] ahci 0000:00:07.0: AHCI 0001.0000 32 slots 6 ports 1.5 Gbps 0x3f impl SATA mode [ 1.261854] ahci 0000:00:07.0: flags: 64bit ncq only [ 1.264657] scsi host0: ahci [ 1.265960] scsi host1: ahci [ 1.267137] scsi host2: ahci [ 1.269148] scsi host3: ahci [ 1.270423] scsi host4: ahci [ 1.271602] scsi host5: ahci [ 1.272603] ata1: SATA max UDMA/133 abar m4096@0xc1443000 port 0xc1443100 irq 24 [ 1.273537] ata2: SATA max UDMA/133 abar m4096@0xc1443000 port 0xc1443180 irq 24 [ 1.274309] ata3: SATA max UDMA/133 abar m4096@0xc1443000 port 0xc1443200 irq 24 [ 1.275128] ata4: SATA max UDMA/133 abar m4096@0xc1443000 port 0xc1443280 irq 24 [ 1.275928] ata5: SATA max UDMA/133 abar m4096@0xc1443000 port 0xc1443300 irq 24 [ 1.276703] ata6: SATA max UDMA/133 abar m4096@0xc1443000 port 0xc1443380 irq 24 [ 1.277555] ata_piix 0000:00:01.1: version 2.13 [ 1.278493] scsi host6: ata_piix [ 1.279530] scsi host7: ata_piix [ 1.280367] ata7: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0x1420 irq 14 [ 1.281048] ata8: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0x1428 irq 15 [ 1.281989] ata7: port disabled--ignoring [ 1.281991] tun: Universal TUN/TAP device driver, 1.6 [ 1.282921] ata8: port disabled--ignoring [ 1.285351] e100: Intel(R) PRO/100 Network Driver, 3.5.24-k2-NAPI [ 1.286027] e100: Copyright(c) 1999-2006 Intel Corporation [ 1.286801] e1000: Intel(R) PRO/1000 Network Driver - version 7.3.21-k8-NAPI [ 1.287500] e1000: Copyright (c) 1999-2006 Intel Corporation. [ 1.288249] e1000e: Intel(R) PRO/1000 Network Driver - 3.2.6-k [ 1.288937] e1000e: Copyright(c) 1999 - 2015 Intel Corporation. [ 1.289718] sky2: driver version 1.30 [ 1.290547] VMware vmxnet3 virtual NIC driver - version 1.4.16.0-k-NAPI [ 1.291287] hv_vmbus: registering driver hv_netvsc [ 1.292257] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver [ 1.293020] ehci-pci: EHCI PCI platform driver [ 1.331304] ehci-pci 0000:00:1d.7: EHCI Host Controller [ 1.332278] ehci-pci 0000:00:1d.7: new USB bus registered, assigned bus number 1 [ 1.333551] ehci-pci 0000:00:1d.7: irq 10, io mem 0xc1440000 [ 1.342387] ehci-pci 0000:00:1d.7: USB 2.0 started, EHCI 1.00 [ 1.343232] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 4.19 [ 1.344040] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1 [ 1.344810] usb usb1: Product: EHCI Host Controller [ 1.345612] usb usb1: Manufacturer: Linux 4.19.102 ehci_hcd [ 1.346308] usb usb1: SerialNumber: 0000:00:1d.7 [ 1.347365] hub 1-0:1.0: USB hub found [ 1.348108] hub 1-0:1.0: 6 ports detected [ 1.349431] ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver [ 1.350199] ohci-pci: OHCI PCI platform driver [ 1.351087] uhci_hcd: USB Universal Host Controller Interface driver [ 1.389151] uhci_hcd 0000:00:01.2: UHCI Host Controller [ 1.390236] uhci_hcd 0000:00:01.2: new USB bus registered, assigned bus number 2 [ 1.391302] uhci_hcd 0000:00:01.2: irq 10, io base 0x00001040 [ 1.392396] usb usb2: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 4.19 [ 1.393261] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1 [ 1.394279] usb usb2: Product: UHCI Host Controller [ 1.395248] usb usb2: Manufacturer: Linux 4.19.102 uhci_hcd [ 1.396205] usb usb2: SerialNumber: 0000:00:01.2 [ 1.397453] hub 2-0:1.0: USB hub found [ 1.398417] hub 2-0:1.0: 2 ports detected [ 1.437172] uhci_hcd 0000:00:1d.0: UHCI Host Controller [ 1.438415] uhci_hcd 0000:00:1d.0: new USB bus registered, assigned bus number 3 [ 1.439623] uhci_hcd 0000:00:1d.0: irq 11, io base 0x000010c0 [ 1.440727] usb usb3: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 4.19 [ 1.441734] usb usb3: New USB device strings: Mfr=3, Product=2, SerialNumber=1 [ 1.442674] usb usb3: Product: UHCI Host Controller [ 1.443609] usb usb3: Manufacturer: Linux 4.19.102 uhci_hcd [ 1.444548] usb usb3: SerialNumber: 0000:00:1d.0 [ 1.445772] hub 3-0:1.0: USB hub found [ 1.446730] hub 3-0:1.0: 2 ports detected [ 1.485181] uhci_hcd 0000:00:1d.1: UHCI Host Controller [ 1.486289] uhci_hcd 0000:00:1d.1: new USB bus registered, assigned bus number 4 [ 1.487428] uhci_hcd 0000:00:1d.1: irq 11, io base 0x000010e0 [ 1.488526] usb usb4: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 4.19 [ 1.489513] usb usb4: New USB device strings: Mfr=3, Product=2, SerialNumber=1 [ 1.490428] usb usb4: Product: UHCI Host Controller [ 1.491303] usb usb4: Manufacturer: Linux 4.19.102 uhci_hcd [ 1.492205] usb usb4: SerialNumber: 0000:00:1d.1 [ 1.493457] hub 4-0:1.0: USB hub found [ 1.494376] hub 4-0:1.0: 2 ports detected [ 1.532616] uhci_hcd 0000:00:1d.2: UHCI Host Controller [ 1.533713] uhci_hcd 0000:00:1d.2: new USB bus registered, assigned bus number 5 [ 1.534817] uhci_hcd 0000:00:1d.2: irq 10, io base 0x00001400 [ 1.535892] usb usb5: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 4.19 [ 1.536811] usb usb5: New USB device strings: Mfr=3, Product=2, SerialNumber=1 [ 1.537774] usb usb5: Product: UHCI Host Controller [ 1.538665] usb usb5: Manufacturer: Linux 4.19.102 uhci_hcd [ 1.539567] usb usb5: SerialNumber: 0000:00:1d.2 [ 1.540768] hub 5-0:1.0: USB hub found [ 1.541730] hub 5-0:1.0: 2 ports detected [ 1.542983] usbcore: registered new interface driver usblp [ 1.543936] usbcore: registered new interface driver usb-storage [ 1.544867] usbcore: registered new interface driver usbserial_generic [ 1.545784] usbserial: USB Serial support registered for generic [ 1.546721] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 1.548872] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 1.549825] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 1.551248] rtc_cmos 00:00: RTC can wake from S4 [ 1.553030] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 1.555452] rtc_cmos 00:00: registered as rtc0 [ 1.556418] rtc_cmos 00:00: alarms up to one day, y3k, 114 bytes nvram, hpet irqs [ 1.557759] device-mapper: ioctl: 4.39.0-ioctl (2018-04-03) initialised: dm-devel@redhat.com [ 1.558772] usbcore: registered new interface driver btusb [ 1.559780] EFI Variables Facility v0.08 2004-May-17 [ 1.563238] hidraw: raw HID events driver (C) Jiri Kosina [ 1.564507] usbcore: registered new interface driver usbhid [ 1.565506] usbhid: USB HID core driver [ 1.567901] IPVS: Registered protocols () [ 1.568894] IPVS: Connection hash table configured (size=4096, memory=64Kbytes) [ 1.570531] IPVS: ipvs loaded. [ 1.571448] IPVS: [rr] scheduler registered. [ 1.573428] NET: Registered protocol family 10 [ 1.574782] Segment Routing with IPv6 [ 1.576782] sit: IPv6, IPv4 and MPLS over IPv4 tunneling driver [ 1.577985] NET: Registered protocol family 17 [ 1.578953] Bridge firewalling registered [ 1.579913] Key type dns_resolver registered [ 1.581488] mce: Using 10 MCE banks [ 1.582477] sched_clock: Marking stable (1580408534, 1980187)->(1586532225, -4143504) [ 1.583465] ata5: SATA link down (SStatus 0 SControl 300) [ 1.584803] ata6: SATA link down (SStatus 0 SControl 300) [ 1.585167] registered taskstats version 1 [ 1.586112] ata2: SATA link down (SStatus 0 SControl 300) [ 1.586628] Loading compiled-in X.509 certificates [ 1.587929] ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 300) [ 1.588420] AppArmor: AppArmor sha1 policy hashing enabled [ 1.589775] ata4: SATA link down (SStatus 0 SControl 300) [ 1.590709] Magic number: 12:96:436 [ 1.591296] ata1.00: ATA-7: QEMU HARDDISK, 2.5+, max UDMA/100 [ 1.592991] ata1.00: 50331648 sectors, multi 16: LBA48 NCQ (depth 32) [ 1.592996] ata1.00: applying bridge limits [ 1.593373] ata3: SATA link down (SStatus 0 SControl 300) [ 1.594144] console [netcon0] enabled [ 1.596014] ata1.00: configured for UDMA/100 [ 1.596920] netconsole: network logging started [ 1.598170] scsi 0:0:0:0: Direct-Access ATA QEMU HARDDISK 2.5+ PQ: 0 ANSI: 5 [ 1.598936] cfg80211: Loading compiled-in X.509 certificates for regulatory database [ 1.600035] sd 0:0:0:0: [sda] 50331648 512-byte logical blocks: (25.8 GB/24.0 GiB) [ 1.600076] sd 0:0:0:0: Attached scsi generic sg0 type 0 [ 1.601694] cfg80211: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' [ 1.602582] sd 0:0:0:0: [sda] Write Protect is off [ 1.603461] ALSA device list: [ 1.604372] sd 0:0:0:0: [sda] Mode Sense: 00 3a 00 00 [ 1.605228] No soundcards found. [ 1.605354] sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA [ 1.606262] platform regulatory.0: Direct firmware load for regulatory.db failed with error -2 [ 1.608973] cfg80211: failed to load regulatory.db [ 1.616220] sda: sda1 sda2 sda3 sda4 sda5 sda6 sda7 sda8 [ 1.618403] sd 0:0:0:0: [sda] Attached SCSI disk [ 1.722366] usb 2-1: new full-speed USB device number 2 using uhci_hcd [ 1.863308] usb 2-1: not running at top speed; connect to a high speed hub [ 1.866364] usb 3-1: new full-speed USB device number 2 using uhci_hcd [ 1.896842] usb 2-1: New USB device found, idVendor=0627, idProduct=0001, bcdDevice= 0.00 [ 1.898046] usb 2-1: New USB device strings: Mfr=1, Product=3, SerialNumber=10 [ 1.899094] usb 2-1: Product: QEMU USB Tablet [ 1.900118] usb 2-1: Manufacturer: QEMU [ 1.901117] usb 2-1: SerialNumber: 28754-0000:00:01.2-1 [ 1.910506] input: QEMU QEMU USB Tablet as /devices/pci0000:00/0000:00:01.2/usb2/2-1/2-1:1.0/0003:0627:0001.0001/input/input4 [ 1.912975] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:01.2-1/input0 [ 2.037301] usb 3-1: New USB device found, idVendor=0403, idProduct=6015, bcdDevice=10.00 [ 2.038529] usb 3-1: New USB device strings: Mfr=1, Product=2, SerialNumber=3 [ 2.039658] usb 3-1: Product: FT230X Basic UART [ 2.040775] usb 3-1: Manufacturer: FTDI [ 2.041915] usb 3-1: SerialNumber: DM01DH75 [ 2.177217] random: fast init done [ 2.178931] input: ImExPS/2 Generic Explorer Mouse as /devices/platform/i8042/serio1/input/input3 [ 2.180500] md: Waiting for all devices to be available before autodetect [ 2.181689] md: If you don't use raid, use raid=noautodetect [ 2.183135] md: Autodetecting RAID arrays. [ 2.184280] md: autorun ... [ 2.185452] md: ... autorun DONE. [ 2.191639] VFS: Mounted root (squashfs filesystem) readonly on device 8:5. [ 2.195183] devtmpfs: mounted [ 2.197869] Freeing unused kernel image memory: 1452K [ 2.204424] Write protecting the kernel read-only data: 20480k [ 2.206605] Freeing unused kernel image memory: 2000K [ 2.208086] Freeing unused kernel image memory: 816K [ 2.209469] Run /sbin/init as init process [ 2.327487] systemd[1]: systemd 240 running in system mode. (-PAM -AUDIT -SELINUX -IMA -APPARMOR -SMACK +SYSVINIT +UTMP -LIBCRYPTSETUP +GCRYPT -GNUTLS -ACL -XZ -LZ4 -SECCOMP +BLKID -ELFUTILS +KMOD -IDN2 -IDN -PCRE2 default-hierarchy=hybrid) [ 2.330862] systemd[1]: Detected virtualization kvm. [ 2.331972] systemd[1]: Detected architecture x86-64. [ 2.346088] systemd[1]: Set hostname to . [ 2.348820] systemd[1]: Initializing machine ID from KVM UUID. [ 2.350045] systemd[1]: Installed transient /etc/machine-id file. [ 2.352713] systemd[1]: Failed to bump fs.file-max, ignoring: Invalid argument [ 2.445167] systemd[1]: /usr/lib/systemd/system/vmtoolsd.service:7: PIDFile= references path below legacy directory /var/run/, updating /var/run/vmtoolsd.pid → /run/vmtoolsd.pid; please update the unit file accordingly. [ 2.472083] random: systemd: uninitialized urandom read (16 bytes read) [ 2.476458] random: systemd: uninitialized urandom read (16 bytes read) [ 2.477789] systemd[1]: Started Forward Password Requests to Wall Directory Watch. [ 2.480185] random: systemd: uninitialized urandom read (16 bytes read) [ 2.488421] systemd[1]: Listening on Process Core Dump Socket. [ 2.542453] zram1: detected capacity change from 0 to 33554432 [ 2.544299] zram2: detected capacity change from 0 to 16777216 [ 2.546220] zram0: detected capacity change from 0 to 1032278016 [ 2.835097] virtio_net virtio2 enp0s18: renamed from eth0 [ 2.858977] usbcore: registered new interface driver ftdi_sio [ 2.860591] usbserial: USB Serial support registered for FTDI USB Serial Device [ 2.862077] ftdi_sio 3-1:1.0: FTDI USB Serial Device converter detected [ 2.863835] usb 3-1: Detected FT-X [ 2.890240] usb 3-1: FTDI USB Serial Device converter now attached to ttyUSB0 [ 3.254735] EXT4-fs (sda7): mounted filesystem with ordered data mode. Opts: (null) [ 3.383039] EXT4-fs (sda8): mounted filesystem with ordered data mode. Opts: (null) [ 3.578904] Adding 1008080k swap on /dev/zram0. Priority:-2 extents:1 across:1008080k SS [ 3.740743] random: crng init done [ 3.741554] random: 7 urandom warning(s) missed due to ratelimiting [ 3.826801] EXT4-fs (zram1): mounted filesystem without journal. Opts: nobarrier [ 3.830757] EXT4-fs (zram2): mounted filesystem without journal. Opts: nobarrier [ 3.927060] systemd-journald[1406]: Received request to flush runtime journal from PID 1 [ 4.482614] audit: type=1400 audit(1585578334.759:2): apparmor="STATUS" operation="profile_load" profile="unconfined" name="hassio-supervisor" pid=2984 comm="apparmor_parser" [ 4.484525] audit: type=1400 audit(1585578334.759:2): apparmor="STATUS" operation="profile_load" profile="unconfined" name="hassio-supervisor///usr/bin/gdbus" pid=2984 comm="apparmor_parser" [ 4.484534] audit: type=1400 audit(1585578334.759:2): apparmor="STATUS" operation="profile_load" profile="unconfined" name="hassio-supervisor///usr/bin/git" pid=2984 comm="apparmor_parser" [ 4.488541] audit: type=1400 audit(1585578334.759:2): apparmor="STATUS" operation="profile_load" profile="unconfined" name="hassio-supervisor///usr/bin/socat" pid=2984 comm="apparmor_parser" [ 4.490727] audit: type=1300 audit(1585578334.759:2): arch=c000003e syscall=1 success=yes exit=69956 a0=6 a1=7f37e2421010 a2=11144 a3=fc items=0 ppid=2982 pid=2984 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="apparmor_parser" exe="/usr/sbin/apparmor_parser" subj==unconfined key=(null) [ 4.495006] audit: type=1327 audit(1585578334.759:2): proctitle=61707061726D6F725F706172736572002D72002D57002D4C002F6D6E742F646174612F73757065727669736F722F61707061726D6F722F6361636865002F6D6E742F646174612F73757065727669736F722F61707061726D6F722F68617373696F2D73757065727669736F72 [ 4.737891] IPv6: ADDRCONF(NETDEV_UP): enp0s18: link is not ready [ 5.910396] audit: type=1400 audit(1585578336.187:3): apparmor="STATUS" operation="profile_load" profile="unconfined" name="docker-default" pid=3036 comm="apparmor_parser" [ 5.912855] audit: type=1300 audit(1585578336.187:3): arch=c000003e syscall=1 success=yes exit=7945 a0=4 a1=87c640 a2=1f09 a3=fc items=0 ppid=3035 pid=3036 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="apparmor_parser" exe="/usr/sbin/apparmor_parser" subj==unconfined key=(null) [ 5.917841] audit: type=1327 audit(1585578336.187:3): proctitle=61707061726D6F725F706172736572002D4B72002F6D6E742F646174612F646F636B65722F746D702F646F636B65722D64656661756C74343237333737313735 [ 7.808900] audit: type=1325 audit(1585578338.086:4): table=nat family=2 entries=5 [ 7.810274] audit: type=1300 audit(1585578338.086:4): arch=c000003e syscall=54 success=yes exit=0 a0=4 a1=0 a2=40 a3=117da60 items=0 ppid=3007 pid=3065 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="iptables" exe="/usr/sbin/xtables-legacy-multi" subj==unconfined key=(null) [ 7.815215] audit: type=1327 audit(1585578338.086:4): proctitle=2F7573722F7362696E2F69707461626C6573002D2D77616974002D74006E6174002D4E00444F434B4552 [ 7.817745] audit: type=1325 audit(1585578338.088:5): table=filter family=2 entries=4 [ 7.819037] audit: type=1300 audit(1585578338.088:5): arch=c000003e syscall=54 success=yes exit=0 a0=4 a1=0 a2=40 a3=19f8940 items=0 ppid=3007 pid=3067 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="iptables" exe="/usr/sbin/xtables-legacy-multi" subj==unconfined key=(null) [ 7.823961] audit: type=1327 audit(1585578338.088:5): proctitle=2F7573722F7362696E2F69707461626C6573002D2D77616974002D740066696C746572002D4E00444F434B4552 [ 7.826663] audit: type=1325 audit(1585578338.096:6): table=filter family=2 entries=6 [ 7.894477] Initializing XFRM netlink socket [ 8.014160] IPv6: ADDRCONF(NETDEV_UP): hassio: link is not ready [ 8.085580] IPv6: ADDRCONF(NETDEV_UP): docker0: link is not ready [ 10.456921] docker0: port 1(vetha856d19) entered blocking state [ 10.458349] docker0: port 1(vetha856d19) entered disabled state [ 10.460312] device vetha856d19 entered promiscuous mode [ 10.461963] IPv6: ADDRCONF(NETDEV_UP): vetha856d19: link is not ready [ 10.506859] hassio: port 1(veth466a8a8) entered blocking state [ 10.508150] hassio: port 1(veth466a8a8) entered disabled state [ 10.509530] device veth466a8a8 entered promiscuous mode [ 10.510999] IPv6: ADDRCONF(NETDEV_UP): veth466a8a8: link is not ready [ 10.512231] hassio: port 1(veth466a8a8) entered blocking state [ 10.513459] hassio: port 1(veth466a8a8) entered forwarding state [ 10.514764] hassio: port 1(veth466a8a8) entered disabled state [ 10.946346] eth0: renamed from veth85b88ad [ 10.954801] IPv6: ADDRCONF(NETDEV_CHANGE): vetha856d19: link becomes ready [ 10.956015] docker0: port 1(vetha856d19) entered blocking state [ 10.957093] docker0: port 1(vetha856d19) entered forwarding state [ 10.958320] IPv6: ADDRCONF(NETDEV_CHANGE): docker0: link becomes ready [ 11.038235] kauditd_printk_skb: 146 callbacks suppressed [ 11.038237] audit: type=1325 audit(1585578341.315:55): table=nat family=2 entries=0 [ 11.040533] audit: type=1300 audit(1585578341.315:55): arch=c000003e syscall=55 success=yes exit=0 a0=4 a1=0 a2=40 a3=7ffd43cc7e8c items=0 ppid=3395 pid=3403 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="iptables" exe="/usr/sbin/xtables-legacy-multi" subj==unconfined key=(null) [ 11.044485] audit: type=1327 audit(1585578341.315:55): proctitle=2F7573722F7362696E2F69707461626C6573002D2D77616974002D74006E6174002D4C002D6E [ 11.046519] audit: type=1325 audit(1585578341.322:56): table=filter family=2 entries=0 [ 11.047570] audit: type=1300 audit(1585578341.322:56): arch=c000003e syscall=55 success=yes exit=0 a0=4 a1=0 a2=40 a3=7ffcbe36f9ec items=0 ppid=3395 pid=3405 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="iptables" exe="/usr/sbin/xtables-legacy-multi" subj==unconfined key=(null) [ 11.052199] audit: type=1327 audit(1585578341.322:56): proctitle=2F7573722F7362696E2F69707461626C6573002D2D77616974002D4C002D6E [ 11.056178] audit: type=1325 audit(1585578341.333:57): table=nat family=2 entries=5 [ 11.058514] audit: type=1300 audit(1585578341.333:57): arch=c000003e syscall=54 success=yes exit=0 a0=4 a1=0 a2=40 a3=128da60 items=0 ppid=3395 pid=3408 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="iptables" exe="/usr/sbin/xtables-legacy-multi" subj==unconfined key=(null) [ 11.063724] audit: type=1327 audit(1585578341.333:57): proctitle=2F7573722F7362696E2F69707461626C6573002D2D77616974002D74006E6174002D4E00444F434B45525F4F5554505554 [ 11.066648] audit: type=1325 audit(1585578341.336:58): table=nat family=2 entries=7 [ 11.103954] eth1: renamed from veth4d3a6ef [ 11.111865] IPv6: ADDRCONF(NETDEV_CHANGE): veth466a8a8: link becomes ready [ 11.113348] hassio: port 1(veth466a8a8) entered blocking state [ 11.114661] hassio: port 1(veth466a8a8) entered forwarding state [ 11.116067] IPv6: ADDRCONF(NETDEV_CHANGE): hassio: link becomes ready [ 11.578963] udevd[181]: starting version 3.2.9 [ 11.599221] udevd[182]: starting eudev-3.2.9 [ 15.375314] hassio: port 2(veth63b1aae) entered blocking state [ 15.379615] hassio: port 2(veth63b1aae) entered disabled state [ 15.388929] device veth63b1aae entered promiscuous mode [ 15.399388] IPv6: ADDRCONF(NETDEV_UP): veth63b1aae: link is not ready [ 15.428497] hassio: port 3(vethe21a01a) entered blocking state [ 15.430082] hassio: port 3(vethe21a01a) entered disabled state [ 15.431969] device vethe21a01a entered promiscuous mode [ 15.435042] IPv6: ADDRCONF(NETDEV_UP): vethe21a01a: link is not ready [ 15.436433] hassio: port 3(vethe21a01a) entered blocking state [ 15.437796] hassio: port 3(vethe21a01a) entered forwarding state [ 15.672951] hassio: port 4(vethc7150d5) entered blocking state [ 15.674214] hassio: port 4(vethc7150d5) entered disabled state [ 15.675583] device vethc7150d5 entered promiscuous mode [ 15.702939] IPv6: ADDRCONF(NETDEV_UP): vethc7150d5: link is not ready [ 15.704155] hassio: port 4(vethc7150d5) entered blocking state [ 15.705372] hassio: port 4(vethc7150d5) entered forwarding state [ 16.052376] kauditd_printk_skb: 44 callbacks suppressed [ 16.052377] audit: type=1325 audit(1585578346.329:72): table=nat family=2 entries=7 [ 16.054859] audit: type=1300 audit(1585578346.329:72): arch=c000003e syscall=54 success=yes exit=0 a0=4 a1=0 a2=40 a3=1f0d2e0 items=0 ppid=4293 pid=4331 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="iptables" exe="/usr/sbin/xtables-legacy-multi" subj==unconfined key=(null) [ 16.059149] audit: type=1327 audit(1585578346.329:72): proctitle=2F7573722F7362696E2F69707461626C6573002D2D77616974002D74006E6174002D49004F5554505554002D64003132372E302E302E3131002D6A00444F434B45525F4F5554505554 [ 16.068309] audit: type=1325 audit(1585578346.345:73): table=nat family=2 entries=8 [ 16.070487] audit: type=1300 audit(1585578346.345:73): arch=c000003e syscall=54 success=yes exit=0 a0=4 a1=0 a2=40 a3=230adb0 items=0 ppid=4293 pid=4341 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="iptables" exe="/usr/sbin/xtables-legacy-multi" subj==unconfined key=(null) [ 16.076111] audit: type=1327 audit(1585578346.345:73): proctitle=2F7573722F7362696E2F69707461626C6573002D2D77616974002D74006E6174002D4E00444F434B45525F504F5354524F5554494E47 [ 16.082706] audit: type=1325 audit(1585578346.360:74): table=nat family=2 entries=10 [ 16.084167] audit: type=1300 audit(1585578346.360:74): arch=c000003e syscall=54 success=yes exit=0 a0=4 a1=0 a2=40 a3=15bf630 items=0 ppid=4293 pid=4342 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="iptables" exe="/usr/sbin/xtables-legacy-multi" subj==unconfined key=(null) [ 16.089115] audit: type=1327 audit(1585578346.360:74): proctitle=2F7573722F7362696E2F69707461626C6573002D2D77616974002D74006E6174002D4900504F5354524F5554494E47002D64003132372E302E302E3131002D6A00444F434B45525F504F5354524F5554494E47 [ 16.094825] audit: type=1325 audit(1585578346.372:75): table=nat family=2 entries=0 [ 16.163244] eth0: renamed from veth9a94b0d [ 16.176414] hassio: port 3(vethe21a01a) entered disabled state [ 16.177947] hassio: port 4(vethc7150d5) entered disabled state [ 16.179377] IPv6: ADDRCONF(NETDEV_CHANGE): veth63b1aae: link becomes ready [ 16.180783] hassio: port 2(veth63b1aae) entered blocking state [ 16.182192] hassio: port 2(veth63b1aae) entered forwarding state [ 16.223555] eth0: renamed from veth8c3fe21 [ 16.230891] IPv6: ADDRCONF(NETDEV_CHANGE): vethe21a01a: link becomes ready [ 16.232397] hassio: port 3(vethe21a01a) entered blocking state [ 16.233791] hassio: port 3(vethe21a01a) entered forwarding state [ 16.342043] eth0: renamed from veth68eea3e [ 16.355541] IPv6: ADDRCONF(NETDEV_CHANGE): vethc7150d5: link becomes ready [ 16.357014] hassio: port 4(vethc7150d5) entered blocking state [ 16.358402] hassio: port 4(vethc7150d5) entered forwarding state [ 17.253534] udevd[309]: starting version 3.2.9 [ 17.290280] udevd[310]: starting eudev-3.2.9 [ 23.982085] kauditd_printk_skb: 71 callbacks suppressed [ 23.982087] audit: type=1400 audit(1585578354.259:99): apparmor="STATUS" operation="profile_replace" profile="unconfined" name="hassio-supervisor" pid=5225 comm="apparmor_parser" [ 23.992763] audit: type=1300 audit(1585578354.259:99): arch=c000003e syscall=1 success=yes exit=20929 a0=6 a1=b0acc0 a2=51c1 a3=fc items=0 ppid=5224 pid=5225 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="apparmor_parser" exe="/usr/sbin/apparmor_parser" subj==unconfined key=(null) [ 23.998594] audit: type=1327 audit(1585578354.259:99): proctitle=61707061726D6F725F706172736572002D72002D57002D4C002F6D6E742F646174612F73757065727669736F722F61707061726D6F722F6361636865002F6D6E742F646174612F73757065727669736F722F61707061726D6F722F68617373696F2D73757065727669736F72 [ 24.002618] audit: type=1400 audit(1585578354.270:100): apparmor="STATUS" operation="profile_replace" profile="unconfined" name="hassio-supervisor///usr/bin/gdbus" pid=5225 comm="apparmor_parser" [ 24.005363] audit: type=1300 audit(1585578354.270:100): arch=c000003e syscall=1 success=yes exit=14673 a0=6 a1=b295f0 a2=3951 a3=0 items=0 ppid=5224 pid=5225 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="apparmor_parser" exe="/usr/sbin/apparmor_parser" subj==unconfined key=(null) [ 24.010740] audit: type=1327 audit(1585578354.270:100): proctitle=61707061726D6F725F706172736572002D72002D57002D4C002F6D6E742F646174612F73757065727669736F722F61707061726D6F722F6361636865002F6D6E742F646174612F73757065727669736F722F61707061726D6F722F68617373696F2D73757065727669736F72 [ 24.014667] audit: type=1400 audit(1585578354.270:101): apparmor="STATUS" operation="profile_replace" profile="unconfined" name="hassio-supervisor///usr/bin/git" pid=5225 comm="apparmor_parser" [ 24.017346] audit: type=1300 audit(1585578354.270:101): arch=c000003e syscall=1 success=yes exit=15265 a0=6 a1=9d0d20 a2=3ba1 a3=0 items=0 ppid=5224 pid=5225 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="apparmor_parser" exe="/usr/sbin/apparmor_parser" subj==unconfined key=(null) [ 24.022789] audit: type=1327 audit(1585578354.270:101): proctitle=61707061726D6F725F706172736572002D72002D57002D4C002F6D6E742F646174612F73757065727669736F722F61707061726D6F722F6361636865002F6D6E742F646174612F73757065727669736F722F61707061726D6F722F68617373696F2D73757065727669736F72 [ 24.027186] audit: type=1400 audit(1585578354.271:102): apparmor="STATUS" operation="profile_replace" profile="unconfined" name="hassio-supervisor///usr/bin/socat" pid=5225 comm="apparmor_parser" [ 28.441823] docker0: port 1(vetha856d19) entered disabled state [ 28.443973] veth85b88ad: renamed from eth0 [ 28.485284] docker0: port 1(vetha856d19) entered disabled state [ 28.487197] device vetha856d19 left promiscuous mode [ 28.488803] docker0: port 1(vetha856d19) entered disabled state [ 28.512807] hassio: port 1(veth466a8a8) entered disabled state [ 28.514591] veth4d3a6ef: renamed from eth1 [ 28.551427] hassio: port 1(veth466a8a8) entered disabled state [ 28.553588] device veth466a8a8 left promiscuous mode [ 28.555181] hassio: port 1(veth466a8a8) entered disabled state [ 34.835127] docker0: port 1(veth90f44fa) entered blocking state [ 34.836620] docker0: port 1(veth90f44fa) entered disabled state [ 34.838220] device veth90f44fa entered promiscuous mode [ 34.839699] kauditd_printk_skb: 8 callbacks suppressed [ 34.839701] audit: type=1700 audit(1585578365.111:105): dev=veth90f44fa prom=256 old_prom=0 auid=4294967295 uid=0 gid=0 ses=4294967295 [ 34.839953] IPv6: ADDRCONF(NETDEV_UP): veth90f44fa: link is not ready [ 34.841194] audit: type=1300 audit(1585578365.111:105): arch=c000003e syscall=44 success=yes exit=40 a0=c a1=c000bb8870 a2=28 a3=0 items=0 ppid=1 pid=3007 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="dockerd" exe="/usr/bin/dockerd" subj==unconfined key=(null) [ 34.851213] audit: type=1327 audit(1585578365.111:105): proctitle=2F7573722F62696E2F646F636B657264002D480066643A2F2F002D2D73746F726167652D6472697665723D6F7665726C617932002D2D6C6F672D6472697665723D6A6F75726E616C64002D2D646174612D726F6F74002F6D6E742F646174612F646F636B6572 [ 35.182988] eth0: renamed from veth16c7d4a [ 35.188713] IPv6: ADDRCONF(NETDEV_CHANGE): veth90f44fa: link becomes ready [ 35.190290] docker0: port 1(veth90f44fa) entered blocking state [ 35.191628] docker0: port 1(veth90f44fa) entered forwarding state [ 35.470626] udevd[177]: starting version 3.2.9 [ 35.475752] udevd[178]: starting eudev-3.2.9 [ 36.770933] audit: type=1400 audit(1585578367.047:106): apparmor="DENIED" operation="file_mmap" profile="hassio-supervisor///usr/bin/git" name="/usr/local/lib/libjemalloc.so.2" pid=6987 comm="git" requested_mask="m" denied_mask="m" fsuid=0 ouid=0 [ 36.774828] audit: type=1300 audit(1585578367.047:106): arch=c000003e syscall=9 success=no exit=-13 a0=7f5e8f2a4000 a1=7f000 a2=5 a3=12 items=0 ppid=6955 pid=6987 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="git" exe="/usr/bin/git" subj==hassio-supervisor///usr/bin/git (enforce) key=(null) [ 36.780104] audit: type=1327 audit(1585578367.047:106): proctitle=6769740076657273696F6E [ 36.781467] audit: type=1400 audit(1585578367.054:107): apparmor="DENIED" operation="file_mmap" profile="hassio-supervisor///usr/bin/git" name="/usr/local/lib/libjemalloc.so.2" pid=6988 comm="git" requested_mask="m" denied_mask="m" fsuid=0 ouid=0 [ 36.785356] audit: type=1300 audit(1585578367.054:107): arch=c000003e syscall=9 success=no exit=-13 a0=7f698700b000 a1=7f000 a2=5 a3=12 items=0 ppid=6955 pid=6988 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="git" exe="/usr/bin/git" subj==hassio-supervisor///usr/bin/git (enforce) key=(null) [ 36.790768] audit: type=1327 audit(1585578367.054:107): proctitle=6769740076657273696F6E [ 37.331814] hassio: port 1(vethf9b8f7e) entered blocking state [ 37.333261] hassio: port 1(vethf9b8f7e) entered disabled state [ 37.335136] device vethf9b8f7e entered promiscuous mode [ 37.336668] audit: type=1700 audit(1585578367.608:108): dev=vethf9b8f7e prom=256 old_prom=0 auid=4294967295 uid=0 gid=0 ses=4294967295 [ 37.337018] IPv6: ADDRCONF(NETDEV_UP): vethf9b8f7e: link is not ready [ 37.485905] eth1: renamed from veth3c3326f [ 37.493293] IPv6: ADDRCONF(NETDEV_CHANGE): vethf9b8f7e: link becomes ready [ 37.494930] hassio: port 1(vethf9b8f7e) entered blocking state [ 37.496353] hassio: port 1(vethf9b8f7e) entered forwarding state [ 41.689611] hassio: port 2(veth63b1aae) entered disabled state [ 41.691524] veth9a94b0d: renamed from eth0 [ 41.723488] hassio: port 2(veth63b1aae) entered disabled state [ 41.725857] device veth63b1aae left promiscuous mode [ 41.727206] hassio: port 2(veth63b1aae) entered disabled state [ 41.727236] kauditd_printk_skb: 65 callbacks suppressed [ 41.727238] audit: type=1700 audit(1585578371.999:129): dev=veth63b1aae prom=0 old_prom=256 auid=4294967295 uid=0 gid=0 ses=4294967295 [ 41.737314] audit: type=1300 audit(1585578371.999:129): arch=c000003e syscall=44 success=yes exit=32 a0=c a1=c000401080 a2=20 a3=0 items=0 ppid=1 pid=3007 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="dockerd" exe="/usr/bin/dockerd" subj==unconfined key=(null) [ 41.742781] audit: type=1327 audit(1585578371.999:129): proctitle=2F7573722F62696E2F646F636B657264002D480066643A2F2F002D2D73746F726167652D6472697665723D6F7665726C617932002D2D6C6F672D6472697665723D6A6F75726E616C64002D2D646174612D726F6F74002F6D6E742F646174612F646F636B6572 [ 41.803795] hassio: port 2(veth18df983) entered blocking state [ 41.805412] hassio: port 2(veth18df983) entered disabled state [ 41.807672] device veth18df983 entered promiscuous mode [ 41.809690] audit: type=1700 audit(1585578372.081:130): dev=veth18df983 prom=256 old_prom=0 auid=4294967295 uid=0 gid=0 ses=4294967295 [ 41.814296] audit: type=1300 audit(1585578372.081:130): arch=c000003e syscall=44 success=yes exit=40 a0=c a1=c000bb8ff0 a2=28 a3=0 items=0 ppid=1 pid=3007 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="dockerd" exe="/usr/bin/dockerd" subj==unconfined key=(null) [ 41.819193] IPv6: ADDRCONF(NETDEV_UP): veth18df983: link is not ready [ 41.820778] audit: type=1327 audit(1585578372.081:130): proctitle=2F7573722F62696E2F646F636B657264002D480066643A2F2F002D2D73746F726167652D6472697665723D6F7665726C617932002D2D6C6F672D6472697665723D6A6F75726E616C64002D2D646174612D726F6F74002F6D6E742F646174612F646F636B6572 [ 41.824794] hassio: port 2(veth18df983) entered blocking state [ 41.826182] hassio: port 2(veth18df983) entered forwarding state [ 42.194494] audit: type=1325 audit(1585578372.472:131): table=nat family=2 entries=0 [ 42.196296] audit: type=1300 audit(1585578372.472:131): arch=c000003e syscall=55 success=yes exit=0 a0=4 a1=0 a2=40 a3=7ffdb2e5f34c items=0 ppid=7259 pid=7268 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="iptables" exe="/usr/sbin/xtables-legacy-multi" subj==unconfined key=(null) [ 42.201572] audit: type=1327 audit(1585578372.472:131): proctitle=2F7573722F7362696E2F69707461626C6573002D2D77616974002D74006E6174002D4C002D6E [ 42.211154] audit: type=1325 audit(1585578372.488:132): table=filter family=2 entries=0 [ 42.251486] eth0: renamed from vethd8cdffc [ 42.260594] IPv6: ADDRCONF(NETDEV_CHANGE): veth18df983: link becomes ready [ 49.072027] hassio: port 5(veth97cf783) entered blocking state [ 49.073748] hassio: port 5(veth97cf783) entered disabled state [ 49.075688] device veth97cf783 entered promiscuous mode [ 49.077302] kauditd_printk_skb: 32 callbacks suppressed [ 49.077304] audit: type=1700 audit(1585578379.351:143): dev=veth97cf783 prom=256 old_prom=0 auid=4294967295 uid=0 gid=0 ses=4294967295 [ 49.079702] IPv6: ADDRCONF(NETDEV_UP): veth97cf783: link is not ready [ 49.081535] audit: type=1300 audit(1585578379.351:143): arch=c000003e syscall=44 success=yes exit=40 a0=c a1=c0018b0c00 a2=28 a3=0 items=0 ppid=1 pid=3007 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="dockerd" exe="/usr/bin/dockerd" subj==unconfined key=(null) [ 49.088436] audit: type=1327 audit(1585578379.351:143): proctitle=2F7573722F62696E2F646F636B657264002D480066643A2F2F002D2D73746F726167652D6472697665723D6F7665726C617932002D2D6C6F672D6472697665723D6A6F75726E616C64002D2D646174612D726F6F74002F6D6E742F646174612F646F636B6572 [ 49.118087] hassio: port 6(veth286f0a2) entered blocking state [ 49.119894] hassio: port 6(veth286f0a2) entered disabled state [ 49.121850] device veth286f0a2 entered promiscuous mode [ 49.124060] audit: type=1700 audit(1585578379.397:144): dev=veth286f0a2 prom=256 old_prom=0 auid=4294967295 uid=0 gid=0 ses=4294967295 [ 49.127067] audit: type=1300 audit(1585578379.397:144): arch=c000003e syscall=44 success=yes exit=40 a0=c a1=c0018b1230 a2=28 a3=0 items=0 ppid=1 pid=3007 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="dockerd" exe="/usr/bin/dockerd" subj==unconfined key=(null) [ 49.133154] audit: type=1327 audit(1585578379.397:144): proctitle=2F7573722F62696E2F646F636B657264002D480066643A2F2F002D2D73746F726167652D6472697665723D6F7665726C617932002D2D6C6F672D6472697665723D6A6F75726E616C64002D2D646174612D726F6F74002F6D6E742F646174612F646F636B6572 [ 49.138867] IPv6: ADDRCONF(NETDEV_UP): veth286f0a2: link is not ready [ 49.140588] hassio: port 6(veth286f0a2) entered blocking state [ 49.142049] hassio: port 6(veth286f0a2) entered forwarding state [ 49.177445] audit: type=1325 audit(1585578379.456:145): table=nat family=2 entries=13 [ 49.178905] audit: type=1300 audit(1585578379.456:145): arch=c000003e syscall=54 success=yes exit=0 a0=4 a1=0 a2=40 a3=1bdf060 items=0 ppid=3007 pid=7627 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="iptables" exe="/usr/sbin/xtables-legacy-multi" subj==unconfined key=(null) [ 49.184372] audit: type=1327 audit(1585578379.456:145): proctitle=2F7573722F7362696E2F69707461626C6573002D2D77616974002D74006E6174002D4100444F434B4552002D7000746370002D6400302F30002D2D64706F72740038303836002D6A00444E4154002D2D746F2D64657374696E6174696F6E003137322E33302E33332E303A383038360000002D690068617373696F [ 49.199168] audit: type=1325 audit(1585578379.478:146): table=filter family=2 entries=29 [ 49.303172] hassio: port 7(vethc211972) entered blocking state [ 49.304869] hassio: port 7(vethc211972) entered disabled state [ 49.306721] device vethc211972 entered promiscuous mode [ 49.314886] IPv6: ADDRCONF(NETDEV_UP): vethc211972: link is not ready [ 49.316337] hassio: port 7(vethc211972) entered blocking state [ 49.317782] hassio: port 7(vethc211972) entered forwarding state [ 49.470973] hassio: port 8(vethf9c3371) entered blocking state [ 49.472400] hassio: port 8(vethf9c3371) entered disabled state [ 49.477041] device vethf9c3371 entered promiscuous mode [ 49.482984] IPv6: ADDRCONF(NETDEV_UP): vethf9c3371: link is not ready [ 49.484318] hassio: port 8(vethf9c3371) entered blocking state [ 49.485644] hassio: port 8(vethf9c3371) entered forwarding state [ 50.087602] eth0: renamed from veth39aca77 [ 50.103486] hassio: port 6(veth286f0a2) entered disabled state [ 50.105455] hassio: port 7(vethc211972) entered disabled state [ 50.107165] hassio: port 8(vethf9c3371) entered disabled state [ 50.108548] IPv6: ADDRCONF(NETDEV_CHANGE): veth97cf783: link becomes ready [ 50.110049] hassio: port 5(veth97cf783) entered blocking state [ 50.111372] hassio: port 5(veth97cf783) entered forwarding state [ 50.137276] eth0: renamed from veth8893126 [ 50.143851] IPv6: ADDRCONF(NETDEV_CHANGE): veth286f0a2: link becomes ready [ 50.145100] hassio: port 6(veth286f0a2) entered blocking state [ 50.146192] hassio: port 6(veth286f0a2) entered forwarding state [ 50.379127] eth0: renamed from veth4f0718e [ 50.398829] IPv6: ADDRCONF(NETDEV_CHANGE): vethc211972: link becomes ready [ 50.400043] hassio: port 7(vethc211972) entered blocking state [ 50.401165] hassio: port 7(vethc211972) entered forwarding state [ 50.433596] eth0: renamed from veth0bd0dc6 [ 50.438837] IPv6: ADDRCONF(NETDEV_CHANGE): vethf9c3371: link becomes ready [ 50.440006] hassio: port 8(vethf9c3371) entered blocking state [ 50.441067] hassio: port 8(vethf9c3371) entered forwarding state [ 57.432233] udevd[180]: starting version 3.2.9 [ 57.601129] udevd[181]: starting eudev-3.2.9 [ 91.393208] kauditd_printk_skb: 140 callbacks suppressed [ 91.393210] audit: type=1400 audit(1585578421.672:193): apparmor="DENIED" operation="file_mmap" profile="hassio-supervisor///usr/bin/gdbus" name="/usr/local/lib/libjemalloc.so.2" pid=11836 comm="gdbus" requested_mask="m" denied_mask="m" fsuid=0 ouid=0 [ 91.397602] audit: type=1300 audit(1585578421.672:193): arch=c000003e syscall=9 success=no exit=-13 a0=7fddbc03d000 a1=7f000 a2=5 a3=12 items=0 ppid=6955 pid=11836 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="gdbus" exe="/usr/bin/gdbus" subj==hassio-supervisor///usr/bin/gdbus (enforce) key=(null) [ 91.401519] audit: type=1327 audit(1585578421.672:193): proctitle=67646275730063616C6C002D2D73797374656D002D2D64657374006F72672E667265656465736B746F702E686F73746E616D6531002D2D6F626A6563742D70617468002F6F72672F667265656465736B746F702F686F73746E616D6531002D2D6D6574686F64006F72672E667265656465736B746F702E444275732E50726F70 [ 91.657294] audit: type=1400 audit(1585578421.936:194): apparmor="DENIED" operation="file_mmap" profile="hassio-supervisor///usr/bin/gdbus" name="/usr/local/lib/libjemalloc.so.2" pid=11844 comm="gdbus" requested_mask="m" denied_mask="m" fsuid=0 ouid=0 [ 91.661670] audit: type=1300 audit(1585578421.936:194): arch=c000003e syscall=9 success=no exit=-13 a0=7fd2cda26000 a1=7f000 a2=5 a3=12 items=0 ppid=6955 pid=11844 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="gdbus" exe="/usr/bin/gdbus" subj==hassio-supervisor///usr/bin/gdbus (enforce) key=(null) [ 91.668227] audit: type=1327 audit(1585578421.936:194): proctitle=67646275730063616C6C002D2D73797374656D002D2D64657374006F72672E667265656465736B746F702E73797374656D6431002D2D6F626A6563742D70617468002F6F72672F667265656465736B746F702F73797374656D6431002D2D6D6574686F64006F72672E667265656465736B746F702E73797374656D64312E4D61 [ 91.758036] audit: type=1400 audit(1585578422.037:195): apparmor="DENIED" operation="file_mmap" profile="hassio-supervisor///usr/bin/gdbus" name="/usr/local/lib/libjemalloc.so.2" pid=11856 comm="gdbus" requested_mask="m" denied_mask="m" fsuid=0 ouid=0 [ 91.763166] audit: type=1300 audit(1585578422.037:195): arch=c000003e syscall=9 success=no exit=-13 a0=7fea12b05000 a1=7f000 a2=5 a3=12 items=0 ppid=6955 pid=11856 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="gdbus" exe="/usr/bin/gdbus" subj==hassio-supervisor///usr/bin/gdbus (enforce) key=(null) [ 91.769689] audit: type=1327 audit(1585578422.037:195): proctitle=67646275730063616C6C002D2D73797374656D002D2D64657374006F72672E667265656465736B746F702E4E6574776F726B4D616E61676572002D2D6F626A6563742D70617468002F6F72672F667265656465736B746F702F4E6574776F726B4D616E616765722F446E734D616E61676572002D2D6D6574686F64006F72672E ```

Description of problem:

Initiated HassOS upgrade from Supervisor UI. VM failed to boot, with what I believe to be filesystem-related errors.

HassOS is running within a Proxmox VM, on an Intel processor.

As I had used Proxmox to take a disk snapshot prior to initiating the HassOS upgrade, I rolled-back to the pre-upgrade snapshot. The logs above are from the VM post-rollback (and therefore are pre-upgrade).

The following screenshots show the VM in its failed state:

**First boot after attempting HassOS upgrade** ![Screen Shot 2020-03-30 at 9 24 47 AM](https://user-images.githubusercontent.com/133385/77925119-17d29d00-726a-11ea-94b8-8884ca40d925.png)
**Second boot, after unclean shutdown (using "Stop")** ![Screen Shot 2020-03-30 at 9 07 41 AM](https://user-images.githubusercontent.com/133385/77925287-4d778600-726a-11ea-90da-3fa72977bf58.png)

Please let me know what other information I can collect, or how I can otherwise assist with troubleshooting. Thank you!

adamfeldman commented 4 years ago

Per https://github.com/home-assistant/supervisor/issues/1620, I am now having issues running HassOS 3.10 with Supervisor 214 (latest release as of today).

pvizeli commented 4 years ago

It's carefully to use snapshot because that is not aware of the docker database. I'm just 100% sure that the issue starts with a rollback of a snapshot. Please use Home Assistant snapshots they are docker safe.

adamfeldman commented 4 years ago

@pvizeli thank you for your assistance!

I only take VM snapshots for rollback in emergencies 🙂. I also create and export regular HA snapshots.

This is the result of logging in to the console (not via Terminal add-on) and attempting cli update:

Screen Shot 2020-03-30 at 4 15 54 PM

The same thing happens when I run the same command over ssh (currently, when I ssh into hassos, I get the hassio utlity, not ha -- as you pointed out, this is what I need to fix)

adamfeldman commented 4 years ago

According to http://hassio.local:8123/hassio/addon/core_ssh, I am already running core_ssh version 8.2, which is latest. Yet it offers me the hassio cli, and not ha. @pvizeli I am not sure which other add-on you want me to install (per your comment in the other issue I opened)

adamfeldman commented 4 years ago

Oops, I see in repo that latest is 8.5.3: https://github.com/home-assistant/hassio-addons/blob/master/ssh/config.json

I wonder why my Supervisor isn't picking up the latest version?

Screen Shot 2020-03-30 at 4 23 56 PM

adamfeldman commented 4 years ago

Anndd running the following over my SSH session into HassOS picked up the latest versions...

$ hassio addons reload

Sorry to be too quick to comment here. 😅 will update here with any further steps I end up needing to take to solve the issue.

adamfeldman commented 4 years ago

So far so good. Thanks again @pvizeli!!

Note for future readers of this thread: I ended up stopping work on my rolled-back snapshot that was quite old (~5 weeks). I used what I learned to fix-up* my almost-working original VM (which I had rolled-back to the point right after the Supervisor was updated to 214, and right before updating from 3.10 to 3.12). Lots of fiddling involved but it seems okay now.

*by running ha cli update or the equivalent for the older cli