Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

Sign up for GitHub

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

Jump to bottom

datactl move not using external drive? #1040

Closed
2 of 7 tasks
UnderpantsGnome opened this issue Dec 1, 2020 · 17 comments
Closed
2 of 7 tasks

datactl move not using external drive? #1040

UnderpantsGnome opened this issue Dec 1, 2020 · 17 comments
Labels
os

Comments

@UnderpantsGnome
Copy link

UnderpantsGnome commented Dec 1, 2020 •
edited

Hardware Environment

  • Raspberry Pi 4
  • ODROID [C2/C4/N2(+)/XU4]
  • ASUS Tinker [S]
  • Intel NUC (or compatible)
  • OVA (Open Virtualization Applicance, on Intel NUC or any other hardware, please add the Hypervisor you are using)

Home Assistant OS release:

  • Fresh installation of release 4.16
  • Updated from version x.y
  • Additional information (if accessible):
arch: aarch64
channel: stable
docker: 19.03.12
features:
- reboot
- shutdown
- services
- network
- hostname
- hassos
hassos: "4.16"
homeassistant: 0.118.4
hostname: homeassistant
logging: info
machine: raspberrypi4-64
operating_system: HassOS 4.16
supervisor: 2020.11.0
supported: true
supported_arch:
- aarch64
- armv7
- armhf

Supervisor logs:

20-12-01 00:18:21 INFO (MainThread) [supervisor.misc.hwmon] Stopped Supervisor hardware monitor
[cont-finish.d] executing container finish scripts...
[cont-finish.d] done.
[s6-finish] waiting for services.
20-12-01 00:18:21 INFO (MainThread) [supervisor.core] Supervisor is down
20-12-01 00:18:21 INFO (MainThread) [__main__] Closing Supervisor
[s6-finish] sending all processes the TERM signal.
[s6-finish] sending all processes the KILL signal and exiting.
[s6-init] making user provided files available at /var/run/s6/etc...exited 0.
[s6-init] ensuring user provided files have correct perms...exited 0.
[fix-attrs.d] applying ownership & permissions fixes...
[fix-attrs.d] done.
[cont-init.d] executing container initialization scripts...
[cont-init.d] udev.sh: executing... 
[00:18:57] INFO: Update udev information
[cont-init.d] udev.sh: exited 0.
[cont-init.d] done.
[services.d] starting services
[services.d] done.
[00:18:58] INFO: Starting local supervisor watchdog...
20-12-01 00:19:01 INFO (MainThread) [__main__] Initializing Supervisor setup
20-12-01 00:19:01 INFO (MainThread) [supervisor.bootstrap] Initializing Supervisor Sentry
20-12-01 00:19:01 INFO (SyncWorker_0) [supervisor.docker.supervisor] Attaching to Supervisor homeassistant/aarch64-hassio-supervisor with version 2020.11.0
20-12-01 00:19:01 INFO (MainThread) [__main__] Setting up Supervisor
20-12-01 00:19:01 INFO (MainThread) [supervisor.api] Starting API on 172.30.32.2
20-12-01 00:19:02 INFO (MainThread) [supervisor.host.info] Updating local host information
20-12-01 00:19:02 INFO (MainThread) [supervisor.host.services] Updating service information
20-12-01 00:19:02 INFO (MainThread) [supervisor.host.network] Updating local network information
20-12-01 00:19:03 INFO (MainThread) [supervisor.host.sound] Updating PulseAudio information
20-12-01 00:19:03 INFO (MainThread) [supervisor.host.apparmor] Loading AppArmor Profiles: {'hassio-supervisor'}
20-12-01 00:19:03 INFO (MainThread) [supervisor.host.services] Reloading local service hassos-apparmor.service
20-12-01 00:19:03 INFO (SyncWorker_0) [supervisor.docker.interface] Attaching to homeassistant/aarch64-hassio-dns with version 2020.11.0
20-12-01 00:19:03 INFO (MainThread) [supervisor.plugins.dns] Starting CoreDNS plugin
20-12-01 00:19:03 INFO (SyncWorker_0) [supervisor.docker.interface] Cleaning hassio_dns application
20-12-01 00:19:04 INFO (SyncWorker_0) [supervisor.docker.dns] Starting DNS homeassistant/aarch64-hassio-dns with version 2020.11.0 - 172.30.32.3
20-12-01 00:19:04 INFO (MainThread) [supervisor.plugins.dns] Updated /etc/resolv.conf
20-12-01 00:19:04 INFO (SyncWorker_0) [supervisor.docker.interface] Attaching to homeassistant/aarch64-hassio-audio with version 17
20-12-01 00:19:04 INFO (MainThread) [supervisor.plugins.audio] Starting Audio plugin
20-12-01 00:19:04 INFO (SyncWorker_0) [supervisor.docker.interface] Cleaning hassio_audio application
20-12-01 00:19:06 INFO (SyncWorker_0) [supervisor.docker.audio] Starting Audio homeassistant/aarch64-hassio-audio with version 17 - 172.30.32.4
20-12-01 00:19:06 INFO (SyncWorker_0) [supervisor.docker.interface] Attaching to homeassistant/aarch64-hassio-cli with version 2020.10.1
20-12-01 00:19:06 INFO (MainThread) [supervisor.plugins.cli] Starting CLI plugin
20-12-01 00:19:06 INFO (SyncWorker_0) [supervisor.docker.interface] Cleaning hassio_cli application
20-12-01 00:19:28 INFO (SyncWorker_0) [supervisor.docker.cli] Starting CLI homeassistant/aarch64-hassio-cli with version 2020.10.1 - 172.30.32.5
20-12-01 00:19:28 INFO (SyncWorker_0) [supervisor.docker.interface] Attaching to homeassistant/aarch64-hassio-observer with version 2020.10.1
20-12-01 00:19:28 INFO (SyncWorker_0) [supervisor.docker.interface] Attaching to homeassistant/aarch64-hassio-multicast with version 3
20-12-01 00:19:28 INFO (MainThread) [supervisor.plugins.multicast] Starting Multicast plugin
20-12-01 00:19:28 INFO (SyncWorker_0) [supervisor.docker.interface] Cleaning hassio_multicast application
20-12-01 00:19:29 INFO (SyncWorker_0) [supervisor.docker.multicast] Starting Multicast homeassistant/aarch64-hassio-multicast with version 3 - Host
20-12-01 00:19:29 INFO (MainThread) [supervisor.updater] Fetching update data from https://version.home-assistant.io/stable.json
20-12-01 00:19:30 INFO (MainThread) [supervisor.homeassistant.secrets] Loaded 1 Home Assistant secrets
20-12-01 00:19:30 INFO (SyncWorker_0) [supervisor.docker.interface] Attaching to homeassistant/raspberrypi4-64-homeassistant with version 0.118.4
20-12-01 00:19:30 INFO (MainThread) [supervisor.hassos] Detect HassOS 4.16 / BootSlot A
20-12-01 00:19:30 INFO (MainThread) [supervisor.store.git] Loading add-on /data/addons/git/110c40b1 repository
20-12-01 00:19:30 INFO (MainThread) [supervisor.store.git] Loading add-on /data/addons/core repository
20-12-01 00:19:30 INFO (MainThread) [supervisor.store.git] Loading add-on /data/addons/git/a0d7b954 repository
20-12-01 00:19:30 INFO (MainThread) [supervisor.store] Loading add-ons from store: 68 all - 68 new - 0 remove
20-12-01 00:19:30 INFO (MainThread) [supervisor.addons] Found 5 installed add-ons
20-12-01 00:19:30 INFO (SyncWorker_1) [supervisor.docker.interface] Attaching to dwelch2101/zigbee2mqtt-aarch64 with version 1.16.1
20-12-01 00:19:30 INFO (SyncWorker_0) [supervisor.docker.interface] Attaching to homeassistant/aarch64-addon-ssh with version 8.10.0
20-12-01 00:19:30 INFO (SyncWorker_3) [supervisor.docker.interface] Attaching to homeassistant/aarch64-addon-samba with version 9.3.0
20-12-01 00:19:30 INFO (SyncWorker_2) [supervisor.docker.interface] Attaching to homeassistant/aarch64-addon-mosquitto with version 5.1
20-12-01 00:19:30 INFO (SyncWorker_4) [supervisor.docker.interface] Attaching to hassioaddons/plex-aarch64 with version 2.4.2
20-12-01 00:19:30 INFO (MainThread) [supervisor.snapshots] Found 1 snapshot files
20-12-01 00:19:30 INFO (MainThread) [supervisor.discovery] Loaded 1 messages
20-12-01 00:19:30 INFO (MainThread) [supervisor.ingress] Loaded 0 ingress sessions
20-12-01 00:19:31 INFO (MainThread) [__main__] Running Supervisor
20-12-01 00:19:31 INFO (MainThread) [supervisor.hassos] Rauc: A - marked slot kernel.0 as good
20-12-01 00:19:31 INFO (MainThread) [supervisor.addons] Phase 'AddonStartup.INITIALIZE' starting 0 add-ons
20-12-01 00:19:31 INFO (MainThread) [supervisor.addons] Phase 'AddonStartup.SYSTEM' starting 1 add-ons
20-12-01 00:19:31 INFO (SyncWorker_4) [supervisor.docker.interface] Cleaning addon_core_mosquitto application
20-12-01 00:19:35 INFO (SyncWorker_4) [supervisor.docker.addon] Starting Docker add-on homeassistant/aarch64-addon-mosquitto with version 5.1
20-12-01 00:19:37 INFO (MainThread) [supervisor.services.modules.mqtt] Set core_mosquitto as service provider for mqtt
20-12-01 00:19:40 INFO (MainThread) [supervisor.addons] Phase 'AddonStartup.SERVICES' starting 3 add-ons
20-12-01 00:19:40 INFO (SyncWorker_3) [supervisor.docker.interface] Cleaning addon_a0d7b954_plex application
20-12-01 00:19:42 INFO (SyncWorker_3) [supervisor.docker.addon] Starting Docker add-on hassioaddons/plex-aarch64 with version 2.4.2
20-12-01 00:19:42 INFO (SyncWorker_2) [supervisor.docker.interface] Cleaning addon_core_ssh application
20-12-01 00:19:44 INFO (SyncWorker_2) [supervisor.docker.addon] Starting Docker add-on homeassistant/aarch64-addon-ssh with version 8.10.0
20-12-01 00:19:44 INFO (MainThread) [supervisor.api.security] /host/info access from a0d7b954_plex
20-12-01 00:19:44 INFO (SyncWorker_4) [supervisor.docker.interface] Cleaning addon_core_samba application
20-12-01 00:19:46 INFO (SyncWorker_4) [supervisor.docker.addon] Starting Docker add-on homeassistant/aarch64-addon-samba with version 9.3.0
20-12-01 00:19:51 INFO (SyncWorker_0) [supervisor.docker.interface] Starting homeassistant
20-12-01 00:19:51 INFO (MainThread) [supervisor.homeassistant.core] Wait until Home Assistant is ready
20-12-01 00:20:11 INFO (MainThread) [supervisor.homeassistant.api] Updated Home Assistant API token
20-12-01 00:20:16 INFO (MainThread) [supervisor.homeassistant.core] Detect a running Home Assistant instance
20-12-01 00:20:16 INFO (MainThread) [supervisor.addons] Phase 'AddonStartup.APPLICATION' starting 1 add-ons
20-12-01 00:20:16 INFO (SyncWorker_0) [supervisor.docker.interface] Cleaning addon_110c40b1_zigbee2mqtt application
20-12-01 00:20:19 INFO (SyncWorker_0) [supervisor.docker.addon] Starting Docker add-on dwelch2101/zigbee2mqtt-aarch64 with version 1.16.1
20-12-01 00:20:24 INFO (MainThread) [supervisor.misc.tasks] All core tasks are scheduled
20-12-01 00:20:24 INFO (MainThread) [supervisor.misc.hwmon] Started Supervisor hardware monitor
20-12-01 00:20:24 INFO (MainThread) [supervisor.core] Supervisor is up and running
20-12-01 00:20:24 INFO (MainThread) [supervisor.host.info] Updating local host information
20-12-01 00:20:24 INFO (MainThread) [supervisor.updater] Fetching update data from https://version.home-assistant.io/stable.json
20-12-01 00:20:24 INFO (MainThread) [supervisor.host.services] Updating service information
20-12-01 00:20:24 INFO (MainThread) [supervisor.host.network] Updating local network information
20-12-01 00:20:25 INFO (MainThread) [supervisor.host.sound] Updating PulseAudio information

Journal logs:

Kernel logs:

[    0.000000] Booting Linux on physical CPU 0x0000000000 [0x410fd083]
[    0.000000] Linux version 4.19.127-v8 (builder@6833b1ddbf11) (gcc version 9.3.0 (Buildroot -g60b6d064)) #1 SMP PREEMPT Sat Nov 7 04:18:51 UTC 2020
[    0.000000] Machine model: Raspberry Pi 4 Model B Rev 1.1
[    0.000000] efi: Getting EFI parameters from FDT:
[    0.000000] efi: UEFI not found.
[    0.000000] cma: Reserved 64 MiB at 0x0000000037400000
[    0.000000] On node 0 totalpages: 1012736
[    0.000000]   DMA32 zone: 3792 pages used for memmap
[    0.000000]   DMA32 zone: 0 pages reserved
[    0.000000]   DMA32 zone: 242688 pages, LIFO batch:63
[    0.000000]   Normal zone: 12032 pages used for memmap
[    0.000000]   Normal zone: 770048 pages, LIFO batch:63
[    0.000000] random: get_random_bytes called from start_kernel+0xa0/0x470 with crng_init=0
[    0.000000] percpu: Embedded 24 pages/cpu s59160 r8192 d30952 u98304
[    0.000000] pcpu-alloc: s59160 r8192 d30952 u98304 alloc=24*4096
[    0.000000] pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3
[    0.000000] Detected PIPT I-cache on CPU0
[    0.000000] ARM_SMCCC_ARCH_WORKAROUND_1 missing from firmware
[    0.000000] CPU features: enabling workaround for EL2 vector hardening
[    0.000000] Built 1 zonelists, mobility grouping on.  Total pages: 996912
[    0.000000] Kernel command line: zram.enabled=1 zram.num_devices=3 apparmor=1 security=apparmor rootwait systemd.machine_id=1f4380f014334d2fa3b106e1a8f03b0d cgroup_enable=memory coherent_pool=1M 8250.nr_uarts=0 cma=64M bcm2708_fb.fbwidth=1824 bcm2708_fb.fbheight=984 bcm2708_fb.fbswap=1 smsc95xx.macaddr=DC:A6:32:20:A2:B4 vc_mem.mem_base=0x3ec00000 vc_mem.mem_size=0x40000000  dwc_otg.lpm_enable=0 console=tty1 root=PARTUUID=8d3d53e3-6d49-4c38-8349-aff6859e82fd rootfstype=squashfs ro rauc.slot=A
[    0.000000] Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes)
[    0.000000] Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes)
[    0.000000] software IO TLB: mapped [mem 0x333ff000-0x373ff000] (64MB)
[    0.000000] Memory: 3830428K/4050944K available (9916K kernel code, 1146K rwdata, 3060K rodata, 1088K init, 1155K bss, 154980K reserved, 65536K cma-reserved)
[    0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1
[    0.000000] ftrace: allocating 34551 entries in 135 pages
[    0.000000] rcu: Preemptible hierarchical RCU implementation.
[    0.000000] rcu: 	RCU restricting CPUs from NR_CPUS=64 to nr_cpu_ids=4.
[    0.000000] 	Tasks RCU enabled.
[    0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4
[    0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0
[    0.000000] GIC: Using split EOI/Deactivate mode
[    0.000000] arch_timer: cp15 timer(s) running at 54.00MHz (phys).
[    0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0xc743ce346, max_idle_ns: 440795203123 ns
[    0.000006] sched_clock: 56 bits at 54MHz, resolution 18ns, wraps every 4398046511102ns
[    0.000220] Console: colour dummy device 80x25
[    0.000600] console [tty1] enabled
[    0.000651] Calibrating delay loop (skipped), value calculated using timer frequency.. 108.00 BogoMIPS (lpj=216000)
[    0.000684] pid_max: default: 32768 minimum: 301
[    0.000795] Security Framework initialized
[    0.000900] AppArmor: AppArmor initialized
[    0.001103] Mount-cache hash table entries: 8192 (order: 4, 65536 bytes)
[    0.001171] Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes)
[    0.024042] ASID allocator initialised with 32768 entries
[    0.032037] rcu: Hierarchical SRCU implementation.
[    0.040535] EFI services will not be available.
[    0.048093] smp: Bringing up secondary CPUs ...
[    0.080300] Detected PIPT I-cache on CPU1
[    0.080360] CPU1: Booted secondary processor 0x0000000001 [0x410fd083]
[    0.112381] Detected PIPT I-cache on CPU2
[    0.112423] CPU2: Booted secondary processor 0x0000000002 [0x410fd083]
[    0.144492] Detected PIPT I-cache on CPU3
[    0.144533] CPU3: Booted secondary processor 0x0000000003 [0x410fd083]
[    0.144662] smp: Brought up 1 node, 4 CPUs
[    0.144751] SMP: Total of 4 processors activated.
[    0.144774] CPU features: detected: 32-bit EL0 Support
[    0.145653] CPU: All CPU(s) started at EL2
[    0.145704] alternatives: patching kernel code
[    0.147093] devtmpfs: initialized
[    0.155318] Enabled cp15_barrier support
[    0.155368] Enabled setend support
[    0.155754] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns
[    0.155796] futex hash table entries: 1024 (order: 4, 65536 bytes)
[    0.157833] pinctrl core: initialized pinctrl subsystem
[    0.158450] DMI not present or invalid.
[    0.158859] NET: Registered protocol family 16
[    0.159570] audit: initializing netlink subsys (disabled)
[    0.159883] audit: type=2000 audit(0.156:1): state=initialized audit_enabled=0 res=1
[    0.160516] cpuidle: using governor menu
[    0.160951] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers.
[    0.163565] DMA: preallocated 1024 KiB pool for atomic allocations
[    0.163671] Serial: AMBA PL011 UART driver
[    0.166915] bcm2835-mbox fe00b880.mailbox: mailbox enabled
[    0.176611] raspberrypi-firmware soc:firmware: Attached to firmware from 2020-06-01 13:23, variant start
[    0.180624] raspberrypi-firmware soc:firmware: Firmware hash is 6379679d1ec6a8c746d7e77e015f5b56b939976f
[    0.213375] bcm2835-dma fe007000.dma: DMA legacy API manager at (____ptrval____), dmachans=0x1
[    0.216441] vgaarb: loaded
[    0.217110] SCSI subsystem initialized
[    0.217316] usbcore: registered new interface driver usbfs
[    0.217391] usbcore: registered new interface driver hub
[    0.217506] usbcore: registered new device driver usb
[    0.217570] media: Linux media interface: v0.10
[    0.217624] videodev: Linux video capture interface: v2.00
[    0.217705] pps_core: LinuxPPS API ver. 1 registered
[    0.217726] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>
[    0.217763] PTP clock support registered
[    0.219043] clocksource: Switched to clocksource arch_sys_counter
[    0.300685] VFS: Disk quotas dquot_6.6.0
[    0.300791] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)
[    0.300980] FS-Cache: Loaded
[    0.301170] CacheFiles: Loaded
[    0.301829] AppArmor: AppArmor Filesystem Enabled
[    0.309974] NET: Registered protocol family 2
[    0.310657] tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes)
[    0.310721] TCP established hash table entries: 32768 (order: 6, 262144 bytes)
[    0.310943] TCP bind hash table entries: 32768 (order: 7, 524288 bytes)
[    0.311483] TCP: Hash tables configured (established 32768 bind 32768)
[    0.311725] UDP hash table entries: 2048 (order: 4, 65536 bytes)
[    0.311802] UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes)
[    0.312093] NET: Registered protocol family 1
[    0.316789] RPC: Registered named UNIX socket transport module.
[    0.316815] RPC: Registered udp transport module.
[    0.316834] RPC: Registered tcp transport module.
[    0.316852] RPC: Registered tcp NFSv4.1 backchannel transport module.
[    0.316880] PCI: CLS 0 bytes, default 64
[    0.318660] hw perfevents: enabled with armv8_cortex_a72 PMU driver, 7 counters available
[    0.318883] kvm [1]: 8-bit VMID
[    0.319702] kvm [1]: vgic interrupt IRQ1
[    0.319933] kvm [1]: Hyp mode initialized successfully
[    0.321914] Initialise system trusted keyrings
[    0.322186] workingset: timestamp_bits=46 max_order=20 bucket_order=0
[    0.329553] squashfs: version 4.0 (2009/01/31) Phillip Lougher
[    0.329778] FS-Cache: Netfs 'nfs' registered for caching
[    0.330478] NFS: Registering the id_resolver key type
[    0.330523] Key type id_resolver registered
[    0.330543] Key type id_legacy registered
[    0.330573] nfs4filelayout_init: NFSv4 File Layout Driver Registering...
[    0.336635] Key type asymmetric registered
[    0.336669] Asymmetric key parser 'x509' registered
[    0.336738] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247)
[    0.336946] io scheduler noop registered
[    0.336970] io scheduler deadline registered
[    0.337131] io scheduler cfq registered (default)
[    0.337154] io scheduler mq-deadline registered
[    0.337174] io scheduler kyber registered
[    0.371029] brcm-pcie fd500000.pcie: dmabounce: initialised - 32768 kB, threshold 0x00000000c0000000
[    0.371116] brcm-pcie fd500000.pcie: could not get clock
[    0.371212] brcm-pcie fd500000.pcie: host bridge /scb/pcie@7d500000 ranges:
[    0.371269] brcm-pcie fd500000.pcie:   MEM 0x600000000..0x603ffffff -> 0xf8000000
[    0.407082] brcm-pcie fd500000.pcie: link up, 5.0 Gbps x1 (!SSC)
[    0.407407] brcm-pcie fd500000.pcie: PCI host bridge to bus 0000:00
[    0.407437] pci_bus 0000:00: root bus resource [bus 00-01]
[    0.407463] pci_bus 0000:00: root bus resource [mem 0x600000000-0x603ffffff] (bus address [0xf8000000-0xfbffffff])
[    0.407530] pci 0000:00:00.0: [14e4:2711] type 01 class 0x060400
[    0.407693] pci 0000:00:00.0: PME# supported from D0 D3hot
[    0.410640] pci 0000:01:00.0: [1106:3483] type 00 class 0x0c0330
[    0.410768] pci 0000:01:00.0: reg 0x10: [mem 0x00000000-0x00000fff 64bit]
[    0.411166] pci 0000:01:00.0: PME# supported from D0 D3cold
[    0.413841] pci 0000:00:00.0: BAR 8: assigned [mem 0x600000000-0x6000fffff]
[    0.413876] pci 0000:01:00.0: BAR 0: assigned [mem 0x600000000-0x600000fff 64bit]
[    0.413961] pci 0000:00:00.0: PCI bridge to [bus 01]
[    0.413990] pci 0000:00:00.0:   bridge window [mem 0x600000000-0x6000fffff]
[    0.414104] pci 0000:00:00.0: enabling device (0000 -> 0002)
[    0.414168] pci 0000:01:00.0: enabling device (0000 -> 0002)
[    0.414767] bcm2708_fb soc:fb: FB found 1 display(s)
[    0.455568] Console: switching to colour frame buffer device 228x61
[    0.466324] bcm2708_fb soc:fb: Registered framebuffer for display 0, size 1824x984
[    0.469977] iproc-rng200 fe104000.rng: hwrng registered
[    0.470324] vc-mem: phys_addr:0x00000000 mem_base=0x3ec00000 mem_size:0x40000000(1024 MiB)
[    0.471122] gpiomem-bcm2835 fe200000.gpiomem: Initialised: Registers at 0xfe200000
[    0.471480] cacheinfo: Unable to detect cache hierarchy for CPU 0
[    0.481379] brd: module loaded
[    0.491771] loop: module loaded
[    0.492358] zram: Added device: zram0
[    0.492864] zram: Added device: zram1
[    0.493386] zram: Added device: zram2
[    0.494294] Loading iSCSI transport class v2.0-870.
[    0.496235] libphy: Fixed MDIO Bus: probed
[    0.496328] tun: Universal TUN/TAP device driver, 1.6
[    0.497004] bcmgenet fd580000.genet: failed to get enet clock
[    0.497089] bcmgenet fd580000.genet: GENET 5.0 EPHY: 0x0000
[    0.497166] bcmgenet fd580000.genet: failed to get enet-wol clock
[    0.497247] bcmgenet fd580000.genet: failed to get enet-eee clock
[    0.497333] bcmgenet: Skipping UMAC reset
[    0.497620] unimac-mdio unimac-mdio.-19: DMA mask not set
[    0.507077] libphy: bcmgenet MII bus: probed
[    0.531875] unimac-mdio unimac-mdio.-19: Broadcom UniMAC MDIO bus at 0x(____ptrval____)
[    0.532867] usbcore: registered new interface driver r8152
[    0.532983] usbcore: registered new interface driver lan78xx
[    0.533449] xhci_hcd 0000:01:00.0: xHCI Host Controller
[    0.533536] xhci_hcd 0000:01:00.0: new USB bus registered, assigned bus number 1
[    0.539465] xhci_hcd 0000:01:00.0: hcc params 0x002841eb hci version 0x100 quirks 0x0000001000000890
[    0.539889] genirq: irq_chip Brcm_MSI did not update eff. affinity mask of irq 36
[    0.540820] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 4.19
[    0.540922] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[    0.541010] usb usb1: Product: xHCI Host Controller
[    0.541072] usb usb1: Manufacturer: Linux 4.19.127-v8 xhci-hcd
[    0.541143] usb usb1: SerialNumber: 0000:01:00.0
[    0.541734] hub 1-0:1.0: USB hub found
[    0.541866] hub 1-0:1.0: 1 port detected
[    0.542408] xhci_hcd 0000:01:00.0: xHCI Host Controller
[    0.542490] xhci_hcd 0000:01:00.0: new USB bus registered, assigned bus number 2
[    0.545135] xhci_hcd 0000:01:00.0: Host supports USB 3.0 SuperSpeed
[    0.548162] usb usb2: New USB device found, idVendor=1d6b, idProduct=0003, bcdDevice= 4.19
[    0.550678] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[    0.553311] usb usb2: Product: xHCI Host Controller
[    0.555971] usb usb2: Manufacturer: Linux 4.19.127-v8 xhci-hcd
[    0.558572] usb usb2: SerialNumber: 0000:01:00.0
[    0.561654] hub 2-0:1.0: USB hub found
[    0.564303] hub 2-0:1.0: 4 ports detected
[    0.567971] dwc_otg: version 3.00a 10-AUG-2012 (platform bus)
[    0.570735] dwc_otg: FIQ enabled
[    0.570743] dwc_otg: NAK holdoff enabled
[    0.570751] dwc_otg: FIQ split-transaction FSM enabled
[    0.570764] Module dwc_common_port init
[    0.571296] usbcore: registered new interface driver uas
[    0.573945] usbcore: registered new interface driver usb-storage
[    0.576610] usbcore: registered new interface driver usbserial_generic
[    0.579189] usbserial: USB Serial support registered for generic
[    0.581909] mousedev: PS/2 mouse device common for all mice
[    0.585547] bcm2835-wdt bcm2835-wdt: Broadcom BCM2835 watchdog timer
[    0.589809] sdhci: Secure Digital Host Controller Interface driver
[    0.592379] sdhci: Copyright(c) Pierre Ossman
[    0.595090] mmc-bcm2835 fe300000.mmcnr: could not get clk, deferring probe
[    0.596436] sdhci-pltfm: SDHCI platform and OF driver helper
[    0.599577] ledtrig-cpu: registered to indicate activity on CPUs
[    0.600786] hidraw: raw HID events driver (C) Jiri Kosina
[    0.602025] usbcore: registered new interface driver usbhid
[    0.603225] usbhid: USB HID core driver
[    0.604838] vchiq: vchiq_init_state: slot_zero = (____ptrval____), is_master = 0
[    0.608088] IPVS: Registered protocols (TCP, UDP, SCTP, AH, ESP)
[    0.609450] IPVS: Connection hash table configured (size=4096, memory=64Kbytes)
[    0.610858] IPVS: ipvs loaded.
[    0.612228] IPVS: [rr] scheduler registered.
[    0.614041] NET: Registered protocol family 10
[    0.615920] Segment Routing with IPv6
[    0.617084] NET: Registered protocol family 17
[    0.618224] Bridge firewalling registered
[    0.619444] Key type dns_resolver registered
[    0.621043] registered taskstats version 1
[    0.622203] Loading compiled-in X.509 certificates
[    0.623414] AppArmor: AppArmor sha1 policy hashing enabled
[    0.627741] uart-pl011 fe201000.serial: cts_event_workaround enabled
[    0.629017] fe201000.serial: ttyAMA0 at MMIO 0xfe201000 (irq = 19, base_baud = 0) is a PL011 rev2
[    0.634013] bcm2835-power bcm2835-power: Broadcom BCM2835 power domains driver
[    0.635539] brcmstb_thermal fd5d2200.thermal: registered AVS TMON of-sensor driver
[    0.637074] mmc-bcm2835 fe300000.mmcnr: mmc_debug:0 mmc_debug2:0
[    0.638322] mmc-bcm2835 fe300000.mmcnr: DMA channel allocated
[    0.664071] sdhci-iproc fe340000.emmc2: Linked as a consumer to regulator.3
[    0.665760] sdhci-iproc fe340000.emmc2: Linked as a consumer to regulator.4
[    0.685447] mmc1: queuing unknown CIS tuple 0x80 (2 bytes)
[    0.688844] mmc1: queuing unknown CIS tuple 0x80 (3 bytes)
[    0.692680] mmc1: queuing unknown CIS tuple 0x80 (3 bytes)
[    0.697603] mmc1: queuing unknown CIS tuple 0x80 (7 bytes)
[    0.700761] mmc1: queuing unknown CIS tuple 0x80 (3 bytes)
[    0.703619] mmc0: SDHCI controller on fe340000.emmc2 [fe340000.emmc2] using ADMA
[    0.706813] console [netcon0] enabled
[    0.708361] netconsole: network logging started
[    0.709872] of_cfs_init
[    0.711488] of_cfs_init: OK
[    0.713587] Waiting for root device PARTUUID=8d3d53e3-6d49-4c38-8349-aff6859e82fd...
[    0.757132] random: fast init done
[    0.784150] mmc1: new high speed SDIO card at address 0001
[    0.820128] mmc0: new ultra high speed DDR50 SDXC card at address aaaa
[    0.822171] mmcblk0: mmc0:aaaa SB64G 59.5 GiB
[    0.829170]  mmcblk0: p1 p2 p3 p4 p5 p6 p7 p8
[    0.851648] VFS: Mounted root (squashfs filesystem) readonly on device 179:3.
[    0.856054] devtmpfs: mounted
[    0.862072] Freeing unused kernel memory: 1088K
[    0.865109] Run /sbin/init as init process
[    0.875058] usb 1-1: new high-speed USB device number 2 using xhci_hcd
[    1.025660] usb 1-1: New USB device found, idVendor=2109, idProduct=3431, bcdDevice= 4.21
[    1.027357] usb 1-1: New USB device strings: Mfr=0, Product=1, SerialNumber=0
[    1.029029] usb 1-1: Product: USB2.0 Hub
[    1.032576] hub 1-1:1.0: USB hub found
[    1.034467] hub 1-1:1.0: 4 ports detected
[    1.055273] systemd[1]: System time before build time, advancing clock.
[    1.070256] systemd[1]: systemd 244 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)
[    1.073214] systemd[1]: Detected architecture arm64.
[    1.088175] systemd[1]: Set hostname to <homeassistant>.
[    1.089586] systemd[1]: Installed transient /etc/machine-id file.
[    1.245797] systemd[1]: /usr/lib/systemd/system/docker.socket:6: ListenStream= references a path below legacy directory /var/run/, updating /var/run/docker.sock \xe2\x86\x92 /run/docker.sock; please update the unit file accordingly.
[    1.270643] systemd[1]: /usr/lib/systemd/system/dbus.socket:5: ListenStream= references a path below legacy directory /var/run/, updating /var/run/dbus/system_bus_socket \xe2\x86\x92 /run/dbus/system_bus_socket; please update the unit file accordingly.
[    1.331085] usb 1-1.3: new high-speed USB device number 3 using xhci_hcd
[    1.333630] random: systemd: uninitialized urandom read (16 bytes read)
[    1.337540] systemd[1]: Created slice system-getty.slice.
[    1.340842] random: systemd: uninitialized urandom read (16 bytes read)
[    1.342437] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.
[    1.345534] random: systemd: uninitialized urandom read (16 bytes read)
[    1.347190] systemd[1]: Started Forward Password Requests to Wall Directory Watch.
[    1.350342] systemd[1]: Reached target HassOS zram targets.
[    1.353554] systemd[1]: Reached target Paths.
[    1.356815] systemd[1]: Reached target Remote File Systems.
[    1.359977] systemd[1]: Reached target Slices.
[    1.365097] systemd[1]: Listening on Process Core Dump Socket.
[    1.368351] systemd[1]: Listening on initctl Compatibility Named Pipe.
[    1.371896] systemd[1]: Listening on Journal Audit Socket.
[    1.375303] systemd[1]: Listening on Journal Socket (/dev/log).
[    1.378834] systemd[1]: Listening on Journal Socket.
[    1.382401] systemd[1]: Listening on udev Control Socket.
[    1.385741] systemd[1]: Listening on udev Kernel Socket.
[    1.389330] systemd[1]: Condition check resulted in Huge Pages File System being skipped.
[    1.393599] systemd[1]: Mounting POSIX Message Queue File System...
[    1.400655] systemd[1]: Mounting Kernel Debug File System...
[    1.408971] systemd[1]: Starting Create list of static device nodes for the current kernel...
[    1.417028] systemd[1]: Starting Journal Service...
[    1.424622] systemd[1]: Starting Remount Root and Kernel File Systems...
[    1.432725] systemd[1]: Starting Wait Until Kernel Time Synchronized...
[    1.438791] usb 1-1.3: New USB device found, idVendor=8564, idProduct=1000, bcdDevice= 1.00
[    1.440989] systemd[1]: Starting udev Coldplug all Devices...
[    1.441527] usb 1-1.3: New USB device strings: Mfr=1, Product=2, SerialNumber=3
[    1.445044] usb 1-1.3: Product: Mass Storage Device
[    1.445050] usb 1-1.3: Manufacturer: JetFlash
[    1.448748] usb 1-1.3: SerialNumber: 6B9FFLS0
[    1.453309] usb-storage 1-1.3:1.0: USB Mass Storage device detected
[    1.456479] systemd[1]: Starting HassOS ZRAM swap...
[    1.461971] scsi host0: usb-storage 1-1.3:1.0
[    1.464911] systemd[1]: Starting HassOS ZRAM tmp...
[    1.472277] systemd[1]: Starting HassOS ZRAM var...
[    1.480165] systemd[1]: Mounted POSIX Message Queue File System.
[    1.484141] systemd[1]: Mounted Kernel Debug File System.
[    1.490884] systemd[1]: Started Create list of static device nodes for the current kernel.
[    1.497474] systemd[1]: Started Remount Root and Kernel File Systems.
[    1.504898] zram1: detected capacity change from 0 to 33554432
[    1.506842] systemd[1]: Starting Create Static Device Nodes in /dev...
[    1.508636] zram2: detected capacity change from 0 to 16777216
[    1.510510] zram0: detected capacity change from 0 to 997646336
[    1.515561] systemd[1]: Started Journal Service.
[    1.718564] vc_sm_cma: module is from the staging directory, the quality is unknown, you have been warned.
[    1.721667] bcm2835_vc_sm_cma_probe: Videocore shared memory driver
[    1.723506] rpivid-mem feb00000.hevc-decoder: rpivid-hevcmem initialised: Registers at 0xfeb00000 length 0x0000ffff
[    1.726808] [vc_sm_connected_init]: start
[    1.730725] rpivid-mem feb10000.rpivid-local-intc: rpivid-intcmem initialised: Registers at 0xfeb10000 length 0x00000fff
[    1.735208] rpivid-mem feb20000.h264-decoder: rpivid-h264mem initialised: Registers at 0xfeb20000 length 0x0000ffff
[    1.740613] rpivid-mem feb30000.vp9-decoder: rpivid-vp9mem initialised: Registers at 0xfeb30000 length 0x0000ffff
[    1.747636] [vc_sm_connected_init]: installed successfully
[    1.752709] bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned.
[    1.757578] bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned.
[    1.767255] bcm2835_v4l2: module is from the staging directory, the quality is unknown, you have been warned.
[    1.786879] bcm2835_codec: module is from the staging directory, the quality is unknown, you have been warned.
[    1.808464] snd_bcm2835: module is from the staging directory, the quality is unknown, you have been warned.
[    1.810233] bcm2835-codec bcm2835-codec: Device registered as /dev/video10
[    1.812126] bcm2835-codec bcm2835-codec: Loaded V4L2 decode
[    1.818916] bcm2835-codec bcm2835-codec: Device registered as /dev/video11
[    1.820757] bcm2835-codec bcm2835-codec: Loaded V4L2 encode
[    1.829837] bcm2835_audio soc:audio: card created with 8 channels
[    1.833234] bcm2835-codec bcm2835-codec: Device registered as /dev/video12
[    1.834819] bcm2835-codec bcm2835-codec: Loaded V4L2 isp
[    1.856129] cfg80211: Loading compiled-in X.509 certificates for regulatory database
[    1.867677] cfg80211: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'
[    1.943275] brcmfmac: F1 signature read @0x18000000=0x15264345
[    1.949811] brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6
[    1.952895] usbcore: registered new interface driver brcmfmac
[    2.184234] EXT4-fs (mmcblk0p7): mounted filesystem with ordered data mode. Opts: (null)
[    2.213413] brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6
[    2.225008] FAT-fs (mmcblk0p1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck.
[    2.231733] brcmfmac: brcmf_c_preinit_dcmds: Firmware: BCM4345/6 wl0: Feb 27 2018 03:15:32 version 7.45.154 (r684107 CY) FWID 01-4fbe0b04
[    2.497145] scsi 0:0:0:0: Direct-Access     JetFlash Transcend 32GB   8.07 PQ: 0 ANSI: 4
[    2.500159] sd 0:0:0:0: [sda] 62939136 512-byte logical blocks: (32.2 GB/30.0 GiB)
[    2.502825] sd 0:0:0:0: [sda] Write Protect is off
[    2.504648] sd 0:0:0:0: [sda] Mode Sense: 23 00 00 00
[    2.505434] sd 0:0:0:0: [sda] Write cache: disabled, read cache: enabled, doesn't support DPO or FUA
[    2.524878]  sda:
[    2.529522] sd 0:0:0:0: [sda] Attached SCSI removable disk
[    2.548732] EXT4-fs (mmcblk0p8): mounted filesystem with ordered data mode. Opts: (null)
[    2.555441] Adding 974260k swap on /dev/zram0.  Priority:-2 extents:1 across:974260k SSFS
[    3.159397] usb 2-1: new SuperSpeed Gen 1 USB device number 2 using xhci_hcd
[    3.180604] usb 2-1: New USB device found, idVendor=0bc2, idProduct=331a, bcdDevice= 9.09
[    3.182885] usb 2-1: New USB device strings: Mfr=1, Product=2, SerialNumber=3
[    3.185123] usb 2-1: Product: Expansion Desk
[    3.187402] usb 2-1: Manufacturer: Seagate
[    3.189642] usb 2-1: SerialNumber: NA8EJ5BT
[    3.216028] scsi host1: uas
[    3.222764] scsi 1:0:0:0: Direct-Access     Seagate  Expansion Desk   0909 PQ: 0 ANSI: 6
[    3.229630] sd 1:0:0:0: [sdb] Spinning up disk...
[    3.569137] EXT4-fs (zram2): mounted filesystem without journal. Opts: nobarrier
[    3.574201] EXT4-fs (zram1): mounted filesystem without journal. Opts: nobarrier
[    3.686948] systemd-journald[112]: Received client request to flush runtime journal.
[    4.093433] systemd-journald[112]: File /var/log/journal/1f4380f014334d2fa3b106e1a8f03b0d/system.journal corrupted or uncleanly shut down, renaming and replacing.
[    4.255094] ..ready
[    5.281134] sd 1:0:0:0: [sdb] 9767541167 512-byte logical blocks: (5.00 TB/4.55 TiB)
[    5.282394] sd 1:0:0:0: [sdb] 4096-byte physical blocks
[    5.283794] sd 1:0:0:0: [sdb] Write Protect is off
[    5.285007] sd 1:0:0:0: [sdb] Mode Sense: 53 00 00 08
[    5.285368] sd 1:0:0:0: [sdb] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
[    5.287296] sd 1:0:0:0: [sdb] Optimal transfer size 33553920 bytes not a multiple of physical block size (4096 bytes)
[    5.354636]  sdb: sdb1
[    5.358115] sd 1:0:0:0: [sdb] Attached SCSI disk
[    5.487844] random: crng init done
[    5.489262] random: 7 urandom warning(s) missed due to ratelimiting
[    6.692678] uart-pl011 fe201000.serial: no DMA platform data
[    6.963164] audit: type=1400 audit(1606781918.379:2): apparmor="STATUS" operation="profile_load" profile="unconfined" name="hassio-supervisor" pid=317 comm="apparmor_parser"
[    6.964490] audit: type=1400 audit(1606781918.379:2): apparmor="STATUS" operation="profile_load" profile="unconfined" name="hassio-supervisor///usr/bin/gdbus" pid=317 comm="apparmor_parser"
[    6.965914] audit: type=1400 audit(1606781918.379:2): apparmor="STATUS" operation="profile_load" profile="unconfined" name="hassio-supervisor///usr/bin/git" pid=317 comm="apparmor_parser"
[    6.967185] audit: type=1400 audit(1606781918.379:2): apparmor="STATUS" operation="profile_load" profile="unconfined" name="hassio-supervisor///usr/bin/socat" pid=317 comm="apparmor_parser"
[    6.968487] audit: type=1300 audit(1606781918.379:2): arch=c00000b7 syscall=64 success=yes exit=54180 a0=6 a1=b0baf0 a2=d3a4 a3=0 items=0 ppid=305 pid=317 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)
[    6.972129] audit: type=1327 audit(1606781918.379:2): proctitle=61707061726D6F725F706172736572002D72002D57002D4C002F6D6E742F646174612F73757065727669736F722F61707061726D6F722F6361636865002F6D6E742F646174612F73757065727669736F722F61707061726D6F722F68617373696F2D73757065727669736F72
[    7.275874] Bluetooth: Core ver 2.22
[    7.277363] NET: Registered protocol family 31
[    7.278795] Bluetooth: HCI device and connection manager initialized
[    7.280206] Bluetooth: HCI socket layer initialized
[    7.281513] Bluetooth: L2CAP socket layer initialized
[    7.283300] Bluetooth: SCO socket layer initialized
[    7.300769] Bluetooth: HCI UART driver ver 2.3
[    7.302108] Bluetooth: HCI UART protocol H4 registered
[    7.303506] Bluetooth: HCI UART protocol Three-wire (H5) registered
[    7.305008] Bluetooth: HCI UART protocol Broadcom registered
[    7.457348] Bluetooth: BNEP (Ethernet Emulation) ver 1.3
[    7.458951] Bluetooth: BNEP filters: protocol multicast
[    7.460360] Bluetooth: BNEP socket layer initialized
[    7.480569] NET: Registered protocol family 38
[    7.509374] cryptd: max_cpu_qlen set to 1000
[    7.601294] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready
[    7.602924] bcmgenet: Skipping UMAC reset
[    7.679343] bcmgenet fd580000.genet: configuring instance for external RGMII (no delay)
[    7.681112] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready
[    7.699338] IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready
[    7.726856] IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready
[    7.728205] brcmfmac: brcmf_cfg80211_set_power_mgmt: power save enabled
[    8.257934] IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready
[    8.259301] brcmfmac: brcmf_cfg80211_set_power_mgmt: power save enabled
[    8.328325] IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready
[    8.703132] bcmgenet fd580000.genet eth0: Link is Down
[   11.425761] IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready
[   11.427132] brcmfmac: brcmf_cfg80211_set_power_mgmt: power save enabled
[   12.799149] bcmgenet fd580000.genet eth0: Link is Up - 1Gbps/Full - flow control rx/tx
[   12.800470] IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready
[   16.737743] IPv6: ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready
[   18.320271] audit: type=1400 audit(1606781929.739:3): apparmor="STATUS" operation="profile_load" profile="unconfined" name="docker-default" pid=411 comm="apparmor_parser"
[   18.321880] audit: type=1300 audit(1606781929.739:3): arch=c00000b7 syscall=64 success=yes exit=7945 a0=4 a1=b109560 a2=1f09 a3=0 items=0 ppid=410 pid=411 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)
[   18.324585] audit: type=1327 audit(1606781929.739:3): proctitle=61707061726D6F725F706172736572002D4B72002F6D6E742F646174612F646F636B65722F746D702F646F636B65722D64656661756C74343937393631373933
[   20.114222] audit: type=1325 audit(1606781931.531:4): table=nat family=2 entries=5
[   20.116254] audit: type=1300 audit(1606781931.531:4): arch=c00000b7 syscall=208 success=yes exit=0 a0=4 a1=0 a2=40 a3=6951a60 items=0 ppid=381 pid=445 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)
[   20.120628] audit: type=1327 audit(1606781931.531:4): proctitle=2F7573722F7362696E2F69707461626C6573002D2D77616974002D74006E6174002D4E00444F434B4552
[   20.127815] audit: type=1325 audit(1606781931.547:5): table=filter family=2 entries=4
[   20.130160] audit: type=1300 audit(1606781931.547:5): arch=c00000b7 syscall=208 success=yes exit=0 a0=4 a1=0 a2=40 a3=20d83940 items=0 ppid=381 pid=447 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="iptables" exe="/usr/sbin/xtables-legacy-multi" subj==unconfined key=(null)
[   20.134330] audit: type=1327 audit(1606781931.547:5): proctitle=2F7573722F7362696E2F69707461626C6573002D2D77616974002D740066696C746572002D4E00444F434B4552
[   20.140492] audit: type=1325 audit(1606781931.559:6): table=filter family=2 entries=6
[   20.201043] Initializing XFRM netlink socket
[   20.387621] IPv6: ADDRCONF(NETDEV_UP): docker0: link is not ready
[   20.551900] IPv6: ADDRCONF(NETDEV_UP): hassio: link is not ready
[   21.038461] hassio: port 1(vethdd928cb) entered blocking state
[   21.040305] hassio: port 1(vethdd928cb) entered disabled state
[   21.042716] device vethdd928cb entered promiscuous mode
[   21.046741] IPv6: ADDRCONF(NETDEV_UP): vethdd928cb: link is not ready
[   21.050203] hassio: port 1(vethdd928cb) entered blocking state
[   21.052505] hassio: port 1(vethdd928cb) entered forwarding state
[   21.054764] IPv6: ADDRCONF(NETDEV_CHANGE): hassio: link becomes ready
[   21.056703] hassio: port 1(vethdd928cb) entered disabled state
[   21.626343] cgroup: cgroup: disabling cgroup2 socket matching due to net_prio or net_cls activation
[   22.041597] eth0: renamed from vethead0b48
[   22.080626] IPv6: ADDRCONF(NETDEV_CHANGE): vethdd928cb: link becomes ready
[   22.084700] hassio: port 1(vethdd928cb) entered blocking state
[   22.088690] hassio: port 1(vethdd928cb) entered forwarding state
[   24.460257] docker0: port 1(veth9fd0f83) entered blocking state
[   24.462544] docker0: port 1(veth9fd0f83) entered disabled state
[   24.465836] device veth9fd0f83 entered promiscuous mode
[   24.467973] kauditd_printk_skb: 182 callbacks suppressed
[   24.467977] audit: type=1700 audit(1606781935.879:67): dev=veth9fd0f83 prom=256 old_prom=0 auid=4294967295 uid=0 gid=0 ses=4294967295
[   24.471777] IPv6: ADDRCONF(NETDEV_UP): veth9fd0f83: link is not ready
[   24.474055] audit: type=1300 audit(1606781935.879:67): arch=c00000b7 syscall=206 success=yes exit=40 a0=c a1=400083f3e0 a2=28 a3=0 items=0 ppid=1 pid=381 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)
[   24.478197] audit: type=1327 audit(1606781935.879:67): proctitle=2F7573722F62696E2F646F636B657264002D480066643A2F2F002D2D73746F726167652D6472697665723D6F7665726C617932002D2D6C6F672D6472697665723D6A6F75726E616C64002D2D646174612D726F6F74002F6D6E742F646174612F646F636B6572
[   24.522183] hassio: port 2(veth7f8a9a0) entered blocking state
[   24.525595] hassio: port 2(veth7f8a9a0) entered disabled state
[   24.528988] device veth7f8a9a0 entered promiscuous mode
[   24.533224] audit: type=1700 audit(1606781935.939:68): dev=veth7f8a9a0 prom=256 old_prom=0 auid=4294967295 uid=0 gid=0 ses=4294967295
[   24.533916] IPv6: ADDRCONF(NETDEV_UP): veth7f8a9a0: link is not ready
[   24.535271] audit: type=1300 audit(1606781935.939:68): arch=c00000b7 syscall=206 success=yes exit=40 a0=c a1=400083fd40 a2=28 a3=0 items=0 ppid=1 pid=381 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)
[   24.537282] hassio: port 2(veth7f8a9a0) entered blocking state
[   24.541420] audit: type=1327 audit(1606781935.939:68): proctitle=2F7573722F62696E2F646F636B657264002D480066643A2F2F002D2D73746F726167652D6472697665723D6F7665726C617932002D2D6C6F672D6472697665723D6A6F75726E616C64002D2D646174612D726F6F74002F6D6E742F646174612F646F636B6572
[   24.543439] hassio: port 2(veth7f8a9a0) entered forwarding state
[   24.944528] eth0: renamed from vethe7aa777
[   24.976631] hassio: port 2(veth7f8a9a0) entered disabled state
[   24.981813] IPv6: ADDRCONF(NETDEV_CHANGE): veth9fd0f83: link becomes ready
[   24.986772] docker0: port 1(veth9fd0f83) entered blocking state
[   24.990283] docker0: port 1(veth9fd0f83) entered forwarding state
[   24.992399] IPv6: ADDRCONF(NETDEV_CHANGE): docker0: link becomes ready
[   25.091867] audit: type=1325 audit(1606781936.511:69): table=nat family=2 entries=0
[   25.094168] audit: type=1300 audit(1606781936.511:69): arch=c00000b7 syscall=209 success=yes exit=0 a0=4 a1=0 a2=40 a3=7fd3534bf0 items=0 ppid=979 pid=988 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)
[   25.098830] audit: type=1327 audit(1606781936.511:69): proctitle=2F7573722F7362696E2F69707461626C6573002D2D77616974002D74006E6174002D4C002D6E
[   25.103358] audit: type=1325 audit(1606781936.523:70): table=filter family=2 entries=0
[   25.190296] eth1: renamed from veth3753b21
[   25.217439] IPv6: ADDRCONF(NETDEV_CHANGE): veth7f8a9a0: link becomes ready
[   25.221773] hassio: port 2(veth7f8a9a0) entered blocking state
[   25.225246] hassio: port 2(veth7f8a9a0) entered forwarding state
[   25.908289] udevd[205]: starting version 3.2.9
[   25.932980] udevd[206]: starting eudev-3.2.9
[   31.690159] kauditd_printk_skb: 26 callbacks suppressed
[   31.690165] audit: type=1400 audit(1606781943.107:79): apparmor="STATUS" operation="profile_replace" info="same as current profile, skipping" profile="unconfined" name="hassio-supervisor" pid=1486 comm="apparmor_parser"
[   31.690710] audit: type=1400 audit(1606781943.107:79): apparmor="STATUS" operation="profile_replace" info="same as current profile, skipping" profile="unconfined" name="hassio-supervisor///usr/bin/gdbus" pid=1486 comm="apparmor_parser"
[   31.691066] audit: type=1400 audit(1606781943.107:79): apparmor="STATUS" operation="profile_replace" info="same as current profile, skipping" profile="unconfined" name="hassio-supervisor///usr/bin/git" pid=1486 comm="apparmor_parser"
[   31.691736] audit: type=1400 audit(1606781943.107:79): apparmor="STATUS" operation="profile_replace" info="same as current profile, skipping" profile="unconfined" name="hassio-supervisor///usr/bin/socat" pid=1486 comm="apparmor_parser"
[   31.692014] audit: type=1300 audit(1606781943.107:79): arch=c00000b7 syscall=64 success=yes exit=54180 a0=6 a1=17af3af0 a2=d3a4 a3=0 items=0 ppid=1485 pid=1486 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)
[   31.692331] audit: type=1327 audit(1606781943.107:79): proctitle=61707061726D6F725F706172736572002D72002D57002D4C002F6D6E742F646174612F73757065727669736F722F61707061726D6F722F6361636865002F6D6E742F646174612F73757065727669736F722F61707061726D6F722F68617373696F2D73757065727669736F72
[   32.640965] hassio: port 3(veth2f4732c) entered blocking state
[   32.641144] hassio: port 3(veth2f4732c) entered disabled state
[   32.641397] device veth2f4732c entered promiscuous mode
[   32.641501] audit: type=1700 audit(1606781944.059:80): dev=veth2f4732c prom=256 old_prom=0 auid=4294967295 uid=0 gid=0 ses=4294967295
[   32.641570] audit: type=1300 audit(1606781944.059:80): arch=c00000b7 syscall=206 success=yes exit=40 a0=c a1=4000e29650 a2=28 a3=0 items=0 ppid=1 pid=381 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)
[   32.641696] audit: type=1327 audit(1606781944.059:80): proctitle=2F7573722F62696E2F646F636B657264002D480066643A2F2F002D2D73746F726167652D6472697665723D6F7665726C617932002D2D6C6F672D6472697665723D6A6F75726E616C64002D2D646174612D726F6F74002F6D6E742F646174612F646F636B6572
[   32.641697] IPv6: ADDRCONF(NETDEV_UP): veth2f4732c: link is not ready
[   33.083018] audit: type=1325 audit(1606781944.499:81): table=nat family=2 entries=0
[   33.189648] eth0: renamed from veth6a01b5b
[   33.208136] IPv6: ADDRCONF(NETDEV_CHANGE): veth2f4732c: link becomes ready
[   33.208311] hassio: port 3(veth2f4732c) entered blocking state
[   33.208361] hassio: port 3(veth2f4732c) entered forwarding state
[   34.453320] hassio: port 4(veth233fbae) entered blocking state
[   34.453376] hassio: port 4(veth233fbae) entered disabled state
[   34.453724] device veth233fbae entered promiscuous mode
[   34.454045] IPv6: ADDRCONF(NETDEV_UP): veth233fbae: link is not ready
[   35.121011] eth0: renamed from veth02accb7
[   35.140489] IPv6: ADDRCONF(NETDEV_CHANGE): veth233fbae: link becomes ready
[   35.140707] hassio: port 4(veth233fbae) entered blocking state
[   35.140771] hassio: port 4(veth233fbae) entered forwarding state
[   36.200831] hassio: port 5(vethb9ce54f) entered blocking state
[   36.200917] hassio: port 5(vethb9ce54f) entered disabled state
[   36.201155] device vethb9ce54f entered promiscuous mode
[   36.201754] IPv6: ADDRCONF(NETDEV_UP): vethb9ce54f: link is not ready
[   36.695251] kauditd_printk_skb: 83 callbacks suppressed
[   36.695256] audit: type=1325 audit(1606781968.415:109): table=nat family=2 entries=11
[   36.695349] audit: type=1300 audit(1606781968.415:109): arch=c00000b7 syscall=208 success=yes exit=0 a0=4 a1=0 a2=40 a3=27cf1d20 items=0 ppid=2214 pid=2240 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)
[   36.695481] audit: type=1327 audit(1606781968.415:109): proctitle=2F7573722F7362696E2F69707461626C6573002D2D77616974002D74006E6174002D4900444F434B45525F4F5554505554002D64003132372E302E302E3131002D7000756470002D2D64706F7274003533002D6A00444E4154002D2D746F2D64657374696E6174696F6E003132372E302E302E31313A3539373739
[   36.700428] audit: type=1325 audit(1606781968.419:110): table=nat family=2 entries=12
[   36.700841] audit: type=1300 audit(1606781968.419:110): arch=c00000b7 syscall=208 success=yes exit=0 a0=4 a1=0 a2=40 a3=26dbce30 items=0 ppid=2214 pid=2241 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)
[   36.700971] audit: type=1327 audit(1606781968.419:110): proctitle=2F7573722F7362696E2F69707461626C6573002D2D77616974002D74006E6174002D4900444F434B45525F504F5354524F5554494E47002D73003132372E302E302E3131002D7000756470002D2D73706F7274003539373739002D6A00534E4154002D2D746F2D736F75726365003A3533
[   36.705840] audit: type=1325 audit(1606781968.423:111): table=nat family=2 entries=13
[   36.706175] audit: type=1300 audit(1606781968.423:111): arch=c00000b7 syscall=208 success=yes exit=0 a0=4 a1=0 a2=40 a3=1bb8f060 items=0 ppid=2214 pid=2242 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)
[   36.706310] audit: type=1327 audit(1606781968.423:111): proctitle=2F7573722F7362696E2F69707461626C6573002D2D77616974002D74006E6174002D4900444F434B45525F4F5554505554002D64003132372E302E302E3131002D7000746370002D2D64706F7274003533002D6A00444E4154002D2D746F2D64657374696E6174696F6E003132372E302E302E31313A3335333539
[   36.711787] audit: type=1325 audit(1606781968.431:112): table=nat family=2 entries=14
[   36.749597] eth0: renamed from veth8d9bdce
[   36.772246] IPv6: ADDRCONF(NETDEV_CHANGE): vethb9ce54f: link becomes ready
[   36.772456] hassio: port 5(vethb9ce54f) entered blocking state
[   36.772507] hassio: port 5(vethb9ce54f) entered forwarding state
[   38.492666] udevd[493]: starting version 3.2.9
[   38.537363] udevd[494]: starting eudev-3.2.9
[   38.983362] Bluetooth: RFCOMM TTY layer initialized
[   38.999997] Bluetooth: RFCOMM socket layer initialized
[   39.015129] Bluetooth: RFCOMM ver 1.11
[   42.391343] hassio: port 6(veth43211f0) entered blocking state
[   42.406815] hassio: port 6(veth43211f0) entered disabled state
[   42.420763] device veth43211f0 entered promiscuous mode
[   42.434082] kauditd_printk_skb: 2 callbacks suppressed
[   42.434086] audit: type=1700 audit(1606781974.111:113): dev=veth43211f0 prom=256 old_prom=0 auid=4294967295 uid=0 gid=0 ses=4294967295
[   42.434436] IPv6: ADDRCONF(NETDEV_UP): veth43211f0: link is not ready
[   42.446988] audit: type=1300 audit(1606781974.111:113): arch=c00000b7 syscall=206 success=yes exit=40 a0=c a1=40000570e0 a2=28 a3=0 items=0 ppid=1 pid=381 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)
[   42.500666] audit: type=1327 audit(1606781974.111:113): proctitle=2F7573722F62696E2F646F636B657264002D480066643A2F2F002D2D73746F726167652D6472697665723D6F7665726C617932002D2D6C6F672D6472697665723D6A6F75726E616C64002D2D646174612D726F6F74002F6D6E742F646174612F646F636B6572
[   42.536962] audit: type=1325 audit(1606781974.255:114): table=nat family=2 entries=15
[   42.553416] audit: type=1300 audit(1606781974.255:114): arch=c00000b7 syscall=208 success=yes exit=0 a0=4 a1=0 a2=40 a3=f4e4520 items=0 ppid=381 pid=2937 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.584915] audit: type=1327 audit(1606781974.255:114): proctitle=2F7573722F7362696E2F69707461626C6573002D2D77616974002D74006E6174002D4100444F434B4552002D7000746370002D6400302F30002D2D64706F72740038383834002D6A00444E4154002D2D746F2D64657374696E6174696F6E003137322E33302E33332E303A383838340000002D690068617373696F
[   42.615643] audit: type=1325 audit(1606781974.271:115): table=filter family=2 entries=30
[   42.630662] audit: type=1300 audit(1606781974.271:115): arch=c00000b7 syscall=208 success=yes exit=0 a0=4 a1=0 a2=40 a3=1db19880 items=0 ppid=381 pid=2939 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.662469] audit: type=1327 audit(1606781974.271:115): proctitle=2F7573722F7362696E2F69707461626C6573002D2D77616974002D740066696C746572002D4100444F434B45520000002D690068617373696F002D6F0068617373696F002D7000746370002D64003137322E33302E33332E30002D2D64706F72740038383834002D6A00414343455054
[   42.694782] audit: type=1325 audit(1606781974.287:116): table=nat family=2 entries=16
[   43.289025] eth0: renamed from vethe65ece1
[   43.328347] IPv6: ADDRCONF(NETDEV_CHANGE): veth43211f0: link becomes ready
[   43.345637] hassio: port 6(veth43211f0) entered blocking state
[   43.360132] hassio: port 6(veth43211f0) entered forwarding state
[   49.376458] hassio: port 7(veth9957567) entered blocking state
[   49.394277] hassio: port 7(veth9957567) entered disabled state
[   49.409269] device veth9957567 entered promiscuous mode
[   49.423796] kauditd_printk_skb: 59 callbacks suppressed
[   49.423805] audit: type=1700 audit(1606781981.095:136): dev=veth9957567 prom=256 old_prom=0 auid=4294967295 uid=0 gid=0 ses=4294967295
[   49.424043] IPv6: ADDRCONF(NETDEV_UP): veth9957567: link is not ready
[   49.438291] audit: type=1300 audit(1606781981.095:136): arch=c00000b7 syscall=206 success=yes exit=40 a0=c a1=40014daa50 a2=28 a3=0 items=0 ppid=1 pid=381 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.501473] audit: type=1327 audit(1606781981.095:136): proctitle=2F7573722F62696E2F646F636B657264002D480066643A2F2F002D2D73746F726167652D6472697665723D6F7665726C617932002D2D6C6F672D6472697665723D6A6F75726E616C64002D2D646174612D726F6F74002F6D6E742F646174612F646F636B6572
[   49.537798] audit: type=1325 audit(1606781981.239:137): table=nat family=2 entries=23
[   49.556119] audit: type=1300 audit(1606781981.239:137): arch=c00000b7 syscall=208 success=yes exit=0 a0=4 a1=0 a2=40 a3=1a7eb5c0 items=0 ppid=381 pid=3118 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.592795] audit: type=1327 audit(1606781981.239:137): proctitle=2F7573722F7362696E2F69707461626C6573002D2D77616974002D74006E6174002D4100444F434B4552002D7000746370002D6400302F30002D2D64706F7274003333343433002D6A00444E4154002D2D746F2D64657374696E6174696F6E003137322E33302E33332E313A33333434330000002D690068617373696F
[   49.625804] audit: type=1325 audit(1606781981.251:138): table=filter family=2 entries=34
[   49.642911] audit: type=1300 audit(1606781981.251:138): arch=c00000b7 syscall=208 success=yes exit=0 a0=4 a1=0 a2=40 a3=1f39afe0 items=0 ppid=381 pid=3120 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.680203] audit: type=1327 audit(1606781981.251:138): proctitle=2F7573722F7362696E2F69707461626C6573002D2D77616974002D740066696C746572002D4100444F434B45520000002D690068617373696F002D6F0068617373696F002D7000746370002D64003137322E33302E33332E31002D2D64706F7274003333343433002D6A00414343455054
[   49.716713] audit: type=1325 audit(1606781981.267:139): table=nat family=2 entries=24
[   50.764644] eth0: renamed from vethcbba34d
[   50.800130] IPv6: ADDRCONF(NETDEV_CHANGE): veth9957567: link becomes ready
[   50.822225] hassio: port 7(veth9957567) entered blocking state
[   50.838952] hassio: port 7(veth9957567) entered forwarding state
[   52.220611] hassio: port 8(veth0f5843d) entered blocking state
[   52.236161] hassio: port 8(veth0f5843d) entered disabled state
[   52.251885] device veth0f5843d entered promiscuous mode
[   52.267775] IPv6: ADDRCONF(NETDEV_UP): veth0f5843d: link is not ready
[   52.915901] eth0: renamed from vethc862465
[   52.944505] IPv6: ADDRCONF(NETDEV_CHANGE): veth0f5843d: link becomes ready
[   52.962482] hassio: port 8(veth0f5843d) entered blocking state
[   52.978009] hassio: port 8(veth0f5843d) entered forwarding state
[   60.379181] udevd[193]: starting version 3.2.9
[   60.421203] udevd[194]: starting eudev-3.2.9
[   86.198488] hassio: port 9(veth986d0ec) entered blocking state
[   86.216062] hassio: port 9(veth986d0ec) entered disabled state
[   86.232192] device veth986d0ec entered promiscuous mode
[   86.247961] kauditd_printk_skb: 155 callbacks suppressed
[   86.247964] audit: type=1700 audit(1606782017.915:191): dev=veth986d0ec prom=256 old_prom=0 auid=4294967295 uid=0 gid=0 ses=4294967295
[   86.248258] IPv6: ADDRCONF(NETDEV_UP): veth986d0ec: link is not ready
[   86.267695] audit: type=1300 audit(1606782017.915:191): arch=c00000b7 syscall=206 success=yes exit=40 a0=c a1=4000cf84e0 a2=28 a3=0 items=0 ppid=1 pid=381 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)
[   86.325555] audit: type=1327 audit(1606782017.915:191): proctitle=2F7573722F62696E2F646F636B657264002D480066643A2F2F002D2D73746F726167652D6472697665723D6F7665726C617932002D2D6C6F672D6472697665723D6A6F75726E616C64002D2D646174612D726F6F74002F6D6E742F646174612F646F636B6572
[   86.409346] audit: type=1325 audit(1606782018.127:192): table=nat family=2 entries=45
[   86.426319] audit: type=1300 audit(1606782018.127:192): arch=c00000b7 syscall=208 success=yes exit=0 a0=4 a1=0 a2=40 a3=28554380 items=0 ppid=381 pid=5166 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)
[   86.461208] audit: type=1327 audit(1606782018.127:192): proctitle=2F7573722F7362696E2F69707461626C6573002D2D77616974002D74006E6174002D4100444F434B4552002D7000746370002D6400302F30002D2D64706F72740038343835002D6A00444E4154002D2D746F2D64657374696E6174696F6E003137322E33302E33332E333A383438350000002D690068617373696F
[   86.495117] audit: type=1325 audit(1606782018.171:193): table=filter family=2 entries=45
[   86.510228] audit: type=1300 audit(1606782018.171:193): arch=c00000b7 syscall=208 success=yes exit=0 a0=4 a1=0 a2=40 a3=2ae8420 items=0 ppid=381 pid=5168 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)
[   86.540465] audit: type=1327 audit(1606782018.171:193): proctitle=2F7573722F7362696E2F69707461626C6573002D2D77616974002D740066696C746572002D4100444F434B45520000002D690068617373696F002D6F0068617373696F002D7000746370002D64003137322E33302E33332E33002D2D64706F72740038343835002D6A00414343455054
[   86.571600] audit: type=1325 audit(1606782018.191:194): table=nat family=2 entries=46
[   87.081499] eth0: renamed from veth1e7a283
[   87.129417] IPv6: ADDRCONF(NETDEV_CHANGE): veth986d0ec: link becomes ready
[   87.148614] hassio: port 9(veth986d0ec) entered blocking state
[   87.163488] hassio: port 9(veth986d0ec) entered forwarding state

Description of problem:

New setup, I attached a 5TB external drive to use for the Plex add-on. I ran datactl move /dev/sda, rebooted and let it run for a little over an hour,. After logging in the system does not appear to be using the external drive for /mnt/data.

# lsblk
NAME        MAJ:MIN RM   SIZE RO TYPE MOUNTPOINT
sda           8:0    1    30G  0 disk
sdb           8:16   0   4.6T  0 disk
`-sdb1        8:17   0   4.6T  0 part
mmcblk0     179:0    0  59.5G  0 disk
|-mmcblk0p1 179:1    0    32M  0 part /mnt/boot
|-mmcblk0p2 179:2    0    24M  0 part
|-mmcblk0p3 179:3    0   256M  0 part /
|-mmcblk0p4 179:4    0    24M  0 part
|-mmcblk0p5 179:5    0   256M  0 part
|-mmcblk0p6 179:6    0     8M  0 part
|-mmcblk0p7 179:7    0    96M  0 part /mnt/overlay
`-mmcblk0p8 179:8    0  58.8G  0 part /mnt/data
zram0       254:0    0 951.4M  0 disk [SWAP]
zram1       254:1    0    32M  0 disk /var
zram2       254:2    0    16M  0 disk /tmp

# df -h
Filesystem                Size      Used Available Use% Mounted on
/dev/root               102.6M    102.6M         0 100% /
devtmpfs                  1.8G         0      1.8G   0% /dev
tmpfs                     1.9G         0      1.9G   0% /dev/shm
tmpfs                     1.9G      1.0M      1.9G   0% /run
tmpfs                     1.9G         0      1.9G   0% /sys/fs/cgroup
tmpfs                     1.9G      1.0M      1.9G   0% /etc/machine-id
/dev/mmcblk0p7           89.0M      1.7M     80.5M   2% /mnt/overlay
/dev/mmcblk0p7           89.0M      1.7M     80.5M   2% /etc/docker
/dev/mmcblk0p7           89.0M      1.7M     80.5M   2% /etc/dropbear
/dev/mmcblk0p7           89.0M      1.7M     80.5M   2% /etc/modprobe.d
/dev/mmcblk0p7           89.0M      1.7M     80.5M   2% /etc/modules-load.d
/dev/mmcblk0p7           89.0M      1.7M     80.5M   2% /etc/udev/rules.d
/dev/mmcblk0p1           31.9M      3.1M     28.8M  10% /mnt/boot
/dev/mmcblk0p7           89.0M      1.7M     80.5M   2% /root/.docker
/dev/mmcblk0p7           89.0M      1.7M     80.5M   2% /root/.ssh
/dev/mmcblk0p7           89.0M      1.7M     80.5M   2% /etc/NetworkManager/system-connections
/dev/mmcblk0p7           89.0M      1.7M     80.5M   2% /etc/hostname
/dev/mmcblk0p7           89.0M      1.7M     80.5M   2% /etc/hosts
/dev/mmcblk0p7           89.0M      1.7M     80.5M   2% /etc/systemd/timesyncd.conf
/dev/mmcblk0p8           57.8G      3.3G     51.6G   6% /mnt/data
/dev/zram2               15.5M     44.0K     14.3M   0% /tmp
/dev/zram1               31.0M    128.0K     28.6M   0% /var
/dev/mmcblk0p7           89.0M      1.7M     80.5M   2% /var/lib/NetworkManager
/dev/mmcblk0p7           89.0M      1.7M     80.5M   2% /var/lib/bluetooth
/dev/mmcblk0p8           57.8G      3.3G     51.6G   6% /var/lib/docker
/dev/mmcblk0p7           89.0M      1.7M     80.5M   2% /var/lib/systemd
/dev/mmcblk0p8           57.8G      3.3G     51.6G   6% /var/log/journal

I attached the drive to another machine and it's "empty"

$ ls -la
total 24
drwxr-xr-x  3 root root  4096 Nov 30 14:09 .
drwxr-x---+ 3 root root  4096 Nov 30 16:55 ..
drwx------  2 root root 16384 Nov 30 14:09 lost+found
@agners
Copy link
Member

agners commented Dec 1, 2020

Hm, it seems the system opted to use the SD card non-the-less.

Do you have that system still booted after that first reboot after using the datactl command? If so, can you use the OS console and run journalctl -u hassos-data.service, it should get a bit more logs about the moving process.

@agners agners added the os label Dec 1, 2020
@UnderpantsGnome
Copy link
Author

Last night I decided to delete the partition and take another try, it was still doing the setting up the data partition after 14 hours, then... power outage, so I'll have to take another crack at it. 😞

@agners
Copy link
Member

agners commented Dec 1, 2020

So the process was stuck when running datactl or the reboot after?

It could be a disk size problem, I never tried with such a large disk.

@UnderpantsGnome
Copy link
Author

On the reboot after, this is where it's been sitting for the last 30 minutes or so.
IMG_7148

@UnderpantsGnome
Copy link
Author

@agners here is the additional logging

# journalctl -u hassos-data.service
-- Logs begin at Sun 2020-09-20 10:43:58 UTC, end at Tue 2020-12-01 18:41:07 UTC. --
Sep 20 10:43:59 homeassistant systemd[1]: Condition check resulted in HassOS data partition being skipped.
-- Reboot --
Sep 20 10:43:59 homeassistant systemd[1]: Condition check resulted in HassOS data partition being skipped.
-- Reboot --
Sep 20 10:43:59 homeassistant systemd[1]: Starting HassOS data partition...
-- Reboot --
Sep 20 10:43:59 homeassistant systemd[1]: Condition check resulted in HassOS data partition being skipped.
-- Reboot --
Sep 20 10:43:59 homeassistant systemd[1]: Condition check resulted in HassOS data partition being skipped.
-- Reboot --
Sep 20 10:44:00 homeassistant systemd[1]: Condition check resulted in HassOS data partition being skipped.
-- Reboot --
Sep 20 10:44:00 homeassistant systemd[1]: Condition check resulted in HassOS data partition being skipped.
-- Reboot --
Sep 20 10:44:04 homeassistant hassos-data[196]: [INFO] Cleanup device /dev/sda!
Sep 20 10:44:05 homeassistant hassos-data[271]: ***************************************************************
Sep 20 10:44:05 homeassistant hassos-data[271]: Found invalid GPT and valid MBR; converting MBR to GPT format
Sep 20 10:44:05 homeassistant hassos-data[271]: in memory.
Sep 20 10:44:05 homeassistant hassos-data[271]: ***************************************************************
Sep 20 10:44:05 homeassistant hassos-data[271]: Warning! Secondary partition table overlaps the last partition by
Sep 20 10:44:05 homeassistant hassos-data[271]: 33 blocks!
Sep 20 10:44:05 homeassistant hassos-data[271]: You will need to delete this partition or resize it in another utility.
Sep 20 10:44:05 homeassistant hassos-data[271]: GPT data structures destroyed! You may now partition the disk using fdisk or
Sep 20 10:44:05 homeassistant hassos-data[271]: other utilities.
Sep 20 10:44:05 homeassistant hassos-data[196]: [INFO] Create new hassos-data partition
Sep 20 10:44:07 homeassistant hassos-data[273]: Creating new GPT entries in memory.
Sep 20 10:44:07 homeassistant hassos-data[273]: The operation has completed successfully.
Sep 20 10:44:08 homeassistant hassos-data[274]: Setting name!
Sep 20 10:44:08 homeassistant hassos-data[274]: partNum is 0
Sep 20 10:44:08 homeassistant hassos-data[274]: The operation has completed successfully.
Sep 20 10:44:08 homeassistant hassos-data[275]: No problems found. 2014 free sectors (1007.0 KiB) available in 1
Sep 20 10:44:08 homeassistant hassos-data[275]: segments, the largest of which is 2014 (1007.0 KiB) in size.
Sep 20 10:44:08 homeassistant hassos-data[196]: [INFO] Move hassos-data from /dev/mmcblk0p8 to /dev/sda1
Sep 20 11:31:27 homeassistant hassos-data[282]: dd: error writing '/dev/sda1': No space left on device
Sep 20 11:33:07 homeassistant hassos-data[196]: [ERROR] Data copy fails!
Sep 20 11:33:09 homeassistant hassos-data[322]: The operation has completed successfully.
Sep 20 11:33:09 homeassistant hassos-data[323]: partx: specified range <1:0> does not make sense
Sep 20 11:33:09 homeassistant systemd[1]: hassos-data.service: Main process exited, code=exited, status=1/FAILURE
Sep 20 11:33:09 homeassistant systemd[1]: hassos-data.service: Failed with result 'exit-code'.
Sep 20 11:33:09 homeassistant systemd[1]: Failed to start HassOS data partition.

@UnderpantsGnome
Copy link
Author

Based on the errors in that log output, I wiped the drive and set it up for GPT, then reran datactl move /dev/sda rebooted, it's been on the setting up data partition for a couple hours now. Is there a way I can modify the SD card and/or the external drive to make HA think it's already done?

@agners
Copy link
Member

agners commented Dec 1, 2020

Hm, the screenshot with the stack traces don't look good. It seems as if the bus the harddrive is connected to (USB?) or the harddrive itself had some issues writing data which made the "write" commands time out. Is that driver powered externally? Are you sure the harddrive is fine?

The log then sounds like Home Assistant OS tried to create a very small partition which then filled up immediately. I think ideally you should not have any partition table or partition since this is done by the hassos-data.service started on first boot.

I am planning to work on the feature tomorrow to improve its usability. Mainly I like to move more logic into datactl to catch problems before moving data.

@UnderpantsGnome
Copy link
Author

The drive is powered externally. I ran Gparted on it and it says it fixed a bunch of issues. I reattached it to the pi but HA still isn't using it. Tried datactl again but got

# datactl move /dev/sda
[ERROR] Can't be the same disk!

🤷‍♂️

@agners
Copy link
Member

agners commented Dec 2, 2020

Yeah I think that happens when there is still some parts of the partition table there. Can you try:

sgdisk --zap-all /dev/sda

before using datactl?

@UnderpantsGnome
Copy link
Author

Here is what is currently on the external drive.

# cd supervisor/
# ls -l
-rw-------    1 root     root         19321 Dec  1 19:16 addons.json
drwxr-xr-x    2 root     root          4096 Sep 20 10:44 apparmor
-rw-------    1 root     root            70 Dec  1 19:07 audio.json
-rw-------    1 root     root           209 Dec  1 19:15 cli.json
-rw-------    1 root     root           345 Dec  1 19:17 config.json
-rw-------    1 root     root           401 Nov 30 22:46 discovery.json
-rw-------    1 root     root            92 Dec  1 19:07 dns.json
-rw-------    1 root     root            22 Nov 30 22:45 docker.json
-rw-------    1 root     root           550 Dec  1 19:16 homeassistant.json
-rw-------    1 root     root            34 Dec  1 19:17 ingress.json
-rw-------    1 root     root            73 Dec  1 19:15 multicast.json
-rw-------    1 root     root           214 Dec  1 19:15 observer.json
-rw-------    1 root     root           265 Dec  1 19:15 services.json
-rw-------    1 root     root           607 Dec  1 19:16 updater.json

Should I still sgdisk --zap-all /dev/sda it or is there another path?

@agners
Copy link
Member

agners commented Dec 2, 2020

There should be single partition with all data on it. Is the old data partition still there?

@UnderpantsGnome
Copy link
Author

UnderpantsGnome commented Dec 2, 2020 •
edited

here is the fdisk info.

# fdisk /dev/sda

Welcome to fdisk (util-linux 2.35.1).
Changes will remain in memory only, until you decide to write them.
Be careful before using the write command.


Command (m for help): p
Disk /dev/sda: 4.56 TiB, 5000981077504 bytes, 9767541167 sectors
Disk model: Expansion Desk
Units: sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 4096 bytes
I/O size (minimum/optimal): 4096 bytes / 4096 bytes
Disklabel type: gpt
Disk identifier: 48B0E587-59ED-4D30-BA58-4B1AA8B7EEF1

Device     Start        End    Sectors  Size Type
/dev/sda1   2048 9767541133 9767539086  4.6T Linux filesystem

Command (m for help): i
Selected partition 1
         Device: /dev/sda1
          Start: 2048
            End: 9767541133
        Sectors: 9767539086
           Size: 4.6T
           Type: Linux filesystem
      Type-UUID: 0FC63DAF-8483-4772-8E79-3D69D8477DE4
           UUID: A52A4597-FA3A-4851-AEFD-2FBE9F849079
           Name: hassos-data

# fdisk /dev/mmcblk0

Welcome to fdisk (util-linux 2.35.1).
Changes will remain in memory only, until you decide to write them.
Be careful before using the write command.

A hybrid GPT was detected. You have to sync the hybrid MBR manually (expert command 'M').

Command (m for help): p

Disk /dev/mmcblk0: 59.49 GiB, 63864569856 bytes, 124735488 sectors
Units: sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disklabel type: gpt
Disk identifier: A787ED70-3F4C-4BE6-8696-BB7F17D9A48F

Device           Start       End   Sectors  Size Type
/dev/mmcblk0p1    2048     67583     65536   32M Microsoft reserved
/dev/mmcblk0p2   67584    116735     49152   24M Linux filesystem
/dev/mmcblk0p3  116736    641023    524288  256M Linux filesystem
/dev/mmcblk0p4  641024    690175     49152   24M Linux filesystem
/dev/mmcblk0p5  690176   1214463    524288  256M Linux filesystem
/dev/mmcblk0p6 1214464   1230847     16384    8M Linux filesystem
/dev/mmcblk0p7 1230848   1427455    196608   96M Linux filesystem
/dev/mmcblk0p8 1427456 124735454 123307999 58.8G Linux filesystem

Command (m for help): i
Partition number (1-8, default 8):

         Device: /dev/mmcblk0p8
          Start: 1427456
            End: 124735454
        Sectors: 123307999
           Size: 58.8G
           Type: Linux filesystem
      Type-UUID: 0FC63DAF-8483-4772-8E79-3D69D8477DE4
           UUID: A52A4597-FA3A-4851-AEFD-2FBE9F849079
           Name: hassos-data

@agners
Copy link
Member

agners commented Dec 2, 2020

Yeah so /dev/mmcblk0p8 is still there. It should be safe to remove the partition table and partition on the external drive using sgdisk --zap-all /dev/sda then.

While in theory the current script should work, it will take quite a while since your source partition is quite large. I am working on a rewrite of datactl which should be faster, see #1046. It will be part of the next pre-release, so if you can wait couple of days it might be more efficient.

@UnderpantsGnome
Copy link
Author

Thanks @agners I'll hold off, I can live without the Plex stuff for a while 😉

@UnderpantsGnome
Copy link
Author

Hey @agners just checking in to see if any progress has been made on the datactl flow.

@UnderpantsGnome
Copy link
Author

I have a followup on this. I updated the OS to 5.9, reran the datactl move. After the reboot I can see that is it using the external drive, the new partition was created to fill the drive, but the file system is only the size of the SD card.

# datactl move /dev/sda
WARNING: All partitions on /dev/sda will be deleted!
Enter "yes" to confirm: yes
Checking that no-one is using this disk right now ... OK

Disk /dev/sda: 4.55 TiB, 5000981077504 bytes, 9767541167 sectors
Disk model: Expansion Desk
Units: sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 4096 bytes
I/O size (minimum/optimal): 4096 bytes / 4096 bytes
Disklabel type: gpt
Disk identifier: 48B0E587-59ED-4D30-BA58-4B1AA8B7EEF1

Old situation:

>>> Script header accepted.
>>> Created a new GPT disklabel (GUID: BB9860C9-8D12-FD4E-9DCC-14B4483930DB).
/dev/sda1: Created a new partition 1 of type 'Linux filesystem' and of size 4.5 TiB.
Partition #1 contains a ext4 signature.
The signature will be removed by a write command.
/dev/sda2: Done.

New situation:
Disklabel type: gpt
Disk identifier: BB9860C9-8D12-FD4E-9DCC-14B4483930DB

Device     Start        End    Sectors  Size Type
/dev/sda1   2048 9767541133 9767539086  4.5T Linux filesystem

Filesystem/RAID signature on partition 1 will be wiped.

The partition table has been altered.
Calling ioctl() to re-read partition table.
Syncing disks.

Disk /dev/sda has been prepared to be used as data drive and the data
move has been scheduled for the next reboot. Please reboot the device now and
make sure to leave the disk connected to the system from now on.

# fdisk /dev/sda

Command (m for help): p
Disk /dev/sda: 4.55 TiB, 5000981077504 bytes, 9767541167 sectors
Disk model: Expansion Desk
Units: sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 4096 bytes
I/O size (minimum/optimal): 4096 bytes / 4096 bytes
Disklabel type: gpt
Disk identifier: BB9860C9-8D12-FD4E-9DCC-14B4483930DB

Device     Start        End    Sectors  Size Type
/dev/sda1   2048 9767541133 9767539086  4.5T Linux filesystem

# lsblk
NAME        MAJ:MIN RM   SIZE RO TYPE MOUNTPOINT
sda           8:0    0   4.5T  0 disk
`-sda1        8:1    0   4.5T  0 part /mnt/data
mmcblk0     179:0    0  59.5G  0 disk
|-mmcblk0p1 179:1    0    32M  0 part /mnt/boot
|-mmcblk0p2 179:2    0    24M  0 part
|-mmcblk0p3 179:3    0   256M  0 part /
|-mmcblk0p4 179:4    0    24M  0 part
|-mmcblk0p5 179:5    0   256M  0 part
|-mmcblk0p6 179:6    0     8M  0 part
|-mmcblk0p7 179:7    0    96M  0 part /mnt/overlay
`-mmcblk0p8 179:8    0  58.8G  0 part
zram0       254:0    0 949.1M  0 disk [SWAP]
zram1       254:1    0    32M  0 disk /var
zram2       254:2    0    16M  0 disk /tmp

# df -h
Filesystem                Size      Used Available Use% Mounted on
/dev/root               100.5M    100.5M         0 100% /
devtmpfs                  1.8G         0      1.8G   0% /dev
tmpfs                     1.9G         0      1.9G   0% /dev/shm
tmpfs                   759.3M      1.2M    758.1M   0% /run
tmpfs                     4.0M         0      4.0M   0% /sys/fs/cgroup
tmpfs                   759.3M      1.2M    758.1M   0% /etc/machine-id
/dev/mmcblk0p1           31.9M      3.4M     28.6M  11% /mnt/boot
/dev/mmcblk0p7           89.0M      1.7M     80.5M   2% /mnt/overlay
/dev/mmcblk0p7           89.0M      1.7M     80.5M   2% /etc/docker
/dev/mmcblk0p7           89.0M      1.7M     80.5M   2% /etc/dropbear
/dev/mmcblk0p7           89.0M      1.7M     80.5M   2% /etc/modprobe.d
/dev/mmcblk0p7           89.0M      1.7M     80.5M   2% /etc/modules-load.d
/dev/mmcblk0p7           89.0M      1.7M     80.5M   2% /etc/udev/rules.d
/dev/mmcblk0p7           89.0M      1.7M     80.5M   2% /root/.docker
/dev/mmcblk0p7           89.0M      1.7M     80.5M   2% /root/.ssh
/dev/mmcblk0p7           89.0M      1.7M     80.5M   2% /etc/NetworkManager/system-connections
/dev/mmcblk0p7           89.0M      1.7M     80.5M   2% /etc/hostname
/dev/mmcblk0p7           89.0M      1.7M     80.5M   2% /etc/hosts
/dev/mmcblk0p7           89.0M      1.7M     80.5M   2% /etc/systemd/timesyncd.conf
/dev/zram2               15.5M     56.0K     14.3M   0% /tmp
/dev/zram1               31.0M    132.0K     28.6M   0% /var
/dev/mmcblk0p7           89.0M      1.7M     80.5M   2% /var/lib/NetworkManager
/dev/mmcblk0p7           89.0M      1.7M     80.5M   2% /var/lib/bluetooth
/dev/mmcblk0p7           89.0M      1.7M     80.5M   2% /var/lib/systemd
/dev/sda1                57.8G      7.0G     47.9G  13% /mnt/data
/dev/sda1                57.8G      7.0G     47.9G  13% /var/lib/docker
/dev/sda1                57.8G      7.0G     47.9G  13% /var/log/journal

@agners
Copy link
Member

agners commented Dec 30, 2020 •
edited

Sorry for the delay, and thanks for the detailed reports! The file system should grow with the next release (fixed with #1133). You can grow the file system manually using /lib/systemd/systemd-growfs /mnt/data.

@agners agners closed this as completed Dec 30, 2020
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Assignees
No one assigned
Labels
os
Projects
None yet
Milestone
No milestone
Development

No branches or pull requests
2 participants
@UnderpantsGnome @agners