search

canonical / multipass

Multipass orchestrates virtual Ubuntu instances
https://multipass.run
GNU General Public License v3.0
7.92k stars 654 forks source link

instance doesn't start - timed out after I enabled ufw firewall and opened a port #2721

Open moorthi07 opened 2 years ago

moorthi07 commented 2 years ago

instance timed out starting after I enabled ufw firewall and opened a port

Error:

start failed: The following errors occurred:
docker: timed out waiting for response

Saving session... ...copying shared history... ...saving history...truncating history files... ...completed.

[Process completed]

ricab commented 2 years ago

Hi @moorthi07, ufw could be blocking DHCP to the instance, but we'd need more info to try to help out. What driver are you using? What do the logs say? Do you have a simple ufw config to reproduce?

moorthi07 commented 2 years ago

Sure. Instance Name: Docker And here is the log for that instance. And re. ufw, I'm not even able to reach the prompt, totally not loading the os.

docker-dev-hyperkit.log multipassd.log docker-hyperkit.log primary-hyperkit.log imAdmin@i-m Multipass % tail multipassd.log [2022-08-25T09:19:27.707] [info] [docker] hyperkit: [INFO] block recycler: flush: 252070 used; 976 junk; 0 erased; 2048 available; 0 copies; 0 roots; 0 Copying; 0 Copied; 0 Flushed; 0 Referenced; max_cluster = 252346 [2022-08-25T09:19:27.707] [info] [docker] hyperkit: [INFO] block recycler: resize to 252347 clusters [2022-08-25T09:19:32.712] [info] [docker] hyperkit: [INFO] block recycler: triggering background flush: 252070 used; 0 junk; 0 erased; 268 available; 0 copies; 0 roots; 0 Copying; 0 Copied; 0 Flushed; 0 Referenced; max_cluster = 252346 [2022-08-25T09:19:34.654] [info] [docker] hyperkit: [INFO] Allocator: 252070 used; 0 junk; 0 erased; 268 available; 0 copies; 0 roots; 0 Copying; 0 Copied; 0 Flushed; 0 Referenced; max_cluster = 252346 [2022-08-25T09:19:34.654] [info] [docker] hyperkit: [INFO] Allocator: file contains cluster 0 .. 252346 will enlarge file to 0 .. 252858 [2022-08-25T09:19:34.654] [info] [docker] hyperkit: [INFO] resize: adding available clusters (Node ((x 252347) (y 252858) (l Empty) (r Empty) (h 1) (cardinal 512))) [2022-08-25T09:19:37.784] [info] [docker] hyperkit: [INFO] block recycler: triggering background flush: 252071 used; 0 junk; 0 erased; 779 available; 0 copies; 0 roots; 0 Copying; 0 Copied; 0 Flushed; 0 Referenced; max_cluster = 252346 [2022-08-25T09:19:50.983] [info] [docker] hyperkit: [INFO] block recycler: triggering background flush: 252071 used; 0 junk; 1 erased; 778 available; 0 copies; 0 roots; 0 Copying; 0 Copied; 0 Flushed; 0 Referenced; max_cluster = 252346 [2022-08-25T09:19:50.986] [info] [docker] hyperkit: [INFO] block recycler: 0 cluster copies flushed; 0 cluster copies complete; 1 clusters erased [2022-08-25T09:19:50.989] [info] [docker] hyperkit: [INFO] block recycler: flush: 252071 used; 0 junk; 0 erased; 779 available; 0 copies; 0 roots; 0 Copying; 0 Copied; 0 Flushed; 0 Referenced; max_cluster = 252346 imAdmin@i-m Multipass % cat docker-hyperkit.log [ 0.000000] Linux version 5.15.0-41-generic (buildd@lcy02-amd64-065) (gcc (Ubuntu 11.2.0-19ubuntu1) 11.2.0, GNU ld (GNU Binutils for Ubuntu) 2.38) #44-Ubuntu SMP Wed Jun 22 14:20:53 UTC 2022 (Ubuntu 5.15.0-41.44-generic 5.15.39) [ 0.000000] Command line: earlyprintk=serial console=ttyS0 root=/dev/vda1 rw panic=1 no_timer_check [ 0.000000] KERNEL supported cpus: [ 0.000000] Intel GenuineIntel [ 0.000000] AMD AuthenticAMD [ 0.000000] Hygon HygonGenuine [ 0.000000] Centaur CentaurHauls [ 0.000000] zhaoxin Shanghai
[ 0.000000] x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' [ 0.000000] x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' [ 0.000000] x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers'

[ 0.000000] x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'standard' format. [ 0.000000] signal: max sigframe size: 1776 [ 0.000000] BIOS-provided physical RAM map: [ 0.000000] BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable [ 0.000000] BIOS-e820: [mem 0x0000000000100000-0x00000000bfffffff] usable [ 0.000000] BIOS-e820: [mem 0x0000000100000000-0x000000013fffffff] usable [ 0.000000] printk: bootconsole [earlyser0] enabled [ 0.000000] ERROR: earlyprintk= earlyser already used [ 0.000000] NX (Execute Disable) protection: active [ 0.000000] SMBIOS 2.6 present. [ 0.000000] DMI: BHYVE, BIOS 1.00 03/14/2014 [ 0.000000] last_pfn = 0x140000 max_arch_pfn = 0x400000000 [ 0.000000] Disabled [ 0.000000] x86/PAT: MTRRs disabled, skipping PAT initialization too. [ 0.000000] CPU MTRRs all blank - virtualized system. [ 0.000000] x86/PAT: Configuration [0-7]: WB WT UC- UC WB WT UC- UC
Memory KASLR using RDRAND RDTSC... [ 0.000000] last_pfn = 0xc0000 max_arch_pfn = 0x400000000 [ 0.000000] found SMP MP-table at [mem 0x000f0000-0x000f000f] [ 0.000000] Using GB pages for direct mapping [ 0.000000] RAMDISK: [mem 0x7e258000-0x7fffffff] [ 0.000000] ACPI: Early table checksum verification disabled [ 0.000000] ACPI: RSDP 0x00000000000F2400 000024 (v02 BHYVE ) [ 0.000000] ACPI: XSDT 0x00000000000F2480 000044 (v01 BHYVE BVXSDT 00000001 INTL 20140828) [ 0.000000] ACPI: APIC 0x00000000000F2500 000062 (v01 BHYVE BVMADT 00000001 INTL 20140828) [ 0.000000] ACPI: FACP 0x00000000000F2A00 00010C (v05 BHYVE BVFACP 00000001 INTL 20140828) [ 0.000000] ACPI: DSDT 0x00000000000F2C00 000A2D (v02 BHYVE BVDSDT 00000001 INTL 20140828) [ 0.000000] ACPI: FACS 0x00000000000F2BC0 000040 [ 0.000000] ACPI: FACS 0x00000000000F2BC0 000040 [ 0.000000] ACPI: HPET 0x00000000000F2B40 000038 (v01 BHYVE BVHPET 00000001 INTL 20140828) [ 0.000000] ACPI: MCFG 0x00000000000F2B80 00003C (v01 BHYVE BVMCFG 00000001 INTL 20140828) [ 0.000000] ACPI: Reserving APIC table memory at [mem 0xf2500-0xf2561] [ 0.000000] ACPI: Reserving FACP table memory at [mem 0xf2a00-0xf2b0b] [ 0.000000] ACPI: Reserving DSDT table memory at [mem 0xf2c00-0xf362c] [ 0.000000] ACPI: Reserving FACS table memory at [mem 0xf2bc0-0xf2bff] [ 0.000000] ACPI: Reserving FACS table memory at [mem 0xf2bc0-0xf2bff] [ 0.000000] ACPI: Reserving HPET table memory at [mem 0xf2b40-0xf2b77] [ 0.000000] ACPI: Reserving MCFG table memory at [mem 0xf2b80-0xf2bbb] [ 0.000000] Setting APIC routing to physical flat. [ 0.000000] No NUMA configuration found [ 0.000000] Faking a node at [mem 0x0000000000000000-0x000000013fffffff] [ 0.000000] NODE_DATA(0) allocated [mem 0x13ffd6000-0x13fffffff] [ 0.000000] Zone ranges: [ 0.000000] DMA [mem 0x0000000000001000-0x0000000000ffffff] [ 0.000000] DMA32 [mem 0x0000000001000000-0x00000000ffffffff] [ 0.000000] Normal [mem 0x0000000100000000-0x000000013fffffff] [ 0.000000] Device empty [ 0.000000] Movable zone start for each node [ 0.000000] Early memory node ranges [ 0.000000] node 0: [mem 0x0000000000001000-0x000000000009efff] [ 0.000000] node 0: [mem 0x0000000000100000-0x00000000bfffffff] [ 0.000000] node 0: [mem 0x0000000100000000-0x000000013fffffff] [ 0.000000] Initmem setup node 0 [mem 0x0000000000001000-0x000000013fffffff] [ 0.000000] On node 0, zone DMA: 1 pages in unavailable ranges [ 0.000000] On node 0, zone DMA: 97 pages in unavailable ranges [ 0.000000] ACPI: PM-Timer IO Port: 0x408 [ 0.000000] ACPI: LAPIC_NMI (acpi_id[0xff] high edge lint[0x1]) [ 0.000000] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 high edge) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 low level) [ 0.000000] ACPI: Using ACPI (MADT) for SMP configuration information [ 0.000000] ACPI: HPET id: 0x80860701 base: 0xfed00000 [ 0.000000] smpboot: Allowing 2 CPUs, 0 hotplug CPUs [ 0.000000] PM: hibernation: Registered nosave memory: [mem 0x00000000-0x00000fff] [ 0.000000] PM: hibernation: Registered nosave memory: [mem 0x0009f000-0x000fffff] [ 0.000000] PM: hibernation: Registered nosave memory: [mem 0xc0000000-0xffffffff] [ 0.000000] [mem 0xc0000000-0xffffffff] available for PCI devices [ 0.000000] Booting paravirtualized kernel on bare hardware [ 0.000000] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645519600211568 ns [ 0.000000] setup_percpu: NR_CPUS:8192 nr_cpumask_bits:2 nr_cpu_ids:2 nr_node_ids:1 [ 0.000000] percpu: Embedded 60 pages/cpu s208896 r8192 d28672 u1048576 [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 1031936 [ 0.000000] Policy zone: Normal [ 0.000000] Kernel command line: earlyprintk=serial console=ttyS0 root=/dev/vda1 rw panic=1 no_timer_check [ 0.000000] Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) [ 0.000000] Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) [ 0.000000] mem auto-init: stack:off, heap alloc:on, heap free:off [ 0.000000] Memory: 3980892K/4193912K available (16393K kernel code, 4381K rwdata, 10796K rodata, 2900K init, 4852K bss, 212760K reserved, 0K cma-reserved) [ 0.000000] random: get_random_u64 called from kmem_cache_open+0x2b/0x320 with crng_init=0 [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1 [ 0.000000] Kernel/User page tables isolation: enabled [ 0.000000] ftrace: allocating 50452 entries in 198 pages [ 0.000000] ftrace: allocated 198 pages with 4 groups [ 0.000000] rcu: Hierarchical RCU implementation. [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=8192 to nr_cpu_ids=2. [ 0.000000] Rude variant of Tasks RCU enabled. [ 0.000000] Tracing variant of Tasks RCU enabled. [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies. [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=2 [ 0.000000] NR_IRQS: 524544, nr_irqs: 440, preallocated irqs: 16 [ 0.000000] random: crng done (trusting CPU's manufacturer) [ 0.000000] Console: colour dummy device 80x25 [ 0.000000] printk: console [ttyS0] enabled [ 0.000000] printk: console [ttyS0] enabled [ 0.000000] printk: bootconsole [earlyser0] disabled [ 0.000000] printk: bootconsole [earlyser0] disabled [ 0.000000] ACPI: Core revision 20210730 [ 0.000000] ACPI BIOS Warning (bug): Incorrect checksum in table [DSDT] - 0x3F, should be 0x9C (20210730/tbprint-173) [ 0.000000] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 191126044627 ns [ 0.000000] APIC: Switch to symmetric I/O mode setup [ 0.000000] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 [ 0.004000] tsc: PIT calibration matches PMTIMER. 2 loops [ 0.004000] tsc: Detected 2193.223 MHz processor [ 0.000008] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x1f9d31123b4, max_idle_ns: 440795296619 ns [ 0.001802] Calibrating delay loop (skipped), value calculated using timer frequency.. 4386.44 BogoMIPS (lpj=8772892) [ 0.003582] pid_max: default: 32768 minimum: 301 [ 0.004395] LSM: Security Framework initializing [ 0.005375] landlock: Up and running. [ 0.005800] Yama: becoming mindful. [ 0.005800] AppArmor: AppArmor initialized [ 0.006053] Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) [ 0.007461] Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Poking KASLR using RDRAND RDTSC... [ 0.010909] Last level iTLB entries: 4KB 1024, 2MB 1024, 4MB 1024 [ 0.011944] Last level dTLB entries: 4KB 1024, 2MB 1024, 4MB 1024, 1GB 4 [ 0.013806] Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization [ 0.015400] Spectre V2 : Mitigation: Retpolines [ 0.016195] Spectre V2 : Spectre v2 / SpectreRSB mitigation: Filling RSB on context switch [ 0.017532] Speculative Store Bypass: Vulnerable [ 0.017800] MDS: Vulnerable: Clear CPU buffers attempted, no microcode [ 0.017800] SRBDS: Unknown: Dependent on hypervisor status [ 0.021339] Freeing SMP alternatives memory: 40K [ 0.085943] smpboot: CPU0: Intel(R) Core(TM) i7-4770HQ CPU @ 2.20GHz (family: 0x6, model: 0x46, stepping: 0x1) [ 0.088695] Performance Events: unsupported p6 CPU model 70 no PMU driver, software events only. [ 0.089800] rcu: Hierarchical SRCU implementation. [ 0.089800] NMI watchdog: Perf NMI watchdog permanently disabled [ 0.089800] smp: Bringing up secondary CPUs ... [ 0.089992] x86: Booting SMP configuration: [ 0.090856] .... node #0, CPUs: #1 [ 0.001800] smpboot: CPU 1 Converting physical 0 to logical die 1 [ 0.001800] calibrate_delay_direct() dropping min bogoMips estimate 1 = 5904292 [ 0.001800] calibrate_delay_direct() dropping min bogoMips estimate 3 = 16822267 [ 0.001800] calibrate_delay_direct() dropping max bogoMips estimate 0 = 26104111 [ 0.182036] smp: Brought up 1 node, 2 CPUs [ 0.182803] smpboot: Max logical packages: 2 [ 0.183529] smpboot: Total of 2 processors activated (15635.51 BogoMIPS) [ 0.185156] devtmpfs: initialized [ 0.185156] x86/mm: Memory block size: 128MB [ 0.185156] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns [ 0.185156] futex hash table entries: 512 (order: 3, 32768 bytes, linear) [ 0.185156] pinctrl core: initialized pinctrl subsystem [ 0.185800] PM: RTC time: 16:17:58, date: 2022-08-25 [ 0.186188] NET: Registered PF_NETLINK/PF_ROUTE protocol family [ 0.188255] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations [ 0.189800] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.189800] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.189800] audit: initializing netlink subsys (disabled) [ 0.189800] audit: type=2000 audit(1661444277.188:1): state=initialized audit_enabled=0 res=1 [ 0.189800] thermal_sys: Registered thermal governor 'fair_share' [ 0.189800] thermal_sys: Registered thermal governor 'bang_bang' [ 0.189800] thermal_sys: Registered thermal governor 'step_wise' [ 0.189800] thermal_sys: Registered thermal governor 'user_space' [ 0.189800] thermal_sys: Registered thermal governor 'power_allocator' [ 0.189800] EISA bus registered [ 0.189800] cpuidle: using governor ladder [ 0.189800] cpuidle: using governor menu [ 0.189800] ACPI FADT declares the system doesn't support PCIe ASPM, so disable it [ 0.189800] ACPI: bus type PCI registered [ 0.189804] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.191155] PCI: MMCONFIG for domain 0000 [bus 00-ff] at [mem 0xe0000000-0xefffffff] (base 0xe0000000) [ 0.193015] PCI: not using MMCONFIG [ 0.193766] PCI: Using configuration type 1 for base access [ 0.193800] Kprobes globally optimized [ 0.193800] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.193800] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.202107] ACPI: Added _OSI(Module Device) [ 0.202723] ACPI: Added _OSI(Processor Device) [ 0.205814] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.206614] ACPI: Added _OSI(Processor Aggregator Device) [ 0.207518] ACPI: Added _OSI(Linux-Dell-Video) [ 0.208262] ACPI: Added _OSI(Linux-Lenovo-NV-HDMI-Audio) [ 0.209130] ACPI: Added _OSI(Linux-HPI-Hybrid-Graphics) [ 0.209800] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.210017] ACPI: Interpreter enabled [ 0.210714] ACPI: PM: (supports S0 S5) [ 0.211358] ACPI: Using IOAPIC for interrupt routing [ 0.212397] PCI: MMCONFIG for domain 0000 [bus 00-ff] at [mem 0xe0000000-0xefffffff] (base 0xe0000000) [ 0.213800] PCI: MMCONFIG at [mem 0xe0000000-0xefffffff] reserved in ACPI motherboard resources [ 0.213800] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.213800] ACPI: PCI Root Bridge [PC00] (domain 0000 [bus 00]) [ 0.213800] acpi PNP0A03:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] [ 0.213800] acpi PNP0A03:00: host bridge window [mem 0x00000000-0xd0000fffff window] ([0x8000000000-0xd0000fffff] ignored, not CPU addressable) [ 0.213800] acpi PNP0A03:00: host bridge window expanded to [mem 0x00000000-0x7fffffffff window]; [mem 0x00000000-0x7fffffffff window] ignored [ 0.213800] PCI host bridge to bus 0000:00 [ 0.213800] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.213800] pci_bus 0000:00: root bus resource [io 0x0d00-0x20ff window] [ 0.213800] pci_bus 0000:00: root bus resource [mem 0x00000000-0x7fffffffff window] [ 0.213800] pci 0000:00:00.0: [1275:1275] type 00 class 0x060000 [ 0.214187] pci 0000:00:00.0: reg 0x30: [mem 0x00000000-0x000007ff pref] [ 0.217262] pci 0000:00:01.0: [1af4:1001] type 00 class 0x010000 [ 0.217800] pci 0000:00:01.0: reg 0x10: [io 0x2000-0x207f] [ 0.217800] pci 0000:00:01.0: reg 0x14: [mem 0xc0000000-0xc0001fff] [ 0.217800] pci 0000:00:01.0: reg 0x30: [mem 0x00000000-0x000007ff pref] [ 0.217800] pci 0000:00:01.1: [8086:2821] type 00 class 0x010601 [ 0.217800] pci 0000:00:01.1: reg 0x24: [mem 0xc0002000-0xc00023ff] [ 0.217800] pci 0000:00:01.1: reg 0x30: [mem 0x00000000-0x000007ff pref] [ 0.217800] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 [ 0.217800] pci 0000:00:02.0: reg 0x10: [io 0x2080-0x20bf] [ 0.217800] pci 0000:00:02.0: reg 0x14: [mem 0xc0004000-0xc0005fff] [ 0.217800] pci 0000:00:02.0: reg 0x30: [mem 0x00000000-0x000007ff pref] [ 0.221800] pci 0000:00:05.0: [1af4:1005] type 00 class 0x100000 [ 0.221800] pci 0000:00:05.0: reg 0x10: [io 0x20c0-0x20df] [ 0.221800] pci 0000:00:05.0: reg 0x14: [mem 0xc0006000-0xc0007fff] [ 0.221800] pci 0000:00:05.0: reg 0x30: [mem 0x00000000-0x000007ff pref] [ 0.233800] pci 0000:00:1f.0: [8086:7000] type 00 class 0x060100 [ 0.233800] pci 0000:00:1f.0: reg 0x30: [mem 0x00000000-0x000007ff pref] [ 0.233800] ACPI: PCI: Interrupt link LNKA configured for IRQ 5 [ 0.233800] ACPI: PCI: Interrupt link LNKB configured for IRQ 6 [ 0.233800] ACPI: PCI: Interrupt link LNKC configured for IRQ 7 [ 0.233800] ACPI: PCI: Interrupt link LNKD configured for IRQ 10 [ 0.233800] ACPI: PCI: Interrupt link LNKE configured for IRQ 0 [ 0.233800] ACPI: PCI: Interrupt link LNKE disabled [ 0.233800] ACPI: PCI: Interrupt link LNKF configured for IRQ 0 [ 0.233800] ACPI: PCI: Interrupt link LNKF disabled [ 0.233800] ACPI: PCI: Interrupt link LNKG configured for IRQ 0 [ 0.233803] ACPI: PCI: Interrupt link LNKG disabled [ 0.234692] ACPI: PCI: Interrupt link LNKH configured for IRQ 0 [ 0.235631] ACPI: PCI: Interrupt link LNKH disabled [ 0.236707] iommu: Default domain type: Translated [ 0.236707] iommu: DMA domain TLB invalidation policy: lazy mode [ 0.236707] SCSI subsystem initialized [ 0.237916] vgaarb: loaded [ 0.238329] ACPI: bus type USB registered [ 0.238998] usbcore: registered new interface driver usbfs [ 0.239888] usbcore: registered new interface driver hub [ 0.240739] usbcore: registered new device driver usb [ 0.241579] pps_core: LinuxPPS API ver. 1 registered [ 0.241800] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti giometti@linux.it [ 0.241800] PTP clock support registered [ 0.241878] EDAC MC: Ver: 3.0.0 [ 0.242832] NetLabel: Initializing [ 0.242832] NetLabel: domain hash size = 128 [ 0.243408] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.244319] NetLabel: unlabeled traffic allowed by default [ 0.245263] PCI: Using ACPI for IRQ routing [ 0.408032] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0, 0, 0, 0, 0, 0 [ 0.409803] hpet0: 8 comparators, 32-bit 10.000000 MHz counter [ 0.413800] clocksource: Switched to clocksource tsc-early [ 0.447956] VFS: Disk quotas dquot_6.6.0 [ 0.448617] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.450046] AppArmor: AppArmor Filesystem Enabled [ 0.450819] pnp: PnP ACPI init [ 0.451037] system 00:00: [io 0x0220-0x0223] has been reserved [ 0.451037] system 00:00: [io 0x0224-0x0227] has been reserved [ 0.451037] system 00:00: [io 0x04d0-0x04d1] has been reserved [ 0.454584] system 00:00: [io 0x0400-0x0407] has been reserved [ 0.455524] system 00:00: [mem 0xe0000000-0xefffffff] has been reserved [ 0.456669] pnp: PnP ACPI: found 4 devices [ 0.471397] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 0.471397] NET: Registered PF_INET protocol family [ 0.471397] IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) [ 0.471397] tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) [ 0.471397] TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) [ 0.486573] TCP bind hash table entries: 32768 (order: 7, 524288 bytes, linear) [ 0.487794] TCP: Hash tables configured (established 32768 bind 32768) [ 0.489582] MPTCP token hash table entries: 4096 (order: 4, 98304 bytes, linear) [ 0.489582] UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) [ 0.489582] UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) [ 0.489582] NET: Registered PF_UNIX/PF_LOCAL protocol family [ 0.489582] NET: Registered PF_XDP protocol family [ 0.489582] pci 0000:00:01.0: BAR 6: assigned [mem 0xc0002800-0xc0002fff pref] [ 0.489582] pci 0000:00:01.1: BAR 6: assigned [mem 0xc0003000-0xc00037ff pref] [ 0.489582] pci 0000:00:02.0: BAR 6: assigned [mem 0xc0003800-0xc0003fff pref] [ 0.489582] pci 0000:00:05.0: BAR 6: assigned [mem 0xc0008000-0xc00087ff pref] [ 0.489582] pci 0000:00:1f.0: BAR 6: assigned [mem 0xc0008800-0xc0008fff pref] [ 0.489582] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 0.489582] pci_bus 0000:00: resource 5 [io 0x0d00-0x20ff window] [ 0.489582] pci_bus 0000:00: resource 6 [mem 0x00000000-0x7fffffffff window] [ 0.504974] pci 0000:00:1f.0: Activating ISA DMA hang workarounds [ 0.506068] PCI: CLS 0 bytes, default 64 [ 0.507083] Trying to unpack rootfs image as initramfs... [ 0.513330] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) [ 0.514439] software IO TLB: mapped [mem 0x00000000b5c00000-0x00000000b9c00000] (64MB) [ 0.530582] Initialise system trusted keyrings [ 0.531362] Key type blacklist registered [ 0.532310] workingset: timestamp_bits=36 max_order=20 bucket_order=0 [ 0.534486] zbud: loaded [ 0.535138] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ 0.536471] fuse: init (API version 7.34) [ 0.537695] integrity: Platform Keyring initialized [ 0.547612] Key type asymmetric registered [ 0.548298] Asymmetric key parser 'x509' registered [ 0.549144] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 243) [ 0.550509] io scheduler mq-deadline registered [ 0.551697] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 0.552886] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 0.554138] ACPI: button: Power Button [PWRF] [ 0.555248] virtio-pci 0000:00:01.0: virtio_pci: leaving for legacy driver [ 0.555464] virtio-pci 0000:00:02.0: virtio_pci: leaving for legacy driver [ 0.555464] virtio-pci 0000:00:05.0: virtio_pci: leaving for legacy driver [ 0.559438] Serial: 8250/16550 driver, 32 ports, IRQ sharing enabled [ 0.614251] 00:01: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 0.668962] 00:02: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 0.672499] Linux agpgart interface v0.103 [ 0.677818] loop: module loaded [ 0.677818] tun: Universal TUN/TAP device driver, 1.6 [ 0.677818] PPP generic driver version 2.4.2 [ 0.677818] VFIO - User Level meta-driver version: 0.3 [ 0.677818] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver [ 0.682775] ehci-pci: EHCI PCI platform driver [ 0.683523] ehci-platform: EHCI generic platform driver [ 0.684627] ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver [ 0.685770] ohci-pci: OHCI PCI platform driver [ 0.686551] ohci-platform: OHCI generic platform driver [ 0.686765] uhci_hcd: USB Universal Host Controller Interface driver [ 0.686765] i8042: PNP: No PS/2 controller found. [ 0.686765] mousedev: PS/2 mouse device common for all mice [ 0.686765] ACPI Error: Could not enable RealTimeClock event (20210730/evxfevnt-182) [ 0.686765] ACPI Warning: Could not enable fixed event - RealTimeClock (4) (20210730/evxface-618) [ 0.686765] rtc_cmos 00:03: registered as rtc0 [ 0.686765] rtc_cmos 00:03: setting system clock to 2022-08-25T16:17:58 UTC (1661444278) [ 0.686765] rtc_cmos 00:03: alarms up to one day, y3k, 114 bytes nvram [ 0.686765] i2c_dev: i2c /dev entries driver [ 0.686765] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. [ 0.686765] device-mapper: uevent: version 1.0.3 [ 0.686765] device-mapper: ioctl: 4.45.0-ioctl (2021-03-22) initialised: dm-devel@redhat.com [ 0.702938] platform eisa.0: Probing EISA bus 0 [ 0.703689] platform eisa.0: EISA: Cannot allocate resource for mainboard [ 0.704838] platform eisa.0: Cannot allocate resource for EISA slot 1 [ 0.705890] platform eisa.0: Cannot allocate resource for EISA slot 2 [ 0.706903] platform eisa.0: EISA: Detected 0 cards [ 0.706903] intel_pstate: CPU model not supported [ 0.709114] ledtrig-cpu: registered to indicate activity on CPUs [ 0.710211] drop_monitor: Initializing network drop monitor service [ 0.711636] NET: Registered PF_INET6 protocol family [ 0.954426] Freeing initrd memory: 30368K [ 0.972672] Segment Routing with IPv6 [ 0.973286] In-situ OAM (IOAM) with IPv6 [ 0.974006] NET: Registered PF_PACKET protocol family [ 0.975064] Key type dns_resolver registered [ 0.976218] IPI shorthand broadcast: enabled [ 0.976913] sched_clock: Marking stable (978231017, -2199674)->(1563755083, -587723740) [ 0.978698] registered taskstats version 1 [ 0.979619] Loading compiled-in X.509 certificates [ 0.981565] Loaded X.509 cert 'Build time autogenerated kernel key: cb32a1d62043880df8a86fd2f5449ac2c218dec3' [ 0.983623] Loaded X.509 cert 'Canonical Ltd. Live Patch Signing: 14df34d1a87cf37625abec039ef2bf521249b969' [ 0.985671] Loaded X.509 cert 'Canonical Ltd. Kernel Module Signing: 88f752e560a1e0737e31163a466ad7b70a850c19' [ 0.987242] blacklist: Loading compiled-in revocation X.509 certificates [ 0.988318] Loaded X.509 cert 'Canonical Ltd. Secure Boot Signing: 61482aa2830d0ab2ad5af10b7250da9033ddcef0' [ 0.990974] zswap: loaded using pool lzo/zbud [ 0.992493] Key type ._fscrypt registered [ 0.993186] Key type .fscrypt registered [ 0.993854] Key type fscrypt-provisioning registered [ 1.000435] Key type encrypted registered [ 1.001227] AppArmor: AppArmor sha1 policy hashing enabled [ 1.002230] ima: No TPM chip found, activating TPM-bypass! [ 1.003115] Loading compiled-in module X.509 certificates [ 1.004480] Loaded X.509 cert 'Build time autogenerated kernel key: cb32a1d62043880df8a86fd2f5449ac2c218dec3' [ 1.006123] ima: Allocated hash algorithm: sha1 [ 1.006850] ima: No architecture policies found [ 1.007664] evm: Initialising EVM extended attributes: [ 1.008555] evm: security.selinux [ 1.009089] evm: security.SMACK64 [ 1.009624] evm: security.SMACK64EXEC [ 1.010327] evm: security.SMACK64TRANSMUTE [ 1.011075] evm: security.SMACK64MMAP [ 1.011757] evm: security.apparmor [ 1.012403] evm: security.ima [ 1.012944] evm: security.capability [ 1.013591] evm: HMAC attrs: 0x1 [ 1.014513] PM: Magic number: 2:309:288 [ 1.015250] tty tty21: hash matches [ 1.016517] RAS: Correctable Errors collector initialized. [ 1.019300] Freeing unused decrypted memory: 2036K [ 1.020559] Freeing unused kernel image (initmem) memory: 2900K [ 1.084982] Write protecting the kernel read-only data: 30720k [ 1.087963] Freeing unused kernel image (text/rodata gap) memory: 2036K [ 1.090338] Freeing unused kernel image (rodata/data gap) memory: 1492K [ 1.138239] x86/mm: Checked W+X mappings: passed, no W+X pages found. [ 1.139302] x86/mm: Checking user space page tables [ 1.183274] x86/mm: Checked W+X mappings: passed, no W+X pages found. [ 1.184313] Run /init as init process Loading, please wait... Starting version 249.11-0ubuntu3.4 [ 1.327709] ahci 0000:00:01.1: can't derive routing for PCI INT B [ 1.328761] ahci 0000:00:01.1: PCI INT B: no GSI - using ISA IRQ 6 [ 1.330599] ahci 0000:00:01.1: SSS flag set, parallel bus scan disabled [ 1.331906] ahci 0000:00:01.1: AHCI 0001.0300 32 slots 6 ports 6 Gbps 0x1 impl SATA mode [ 1.333225] ahci 0000:00:01.1: flags: 64bit ncq sntf ilck stag pm led clo pio slum part sxs apst [ 1.342520] cryptd: max_cpu_qlen set to 1000 [ 1.348139] AVX2 version of gcm_enc/dec engaged. [ 1.348435] virtio_blk virtio0: [vda] 83886080 512-byte logical blocks (42.9 GB/40.0 GiB) [ 1.350597] AES CTR mode by8 optimization enabled [ 1.363741] scsi host0: ahci [ 1.371197] scsi host1: ahci [ 1.373125] virtio_net virtio1 enp0s2: renamed from eth0 [ 1.374228] scsi host2: ahci [ 1.376543] scsi host3: ahci [ 1.378062] scsi host4: ahci [ 1.379861] scsi host5: ahci [ 1.381130] ata1: SATA max UDMA/133 abar m1024@0xc0002000 port 0xc0002100 irq 24 [ 1.382294] vda: vda1 vda14 vda15 [ 1.382557] ata2: DUMMY [ 1.383571] ata3: DUMMY [ 1.384012] ata4: DUMMY [ 1.384426] ata5: DUMMY [ 1.384839] ata6: DUMMY [ 2.446795] ata1: SATA link up 6.0 Gbps (SStatus 133 SControl 300) [ 2.448976] tsc: Refined TSC clocksource calibration: 2193.350 MHz [ 2.450512] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x1f9da8a40f2, max_idle_ns: 440795224292 ns [ 2.450549] ata1.00: ATAPI: BHYVE SATA DVD ROM, 001, max UDMA/133 [ 2.453894] clocksource: Switched to clocksource tsc [ 2.454837] ata1.00: configured for UDMA/133 [ 2.455983] scsi 0:0:0:0: CD-ROM BHYVE BHYVE DVD-ROM 001 PQ: 0 ANSI: 5 [ 2.458123] sr 0:0:0:0: [sr0] scsi3-mmc drive: 0x/0x xa/form2 caddy [ 2.459239] cdrom: Uniform CD-ROM driver Revision: 3.20 [ 2.500752] sr 0:0:0:0: Attached scsi generic sg0 type 5 Begin: Loading essential drivers ... [ 3.876452] raid6: avx2x4 gen() 30145 MB/s [ 3.956686] raid6: avx2x4 xor() 12588 MB/s [ 4.025037] raid6: avx2x2 gen() 28292 MB/s [ 4.096091] raid6: avx2x2 xor() 17928 MB/s [ 4.162101] raid6: avx2x1 gen() 24515 MB/s [ 4.239259] raid6: avx2x1 xor() 15340 MB/s [ 4.311995] raid6: sse2x4 gen() 16917 MB/s [ 4.384158] raid6: sse2x4 xor() 8796 MB/s [ 4.452105] raid6: sse2x2 gen() 15450 MB/s [ 4.519634] raid6: sse2x2 xor() 10312 MB/s [ 4.592770] raid6: sse2x1 gen() 14400 MB/s [ 4.657828] raid6: sse2x1 xor() 9158 MB/s [ 4.658571] raid6: using algorithm avx2x4 gen() 30145 MB/s [ 4.659448] raid6: .... xor() 12588 MB/s, rmw enabled [ 4.660251] raid6: using avx2x2 recovery algorithm [ 4.662418] xor: automatically using best checksumming function avx
[ 4.664669] async_tx: api initialized (async) done. Begin: Running /scripts/init-premount ... done. Begin: Mounting root file system ... Begin: Running /scripts/local-top ... done. Begin: Running /scripts/local-premount ... [ 4.773932] Btrfs loaded, crc32c=crc32c-intel, zoned=yes, fsverity=yes Scanning for Btrfs filesystems done. Warning: fsck not present, so skipping root file system [ 4.819393] EXT4-fs (vda1): mounted filesystem with ordered data mode. Opts: (null). Quota mode: none. done. Begin: Running /scripts/local-bottom ... done. Begin: Running /scripts/init-bottom ... done. [ 5.082542] systemd[1]: Inserted module 'autofs4' [ 5.110814] systemd[1]: systemd 249.11-0ubuntu3.4 running in system mode (+PAM +AUDIT +SELINUX +APPARMOR +IMA +SMACK +SECCOMP +GCRYPT +GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP -LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -XKBCOMMON +UTMP +SYSVINIT default-hierarchy=unified) [ 5.116409] systemd[1]: Detected virtualization bhyve. [ 5.117640] systemd[1]: Detected architecture x86-64.

Welcome to Ubuntu 22.04 LTS!

[ 5.120973] systemd[1]: Hostname set to . [ 5.486156] systemd[1]: Queued start job for default target Graphical Interface. [ 5.511977] systemd[1]: Created slice Slice /system/modprobe. [ OK ] Created slice Slice /system/modprobe. [ 5.515667] systemd[1]: Created slice Slice /system/postgresql. [ OK ] Created slice Slice /system/postgresql. [ 5.518935] systemd[1]: Created slice Slice /system/serial-getty. [ OK ] Created slice Slice /system/serial-getty. [ 5.521571] systemd[1]: Created slice Slice /system/systemd-fsck. [ OK ] Created slice Slice /system/systemd-fsck. [ 5.523861] systemd[1]: Created slice User and Session Slice. [ OK ] Created slice User and Session Slice. [ 5.525723] systemd[1]: Started Forward Password Requests to Wall Directory Watch. [ OK ] Started Forward Password R…uests to Wall Directory Watch. [ 5.528435] systemd[1]: Set up automount Arbitrary Executable File Formats File System Automount Point. [ OK ] Set up automount Arbitrary…s File System Automount Point. [ 5.531281] systemd[1]: Reached target Slice Units. [ OK ] Reached target Slice Units. [ 5.532793] systemd[1]: Reached target Swaps. [ OK ] Reached target Swaps. [ 5.534279] systemd[1]: Reached target Local Verity Protected Volumes. [ OK ] Reached target Local Verity Protected Volumes. [ 5.536347] systemd[1]: Listening on Device-mapper event daemon FIFOs. [ OK ] Listening on Device-mapper event daemon FIFOs. [ 5.538488] systemd[1]: Listening on LVM2 poll daemon socket. [ OK ] Listening on LVM2 poll daemon socket. [ 5.540371] systemd[1]: Listening on multipathd control socket. [ OK ] Listening on multipathd control socket. [ 5.542377] systemd[1]: Listening on Syslog Socket. [ OK ] Listening on Syslog Socket. [ 5.544017] systemd[1]: Listening on fsck to fsckd communication Socket. [ OK ] Listening on fsck to fsckd communication Socket. [ 5.546139] systemd[1]: Listening on initctl Compatibility Named Pipe. [ OK ] Listening on initctl Compatibility Named Pipe. [ 5.548287] systemd[1]: Listening on Journal Audit Socket. [ OK ] Listening on Journal Audit Socket. [ 5.550092] systemd[1]: Listening on Journal Socket (/dev/log). [ OK ] Listening on Journal Socket (/dev/log). [ 5.552108] systemd[1]: Listening on Journal Socket. [ OK ] Listening on Journal Socket. [ 5.553874] systemd[1]: Listening on Network Service Netlink Socket. [ OK ] Listening on Network Service Netlink Socket. [ 5.556053] systemd[1]: Listening on udev Control Socket. [ OK ] Listening on udev Control Socket. [ 5.557852] systemd[1]: Listening on udev Kernel Socket. [ OK ] Listening on udev Kernel Socket. [ 5.594913] systemd[1]: Mounting Huge Pages File System... Mounting Huge Pages File System... [ 5.599307] systemd[1]: Mounting POSIX Message Queue File System... Mounting POSIX Message Queue File System... [ 5.603533] systemd[1]: Mounting Kernel Debug File System... Mounting Kernel Debug File System... [ 5.606574] systemd[1]: Mounting Kernel Trace File System... Mounting Kernel Trace File System... [ 5.610932] systemd[1]: Starting Journal Service... Starting Journal Service... [ 5.614278] systemd[1]: Starting Set the console keyboard layout... Starting Set the console keyboard layout... [ 5.618605] systemd[1]: Starting Create List of Static Device Nodes... Starting Create List of Static Device Nodes... [ 5.624523] systemd[1]: Starting Monitoring of LVM2 mirrors, snapshots etc. using dmeventd or progress polling... Starting Monitoring of LVM…meventd or progress polling... [ 5.628687] systemd[1]: Condition check resulted in LXD - agent being skipped. [ 5.634025] systemd[1]: Starting Load Kernel Module chromeos_pstore... Starting Load Kernel Module chromeos_pstore... [ 5.637262] systemd[1]: Starting Load Kernel Module configfs... Starting Load Kernel Module configfs... [ 5.639977] systemd[1]: Starting Load Kernel Module drm... Starting Load Kernel Module drm... [ 5.644833] systemd[1]: Starting Load Kernel Module efi_pstore... Starting Load Kernel Module efi_pstore... [ 5.648272] systemd[1]: Starting Load Kernel Module fuse... Starting Load Kernel Module fuse... [ 5.653267] systemd[1]: Starting Load Kernel Module mtdpstore... Starting Load Kernel Module mtdpstore... [ 5.655921] systemd[1]: Starting Load Kernel Module pstore_blk... Starting Load Kernel Module pstore_blk... [ 5.661539] systemd[1]: Starting Load Kernel Module pstore_zone... Starting Load Kernel Module pstore_zone... [ 5.669649] systemd[1]: Starting Load Kernel Module ramoops... Starting Load Kernel Module ramoops... [ 5.671598] systemd[1]: Condition check resulted in OpenVSwitch configuration for cleanup being skipped. [ 5.673556] systemd[1]: Condition check resulted in File System Check on Root Device being skipped. [ 5.685677] systemd[1]: Starting Load Kernel Modules... Starting Load Kernel Modules... [ 5.688582] systemd[1]: Starting Remount Root and Kernel File Systems... Starting Remount Root and Kernel File Systems... [ 5.692957] systemd[1]: Starting Coldplug All udev Devices... Starting Coldplug All udev Devices... [ 5.698961] systemd[1]: Mounted Huge Pages File System. [ OK ] Mounted Huge Pages File System. [ 5.704697] systemd[1]: Mounted POSIX Message Queue File System. [ OK ] Mounted POSIX Message Queue File System. [ 5.706712] systemd[1]: Mounted Kernel Debug File System. [ OK ] Mounted Kernel Debug File System. [ 5.708492] systemd[1]: Mounted Kernel Trace File System. [ OK ] Mounted Kernel Trace File System. [ 5.710708] systemd[1]: Finished Create List of Static Device Nodes. [ OK ] Finished Create List of Static Device Nodes. [ 5.713285] systemd[1]: modprobe@chromeos_pstore.service: Deactivated successfully. [ 5.715011] IPMI message handler: version 39.2 [ 5.715841] systemd[1]: Finished Load Kernel Module chromeos_pstore. [ OK ] Finished Load Kernel Module chromeos_pstore. [ 5.719429] EXT4-fs (vda1): re-mounted. Opts: discard,errors=remount-ro. Quota mode: none. [ 5.722021] systemd[1]: modprobe@configfs.service: Deactivated successfully. [ 5.723456] systemd[1]: Finished Load Kernel Module configfs. [ OK ] Finished Load Kernel Module configfs. [ 5.725474] systemd[1]: modprobe@mtdpstore.service: Deactivated successfully. [ 5.726915] systemd[1]: Finished Load Kernel Module mtdpstore. [ OK ] Finished Load Kernel Module mtdpstore. [ 5.728944] systemd[1]: modprobe@pstore_blk.service: Deactivated successfully. [ 5.730435] systemd[1]: Finished Load Kernel Module pstore_blk. [ OK ] Finished Load Kernel Module pstore_blk. [ 5.733129] systemd[1]: Finished Remount Root and Kernel File Systems. [ OK ] Finished Remount Root and Kernel File Systems. [ 5.735258] ipmi device interface [ 5.746318] systemd[1]: modprobe@pstore_zone.service: Deactivated successfully. [ 5.747921] systemd[1]: Finished Load Kernel Module pstore_zone. [ OK ] Finished Load Kernel Module pstore_zone. [ 5.750154] systemd[1]: Finished Monitoring of LVM2 mirrors, snapshots etc. using dmeventd or progress polling. [ OK ] Finished Monitoring of LVM… dmeventd or progress polling. [ 5.758220] systemd[1]: Started Journal Service. [ OK ] Started Journal Service. [ OK ] Finished Load Kernel Module fuse. [ OK ] Finished Load Kernel Module ramoops. [ OK ] Finished Load Kernel Modules. [ OK ] Finished Load Kernel Module efi_pstore. Mounting FUSE Control File System... Mounting Kernel Configuration File System... Starting Device-Mapper Multipath Device Controller... Starting Flush Journal to Persistent Storage... [ 5.782776] alua: device handler registered Starting Load/Save Random Seed... Starting Apply Kernel Variables... Starting Create System Users... [ OK ] Finished Load Kernel Module drm. [ 5.795121] emc: device handler registered [ OK ] Mounted FUSE Control File System. [ OK ] Mounted Kernel Configuration File System. [ 5.800286] systemd-journald[375]: Received client request to flush runtime journal. [ 5.805708] rdac: device handler registered [ OK ] Finished Load/Save Random Seed. [ OK ] Finished Set the console keyboard layout. [ OK ] Finished Create System Users. Starting Create Static Device Nodes in /dev... [ OK ] Finished Apply Kernel Variables. [ OK ] Finished Coldplug All udev Devices. [ OK ] Finished Create Static Device Nodes in /dev. Starting Rule-based Manage…for Device Events and Files... [ OK ] Started Device-Mapper Multipath Device Controller. [ OK ] Reached target Preparation for Local File Systems. Mounting Mount unit for core18, revision 2538... Mounting Mount unit for core20, revision 1587... Mounting Mount unit for core20, revision 1611... Mounting Mount unit for lxd, revision 22923... Mounting Mount unit for snapd, revision 16292... Mounting Mount unit for yq, revision 1805... [ OK ] Finished Flush Journal to Persistent Storage. [ OK ] Mounted Mount unit for core18, revision 2538. [ OK ] Mounted Mount unit for core20, revision 1587. [ OK ] Started Rule-based Manager for Device Events and Files. [ OK ] Mounted Mount unit for core20, revision 1611. [ OK ] Mounted Mount unit for lxd, revision 22923. [ OK ] Mounted Mount unit for snapd, revision 16292. [ OK ] Mounted Mount unit for yq, revision 1805. [ OK ] Started Dispatch Password …ts to Console Directory Watch. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Found device /dev/ttyS0. [ OK ] Listening on Load/Save RF …itch Status /dev/rfkill Watch. [ OK ] Found device /dev/disk/by-label/UEFI. Starting File System Check on /dev/disk/by-label/UEFI... [ OK ] Started File System Check Daemon to report status. [ OK ] Finished File System Check on /dev/disk/by-label/UEFI. Mounting /boot/efi... [ OK ] Mounted /boot/efi. [ OK ] Reached target Local File Systems. Starting Load AppArmor profiles... Starting Enable support fo…l executable binary formats... Starting Set console font and keymap... Starting Create final runt…dir for shutdown pivot root... Starting Tell Plymouth To Write Out Runtime Data... Starting Create Volatile Files and Directories... Starting Uncomplicated firewall... [ OK ] Finished Set console font and keymap. [ OK ] Finished Create final runt…e dir for shutdown pivot root. Mounting Arbitrary Executable File Formats File System... [ OK ] Finished Tell Plymouth To Write Out Runtime Data. [ OK ] Mounted Arbitrary Executable File Formats File System. [ OK ] Finished Create Volatile Files and Directories. Starting Network Time Synchronization... Starting Record System Boot/Shutdown in UTMP... [ OK ] Finished Record System Boot/Shutdown in UTMP. [ OK ] Finished Enable support fo…nal executable binary formats. [ OK ] Finished Load AppArmor profiles. Starting Load AppArmor pro…managed internally by snapd... Starting Initial cloud-init job (pre-networking)... [ OK ] Started Network Time Synchronization. [ OK ] Reached target System Time Set. [ OK ] Finished Load AppArmor pro…s managed internally by snapd. [ OK ] Finished Uncomplicated firewall. [ 7.531144] cloud-init[635]: Cloud-init v. 22.2-0ubuntu1~22.04.3 running 'init-local' at Thu, 25 Aug 2022 16:18:05 +0000. Up 7.48 seconds. [ OK ] Finished Initial cloud-init job (pre-networking). [ OK ] Reached target Preparation for Network. Starting Network Configuration... [ OK ] Started Network Configuration. Starting Wait for Network to be Configured... Starting Network Name Resolution... [ OK ] Started Network Name Resolution. [ OK ] Reached target Network. [ OK ] Reached target Host and Network Name Lookups. [ OK ] Finished Wait for Network to be Configured. Starting Initial cloud-ini… (metadata service crawler)... [ 9.838141] cloud-init[653]: Cloud-init v. 22.2-0ubuntu1~22.04.3 running 'init' at Thu, 25 Aug 2022 16:18:07 +0000. Up 9.80 seconds. [ 9.851582] cloud-init[653]: ci-info: +++++++++++++++++++++++++++++++++++++++Net device info+++++++++++++++++++++++++++++++++++++++ [ 9.853132] cloud-init[653]: ci-info: +--------+------+------------------------------+---------------+--------+-------------------+ [ 9.854596] cloud-init[653]: ci-info: | Device | Up | Address | Mask | Scope | Hw-Address | [ 9.856054] cloud-init[653]: ci-info: +--------+------+------------------------------+---------------+--------+-------------------+ [ 9.857511] cloud-init[653]: ci-info: | enp0s2 | True | 192.168.64.7 | 255.255.255.0 | global | aa:33:ff:6d:fc:7a | [ 9.859100] cloud-init[653]: ci-info: | enp0s2 | True | fe80::a833:ffff:fe6d:fc7a/64 | . | link | aa:33:ff:6d:fc:7a | [ 9.860753] cloud-init[653]: ci-info: | lo | True | 127.0.0.1 | 255.0.0.0 | host | . | [ 9.862245] cloud-init[653]: ci-info: | lo | True | ::1/128 | . | host | . | [ 9.863767] cloud-init[653]: ci-info: +--------+------+------------------------------+---------------+--------+-------------------+ [ 9.865461] cloud-init[653]: ci-info: +++++++++++++++++++++++++++++++Route IPv4 info+++++++++++++++++++++++++++++++ [ 9.866792] cloud-init[653]: ci-info: +-------+--------------+--------------+-----------------+-----------+-------+ [ 9.868114] cloud-init[653]: ci-info: | Route | Destination | Gateway | Genmask | Interface | Flags | [ 9.869430] cloud-init[653]: ci-info: +-------+--------------+--------------+-----------------+-----------+-------+ [ 9.870744] cloud-init[653]: ci-info: | 0 | 0.0.0.0 | 192.168.64.1 | 0.0.0.0 | enp0s2 | UG | [ 9.872100] cloud-init[653]: ci-info: | 1 | 192.168.64.0 | 0.0.0.0 | 255.255.255.0 | enp0s2 | U | [ 9.873486] cloud-init[653]: ci-info: | 2 | 192.168.64.1 | 0.0.0.0 | 255.255.255.255 | enp0s2 | UH | [ 9.875138] cloud-init[653]: ci-info: +-------+--------------+--------------+-----------------+-----------+-------+ [ 9.876460] cloud-init[653]: ci-info: +++++++++++++++++++Route IPv6 info+++++++++++++++++++ [ 9.877542] cloud-init[653]: ci-info: +-------+-------------+---------+-----------+-------+ [ 9.878636] cloud-init[653]: ci-info: | Route | Destination | Gateway | Interface | Flags | [ 9.879926] cloud-init[653]: ci-info: +-------+-------------+---------+-----------+-------+ [ 9.881012] cloud-init[653]: ci-info: | 1 | fe80::/64 | :: | enp0s2 | U | [ 9.882201] cloud-init[653]: ci-info: | 3 | local | :: | enp0s2 | U | [ 9.883300] cloud-init[653]: ci-info: | 4 | multicast | :: | enp0s2 | U | [ 9.884501] cloud-init[653]: ci-info: +-------+-------------+---------+-----------+-------+ [ OK ] Finished Initial cloud-ini…ob (metadata service crawler). [ OK ] Reached target Cloud-config availability. [ OK ] Reached target Network is Online. [ OK ] Reached target System Initialization. [ OK ] Started Daily dpkg database backup timer. [ OK ] Started Periodic ext4 Onli…ata Check for All Filesystems. [ OK ] Started Discard unused blocks once a week. [ OK ] Started Refresh fwupd metadata regularly. [ OK ] Started Daily rotation of log files. [ OK ] Started Daily Cleanup of Temporary Directories. [ OK ] Started Ubuntu Advantage Timer for running repeated jobs. [ OK ] Started Download data for …ailed at package install time. [ OK ] Started Check to see wheth…w version of Ubuntu available. [ OK ] Reached target Path Units. [ OK ] Reached target Timer Units. [ OK ] Listening on cloud-init hotplug hook socket. [ OK ] Listening on D-Bus System Message Bus Socket. Starting Docker Socket for the API... [ OK ] Listening on Open-iSCSI iscsid Socket. [ OK ] Listening on Socket unix for snap application lxd.daemon. [ OK ] Listening on Socket unix f…p application lxd.user-daemon. Starting Socket activation for snappy daemon... [ OK ] Listening on UUID daemon activation socket. [ OK ] Reached target Preparation for Remote File Systems. [ OK ] Reached target Remote File Systems. [ OK ] Finished Availability of block devices. [ OK ] Listening on Docker Socket for the API. [ OK ] Listening on Socket activation for snappy daemon. [ OK ] Reached target Socket Units. [ OK ] Reached target Basic System. Starting containerd container runtime... [ OK ] Started Regular background program processing daemon. [ OK ] Started D-Bus System Message Bus. [ OK ] Started Save initial kernel messages after boot. Starting Remove Stale Onli…t4 Metadata Check Snapshots... Starting Record successful boot for GRUB... [ OK ] Started irqbalance daemon. Starting Dispatcher daemon for systemd-networkd... Starting Authorization Manager... Starting PostgreSQL Cluster 14-main... Starting System Logging Service... Starting Service for snap application lxd.activate... Starting Snap Daemon... Starting OpenBSD Secure Shell server... Starting User Login Management... Starting Permit User Sessions... Starting Disk Manager... Starting Rotate log files... [ OK ] Finished Permit User Sessions. Starting Hold until boot process finishes up... Starting Terminate Plymouth Boot Screen... [ OK ] Finished Hold until boot process finishes up. [ OK ] Started Serial Getty on ttyS0. Starting Set console scheme... [ OK ] Finished Remove Stale Onli…ext4 Metadata Check Snapshots. [ OK ] Finished Terminate Plymouth Boot Screen. [ OK ] Finished Set console scheme. [ OK ] Created slice Slice /system/getty. [ OK ] Started Getty on tty1. [ OK ] Reached target Login Prompts. [ OK ] Started System Logging Service. [ OK ] Finished Record successful boot for GRUB. Starting GRUB failed boot detection... [ OK ] Finished GRUB failed boot detection. [ OK ] Started User Login Management. [ OK ] Started Authorization Manager. Starting Modem Manager... [ OK ] Started OpenBSD Secure Shell server. [ OK ] Finished Rotate log files. [ OK ] Started Dispatcher daemon for systemd-networkd. [ OK ] Started Disk Manager. [ OK ] Started Modem Manager. [ OK ] Started containerd container runtime. Starting Docker Application Container Engine... [ OK ] Started PostgreSQL Cluster 14-main. Starting PostgreSQL RDBMS... [ OK ] Finished PostgreSQL RDBMS. [ OK ] Started libcontainer conta…bd1bff9e9ef227289b815ed90ec12. [ OK ] Started libcontainer conta…466e64e93c73052528f50e6048cba. [ OK ] Started libcontainer conta…6f76ac363f6345ab7790989dda434. [ OK ] Started libcontainer conta…079d53a61f0ee6ba4e40d95ec4603. [ OK ] Started libcontainer conta…1d5a5221881283cd0b8c25b23d124.

Ubuntu 22.04 LTS docker ttyS0

docker login: [ 16.733578] cloud-init[1617]: Cloud-init v. 22.2-0ubuntu1~22.04.3 running 'modules:config' at Thu, 25 Aug 2022 16:18:14 +0000. Up 16.49 seconds. [ 18.391071] cloud-init[1703]: Cloud-init v. 22.2-0ubuntu1~22.04.3 running 'modules:final' at Thu, 25 Aug 2022 16:18:16 +0000. Up 18.27 seconds. [ 18.500842] cloud-init[1703]: The system is finally up, after 18.50 seconds

moorthi07 commented 2 years ago

$ multipass shell docker shell failed: Cannot retrieve credentials in unknown state

moorthi07 commented 2 years ago

multipassd.log

[2022-08-25T15:19:13.942] [info] [docker] hyperkit: [INFO] block recycler: triggering background flush: 253789 used; 0 junk; 0 erased; 1684 available; 0 copies; 0 roots; 0 Copying; 0 Copied; 0 Flushed; 0 Referenced; max_cluster = 255152 [2022-08-25T15:19:16.915] [info] [daemon] Cannot open ssh session on "docker" shutdown: ssh connection failed: 'Timeout connecting to 192.168.64.7' [2022-08-25T15:19:16.915] [debug] [sshfs-mounts] No mounts to stop for instance "docker" [2022-08-25T15:19:16.916] [info] [docker] sending shutdown signal to hyperkit process, waiting for it to shutdown... [2022-08-25T15:19:19.821] [info] [docker] hyperkit: [INFO] block recycler: triggering background flush: 253740 used; 0 junk; 55 erased; 1678 available; 0 copies; 0 roots; 0 Copying; 0 Copied; 0 Flushed; 0 Referenced; max_cluster = 255152 [2022-08-25T15:19:19.821] [info] [docker] hyperkit: [INFO] block recycler: 0 cluster copies flushed; 0 cluster copies complete; 55 clusters erased [2022-08-25T15:19:19.826] [info] [docker] hyperkit: [INFO] block recycler: flush: 253740 used; 0 junk; 0 erased; 1733 available; 0 copies; 0 roots; 0 Copying; 0 Copied; 0 Flushed; 0 Referenced; max_cluster = 255152 [2022-08-25T15:19:24.896] [info] [docker] hyperkit: [INFO] block recycler: triggering background flush: 253740 used; 0 junk; 0 erased; 1733 available; 0 copies; 0 roots; 0 Copying; 0 Copied; 0 Flushed; 0 Referenced; max_cluster = 255152 [2022-08-25T15:19:28.915] [info] [docker] linkname /var/root/Library/Application Support/multipassd/vault/instances/docker/pty COM1 connected to /dev/ttys004

[2022-08-25T15:19:29.032] [info] [docker] process state changed to NotRunning [2022-08-25T15:19:29.071] [info] [docker] process finished with exit code 0 [2022-08-25T17:24:34.395] [debug] [daemon] Succeeded setting local.bridged-network=ls [2022-08-25T17:24:34.900] [info] [daemon] Goodbye! [2022-08-25T17:24:34.917] [info] [docker-dev] sending shutdown signal to hyperkit process, waiting for it to shutdown...

ricab commented 2 years ago

Hi @moorthi07, your logs indicate that the OS loaded just fine. To get you a prompt, though, multipass shell needs to connect via SSH. It can't do that if ufw blocks it. Also, have a look here for other problems and solutions.

BTW, hyperkit does not support bridging, so "setting local.bridged-network=ls" will have no effect.

moorthi07 commented 2 years ago

@ricab Thanks for the info. especially about hyperkit , the doc@https://multipass.run/docs/networks-command doesn't show anything about hyperkit.

Can't quiet get it. How do I turn off the ufw now? I don't see any prompt in the link. I enabled ufw , opened a port with 'sudo ufw allow 5432'

As you said 'networks' not working unlike its mentioned in the doc including sudo multipass set local.driver=virtualbox

How do I enable multipass to work with vitualbox so that I can use networks?

ricab commented 2 years ago

multipass networks should work with the virtualbox driver, but you need to install VirtualBox first. But perhaps try qemu first: sudo multipass set local.driver=qemu.

moorthi07 commented 2 years ago

@ricab Thanks. I have VB already long before. My mistake I tried the sudo Virtual box from multipass instance. So, now running from my root, sudo Virtualbox - opens up with this msg.

DEBUG: issetugid_for_AppKit was called by 0x7ff80d7cd045 /System/Library/Frameworks/AppKit.framework/Versions/C/AppKit::_NSCheckForIllegalSetugidApp+0xb (via 0x7ff80d7ccded)

It doesn't show any instances although I have 2 multipass instances running.

Isn't the qemu driver recommended for M1 chipsets? But I'll give it a try any way.

ricab commented 2 years ago

Isn't the qemu driver recommended for M1 chipsets? But I'll give it a try any way.

It is supported on Intel Macs too, as long as you have macOS 10.15+: https://multipass.run/docs/local-driver

moorthi07 commented 2 years ago

Got it. Thanks. Is there any ways, I can ssh into the ufw blocked multipass instance?

ricab commented 2 years ago

I am afraid not, SSH is the only way to access Multipass instances at the time being. If ufw blocks it inside the instance, it locks you out. You could try to hack the hyperkit command to give you a console, but unless you happened to add a user with a password, you'd still be unable to login.

We want to introduce a way to recover data from instances, but not there yet.

moorthi07 commented 2 years ago

Thanks. I guess it would be useful for 'nix freshers to add about login options and risks of loosing multipass instances,

moorthi07 commented 2 years ago

Is there any ways to access startup script for multipass instance? like mentioned here, https://stackoverflow.com/questions/49276532/enabled-ufw-and-now-i-cant-connect-to-ssh

Method 1: Add startup script in instance settings page to disable ufw

#! /bin/bash
/usr/sbin/ufw disable

Method 2: Attach the boot disk to another instance and modifies the file /etc/ufw/ufw.conf
moorthi07 commented 2 years ago

May be you can add 'cloud-init' / startupscript optional argument for 'multipass start' command also. Just guessing. does it make sense?

ricab commented 2 years ago

Yes, you can run arbitrary commands with cloud-init.

moorthi07 commented 2 years ago

You mean , Can I use cloud-init with 'multipass start' command?

townsend2010 commented 2 years ago

Hi @moorthi07,

No, it is only supported at multipass launch because cloud-init is used for provisioning an instance the first time it boots.

moorthi07 commented 2 years ago

so its not going to help to recover this ufw blocked instance, right?

moorthi07 commented 2 years ago

But I have a good news and also wondering how it works. Ironically, the portainer is loading at port 9000 from this 'ssh blocked instance'. And also I'm able to access all the docker apps in port 80, 8282 , etc.

What actually going on with this instance, is it ufw or any other issue with ssh that I should be looking into?

townsend2010 commented 2 years ago

So somehow, port 22 is apparently being blocked. I'm not sure of your set up, so I can't say how or where this is happening, but that is the problem.