0s autopkgtest [08:09:43]: starting date and time: 2025-10-31 08:09:43+0000 0s autopkgtest [08:09:43]: git checkout: 4b346b80 nova: make wait_reboot return success even when a no-op 0s autopkgtest [08:09:43]: host juju-7f2275-prod-proposed-migration-environment-15; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.vzrfy41n/out --timeout-copy=6000 --setup-commands /home/ubuntu/autopkgtest-cloud/worker-config-production/setup-canonical.sh --apt-pocket=proposed=src:adsys --apt-upgrade adsys --timeout-short=300 --timeout-copy=20000 --timeout-build=20000 --env=ADT_TEST_TRIGGERS=adsys/0.16.3ubuntu0.25.10.1 -- ssh -s /home/ubuntu/autopkgtest/ssh-setup/nova -- --flavor autopkgtest-cpu2-ram4-disk20-amd64 --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-15@sto01-10.secgroup --name adt-questing-amd64-adsys-20251031-074722-juju-7f2275-prod-proposed-migration-environment-15-37a8da8a-9da2-464f-bc24-49de849871fb --image adt/ubuntu-questing-amd64-server --keyname testbed-juju-7f2275-prod-proposed-migration-environment-15 --net-id=net_prod-autopkgtest-workers-amd64 -e TERM=linux --mirror=http://ftpmaster.internal/ubuntu/ 3s Creating nova instance adt-questing-amd64-adsys-20251031-074722-juju-7f2275-prod-proposed-migration-environment-15-37a8da8a-9da2-464f-bc24-49de849871fb from image adt/ubuntu-questing-amd64-server-20251029.img (UUID 9ad666d7-3d1a-4dd9-8d34-135736dea446)... 347s ------- nova console-log 31c0566f-1d8f-4785-abd9-607adddcd51f (adt-questing-amd64-adsys-20251031-074722-juju-7f2275-prod-proposed-migration-environment-15-37a8da8a-9da2-464f-bc24-49de849871fb) ------ 349s [ 0.000000] Linux version 6.17.0-6-generic (buildd@lcy02-amd64-018) (x86_64-linux-gnu-gcc (Ubuntu 15.2.0-4ubuntu4) 15.2.0, GNU ld (GNU Binutils for Ubuntu) 2.45) #6-Ubuntu SMP PREEMPT_DYNAMIC Tue Oct 7 13:34:17 UTC 2025 (Ubuntu 6.17.0-6.6-generic 6.17.1) 349s [ 0.000000] Command line: BOOT_IMAGE=/vmlinuz-6.17.0-6-generic root=UUID=5b34d3dd-a752-4305-8452-98500dfa2307 ro console=tty0 console=hvc0 console=ttyS0 349s [ 0.000000] KERNEL supported cpus: 349s [ 0.000000] Intel GenuineIntel 349s [ 0.000000] AMD AuthenticAMD 349s [ 0.000000] Hygon HygonGenuine 349s [ 0.000000] Centaur CentaurHauls 349s [ 0.000000] zhaoxin Shanghai 349s [ 0.000000] BIOS-provided physical RAM map: 349s [ 0.000000] BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable 349s [ 0.000000] BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved 349s [ 0.000000] BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved 349s [ 0.000000] BIOS-e820: [mem 0x0000000000100000-0x00000000bffdcfff] usable 349s [ 0.000000] BIOS-e820: [mem 0x00000000bffdd000-0x00000000bfffffff] reserved 349s [ 0.000000] BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved 349s [ 0.000000] BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved 349s [ 0.000000] BIOS-e820: [mem 0x0000000100000000-0x000000013fffffff] usable 349s [ 0.000000] BIOS-e820: [mem 0x000000fd00000000-0x000000ffffffffff] reserved 349s [ 0.000000] NX (Execute Disable) protection: active 349s [ 0.000000] APIC: Static calls initialized 349s [ 0.000000] SMBIOS 2.8 present. 349s [ 0.000000] DMI: OpenStack Foundation OpenStack Nova, BIOS 1.16.3-debian-1.16.3-2~backport22.04.202412060920~ubuntu22.04.1 04/01/2014 349s [ 0.000000] DMI: Memory slots populated: 1/1 349s [ 0.000000] Hypervisor detected: KVM 349s [ 0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00 349s [ 0.000000] kvm-clock: using sched offset of 1820247899 cycles 349s [ 0.000002] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns 349s [ 0.000005] tsc: Detected 2396.400 MHz processor 349s [ 0.000857] last_pfn = 0x140000 max_arch_pfn = 0x400000000 349s [ 0.000894] MTRR map: 4 entries (3 fixed + 1 variable; max 19), built from 8 variable MTRRs 349s [ 0.000896] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT 349s [ 0.000949] last_pfn = 0xbffdd max_arch_pfn = 0x400000000 349s [ 0.003229] found SMP MP-table at [mem 0x000f53c0-0x000f53cf] 349s [ 0.003245] Using GB pages for direct mapping 349s [ 0.003429] RAMDISK: [mem 0x2f45d000-0x33a25fff] 349s [ 0.003805] ACPI: Early table checksum verification disabled 349s [ 0.003808] ACPI: RSDP 0x00000000000F5140 000014 (v00 BOCHS ) 349s [ 0.003811] ACPI: RSDT 0x00000000BFFE2334 000034 (v01 BOCHS BXPC 00000001 BXPC 00000001) 349s [ 0.003815] ACPI: FACP 0x00000000BFFE2150 000074 (v01 BOCHS BXPC 00000001 BXPC 00000001) 349s [ 0.003819] ACPI: DSDT 0x00000000BFFE0040 002110 (v01 BOCHS BXPC 00000001 BXPC 00000001) 349s [ 0.003821] ACPI: FACS 0x00000000BFFE0000 000040 349s [ 0.003823] ACPI: APIC 0x00000000BFFE21C4 000080 (v03 BOCHS BXPC 00000001 BXPC 00000001) 349s [ 0.003825] ACPI: SRAT 0x00000000BFFE2244 0000C8 (v01 BOCHS BXPC 00000001 BXPC 00000001) 349s [ 0.003827] ACPI: WAET 0x00000000BFFE230C 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) 349s [ 0.003828] ACPI: Reserving FACP table memory at [mem 0xbffe2150-0xbffe21c3] 349s [ 0.003829] ACPI: Reserving DSDT table memory at [mem 0xbffe0040-0xbffe214f] 349s [ 0.003830] ACPI: Reserving FACS table memory at [mem 0xbffe0000-0xbffe003f] 349s [ 0.003830] ACPI: Reserving APIC table memory at [mem 0xbffe21c4-0xbffe2243] 349s [ 0.003831] ACPI: Reserving SRAT table memory at [mem 0xbffe2244-0xbffe230b] 349s [ 0.003832] ACPI: Reserving WAET table memory at [mem 0xbffe230c-0xbffe2333] 349s [ 0.003903] ACPI: SRAT: Node 0 PXM 0 [mem 0x00000000-0x0009ffff] 349s [ 0.003905] ACPI: SRAT: Node 0 PXM 0 [mem 0x00100000-0xbfffffff] 349s [ 0.003906] ACPI: SRAT: Node 0 PXM 0 [mem 0x100000000-0x13fffffff] 349s [ 0.003908] NUMA: Node 0 [mem 0x00001000-0x0009ffff] + [mem 0x00100000-0xbfffffff] -> [mem 0x00001000-0xbfffffff] 349s [ 0.003910] NUMA: Node 0 [mem 0x00001000-0xbfffffff] + [mem 0x100000000-0x13fffffff] -> [mem 0x00001000-0x13fffffff] 349s [ 0.003919] NODE_DATA(0) allocated [mem 0x13ffd52c0-0x13fffffff] 349s [ 0.004112] Zone ranges: 349s [ 0.004112] DMA [mem 0x0000000000001000-0x0000000000ffffff] 349s [ 0.004113] DMA32 [mem 0x0000000001000000-0x00000000ffffffff] 349s [ 0.004114] Normal [mem 0x0000000100000000-0x000000013fffffff] 349s [ 0.004115] Device empty 349s [ 0.004116] Movable zone start for each node 349s [ 0.004118] Early memory node ranges 349s [ 0.004118] node 0: [mem 0x0000000000001000-0x000000000009efff] 349s [ 0.004119] node 0: [mem 0x0000000000100000-0x00000000bffdcfff] 349s [ 0.004120] node 0: [mem 0x0000000100000000-0x000000013fffffff] 349s [ 0.004121] Initmem setup node 0 [mem 0x0000000000001000-0x000000013fffffff] 349s [ 0.004125] On node 0, zone DMA: 1 pages in unavailable ranges 349s [ 0.004137] On node 0, zone DMA: 97 pages in unavailable ranges 349s [ 0.006870] On node 0, zone Normal: 35 pages in unavailable ranges 349s [ 0.007262] ACPI: PM-Timer IO Port: 0x608 349s [ 0.007269] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) 349s [ 0.007295] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 349s [ 0.007297] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) 349s [ 0.007299] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) 349s [ 0.007299] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) 349s [ 0.007300] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) 349s [ 0.007301] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) 349s [ 0.007304] ACPI: Using ACPI (MADT) for SMP configuration information 349s [ 0.007308] CPU topo: Max. logical packages: 2 349s [ 0.007308] CPU topo: Max. logical dies: 2 349s [ 0.007309] CPU topo: Max. dies per package: 1 349s [ 0.007312] CPU topo: Max. threads per core: 1 349s [ 0.007313] CPU topo: Num. cores per package: 1 349s [ 0.007313] CPU topo: Num. threads per package: 1 349s [ 0.007313] CPU topo: Allowing 2 present CPUs plus 0 hotplug CPUs 349s [ 0.007326] kvm-guest: APIC: eoi() replaced with kvm_guest_apic_eoi_write() 349s [ 0.007339] PM: hibernation: Registered nosave memory: [mem 0x00000000-0x00000fff] 349s [ 0.007341] PM: hibernation: Registered nosave memory: [mem 0x0009f000-0x000fffff] 349s [ 0.007342] PM: hibernation: Registered nosave memory: [mem 0xbffdd000-0xffffffff] 349s [ 0.007343] [mem 0xc0000000-0xfeffbfff] available for PCI devices 349s [ 0.007344] Booting paravirtualized kernel on KVM 349s [ 0.007345] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns 349s [ 0.007350] setup_percpu: NR_CPUS:8192 nr_cpumask_bits:2 nr_cpu_ids:2 nr_node_ids:1 349s [ 0.007441] percpu: Embedded 84 pages/cpu s221184 r8192 d114688 u1048576 349s [ 0.007467] kvm-guest: PV spinlocks disabled, no host support 349s [ 0.007468] Kernel command line: BOOT_IMAGE=/vmlinuz-6.17.0-6-generic root=UUID=5b34d3dd-a752-4305-8452-98500dfa2307 ro console=tty0 console=hvc0 console=ttyS0 349s [ 0.007518] Unknown kernel command line parameters "BOOT_IMAGE=/vmlinuz-6.17.0-6-generic", will be passed to user space. 349s [ 0.007529] random: crng init done 349s [ 0.007530] printk: log buffer data + meta data: 262144 + 917504 = 1179648 bytes 349s [ 0.007661] Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) 349s [ 0.007726] Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) 349s [ 0.007751] software IO TLB: area num 2. 349s [ 0.011801] Fallback order for Node 0: 0 349s [ 0.011803] Built 1 zonelists, mobility grouping on. Total pages: 1048443 349s [ 0.011804] Policy zone: Normal 349s [ 0.011810] mem auto-init: stack:all(zero), heap alloc:on, heap free:off 349s [ 0.017978] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1 349s [ 0.030310] ftrace: allocating 61460 entries in 242 pages 349s [ 0.030312] ftrace: allocated 242 pages with 5 groups 349s [ 0.031260] Dynamic Preempt: voluntary 349s [ 0.031296] rcu: Preemptible hierarchical RCU implementation. 349s [ 0.031296] rcu: RCU restricting CPUs from NR_CPUS=8192 to nr_cpu_ids=2. 349s [ 0.031298] Trampoline variant of Tasks RCU enabled. 349s [ 0.031299] Rude variant of Tasks RCU enabled. 349s [ 0.031299] Tracing variant of Tasks RCU enabled. 349s [ 0.031300] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. 349s [ 0.031300] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=2 349s [ 0.031305] RCU Tasks: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=2. 349s [ 0.031306] RCU Tasks Rude: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=2. 349s [ 0.031307] RCU Tasks Trace: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=2. 349s [ 0.035345] NR_IRQS: 524544, nr_irqs: 440, preallocated irqs: 16 349s [ 0.035570] rcu: srcu_init: Setting srcu_struct sizes based on contention. 349s [ 0.044839] Console: colour VGA+ 80x25 349s [ 0.044843] printk: legacy console [tty0] enabled 349s [ 0.080163] printk: legacy console [ttyS0] enabled 349s [ 0.185298] ACPI: Core revision 20250404 349s [ 0.186174] APIC: Switch to symmetric I/O mode setup 349s [ 0.187286] x2apic enabled 349s [ 0.188117] APIC: Switched APIC routing to: physical x2apic 349s [ 0.190218] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 349s [ 0.191290] tsc: Marking TSC unstable due to TSCs unsynchronized 349s [ 0.192322] Calibrating delay loop (skipped) preset value.. 4792.80 BogoMIPS (lpj=2396400) 349s [ 0.193781] x86/cpu: User Mode Instruction Prevention (UMIP) activated 349s [ 0.194661] Last level iTLB entries: 4KB 512, 2MB 255, 4MB 127 349s [ 0.195571] Last level dTLB entries: 4KB 512, 2MB 255, 4MB 127, 1GB 0 349s [ 0.197326] mitigations: Enabled attack vectors: user_kernel, user_user, guest_host, guest_guest, SMT mitigations: auto 349s [ 0.198755] Speculative Store Bypass: Vulnerable 349s [ 0.199538] Spectre V2 : Mitigation: Retpolines 349s [ 0.200540] RETBleed: Mitigation: untrained return thunk 349s [ 0.201558] Speculative Return Stack Overflow: Mitigation: SMT disabled 349s [ 0.202588] Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization 349s [ 0.204319] Spectre V2 : Spectre v2 / SpectreRSB: Filling RSB on context switch and VMEXIT 349s [ 0.205688] Spectre V2 : Enabling Speculation Barrier for firmware calls 349s [ 0.206586] active return thunk: retbleed_return_thunk 349s [ 0.208321] Spectre V2 : mitigation: Enabling conditional Indirect Branch Prediction Barrier 349s [ 0.209705] x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' 349s [ 0.211319] x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' 349s [ 0.212594] x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' 349s [ 0.213599] x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 349s [ 0.214594] x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'compacted' format. 349s [ 0.240620] Freeing SMP alternatives memory: 52K 349s [ 0.241321] pid_max: default: 32768 minimum: 301 349s [ 0.242360] LSM: initializing lsm=lockdown,capability,landlock,yama,apparmor,ima,evm 349s [ 0.243706] landlock: Up and running. 349s [ 0.244527] Yama: becoming mindful. 349s [ 0.248340] AppArmor: AppArmor initialized 349s [ 0.249156] Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) 349s [ 0.250321] Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) 349s [ 0.354391] smpboot: CPU0: AMD EPYC-Rome Processor (family: 0x17, model: 0x31, stepping: 0x0) 349s [ 0.355912] Performance Events: Fam17h+ core perfctr, AMD PMU driver. 349s [ 0.357322] ... version: 0 349s [ 0.358539] ... bit width: 48 349s [ 0.359319] ... generic registers: 6 349s [ 0.360085] ... value mask: 0000ffffffffffff 349s [ 0.360580] ... max period: 00007fffffffffff 349s [ 0.361574] ... fixed-purpose events: 0 349s [ 0.362536] ... event mask: 000000000000003f 349s [ 0.363633] signal: max sigframe size: 1776 349s [ 0.364574] rcu: Hierarchical SRCU implementation. 349s [ 0.365561] rcu: Max phase no-delay instances is 400. 349s [ 0.366605] Timer migration: 1 hierarchy levels; 8 children per group; 1 crossnode level 349s [ 0.370269] smp: Bringing up secondary CPUs ... 349s [ 0.370667] smpboot: x86: Booting SMP configuration: 349s [ 0.371563] .... node #0, CPUs: #1 349s [ 0.372354] smp: Brought up 1 node, 2 CPUs 349s [ 0.374102] smpboot: Total of 2 processors activated (9585.60 BogoMIPS) 349s [ 0.375494] Memory: 3920240K/4193772K available (22760K kernel code, 4660K rwdata, 16316K rodata, 5140K init, 4328K bss, 266296K reserved, 0K cma-reserved) 349s [ 0.376955] devtmpfs: initialized 349s [ 0.377562] x86/mm: Memory block size: 128MB 349s [ 0.378857] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns 349s [ 0.379759] posixtimers hash table entries: 1024 (order: 2, 16384 bytes, linear) 349s [ 0.381322] futex hash table entries: 512 (32768 bytes on 1 NUMA nodes, total 32 KiB, linear). 349s [ 0.382776] pinctrl core: initialized pinctrl subsystem 349s [ 0.383693] PM: RTC time: 08:09:56, date: 2025-10-31 349s [ 0.384859] NET: Registered PF_NETLINK/PF_ROUTE protocol family 349s [ 0.385674] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations 349s [ 0.386643] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations 349s [ 0.388338] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations 349s [ 0.389700] audit: initializing netlink subsys (disabled) 349s [ 0.390603] audit: type=2000 audit(1761898196.957:1): state=initialized audit_enabled=0 res=1 349s [ 0.390603] thermal_sys: Registered thermal governor 'fair_share' 349s [ 0.391717] thermal_sys: Registered thermal governor 'bang_bang' 349s [ 0.392588] thermal_sys: Registered thermal governor 'step_wise' 349s [ 0.393592] thermal_sys: Registered thermal governor 'user_space' 349s [ 0.394590] thermal_sys: Registered thermal governor 'power_allocator' 349s [ 0.395603] cpuidle: using governor ladder 349s [ 0.397543] cpuidle: using governor menu 349s [ 0.399376] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 349s [ 0.400432] PCI: Using configuration type 1 for base access 349s [ 0.401581] PCI: Using configuration type 1 for extended access 349s [ 0.402666] kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. 349s [ 0.420402] HugeTLB: allocation took 0ms with hugepage_allocation_threads=1 349s [ 0.421636] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages 349s [ 0.422618] HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page 349s [ 0.423613] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages 349s [ 0.425620] HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page 349s [ 0.427041] ACPI: Added _OSI(Module Device) 349s [ 0.427555] ACPI: Added _OSI(Processor Device) 349s [ 0.428551] ACPI: Added _OSI(Processor Aggregator Device) 349s [ 0.430422] ACPI: 1 ACPI AML tables successfully acquired and loaded 349s [ 0.432414] ACPI: Interpreter enabled 349s [ 0.433548] ACPI: PM: (supports S0 S3 S4 S5) 349s [ 0.434543] ACPI: Using IOAPIC for interrupt routing 349s [ 0.435387] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug 349s [ 0.436738] PCI: Using E820 reservations for host bridge windows 349s [ 0.438328] ACPI: Enabled 2 GPEs in block 00 to 0F 349s [ 0.440595] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) 349s [ 0.441599] acpi PNP0A03:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI EDR HPX-Type3] 349s [ 0.443979] acpiphp: Slot [3] registered 349s [ 0.444561] acpiphp: Slot [4] registered 349s [ 0.445545] acpiphp: Slot [5] registered 349s [ 0.446332] acpiphp: Slot [6] registered 349s [ 0.447080] acpiphp: Slot [7] registered 349s [ 0.447552] acpiphp: Slot [8] registered 349s [ 0.448546] acpiphp: Slot [9] registered 349s [ 0.449330] acpiphp: Slot [10] registered 349s [ 0.450111] acpiphp: Slot [11] registered 349s [ 0.450553] acpiphp: Slot [12] registered 349s [ 0.451553] acpiphp: Slot [13] registered 349s [ 0.452550] acpiphp: Slot [14] registered 349s [ 0.453331] acpiphp: Slot [15] registered 349s [ 0.454105] acpiphp: Slot [16] registered 349s [ 0.454560] acpiphp: Slot [17] registered 349s [ 0.455556] acpiphp: Slot [18] registered 349s [ 0.456546] acpiphp: Slot [19] registered 349s [ 0.457330] acpiphp: Slot [20] registered 349s [ 0.458111] acpiphp: Slot [21] registered 349s [ 0.458557] acpiphp: Slot [22] registered 349s [ 0.459545] acpiphp: Slot [23] registered 349s [ 0.460330] acpiphp: Slot [24] registered 349s [ 0.461108] acpiphp: Slot [25] registered 349s [ 0.461553] acpiphp: Slot [26] registered 349s [ 0.462547] acpiphp: Slot [27] registered 349s [ 0.463330] acpiphp: Slot [28] registered 349s [ 0.464103] acpiphp: Slot [29] registered 349s [ 0.464548] acpiphp: Slot [30] registered 349s [ 0.465553] acpiphp: Slot [31] registered 349s [ 0.466551] PCI host bridge to bus 0000:00 349s [ 0.467321] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] 349s [ 0.468606] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] 349s [ 0.469605] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] 349s [ 0.470687] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] 349s [ 0.472319] pci_bus 0000:00: root bus resource [mem 0xc000000000-0xc07fffffff window] 349s [ 0.474321] pci_bus 0000:00: root bus resource [bus 00-ff] 349s [ 0.475463] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 conventional PCI endpoint 349s [ 0.477562] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 conventional PCI endpoint 349s [ 0.479509] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 conventional PCI endpoint 349s [ 0.481895] pci 0000:00:01.1: BAR 4 [io 0xc140-0xc14f] 349s [ 0.482748] pci 0000:00:01.1: BAR 0 [io 0x01f0-0x01f7]: legacy IDE quirk 349s [ 0.484319] pci 0000:00:01.1: BAR 1 [io 0x03f6]: legacy IDE quirk 349s [ 0.485319] pci 0000:00:01.1: BAR 2 [io 0x0170-0x0177]: legacy IDE quirk 349s [ 0.486605] pci 0000:00:01.1: BAR 3 [io 0x0376]: legacy IDE quirk 349s [ 0.487741] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 conventional PCI endpoint 349s [ 0.489626] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI 349s [ 0.490695] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB 349s [ 0.492502] pci 0000:00:02.0: [1b36:0100] type 00 class 0x030000 conventional PCI endpoint 349s [ 0.497329] pci 0000:00:02.0: BAR 0 [mem 0xf4000000-0xf7ffffff] 349s [ 0.498327] pci 0000:00:02.0: BAR 1 [mem 0xf8000000-0xfbffffff] 349s [ 0.499326] pci 0000:00:02.0: BAR 2 [mem 0xfc090000-0xfc091fff] 349s [ 0.500326] pci 0000:00:02.0: BAR 3 [io 0xc100-0xc11f] 349s [ 0.501338] pci 0000:00:02.0: ROM [mem 0xfc080000-0xfc08ffff pref] 349s [ 0.502633] pci 0000:00:02.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff] 349s [ 0.504949] pci 0000:00:03.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint 349s [ 0.508106] pci 0000:00:03.0: BAR 0 [io 0xc000-0xc03f] 349s [ 0.508586] pci 0000:00:03.0: BAR 1 [mem 0xfc092000-0xfc092fff] 349s [ 0.509607] pci 0000:00:03.0: BAR 4 [mem 0xc000000000-0xc000003fff 64bit pref] 349s [ 0.511326] pci 0000:00:03.0: ROM [mem 0xfc000000-0xfc07ffff pref] 349s [ 0.513599] pci 0000:00:04.0: [1af4:1004] type 00 class 0x010000 conventional PCI endpoint 349s [ 0.517327] pci 0000:00:04.0: BAR 0 [io 0xc040-0xc07f] 349s [ 0.517588] pci 0000:00:04.0: BAR 1 [mem 0xfc093000-0xfc093fff] 349s [ 0.518616] pci 0000:00:04.0: BAR 4 [mem 0xc000004000-0xc000007fff 64bit pref] 349s [ 0.521399] pci 0000:00:05.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint 349s [ 0.524327] pci 0000:00:05.0: BAR 0 [io 0xc080-0xc0bf] 349s [ 0.525580] pci 0000:00:05.0: BAR 1 [mem 0xfc094000-0xfc094fff] 349s [ 0.527325] pci 0000:00:05.0: BAR 4 [mem 0xc000008000-0xc00000bfff 64bit pref] 349s [ 0.529598] pci 0000:00:06.0: [1af4:1002] type 00 class 0x00ff00 conventional PCI endpoint 349s [ 0.531938] pci 0000:00:06.0: BAR 0 [io 0xc0c0-0xc0ff] 349s [ 0.533327] pci 0000:00:06.0: BAR 1 [mem 0xfc095000-0xfc095fff] 349s [ 0.534610] pci 0000:00:06.0: BAR 4 [mem 0xc00000c000-0xc00000ffff 64bit pref] 349s [ 0.537196] pci 0000:00:07.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint 349s [ 0.539769] pci 0000:00:07.0: BAR 0 [io 0xc120-0xc13f] 349s [ 0.540580] pci 0000:00:07.0: BAR 1 [mem 0xfc096000-0xfc096fff] 349s [ 0.541612] pci 0000:00:07.0: BAR 4 [mem 0xc000010000-0xc000013fff 64bit pref] 349s [ 0.551053] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 349s [ 0.551697] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 349s [ 0.553406] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 349s [ 0.554402] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 349s [ 0.555639] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 349s [ 0.557326] iommu: Default domain type: Translated 349s [ 0.557565] iommu: DMA domain TLB invalidation policy: lazy mode 349s [ 0.559435] SCSI subsystem initialized 349s [ 0.563349] ACPI: bus type USB registered 349s [ 0.564330] usbcore: registered new interface driver usbfs 349s [ 0.565324] usbcore: registered new interface driver hub 349s [ 0.566322] usbcore: registered new device driver usb 349s [ 0.567335] pps_core: LinuxPPS API ver. 1 registered 349s [ 0.568251] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti 349s [ 0.569733] PTP clock support registered 349s [ 0.570564] EDAC MC: Ver: 3.0.0 349s [ 0.571484] NetLabel: Initializing 349s [ 0.572320] NetLabel: domain hash size = 128 349s [ 0.573140] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO 349s [ 0.574592] NetLabel: unlabeled traffic allowed by default 349s [ 0.575354] mctp: management component transport protocol core 349s [ 0.576319] NET: Registered PF_MCTP protocol family 349s [ 0.577325] PCI: Using ACPI for IRQ routing 349s [ 0.578288] pci 0000:00:02.0: vgaarb: setting as boot VGA device 349s [ 0.578318] pci 0000:00:02.0: vgaarb: bridge control possible 349s [ 0.578318] pci 0000:00:02.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none 349s [ 0.581319] vgaarb: loaded 349s [ 0.582539] clocksource: Switched to clocksource kvm-clock 349s [ 0.583478] VFS: Disk quotas dquot_6.6.0 349s [ 0.584276] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) 349s [ 0.586067] AppArmor: AppArmor Filesystem Enabled 349s [ 0.586971] pnp: PnP ACPI init 349s [ 0.587794] pnp: PnP ACPI: found 5 devices 349s [ 0.593981] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns 349s [ 0.595628] NET: Registered PF_INET protocol family 349s [ 0.596660] IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) 349s [ 0.608354] tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) 349s [ 0.609946] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) 349s [ 0.611373] TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) 349s [ 0.612864] TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, linear) 349s [ 0.614261] TCP: Hash tables configured (established 32768 bind 32768) 349s [ 0.615462] MPTCP token hash table entries: 4096 (order: 4, 98304 bytes, linear) 349s [ 0.616857] UDP hash table entries: 2048 (order: 5, 131072 bytes, linear) 349s [ 0.618070] UDP-Lite hash table entries: 2048 (order: 5, 131072 bytes, linear) 349s [ 0.619441] NET: Registered PF_UNIX/PF_LOCAL protocol family 349s [ 0.620471] NET: Registered PF_XDP protocol family 349s [ 0.621375] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] 349s [ 0.622485] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] 349s [ 0.624212] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] 349s [ 0.625435] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] 349s [ 0.626659] pci_bus 0000:00: resource 8 [mem 0xc000000000-0xc07fffffff window] 349s [ 0.628030] pci 0000:00:01.0: PIIX3: Enabling Passive Release 349s [ 0.629082] pci 0000:00:00.0: Limiting direct PCI/PCI transfers 349s [ 0.630224] PCI: CLS 0 bytes, default 64 349s [ 0.631056] Trying to unpack rootfs image as initramfs... 349s [ 0.631844] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) 349s [ 0.633210] software IO TLB: mapped [mem 0x00000000bbfdd000-0x00000000bffdd000] (64MB) 349s [ 0.649944] Initialise system trusted keyrings 349s [ 0.650867] Key type blacklist registered 349s [ 0.651687] workingset: timestamp_bits=36 max_order=20 bucket_order=0 349s [ 0.653888] squashfs: version 4.0 (2009/01/31) Phillip Lougher 349s [ 0.655038] fuse: init (API version 7.44) 349s [ 0.655919] integrity: Platform Keyring initialized 349s [ 0.656821] integrity: Machine keyring initialized 349s [ 0.665076] Key type asymmetric registered 349s [ 0.665864] Asymmetric key parser 'x509' registered 349s [ 0.666803] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242) 349s [ 0.668215] io scheduler mq-deadline registered 349s [ 0.669990] ledtrig-cpu: registered to indicate activity on CPUs 349s [ 0.671204] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 349s [ 0.672632] ACPI: button: Power Button [PWRF] 349s [ 0.674193] ACPI: \_SB_.LNKC: Enabled at IRQ 11 349s [ 0.676588] ACPI: \_SB_.LNKD: Enabled at IRQ 10 349s [ 0.678956] ACPI: \_SB_.LNKA: Enabled at IRQ 10 349s [ 0.681203] ACPI: \_SB_.LNKB: Enabled at IRQ 11 349s [ 0.685787] Serial: 8250/16550 driver, 32 ports, IRQ sharing enabled 349s [ 0.710446] 00:00: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A 349s [ 0.713854] Freeing initrd memory: 71460K 349s [ 0.724080] Linux agpgart interface v0.103 349s [ 0.725665] loop: module loaded 349s [ 0.726615] virtio_scsi virtio1: 2/0/0 default/read/poll queues 349s [ 0.728538] scsi host0: Virtio SCSI HBA 349s [ 0.730679] scsi host1: ata_piix 349s [ 0.731411] scsi 0:0:0:0: Direct-Access QEMU QEMU HARDDISK 2.5+ PQ: 0 ANSI: 5 349s [ 0.733016] scsi host2: ata_piix 349s [ 0.733726] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc140 irq 14 lpm-pol 0 349s [ 0.735162] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc148 irq 15 lpm-pol 0 349s [ 0.736645] ACPI: bus type drm_connector registered 349s [ 0.738014] tun: Universal TUN/TAP device driver, 1.6 349s [ 0.741580] PPP generic driver version 2.4.2 349s [ 0.742608] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 349s [ 0.745389] serio: i8042 KBD port at 0x60,0x64 irq 1 349s [ 0.746392] serio: i8042 AUX port at 0x60,0x64 irq 12 349s [ 0.747549] mousedev: PS/2 mouse device common for all mice 349s [ 0.748897] rtc_cmos 00:04: RTC can wake from S4 349s [ 0.750005] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 349s [ 0.752732] rtc_cmos 00:04: registered as rtc0 349s [ 0.753993] rtc_cmos 00:04: setting system clock to 2025-10-31T08:09:56 UTC (1761898196) 349s [ 0.756118] rtc_cmos 00:04: alarms up to one day, y3k, 242 bytes nvram 349s [ 0.757014] sd 0:0:0:0: Power-on or device reset occurred 349s [ 0.757305] i2c_dev: i2c /dev entries driver 349s [ 0.758048] sd 0:0:0:0: Attached scsi generic sg0 type 0 349s [ 0.758641] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. 349s [ 0.759399] sd 0:0:0:0: [sda] 41943040 512-byte logical blocks: (21.5 GB/20.0 GiB) 349s [ 0.761008] device-mapper: uevent: version 1.0.3 349s [ 0.762076] sd 0:0:0:0: [sda] Write Protect is off 349s [ 0.762682] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev 349s [ 0.764498] amd_pstate: the _CPC object is not present in SBIOS or ACPI disabled 349s [ 0.764861] sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA 349s [ 0.765895] drop_monitor: Initializing network drop monitor service 349s [ 0.771740] NET: Registered PF_INET6 protocol family 349s [ 0.773361] Segment Routing with IPv6 349s [ 0.774195] sda: sda1 sda13 sda14 sda15 349s [ 0.774389] In-situ OAM (IOAM) with IPv6 349s [ 0.775010] sd 0:0:0:0: [sda] Attached SCSI disk 349s [ 0.775485] NET: Registered PF_PACKET protocol family 349s [ 0.777799] Key type dns_resolver registered 349s [ 0.778828] IPI shorthand broadcast: enabled 349s [ 0.780659] sched_clock: Marking stable (627005377, 152741733)->(784298197, -4551087) 349s [ 0.782273] registered taskstats version 1 349s [ 0.783252] Loading compiled-in X.509 certificates 349s [ 0.784571] Loaded X.509 cert 'Build time autogenerated kernel key: cabcb7c9e02399f9ce4787cbfa1c3917f4396e58' 349s [ 0.786691] Loaded X.509 cert 'Canonical Ltd. Live Patch Signing 2025 Kmod: d541cef61dc7e793b7eb7e899970a2eef0b5dc8c' 349s [ 0.789080] Loaded X.509 cert 'Canonical Ltd. Live Patch Signing: 14df34d1a87cf37625abec039ef2bf521249b969' 349s [ 0.791172] Loaded X.509 cert 'Canonical Ltd. Kernel Module Signing: 88f752e560a1e0737e31163a466ad7b70a850c19' 349s [ 0.792987] blacklist: Loading compiled-in revocation X.509 certificates 349s [ 0.794205] Loaded X.509 cert 'Canonical Ltd. Secure Boot Signing: 61482aa2830d0ab2ad5af10b7250da9033ddcef0' 349s [ 0.795991] Loaded X.509 cert 'Canonical Ltd. Secure Boot Signing (2017): 242ade75ac4a15e50d50c84b0d45ff3eae707a03' 349s [ 0.797944] Loaded X.509 cert 'Canonical Ltd. Secure Boot Signing (ESM 2018): 365188c1d374d6b07c3c8f240f8ef722433d6a8b' 349s [ 0.799883] Loaded X.509 cert 'Canonical Ltd. Secure Boot Signing (2019): c0746fd6c5da3ae827864651ad66ae47fe24b3e8' 349s [ 0.801740] Loaded X.509 cert 'Canonical Ltd. Secure Boot Signing (2021 v1): a8d54bbb3825cfb94fa13c9f8a594a195c107b8d' 349s [ 0.804329] Loaded X.509 cert 'Canonical Ltd. Secure Boot Signing (2021 v2): 4cf046892d6fd3c9a5b03f98d845f90851dc6a8c' 349s [ 0.806249] Loaded X.509 cert 'Canonical Ltd. Secure Boot Signing (2021 v3): 100437bb6de6e469b581e61cd66bce3ef4ed53af' 349s [ 0.808165] Loaded X.509 cert 'Canonical Ltd. Secure Boot Signing (Ubuntu Core 2019): c1d57b8f6b743f23ee41f4f7ee292f06eecadfb9' 349s [ 0.812314] Demotion targets for Node 0: null 349s [ 0.813266] Key type .fscrypt registered 349s [ 0.814044] Key type fscrypt-provisioning registered 349s [ 0.815028] Key type big_key registered 349s [ 0.821878] Key type encrypted registered 349s [ 0.822684] AppArmor: AppArmor sha256 policy hashing enabled 349s [ 0.823771] ima: No TPM chip found, activating TPM-bypass! 349s [ 0.824788] Loading compiled-in module X.509 certificates 349s [ 0.826110] Loaded X.509 cert 'Build time autogenerated kernel key: cabcb7c9e02399f9ce4787cbfa1c3917f4396e58' 349s [ 0.827909] ima: Allocated hash algorithm: sha256 349s [ 0.828823] ima: No architecture policies found 349s [ 0.829688] evm: Initialising EVM extended attributes: 349s [ 0.830652] evm: security.selinux 349s [ 0.831303] evm: security.SMACK64 349s [ 0.835693] evm: security.SMACK64EXEC 349s [ 0.836430] evm: security.SMACK64TRANSMUTE 349s [ 0.837254] evm: security.SMACK64MMAP 349s [ 0.837982] evm: security.apparmor 349s [ 0.838664] evm: security.ima 349s [ 0.839277] evm: security.capability 349s [ 0.839988] evm: HMAC attrs: 0x1 349s [ 0.840870] PM: Magic number: 13:817:170 349s [ 0.842501] RAS: Correctable Errors collector initialized. 349s [ 0.846730] clk: Disabling unused clocks 349s [ 0.847503] PM: genpd: Disabling unused power domains 349s [ 0.894880] Freeing unused decrypted memory: 2028K 349s [ 0.896008] Freeing unused kernel image (initmem) memory: 5140K 349s [ 0.896914] Write protecting the kernel read-only data: 40960k 349s [ 0.898093] Freeing unused kernel image (text/rodata gap) memory: 1812K 349s [ 0.899121] Freeing unused kernel image (rodata/data gap) memory: 68K 349s [ 0.928023] x86/mm: Checked W+X mappings: passed, no W+X pages found. 349s [ 0.928988] Run /init as init process 349s Loading, please wait... 349s Starting systemd-udevd version 257.9-0ubuntu2 349s [ 0.978635] virtio_net virtio0 ens3: renamed from eth0 349s [ 0.987361] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 349s [ 0.990995] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 349s [ 1.011276] FDC 0 is a S82078B 349s Begin: Loading essential drivers ... done. 349s Begin: Running /scripts/init-premount ... done. 349s Begin: Mounting root file system ... Begin: Running /scripts/local-top ... done. 349s Begin: Running /scripts/local-premount ... done. 349s Begin: Will now check root file system ... fsck from util-linux 2.41 349s [/usr/sbin/fsck.ext4 (1) -- /dev/sda1] fsck.ext4 -a -C0 /dev/sda1 349s cloudimg-rootfs: clean, 125768/2383360 files, 853777/4952315 blocks 349s done. 349s [ 2.148146] EXT4-fs (sda1): orphan cleanup on readonly fs 349s [ 2.149124] EXT4-fs (sda1): mounted filesystem 5b34d3dd-a752-4305-8452-98500dfa2307 ro with ordered data mode. Quota mode: none. 349s done. 349s Begin: Running /scripts/local-bottom ... done. 349s Begin: Running /scripts/init-bottom ... done. 349s [ 2.247627] systemd[1]: Inserted module 'autofs4' 349s [!p]104[?7h[ 2.260077] systemd[1]: systemd 257.9-0ubuntu2 running in system mode (+PAM +AUDIT +SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK +BTF -XKBCOMMON -UTMP +SYSVINIT +LIBARCHIVE) 349s [ 2.264801] systemd[1]: Detected virtualization kvm. 349s [ 2.265579] systemd[1]: Detected architecture x86-64. 349s 349s [0;1;39mWelcome to [0m[1mUbuntu 25.10[0m[0;1;39m![0m 349s 349s [ 2.267868] systemd[1]: Hostname set to . 349s [ 2.305811] systemd[1]: bpf-restrict-fs: BPF LSM hook not enabled in the kernel, BPF LSM not supported. 349s [ 2.328527] Guest personality initialized and is inactive 349s [ 2.330077] VMCI host device registered (name=vmci, major=10, minor=261) 349s [ 2.331237] Initialized host personality 349s [ 2.334257] NET: Registered PF_VSOCK protocol family 349s [ 2.443471] systemd[1]: Queued start job for default target graphical.target. 349s [ 2.452638] systemd[1]: Created slice system-autopkgtest.slice - Slice /system/autopkgtest. 349s [[0;32m OK [0m] Created slice [0;1;39msystem-autopkgtest.slice[0m - Slice /system/autopkgtest. 349s [ 2.455994] systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. 349s [[0;32m OK [0m] Created slice [0;1;39msystem-modprobe.slice[0m - Slice /system/modprobe. 349s [ 2.458666] systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. 349s [[0;32m OK [0m] Created slice [0;1;39msystem-serial\x2dget���slice[0m - Slice /system/serial-getty. 349s [ 2.461597] systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. 349s [[0;32m OK [0m] Created slice [0;1;39msystem-systemd\x2dfs���slice[0m - Slice /system/systemd-fsck. 349s [ 2.464452] systemd[1]: Created slice user.slice - User and Session Slice. 349s [[0;32m OK [0m] Created slice [0;1;39muser.slice[0m - User and Session Slice. 349s [ 2.466582] systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. 349s [[0;32m OK [0m] Started [0;1;39msystemd-ask-password-wall.���[0md Requests to Wall Directory Watch. 349s [ 2.469552] systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. 349s [[0;32m OK [0m] Set up automount [0;1;39mproc-sys-fs-binfm���[0mormats File System Automount Point. 349s [ 2.472665] systemd[1]: Expecting device dev-disk-by\x2dlabel-BOOT.device - /dev/disk/by-label/BOOT... 349s Expecting device [0;1;39mdev-disk-by\x2dla���device[0m - /dev/disk/by-label/BOOT... 349s [ 2.475241] systemd[1]: Expecting device dev-disk-by\x2dlabel-UEFI.device - /dev/disk/by-label/UEFI... 349s Expecting device [0;1;39mdev-disk-by\x2dla���device[0m - /dev/disk/by-label/UEFI... 349s [ 2.477855] systemd[1]: Expecting device dev-ttyS0.device - /dev/ttyS0... 349s Expecting device [0;1;39mdev-ttyS0.device[0m - /dev/ttyS0... 349s [ 2.479841] systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. 349s [[0;32m OK [0m] Reached target [0;1;39mintegritysetup.targ���[0m Local Integrity Protected Volumes. 349s [ 2.482548] systemd[1]: Reached target remote-fs.target - Remote File Systems. 349s [[0;32m OK [0m] Reached target [0;1;39mremote-fs.target[0m - Remote File Systems. 349s [ 2.485358] systemd[1]: Reached target slices.target - Slice Units. 349s [[0;32m OK [0m] Reached target [0;1;39mslices.target[0m - Slice Units. 349s [ 2.487327] systemd[1]: Reached target swap.target - Swaps. 349s [[0;32m OK [0m] Reached target [0;1;39mswap.target[0m - Swaps. 349s [ 2.489122] systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. 349s [[0;32m OK [0m] Reached target [0;1;39mveritysetup.target[0m - Local Verity Protected Volumes. 349s [ 2.491788] systemd[1]: Listening on syslog.socket - Syslog Socket. 349s [[0;32m OK [0m] Listening on [0;1;39msyslog.socket[0m - Syslog Socket. 349s [ 2.494514] systemd[1]: Listening on systemd-creds.socket - Credential Encryption/Decryption. 349s [[0;32m OK [0m] Listening on [0;1;39msystemd-creds.socket[0m - Credential Encryption/Decryption. 349s [ 2.497144] systemd[1]: Listening on systemd-initctl.socket - initctl Compatibility Named Pipe. 349s [[0;32m OK [0m] Listening on [0;1;39msystemd-initctl.socke���[0m- initctl Compatibility Named Pipe. 349s [ 2.499819] systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). 349s [[0;32m OK [0m] Listening on [0;1;39msystemd-journald-dev-���socket[0m - Journal Socket (/dev/log). 349s [ 2.502578] systemd[1]: Listening on systemd-journald.socket - Journal Sockets. 349s [[0;32m OK [0m] Listening on [0;1;39msystemd-journald.socket[0m - Journal Sockets. 349s [ 2.504988] systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. 349s [[0;32m OK [0m] Listening on [0;1;39msystemd-networkd.socket[0m - Network Service Netlink Socket. 349s [ 2.507609] systemd[1]: systemd-pcrextend.socket - TPM PCR Measurements was skipped because of an unmet condition check (ConditionSecurity=measured-uki). 349s [ 2.509543] systemd[1]: systemd-pcrlock.socket - Make TPM PCR Policy was skipped because of an unmet condition check (ConditionSecurity=measured-uki). 349s [ 2.511533] systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. 349s [[0;32m OK [0m] Listening on [0;1;39msystemd-udevd-control.socket[0m - udev Control Socket. 349s [ 2.514589] systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. 349s [[0;32m OK [0m] Listening on [0;1;39msystemd-udevd-kernel.socket[0m - udev Kernel Socket. 349s [ 2.517984] systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... 349s Mounting [0;1;39mdev-hugepages.mount[0m - Huge Pages File System... 349s [ 2.521805] systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... 349s Mounting [0;1;39mdev-mqueue.mount[0m - POSIX Message Queue File System... 349s [ 2.529803] systemd[1]: Mounting run-lock.mount - Legacy Locks Directory /run/lock... 349s Mounting [0;1;39mrun-lock.mount[0m - Legacy Locks Directory /run/lock... 349s [ 2.534400] systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... 349s Mounting [0;1;39msys-kernel-debug.moun[ 2.536508] systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... 349s t[0m - Kernel Debug File System... 349s Mounting [0;1;39msys-kernel-tracing.mount[0m - Kernel Trace File System... 349s [ 2.542423] systemd[1]: Starting systemd-journald.service - Journal Service... 349s Starting [0;1;39msystemd-journald.service[0m - Journal Service... 349s [ 2.546482] systemd[1]: Starting keyboard-setup.service - Set the console keyboard layout... 349s Starting [0;1;39mkeyboard-setup.service[0m - Set the console keyboard layout... 349s [ 2.552855] systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... 349s Starting [0;1;39mkmod-static-nodes.service[0m���eate List of Static Device Nodes... 349s [ 2.561609] systemd-journald[232]: Collecting audit messages is disabled. 349s [ 2.563833] systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... 349s Starting [0;1;39mmodprobe@configfs.service[0m - Load Kernel Module configfs... 349s [ 2.576812] systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... 349s Starting [0;1;39mmodprobe@drm.service[0m - Load Kernel Module drm... 349s [ 2.582634] systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... 349s Starting [0;1;39mmodprobe@efi_pstore.servi���[0m - Load Kernel Module efi_pstore... 349s [ 2.586820] systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... 349s Starting [0;1;39mmodprobe@fuse.service[0m - Load Kernel Module fuse... 349s [ 2.589865] systemd[1]: netplan-ovs-cleanup.service - OpenVSwitch configuration for cleanup was skipped because of an unmet condition check (ConditionFileIsExecutable=/usr/bin/ovs-vsctl). 349s [ 2.592388] systemd[1]: systemd-fsck-root.service - File System Check on Root Device was skipped because of an unmet condition check (ConditionPathExists=!/run/initramfs/fsck-root). 349s [ 2.594764] systemd[1]: systemd-hibernate-clear.service - Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67). 349s [ 2.598677] systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... 349s Starting [0;1;39msystemd-modules-load.service[0m - Load Kernel Modules... 349s [ 2.600887] systemd[1]: systemd-pcrmachine.service - TPM PCR Machine ID Measurement was skipped because of an unmet condition check (ConditionSecurity=measured-uki). 349s [ 2.609472] systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... 349s Starting [0;1;39msystemd-remount-fs.servic���[0munt Root and Kernel File Systems... 349s [ 2.613615] systemd[1]: systemd-tpm2-setup-early.service - Early TPM SRK Setup was skipped because of an unmet condition check (ConditionSecurity=measured-uki). 349s [ 2.616207] systemd[1]: Starting systemd-udev-load-credentials.service - Load udev Rules from Credentials... 349s [ 2.617737] EXT4-fs (sda1): re-mounted 5b34d3dd-a752-4305-8452-98500dfa2307 r/w. 349s Starting [0;1;39msystemd-udev-load-credent���[0mLoad udev Rules from Credentials... 349s [ 2.630171] systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... 349s Starting [0;1;39msystemd-udev-trigger.service[0m - Coldplug All udev Devices... 349s [ 2.634701] systemd[1]: Started systemd-journald.service - Journal Service. 349s [[0;32m OK [0m] Started [0;1;39msystemd-journald.service[0m - Journal Service. 349s [[0;32m OK [0m] Mounted [0;1;39mdev-hugepages.mount[0m - Huge Pages File System. 349s [[0;32m OK [0m] Mounted [0;1;39mdev-mqueue.mount[0m - POSIX Message Queue File System. 349s [[0;32m OK [0m] Mounted [0;1;39mrun-lock.mount[0m - Legacy Locks Directory /run/lock. 349s [[0;32m OK [0m] Mounted [0;1;39msys-kernel-debug.mount[0m - Kernel Debug File System. 349s [[0;32m OK [0m] Mounted [0;1;39msys-kernel-tracing.mount[0m - Kernel Trace File System. 349s [[0;32m OK [0m] Finished [0;1;39mkeyboard-setup.service[0m - Set the console keyboard layout. 349s [[0;32m OK [0m] Finished [0;1;39mkmod-static-nodes.service[0m���Create List of Static Device Nodes. 349s [[0;32m OK [0m] Finished [0;1;39mmodprobe@configfs.service[0m - Load Kernel Module configfs. 349s [[0;32m OK [0m] Finished [0;1;39mmodprobe@drm.service[0m - Load Kernel Module drm. 349s [[0;32m OK [0m] Finished [0;1;39mmodprobe@efi_pstore.service[0m - Load Kernel Module efi_pstore. 349s [[0;32m OK [0m] Finished [0;1;39mmodprobe@fuse.service[0m - Load Kernel Module fuse. 349s [[0;32m OK [0m] Finished [0;1;39msystemd-modules-load.service[0m - Load Kernel Modules. 349s [[0;32m OK [0m] Finished [0;1;39msystemd-remount-fs.servic���[0mmount Root and Kernel File Systems. 349s [[0;32m OK [0m] Finished [0;1;39msystemd-udev-load-credent���[0m- Load udev Rules from Credentials. 349s Mounting [0;1;39msys-fs-fuse-connections.mount[0m - FUSE Control File System... 349s Mounting [0;1;39msys-kernel-config.mount[0m - Kernel Configuration File System... 349s Starting [0;1;39mcloud-init-main.service[0m - Cloud-init: Single Process... 349s Starting [0;1;39msystemd-journal-flush.ser���[0msh Journal to Persistent Storage... 349s Starting [0;1;39msystemd-random-seed.service[0m - Load/Save OS Random Seed... 349s Starting [0;1;39msystemd-sysctl.service[0m - Apply Kernel Variables... 349s [ 2.686176] systemd-journald[232]: Received client request to flush runtime journal. 349s Starting [0;1;39msystemd-tmpfiles-setup-de���[0m Device Nodes in /dev gracefully... 349s [[0;32m OK [0m] Finished [0;1;39msystemd-udev-trigger.service[0m - Coldplug All udev Devices. 349s [[0;32m OK [0m] Mounted [0;1;39msys-fs-fuse-connections.mount[0m - FUSE Control File System. 349s [[0;32m OK [0m] Mounted [0;1;39msys-kernel-config.mount[0m - Kernel Configuration File System. 349s [[0;32m OK [0m] Finished [0;1;39msystemd-journal-flush.ser���[0mlush Journal to Persistent Storage. 349s [[0;32m OK [0m] Finished [0;1;39msystemd-tmpfiles-setup-de���[0mic Device Nodes in /dev gracefully. 349s [[0;32m OK [0m] Finished [0;1;39msystemd-sysctl.service[0m - Apply Kernel Variables. 349s Starting [0;1;39msystemd-resolved.service[0m - Network Name Resolution... 349s Starting [0;1;39msystemd-tmpfiles-setup-de���[0meate Static Device Nodes in /dev... 349s [[0;32m OK [0m] Finished [0;1;39msystemd-random-seed.service[0m - Load/Save OS Random Seed. 349s [[0;32m OK [0m] Finished [0;1;39msystemd-tmpfiles-setup-de���[0mCreate Static Device Nodes in /dev. 349s [[0;32m OK [0m] Reached target [0;1;39mlocal-fs-pre.target[0m���Preparation for Local File Systems. 349s Starting [0;1;39msystemd-udevd.service[0m - R���ager for Device Events and Files... 349s [[0;32m OK [0m] Started [0;1;39msystemd-udevd.service[0m - Ru���anager for Device Events and Files. 349s [[0;32m OK [0m] Started [0;1;39msystemd-ask-password-conso���[0mequests to Console Directory Watch. 349s [[0;32m OK [0m] Reached target [0;1;39mcryptsetup.target[0m - Local Encrypted Volumes. 349s [[0;32m OK [0m] Started [0;1;39msystemd-resolved.service[0m - Network Name Resolution. 349s [[0;32m OK [0m] Reached target [0;1;39mnss-lookup.target[0m - Host and Network Name Lookups. 349s [[0;32m OK [0m] Found device [0;1;39mdev-ttyS0.device[0m - /dev/ttyS0. 349s [[0;32m OK [0m] Found device [0;1;39mdev-disk-by\x2dlabel-UEFI.device[0m - QEMU_HARDDISK UEFI. 349s [[0;32m OK [0m] Found device [0;1;39mdev-disk-by\x2dlabel-BOOT.device[0m - QEMU_HARDDISK BOOT. 349s Starting [0;1;39msystemd-fsck@dev-disk-by\���[0mCheck on /dev/disk/by-label/BOOT... 349s Starting [0;1;39msystemd-fsck@dev-disk-by\���[0mCheck on /dev/disk/by-label/UEFI... 349s [[0;32m OK [0m] Listening on [0;1;39msystemd-rfkill.socket[0m���ll Switch Status /dev/rfkill Watch. 349s [[0;32m OK [0m] Finished [0;1;39msystemd-fsck@dev-disk-by\���[0mm Check on /dev/disk/by-label/BOOT. 349s [[0;32m OK [0m] Finished [0;1;39msystemd-fsck@dev-disk-by\���[0mm Check on /dev/disk/by-label/UEFI. 349s [[0;32m OK [0m] Started [0;1;39mcloud-init-main.service[0m - Cloud-init: Single Process. 349s Starting [0;1;39mcloud-init-local.service[0m ���-init: Local Stage (pre-network)... 349s [ 3.083254] cloud-init[371]: Cloud-init v. 25.3~2g890873f5-0ubuntu2 running 'init-local' at Fri, 31 Oct 2025 08:09:58 +0000. Up 3.07 seconds. 349s Mounting [0;1;39mboot.mount[0m - /boot... 349s [[0;32m OK [0m] Mounted [0;1;39mboot.mount[0m - /boot. 349s Mounting [0;1;39mboot-efi.mount[0m - /boot/efi... 349s [[0;32m OK [0m] Mounted [0;1;39mboot-efi.mount[0m - /boot/efi. 349s [[0;32m OK [0m] Reached target [0;1;39mlocal-fs.target[0m - Local File Systems. 349s [[0;32m OK [0m] Listening on [0;1;39msystemd-sysext.socket[0m��� System Extension Image Management. 349s Starting [0;1;39mapparmor.service[0m - Load AppArmor profiles... 349s Starting [0;1;39mconsole-setup.service[0m - Set console font and keymap... 349s Starting [0;1;39mplymouth-read-write.servi���[0mymouth To Write Out Runtime Data... 349s Starting [0;1;39msystemd-binfmt.service[0m - Set Up Additional Binary Formats... 349s Starting [0;1;39msystemd-tmpfiles-setup.se���[0mate System Files and Directories... 349s Starting [0;1;39mufw.service[0m - Uncomplicated firewall... 349s [[0;32m OK [0m] Finished [0;1;39mconsole-setup.service[0m - Set console font and keymap. 349s [[0;32m OK [0m] Finished [0;1;39mplymouth-read-write.servi���[0mPlymouth To Write Out Runtime Data. 349s [[0;32m OK [0m] Finished [0;1;39mufw.service[0m - Uncomplicated firewall. 349s [[0;32m OK [0m] Finished [0;1;39msystemd-tmpfiles-setup.se���[0mreate System Files and Directories. 349s Mounting [0;1;39mproc-sys-fs-binfmt_misc.m���[0mcutable File Formats File System... 349s [[0;32m OK [0m] Mounted [0;1;39mproc-sys-fs-binfmt_misc.mo���[0mxecutable File Formats File System. 349s [[0;32m OK [0m] Finished [0;1;39msystemd-binfmt.service[0m - Set Up Additional Binary Formats. 349s [[0;32m OK [0m] Finished [0;1;39mapparmor.service[0m - Load AppArmor profiles. 349s [ 8.701431] sh[367]: Completed socket interaction for boot stage local 349s [[0;32m OK [0m] Finished [0;1;39mcloud-init-local.service[0m ���ud-init: Local Stage (pre-network). 349s [[0;32m OK [0m] Reached target [0;1;39mnetwork-pre.target[0m - Preparation for Network. 349s Starting [0;1;39mcloud-init-network.service[0m - Cloud-init: Network Stage... 349s Starting [0;1;39msystemd-networkd.service[0m - Network Configuration... 349s [ 8.728156] cloud-init[371]: Cloud-init v. 25.3~2g890873f5-0ubuntu2 running 'init' at Fri, 31 Oct 2025 08:10:04 +0000. Up 8.72 seconds. 349s [ 8.736015] cloud-init[371]: ci-info: +++++++++++++++++++++++++++Net device info++++++++++++++++++++++++++++ 349s [ 8.737258] cloud-init[371]: ci-info: +--------+-------+-----------+-----------+-------+-------------------+ 349s [ 8.739141] cloud-init[371]: ci-info: | Device | Up | Address | Mask | Scope | Hw-Address | 349s [[0;32m OK [0m] Started [0;1;39msystemd-networkd.service[0m - Network Configuration. 349s [ 8.743626] cloud-init[371]: ci-info: +--------+-------+-----------+-----------+-------+-------------------+ 349s [[0;32m OK [0m] Reached target [0;1;39mnetwork.target[0m - Network. 349s [ 8.746120] cloud-init[371]: ci-info: | ens3 | False | . | . | . | fa:16:3e:d6:4b:91 | 349s Starting [0;1;39msystemd-networkd-persiste���[0mtent Storage in systemd-networkd... 349s Starting [0;1;39msystemd-networkd-wait-onl���[0mait for Network to be Configured... 349s [ 8.752046] cloud-init[371]: ci-info: | lo | True | 127.0.0.1 | 255.0.0.0 | host | . | 349s [ 8.753282] cloud-init[371]: ci-info: | lo | True | ::1/128 | . | host | . | 349s [ 8.754788] cloud-init[371]: ci-info: +--------+-------+-----------+-----------+-------+-------------------+ 349s [ 8.756915] cloud-init[371]: ci-info: +++++++++++++++++++Route IPv6 info+++++++++++++++++++ 349s [ 8.758609] cloud-init[371]: ci-info: +-------+-------------+---------+-----------+-------+ 349s [ 8.760129] cloud-init[371]: ci-info: | Route | Destination | Gateway | Interface | Flags | 349s [ 8.763146] cloud-init[371]: ci-info: +-------+-------------+---------+-----------+-------+ 349s [ 8.764577] cloud-init[371]: ci-info: +-------+-------------+---------+-----------+-------+ 349s [[0;32m OK [0m] Finished [0;1;39msystemd-networkd-persiste���[0mistent Storage in systemd-networkd. 349s [ 8.778391] cloud-init[371]: 2025-10-31 08:10:04,677 - loggers.py[DEPRECATED]: Deprecated cloud-config provided: apt_update: Deprecated in version 22.2. Use **package_update** instead., apt_upgrade: Deprecated in version 22.2. Use **package_upgrade** instead. 349s [ 8.781228] cloud-init[371]: 2025-10-31 08:10:04,677 - schema.py[WARNING]: cloud-config failed schema validation! You may run 'sudo cloud-init schema --system' to check the details. 349s [ 8.953114] sh[637]: Completed socket interaction for boot stage network 349s [[0;32m OK [0m] Finished [0;1;39mcloud-init-network.service[0m - Cloud-init: Network Stage. 349s [[0;32m OK [0m] Reached target [0;1;39mcloud-config.target[0m - Cloud-config availability. 349s [[0;32m OK [0m] Reached target [0;1;39msysinit.target[0m - System Initialization. 349s [[0;32m OK [0m] Started [0;1;39mapt-daily.timer[0m - Daily apt download activities. 349s [[0;32m OK [0m] Started [0;1;39mapt-daily-upgrade.timer[0m - ���y apt upgrade and clean activities. 349s [[0;32m OK [0m] Started [0;1;39mdpkg-db-backup.timer[0m - Daily dpkg database backup timer. 349s [[0;32m OK [0m] Started [0;1;39me2scrub_all.timer[0m - Period���Metadata Check for All Filesystems. 349s [[0;32m OK [0m] Started [0;1;39mfstrim.timer[0m - Discard unused filesystem blocks once a week. 349s [[0;32m OK [0m] Started [0;1;39mlogrotate.timer[0m - Daily rotation of log files. 349s [[0;32m OK [0m] Started [0;1;39mman-db.timer[0m - Daily man-db regeneration. 349s [[0;32m OK [0m] Started [0;1;39mmotd-news.timer[0m - Message of the Day. 349s [[0;32m OK [0m] Started [0;1;39msysstat-collect.timer[0m - Ru���y accounting tool every 10 minutes. 349s [[0;32m OK [0m] Started [0;1;39msysstat-rotate.timer[0m - Rot���tem activity data file at midnight. 349s [[0;32m OK [0m] Started [0;1;39msysstat-summary.timer[0m - Ge��� of yesterday's process accounting. 349s [[0;32m OK [0m] Started [0;1;39msystemd-tmpfiles-clean.tim���[0my Cleanup of Temporary Directories. 349s [[0;32m OK [0m] Reached target [0;1;39mboot-complete.target[0m - Boot Completion Check. 349s [[0;32m OK [0m] Reached target [0;1;39mpaths.target[0m - Path Units. 349s [[0;32m OK [0m] Reached target [0;1;39mtimers.target[0m - Timer Units. 349s [[0;32m OK [0m] Listening on [0;1;39mcloud-init-hotplugd.s���t[0m - cloud-init hotplug hook socket. 349s [[0;32m OK [0m] Listening on [0;1;39mdbus.socket[0m - D-Bus System Message Bus Socket. 349s [[0;32m OK [0m] Listening on [0;1;39mssh.socket[0m - OpenBSD Secure Shell server socket. 349s [[0;32m OK [0m] Listening on [0;1;39msshd-unix-local.socke���[0mtemd-ssh-generator, AF_UNIX Local). 349s [[0;32m OK [0m] Listening on [0;1;39msshd-vsock.socket[0m - O��� (systemd-ssh-generator, AF_VSOCK). 349s [[0;32m OK [0m] Reached target [0;1;39mssh-access.target[0m - SSH Access Available. 349s [[0;32m OK [0m] Listening on [0;1;39msystemd-hostnamed.socket[0m - Hostname Service Socket. 349s [[0;32m OK [0m] Listening on [0;1;39muuidd.socket[0m - UUID daemon activation socket. 349s [[0;32m OK [0m] Reached target [0;1;39msockets.target[0m - Socket Units. 349s [[0;32m OK [0m] Reached target [0;1;39mbasic.target[0m - Basic System. 349s [[0;32m OK [0m] Started [0;1;39mautopkgtest@ttyS1.service[0m - autopkgtest root shell on ttyS1. 349s Starting [0;1;39mchrony.service[0m - chrony, an NTP client/server... 349s [[0;32m OK [0m] Started [0;1;39mcron.service[0m - Regular background program processing daemon. 349s Starting [0;1;39mdbus.service[0m - D-Bus System Message Bus... 349s [[0;32m OK [0m] Started [0;1;39mdmesg.service[0m - Save initial kernel messages after boot. 349s Starting [0;1;39me2scrub_reap.service[0m - Re���ne ext4 Metadata Check Snapshots... 349s Starting [0;1;39mgrub-initrd-fallback.service[0m - GRUB failed boot detection... 349s Starting [0;1;39mgrub2-common.service[0m - Record successful boot for GRUB... 349s Starting [0;1;39mnetworkd-dispatcher.servi���[0mcher daemon for systemd-networkd... 349s Starting [0;1;39mrng-tools-debian.service[0m ���gatherer daemon (Debian variant)... 349s Starting [0;1;39mrsyslog.service[0m - System Logging Service... 349s Starting [0;1;39mssh.service[0m - OpenBSD Secure Shell server... 349s Starting [0;1;39msysstat.service[0m - Resets System Activity Logs... 349s Starting [0;1;39msystemd-logind.service[0m - User Login Management... 349s Starting [0;1;39msystemd-user-sessions.service[0m - Permit User Sessions... 349s [[0;32m OK [0m] Finished [0;1;39me2scrub_reap.service[0m - Re���line ext4 Metadata Check Snapshots. 349s [[0;32m OK [0m] Finished [0;1;39msystemd-user-sessions.service[0m - Permit User Sessions. 349s [[0;32m OK [0m] Finished [0;1;39mgrub-initrd-fallback.service[0m - GRUB failed boot detection. 349s [[0;32m OK [0m] Started [0;1;39msystemd-logind.service[0m - User Login Management. 349s [[0;32m OK [0m] Finished [0;1;39msysstat.service[0m - Resets System Activity Logs. 349s [[0;32m OK [0m] Started [0;1;39mssh.service[0m - OpenBSD Secure Shell server. 349s Starting [0;1;39mdpkg-db-backup.service[0m - ���ily dpkg database backup service... 349s Starting [0;1;39mplymouth-quit-wait.servic���[0md until boot process finishes up... 349s Starting [0;1;39mplymouth-quit.service[0m - Terminate Plymouth Boot Screen... 349s [[0;32m OK [0m] Started [0;1;39mdbus.service[0m - D-Bus System Message Bus. 349s [[0;32m OK [0m] Finished [0;1;39mgrub2-common.service[0m - Record successful boot for GRUB. 349s [[0;32m OK [0m] Finished [0;1;39mplymouth-quit-wait.servic���[0mold until boot process finishes up. 349s [[0;32m OK [0m] Started [0;1;39mserial-getty@ttyS0.service[0m - Serial Getty on ttyS0. 349s Starting [0;1;39msetvtrgb.service[0m - Set console scheme... 349s [[0;32m OK [0m] Started [0;1;39mchrony.service[0m - chrony, an NTP client/server. 349s [[0;32m OK [0m] Finished [0;1;39mplymouth-quit.service[0m - Terminate Plymouth Boot Screen. 349s [[0;32m OK [0m] Finished [0;1;39msetvtrgb.service[0m - Set console scheme. 349s [[0;32m OK [0m] Created slice [0;1;39msystem-getty.slice[0m - Slice /system/getty. 349s [[0;32m OK [0m] Started [0;1;39mgetty@tty1.service[0m - Getty on tty1. 349s [[0;32m OK [0m] Reached target [0;1;39mgetty.target[0m - Login Prompts. 349s [[0;32m OK [0m] Started [0;1;39mnetworkd-dispatcher.servic���[0matcher daemon for systemd-networkd. 349s [[0;32m OK [0m] Started [0;1;39mrsyslog.service[0m - System Logging Service. 349s [[0;32m OK [0m] Reached target [0;1;39mmulti-user.target[0m - Multi-User System. 349s [[0;32m OK [0m] Reached target [0;1;39mgraphical.target[0m - Graphical Interface. 349s [[0;32m OK [0m] Finished [0;1;39mdpkg-db-backup.service[0m - Daily dpkg database backup service. 349s [[0;32m OK [0m] Finished [0;1;39msystemd-networkd-wait-onl���[0m Wait for Network to be Configured. 349s [[0;32m OK [0m] Reached target [0;1;39mnetwork-online.target[0m - Network is Online. 349s Starting [0;1;39mcloud-config.service[0m - Cloud-init: Config Stage... 349s [ 10.039128] cloud-init[371]: Cloud-init v. 25.3~2g890873f5-0ubuntu2 running 'modules:config' at Fri, 31 Oct 2025 08:10:05 +0000. Up 10.02 seconds. 349s [ 10.048445] cloud-init[371]: 2025-10-31 08:10:05,947 - lifecycle.py[DEPRECATED]: The following config key(s): ['apt_mirror', 'apt_preserve_sources_list'] is deprecated in 22.1 and scheduled to be removed in 27.1. 349s [ 10.050946] cloud-init[371]: 2025-10-31 08:10:05,947 - lifecycle.py[DEPRECATED]: Support for combined old and new apt module keys is deprecated in 22.1 and scheduled to be removed in 27.1. 349s [ 10.065796] sh[852]: Completed socket interaction for boot stage config 349s [[0;32m OK [0m] Finished [0;1;39mcloud-config.service[0m - Cloud-init: Config Stage. 349s Starting [0;1;39mcloud-final.service[0m - Cloud-init: Final Stage... 349s [ 10.103649] cloud-init[371]: Cloud-init v. 25.3~2g890873f5-0ubuntu2 running 'modules:final' at Fri, 31 Oct 2025 08:10:05 +0000. Up 10.08 seconds. 349s [ 10.232564] cloud-init[371]: Hit:1 http://ftpmaster.internal/ubuntu questing InRelease 349s [ 10.264346] cloud-init[371]: Get:2 http://ftpmaster.internal/ubuntu questing-updates InRelease [136 kB] 349s [ 10.404952] cloud-init[371]: Get:3 http://ftpmaster.internal/ubuntu questing-security InRelease [136 kB] 349s [ 10.522174] cloud-init[371]: Get:4 http://ftpmaster.internal/ubuntu questing-updates/main Sources [26.5 kB] 349s [ 10.572424] cloud-init[371]: Get:5 http://ftpmaster.internal/ubuntu questing-updates/universe Sources [9088 B] 349s [ 10.609256] cloud-init[371]: Get:6 http://ftpmaster.internal/ubuntu questing-updates/main amd64 Packages [58.6 kB] 349s [ 10.642838] cloud-init[371]: Get:7 http://ftpmaster.internal/ubuntu questing-updates/main i386 Packages [20.8 kB] 349s [ 10.688261] cloud-init[371]: Get:8 http://ftpmaster.internal/ubuntu questing-updates/main amd64 c-n-f Metadata [1448 B] 349s [ 10.728640] cloud-init[371]: Get:9 http://ftpmaster.internal/ubuntu questing-updates/universe i386 Packages [7304 B] 349s [ 10.762160] cloud-init[371]: Get:10 http://ftpmaster.internal/ubuntu questing-updates/universe amd64 Packages [32.0 kB] 349s [ 10.794753] cloud-init[371]: Get:11 http://ftpmaster.internal/ubuntu questing-updates/universe amd64 c-n-f Metadata [844 B] 349s [ 10.828486] cloud-init[371]: Get:12 http://ftpmaster.internal/ubuntu questing-security/universe Sources [9088 B] 349s [ 10.862911] cloud-init[371]: Get:13 http://ftpmaster.internal/ubuntu questing-security/main Sources [23.8 kB] 349s [ 10.896291] cloud-init[371]: Get:14 http://ftpmaster.internal/ubuntu questing-security/main amd64 Packages [53.8 kB] 349s [ 10.930504] cloud-init[371]: Get:15 http://ftpmaster.internal/ubuntu questing-security/main i386 Packages [15.9 kB] 349s [ 10.973833] cloud-init[371]: Get:16 http://ftpmaster.internal/ubuntu questing-security/main amd64 c-n-f Metadata [1096 B] 349s [ 11.011188] cloud-init[371]: Get:17 http://ftpmaster.internal/ubuntu questing-security/universe amd64 Packages [31.5 kB] 349s [ 11.049065] cloud-init[371]: Get:18 http://ftpmaster.internal/ubuntu questing-security/universe i386 Packages [6764 B] 349s [ 11.082332] cloud-init[371]: Get:19 http://ftpmaster.internal/ubuntu questing-security/universe amd64 c-n-f Metadata [848 B] 349s [ 11.614503] cloud-init[371]: Fetched 570 kB in 1s (607 kB/s) 349s [ 12.079707] cloud-init[371]: Reading package lists... 349s ci-info: ++++++++++++++++++++++++++++++++++++++++++++++++++++++Authorized keys from /home/ubuntu/.ssh/authorized_keys for user ubuntu+++++++++++++++++++++++++++++++++++++++++++++++++++++++ 349s ci-info: +---------+-------------------------------------------------------------------------------------------------+---------+-----------------------------------------------------------+ 349s ci-info: | Keytype | Fingerprint (sha256) | Options | Comment | 349s ci-info: +---------+-------------------------------------------------------------------------------------------------+---------+-----------------------------------------------------------+ 349s ci-info: | ssh-rsa | 87:eb:11:e6:d9:4d:64:37:d3:15:bc:fb:70:01:fb:c2:54:4a:7c:f0:03:f0:ca:42:74:a2:5a:a0:67:8b:da:f8 | - | ubuntu@juju-7f2275-prod-proposed-migration-environment-2 | 349s ci-info: | ssh-rsa | e8:35:e0:40:84:e9:20:46:36:c3:0d:24:8f:f7:02:62:a2:c6:f7:f5:5c:ed:3f:df:2b:54:8b:69:97:eb:98:5e | - | ubuntu@juju-7f2275-prod-proposed-migration-environment-15 | 349s ci-info: +---------+-------------------------------------------------------------------------------------------------+---------+-----------------------------------------------------------+ 349s <14>Oct 31 08:10:08 cloud-init: ############################################################# 349s <14>Oct 31 08:10:08 cloud-init: -----BEGIN SSH HOST KEY FINGERPRINTS----- 349s <14>Oct 31 08:10:08 cloud-init: 256 SHA256:Nu0cnFmbFa1SGVVly4/X3yZj3mp8TB43bftNPYRYfWQ root@autopkgtest (ECDSA) 349s <14>Oct 31 08:10:08 cloud-init: 256 SHA256:SuGXEJvZBoPg0jmzFFwdqJOCEe5gbUxkqGSBTmdL9NU root@autopkgtest (ED25519) 349s <14>Oct 31 08:10:08 cloud-init: 3072 SHA256:7VTb8FUHUwouFKLeifCHXQEA/i3r+SZlMAkcUfiaHh0 root@autopkgtest (RSA) 349s <14>Oct 31 08:10:08 cloud-init: -----END SSH HOST KEY FINGERPRINTS----- 349s <14>Oct 31 08:10:08 cloud-init: ############################################################# 349s -----BEGIN SSH HOST KEY KEYS----- 349s ecdsa-sha2-nistp256 AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBC9pEHcTp6r6/oLahn/z4VkgsXAvHeDMiW1sMjFwneyKMIDRfK8HBpfJBMC9KZk0AKB34sC8cgSpRm8OBnY/ccM= root@autopkgtest 349s ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAID+iCgc3HlHddO9IPIGJ24+pIz2XX1BLZYt5MnblaLtL root@autopkgtest 349s ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQDJ5It4DS5z5BpP0PhowKPikbvv+J3tdqh0x2DoCFJ/+4voWWLSKg5HBODGY2WqNvZtoyhq7QM9xCvNvGdqH19jVk7Rsl9w4aT4313kSGRSPX81ccmjOdizHptdz+8U8OAACBNP6QhSLsF9oIxbC85ERelG5Z7Qg6+ghs8UNi/KBoTjZ/sMvDkV1r3ueUCnaWArG/QvbKHa8U7N4e2k/TfDdrhuZvjaR1sfQru3aW0kGcAuHXqIn/wd0/TKm8xkG767AFOG7k625nhWOCTLlStijiLEE7UIMKvN9kUBsOvxRC703oDlN3/PjVL/7UNHQ6KyEGVEJQwh0jQ9+ux1O/S/vyZFv546T9bFwtG3qyrSWrPYY9OqvbglE/XqOGIFinMW+lyQi45qj7ghORoEeriNmJhXILL6EjiRFuSOjXLTdGjoUPz7Nim9GcKcpo++aLyUWie9DyQSF9QI3hBupgTaDxqNYzY9Dh9z9nItOlLGTPOMx2Pff23Wx1Ov1FAQr6c= root@autopkgtest 349s -----END SSH HOST KEY KEYS----- 349s [ 12.139684] cloud-init[371]: Cloud-init v. 25.3~2g890873f5-0ubuntu2 finished at Fri, 31 Oct 2025 08:10:08 +0000. Datasource DataSourceOpenStackLocal [net,ver=2]. Up 12.13 seconds 349s [ 12.142789] cloud-init[371]: Generating public/private rsa key pair. 349s [[0;32m OK [0m] Finished [0;1;39mcloud-final.service[0m - Cloud-init: Final Stage. 349s [ 12.144749] cloud-init[371]: Your identification has been saved in /etc/ssh/ssh_host_rsa_key 349s [[0;32m OK [0m] Reached target [0;1;39mcloud-init.target[0m - Cloud-init target. 349s [ 12.147256] cloud-init[371]: Your public key has been saved in /etc/ssh/ssh_host_rsa_key.pub 349s [!p]104[?7h[6n[32766;32766H[6n[ 12.152946] cloud-init[371]: The key fingerprint is: 349s [ 12.161011] cloud-init[371]: SHA256:7VTb8FUHUwouFKLeifCHXQEA/i3r+SZlMAkcUfiaHh0 root@autopkgtest 349s [ 12.162650] cloud-init[371]: The key's randomart image is: 349s [ 12.163382] cloud-init[371]: +---[RSA 3072]----+ 349s [ 12.164049] cloud-init[371]: | .+*o.o.+.. oo+| 349s [ 12.164652] cloud-init[371]: | .+ . o o . oo| 349s [ 12.165277] cloud-init[371]: | oo.. o + . .| 349s [ 12.165902] cloud-init[371]: | =E* + o = . | 349s [ 12.166540] cloud-init[371]: | +B+S o . o | 349s [ 12.167178] cloud-init[371]: | + .+oo | 349s [ 12.167793] cloud-init[371]: | . ..o . | 349s [ 12.168573] cloud-init[371]: | ..... | 349s [ 12.169244] cloud-init[371]: | o+. | 349s [ 12.169868] cloud-init[371]: +----[SHA256]-----+ 349s [ 12.170498] cloud-init[371]: Generating public/private ecdsa key pair. 349s [ 12.171340] cloud-init[371]: Your identification has been saved in /etc/ssh/ssh_host_ecdsa_key 349s [ 12.172409] cloud-init[371]: Your public key has been saved in /etc/ssh/ssh_host_ecdsa_key.pub 349s [ 12.173487] cloud-init[371]: The key fingerprint is: 349s [ 12.174154] cloud-init[371]: SHA256:Nu0cnFmbFa1SGVVly4/X3yZj3mp8TB43bftNPYRYfWQ root@autopkgtest 349s [ 12.175238] cloud-init[371]: The key's randomart image is: 349s [ 12.176162] cloud-init[371]: +---[ECDSA 256]---+ 349s [ 12.176786] cloud-init[371]: | o=E| 349s [ 12.177412] cloud-init[371]: | ==o| 349s [ 12.178050] cloud-init[371]: | .o.=.| 349s [ 12.178656] cloud-init[371]: | o +++o.+| 349s [ 12.179282] cloud-init[371]: | S *.oo..=| 349s [ 12.179905] cloud-init[371]: | . + . .+O| 349s [ 12.180662] cloud-init[371]: | o .+*@| 349s [ 12.181306] cloud-init[371]: | ooB*| 349s [ 12.181935] cloud-init[371]: | .oo=| 349s [ 12.182587] cloud-init[371]: +----[SHA256]-----+ 349s [ 12.183227] cloud-init[371]: Generating public/private ed25519 key pair. 349s [ 12.184099] cloud-init[371]: Your identification has been saved in /etc/ssh/ssh_host_ed25519_key 349s [ 12.185194] cloud-init[371]: Your public key has been saved in /etc/ssh/ssh_host_ed25519_key.pub 349s [ 12.186294] cloud-init[371]: The key fingerprint is: 349s [ 12.186952] cloud-init[371]: SHA256:SuGXEJvZBoPg0jmzFFwdqJOCEe5gbUxkqGSBTmdL9NU root@autopkgtest 349s [ 12.188539] cloud-init[371]: The key's randomart image is: 349s [ 12.191120] cloud-init[371]: +--[ED25519 256]--+ 349s [ 12.191727] cloud-init[371]: |o+BB.+=o. | 349s [ 12.192364] cloud-init[371]: |+B**+ oO E | 349s [ 12.193283] cloud-init[371]: |X+@*..* o | 349s [ 12.194533] cloud-init[371]: |B+== . + . | 349s [ 12.195228] cloud-init[371]: | o.. o S | 349s [ 12.195828] cloud-init[371]: | . o | 349s [ 12.198056] cloud-init[371]: | . | 349s [ 12.198855] cloud-init[371]: | | 349s [ 12.200309] cloud-init[371]: | | 349s [ 12.201750] cloud-init[371]: +----[SHA256]-----+ 349s [ 12.204188] sh[873]: Completed socket interaction for boot stage final 349s [!p]104[?7h[6n[32766;32766H[6n 349s Ubuntu 25.10 autopkgtest ttyS0 349s 349s autopkgtest login: 349s --------------------------------------------------- 349s ------- nova show 31c0566f-1d8f-4785-abd9-607adddcd51f (adt-questing-amd64-adsys-20251031-074722-juju-7f2275-prod-proposed-migration-environment-15-37a8da8a-9da2-464f-bc24-49de849871fb) ------ 351s +--------------------------------------------+--------------------------------------------------------------------------------------------------------------------------------------------------------+ 351s | Property | Value | 351s +--------------------------------------------+--------------------------------------------------------------------------------------------------------------------------------------------------------+ 351s | OS-DCF:diskConfig | MANUAL | 351s | OS-EXT-AZ:availability_zone | AZ2 | 351s | OS-EXT-SRV-ATTR:host | ps7-ra3-n5.ps7.canonical.com | 351s | OS-EXT-SRV-ATTR:hostname | adt-questing-amd64-adsys-20251031-074722-juju-7f2275-prod-propo | 351s | OS-EXT-SRV-ATTR:hypervisor_hostname | ps7-ra3-n5.ps7.canonical.com | 351s | OS-EXT-SRV-ATTR:instance_name | instance-0020df35 | 351s | OS-EXT-SRV-ATTR:kernel_id | | 351s | OS-EXT-SRV-ATTR:launch_index | 0 | 351s | OS-EXT-SRV-ATTR:ramdisk_id | | 351s | OS-EXT-SRV-ATTR:reservation_id | r-otaszcet | 351s | OS-EXT-SRV-ATTR:root_device_name | /dev/sda | 351s | OS-EXT-STS:power_state | 1 | 351s | OS-EXT-STS:task_state | - | 351s | OS-EXT-STS:vm_state | active | 351s | OS-SRV-USG:launched_at | 2025-10-31T08:09:55.000000 | 351s | OS-SRV-USG:terminated_at | - | 351s | accessIPv4 | | 351s | accessIPv6 | | 351s | config_drive | | 351s | created | 2025-10-31T08:09:50Z | 351s | description | - | 351s | flavor:disk | 20 | 351s | flavor:ephemeral | 0 | 351s | flavor:extra_specs | {"aggregate_instance_extra_specs:commit": "amd64", "hw:cpu_policy": "shared", "hw:mem_page_size": "large", "hw:pci_numa_affinity_policy": "preferred"} | 351s | flavor:original_name | autopkgtest-cpu2-ram4-disk20-amd64 | 351s | flavor:ram | 4096 | 351s | flavor:swap | 0 | 351s | flavor:vcpus | 2 | 351s | hostId | 5d3df0ce72b8c8a70282ccaf85f5d50ddaab4bba786dee79c49fafe3 | 351s | id | 31c0566f-1d8f-4785-abd9-607adddcd51f | 351s | image | adt/ubuntu-questing-amd64-server-20251029.img (9ad666d7-3d1a-4dd9-8d34-135736dea446) | 351s | key_name | testbed-juju-7f2275-prod-proposed-migration-environment-15 | 351s | locked | False | 351s | locked_reason | - | 351s | metadata | {} | 351s | name | adt-questing-amd64-adsys-20251031-074722-juju-7f2275-prod-proposed-migration-environment-15-37a8da8a-9da2-464f-bc24-49de849871fb | 351s | net_prod-autopkgtest-workers-amd64 network | 10.151.36.154 | 351s | os-extended-volumes:volumes_attached | [] | 351s | progress | 0 | 351s | security_groups | autopkgtest-juju-7f2275-prod-proposed-migration-environment-15@sto01-10.secgroup | 351s | server_groups | [] | 351s | status | ACTIVE | 351s | tags | [] | 351s | tenant_id | c20da633fc2e44cd97ff31be0acd959a | 351s | trusted_image_certificates | - | 351s | updated | 2025-10-31T08:09:55Z | 351s | user_id | d1c206dbd7b64e3eacefc738fd9acdca | 351s +--------------------------------------------+--------------------------------------------------------------------------------------------------------------------------------------------------------+ 351s --------------------------------------------------- 354s : failure: Timed out on waiting for ssh connection 354s autopkgtest [08:15:37]: ERROR: testbed failure: eof from the virtualisation server