0s autopkgtest [08:46:54]: starting date and time: 2025-10-30 08:46:54+0000 0s autopkgtest [08:46:54]: git checkout: 4b346b80 nova: make wait_reboot return success even when a no-op 0s autopkgtest [08:46:54]: host juju-7f2275-prod-proposed-migration-environment-20; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.tdik5641/out --timeout-copy=6000 --setup-commands /home/ubuntu/autopkgtest-cloud/worker-config-production/setup-canonical.sh --apt-pocket=proposed=src:linux-meta-oem-6.17,src:linux-oem-6.17,src:linux-signed-oem-6.17 --apt-upgrade dpdk-kmods --timeout-short=300 --timeout-copy=20000 --timeout-build=20000 '--env=ADT_TEST_TRIGGERS=linux-meta-oem-6.17/6.17.0-1006.6 linux-oem-6.17/6.17.0-1006.6 linux-signed-oem-6.17/6.17.0-1006.6' --setup-commands 'apt-get install -y ^kernel-testing--linux\-oem\-6\.17--full--preferred$ || apt-get install -y ^linux-image\-oem\-24\.04$ ^linux-headers\-oem\-24\.04$ || apt-get install -y ^linux-image-generic\-oem\-24\.04$ ^linux-headers-generic\-oem\-24\.04$' --setup-commands 'apt-get install -y ^kernel-testing--linux\-oem\-6\.17--modules-extra--preferred$ || apt-get install -y ^linux-modules-extra\-oem\-24\.04$ || :' -- ssh -s /home/ubuntu/autopkgtest/ssh-setup/nova -- --flavor autopkgtest-cpu2-ram4-disk20-amd64 --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-20@sto01-10.secgroup --name adt-noble-amd64-dpdk-kmods-20251030-082544-juju-7f2275-prod-proposed-migration-environment-20-af9da94d-20bf-46e7-9a02-c720359dbe1f --image adt/ubuntu-noble-amd64-server --keyname testbed-juju-7f2275-prod-proposed-migration-environment-20 --net-id=net_prod-autopkgtest-workers-amd64 -e TERM=linux --mirror=http://ftpmaster.internal/ubuntu/ 9s Creating nova instance adt-noble-amd64-dpdk-kmods-20251030-082544-juju-7f2275-prod-proposed-migration-environment-20-af9da94d-20bf-46e7-9a02-c720359dbe1f from image adt/ubuntu-noble-amd64-server-20251029.img (UUID b41dc4cf-b23a-49ac-8f53-9fa4b547e195)... 324s ------- nova console-log af640175-b30e-44c9-8e86-aea63d323976 (adt-noble-amd64-dpdk-kmods-20251030-082544-juju-7f2275-prod-proposed-migration-environment-20-af9da94d-20bf-46e7-9a02-c720359dbe1f) ------ 326s [ 0.000000] Linux version 6.8.0-86-generic (buildd@lcy02-amd64-031) (x86_64-linux-gnu-gcc-13 (Ubuntu 13.3.0-6ubuntu2~24.04) 13.3.0, GNU ld (GNU Binutils for Ubuntu) 2.42) #87-Ubuntu SMP PREEMPT_DYNAMIC Mon Sep 22 18:03:36 UTC 2025 (Ubuntu 6.8.0-86.87-generic 6.8.12) 326s [ 0.000000] Command line: BOOT_IMAGE=/vmlinuz-6.8.0-86-generic root=UUID=ffe7eb40-94f7-473b-b336-935460126345 ro console=tty0 console=hvc0 console=ttyS0 326s [ 0.000000] KERNEL supported cpus: 326s [ 0.000000] Intel GenuineIntel 326s [ 0.000000] AMD AuthenticAMD 326s [ 0.000000] Hygon HygonGenuine 326s [ 0.000000] Centaur CentaurHauls 326s [ 0.000000] zhaoxin Shanghai 326s [ 0.000000] BIOS-provided physical RAM map: 326s [ 0.000000] BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable 326s [ 0.000000] BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved 326s [ 0.000000] BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved 326s [ 0.000000] BIOS-e820: [mem 0x0000000000100000-0x00000000bffdcfff] usable 326s [ 0.000000] BIOS-e820: [mem 0x00000000bffdd000-0x00000000bfffffff] reserved 326s [ 0.000000] BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved 326s [ 0.000000] BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved 326s [ 0.000000] BIOS-e820: [mem 0x0000000100000000-0x000000013fffffff] usable 326s [ 0.000000] BIOS-e820: [mem 0x000000fd00000000-0x000000ffffffffff] reserved 326s [ 0.000000] NX (Execute Disable) protection: active 326s [ 0.000000] APIC: Static calls initialized 326s [ 0.000000] SMBIOS 2.8 present. 326s [ 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 326s [ 0.000000] Hypervisor detected: KVM 326s [ 0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00 326s [ 0.000000] kvm-clock: using sched offset of 1973616464 cycles 326s [ 0.000002] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns 326s [ 0.000004] tsc: Detected 2396.398 MHz processor 326s [ 0.000874] last_pfn = 0x140000 max_arch_pfn = 0x400000000 326s [ 0.000911] MTRR map: 4 entries (3 fixed + 1 variable; max 19), built from 8 variable MTRRs 326s [ 0.000913] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT 326s [ 0.000961] last_pfn = 0xbffdd max_arch_pfn = 0x400000000 326s [ 0.003195] found SMP MP-table at [mem 0x000f53c0-0x000f53cf] 326s [ 0.003207] Using GB pages for direct mapping 326s [ 0.003399] RAMDISK: [mem 0x2ffa3000-0x33fc8fff] 326s [ 0.003737] ACPI: Early table checksum verification disabled 326s [ 0.003740] ACPI: RSDP 0x00000000000F5140 000014 (v00 BOCHS ) 326s [ 0.003744] ACPI: RSDT 0x00000000BFFE2334 000034 (v01 BOCHS BXPC 00000001 BXPC 00000001) 326s [ 0.003748] ACPI: FACP 0x00000000BFFE2150 000074 (v01 BOCHS BXPC 00000001 BXPC 00000001) 326s [ 0.003752] ACPI: DSDT 0x00000000BFFE0040 002110 (v01 BOCHS BXPC 00000001 BXPC 00000001) 326s [ 0.003754] ACPI: FACS 0x00000000BFFE0000 000040 326s [ 0.003756] ACPI: APIC 0x00000000BFFE21C4 000080 (v03 BOCHS BXPC 00000001 BXPC 00000001) 326s [ 0.003758] ACPI: SRAT 0x00000000BFFE2244 0000C8 (v01 BOCHS BXPC 00000001 BXPC 00000001) 326s [ 0.003761] ACPI: WAET 0x00000000BFFE230C 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) 326s [ 0.003762] ACPI: Reserving FACP table memory at [mem 0xbffe2150-0xbffe21c3] 326s [ 0.003764] ACPI: Reserving DSDT table memory at [mem 0xbffe0040-0xbffe214f] 326s [ 0.003764] ACPI: Reserving FACS table memory at [mem 0xbffe0000-0xbffe003f] 326s [ 0.003765] ACPI: Reserving APIC table memory at [mem 0xbffe21c4-0xbffe2243] 326s [ 0.003766] ACPI: Reserving SRAT table memory at [mem 0xbffe2244-0xbffe230b] 326s [ 0.003766] ACPI: Reserving WAET table memory at [mem 0xbffe230c-0xbffe2333] 326s [ 0.003827] SRAT: PXM 0 -> APIC 0x00 -> Node 0 326s [ 0.003828] SRAT: PXM 0 -> APIC 0x01 -> Node 0 326s [ 0.003829] ACPI: SRAT: Node 0 PXM 0 [mem 0x00000000-0x0009ffff] 326s [ 0.003831] ACPI: SRAT: Node 0 PXM 0 [mem 0x00100000-0xbfffffff] 326s [ 0.003832] ACPI: SRAT: Node 0 PXM 0 [mem 0x100000000-0x13fffffff] 326s [ 0.003834] NUMA: Node 0 [mem 0x00000000-0x0009ffff] + [mem 0x00100000-0xbfffffff] -> [mem 0x00000000-0xbfffffff] 326s [ 0.003836] NUMA: Node 0 [mem 0x00000000-0xbfffffff] + [mem 0x100000000-0x13fffffff] -> [mem 0x00000000-0x13fffffff] 326s [ 0.003843] NODE_DATA(0) allocated [mem 0x13ffd3000-0x13fffdfff] 326s [ 0.003989] Zone ranges: 326s [ 0.003989] DMA [mem 0x0000000000001000-0x0000000000ffffff] 326s [ 0.003991] DMA32 [mem 0x0000000001000000-0x00000000ffffffff] 326s [ 0.003992] Normal [mem 0x0000000100000000-0x000000013fffffff] 326s [ 0.003993] Device empty 326s [ 0.003994] Movable zone start for each node 326s [ 0.003996] Early memory node ranges 326s [ 0.003996] node 0: [mem 0x0000000000001000-0x000000000009efff] 326s [ 0.003997] node 0: [mem 0x0000000000100000-0x00000000bffdcfff] 326s [ 0.003999] node 0: [mem 0x0000000100000000-0x000000013fffffff] 326s [ 0.004000] Initmem setup node 0 [mem 0x0000000000001000-0x000000013fffffff] 326s [ 0.004003] On node 0, zone DMA: 1 pages in unavailable ranges 326s [ 0.004016] On node 0, zone DMA: 97 pages in unavailable ranges 326s [ 0.006600] On node 0, zone Normal: 35 pages in unavailable ranges 326s [ 0.007008] ACPI: PM-Timer IO Port: 0x608 326s [ 0.007019] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) 326s [ 0.007050] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 326s [ 0.007053] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) 326s [ 0.007055] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) 326s [ 0.007056] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) 326s [ 0.007057] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) 326s [ 0.007058] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) 326s [ 0.007060] ACPI: Using ACPI (MADT) for SMP configuration information 326s [ 0.007062] smpboot: Allowing 2 CPUs, 0 hotplug CPUs 326s [ 0.007081] kvm-guest: APIC: eoi() replaced with kvm_guest_apic_eoi_write() 326s [ 0.007096] PM: hibernation: Registered nosave memory: [mem 0x00000000-0x00000fff] 326s [ 0.007098] PM: hibernation: Registered nosave memory: [mem 0x0009f000-0x0009ffff] 326s [ 0.007098] PM: hibernation: Registered nosave memory: [mem 0x000a0000-0x000effff] 326s [ 0.007099] PM: hibernation: Registered nosave memory: [mem 0x000f0000-0x000fffff] 326s [ 0.007100] PM: hibernation: Registered nosave memory: [mem 0xbffdd000-0xbfffffff] 326s [ 0.007101] PM: hibernation: Registered nosave memory: [mem 0xc0000000-0xfeffbfff] 326s [ 0.007101] PM: hibernation: Registered nosave memory: [mem 0xfeffc000-0xfeffffff] 326s [ 0.007102] PM: hibernation: Registered nosave memory: [mem 0xff000000-0xfffbffff] 326s [ 0.007102] PM: hibernation: Registered nosave memory: [mem 0xfffc0000-0xffffffff] 326s [ 0.007104] [mem 0xc0000000-0xfeffbfff] available for PCI devices 326s [ 0.007105] Booting paravirtualized kernel on KVM 326s [ 0.007107] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns 326s [ 0.007114] setup_percpu: NR_CPUS:8192 nr_cpumask_bits:2 nr_cpu_ids:2 nr_node_ids:1 326s [ 0.007207] percpu: Embedded 86 pages/cpu s229376 r8192 d114688 u1048576 326s [ 0.007237] kvm-guest: PV spinlocks disabled, no host support 326s [ 0.007237] Kernel command line: BOOT_IMAGE=/vmlinuz-6.8.0-86-generic root=UUID=ffe7eb40-94f7-473b-b336-935460126345 ro console=tty0 console=hvc0 console=ttyS0 326s [ 0.007294] Unknown kernel command line parameters "BOOT_IMAGE=/vmlinuz-6.8.0-86-generic", will be passed to user space. 326s [ 0.007307] random: crng init done 326s [ 0.007450] Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) 326s [ 0.007533] Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) 326s [ 0.007559] Fallback order for Node 0: 0 326s [ 0.007562] Built 1 zonelists, mobility grouping on. Total pages: 1031901 326s [ 0.007563] Policy zone: Normal 326s [ 0.007567] mem auto-init: stack:all(zero), heap alloc:on, heap free:off 326s [ 0.007571] software IO TLB: area num 2. 326s [ 0.016879] Memory: 3934960K/4193772K available (22528K kernel code, 4441K rwdata, 14384K rodata, 4912K init, 4796K bss, 258552K reserved, 0K cma-reserved) 326s [ 0.017785] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1 326s [ 0.017809] ftrace: allocating 58191 entries in 228 pages 326s [ 0.029314] ftrace: allocated 228 pages with 4 groups 326s [ 0.030208] Dynamic Preempt: voluntary 326s [ 0.030252] rcu: Preemptible hierarchical RCU implementation. 326s [ 0.030253] rcu: RCU restricting CPUs from NR_CPUS=8192 to nr_cpu_ids=2. 326s [ 0.030254] Trampoline variant of Tasks RCU enabled. 326s [ 0.030255] Rude variant of Tasks RCU enabled. 326s [ 0.030255] Tracing variant of Tasks RCU enabled. 326s [ 0.030256] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. 326s [ 0.030256] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=2 326s [ 0.030263] RCU Tasks: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=2. 326s [ 0.030265] RCU Tasks Rude: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=2. 326s [ 0.030266] RCU Tasks Trace: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=2. 326s [ 0.032792] NR_IRQS: 524544, nr_irqs: 440, preallocated irqs: 16 326s [ 0.033007] rcu: srcu_init: Setting srcu_struct sizes based on contention. 326s [ 0.042378] Console: colour VGA+ 80x25 326s [ 0.042382] printk: legacy console [tty0] enabled 326s [ 0.079615] printk: legacy console [ttyS0] enabled 326s [ 0.189411] ACPI: Core revision 20230628 326s [ 0.190255] APIC: Switch to symmetric I/O mode setup 326s [ 0.191370] x2apic enabled 326s [ 0.192215] APIC: Switched APIC routing to: physical x2apic 326s [ 0.194308] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 326s [ 0.195392] tsc: Marking TSC unstable due to TSCs unsynchronized 326s [ 0.196463] Calibrating delay loop (skipped) preset value.. 4792.79 BogoMIPS (lpj=2396398) 326s [ 0.197925] x86/cpu: User Mode Instruction Prevention (UMIP) activated 326s [ 0.199478] Last level iTLB entries: 4KB 512, 2MB 255, 4MB 127 326s [ 0.200461] Last level dTLB entries: 4KB 512, 2MB 255, 4MB 127, 1GB 0 326s [ 0.201464] Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization 326s [ 0.202843] Spectre V2 : Mitigation: Retpolines 326s [ 0.203684] Spectre V2 : Spectre v2 / SpectreRSB mitigation: Filling RSB on context switch 326s [ 0.204832] Spectre V2 : Spectre v2 / SpectreRSB : Filling RSB on VMEXIT 326s [ 0.206461] Spectre V2 : Enabling Speculation Barrier for firmware calls 326s [ 0.207742] RETBleed: Mitigation: untrained return thunk 326s [ 0.208712] Spectre V2 : mitigation: Enabling conditional Indirect Branch Prediction Barrier 326s [ 0.209834] Speculative Store Bypass: Vulnerable 326s [ 0.210689] Speculative Return Stack Overflow: IBPB-extending microcode not applied! 326s [ 0.211816] Speculative Return Stack Overflow: WARNING: See https://kernel.org/doc/html/latest/admin-guide/hw-vuln/srso.html for mitigation options. 326s [ 0.211817] Speculative Return Stack Overflow: Vulnerable: Safe RET, no microcode 326s [ 0.216472] x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' 326s [ 0.217841] x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' 326s [ 0.219462] x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' 326s [ 0.222461] x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 326s [ 0.224461] x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'compacted' format. 326s [ 0.252133] Freeing SMP alternatives memory: 48K 326s [ 0.252713] pid_max: default: 32768 minimum: 301 326s [ 0.253765] LSM: initializing lsm=lockdown,capability,landlock,yama,apparmor,integrity 326s [ 0.255475] landlock: Up and running. 326s [ 0.256202] Yama: becoming mindful. 326s [ 0.256716] AppArmor: AppArmor initialized 326s [ 0.257746] Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) 326s [ 0.258838] Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) 326s [ 0.362995] smpboot: CPU0: AMD EPYC-Rome Processor (family: 0x17, model: 0x31, stepping: 0x0) 326s [ 0.364144] Performance Events: Fam17h+ core perfctr, AMD PMU driver. 326s [ 0.364757] ... version: 0 326s [ 0.365692] ... bit width: 48 326s [ 0.366462] ... generic registers: 6 326s [ 0.367246] ... value mask: 0000ffffffffffff 326s [ 0.367726] ... max period: 00007fffffffffff 326s [ 0.368730] ... fixed-purpose events: 0 326s [ 0.369698] ... event mask: 000000000000003f 326s [ 0.370820] signal: max sigframe size: 1776 326s [ 0.371741] rcu: Hierarchical SRCU implementation. 326s [ 0.372709] rcu: Max phase no-delay instances is 400. 326s [ 0.377481] smp: Bringing up secondary CPUs ... 326s [ 0.378497] smpboot: x86: Booting SMP configuration: 326s [ 0.379424] .... node #0, CPUs: #1 326s [ 0.379563] smp: Brought up 1 node, 2 CPUs 326s [ 0.381464] smpboot: Max logical packages: 2 326s [ 0.382461] smpboot: Total of 2 processors activated (9585.59 BogoMIPS) 326s [ 0.383680] devtmpfs: initialized 326s [ 0.384722] x86/mm: Memory block size: 128MB 326s [ 0.386559] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns 326s [ 0.387917] futex hash table entries: 512 (order: 3, 32768 bytes, linear) 326s [ 0.389524] pinctrl core: initialized pinctrl subsystem 326s [ 0.390619] PM: RTC time: 08:47:13, date: 2025-10-30 326s [ 0.391872] NET: Registered PF_NETLINK/PF_ROUTE protocol family 326s [ 0.392907] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations 326s [ 0.393795] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations 326s [ 0.395480] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations 326s [ 0.396863] audit: initializing netlink subsys (disabled) 326s [ 0.397765] audit: type=2000 audit(1761814033.989:1): state=initialized audit_enabled=0 res=1 326s [ 0.397765] thermal_sys: Registered thermal governor 'fair_share' 326s [ 0.399866] thermal_sys: Registered thermal governor 'bang_bang' 326s [ 0.400743] thermal_sys: Registered thermal governor 'step_wise' 326s [ 0.401742] thermal_sys: Registered thermal governor 'user_space' 326s [ 0.402748] thermal_sys: Registered thermal governor 'power_allocator' 326s [ 0.403758] cpuidle: using governor ladder 326s [ 0.406255] cpuidle: using governor menu 326s [ 0.407559] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 326s [ 0.408900] PCI: Using configuration type 1 for base access 326s [ 0.409731] PCI: Using configuration type 1 for extended access 326s [ 0.410897] kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. 326s [ 0.423607] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages 326s [ 0.424793] HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page 326s [ 0.425758] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages 326s [ 0.427762] HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page 326s [ 0.429120] ACPI: Added _OSI(Module Device) 326s [ 0.429705] ACPI: Added _OSI(Processor Device) 326s [ 0.430703] ACPI: Added _OSI(3.0 _SCP Extensions) 326s [ 0.431466] ACPI: Added _OSI(Processor Aggregator Device) 326s [ 0.434481] ACPI: 1 ACPI AML tables successfully acquired and loaded 326s [ 0.438859] ACPI: _OSC evaluation for CPUs failed, trying _PDC 326s [ 0.439676] ACPI: Interpreter enabled 326s [ 0.440482] ACPI: PM: (supports S0 S3 S4 S5) 326s [ 0.441303] ACPI: Using IOAPIC for interrupt routing 326s [ 0.442462] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug 326s [ 0.443881] PCI: Using E820 reservations for host bridge windows 326s [ 0.444873] ACPI: Enabled 2 GPEs in block 00 to 0F 326s [ 0.450254] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) 326s [ 0.451467] acpi PNP0A03:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI EDR HPX-Type3] 326s [ 0.454486] acpiphp: Slot [3] registered 326s [ 0.455292] acpiphp: Slot [4] registered 326s [ 0.455777] acpiphp: Slot [5] registered 326s [ 0.456781] acpiphp: Slot [6] registered 326s [ 0.457749] acpiphp: Slot [7] registered 326s [ 0.458481] acpiphp: Slot [8] registered 326s [ 0.459482] acpiphp: Slot [9] registered 326s [ 0.460351] acpiphp: Slot [10] registered 326s [ 0.461716] acpiphp: Slot [11] registered 326s [ 0.461731] acpiphp: Slot [12] registered 326s [ 0.462770] acpiphp: Slot [13] registered 326s [ 0.463725] acpiphp: Slot [14] registered 326s [ 0.464481] acpiphp: Slot [15] registered 326s [ 0.465484] acpiphp: Slot [16] registered 326s [ 0.466333] acpiphp: Slot [17] registered 326s [ 0.466707] acpiphp: Slot [18] registered 326s [ 0.467786] acpiphp: Slot [19] registered 326s [ 0.468777] acpiphp: Slot [20] registered 326s [ 0.469742] acpiphp: Slot [21] registered 326s [ 0.470480] acpiphp: Slot [22] registered 326s [ 0.471485] acpiphp: Slot [23] registered 326s [ 0.472376] acpiphp: Slot [24] registered 326s [ 0.472721] acpiphp: Slot [25] registered 326s [ 0.473742] acpiphp: Slot [26] registered 326s [ 0.474759] acpiphp: Slot [27] registered 326s [ 0.475720] acpiphp: Slot [28] registered 326s [ 0.476481] acpiphp: Slot [29] registered 326s [ 0.477484] acpiphp: Slot [30] registered 326s [ 0.478346] acpiphp: Slot [31] registered 326s [ 0.478700] PCI host bridge to bus 0000:00 326s [ 0.479763] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] 326s [ 0.481762] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] 326s [ 0.482764] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] 326s [ 0.484462] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] 326s [ 0.485843] pci_bus 0000:00: root bus resource [mem 0xc000000000-0xc07fffffff window] 326s [ 0.486843] pci_bus 0000:00: root bus resource [bus 00-ff] 326s [ 0.488545] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 conventional PCI endpoint 326s [ 0.490543] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 conventional PCI endpoint 326s [ 0.492520] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 conventional PCI endpoint 326s [ 0.494894] pci 0000:00:01.1: BAR 4 [io 0xc140-0xc14f] 326s [ 0.495759] pci 0000:00:01.1: BAR 0 [io 0x01f0-0x01f7]: legacy IDE quirk 326s [ 0.496843] pci 0000:00:01.1: BAR 1 [io 0x03f6]: legacy IDE quirk 326s [ 0.498742] pci 0000:00:01.1: BAR 2 [io 0x0170-0x0177]: legacy IDE quirk 326s [ 0.499461] pci 0000:00:01.1: BAR 3 [io 0x0376]: legacy IDE quirk 326s [ 0.500951] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 conventional PCI endpoint 326s [ 0.502787] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI 326s [ 0.503838] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB 326s [ 0.505678] pci 0000:00:02.0: [1b36:0100] type 00 class 0x030000 conventional PCI endpoint 326s [ 0.511472] pci 0000:00:02.0: BAR 0 [mem 0xf4000000-0xf7ffffff] 326s [ 0.512469] pci 0000:00:02.0: BAR 1 [mem 0xf8000000-0xfbffffff] 326s [ 0.513755] pci 0000:00:02.0: BAR 2 [mem 0xfc090000-0xfc091fff] 326s [ 0.514752] pci 0000:00:02.0: BAR 3 [io 0xc100-0xc11f] 326s [ 0.515746] pci 0000:00:02.0: ROM [mem 0xfc080000-0xfc08ffff pref] 326s [ 0.516787] pci 0000:00:02.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff] 326s [ 0.518721] pci 0000:00:03.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint 326s [ 0.521470] pci 0000:00:03.0: BAR 0 [io 0xc000-0xc03f] 326s [ 0.522731] pci 0000:00:03.0: BAR 1 [mem 0xfc092000-0xfc092fff] 326s [ 0.523759] pci 0000:00:03.0: BAR 4 [mem 0xc000000000-0xc000003fff 64bit pref] 326s [ 0.525839] pci 0000:00:03.0: ROM [mem 0xfc000000-0xfc07ffff pref] 326s [ 0.528122] pci 0000:00:04.0: [1af4:1004] type 00 class 0x010000 conventional PCI endpoint 326s [ 0.531292] pci 0000:00:04.0: BAR 0 [io 0xc040-0xc07f] 326s [ 0.532736] pci 0000:00:04.0: BAR 1 [mem 0xfc093000-0xfc093fff] 326s [ 0.533761] pci 0000:00:04.0: BAR 4 [mem 0xc000004000-0xc000007fff 64bit pref] 326s [ 0.536292] pci 0000:00:05.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint 326s [ 0.539022] pci 0000:00:05.0: BAR 0 [io 0xc080-0xc0bf] 326s [ 0.539732] pci 0000:00:05.0: BAR 1 [mem 0xfc094000-0xfc094fff] 326s [ 0.540761] pci 0000:00:05.0: BAR 4 [mem 0xc000008000-0xc00000bfff 64bit pref] 326s [ 0.544559] pci 0000:00:06.0: [1af4:1002] type 00 class 0x00ff00 conventional PCI endpoint 326s [ 0.547470] pci 0000:00:06.0: BAR 0 [io 0xc0c0-0xc0ff] 326s [ 0.548745] pci 0000:00:06.0: BAR 1 [mem 0xfc095000-0xfc095fff] 326s [ 0.549765] pci 0000:00:06.0: BAR 4 [mem 0xc00000c000-0xc00000ffff 64bit pref] 326s [ 0.552970] pci 0000:00:07.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint 326s [ 0.555462] pci 0000:00:07.0: BAR 0 [io 0xc120-0xc13f] 326s [ 0.556469] pci 0000:00:07.0: BAR 1 [mem 0xfc096000-0xfc096fff] 326s [ 0.557488] pci 0000:00:07.0: BAR 4 [mem 0xc000010000-0xc000013fff 64bit pref] 326s [ 0.567746] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 326s [ 0.568900] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 326s [ 0.569879] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 326s [ 0.574606] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 326s [ 0.575821] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 326s [ 0.577731] iommu: Default domain type: Translated 326s [ 0.578462] iommu: DMA domain TLB invalidation policy: lazy mode 326s [ 0.580022] SCSI subsystem initialized 326s [ 0.580747] ACPI: bus type USB registered 326s [ 0.581725] usbcore: registered new interface driver usbfs 326s [ 0.582799] usbcore: registered new interface driver hub 326s [ 0.583797] usbcore: registered new device driver usb 326s [ 0.584795] pps_core: LinuxPPS API ver. 1 registered 326s [ 0.585794] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti 326s [ 0.587467] PTP clock support registered 326s [ 0.588520] EDAC MC: Ver: 3.0.0 326s [ 0.589811] NetLabel: Initializing 326s [ 0.590705] NetLabel: domain hash size = 128 326s [ 0.591462] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO 326s [ 0.592761] NetLabel: unlabeled traffic allowed by default 326s [ 0.593794] mctp: management component transport protocol core 326s [ 0.594745] NET: Registered PF_MCTP protocol family 326s [ 0.595728] PCI: Using ACPI for IRQ routing 326s [ 0.596652] pci 0000:00:02.0: vgaarb: setting as boot VGA device 326s [ 0.597460] pci 0000:00:02.0: vgaarb: bridge control possible 326s [ 0.597460] pci 0000:00:02.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none 326s [ 0.600463] vgaarb: loaded 326s [ 0.601568] clocksource: Switched to clocksource kvm-clock 326s [ 0.603226] VFS: Disk quotas dquot_6.6.0 326s [ 0.604031] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) 326s [ 0.606229] AppArmor: AppArmor Filesystem Enabled 326s [ 0.607155] pnp: PnP ACPI init 326s [ 0.608213] pnp: PnP ACPI: found 5 devices 326s [ 0.615196] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns 326s [ 0.616914] NET: Registered PF_INET protocol family 326s [ 0.617880] IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) 326s [ 0.633134] tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) 326s [ 0.634838] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) 326s [ 0.636401] TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) 326s [ 0.637910] TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, linear) 326s [ 0.639363] TCP: Hash tables configured (established 32768 bind 32768) 326s [ 0.640632] MPTCP token hash table entries: 4096 (order: 4, 98304 bytes, linear) 326s [ 0.642039] UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) 326s [ 0.643267] UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) 326s [ 0.644602] NET: Registered PF_UNIX/PF_LOCAL protocol family 326s [ 0.645661] NET: Registered PF_XDP protocol family 326s [ 0.646583] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] 326s [ 0.647716] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] 326s [ 0.648856] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] 326s [ 0.650103] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] 326s [ 0.651346] pci_bus 0000:00: resource 8 [mem 0xc000000000-0xc07fffffff window] 326s [ 0.652759] pci 0000:00:01.0: PIIX3: Enabling Passive Release 326s [ 0.653828] pci 0000:00:00.0: Limiting direct PCI/PCI transfers 326s [ 0.654999] PCI: CLS 0 bytes, default 64 326s [ 0.655861] Trying to unpack rootfs image as initramfs... 326s [ 0.656662] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) 326s [ 0.658114] software IO TLB: mapped [mem 0x00000000bbfdd000-0x00000000bffdd000] (64MB) 326s [ 0.661739] Initialise system trusted keyrings 326s [ 0.663146] Key type blacklist registered 326s [ 0.663995] workingset: timestamp_bits=36 max_order=20 bucket_order=0 326s [ 0.665191] zbud: loaded 326s [ 0.666024] squashfs: version 4.0 (2009/01/31) Phillip Lougher 326s [ 0.667214] fuse: init (API version 7.39) 326s [ 0.668177] integrity: Platform Keyring initialized 326s [ 0.669125] integrity: Machine keyring initialized 326s [ 0.679997] Key type asymmetric registered 326s [ 0.680806] Asymmetric key parser 'x509' registered 326s [ 0.681745] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 243) 326s [ 0.683185] io scheduler mq-deadline registered 326s [ 0.684612] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 326s [ 0.685973] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 326s [ 0.687432] ACPI: button: Power Button [PWRF] 326s [ 0.689846] ACPI: \_SB_.LNKC: Enabled at IRQ 11 326s [ 0.693076] ACPI: \_SB_.LNKD: Enabled at IRQ 10 326s [ 0.696245] ACPI: \_SB_.LNKA: Enabled at IRQ 10 326s [ 0.699302] ACPI: \_SB_.LNKB: Enabled at IRQ 11 326s [ 0.704195] Serial: 8250/16550 driver, 32 ports, IRQ sharing enabled 326s [ 0.729733] 00:00: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A 326s [ 0.732929] Freeing initrd memory: 65688K 326s [ 0.744162] Linux agpgart interface v0.103 326s [ 0.746470] loop: module loaded 326s [ 0.747492] virtio_scsi virtio1: 2/0/0 default/read/poll queues 326s [ 0.749487] scsi host0: Virtio SCSI HBA 326s [ 0.751147] scsi 0:0:0:0: Direct-Access QEMU QEMU HARDDISK 2.5+ PQ: 0 ANSI: 5 326s [ 0.754433] scsi host1: ata_piix 326s [ 0.755492] scsi host2: ata_piix 326s [ 0.756205] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc140 irq 14 lpm-pol 0 326s [ 0.757664] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc148 irq 15 lpm-pol 0 326s [ 0.759319] ACPI: bus type drm_connector registered 326s [ 0.761341] tun: Universal TUN/TAP device driver, 1.6 326s [ 0.765233] PPP generic driver version 2.4.2 326s [ 0.766543] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 326s [ 0.769072] serio: i8042 KBD port at 0x60,0x64 irq 1 326s [ 0.770221] serio: i8042 AUX port at 0x60,0x64 irq 12 326s [ 0.771519] mousedev: PS/2 mouse device common for all mice 326s [ 0.772883] rtc_cmos 00:04: RTC can wake from S4 326s [ 0.774528] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 326s [ 0.774881] sd 0:0:0:0: Power-on or device reset occurred 326s [ 0.775848] rtc_cmos 00:04: registered as rtc0 326s [ 0.776591] sd 0:0:0:0: Attached scsi generic sg0 type 0 326s [ 0.777636] rtc_cmos 00:04: setting system clock to 2025-10-30T08:47:13 UTC (1761814033) 326s [ 0.778035] sd 0:0:0:0: [sda] 41943040 512-byte logical blocks: (21.5 GB/20.0 GiB) 326s [ 0.779246] rtc_cmos 00:04: alarms up to one day, y3k, 242 bytes nvram 326s [ 0.780277] sd 0:0:0:0: [sda] Write Protect is off 326s [ 0.781098] i2c_dev: i2c /dev entries driver 326s [ 0.782872] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. 326s [ 0.783347] sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA 326s [ 0.784984] device-mapper: uevent: version 1.0.3 326s [ 0.787698] sda: sda1 sda14 sda15 sda16 326s [ 0.788620] device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@redhat.com 326s [ 0.790288] sd 0:0:0:0: [sda] Attached SCSI disk 326s [ 0.790548] amd_pstate: the _CPC object is not present in SBIOS or ACPI disabled 326s [ 0.795552] ledtrig-cpu: registered to indicate activity on CPUs 326s [ 0.796759] drop_monitor: Initializing network drop monitor service 326s [ 0.798017] NET: Registered PF_INET6 protocol family 326s [ 0.804396] Segment Routing with IPv6 326s [ 0.805171] In-situ OAM (IOAM) with IPv6 326s [ 0.805986] NET: Registered PF_PACKET protocol family 326s [ 0.807002] Key type dns_resolver registered 326s [ 0.808125] IPI shorthand broadcast: enabled 326s [ 0.811180] sched_clock: Marking stable (651004497, 159447209)->(814018206, -3566500) 326s [ 0.813296] registered taskstats version 1 326s [ 0.814271] Loading compiled-in X.509 certificates 326s [ 0.815665] Loaded X.509 cert 'Build time autogenerated kernel key: b54828a0f24178b39a7f6ff8db8f13420879d069' 326s [ 0.818069] Loaded X.509 cert 'Canonical Ltd. Live Patch Signing 2025 Kmod: d541cef61dc7e793b7eb7e899970a2eef0b5dc8c' 326s [ 0.820347] Loaded X.509 cert 'Canonical Ltd. Live Patch Signing: 14df34d1a87cf37625abec039ef2bf521249b969' 326s [ 0.822494] Loaded X.509 cert 'Canonical Ltd. Kernel Module Signing: 88f752e560a1e0737e31163a466ad7b70a850c19' 326s [ 0.824416] blacklist: Loading compiled-in revocation X.509 certificates 326s [ 0.825662] Loaded X.509 cert 'Canonical Ltd. Secure Boot Signing: 61482aa2830d0ab2ad5af10b7250da9033ddcef0' 326s [ 0.827476] Loaded X.509 cert 'Canonical Ltd. Secure Boot Signing (2017): 242ade75ac4a15e50d50c84b0d45ff3eae707a03' 326s [ 0.829364] Loaded X.509 cert 'Canonical Ltd. Secure Boot Signing (ESM 2018): 365188c1d374d6b07c3c8f240f8ef722433d6a8b' 326s [ 0.831316] Loaded X.509 cert 'Canonical Ltd. Secure Boot Signing (2019): c0746fd6c5da3ae827864651ad66ae47fe24b3e8' 326s [ 0.833218] Loaded X.509 cert 'Canonical Ltd. Secure Boot Signing (2021 v1): a8d54bbb3825cfb94fa13c9f8a594a195c107b8d' 326s [ 0.835153] Loaded X.509 cert 'Canonical Ltd. Secure Boot Signing (2021 v2): 4cf046892d6fd3c9a5b03f98d845f90851dc6a8c' 326s [ 0.837098] Loaded X.509 cert 'Canonical Ltd. Secure Boot Signing (2021 v3): 100437bb6de6e469b581e61cd66bce3ef4ed53af' 326s [ 0.839040] Loaded X.509 cert 'Canonical Ltd. Secure Boot Signing (Ubuntu Core 2019): c1d57b8f6b743f23ee41f4f7ee292f06eecadfb9' 326s [ 0.845795] Key type .fscrypt registered 326s [ 0.846599] Key type fscrypt-provisioning registered 326s [ 0.860238] Key type encrypted registered 326s [ 0.861075] AppArmor: AppArmor sha256 policy hashing enabled 326s [ 0.862142] ima: No TPM chip found, activating TPM-bypass! 326s [ 0.863170] Loading compiled-in module X.509 certificates 326s [ 0.864574] Loaded X.509 cert 'Build time autogenerated kernel key: b54828a0f24178b39a7f6ff8db8f13420879d069' 326s [ 0.866441] ima: Allocated hash algorithm: sha256 326s [ 0.870553] ima: No architecture policies found 326s [ 0.871444] evm: Initialising EVM extended attributes: 326s [ 0.872944] evm: security.selinux 326s [ 0.873624] evm: security.SMACK64 326s [ 0.874297] evm: security.SMACK64EXEC 326s [ 0.875036] evm: security.SMACK64TRANSMUTE 326s [ 0.875841] evm: security.SMACK64MMAP 326s [ 0.876576] evm: security.apparmor 326s [ 0.877263] evm: security.ima 326s [ 0.877890] evm: security.capability 326s [ 0.878611] evm: HMAC attrs: 0x1 326s [ 0.879582] PM: Magic number: 13:363:777 326s [ 0.880424] misc rfkill: hash matches 326s [ 0.887276] RAS: Correctable Errors collector initialized. 326s [ 0.888417] clk: Disabling unused clocks 326s [ 0.915227] Freeing unused decrypted memory: 2028K 326s [ 0.916519] Freeing unused kernel image (initmem) memory: 4912K 326s [ 0.917439] Write protecting the kernel read-only data: 38912k 326s [ 0.918814] Freeing unused kernel image (rodata/data gap) memory: 2000K 326s [ 0.966764] x86/mm: Checked W+X mappings: passed, no W+X pages found. 326s [ 0.967797] Run /init as init process 326s Loading, please wait... 326s Starting systemd-udevd version 255.4-1ubuntu8.11 326s [ 1.072680] virtio_net virtio0 ens3: renamed from eth0 326s [ 1.102584] FDC 0 is a S82078B 326s [ 1.102716] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 326s [ 1.106287] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 326s Begin: Loading essential drivers ... done. 326s Begin: Running /scripts/init-premount ... done. 326s Begin: Mounting root file system ... Begin: Running /scripts/local-top ... done. 326s Begin: Running /scripts/local-premount ... [ 2.243456] raid6: avx2x4 gen() 50011 MB/s 326s [ 2.260457] raid6: avx2x2 gen() 50405 MB/s 326s [ 2.277457] raid6: avx2x1 gen() 42706 MB/s 326s [ 2.278170] raid6: using algorithm avx2x2 gen() 50405 MB/s 326s [ 2.295456] raid6: .... xor() 35630 MB/s, rmw enabled 326s [ 2.296267] raid6: using avx2x2 recovery algorithm 326s [ 2.297912] xor: automatically using best checksumming function avx 326s [ 2.365619] Btrfs loaded, zoned=yes, fsverity=yes 326s Scanning for Btrfs filesystems 326s done. 326s Begin: Will now check root file system ... fsck from util-linux 2.39.3 326s [/usr/sbin/fsck.ext4 (1) -- /dev/sda1] fsck.ext4 -a -C0 /dev/sda1 326s cloudimg-rootfs: clean, 82181/2490368 files, 756461/4980475 blocks 326s done. 326s [ 2.410440] EXT4-fs (sda1): mounted filesystem ffe7eb40-94f7-473b-b336-935460126345 ro with ordered data mode. Quota mode: none. 326s done. 326s Begin: Running /scripts/local-bottom ... done. 326s Begin: Running /scripts/init-bottom ... done. 326s [ 2.536583] systemd[1]: Inserted module 'autofs4' 326s [ 2.557347] systemd[1]: systemd 255.4-1ubuntu8.11 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 +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP +SYSVINIT default-hierarchy=unified) 326s [ 2.561958] systemd[1]: Detected virtualization kvm. 326s [ 2.562811] systemd[1]: Detected architecture x86-64. 326s 326s Welcome to [1mUbuntu 24.04.3 LTS[0m! 326s 326s [ 2.565492] systemd[1]: Hostname set to . 326s [ 2.721956] systemd[1]: Queued start job for default target graphical.target. 326s [ 2.736443] systemd[1]: Created slice system-autopkgtest.slice - Slice /system/autopkgtest. 326s [[0;32m OK [0m] Created slice [0;1;39msystem-autopkgtest.slice[0m - Slice /system/autopkgtest. 326s [ 2.739287] systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. 326s [[0;32m OK [0m] Created slice [0;1;39msystem-modprobe.slice[0m - Slice /system/modprobe. 326s [ 2.741877] systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. 326s [[0;32m OK [0m] Created slice [0;1;39msystem-serial\x2dget���slice[0m - Slice /system/serial-getty. 326s [ 2.744711] systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. 326s [[0;32m OK [0m] Created slice [0;1;39msystem-systemd\x2dfs���slice[0m - Slice /system/systemd-fsck. 326s [ 2.747552] systemd[1]: Created slice user.slice - User and Session Slice. 326s [[0;32m OK [0m] Created slice [0;1;39muser.slice[0m - User and Session Slice. 326s [ 2.749647] systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. 326s [[0;32m OK [0m] Started [0;1;39msystemd-ask-password-wall.���[0md Requests to Wall Directory Watch. 326s [ 2.752469] systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. 326s [[0;32m OK [0m] Set up automount [0;1;39mproc-sys-fs-binfm���[0mormats File System Automount Point. 326s [ 2.755464] systemd[1]: Expecting device dev-disk-by\x2dlabel-BOOT.device - /dev/disk/by-label/BOOT... 326s Expecting device [0;1;39mdev-disk-by\x2dla���device[0m - /dev/disk/by-label/BOOT... 326s [ 2.757934] systemd[1]: Expecting device dev-disk-by\x2dlabel-UEFI.device - /dev/disk/by-label/UEFI... 326s Expecting device [0;1;39mdev-disk-by\x2dla���device[0m - /dev/disk/by-label/UEFI... 326s [ 2.760389] systemd[1]: Expecting device dev-ttyS0.device - /dev/ttyS0... 326s Expecting device [0;1;39mdev-ttyS0.device[0m - /dev/ttyS0... 326s [ 2.762738] systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. 326s [[0;32m OK [0m] Reached target [0;1;39mintegritysetup.targ���[0m Local Integrity Protected Volumes. 326s [ 2.765285] systemd[1]: Reached target remote-fs.target - Remote File Systems. 326s [[0;32m OK [0m] Reached target [0;1;39mremote-fs.target[0m - Remote File Systems. 326s [ 2.767380] systemd[1]: Reached target slices.target - Slice Units. 326s [[0;32m OK [0m] Reached target [0;1;39mslices.target[0m - Slice Units. 326s [ 2.769186] systemd[1]: Reached target swap.target - Swaps. 326s [[0;32m OK [0m] Reached target [0;1;39mswap.target[0m - Swaps. 326s [ 2.770829] systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. 326s [[0;32m OK [0m] Reached target [0;1;39mveritysetup.target[0m - Local Verity Protected Volumes. 326s [ 2.773356] systemd[1]: Listening on multipathd.socket - multipathd control socket. 326s [[0;32m OK [0m] Listening on [0;1;39mmultipathd.socket[0m - multipathd control socket. 326s [ 2.775679] systemd[1]: Listening on syslog.socket - Syslog Socket. 326s [[0;32m OK [0m] Listening on [0;1;39msyslog.socket[0m - Syslog Socket. 326s [ 2.777568] systemd[1]: Listening on systemd-fsckd.socket - fsck to fsckd communication Socket. 326s [[0;32m OK [0m] Listening on [0;1;39msystemd-fsckd.socket[0m ���fsck to fsckd communication Socket. 326s [ 2.780086] systemd[1]: Listening on systemd-initctl.socket - initctl Compatibility Named Pipe. 326s [[0;32m OK [0m] Listening on [0;1;39msystemd-initctl.socke���[0m- initctl Compatibility Named Pipe. 326s [ 2.782645] systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). 326s [[0;32m OK [0m] Listening on [0;1;39msystemd-journald-dev-���socket[0m - Journal Socket (/dev/log). 326s [ 2.785237] systemd[1]: Listening on systemd-journald.socket - Journal Socket. 326s [[0;32m OK [0m] Listening on [0;1;39msystemd-journald.socket[0m - Journal Socket. 326s [ 2.787483] systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. 326s [[0;32m OK [0m] Listening on [0;1;39msystemd-networkd.socket[0m - Network Service Netlink Socket. 326s [ 2.789929] systemd[1]: systemd-pcrextend.socket - TPM2 PCR Extension (Varlink) was skipped because of an unmet condition check (ConditionSecurity=measured-uki). 326s [ 2.792960] systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. 326s [[0;32m OK [0m] Listening on [0;1;39msystemd-udevd-control.socket[0m - udev Control Socket. 326s [ 2.795381] systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. 326s [[0;32m OK [0m] Listening on [0;1;39msystemd-udevd-kernel.socket[0m - udev Kernel Socket. 326s [ 2.803531] systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... 326s Mounting [0;1;39mdev-hugepages.mount[0m - Huge Pages File System... 326s [ 2.807063] systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... 326s Mounting [0;1;39mdev-mqueue.mount[0m - POSIX Message Queue File System... 326s [ 2.811425] systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... 326s Mounting [0;1;39msys-kernel-debug.mount[0m - Kernel Debug File System... 326s [ 2.815884] systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... 326s Mounting [0;1;39msys-kernel-tracing.mount[0m - Kernel Trace File System... 326s [ 2.819944] systemd[1]: Starting systemd-journald.service - Journal Service... 326s Starting [0;1;39msystemd-journald.service[0m - Journal Service... 326s [ 2.825570] systemd[1]: Starting keyboard-setup.service - Set the console keyboard layout... 326s Starting [0;1;39mkeyboard-setup.service[0m - Set the console keyboard layout... 326s [ 2.829942] systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... 326s Starting [0;1;39mkmod-static-nodes.service[0m���eate List of Static Device Nodes... 326s [ 2.834574] systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... 326s [ 2.835487] systemd-journald[263]: Collecting audit messages is disabled. 326s Starting [0;1;39mmodprobe@configfs.service[0m - Load Kernel Module configfs... 326s [ 2.845437] systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... 326s Starting [0;1;39mmodprobe@dm_mod.service[0m - Load Kernel Module dm_mod... 326s [ 2.852566] systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... 326s Starting [0;1;39mmodprobe@drm.service[0m - Load Kernel Module drm... 326s [ 2.860571] systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... 326s Starting [0;1;39mmodprobe@efi_pstore.servi���[0m - Load Kernel Module efi_pstore... 326s [ 2.878406] systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... 326s Starting [0;1;39mmodprobe@fuse.service[0m - Load Kernel Module fuse... 326s [ 2.884440] systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... 326s Starting [0;1;39mmodprobe@loop.service[0m - Load Kernel Module loop... 326s [ 2.886651] systemd[1]: netplan-ovs-cleanup.service - OpenVSwitch configuration for cleanup was skipped because of an unmet condition check (ConditionFileIsExecutable=/usr/bin/ovs-vsctl). 326s [ 2.889155] 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). 326s [ 2.894608] systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... 326s Starting [0;1;39msystemd-modules-load.service[0m - Load Kernel Modules... 326s [ 2.896855] systemd[1]: systemd-pcrmachine.service - TPM2 PCR Machine ID Measurement was skipped because of an unmet condition check (ConditionSecurity=measured-uki). 326s [ 2.900665] systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... 326s Starting [0;1;39msystemd-remount-fs.servic���[0munt Root and Kernel File Systems... 326s [ 2.903366] systemd[1]: systemd-tpm2-setup-early.service - TPM2 SRK Setup (Early) was skipped because of an unmet condition check (ConditionSecurity=measured-uki). 326s [ 2.906591] systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... 326s Starting [0;1;39msystemd-udev-trigger.service[0m - Coldplug All udev Devices... 326s [ 2.910398] systemd[1]: Started systemd-journald.service - Journal Service. 326s [[0;32m OK [0m] Started [0;1;39msystemd-journald.service[0m - Journal Service. 326s [[0;32m OK [0m] Mounted [0;1;39mdev-hugepages.mount[0m - Huge Pages File System. 326s [[0;32m OK [0m] Mounted [0;1;39mdev-mqueue.mount[0m - POSIX Message Queue File System. 326s [[0;32m OK [0m] Mounted [0;1;39msys-kernel-debug.mount[0m - Kernel Debug File System. 326s [[0;32m OK [0m] Mounted [0;1;39msys-kernel-tracing.mount[0m - Kernel Trace File System. 326s [[0;32m OK [0m] Finished [0;1;39mkeyboard-setup.service[0m - Set the console keyboard layout.[ 2.922219] EXT4-fs (sda1): re-mounted ffe7eb40-94f7-473b-b336-935460126345 r/w. Quota mode: none. 326s 326s [[0;32m OK [0m] Finished [0;1;39mkmod-static-nodes.service[0m���Create List of Static Device Nodes. 326s [[0;32m OK [0m] Finished [0;1;39mmodprobe@configfs.service[0m - Load Kernel Module configfs. 326s [[0;32m OK [0m] Finished [0;1;39mmodprobe@dm_mod.service[0m - Load Kernel Module dm_mod. 326s [[0;32m OK [0m] Finished [0;1;39mmodprobe@drm.service[0m - Load Kernel Module drm. 326s [[0;32m OK [0m] Finished [0;1;39mmodprobe@efi_pstore.service[0m - Load Kernel Module efi_pstore. 326s [[0;32m OK [0m] Finished [0;1;39mmodprobe@fuse.service[0m - Load Kernel Module fuse. 326s [[0;32m OK [0m] Finished [0;1;39mmodprobe@loop.service[0m - Load Kernel Module loop. 326s [[0;32m OK [0m] Finished [0;1;39msystemd-modules-load.service[0m - Load Kernel Modules. 326s [[0;32m OK [0m] Finished [0;1;39msystemd-remount-fs.servic���[0mmount Root and Kernel File Systems. 326s Mounting [0;1;39msys-fs-fuse-connections.mount[0m - FUSE Control File System... 326s Mounting [0;1;39msys-kernel-config.mount[0m - Kernel Configuration File System... 326s Starting [0;1;39mcloud-init-local.service[0m ���-init: Local Stage (pre-network)... 326s Starting [0;1;39mmultipathd.service[0m - Devi���pper Multipath Device Controller... 326s Starting [0;1;39msystemd-journal-flush.ser���[0msh Journal to Persistent Storage... 326s Starting [0;1;39msystemd-random-seed.service[0m - Load/Save OS Random Seed... 326s Starting [0;1;39msystemd-sysctl.service[0m - Apply Kernel Variables... 326s [ 2.993356] systemd-journald[263]: Received client request to flush runtime journal. 326s Starting [0;1;39msystemd-tmpfiles-setup-de���[0m Device Nodes in /dev gracefully... 326s [ 3.006242] systemd-journald[263]: /var/log/journal/ea8e8369d1ee47fe9da4853a2842cf27/system.journal: Journal file uses a different sequence number ID, rotating. 326s [[0;32m OK [0m] Mounted [0;1;39msys-fs-fuse-connections.mount[0m - FUSE Control File System.[ 3.014515] systemd-journald[263]: Rotating system journal. 326s 326s [[0;32m OK [0m] Mounted [0;1;39msys-kernel-config.mount[0m - Kernel Configuration File System. 326s [[0;32m OK [0m] Finished [0;1;39msystemd-sysctl.service[0m - Apply Kernel Variables. 326s [[0;32m OK [0m] Finished [0;1;39msystemd-tmpfiles-setup-de���[0mic Device Nodes in /dev gracefully. 326s [[0;32m OK [0m] Finished [0;1;39msystemd-udev-trigger.service[0m - Coldplug All udev Devices. 326s Starting [0;1;39msystemd-tmpfiles-setup-de���[0meate Static Device Nodes in /dev... 326s [[0;32m OK [0m] Finished [0;1;39msystemd-tmpfiles-setup-de���[0mCreate Static Device Nodes in /dev. 326s Starting [0;1;39msystemd-udevd.service[0m - R���ager for Device Events and Files... 326s [[0;32m OK [0m] Finished [0;1;39msystemd-journal-flush.ser���[0mlush Journal to Persistent Storage. 326s [[0;32m OK [0m] Finished [0;1;39msystemd-random-seed.service[0m - Load/Save OS Random Seed. 326s [[0;32m OK [0m] Started [0;1;39mmultipathd.service[0m - Devic���Mapper Multipath Device Controller. 326s [[0;32m OK [0m] Reached target [0;1;39mlocal-fs-pre.target[0m���Preparation for Local File Systems. 326s [[0;32m OK [0m] Started [0;1;39msystemd-udevd.service[0m - Ru���anager for Device Events and Files. 326s [[0;32m OK [0m] Started [0;1;39msystemd-ask-password-conso���[0mequests to Console Directory Watch. 326s [[0;32m OK [0m] Reached target [0;1;39mcryptsetup.target[0m - Local Encrypted Volumes. 326s [[0;32m OK [0m] Found device [0;1;39mdev-ttyS0.device[0m - /dev/ttyS0. 326s [[0;32m OK [0m] Found device [0;1;39mdev-disk-by\x2dlabel-BOOT.device[0m - QEMU_HARDDISK BOOT. 326s [[0;32m OK [0m] Listening on [0;1;39msystemd-rfkill.socket[0m���ll Switch Status /dev/rfkill Watch. 326s Starting [0;1;39msystemd-fsck@dev-disk-by\���[0mCheck on /dev/disk/by-label/BOOT... 326s [[0;32m OK [0m] Found device [0;1;39mdev-disk-by\x2dlabel-UEFI.device[0m - QEMU_HARDDISK UEFI. 326s Starting [0;1;39msystemd-fsck@dev-disk-by\���[0mCheck on /dev/disk/by-label/UEFI... 326s [[0;32m OK [0m] Started [0;1;39msystemd-fsckd.service[0m - Fi���stem Check Daemon to report status. 326s [ 3.410718] cloud-init[420]: Cloud-init v. 25.2-0ubuntu1~24.04.1 running 'init-local' at Thu, 30 Oct 2025 08:47:16 +0000. Up 3.37 seconds. 326s [[0;32m OK [0m] Finished [0;1;39msystemd-fsck@dev-disk-by\���[0mm Check on /dev/disk/by-label/BOOT. 326s [[0;32m OK [0m] Finished [0;1;39msystemd-fsck@dev-disk-by\���[0mm Check on /dev/disk/by-label/UEFI. 326s Checking in progress on 1 disk (0.0% complete)Checking in progress on 0 disks (100.0% complete) Mounting [0;1;39mboot.mount[0m - /boot... 326s [[0;32m OK [0m] Mounted [0;1;39mboot.mount[0m - /boot. 326s Mounting [0;1;39mboot-efi.mount[0m - /boot/efi... 326s [[0;32m OK [0m] Mounted [0;1;39mboot-efi.mount[0m - /boot/efi. 326s [[0;32m OK [0m] Reached target [0;1;39mlocal-fs.target[0m - Local File Systems. 326s [[0;32m OK [0m] Listening on [0;1;39msystemd-sysext.socket[0m���tension Image Management (Varlink). 326s Starting [0;1;39mapparmor.service[0m - Load AppArmor profiles... 326s Starting [0;1;39mconsole-setup.service[0m - Set console font and keymap... 326s Starting [0;1;39mplymouth-read-write.servi���[0mymouth To Write Out Runtime Data... 326s Starting [0;1;39msystemd-binfmt.service[0m - Set Up Additional Binary Formats... 326s Starting [0;1;39msystemd-tmpfiles-setup.se���[0me Volatile Files and Directories... 326s Starting [0;1;39mufw.service[0m - Uncomplicated firewall... 326s [[0;32m OK [0m] Finished [0;1;39mconsole-setup.service[0m - Set console font and keymap. 326s Mounting [0;1;39mproc-sys-fs-binfmt_misc.m���[0mcutable File Formats File System... 326s [[0;32m OK [0m] Finished [0;1;39mplymouth-read-write.servi���[0mPlymouth To Write Out Runtime Data. 326s [[0;32m OK [0m] Finished [0;1;39mufw.service[0m - Uncomplicated firewall. 326s [[0;32m OK [0m] Mounted [0;1;39mproc-sys-fs-binfmt_misc.mo���[0mxecutable File Formats File System. 326s [[0;32m OK [0m] Finished [0;1;39msystemd-binfmt.service[0m - Set Up Additional Binary Formats. 326s [[0;32m OK [0m] Finished [0;1;39msystemd-tmpfiles-setup.se���[0mate Volatile Files and Directories. 326s Starting [0;1;39msystemd-resolved.service[0m - Network Name Resolution... 326s Starting [0;1;39msystemd-timesyncd.service[0m - Network Time Synchronization... 326s Starting [0;1;39msystemd-update-utmp.servi���[0mord System Boot/Shutdown in UTMP... 326s [[0;32m OK [0m] Finished [0;1;39msystemd-update-utmp.servi���[0mecord System Boot/Shutdown in UTMP. 326s [[0;32m OK [0m] Started [0;1;39msystemd-timesyncd.service[0m - Network Time Synchronization. 326s [[0;32m OK [0m] Reached target [0;1;39mtime-set.target[0m - System Time Set. 326s [[0;32m OK [0m] Started [0;1;39msystemd-resolved.service[0m - Network Name Resolution. 326s [[0;32m OK [0m] Reached target [0;1;39mnss-lookup.target[0m - Host and Network Name Lookups. 326s [[0;32m OK [0m] Finished [0;1;39mapparmor.service[0m - Load AppArmor profiles. 326s [[0;32m OK [0m] Finished [0;1;39mcloud-init-local.service[0m ���ud-init: Local Stage (pre-network). 326s [[0;32m OK [0m] Reached target [0;1;39mnetwork-pre.target[0m - Preparation for Network. 326s Starting [0;1;39msystemd-networkd.service[0m - Network Configuration... 326s [[0;32m OK [0m] Started [0;1;39msystemd-networkd.service[0m - Network Configuration. 326s [[0;32m OK [0m] Reached target [0;1;39mnetwork.target[0m - Network. 326s Starting [0;1;39msystemd-networkd-wait-onl���[0mait for Network to be Configured... 326s [[0;32m OK [0m] Finished [0;1;39msystemd-networkd-wait-onl���[0m Wait for Network to be Configured. 326s Starting [0;1;39mcloud-init.service[0m - Cloud-init: Network Stage... 326s [ 8.463725] cloud-init[663]: Cloud-init v. 25.2-0ubuntu1~24.04.1 running 'init' at Thu, 30 Oct 2025 08:47:21 +0000. Up 8.45 seconds. 326s [ 8.470422] cloud-init[663]: ci-info: +++++++++++++++++++++++++++++++++++++++Net device info+++++++++++++++++++++++++++++++++++++++ 326s [ 8.471938] cloud-init[663]: ci-info: +--------+------+------------------------------+---------------+--------+-------------------+ 326s [ 8.473425] cloud-init[663]: ci-info: | Device | Up | Address | Mask | Scope | Hw-Address | 326s [ 8.475085] cloud-init[663]: ci-info: +--------+------+------------------------------+---------------+--------+-------------------+ 326s [ 8.476524] cloud-init[663]: ci-info: | ens3 | True | 10.151.36.25 | 255.255.255.0 | global | fa:16:3e:d7:10:58 | 326s [ 8.477968] cloud-init[663]: ci-info: | ens3 | True | fe80::f816:3eff:fed7:1058/64 | . | link | fa:16:3e:d7:10:58 | 326s [ 8.479414] cloud-init[663]: ci-info: | lo | True | 127.0.0.1 | 255.0.0.0 | host | . | 326s [ 8.480859] cloud-init[663]: ci-info: | lo | True | ::1/128 | . | host | . | 326s [ 8.482739] cloud-init[663]: ci-info: +--------+------+------------------------------+---------------+--------+-------------------+ 326s [ 8.484282] cloud-init[663]: ci-info: ++++++++++++++++++++++++++++++++Route IPv4 info++++++++++++++++++++++++++++++++ 326s [ 8.485689] cloud-init[663]: ci-info: +-------+-----------------+-------------+-----------------+-----------+-------+ 326s [ 8.487207] cloud-init[663]: ci-info: | Route | Destination | Gateway | Genmask | Interface | Flags | 326s [ 8.488530] cloud-init[663]: ci-info: +-------+-----------------+-------------+-----------------+-----------+-------+ 326s [ 8.489840] cloud-init[663]: ci-info: | 0 | 0.0.0.0 | 10.151.36.1 | 0.0.0.0 | ens3 | UG | 326s [ 8.491248] cloud-init[663]: ci-info: | 1 | 10.151.11.5 | 10.151.36.1 | 255.255.255.255 | ens3 | UGH | 326s [ 8.492576] cloud-init[663]: ci-info: | 2 | 10.151.11.6 | 10.151.36.1 | 255.255.255.255 | ens3 | UGH | 326s [ 8.493927] cloud-init[663]: ci-info: | 3 | 10.151.11.7 | 10.151.36.1 | 255.255.255.255 | ens3 | UGH | 326s [ 8.495311] cloud-init[663]: ci-info: | 4 | 10.151.36.0 | 0.0.0.0 | 255.255.255.0 | ens3 | U | 326s [ 8.496622] cloud-init[663]: ci-info: | 5 | 10.151.36.1 | 0.0.0.0 | 255.255.255.255 | ens3 | UH | 326s [ 8.497965] cloud-init[663]: ci-info: | 6 | 10.151.36.2 | 0.0.0.0 | 255.255.255.255 | ens3 | UH | 326s [ 8.502524] cloud-init[663]: ci-info: | 7 | 169.254.169.254 | 10.151.36.2 | 255.255.255.255 | ens3 | UGH | 326s [ 8.503859] cloud-init[663]: ci-info: +-------+-----------------+-------------+-----------------+-----------+-------+ 326s [ 8.505199] cloud-init[663]: ci-info: +++++++++++++++++++Route IPv6 info+++++++++++++++++++ 326s [ 8.506535] cloud-init[663]: ci-info: +-------+-------------+---------+-----------+-------+ 326s [ 8.507701] cloud-init[663]: ci-info: | Route | Destination | Gateway | Interface | Flags | 326s [ 8.508760] cloud-init[663]: ci-info: +-------+-------------+---------+-----------+-------+ 326s [ 8.509808] cloud-init[663]: ci-info: | 0 | fe80::/64 | :: | ens3 | U | 326s [ 8.510859] cloud-init[663]: ci-info: | 2 | local | :: | ens3 | U | 326s [ 8.511950] cloud-init[663]: ci-info: | 3 | multicast | :: | ens3 | U | 326s [ 8.513015] cloud-init[663]: ci-info: +-------+-------------+---------+-----------+-------+ 326s [ 8.514217] cloud-init[663]: 2025-10-30 08:47:21,392 - 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. 326s [ 8.517232] cloud-init[663]: 2025-10-30 08:47:21,392 - schema.py[WARNING]: cloud-config failed schema validation! You may run 'sudo cloud-init schema --system' to check the details. 326s [ 9.475839] cloud-init[663]: Generating public/private rsa key pair. 326s [ 9.478131] cloud-init[663]: Your identification has been saved in /etc/ssh/ssh_host_rsa_key 326s [ 9.479240] cloud-init[663]: Your public key has been saved in /etc/ssh/ssh_host_rsa_key.pub 326s [ 9.480317] cloud-init[663]: The key fingerprint is: 326s [ 9.480986] cloud-init[663]: SHA256:Zdy8X8iiAK01mcc2eBqsOEYk/R/Ip5RMuL0ExzHclro root@autopkgtest 326s [ 9.482105] cloud-init[663]: The key's randomart image is: 326s [ 9.482824] cloud-init[663]: +---[RSA 3072]----+ 326s [ 9.483468] cloud-init[663]: | ...+oo . | 326s [ 9.484155] cloud-init[663]: | o+ =++* o | 326s [ 9.484785] cloud-init[663]: | .X.=X X o | 326s [ 9.485414] cloud-init[663]: | ...@=oX . o . | 326s [ 9.486090] cloud-init[663]: | +oo*S. o o .| 326s [ 9.486744] cloud-init[663]: | . .E .. . o . | 326s [ 9.487375] cloud-init[663]: | . . | 326s [ 9.487998] cloud-init[663]: | | 326s [ 9.488639] cloud-init[663]: | | 326s [ 9.489271] cloud-init[663]: +----[SHA256]-----+ 326s [ 9.489900] cloud-init[663]: Generating public/private ecdsa key pair. 326s [ 9.490743] cloud-init[663]: Your identification has been saved in /etc/ssh/ssh_host_ecdsa_key 326s [ 9.491837] cloud-init[663]: Your public key has been saved in /etc/ssh/ssh_host_ecdsa_key.pub 326s [ 9.492931] cloud-init[663]: The key fingerprint is: 326s [ 9.493610] cloud-init[663]: SHA256:IGtecSyAYconLxeBWhPrIOoVrOhmWJ3qxoO8Xraj+FM root@autopkgtest 326s [ 9.494714] cloud-init[663]: The key's randomart image is: 326s [ 9.495438] cloud-init[663]: +---[ECDSA 256]---+ 326s [ 9.496089] cloud-init[663]: | ==. | 326s [ 9.496718] cloud-init[663]: |.+=... . | 326s [ 9.497349] cloud-init[663]: |=+.*. + o | 326s [ 9.497979] cloud-init[663]: |=o=.o+ = | 326s [ 9.498665] cloud-init[663]: |ooo+= . S | 326s [ 9.499330] cloud-init[663]: |+.++E. | 326s [ 9.499983] cloud-init[663]: |oB.+. | 326s [ 9.500650] cloud-init[663]: |=oB.. | 326s [ 9.501315] cloud-init[663]: |o*=+. | 326s [ 9.501966] cloud-init[663]: +----[SHA256]-----+ 326s [ 9.502613] cloud-init[663]: Generating public/private ed25519 key pair. 326s [ 9.503493] cloud-init[663]: Your identification has been saved in /etc/ssh/ssh_host_ed25519_key 326s [ 9.504587] cloud-init[663]: Your public key has been saved in /etc/ssh/ssh_host_ed25519_key.pub 326s [ 9.505709] cloud-init[663]: The key fingerprint is: 326s [ 9.506380] cloud-init[663]: SHA256:RltoSmiBeESSQbIVvEmQIlGIYbpRt3hzl2FuRxsItAI root@autopkgtest 326s [ 9.507493] cloud-init[663]: The key's randomart image is: 326s [[0;32m OK [0m] Finished [0;1;39mcloud-init.service[0m - Cloud-init: Network Stage. 326s [[0;32m OK [0m] Reached target [0;1;39mcloud-config.target[0m - Cloud-config availability. 326s [[0;32m OK [0m] Reached target [0;1;39mnetwork-online.target[0m - Network is Online. 326s [[0;32m OK [0m] Reached target [0;1;39msysinit.target[0m - System Initialization. 326s [ 9.508429] cloud-init[663]: +--[ED25519 256]--+ 326s [[0;32m OK [0m] Started [0;1;39mapt-daily.timer[0m - Daily apt download activities. 326s [ 9.513200] cloud-init[663]: |X^Eoo.o.o.o | 326s [ 9.514796] cloud-init[663]: |%==+ + +.= o | 326s [ 9.515403] cloud-init[663]: |*o.oB + O + | 326s [ 9.516029] cloud-init[663]: | ooo = * + | 326s [ 9.516082] cloud-init[663]: |. . S | 326s [ 9.516230] cloud-init[663]: | . | 326s [ 9.516385] cloud-init[663]: | | 326s [ 9.516548] cloud-init[663]: | | 326s [ 9.516694] cloud-init[663]: | | 326s [ 9.516847] cloud-init[663]: +----[SHA256]-----+ 326s [[0;32m OK [0m] Started [0;1;39mapt-daily-upgrade.timer[0m - ���y apt upgrade and clean activities. 326s [[0;32m OK [0m] Started [0;1;39mdpkg-db-backup.timer[0m - Daily dpkg database backup timer. 326s [[0;32m OK [0m] Started [0;1;39me2scrub_all.timer[0m - Period���Metadata Check for All Filesystems. 326s [[0;32m OK [0m] Started [0;1;39mfstrim.timer[0m - Discard unused filesystem blocks once a week. 326s [[0;32m OK [0m] Started [0;1;39mfwupd-refresh.timer[0m - Refresh fwupd metadata regularly. 326s [[0;32m OK [0m] Started [0;1;39mlogrotate.timer[0m - Daily rotation of log files. 326s [[0;32m OK [0m] Started [0;1;39mman-db.timer[0m - Daily man-db regeneration. 326s [[0;32m OK [0m] Started [0;1;39mmotd-news.timer[0m - Message of the Day. 326s [[0;32m OK [0m] Started [0;1;39msysstat-collect.timer[0m - Ru���y accounting tool every 10 minutes. 326s [[0;32m OK [0m] Started [0;1;39msysstat-summary.timer[0m - Ge��� of yesterday's process accounting. 326s [[0;32m OK [0m] Started [0;1;39msystemd-tmpfiles-clean.tim���[0my Cleanup of Temporary Directories. 326s [[0;32m OK [0m] Reached target [0;1;39mpaths.target[0m - Path Units. 326s [[0;32m OK [0m] Reached target [0;1;39mtimers.target[0m - Timer Units. 326s [[0;32m OK [0m] Listening on [0;1;39mcloud-init-hotplugd.s���t[0m - cloud-init hotplug hook socket. 326s [[0;32m OK [0m] Listening on [0;1;39mdbus.socket[0m - D-Bus System Message Bus Socket. 326s Starting [0;1;39mlxd-installer.socket[0m - He���er to install lxd snap on demand... 326s [[0;32m OK [0m] Listening on [0;1;39mssh.socket[0m - OpenBSD Secure Shell server socket. 326s [[0;32m OK [0m] Listening on [0;1;39muuidd.socket[0m - UUID daemon activation socket. 326s [[0;32m OK [0m] Listening on [0;1;39mlxd-installer.socket[0m ���lper to install lxd snap on demand. 326s [[0;32m OK [0m] Reached target [0;1;39msockets.target[0m - Socket Units. 326s [[0;32m OK [0m] Reached target [0;1;39mbasic.target[0m - Basic System. 326s Starting [0;1;39mapport.service[0m - automatic crash report generation... 326s [[0;32m OK [0m] Started [0;1;39mautopkgtest@ttyS1.service[0m - autopkgtest root shell on ttyS1. 326s Starting [0;1;39mcloud-config.service[0m - Cloud-init: Config Stage... 326s [[0;32m OK [0m] Started [0;1;39mcron.service[0m - Regular background program processing daemon. 326s Starting [0;1;39mdbus.service[0m - D-Bus System Message Bus... 326s [[0;32m OK [0m] Started [0;1;39mdmesg.service[0m - Save initial kernel messages after boot. 326s Starting [0;1;39mdpkg-db-backup.service[0m - ���ily dpkg database backup service... 326s Starting [0;1;39me2scrub_reap.service[0m - Re���ne ext4 Metadata Check Snapshots... 326s Starting [0;1;39mgrub-common.service[0m - Record successful boot for GRUB... 326s Starting [0;1;39mpollinate.service[0m - Polli���e pseudo random number generator... 326s Starting [0;1;39mrng-tools-debian.service[0m - LSB: rng-tools (Debian variant)... 326s Starting [0;1;39mrsyslog.service[0m - System Logging Service... 326s Starting [0;1;39msysstat.service[0m - Resets System Activity Logs... 326s Starting [0;1;39msystemd-logind.service[0m - User Login Management... 326s Starting [0;1;39msystemd-user-sessions.service[0m - Permit User Sessions... 326s Starting [0;1;39mudisks2.service[0m - Disk Manager... 326s Starting [0;1;39mlogrotate.service[0m - Rotate log files... 326s [[0;32m OK [0m] Started [0;1;39mdbus.service[0m - D-Bus System Message Bus. 326s [[0;32m OK [0m] Finished [0;1;39me2scrub_reap.service[0m - Re���line ext4 Metadata Check Snapshots. 326s [[0;32m OK [0m] Finished [0;1;39mdpkg-db-backup.service[0m - Daily dpkg database backup service. 326s [[0;32m OK [0m] Finished [0;1;39msysstat.service[0m - Resets System Activity Logs. 326s [[0;32m OK [0m] Started [0;1;39msystemd-logind.service[0m - User Login Management. 326s [[0;32m OK [0m] Finished [0;1;39msystemd-user-sessions.service[0m - Permit User Sessions. 326s [[0;32m OK [0m] Finished [0;1;39mapport.service[0m - automatic crash report generation. 326s [[0;32m OK [0m] Finished [0;1;39mlogrotate.service[0m - Rotate log files. 326s [[0;32m OK [0m] Finished [0;1;39mgrub-common.service[0m - Record successful boot for GRUB. 326s [[0;32m OK [0m] Started [0;1;39mudisks2.service[0m - Disk Manager. 326s Starting [0;1;39mgrub-initrd-fallback.service[0m - GRUB failed boot detection... 326s Starting [0;1;39mplymouth-quit-wait.servic���[0md until boot process finishes up... 326s Starting [0;1;39mplymouth-quit.service[0m - Terminate Plymouth Boot Screen... 326s [[0;32m OK [0m] Started [0;1;39mrng-tools-debian.service[0m - LSB: rng-tools (Debian variant). 326s [[0;32m OK [0m] Finished [0;1;39mplymouth-quit-wait.servic���[0mold until boot process finishes up. 326s [[0;32m OK [0m] Finished [0;1;39mgrub-initrd-fallback.service[0m - GRUB failed boot detection. 326s [[0;32m OK [0m] Started [0;1;39mserial-getty@ttyS0.service[0m - Serial Getty on ttyS0. 326s Starting [0;1;39msetvtrgb.service[0m - Set console scheme... 326s [[0;32m OK [0m] Started [0;1;39mrsyslog.service[0m - System Logging Service. 326s [[0;32m OK [0m] Finished [0;1;39mplymouth-quit.service[0m - Terminate Plymouth Boot Screen. 326s [[0;32m OK [0m] Finished [0;1;39msetvtrgb.service[0m - Set console scheme. 326s [[0;32m OK [0m] Created slice [0;1;39msystem-getty.slice[0m - Slice /system/getty. 326s [[0;32m OK [0m] Started [0;1;39mgetty@tty1.service[0m - Getty on tty1. 326s [[0;32m OK [0m] Reached target [0;1;39mgetty.target[0m - Login Prompts. 326s [ 10.004522] cloud-init[883]: Cloud-init v. 25.2-0ubuntu1~24.04.1 running 'modules:config' at Thu, 30 Oct 2025 08:47:22 +0000. Up 9.97 seconds. 326s [ 10.129414] cloud-init[883]: 2025-10-30 08:47:23,011 - 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. 326s [ 10.131810] cloud-init[883]: 2025-10-30 08:47:23,011 - 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. 326s [[0;32m OK [0m] Finished [0;1;39mcloud-config.service[0m - Cloud-init: Config Stage. 326s 326s Ubuntu 24.04.3 LTS autopkgtest ttyS0 326s 326s autopkgtest login: [ 20.199036] cloud-init[935]: Cloud-init v. 25.2-0ubuntu1~24.04.1 running 'modules:final' at Thu, 30 Oct 2025 08:47:33 +0000. Up 20.17 seconds. 326s [ 20.376223] cloud-init[935]: Hit:1 http://ftpmaster.internal/ubuntu noble InRelease 326s [ 20.404938] cloud-init[935]: Get:2 http://ftpmaster.internal/ubuntu noble-updates InRelease [126 kB] 326s [ 20.439691] cloud-init[935]: Get:3 http://ftpmaster.internal/ubuntu noble-security InRelease [126 kB] 326s [ 20.522043] cloud-init[935]: Get:4 http://ftpmaster.internal/ubuntu noble-updates/main Sources [475 kB] 326s [ 20.577847] cloud-init[935]: Get:5 http://ftpmaster.internal/ubuntu noble-updates/universe Sources [482 kB] 326s [ 20.616149] cloud-init[935]: Get:6 http://ftpmaster.internal/ubuntu noble-updates/restricted Sources [55.4 kB] 326s [ 20.661379] cloud-init[935]: Get:7 http://ftpmaster.internal/ubuntu noble-updates/main i386 Packages [545 kB] 326s [ 20.962777] cloud-init[935]: Get:8 http://ftpmaster.internal/ubuntu noble-updates/main amd64 Packages [1570 kB] 326s [ 21.330312] cloud-init[935]: Get:9 http://ftpmaster.internal/ubuntu noble-updates/restricted amd64 Packages [2217 kB] 326s [ 21.654257] cloud-init[935]: Get:10 http://ftpmaster.internal/ubuntu noble-updates/universe amd64 Packages [1498 kB] 326s [ 21.837733] cloud-init[935]: Get:11 http://ftpmaster.internal/ubuntu noble-updates/universe i386 Packages [988 kB] 326s [ 21.975075] cloud-init[935]: Get:12 http://ftpmaster.internal/ubuntu noble-security/universe Sources [325 kB] 326s [ 22.008019] cloud-init[935]: Get:13 http://ftpmaster.internal/ubuntu noble-security/main Sources [208 kB] 326s [ 22.047694] cloud-init[935]: Get:14 http://ftpmaster.internal/ubuntu noble-security/main amd64 Packages [1270 kB] 326s [ 22.163529] cloud-init[935]: Get:15 http://ftpmaster.internal/ubuntu noble-security/main i386 Packages [344 kB] 326s [ 22.201832] cloud-init[935]: Get:16 http://ftpmaster.internal/ubuntu noble-security/universe amd64 Packages [906 kB] 326s [ 22.277295] cloud-init[935]: Get:17 http://ftpmaster.internal/ubuntu noble-security/universe i386 Packages [562 kB] 326s [ 22.418225] cloud-init[935]: Fetched 11.7 MB in 2s (5504 kB/s) 326s [ 23.344093] cloud-init[935]: Reading package lists... 326s ci-info: ++++++++++++++++++++++++++++++++++++++++++++++++++++++Authorized keys from /home/ubuntu/.ssh/authorized_keys for user ubuntu+++++++++++++++++++++++++++++++++++++++++++++++++++++++ 326s ci-info: +---------+-------------------------------------------------------------------------------------------------+---------+-----------------------------------------------------------+ 326s ci-info: | Keytype | Fingerprint (sha256) | Options | Comment | 326s ci-info: +---------+-------------------------------------------------------------------------------------------------+---------+-----------------------------------------------------------+ 326s 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 | 326s ci-info: | ssh-rsa | 2a:d9:03:84:6a:0e:d7:a1:c3:3d:2c:94:92:f9:7f:cd:87:dd:2b:3e:c8:1b:1c:8c:69:cc:32:78:8b:3a:64:69 | - | ubuntu@juju-7f2275-prod-proposed-migration-environment-20 | 326s ci-info: +---------+-------------------------------------------------------------------------------------------------+---------+-----------------------------------------------------------+ 326s <14>Oct 30 08:47:36 cloud-init: ############################################################# 326s <14>Oct 30 08:47:36 cloud-init: -----BEGIN SSH HOST KEY FINGERPRINTS----- 326s <14>Oct 30 08:47:36 cloud-init: 256 SHA256:IGtecSyAYconLxeBWhPrIOoVrOhmWJ3qxoO8Xraj+FM root@autopkgtest (ECDSA) 326s <14>Oct 30 08:47:36 cloud-init: 256 SHA256:RltoSmiBeESSQbIVvEmQIlGIYbpRt3hzl2FuRxsItAI root@autopkgtest (ED25519) 326s <14>Oct 30 08:47:36 cloud-init: 3072 SHA256:Zdy8X8iiAK01mcc2eBqsOEYk/R/Ip5RMuL0ExzHclro root@autopkgtest (RSA) 326s <14>Oct 30 08:47:36 cloud-init: -----END SSH HOST KEY FINGERPRINTS----- 326s <14>Oct 30 08:47:36 cloud-init: ############################################################# 326s -----BEGIN SSH HOST KEY KEYS----- 326s ecdsa-sha2-nistp256 AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBEK8yPbaGlQ/n7VU3OR2RFR0N3NTCH+cl8AlRfvncDT7jT3waSMh5ToL5Tn27QXHE7GW3ITZSfRjQrTBOWAdZVk= root@autopkgtest 326s ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIBpxXCMpV7Cr4ghN8nbIj0K7maYgIopxesuImr8vZfCh root@autopkgtest 326s ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQCaqbfsqNfFj4oPqROzvmMWTZwhTOvvFoZv4qKNhsOoAAh3biEHEXXOIjc3pgFHc0RTfeJcc1tSS61UjHOBoXJmiYujBbOomvWWtyAnq0g7jxl2DNcxa4BejlZTNuHCyQ+7hH6w7hihOk+5q9enHmeRQz4VkRhortVDA74DQIO6nd+3OGo0zXIeZe8Ozqwv1e/xR/ilwB6VMeM4wnWDJc8ps6/aW/mAny30P4Agfyza4qVoYf9t+iWZcPN8IpA3OPhK2lwqCtB3rs4BO9hGyseb0RiVXscfNMFHV9/AhTN5KrY0+jFK40YTBddntjWPO+OuY/NeLFDtRXWzi+jFxSDJC23Wzcc5kKx35gqB9NULK6VOlksC4sSjRBCCzPPM9+IwQAvgrLq9KCOGwojZLRrZIytTfgBhd0x0Ao4bp1WtyawEpPoLbdQh4onCinnRCFe1EqFnH0FcAwhTm+QysjD945guyvh6N9bjeYGAU3Tj9CBRWqOhTvZe/SncjZlDy/0= root@autopkgtest 326s -----END SSH HOST KEY KEYS----- 326s [ 23.420791] cloud-init[935]: Cloud-init v. 25.2-0ubuntu1~24.04.1 finished at Thu, 30 Oct 2025 08:47:36 +0000. Datasource DataSourceOpenStackLocal [net,ver=2]. Up 23.40 seconds 326s 326s --------------------------------------------------- 326s ------- nova show af640175-b30e-44c9-8e86-aea63d323976 (adt-noble-amd64-dpdk-kmods-20251030-082544-juju-7f2275-prod-proposed-migration-environment-20-af9da94d-20bf-46e7-9a02-c720359dbe1f) ------ 327s +--------------------------------------------+--------------------------------------------------------------------------------------------------------------------------------------------------------+ 327s | Property | Value | 327s +--------------------------------------------+--------------------------------------------------------------------------------------------------------------------------------------------------------+ 327s | OS-DCF:diskConfig | MANUAL | 327s | OS-EXT-AZ:availability_zone | AZ1 | 327s | OS-EXT-SRV-ATTR:host | ps7-ra2-n3.ps7.canonical.com | 327s | OS-EXT-SRV-ATTR:hostname | adt-noble-amd64-dpdk-kmods-20251030-082544-juju-7f2275-prod-pro | 327s | OS-EXT-SRV-ATTR:hypervisor_hostname | ps7-ra2-n3.ps7.canonical.com | 327s | OS-EXT-SRV-ATTR:instance_name | instance-0020b9e5 | 327s | OS-EXT-SRV-ATTR:kernel_id | | 327s | OS-EXT-SRV-ATTR:launch_index | 0 | 327s | OS-EXT-SRV-ATTR:ramdisk_id | | 327s | OS-EXT-SRV-ATTR:reservation_id | r-uagb3q6c | 327s | OS-EXT-SRV-ATTR:root_device_name | /dev/sda | 327s | OS-EXT-STS:power_state | 1 | 327s | OS-EXT-STS:task_state | - | 327s | OS-EXT-STS:vm_state | active | 327s | OS-SRV-USG:launched_at | 2025-10-30T08:47:11.000000 | 327s | OS-SRV-USG:terminated_at | - | 327s | accessIPv4 | | 327s | accessIPv6 | | 327s | config_drive | | 327s | created | 2025-10-30T08:47:07Z | 327s | description | - | 327s | flavor:disk | 20 | 327s | flavor:ephemeral | 0 | 327s | flavor:extra_specs | {"aggregate_instance_extra_specs:commit": "amd64", "hw:cpu_policy": "shared", "hw:mem_page_size": "large", "hw:pci_numa_affinity_policy": "preferred"} | 327s | flavor:original_name | autopkgtest-cpu2-ram4-disk20-amd64 | 327s | flavor:ram | 4096 | 327s | flavor:swap | 0 | 327s | flavor:vcpus | 2 | 327s | hostId | 25629e2f690e830ffc7011011585551295d59d39ea96d0d1660452ed | 327s | id | af640175-b30e-44c9-8e86-aea63d323976 | 327s | image | adt/ubuntu-noble-amd64-server-20251029.img (b41dc4cf-b23a-49ac-8f53-9fa4b547e195) | 327s | key_name | testbed-juju-7f2275-prod-proposed-migration-environment-20 | 327s | locked | False | 327s | locked_reason | - | 327s | metadata | {} | 327s | name | adt-noble-amd64-dpdk-kmods-20251030-082544-juju-7f2275-prod-proposed-migration-environment-20-af9da94d-20bf-46e7-9a02-c720359dbe1f | 327s | net_prod-autopkgtest-workers-amd64 network | 10.151.36.25 | 327s | os-extended-volumes:volumes_attached | [] | 327s | progress | 0 | 327s | security_groups | autopkgtest-juju-7f2275-prod-proposed-migration-environment-20@sto01-10.secgroup | 327s | server_groups | [] | 327s | status | ACTIVE | 327s | tags | [] | 327s | tenant_id | c20da633fc2e44cd97ff31be0acd959a | 327s | trusted_image_certificates | - | 327s | updated | 2025-10-30T08:47:12Z | 327s | user_id | d1c206dbd7b64e3eacefc738fd9acdca | 327s +--------------------------------------------+--------------------------------------------------------------------------------------------------------------------------------------------------------+ 328s --------------------------------------------------- 331s : failure: Timed out on waiting for ssh connection 331s autopkgtest [08:52:25]: ERROR: testbed failure: eof from the virtualisation server