0s autopkgtest [19:53:29]: starting date and time: 2025-08-03 19:53:29+0000 0s autopkgtest [19:53:29]: git checkout: 508d4a25 a-v-ssh wait_for_ssh: demote "ssh connection failed" to a debug message 0s autopkgtest [19:53:29]: host juju-7f2275-prod-proposed-migration-environment-21; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.4omd6a__/out --timeout-copy=6000 --setup-commands /home/ubuntu/autopkgtest-cloud/worker-config-production/setup-canonical.sh --apt-pocket=proposed=src:linux-meta,src:netplan.io --apt-upgrade initramfs-tools --timeout-short=300 --timeout-copy=20000 --timeout-build=20000 '--env=ADT_TEST_TRIGGERS=linux-meta/5.15.0.152.152 netplan.io/0.107.1-3ubuntu0.22.04.2' --setup-commands 'apt-get install -y ^kernel-testing--linux--full--preferred$ || apt-get install -y ^linux-image$ ^linux-headers$ || apt-get install -y ^linux-image-generic$ ^linux-headers-generic$' --setup-commands 'apt-get install -y ^kernel-testing--linux--modules-extra--preferred$ || apt-get install -y ^linux-modules-extra$ || :' -- ssh -s /home/ubuntu/autopkgtest/ssh-setup/nova -- --flavor autopkgtest-ppc64el --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-21@bos03-ppc64el-5.secgroup --name adt-jammy-ppc64el-initramfs-tools-20250803-173835-juju-7f2275-prod-proposed-migration-environment-21-97c6ec16-0c8c-429a-a729-ce32d2e14280 --image adt/ubuntu-jammy-ppc64el-server --keyname testbed-juju-7f2275-prod-proposed-migration-environment-21 --net-id=net_prod-proposed-migration-ppc64el -e TERM=linux --mirror=http://ftpmaster.internal/ubuntu/ 3s Creating nova instance adt-jammy-ppc64el-initramfs-tools-20250803-173835-juju-7f2275-prod-proposed-migration-environment-21-97c6ec16-0c8c-429a-a729-ce32d2e14280 from image adt/ubuntu-jammy-ppc64el-server-20250802.img (UUID 2db06c61-8cb5-4f70-862b-8f635f8ffaa7)... 354s ------- nova console-log 3a7caf9d-a422-450c-a4e3-df07a07ac86a (adt-jammy-ppc64el-initramfs-tools-20250803-173835-juju-7f2275-prod-proposed-migration-environment-21-97c6ec16-0c8c-429a-a729-ce32d2e14280) ------ 357s 357s 357s SLOF[0m[?25l ********************************************************************** 357s [1mQEMU Starting 357s [0m Build Date = Mar 5 2025 10:48:26 357s FW Version = release 20210711 357s Press "s" to enter Open Firmware. 357s 357s [0m[?25hC0000C0100C0120C0140C0200C0240C0260C02E0C0300C0320C0340C0360C0370C0380C0371C0373C0374C03F0C0400C0480C04C0C04D0C0500Populating /vdevice methods 357s Populating /vdevice/v-scsi@2000 357s SCSI: Looking for devices 357s 8000000000000000 CD-ROM : "QEMU QEMU CD-ROM 2.5+" 357s Populating /vdevice/vty@30000000 357s Populating /vdevice/nvram@71000000 357s C05A0Populating /pci@800000020000000 357s 00 0800 (D) : 1af4 1000 virtio [ net ] 357s 00 1000 (D) : 1b36 000d serial bus [ usb-xhci ] 357s 00 1800 (D) : 1af4 1003 virtio [ serial ] 357s 00 2000 (D) : 1af4 1001 virtio [ block ] 357s 00 2800 (D) : 1af4 1002 legacy-device* 357s 00 3000 (D) : 1af4 1005 legacy-device* 357s 00 3800 (D) : 1234 1111 qemu vga 357s C0600C06C0C0700C0800C0880No NVRAM common partition, re-initializing... 357s C0890C08A0C08A8Installing QEMU fb 357s 357s 357s 357s C08B0Scanning USB 357s XHCI: Initializing 357s USB Keyboard 357s USB mouse 357s C08C0C08D0No console specified using screen & keyboard 357s C08E0C08E8C08FF 357s Welcome to Open Firmware 357s 357s Copyright (c) 2004, 2017 IBM Corporation All rights reserved. 357s This program and the accompanying materials are made available 357s under the terms of the BSD License available at 357s http://www.opensource.org/licenses/bsd-license.php 357s 357s 357s Trying to load: from: /pci@800000020000000/scsi@4 ... Successfully loaded 357s Linux ppc64le 357s #161-Ubuntu SMP [ 0.000000] radix-mmu: Page sizes from device-tree: 357s [ 0.000000] radix-mmu: Page size shift = 12 AP=0x0 357s [ 0.000000] radix-mmu: Page size shift = 16 AP=0x5 357s [ 0.000000] radix-mmu: Page size shift = 21 AP=0x1 357s [ 0.000000] radix-mmu: Page size shift = 30 AP=0x2 357s [ 0.000000] Activating Kernel Userspace Execution Prevention 357s [ 0.000000] Activating Kernel Userspace Access Prevention 357s [ 0.000000] radix-mmu: Mapped 0x0000000000000000-0x0000000002800000 with 2.00 MiB pages (exec) 357s [ 0.000000] radix-mmu: Mapped 0x0000000002800000-0x0000000100000000 with 2.00 MiB pages 357s [ 0.000000] lpar: Using radix MMU under hypervisor 357s [ 0.000000] Linux version 5.15.0-151-generic (buildd@bos03-ppc64el-035) (gcc (Ubuntu 11.4.0-1ubuntu1~22.04) 11.4.0, GNU ld (GNU Binutils for Ubuntu) 2.38) #161-Ubuntu SMP Tue Jul 22 14:32:33 UTC 2025 (Ubuntu 5.15.0-151.161-generic 5.15.184) 357s [ 0.000000] Secure boot mode disabled 357s [ 0.000000] Found initrd at 0xc000000004f00000:0xc000000008c01056 357s [ 0.000000] Using pSeries machine description 357s [ 0.000000] Partition configured for 2 cpus. 357s [ 0.000000] CPU maps initialized for 1 thread per core 357s [ 0.000000] numa: Partition configured for 1 NUMA nodes. 357s [ 0.000000] ----------------------------------------------------- 357s [ 0.000000] phys_mem_size = 0x100000000 357s [ 0.000000] dcache_bsize = 0x80 357s [ 0.000000] icache_bsize = 0x80 357s [ 0.000000] cpu_features = 0x0001c06b8f4f9187 357s [ 0.000000] possible = 0x000ffbfbcf5fb187 357s [ 0.000000] always = 0x0000000380008181 357s [ 0.000000] cpu_user_features = 0xdc0065c2 0xaef00000 357s [ 0.000000] mmu_features = 0x3c007641 357s [ 0.000000] firmware_features = 0x00000285455a445f 357s [ 0.000000] vmalloc start = 0xc008000000000000 357s [ 0.000000] IO start = 0xc00a000000000000 357s [ 0.000000] vmemmap start = 0xc00c000000000000 357s [ 0.000000] ----------------------------------------------------- 357s [ 0.000000] numa: NODE_DATA [mem 0xfff68900-0xfff6ffff] 357s [ 0.000000] rfi-flush: fallback displacement flush available 357s [ 0.000000] rfi-flush: ori type flush available 357s [ 0.000000] rfi-flush: mttrig type flush available 357s [ 0.000000] count-cache-flush: hardware flush enabled. 357s [ 0.000000] link-stack-flush: software flush enabled. 357s [ 0.000000] stf-barrier: eieio barrier available 357s [ 0.000000] PPC64 nvram contains 65536 bytes 357s [ 0.000000] barrier-nospec: using ORI speculation barrier 357s [ 0.000000] Zone ranges: 357s [ 0.000000] Normal [mem 0x0000000000000000-0x00000000ffffffff] 357s [ 0.000000] Device empty 357s [ 0.000000] Movable zone start for each node 357s [ 0.000000] Early memory node ranges 357s [ 0.000000] node 0: [mem 0x0000000000000000-0x00000000ffffffff] 357s [ 0.000000] Initmem setup node 0 [mem 0x0000000000000000-0x00000000ffffffff] 357s [ 0.000000] percpu: Embedded 10 pages/cpu s604184 r0 d51176 u655360 357s [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 65472 357s [ 0.000000] Policy zone: Normal 357s [ 0.000000] Kernel command line: BOOT_IMAGE=/boot/vmlinux-5.15.0-151-generic root=UUID=f1264a55-f600-4690-a4c1-cfdf05c1f383 ro console=hvc0 earlyprintk 357s [ 0.000000] Unknown kernel command line parameters "earlyprintk BOOT_IMAGE=/boot/vmlinux-5.15.0-151-generic", will be passed to user space. 357s [ 0.000000] Dentry cache hash table entries: 524288 (order: 6, 4194304 bytes, linear) 357s [ 0.000000] Inode-cache hash table entries: 262144 (order: 5, 2097152 bytes, linear) 357s [ 0.000000] mem auto-init: stack:off, heap alloc:on, heap free:off 357s [ 0.000000] Memory: 3989824K/4194304K available (19392K kernel code, 3904K rwdata, 10304K rodata, 7424K init, 2040K bss, 204480K reserved, 0K cma-reserved) 357s [ 0.000000] SLUB: HWalign=128, Order=0-3, MinObjects=0, CPUs=2, Nodes=1 357s [ 0.000000] ftrace: allocating 45167 entries in 17 pages 357s [ 0.000000] ftrace: allocated 17 pages with 2 groups 357s [ 0.000000] trace event string verifier disabled 357s [ 0.000000] rcu: Hierarchical RCU implementation. 357s [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=2048 to nr_cpu_ids=2. 357s [ 0.000000] Rude variant of Tasks RCU enabled. 357s [ 0.000000] Tracing variant of Tasks RCU enabled. 357s [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies. 357s [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=2 357s [ 0.000000] NR_IRQS: 512, nr_irqs: 512, preallocated irqs: 16 357s [ 0.000000] xive: Using IRQ range [0-1] 357s [ 0.000000] xive: Interrupt handling initialized with spapr backend 357s [ 0.000000] xive: Using priority 6 for all interrupts 357s [ 0.000000] xive: Using 64kB queues 357s [ 0.000000] time_init: 56 bit decrementer (max: 7fffffffffffff) 357s [ 0.000002] clocksource: timebase: mask: 0xffffffffffffffff max_cycles: 0x761537d007, max_idle_ns: 440795202126 ns 357s [ 0.000005] clocksource: timebase mult[1f40000] shift[24] registered 357s [ 0.000061] Console: colour dummy device 80x25 357s [ 0.000098] pid_max: default: 32768 minimum: 301 357s [ 0.000175] LSM: Security Framework initializing 357s [ 0.000205] landlock: Up and running. 357s [ 0.000206] Yama: becoming mindful. 357s [ 0.000227] AppArmor: AppArmor initialized 357s [ 0.000270] Mount-cache hash table entries: 8192 (order: 0, 65536 bytes, linear) 357s [ 0.000276] Mountpoint-cache hash table entries: 8192 (order: 0, 65536 bytes, linear) 357s [ 0.000936] POWER9 performance monitor hardware support registered 357s [ 0.000956] rcu: Hierarchical SRCU implementation. 357s [ 0.001881] smp: Bringing up secondary CPUs ... 357s [ 0.004055] smp: Brought up 1 node, 2 CPUs 357s [ 0.004063] numa: Node 0 CPUs: 0-1 357s [ 0.006614] devtmpfs: initialized 357s [ 0.010726] PCI host bridge /pci@800000020000000 ranges: 357s [ 0.010734] IO 0x0000200000000000..0x000020000000ffff -> 0x0000000000000000 357s [ 0.010737] MEM 0x0000200080000000..0x00002000ffffffff -> 0x0000000080000000 357s [ 0.010741] MEM 0x0000210000000000..0x000021ffffffffff -> 0x0000210000000000 357s [ 0.010758] PCI: OF: PROBE_ONLY disabled 357s [ 0.010785] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns 357s [ 0.010792] futex hash table entries: 512 (order: 0, 65536 bytes, linear) 357s [ 0.010862] pinctrl core: initialized pinctrl subsystem 357s [ 0.011124] NET: Registered PF_NETLINK/PF_ROUTE protocol family 357s [ 0.011208] audit: initializing netlink subsys (disabled) 357s [ 0.012206] thermal_sys: Registered thermal governor 'fair_share' 357s [ 0.012208] thermal_sys: Registered thermal governor 'bang_bang' 357s [ 0.012210] thermal_sys: Registered thermal governor 'step_wise' 357s [ 0.012211] thermal_sys: Registered thermal governor 'user_space' 357s [ 0.012212] thermal_sys: Registered thermal governor 'power_allocator' 357s [ 0.012225] cpuidle: using governor ladder 357s [ 0.012230] cpuidle: using governor menu 357s [ 0.012707] pstore: Registered nvram as persistent store backend 357s [ 0.012786] audit: type=2000 audit(1754250835.012:1): state=initialized audit_enabled=0 res=1 357s [ 0.014252] EEH: pSeries platform initialized 357s [ 0.016892] software IO TLB: tearing down default memory pool 357s [ 0.016996] PCI: Probing PCI hardware 357s [ 0.017120] PCI host bridge to bus 0000:00 357s [ 0.017123] pci_bus 0000:00: root bus resource [io 0x10000-0x1ffff] (bus address [0x0000-0xffff]) 357s [ 0.017127] pci_bus 0000:00: root bus resource [mem 0x200080000000-0x2000ffffffff] (bus address [0x80000000-0xffffffff]) 357s [ 0.017130] pci_bus 0000:00: root bus resource [mem 0x210000000000-0x21ffffffffff 64bit] 357s [ 0.017133] pci_bus 0000:00: root bus resource [bus 00-ff] 357s [ 0.017224] pci 0000:00:01.0: No hypervisor support for SR-IOV on this device, IOV BARs disabled. 357s [ 0.017566] pci 0000:00:02.0: No hypervisor support for SR-IOV on this device, IOV BARs disabled. 357s [ 0.017784] pci 0000:00:03.0: No hypervisor support for SR-IOV on this device, IOV BARs disabled. 357s [ 0.018161] pci 0000:00:04.0: No hypervisor support for SR-IOV on this device, IOV BARs disabled. 357s [ 0.018529] pci 0000:00:05.0: No hypervisor support for SR-IOV on this device, IOV BARs disabled. 357s [ 0.018879] pci 0000:00:06.0: No hypervisor support for SR-IOV on this device, IOV BARs disabled. 357s [ 0.019173] pci 0000:00:07.0: No hypervisor support for SR-IOV on this device, IOV BARs disabled. 357s [ 0.021495] IOMMU table initialized, virtual merging enabled 357s [ 0.021774] pci_bus 0000:00: resource 4 [io 0x10000-0x1ffff] 357s [ 0.021777] pci_bus 0000:00: resource 5 [mem 0x200080000000-0x2000ffffffff] 357s [ 0.021780] pci_bus 0000:00: resource 6 [mem 0x210000000000-0x21ffffffffff 64bit] 357s [ 0.021785] pci 0000:00:01.0: Adding to iommu group 0 357s [ 0.021958] pci 0000:00:02.0: Adding to iommu group 0 357s [ 0.022039] pci 0000:00:03.0: Adding to iommu group 0 357s [ 0.022203] pci 0000:00:04.0: Adding to iommu group 0 357s [ 0.022367] pci 0000:00:05.0: Adding to iommu group 0 357s [ 0.022491] pci 0000:00:06.0: Adding to iommu group 0 357s [ 0.022616] pci 0000:00:07.0: Adding to iommu group 0 357s [ 0.022643] EEH: No capable adapters found: recovery disabled. 357s [ 0.023931] kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. 357s [ 0.027265] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages 357s [ 0.027268] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages 357s [ 0.056196] iommu: Default domain type: Translated 357s [ 0.056198] iommu: DMA domain TLB invalidation policy: strict mode 357s [ 0.056359] SCSI subsystem initialized 357s [ 0.057726] pci 0000:00:07.0: vgaarb: VGA device added: decodes=io+mem,owns=mem,locks=none 357s [ 0.057729] pci 0000:00:07.0: vgaarb: bridge control possible 357s [ 0.057737] pci 0000:00:07.0: vgaarb: setting as boot device (VGA legacy resources not available) 357s [ 0.057739] vgaarb: loaded 357s [ 0.057768] usbcore: registered new interface driver usbfs 357s [ 0.057775] usbcore: registered new interface driver hub 357s [ 0.057782] usbcore: registered new device driver usb 357s [ 0.057800] pps_core: LinuxPPS API ver. 1 registered 357s [ 0.057802] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti 357s [ 0.057805] PTP clock support registered 357s [ 0.059269] EDAC MC: Ver: 3.0.0 357s [ 0.060906] NetLabel: Initializing 357s [ 0.060908] NetLabel: domain hash size = 128 357s [ 0.060909] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO 357s [ 0.060922] NetLabel: unlabeled traffic allowed by default 357s [ 0.064600] clocksource: Switched to clocksource timebase 357s [ 0.078751] VFS: Disk quotas dquot_6.6.0 357s [ 0.078771] VFS: Dquot-cache hash table entries: 8192 (order 0, 65536 bytes) 357s [ 0.078910] AppArmor: AppArmor Filesystem Enabled 357s [ 0.089479] NET: Registered PF_INET protocol family 357s [ 0.089531] IP idents hash table entries: 65536 (order: 3, 524288 bytes, linear) 357s [ 0.090282] tcp_listen_portaddr_hash hash table entries: 4096 (order: 0, 65536 bytes, linear) 357s [ 0.090303] Table-perturb hash table entries: 65536 (order: 2, 262144 bytes, linear) 357s [ 0.090329] TCP established hash table entries: 32768 (order: 2, 262144 bytes, linear) 357s [ 0.090521] TCP bind hash table entries: 32768 (order: 3, 524288 bytes, linear) 357s [ 0.090576] TCP: Hash tables configured (established 32768 bind 32768) 357s [ 0.090618] MPTCP token hash table entries: 4096 (order: 0, 98304 bytes, linear) 357s [ 0.090633] UDP hash table entries: 2048 (order: 0, 65536 bytes, linear) 357s [ 0.090642] UDP-Lite hash table entries: 2048 (order: 0, 65536 bytes, linear) 357s [ 0.090677] NET: Registered PF_UNIX/PF_LOCAL protocol family 357s [ 0.090683] NET: Registered PF_XDP protocol family 357s [ 0.090929] PCI: CLS 0 bytes, default 128 357s [ 0.090972] Trying to unpack rootfs image as initramfs... 357s [ 0.097564] vas: HCALL(398) error -2, query_type 0, result buffer 0xeb9e000 357s [ 0.098034] Initialise system trusted keyrings 357s [ 0.098046] Key type blacklist registered 357s [ 0.098073] workingset: timestamp_bits=38 max_order=16 bucket_order=0 357s [ 0.099243] zbud: loaded 357s [ 0.099635] squashfs: version 4.0 (2009/01/31) Phillip Lougher 357s [ 0.099794] fuse: init (API version 7.34) 357s [ 0.100085] integrity: Platform Keyring initialized 357s [ 0.120071] Key type asymmetric registered 357s [ 0.120073] Asymmetric key parser 'x509' registered 357s [ 0.120088] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 243) 357s [ 0.120127] io scheduler mq-deadline registered 357s [ 0.120509] Using unsupported 800x600 (null) at 200081000000, depth=32, pitch=3200 357s [ 0.120541] fbcon: Deferring console take-over 357s [ 0.120543] fb0: Open Firmware frame buffer device on /pci@800000020000000/vga@7 357s [ 0.120592] virtio-pci 0000:00:01.0: enabling device (0100 -> 0103) 357s [ 0.121256] virtio-pci 0000:00:01.0: ibm,query-pe-dma-windows(2026) 800 8000000 20000000 returned 0 357s [ 0.121512] virtio-pci 0000:00:01.0: ibm,create-pe-dma-window(2027) 800 8000000 20000000 18 20 returned 0 (liobn = 0x80000001 starting addr = 8000000 0) 357s [ 0.122187] virtio-pci 0000:00:03.0: enabling device (0100 -> 0103) 357s [ 0.123284] virtio-pci 0000:00:04.0: enabling device (0100 -> 0103) 357s [ 0.124426] virtio-pci 0000:00:05.0: enabling device (0100 -> 0103) 357s [ 0.125742] virtio-pci 0000:00:06.0: enabling device (0100 -> 0103) 357s [ 0.131822] printk: console [hvc0] enabled 357s [ 0.131859] Serial: 8250/16550 driver, 32 ports, IRQ sharing enabled 357s [ 0.373365] Non-volatile memory driver v1.3 357s [ 0.373446] Linux agpgart interface v0.103 357s [ 0.375888] loop: module loaded 357s [ 0.376221] tun: Universal TUN/TAP device driver, 1.6 357s [ 0.376276] PPP generic driver version 2.4.2 357s [ 0.376343] VFIO - User Level meta-driver version: 0.3 357s [ 0.376484] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver 357s [ 0.376518] ehci-pci: EHCI PCI platform driver 357s [ 0.376547] ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver 357s [ 0.376575] ohci-pci: OHCI PCI platform driver 357s [ 0.376602] uhci_hcd: USB Universal Host Controller Interface driver 357s [ 0.376717] mousedev: PS/2 mouse device common for all mice 357s [ 0.376827] rtc-generic rtc-generic: registered as rtc0 357s [ 0.376863] rtc-generic rtc-generic: setting system clock to 2025-08-03T19:53:55 UTC (1754250835) 357s [ 0.376907] i2c_dev: i2c /dev entries driver 357s [ 0.376984] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. 357s [ 0.377058] device-mapper: uevent: version 1.0.3 357s [ 0.377119] device-mapper: ioctl: 4.45.0-ioctl (2021-03-22) initialised: dm-devel@redhat.com 357s [ 0.377249] ledtrig-cpu: registered to indicate activity on CPUs 357s [ 0.377311] drop_monitor: Initializing network drop monitor service 357s [ 0.377498] NET: Registered PF_INET6 protocol family 357s [ 0.489078] Freeing initrd memory: 62464K 357s [ 0.504655] Segment Routing with IPv6 357s [ 0.504703] In-situ OAM (IOAM) with IPv6 357s [ 0.504741] NET: Registered PF_PACKET protocol family 357s [ 0.506395] Key type dns_resolver registered 357s [ 0.506451] secvar-sysfs: secvar: failed to retrieve secvar operations. 357s [ 0.506481] drmem: No dynamic reconfiguration memory found 357s [ 0.506743] registered taskstats version 1 357s [ 0.508679] Loading compiled-in X.509 certificates 357s [ 0.511865] Loaded X.509 cert 'Build time autogenerated kernel key: cbe73b81d9c4e5c7f08c65bed28afb839b7f8e24' 357s [ 0.512474] Loaded X.509 cert 'Canonical Ltd. Live Patch Signing 2025 Kmod: d541cef61dc7e793b7eb7e899970a2eef0b5dc8c' 357s [ 0.513109] Loaded X.509 cert 'Canonical Ltd. Live Patch Signing: 14df34d1a87cf37625abec039ef2bf521249b969' 357s [ 0.513730] Loaded X.509 cert 'Canonical Ltd. Kernel Module Signing: 88f752e560a1e0737e31163a466ad7b70a850c19' 357s [ 0.513771] blacklist: Loading compiled-in revocation X.509 certificates 357s [ 0.513816] Loaded X.509 cert 'Canonical Ltd. Secure Boot Signing: 61482aa2830d0ab2ad5af10b7250da9033ddcef0' 357s [ 0.513875] Loaded X.509 cert 'Canonical Ltd. Secure Boot Signing (2017): 242ade75ac4a15e50d50c84b0d45ff3eae707a03' 357s [ 0.513930] Loaded X.509 cert 'Canonical Ltd. Secure Boot Signing (ESM 2018): 365188c1d374d6b07c3c8f240f8ef722433d6a8b' 357s [ 0.513987] Loaded X.509 cert 'Canonical Ltd. Secure Boot Signing (2019): c0746fd6c5da3ae827864651ad66ae47fe24b3e8' 357s [ 0.514049] Loaded X.509 cert 'Canonical Ltd. Secure Boot Signing (2021 v1): a8d54bbb3825cfb94fa13c9f8a594a195c107b8d' 357s [ 0.514103] Loaded X.509 cert 'Canonical Ltd. Secure Boot Signing (2021 v2): 4cf046892d6fd3c9a5b03f98d845f90851dc6a8c' 357s [ 0.514158] Loaded X.509 cert 'Canonical Ltd. Secure Boot Signing (2021 v3): 100437bb6de6e469b581e61cd66bce3ef4ed53af' 357s [ 0.514216] Loaded X.509 cert 'Canonical Ltd. Secure Boot Signing (Ubuntu Core 2019): c1d57b8f6b743f23ee41f4f7ee292f06eecadfb9' 357s [ 0.514507] zswap: loaded using pool lzo/zbud 357s [ 0.518806] Key type .fscrypt registered 357s [ 0.518826] Key type fscrypt-provisioning registered 357s [ 0.518895] pstore: Using crash dump compression: deflate 357s [ 0.524402] Key type encrypted registered 357s [ 0.524425] AppArmor: AppArmor sha1 policy hashing enabled 357s [ 0.524463] Secure boot mode disabled 357s [ 0.524482] ima: No TPM chip found, activating TPM-bypass! 357s [ 0.524507] Loading compiled-in module X.509 certificates 357s [ 0.525124] Loaded X.509 cert 'Build time autogenerated kernel key: cbe73b81d9c4e5c7f08c65bed28afb839b7f8e24' 357s [ 0.525167] ima: Allocated hash algorithm: sha256 357s [ 0.525210] Secure boot mode disabled 357s [ 0.525233] Trusted boot mode disabled 357s [ 0.525249] ima: No architecture policies found 357s [ 0.525283] evm: Initialising EVM extended attributes: 357s [ 0.525304] evm: security.selinux 357s [ 0.525321] evm: security.SMACK64 357s [ 0.525337] evm: security.SMACK64EXEC 357s [ 0.525353] evm: security.SMACK64TRANSMUTE 357s [ 0.525369] evm: security.SMACK64MMAP 357s [ 0.525386] evm: security.apparmor 357s [ 0.525402] evm: security.ima 357s [ 0.525418] evm: security.capability 357s [ 0.525434] evm: HMAC attrs: 0x1 357s [ 0.527158] clk: Disabling unused clocks 357s [ 0.527254] integrity: Unable to open file: /etc/keys/x509_evm.der (-2) 357s [ 0.528696] Freeing unused kernel image (initmem) memory: 7424K 357s [ 0.529358] Checked W+X mappings: passed, no W+X pages found 357s [ 0.529388] Run /init as init process 357s Loading, please wait... 357s Starting version 249.11-0ubuntu3.16 357s [ 0.651527] virtio_blk virtio2: [vda] 41943040 512-byte logical blocks (21.5 GB/20.0 GiB) 357s [ 0.665942] xhci_hcd 0000:00:02.0: xHCI Host Controller 357s [ 0.665990] xhci_hcd 0000:00:02.0: new USB bus registered, assigned bus number 1 357s [ 0.669292] random: crng init done 357s [ 0.693848] vda: vda1 vda2 357s [ 0.698379] xhci_hcd 0000:00:02.0: hcc params 0x00087001 hci version 0x100 quirks 0x0000000000000010 357s [ 0.702344] xhci_hcd 0000:00:02.0: xHCI Host Controller 357s [ 0.702375] xhci_hcd 0000:00:02.0: new USB bus registered, assigned bus number 2 357s [ 0.702409] xhci_hcd 0000:00:02.0: Host supports USB 3.0 SuperSpeed 357s [ 0.703169] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 5.15 357s [ 0.703206] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1 357s [ 0.703237] usb usb1: Product: xHCI Host Controller 357s [ 0.703259] usb usb1: Manufacturer: Linux 5.15.0-151-generic xhci-hcd 357s [ 0.703285] usb usb1: SerialNumber: 0000:00:02.0 357s [ 0.703795] hub 1-0:1.0: USB hub found 357s [ 0.704037] hub 1-0:1.0: 4 ports detected 357s [ 0.704731] ibmvscsi 2000: SRP_VERSION: 16.a 357s [ 0.704797] ibmvscsi 2000: Maximum ID: 64 Maximum LUN: 32 Maximum Channel: 3 357s [ 0.704830] scsi host0: IBM POWER Virtual SCSI Adapter 1.5.9 357s [ 0.704951] usb usb2: We don't know the algorithms for LPM for this host, disabling LPM. 357s [ 0.705009] usb usb2: New USB device found, idVendor=1d6b, idProduct=0003, bcdDevice= 5.15 357s [ 0.705041] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1 357s [ 0.705072] usb usb2: Product: xHCI Host Controller 357s [ 0.705093] usb usb2: Manufacturer: Linux 5.15.0-151-generic xhci-hcd 357s [ 0.705119] usb usb2: SerialNumber: 0000:00:02.0 357s [ 0.707547] ibmvscsi 2000: partner initialization complete 357s [ 0.707593] ibmvscsi 2000: host srp version: 16.a, host partition qemu (0), OS 2, max io 2097152 357s [ 0.707637] ibmvscsi 2000: sent SRP login 357s [ 0.707655] ibmvscsi 2000: SRP_LOGIN succeeded 357s [ 0.713543] hub 2-0:1.0: USB hub found 357s [ 0.713647] hub 2-0:1.0: 4 ports detected 357s [ 0.714684] virtio_net virtio0 enp0s1: renamed from eth0 357s [ 0.724797] scsi 0:0:0:0: CD-ROM QEMU QEMU CD-ROM 2.5+ PQ: 0 ANSI: 5 357s [ 0.788904] sr 0:0:0:0: Power-on or device reset occurred 357s [ 0.789008] sr 0:0:0:0: [sr0] scsi3-mmc drive: 16x/50x cd/rw xa/form2 cdda tray 357s [ 0.789041] cdrom: Uniform CD-ROM driver Revision: 3.20 357s [ 0.789639] sr 0:0:0:0: Attached scsi generic sg0 type 5 357s Begin: Loading essential drivers ... done. 357s Begin: Running /scripts/init-premount ... done. 357s Begin: Mounting root file system ... Begin: Running /scripts/local-top ... done. 357s Begin: Running /scripts/local-premount ... [ 0.908604] raid6: vpermxor8 gen() 23671 MB/s 357s [ 0.976604] raid6: vpermxor4 gen() 20589 MB/s 357s [ 1.044609] raid6: vpermxor2 gen() 16464 MB/s 357s [ 1.112623] raid6: vpermxor1 gen() 13998 MB/s 357s [ 1.180615] raid6: altivecx8 gen() 15029 MB/s 357s [ 1.248615] raid6: altivecx4 gen() 14115 MB/s 357s [ 1.316629] raid6: altivecx2 gen() 11859 MB/s 357s [ 1.384631] raid6: altivecx1 gen() 9263 MB/s 357s [ 1.452608] raid6: int64x8 gen() 8097 MB/s 357s [ 1.520636] raid6: int64x8 xor() 4807 MB/s 357s [ 1.588623] raid6: int64x4 gen() 9351 MB/s 357s [ 1.656631] raid6: int64x4 xor() 4482 MB/s 357s [ 1.724610] raid6: int64x2 gen() 6902 MB/s 357s [ 1.792639] raid6: int64x2 xor() 3316 MB/s 357s [ 1.860666] raid6: int64x1 gen() 5466 MB/s 357s [ 1.928659] raid6: int64x1 xor() 2756 MB/s 357s [ 1.928683] raid6: using algorithm vpermxor8 gen() 23671 MB/s 357s [ 1.928709] raid6: using intx1 recovery algorithm 357s [ 1.939606] xor: measuring software checksum speed 357s [ 1.939806] 8regs : 18973 MB/sec 357s [ 1.940021] 8regs_prefetch : 16942 MB/sec 357s [ 1.940214] 32regs : 19133 MB/sec 357s [ 1.940426] 32regs_prefetch : 17161 MB/sec 357s [ 1.940593] altivec : 22742 MB/sec 357s [ 1.940614] xor: using function: altivec (22742 MB/sec) 357s [ 2.064626] usb 1-1: new high-speed USB device number 2 using xhci_hcd 357s [ 2.086939] Btrfs loaded, crc32c=crc32c-vpmsum, zoned=yes, fsverity=yes 357s Scanning for Btrfs filesystems 357s done. 357s Begin: Will now check root file system ... fsck from util-linux 2.37.2 357s [/usr/sbin/fsck.ext4 (1) -- /dev/vda1] fsck.ext4 -a -C0 /dev/vda1 357s cloudimg-rootfs: clean, 102589/2552320 files, 965100/5240560 blocks 357s done. 357s [ 2.212471] EXT4-fs (vda1): mounted filesystem with ordered data mode. Opts: (null). Quota mode: none. 357s done. 357s [ 2.213936] usb 1-1: New USB device found, idVendor=0627, idProduct=0001, bcdDevice= 0.00 357s [ 2.213976] usb 1-1: New USB device strings: Mfr=1, Product=4, SerialNumber=11 357s [ 2.214412] usb 1-1: Product: QEMU USB Keyboard 357s [ 2.214435] usb 1-1: Manufacturer: QEMU 357s [ 2.214452] usb 1-1: SerialNumber: 68284-pci@800000020000000:02.0-1 357s Begin: Running /scripts/local-bottom ... done. 357s Begin: Running /scripts/init-bottom ... [ 2.234391] hid: raw HID events driver (C) Jiri Kosina 357s done. 357s [ 2.242820] usbcore: registered new interface driver usbhid 357s [ 2.242847] usbhid: USB HID core driver 357s [ 2.360627] usb 1-2: new high-speed USB device number 3 using xhci_hcd 357s [ 2.509141] usb 1-2: New USB device found, idVendor=0627, idProduct=0001, bcdDevice= 0.00 357s [ 2.509178] usb 1-2: New USB device strings: Mfr=1, Product=2, SerialNumber=9 357s [ 2.509210] usb 1-2: Product: QEMU USB Mouse 357s [ 2.509231] usb 1-2: Manufacturer: QEMU 357s [ 2.509248] usb 1-2: SerialNumber: 89126-pci@800000020000000:02.0-2 357s [ 2.515305] systemd[1]: Inserted module 'autofs4' 357s [ 2.559910] systemd[1]: systemd 249.11-0ubuntu3.16 running in system mode (+PAM +AUDIT +SELINUX +APPARMOR +IMA +SMACK +SECCOMP +GCRYPT +GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -XKBCOMMON +UTMP +SYSVINIT default-hierarchy=unified) 357s [ 2.560091] systemd[1]: Detected virtualization kvm. 357s [ 2.560117] systemd[1]: Detected architecture ppc64-le. 357s 357s Welcome to [1mUbuntu 22.04.5 LTS[0m! 357s 357s [ 2.567029] systemd[1]: Hostname set to . 357s [ 2.750190] systemd[1]: Configuration file /run/systemd/system/netplan-ovs-cleanup.service is marked world-inaccessible. This has no effect as configuration data is accessible via APIs without restrictions. Proceeding anyway. 357s [ 2.931532] systemd[1]: Queued start job for default target Graphical Interface. 357s [ 2.932486] systemd[1]: Created slice Slice /system/autopkgtest. 357s [[0;32m OK [0m] Created slice [0;1;39mSlice /system/autopkgtest[0m. 357s [ 2.933009] systemd[1]: Created slice Slice /system/modprobe. 357s [[0;32m OK [0m] Created slice [0;1;39mSlice /system/modprobe[0m. 357s [ 2.933428] systemd[1]: Created slice Slice /system/serial-getty. 357s [[0;32m OK [0m] Created slice [0;1;39mSlice /system/serial-getty[0m. 357s [ 2.933775] systemd[1]: Created slice User and Session Slice. 357s [[0;32m OK [0m] Created slice [0;1;39mUser and Session Slice[0m. 357s [ 2.933991] systemd[1]: Started Forward Password Requests to Wall Directory Watch. 357s [[0;32m OK [0m] Started [0;1;39mForward Password R���uests to Wall Directory Watch[0m. 357s [ 2.934985] systemd[1]: Set up automount Arbitrary Executable File Formats File System Automount Point. 357s [[0;32m OK [0m] Set up automount [0;1;39mArbitrary���s File System Automount Point[0m. 357s [ 2.935214] systemd[1]: Reached target Remote File Systems. 357s [[0;32m OK [0m] Reached target [0;1;39mRemote File Systems[0m. 357s [ 2.935363] systemd[1]: Reached target Slice Units. 357s [[0;32m OK [0m] Reached target [0;1;39mSlice Units[0m. 357s [ 2.936161] systemd[1]: Reached target Swaps. 357s [[0;32m OK [0m] Reached target [0;1;39mSwaps[0m. 357s [ 2.936314] systemd[1]: Reached target Local Verity Protected Volumes. 357s [[0;32m OK [0m] Reached target [0;1;39mLocal Verity Protected Volumes[0m. 357s [ 2.936582] systemd[1]: Listening on multipathd control socket. 357s [[0;32m OK [0m] Listening on [0;1;39mmultipathd control socket[0m. 357s [ 2.936831] systemd[1]: Listening on Syslog Socket. 357s [[0;32m OK [0m] Listening on [0;1;39mSyslog Socket[0m. 357s [ 2.937705] systemd[1]: Listening on fsck to fsckd communication Socket. 357s [[0;32m OK [0m] Listening on [0;1;39mfsck to fsckd communication Socket[0m. 357s [ 2.937922] systemd[1]: Listening on initctl Compatibility Named Pipe. 357s [[0;32m OK [0m] Listening on [0;1;39minitctl Compatibility Named Pipe[0m. 357s [ 2.938251] systemd[1]: Listening on Journal Audit Socket. 357s [[0;32m OK [0m] Listening on [0;1;39mJournal Audit Socket[0m. 357s [ 2.939132] systemd[1]: Listening on Journal Socket (/dev/log). 357s [[0;32m OK [0m] Listening on [0;1;39mJournal Socket (/dev/log)[0m. 357s [ 2.939398] systemd[1]: Listening on Journal Socket. 357s [[0;32m OK [0m] Listening on [0;1;39mJournal Socket[0m. 357s [ 2.939655] systemd[1]: Listening on Network Service Netlink Socket. 357s [[0;32m OK [0m] Listening on [0;1;39mNetwork Service Netlink Socket[0m. 357s [ 2.939920] systemd[1]: Listening on udev Control Socket. 357s [[0;32m OK [0m] Listening on [0;1;39mudev Control Socket[0m. 357s [ 2.940812] systemd[1]: Listening on udev Kernel Socket. 357s [[0;32m OK [0m] Listening on [0;1;39mudev Kernel Socket[0m. 357s [ 2.941579] systemd[1]: Mounting Huge Pages File System... 357s Mounting [0;1;39mHuge Pages File System[0m... 357s [ 2.942443] systemd[1]: Mounting POSIX Message Queue File System... 357s Mounting [0;1;39mPOSIX Message Queue File System[0m... 357s [ 2.943164] systemd[1]: Mounting Kernel Debug File System... 357s Mounting [0;1;39mKernel Debug File System[0m... 357s [ 2.944046] systemd[1]: Mounting Kernel Trace File System... 357s Mounting [0;1;39mKernel Trace File System[0m... 357s [ 2.946196] systemd[1]: Starting Journal Service... 357s Starting [0;1;39mJournal Service[0m... 357s [ 2.947187] systemd[1]: Starting Set the console keyboard layout... 357s Starting [0;1;39mSet the console keyboard layout[0m... 357s [ 2.948168] systemd[1]: Starting Create List of Static Device Nodes... 357s Starting [0;1;39mCreate List of Static Device Nodes[0m... 357s [ 2.948359] systemd[1]: Condition check resulted in LXD - agent being skipped. 357s [ 2.949070] systemd[1]: Starting Load Kernel Module configfs... 357s Starting [0;1;39mLoad Kernel Module configfs[0m... 357s [ 2.950586] systemd[1]: Starting Load Kernel Module drm... 357s Starting [0;1;39mLoad Kernel Module drm[0m... 357s [ 2.951411] systemd[1]: Starting Load Kernel Module efi_pstore... 357s Starting [0;1;39mLoad Kernel Module efi_pstore[0m... 357s [ 2.953229] systemd[1]: Starting Load Kernel Module fuse... 357s Starting [0;1;39mLoad Kernel Module fuse[0m... 357s [ 2.953418] systemd[1]: Condition check resulted in OpenVSwitch configuration for cleanup being skipped. 357s [ 2.953537] systemd[1]: Condition check resulted in File System Check on Root Device being skipped. 357s [ 2.957625] systemd[1]: Starting Load Kernel Modules... 357s Starting [0;1;39mLoad Kernel Modules[0m... 357s [ 2.960058] systemd[1]: Starting Remount Root and Kernel File Systems... 357s Starting [0;1;39mRemount Root and Kernel File Systems[0m... 357s [ 2.960846] systemd[1]: Starting Coldplug All udev Devices... 357s Starting [0;1;39mColdplug All udev Devices[0m... 357s [ 2.962240] systemd[1]: Mounted Huge Pages File System. 357s [[0;32m OK [0m] Mounted [0;1;39mHuge Pages File System[0m. 357s [ 2.962475] systemd[1]: Mounted POSIX Message Queue File System. 357s [[0;32m OK [0m] Mounted [0;1;39mPOSIX Message Queue File System[0m. 357s [ 2.962712] systemd[1]: Mounted Kernel Debug File System. 357s [[0;32m OK [0m] Mounted [0;1;39mKernel Debug File System[0m. 357s [ 2.962928] systemd[1]: Mounted Kernel Trace File System. 357s [[0;32m OK [0m] Mounted [0;1;39mKernel Trace File System[0m. 357s [ 2.963352] systemd[1]: Finished Create List of Static Device Nodes. 357s [[0;32m OK [0m] Finished [0;1;39mCreate List of Static Device Nodes[0m. 357s [ 2.963805] systemd[1]: modprobe@configfs.service: Deactivated successfully. 357s [ 2.964078] systemd[1]: Finished Load Kernel Module configfs. 357s [[0;32m OK [0m] Finished [0;1;39mLoad Kernel Module configfs[0m. 357s [ 2.964500] systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. 357s [ 2.964783] systemd[1]: Finished Load Kernel Module efi_pstore. 357s [[0;32m OK [0m] Finished [0;1;39mLoad Kernel Module efi_pstore[0m. 357s [ 2.966551] systemd[1]: modprobe@fuse.service: Deactivated successfully. 357s [ 2.970420] systemd[1]: Finished Load Kernel Module fuse. 357s [[0;32m OK [0m] Finished [0;1;39mLoad Kernel Module fuse[0m. 357s [ 2.989433] systemd[1]: Finished Load Kernel Modules. 357s [[0;32m OK [0m] Finished [0;1;39mLoad Kernel Modules[0m. 357s [ 2.996633] EXT4-fs (vda1): re-mounted. Opts: discard,errors=remount-ro. Quota mode: none. 357s [ 3.000657] systemd[1]: Mounting FUSE Control File System... 357s Mounting [0;1;39mFUSE Control File System[0m... 357s [ 3.012861] systemd[1]: Mounting Kernel Configuration File System... 357s Mounting [0;1;39mKernel Configuration File System[0m... 357s [ 3.033175] systemd[1]: Starting Apply Kernel Variables... 357s Starting [0;1;39mApply Kernel Variables[0m... 357s [ 3.034362] systemd[1]: modprobe@drm.service: Deactivated successfully. 357s [ 3.034643] systemd[1]: Finished Load Kernel Module drm. 357s [[0;32m OK [0m] Finished [0;1;39mLoad Kernel Module drm[0m. 357s [ 3.035102] systemd[1]: Finished Remount Root and Kernel File Systems. 357s [[0;32m OK [0m] Finished [0;1;39mRemount Root and Kernel File Systems[0m. 357s [ 3.035766] systemd[1]: Mounted FUSE Control File System. 357s [[0;32m OK [0m] Mounted [0;1;39mFUSE Control File System[0m. 357s [ 3.035979] systemd[1]: Mounted Kernel Configuration File System. 357s [[0;32m OK [0m] Mounted [0;1;39mKernel Configuration File System[0m. 357s [ 3.037048] systemd[1]: Starting Cloud-init: Local Stage (pre-network)... 357s Starting [0;1;39mCloud-init: Local Stage (pre-network)[0m... 357s [ 3.038043] systemd[1]: Starting Device-Mapper Multipath Device Controller... 357s Starting [0;1;39mDevice-Mapper Multipath Device Controller[0m... 357s [ 3.040781] systemd[1]: Condition check resulted in Platform Persistent Storage Archival being skipped. 357s [ 3.042716] systemd[1]: Starting Load/Save Random Seed... 357s Starting [0;1;39mLoad/Save Random Seed[0m... 357s [ 3.046627] systemd[1]: Starting Create System Users... 357s Starting [0;1;39mCreate System Users[0m... 357s [ 3.047202] systemd[1]: Started Journal Service. 357s [[0;32m OK [0m] Started [0;1;39mJournal Service[0m. 357s [[0;32m OK [0m] Finished [0;1;39mSet the console keyboard layout[0m. 357s Starting [0;1;39mFlush Journal to Persistent Storage[0m... 357s [[0;32m OK [0m] Finished [0;1;39mApply Kernel Variables[0m. 357s [[0;32m OK [0m] Finished [0;1;39mColdplug All udev Devices[0m. 357s [[0;32m OK [0m] Finished [0;1;39mCreate System Users[0m. 357s Starting [0;1;39mCreate Static Device Nodes in /dev[0m... 357s [[0;32m OK [0m] Finished [0;1;39mCreate Static Device Nodes in /dev[0m. 357s Starting [0;1;39mRule-based Manage���for Device Events and Files[0m... 357s [[0;32m OK [0m] Finished [0;1;39mLoad/Save Random Seed[0m. 357s [[0;32m OK [0m] Finished [0;1;39mFlush Journal to Persistent Storage[0m. 357s [[0;32m OK [0m] Started [0;1;39mDevice-Mapper Multipath Device Controller[0m. 357s [[0;32m OK [0m] Reached target [0;1;39mPreparation for Local File Systems[0m. 357s [[0;32m OK [0m] Reached target [0;1;39mLocal File Systems[0m. 357s Starting [0;1;39mLoad AppArmor profiles[0m... 357s Starting [0;1;39mSet console font and keymap[0m... 357s Starting [0;1;39mTell Plymouth To Write Out Runtime Data[0m... 357s Starting [0;1;39mSet Up Additional Binary Formats[0m... 357s Starting [0;1;39mCreate Volatile Files and Directories[0m... 357s Starting [0;1;39mUncomplicated firewall[0m... 357s [[0;32m OK [0m] Finished [0;1;39mSet console font and keymap[0m. 357s Mounting [0;1;39mArbitrary Executable File Formats File System[0m... 357s [[0;32m OK [0m] Finished [0;1;39mTell Plymouth To Write Out Runtime Data[0m. 357s [[0;32m OK [0m] Finished [0;1;39mUncomplicated firewall[0m. 357s [[0;32m OK [0m] Mounted [0;1;39mArbitrary Executable File Formats File System[0m. 357s [[0;32m OK [0m] Finished [0;1;39mSet Up Additional Binary Formats[0m. 357s [[0;32m OK [0m] Finished [0;1;39mCreate Volatile Files and Directories[0m. 357s Starting [0;1;39mNetwork Time Synchronization[0m... 357s Starting [0;1;39mRecord System Boot/Shutdown in UTMP[0m... 357s [[0;32m OK [0m] Finished [0;1;39mRecord System Boot/Shutdown in UTMP[0m. 357s [[0;32m OK [0m] Started [0;1;39mRule-based Manager for Device Events and Files[0m. 357s [[0;32m OK [0m] Started [0;1;39mDispatch Password ���ts to Console Directory Watch[0m. 357s [[0;32m OK [0m] Reached target [0;1;39mLocal Encrypted Volumes[0m. 357s [[0;32m OK [0m] Started [0;1;39mNetwork Time Synchronization[0m. 357s [[0;32m OK [0m] Reached target [0;1;39mSystem Time Set[0m. 357s [[0;32m OK [0m] Finished [0;1;39mLoad AppArmor profiles[0m. 357s [[0;32m OK [0m] Listening on [0;1;39mLoad/Save RF ���itch Status /dev/rfkill Watch[0m. 357s [[0;32m OK [0m] Found device [0;1;39m/dev/hvc0[0m. 357s [ 4.026564] cloud-init[527]: Cloud-init v. 25.1.4-0ubuntu0~22.04.1 running 'init-local' at Sun, 03 Aug 2025 19:53:59 +0000. Up 3.99 seconds. 357s [[0;32m OK [0m] Finished [0;1;39mCloud-init: Local Stage (pre-network)[0m. 357s [[0;32m OK [0m] Reached target [0;1;39mPreparation for Network[0m. 357s Starting [0;1;39mNetwork Configuration[0m... 357s [[0;32m OK [0m] Started [0;1;39mNetwork Configuration[0m. 357s Starting [0;1;39mWait for Network to be Configured[0m... 357s Starting [0;1;39mNetwork Name Resolution[0m... 357s [[0;32m OK [0m] Started [0;1;39mNetwork Name Resolution[0m. 357s [[0;32m OK [0m] Reached target [0;1;39mNetwork[0m. 357s [[0;32m OK [0m] Reached target [0;1;39mHost and Network Name Lookups[0m. 357s [[0;32m OK [0m] Finished [0;1;39mWait for Network to be Configured[0m. 357s Starting [0;1;39mCloud-init: Network Stage[0m... 357s [ 14.016914] cloud-init[569]: Cloud-init v. 25.1.4-0ubuntu0~22.04.1 running 'init' at Sun, 03 Aug 2025 19:54:09 +0000. Up 13.98 seconds. 357s [ 14.032692] cloud-init[569]: ci-info: +++++++++++++++++++++++++++++++++++++++Net device info+++++++++++++++++++++++++++++++++++++++ 357s [ 14.032911] cloud-init[569]: ci-info: +--------+------+------------------------------+---------------+--------+-------------------+ 357s [ 14.033119] cloud-init[569]: ci-info: | Device | Up | Address | Mask | Scope | Hw-Address | 357s [ 14.033950] cloud-init[569]: ci-info: +--------+------+------------------------------+---------------+--------+-------------------+ 357s [ 14.034790] cloud-init[569]: ci-info: | enp0s1 | True | 10.145.227.43 | 255.255.255.0 | global | fa:16:3e:06:b1:ad | 357s [ 14.034978] cloud-init[569]: ci-info: | enp0s1 | True | fe80::f816:3eff:fe06:b1ad/64 | . | link | fa:16:3e:06:b1:ad | 357s [ 14.035808] cloud-init[569]: ci-info: | lo | True | 127.0.0.1 | 255.0.0.0 | host | . | 357s [ 14.035988] cloud-init[569]: ci-info: | lo | True | ::1/128 | . | host | . | 357s [ 14.036223] cloud-init[569]: ci-info: +--------+------+------------------------------+---------------+--------+-------------------+ 357s [ 14.036404] cloud-init[569]: ci-info: ++++++++++++++++++++++++++++++++Route IPv4 info+++++++++++++++++++++++++++++++++ 357s [ 14.036583] cloud-init[569]: ci-info: +-------+-----------------+--------------+-----------------+-----------+-------+ 357s [ 14.036764] cloud-init[569]: ci-info: | Route | Destination | Gateway | Genmask | Interface | Flags | 357s [ 14.037598] cloud-init[569]: ci-info: +-------+-----------------+--------------+-----------------+-----------+-------+ 357s [ 14.037784] cloud-init[569]: ci-info: | 0 | 0.0.0.0 | 10.145.227.1 | 0.0.0.0 | enp0s1 | UG | 357s [ 14.037966] cloud-init[569]: ci-info: | 1 | 10.145.227.0 | 0.0.0.0 | 255.255.255.0 | enp0s1 | U | 357s [ 14.038146] cloud-init[569]: ci-info: | 2 | 10.145.227.1 | 0.0.0.0 | 255.255.255.255 | enp0s1 | UH | 357s [ 14.038999] cloud-init[569]: ci-info: | 3 | 10.145.227.2 | 0.0.0.0 | 255.255.255.255 | enp0s1 | UH | 357s [ 14.039186] cloud-init[569]: ci-info: | 4 | 91.189.91.131 | 10.145.227.1 | 255.255.255.255 | enp0s1 | UGH | 357s [ 14.040021] cloud-init[569]: ci-info: | 5 | 91.189.91.132 | 10.145.227.1 | 255.255.255.255 | enp0s1 | UGH | 357s [ 14.040209] cloud-init[569]: ci-info: | 6 | 169.254.169.254 | 10.145.227.2 | 255.255.255.255 | enp0s1 | UGH | 357s [ 14.041050] cloud-init[569]: ci-info: +-------+-----------------+--------------+-----------------+-----------+-------+ 357s [ 14.041247] cloud-init[569]: ci-info: +++++++++++++++++++Route IPv6 info+++++++++++++++++++ 357s [ 14.042071] cloud-init[569]: ci-info: +-------+-------------+---------+-----------+-------+ 357s [ 14.042234] cloud-init[569]: ci-info: | Route | Destination | Gateway | Interface | Flags | 357s [ 14.042395] cloud-init[569]: ci-info: +-------+-------------+---------+-----------+-------+ 357s [ 14.043207] cloud-init[569]: ci-info: | 1 | fe80::/64 | :: | enp0s1 | U | 357s [ 14.043373] cloud-init[569]: ci-info: | 3 | local | :: | enp0s1 | U | 357s [ 14.043539] cloud-init[569]: ci-info: | 4 | multicast | :: | enp0s1 | U | 357s [ 14.043700] cloud-init[569]: ci-info: +-------+-------------+---------+-----------+-------+ 357s [ 14.130172] cloud-init[569]: 2025-08-03 19:54:09,257 - schema.py[WARNING]: cloud-config failed schema validation! You may run 'sudo cloud-init schema --system' to check the details. 357s [ 14.728248] cloud-init[569]: Generating public/private rsa key pair. 357s [ 14.728517] cloud-init[569]: Your identification has been saved in /etc/ssh/ssh_host_rsa_key 357s [ 14.728674] cloud-init[569]: Your public key has been saved in /etc/ssh/ssh_host_rsa_key.pub 357s [ 14.728860] cloud-init[569]: The key fingerprint is: 357s [ 14.729020] cloud-init[569]: SHA256:6VBRf5px41SDEDpYoJ7Rm0PhbbD/FOBhLIcQLJHk1eg root@autopkgtest 357s [ 14.729200] cloud-init[569]: The key's randomart image is: 357s [ 14.729357] cloud-init[569]: +---[RSA 3072]----+ 357s [ 14.729478] cloud-init[569]: | .o++=+=*.oo ...| 357s [ 14.729591] cloud-init[569]: | .o.o++X++. . ..| 357s [ 14.729714] cloud-init[569]: | .oo *+* .o = | 357s [ 14.729838] cloud-init[569]: | .E+.=.. .O . | 357s [ 14.729963] cloud-init[569]: | o.+S. .o . | 357s [ 14.730087] cloud-init[569]: | o. o | 357s [ 14.730213] cloud-init[569]: | . . | 357s [ 14.730339] cloud-init[569]: | | 357s [ 14.730464] cloud-init[569]: | | 357s [ 14.730589] cloud-init[569]: +----[SHA256]-----+ 357s [ 14.730730] cloud-init[569]: Generating public/private ecdsa key pair. 357s [ 14.730872] cloud-init[569]: Your identification has been saved in /etc/ssh/ssh_host_ecdsa_key 357s [ 14.731026] cloud-init[569]: Your public key has been saved in /etc/ssh/ssh_host_ecdsa_key.pub 357s [ 14.731176] cloud-init[569]: The key fingerprint is: 357s [ 14.731308] cloud-init[569]: SHA256:lzdaeUPLaGLGXAzPWJuPShzFIBZRlx3GRMPvL4DFW18 root@autopkgtest 357s [ 14.731461] cloud-init[569]: The key's randomart image is: 357s [ 14.731590] cloud-init[569]: +---[ECDSA 256]---+ 357s [ 14.731715] cloud-init[569]: | =++o+B*. | 357s [ 14.731840] cloud-init[569]: | . . Xo++. | 357s [ 14.731965] cloud-init[569]: | o.B .. | 357s [ 14.732118] cloud-init[569]: | + +oB..E| 357s [ 14.732254] cloud-init[569]: | S @oOo*o.| 357s [ 14.732386] cloud-init[569]: | =.Boo .o| 357s [ 14.732515] cloud-init[569]: | o . .| 357s [ 14.732645] cloud-init[569]: | . .| 357s [ 14.732785] cloud-init[569]: | . | 357s [ 14.732911] cloud-init[569]: +----[SHA256]-----+ 357s [ 14.733027] cloud-init[569]: Generating public/private ed25519 key pair. 357s [ 14.733174] cloud-init[569]: Your identification has been saved in /etc/ssh/ssh_host_ed25519_key 357s [ 14.733328] cloud-init[569]: Your public key has been saved in /etc/ssh/ssh_host_ed25519_key.pub 357s [ 14.733508] cloud-init[569]: The key fingerprint is: 357s [ 14.733645] cloud-init[569]: SHA256:Bc8s+AhxeWa2+ML5AFDix9fWwKo8ze0sV5J5HUy77PM root@autopkgtest 357s [ 14.733799] cloud-init[569]: The key's randomart image is: 357s [ 14.733930] cloud-init[569]: +--[ED25519 256]--+ 357s [ 14.734060] cloud-init[569]: | ..o .oo | 357s [ 14.734189] cloud-init[569]: | ..o o.o*B . | 357s [ 14.734334] cloud-init[569]: | ..+ oB+.*o . | 357s [ 14.734464] cloud-init[569]: | ..oo+.o + | 357s [ 14.734595] cloud-init[569]: | . *.+So o o | 357s [ 14.734724] cloud-init[569]: | + B * o + | 357s [ 14.734855] cloud-init[569]: | . * + . | 357s [ 14.734984] cloud-init[569]: | . = o | 357s [ 14.735113] cloud-init[569]: | o oE | 357s [ 14.735238] cloud-init[569]: +----[SHA256]-----+ 357s [[0;32m OK [0m] Finished [0;1;39mCloud-init: Network Stage[0m. 357s [[0;32m OK [0m] Reached target [0;1;39mCloud-config availability[0m. 357s [[0;32m OK [0m] Reached target [0;1;39mNetwork is Online[0m. 357s [[0;32m OK [0m] Reached target [0;1;39mSystem Initialization[0m. 357s [[0;32m OK [0m] Started [0;1;39mDaily apt download activities[0m. 357s [[0;32m OK [0m] Started [0;1;39mDaily apt upgrade and clean activities[0m. 357s [[0;32m OK [0m] Started [0;1;39mDaily dpkg database backup timer[0m. 357s [[0;32m OK [0m] Started [0;1;39mPeriodic ext4 Onli���ata Check for All Filesystems[0m. 357s [[0;32m OK [0m] Started [0;1;39mDiscard unused blocks once a week[0m. 357s [[0;32m OK [0m] Started [0;1;39mDaily rotation of log files[0m. 357s [[0;32m OK [0m] Started [0;1;39mDaily man-db regeneration[0m. 357s [[0;32m OK [0m] Started [0;1;39mMessage of the Day[0m. 357s [[0;32m OK [0m] Started [0;1;39mDaily Cleanup of Temporary Directories[0m. 357s [[0;32m OK [0m] Reached target [0;1;39mPath Units[0m. 357s [[0;32m OK [0m] Reached target [0;1;39mTimer Units[0m. 357s [[0;32m OK [0m] Listening on [0;1;39mcloud-init hotplug hook socket[0m. 357s [[0;32m OK [0m] Listening on [0;1;39mD-Bus System Message Bus Socket[0m. 357s [[0;32m OK [0m] Listening on [0;1;39mUUID daemon activation socket[0m. 357s [[0;32m OK [0m] Reached target [0;1;39mSocket Units[0m. 357s [[0;32m OK [0m] Reached target [0;1;39mBasic System[0m. 357s Starting [0;1;39mLSB: automatic crash report generation[0m... 357s [[0;32m OK [0m] Started [0;1;39mautopkgtest root shell on hvc1[0m. 357s [[0;32m OK [0m] Started [0;1;39mautopkgtest root shell on ttyS1[0m. 357s Starting [0;1;39mCloud-init: Config Stage[0m... 357s [[0;32m OK [0m] Started [0;1;39mRegular background program processing daemon[0m. 357s [[0;32m OK [0m] Started [0;1;39mD-Bus System Message Bus[0m. 357s [[0;32m OK [0m] Started [0;1;39mSave initial kernel messages after boot[0m. 357s Starting [0;1;39mRemove Stale Onli���t4 Metadata Check Snapshots[0m... 357s Starting [0;1;39mRecord successful boot for GRUB[0m... 357s Starting [0;1;39mIBM Power Raid dump daemon[0m... 357s [[0;32m OK [0m] Started [0;1;39mirqbalance daemon[0m. 357s Starting [0;1;39mDispatcher daemon for systemd-networkd[0m... 357s Starting [0;1;39mLSB: rng-tools (Debian variant)[0m... 357s Starting [0;1;39mSystem Logging Service[0m... 357s Starting [0;1;39mppc64-diag rtas_e���orm error handling) Service[0m... 357s Starting [0;1;39mOpenBSD Secure Shell server[0m... 357s Starting [0;1;39mUser Login Management[0m... 357s Starting [0;1;39mPermit User Sessions[0m... 357s [[0;32m OK [0m] Finished [0;1;39mRemove Stale Onli���ext4 Metadata Check Snapshots[0m. 357s [[0;32m OK [0m] Started [0;1;39mIBM Power Raid dump daemon[0m. 357s [[0;32m OK [0m] Finished [0;1;39mPermit User Sessions[0m. 357s [[0;32m OK [0m] Started [0;1;39mSystem Logging Service[0m. 357s [[0;32m OK [0m] Started [0;1;39mppc64-diag rtas_er���tform error handling) Service[0m. 357s Starting [0;1;39mIBM Power Raid init daemon[0m... 357s Starting [0;1;39mIBM Power Raid update daemon[0m... 357s Starting [0;1;39mHold until boot process finishes up[0m... 357s Starting [0;1;39mTerminate Plymouth Boot Screen[0m... 357s Starting [0;1;39mRotate log files[0m... 357s [[0;32m OK [0m] Started [0;1;39mLSB: automatic crash report generation[0m. 357s [[0;32m OK [0m] Started [0;1;39mLSB: rng-tools (Debian variant)[0m. 357s [[0;32m OK [0m] Started [0;1;39mIBM Power Raid init daemon[0m. 357s [[0;32m OK [0m] Started [0;1;39mIBM Power Raid update daemon[0m. 357s [[0;32m OK [0m] Finished [0;1;39mHold until boot process finishes up[0m. 357s [[0;32m OK [0m] Finished [0;1;39mTerminate Plymouth Boot Screen[0m. 357s [[0;32m OK [0m] Reached target [0;1;39mIBM Power Raid utilities[0m. 357s [[0;32m OK [0m] Started [0;1;39mSerial Getty on hvc0[0m. 357s Starting [0;1;39mSet console scheme[0m... 357s [[0;32m OK [0m] Started [0;1;39mOpenBSD Secure Shell server[0m. 357s [[0;32m OK [0m] Finished [0;1;39mRecord successful boot for GRUB[0m. 357s Starting [0;1;39mGRUB failed boot detection[0m... 357s [[0;32m OK [0m] Finished [0;1;39mSet console scheme[0m. 357s [[0;32m OK [0m] Created slice [0;1;39mSlice /system/getty[0m. 357s [[0;32m OK [0m] Started [0;1;39mGetty on tty1[0m. 357s [[0;32m OK [0m] Reached target [0;1;39mLogin Prompts[0m. 357s [[0;32m OK [0m] Started [0;1;39mUser Login Management[0m. 357s [[0;32m OK [0m] Finished [0;1;39mGRUB failed boot detection[0m. 357s [[0;32m OK [0m] Finished [0;1;39mRotate log files[0m. 357s [[0;32m OK [0m] Started [0;1;39mDispatcher daemon for systemd-networkd[0m. 357s [[0;32m OK [0m] Reached target [0;1;39mMulti-User System[0m. 357s [[0;32m OK [0m] Reached target [0;1;39mGraphical Interface[0m. 357s Starting [0;1;39mRecord Runlevel Change in UTMP[0m... 357s [[0;32m OK [0m] Finished [0;1;39mRecord Runlevel Change in UTMP[0m. 357s [ 15.229950] cloud-init[700]: Cloud-init v. 25.1.4-0ubuntu0~22.04.1 running 'modules:config' at Sun, 03 Aug 2025 19:54:10 +0000. Up 15.17 seconds. 357s [ 15.660119] cloud-init[700]: 2025-08-03 19:54:10,787 - 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. 357s [ 15.663495] cloud-init[700]: 2025-08-03 19:54:10,787 - 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. 357s [[0;32m OK [0m] Finished [0;1;39mCloud-init: Config Stage[0m. 357s Starting [0;1;39mCloud-init: Final Stage[0m... 357s [ 16.079843] cloud-init[712]: Cloud-init v. 25.1.4-0ubuntu0~22.04.1 running 'modules:final' at Sun, 03 Aug 2025 19:54:11 +0000. Up 16.02 seconds. 357s Starting [0;1;39mUpdate the local ESM caches[0m... 357s [[0;32m OK [0m] Finished [0;1;39mUpdate the local ESM caches[0m. 357s 357s Ubuntu 22.04.5 LTS autopkgtest hvc0 357s 357s autopkgtest login: [ 46.415572] cloud-init[712]: Ign:1 http://ftpmaster.internal/ubuntu jammy InRelease 357s [ 76.443983] cloud-init[712]: Ign:2 http://ftpmaster.internal/ubuntu jammy-updates InRelease 357s [ 106.478143] cloud-init[712]: Ign:3 http://ftpmaster.internal/ubuntu jammy-security InRelease 357s [ 136.512819] cloud-init[712]: Ign:1 http://ftpmaster.internal/ubuntu jammy InRelease 357s [ 166.542334] cloud-init[712]: Ign:2 http://ftpmaster.internal/ubuntu jammy-updates InRelease 357s [ 196.577048] cloud-init[712]: Ign:3 http://ftpmaster.internal/ubuntu jammy-security InRelease 357s [ 226.607350] cloud-init[712]: Ign:1 http://ftpmaster.internal/ubuntu jammy InRelease 357s [ 256.641841] cloud-init[712]: Ign:2 http://ftpmaster.internal/ubuntu jammy-updates InRelease 357s [ 286.672592] cloud-init[712]: Ign:3 http://ftpmaster.internal/ubuntu jammy-security InRelease 357s [ 316.705544] cloud-init[712]: Ign:1 http://ftpmaster.internal/ubuntu jammy InRelease 357s 357s --------------------------------------------------- 357s ------- nova show 3a7caf9d-a422-450c-a4e3-df07a07ac86a (adt-jammy-ppc64el-initramfs-tools-20250803-173835-juju-7f2275-prod-proposed-migration-environment-21-97c6ec16-0c8c-429a-a729-ce32d2e14280) ------ 359s +---------------------------------------------+-------------------------------------------------------------------------------------------------------------------------------------------+ 359s | Property | Value | 359s +---------------------------------------------+-------------------------------------------------------------------------------------------------------------------------------------------+ 359s | OS-DCF:diskConfig | MANUAL | 359s | OS-EXT-AZ:availability_zone | availability-zone-3 | 359s | OS-EXT-SRV-ATTR:host | ps6-rb1-ppc64el-n1.ps6.canonical.com | 359s | OS-EXT-SRV-ATTR:hostname | adt-jammy-ppc64el-initramfs-tools-20250803-173835-juju-7f2275-p | 359s | OS-EXT-SRV-ATTR:hypervisor_hostname | ps6-rb1-ppc64el-n1.ps6.canonical.com | 359s | OS-EXT-SRV-ATTR:instance_name | instance-00a9bfce | 359s | OS-EXT-SRV-ATTR:kernel_id | | 359s | OS-EXT-SRV-ATTR:launch_index | 0 | 359s | OS-EXT-SRV-ATTR:ramdisk_id | | 359s | OS-EXT-SRV-ATTR:reservation_id | r-ys60rl1i | 359s | OS-EXT-SRV-ATTR:root_device_name | /dev/vda | 359s | OS-EXT-STS:power_state | 1 | 359s | OS-EXT-STS:task_state | - | 359s | OS-EXT-STS:vm_state | active | 359s | OS-SRV-USG:launched_at | 2025-08-03T19:53:47.000000 | 359s | OS-SRV-USG:terminated_at | - | 359s | accessIPv4 | | 359s | accessIPv6 | | 359s | config_drive | True | 359s | created | 2025-08-03T19:53:37Z | 359s | description | - | 359s | flavor:disk | 20 | 359s | flavor:ephemeral | 0 | 359s | flavor:extra_specs | {"aggregate_instance_extra_specs:commit": "builder-ppc64el", "hw_rng:allowed": "True"} | 359s | flavor:original_name | autopkgtest-ppc64el | 359s | flavor:ram | 4096 | 359s | flavor:swap | 0 | 359s | flavor:vcpus | 2 | 359s | hostId | 0cd8566d86b217a7459cb0d6d47601335bd97f92d50fbe2dca7367fc | 359s | id | 3a7caf9d-a422-450c-a4e3-df07a07ac86a | 359s | image | adt/ubuntu-jammy-ppc64el-server-20250802.img (2db06c61-8cb5-4f70-862b-8f635f8ffaa7) | 359s | key_name | testbed-juju-7f2275-prod-proposed-migration-environment-21 | 359s | locked | False | 359s | locked_reason | - | 359s | metadata | {} | 359s | name | adt-jammy-ppc64el-initramfs-tools-20250803-173835-juju-7f2275-prod-proposed-migration-environment-21-97c6ec16-0c8c-429a-a729-ce32d2e14280 | 359s | net_prod-proposed-migration-ppc64el network | 10.145.227.43 | 359s | os-extended-volumes:volumes_attached | [] | 359s | progress | 0 | 359s | security_groups | autopkgtest-juju-7f2275-prod-proposed-migration-environment-21@bos03-ppc64el-5.secgroup | 359s | server_groups | [] | 359s | status | ACTIVE | 359s | tags | [] | 359s | tenant_id | 623df63b80274c21bc79a0c35e68d615 | 359s | trusted_image_certificates | - | 359s | updated | 2025-08-03T19:53:47Z | 359s | user_id | c871debdeffd4cb8b69ce618fc4aa361 | 359s +---------------------------------------------+-------------------------------------------------------------------------------------------------------------------------------------------+ 359s --------------------------------------------------- 375s : failure: Timed out on waiting for ssh connection 375s autopkgtest [19:59:44]: ERROR: testbed failure: unexpected eof from the testbed