0s autopkgtest [23:48:44]: starting date and time: 2024-08-12 23:48:44+0000 0s autopkgtest [23:48:44]: git checkout: fd3bed09 nova: allow more retries for quota issues 0s autopkgtest [23:48:44]: host juju-7f2275-prod-proposed-migration-environment-2; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.j44mmndj/out --timeout-copy=6000 --setup-commands /home/ubuntu/autopkgtest-cloud/worker-config-production/setup-canonical.sh --apt-upgrade cminpack --timeout-short=300 --timeout-copy=20000 --timeout-build=20000 --env=ADT_TEST_TRIGGERS=migration-reference/0 -- ssh -s /home/ubuntu/autopkgtest/ssh-setup/nova -- --flavor autopkgtest-s390x --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-2@bos03-s390x-20.secgroup --name adt-noble-s390x-cminpack-20240812-232516-juju-7f2275-prod-proposed-migration-environment-2-3f25730b-1ba0-474f-8d67-26424908f61f --image adt/ubuntu-noble-s390x-server --keyname testbed-juju-7f2275-prod-proposed-migration-environment-2 --net-id=net_prod-proposed-migration-s390x -e TERM=linux -e ''"'"'http_proxy=http://squid.internal:3128'"'"'' -e ''"'"'https_proxy=http://squid.internal:3128'"'"'' -e ''"'"'no_proxy=127.0.0.1,127.0.1.1,login.ubuntu.com,localhost,localdomain,novalocal,internal,archive.ubuntu.com,ports.ubuntu.com,security.ubuntu.com,ddebs.ubuntu.com,changelogs.ubuntu.com,keyserver.ubuntu.com,launchpadlibrarian.net,launchpadcontent.net,launchpad.net,10.24.0.0/24,keystone.ps5.canonical.com,objectstorage.prodstack5.canonical.com'"'"'' --mirror=http://ftpmaster.internal/ubuntu/ 397s nova [W] Using flock in prodstack6-s390x 397s Creating nova instance adt-noble-s390x-cminpack-20240812-232516-juju-7f2275-prod-proposed-migration-environment-2-3f25730b-1ba0-474f-8d67-26424908f61f from image adt/ubuntu-noble-s390x-server-20240713.img (UUID 920ef23d-06ec-432b-945e-a14b2b94e2bd)... 397s nova [E] Timed out waiting for ssh. Aborting! Console log: 397s ------- nova console-log 26cbe89b-3def-43a1-b48a-c6690ffd50d3 (adt-noble-s390x-cminpack-20240812-232516-juju-7f2275-prod-proposed-migration-environment-2-3f25730b-1ba0-474f-8d67-26424908f61f) ------ 397s LOADPARM=[ ] 397s Using virtio-blk. 397s Using SCSI scheme. 397s .......... 397s [ 0.139358] Linux version 6.8.0-38-generic (buildd@bos02-s390x-012) (s390x-linux-gnu-gcc-13 (Ubuntu 13.2.0-23ubuntu4) 13.2.0, GNU ld (GNU Binutils for Ubuntu) 2.42) #38-Ubuntu SMP Fri Jun 7 14:30:57 UTC 2024 (Ubuntu 6.8.0-38.38-generic 6.8.8) 397s [ 0.139361] setup: Linux is running under KVM in 64-bit mode 397s [ 0.142527] setup: The maximum memory size is 4096MB 397s [ 0.142529] setup: Relocating AMODE31 section of size 0x00003000 397s [ 0.142593] cpu: 2 configured CPUs, 0 standby CPUs 397s [ 0.142670] Write protected kernel read-only data: 22736k 397s [ 0.142685] Zone ranges: 397s [ 0.142686] DMA [mem 0x0000000000000000-0x000000007fffffff] 397s [ 0.142688] Normal [mem 0x0000000080000000-0x00000000ffffffff] 397s [ 0.142690] Movable zone start for each node 397s [ 0.142691] Early memory node ranges 397s [ 0.142691] node 0: [mem 0x0000000000000000-0x00000000ffffffff] 397s [ 0.142693] Initmem setup node 0 [mem 0x0000000000000000-0x00000000ffffffff] 397s [ 0.175613] percpu: Embedded 52 pages/cpu s88832 r8192 d115968 u212992 397s [ 0.175630] Kernel command line: root=LABEL=cloudimg-rootfs 397s [ 0.175939] random: crng init done 397s [ 0.177311] Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) 397s [ 0.177998] Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) 397s [ 0.178009] Fallback order for Node 0: 0 397s [ 0.178010] Built 1 zonelists, mobility grouping on. Total pages: 1032192 397s [ 0.178011] Policy zone: Normal 397s [ 0.178012] mem auto-init: stack:all(zero), heap alloc:on, heap free:off 397s [ 0.259901] Memory: 4070168K/4194304K available (15076K kernel code, 3616K rwdata, 7660K rodata, 6532K init, 1064K bss, 124136K reserved, 0K cma-reserved) 397s [ 0.260603] SLUB: HWalign=256, Order=0-3, MinObjects=0, CPUs=2, Nodes=1 397s [ 0.260611] ftrace: allocating 38864 entries in 152 pages 397s [ 0.275571] ftrace: allocated 152 pages with 3 groups 397s [ 0.275793] rcu: Hierarchical RCU implementation. 397s [ 0.275793] rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=2. 397s [ 0.275794] Rude variant of Tasks RCU enabled. 397s [ 0.275795] Tracing variant of Tasks RCU enabled. 397s [ 0.275795] rcu: RCU calculated value of scheduler-enlistment delay is 10 jiffies. 397s [ 0.275796] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=2 397s [ 0.279477] NR_IRQS: 3, nr_irqs: 3, preallocated irqs: 3 397s [ 0.279489] rcu: srcu_init: Setting srcu_struct sizes based on contention. 397s [ 0.279507] clocksource: tod: mask: 0xffffffffffffffff max_cycles: 0x3b0a9be803b0a9, max_idle_ns: 1805497147909793 ns 397s [ 0.279635] Console: colour dummy device 80x25 397s [ 0.279670] printk: legacy console [ttysclp0] enabled 397s [ 0.279715] pid_max: default: 32768 minimum: 301 397s [ 0.279743] LSM: initializing lsm=lockdown,capability,landlock,yama,apparmor,integrity 397s [ 0.279754] landlock: Up and running. 397s [ 0.279755] Yama: becoming mindful. 397s [ 0.279819] AppArmor: AppArmor initialized 397s [ 0.279870] Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) 397s [ 0.279893] Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) 397s [ 0.280381] RCU Tasks Rude: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1. 397s [ 0.280409] RCU Tasks Trace: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1. 397s [ 0.280456] rcu: Hierarchical SRCU implementation. 397s [ 0.280457] rcu: Max phase no-delay instances is 1000. 397s [ 0.280583] smp: Bringing up secondary CPUs ... 397s [ 0.280823] smp: Brought up 1 node, 2 CPUs 397s [ 0.281160] devtmpfs: initialized 397s [ 0.281368] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns 397s [ 0.281414] futex hash table entries: 512 (order: 5, 131072 bytes, linear) 397s [ 0.281612] NET: Registered PF_NETLINK/PF_ROUTE protocol family 397s [ 0.281654] audit: initializing netlink subsys (disabled) 397s [ 0.281698] audit: type=2000 audit(1723506654.825:1): state=initialized audit_enabled=0 res=1 397s [ 0.281727] Spectre V2 mitigation: etokens 397s [ 0.288570] HugeTLB: registered 1.00 MiB page size, pre-allocated 0 pages 397s [ 0.288572] HugeTLB: 12 KiB vmemmap can be freed for a 1.00 MiB page 397s [ 0.288931] iommu: Default domain type: Translated 397s [ 0.288932] iommu: DMA domain TLB invalidation policy: strict mode 397s [ 0.288987] SCSI subsystem initialized 397s [ 0.288998] pps_core: LinuxPPS API ver. 1 registered 397s [ 0.288998] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti 397s [ 0.289335] NetLabel: Initializing 397s [ 0.289336] NetLabel: domain hash size = 128 397s [ 0.289337] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO 397s [ 0.289374] NetLabel: unlabeled traffic allowed by default 397s [ 0.292896] VFS: Disk quotas dquot_6.6.0 397s [ 0.292903] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) 397s [ 0.292991] AppArmor: AppArmor Filesystem Enabled 397s [ 0.294653] NET: Registered PF_INET protocol family 397s [ 0.294851] IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) 397s [ 0.304073] tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) 397s [ 0.304086] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) 397s [ 0.304098] TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) 397s [ 0.304156] TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, linear) 397s [ 0.304202] TCP: Hash tables configured (established 32768 bind 32768) 397s [ 0.304270] MPTCP token hash table entries: 4096 (order: 4, 98304 bytes, linear) 397s [ 0.304306] UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) 397s [ 0.304334] UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) 397s [ 0.304358] NET: Registered PF_UNIX/PF_LOCAL protocol family 397s [ 0.304362] NET: Registered PF_XDP protocol family 397s [ 0.304441] Key type cert_store_key registered 397s [ 0.304463] kvm-s390: SIE is not available 397s [ 0.304477] hypfs: The hardware system does not support hypfs 397s [ 0.304564] Initialise system trusted keyrings 397s [ 0.304568] Key type blacklist registered 397s [ 0.304593] workingset: timestamp_bits=45 max_order=20 bucket_order=0 397s [ 0.304598] zbud: loaded 397s [ 0.304622] Trying to unpack rootfs image as initramfs... 397s [ 0.304807] squashfs: version 4.0 (2009/01/31) Phillip Lougher 397s [ 0.310048] fuse: init (API version 7.39) 397s [ 0.310120] integrity: Platform Keyring initialized 397s [ 0.319337] Key type asymmetric registered 397s [ 0.319339] Asymmetric key parser 'x509' registered 397s [ 0.319364] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 248) 397s [ 0.330045] io scheduler mq-deadline registered 397s [ 0.330125] hvc_iucv: The z/VM IUCV HVC device driver cannot be used without z/VM 397s [ 0.331245] loop: module loaded 397s [ 0.331357] tun: Universal TUN/TAP device driver, 1.6 397s [ 0.331604] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. 397s [ 0.331608] device-mapper: uevent: version 1.0.3 397s [ 0.331639] device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@redhat.com 397s [ 0.331663] cio: Channel measurement facility initialized using format extended (mode autodetected) 397s [ 0.331784] drop_monitor: Initializing network drop monitor service 397s [ 0.331859] NET: Registered PF_INET6 protocol family 397s [ 0.340169] sclp_sd: Store Data request failed (eq=2, di=3, response=0x40f0, flags=0x00, status=0, rc=-5) 397s [ 0.340419] virtio_blk virtio0: 1/0/0 default/read/poll queues 397s [ 0.340643] virtio_blk virtio0: [vda] 41943040 512-byte logical blocks (21.5 GB/20.0 GiB) 397s [ 0.341834] vda: vda1 397s [ 0.369498] Freeing initrd memory: 17292K 397s [ 0.372717] Segment Routing with IPv6 397s [ 0.372722] In-situ OAM (IOAM) with IPv6 397s [ 0.372739] NET: Registered PF_PACKET protocol family 397s [ 0.372775] Key type dns_resolver registered 397s [ 0.373476] registered taskstats version 1 397s [ 0.373566] Loading compiled-in X.509 certificates 397s [ 0.374367] Loaded X.509 cert 'Build time autogenerated kernel key: f6aa641e5e1077928d0349b6d83e21865034f5e7' 397s [ 0.375096] Loaded X.509 cert 'Canonical Ltd. Live Patch Signing: 14df34d1a87cf37625abec039ef2bf521249b969' 397s [ 0.375837] Loaded X.509 cert 'Canonical Ltd. Kernel Module Signing: 88f752e560a1e0737e31163a466ad7b70a850c19' 397s [ 0.375839] blacklist: Loading compiled-in revocation X.509 certificates 397s [ 0.375852] Loaded X.509 cert 'Canonical Ltd. Secure Boot Signing: 61482aa2830d0ab2ad5af10b7250da9033ddcef0' 397s [ 0.375864] Loaded X.509 cert 'Canonical Ltd. Secure Boot Signing (2017): 242ade75ac4a15e50d50c84b0d45ff3eae707a03' 397s [ 0.375876] Loaded X.509 cert 'Canonical Ltd. Secure Boot Signing (ESM 2018): 365188c1d374d6b07c3c8f240f8ef722433d6a8b' 397s [ 0.375894] Loaded X.509 cert 'Canonical Ltd. Secure Boot Signing (2019): c0746fd6c5da3ae827864651ad66ae47fe24b3e8' 397s [ 0.375905] Loaded X.509 cert 'Canonical Ltd. Secure Boot Signing (2021 v1): a8d54bbb3825cfb94fa13c9f8a594a195c107b8d' 397s [ 0.375916] Loaded X.509 cert 'Canonical Ltd. Secure Boot Signing (2021 v2): 4cf046892d6fd3c9a5b03f98d845f90851dc6a8c' 397s [ 0.375928] Loaded X.509 cert 'Canonical Ltd. Secure Boot Signing (2021 v3): 100437bb6de6e469b581e61cd66bce3ef4ed53af' 397s [ 0.375939] Loaded X.509 cert 'Canonical Ltd. Secure Boot Signing (Ubuntu Core 2019): c1d57b8f6b743f23ee41f4f7ee292f06eecadfb9' 397s [ 0.378696] Key type .fscrypt registered 397s [ 0.378698] Key type fscrypt-provisioning registered 397s [ 0.386005] Key type encrypted registered 397s [ 0.386008] AppArmor: AppArmor sha256 policy hashing enabled 397s [ 0.386011] ima: No TPM chip found, activating TPM-bypass! 397s [ 0.386013] Loading compiled-in module X.509 certificates 397s [ 0.386743] Loaded X.509 cert 'Build time autogenerated kernel key: f6aa641e5e1077928d0349b6d83e21865034f5e7' 397s [ 0.386745] ima: Allocated hash algorithm: sha256 397s [ 0.386752] ima: No architecture policies found 397s [ 0.386760] evm: Initialising EVM extended attributes: 397s [ 0.386760] evm: security.selinux 397s [ 0.386761] evm: security.SMACK64 397s [ 0.386762] evm: security.SMACK64EXEC 397s [ 0.386763] evm: security.SMACK64TRANSMUTE 397s [ 0.386764] evm: security.SMACK64MMAP 397s [ 0.386765] evm: security.apparmor 397s [ 0.386766] evm: security.ima 397s [ 0.386766] evm: security.capability 397s [ 0.386767] evm: HMAC attrs: 0x1 397s [ 0.387829] Freeing unused kernel image (initmem) memory: 6532K 397s [ 0.387862] Write protected read-only-after-init data: 148k 397s [ 0.388000] Checked W+X mappings: passed, no unexpected W+X pages found 397s [ 0.388002] Run /init as init process 397s Loading, please wait... 397s Starting systemd-udevd version 255.4-1ubuntu8.1 397s [ 0.421314] virtio_net virtio1 enc1: renamed from eth0 397s Begin: Starting firmware auto-configuration ... done. 397s Begin: Loading essential drivers ... done. 397s Begin: Running /scripts/init-premount ... done. 397s Begin: Mounting root file system ... Begin: Running /scripts/local-top ... done. 397s Begin: Running /scripts/local-premount ... [ 1.830002] raid6: vx128x8 gen() 21860 MB/s 397s [ 1.830007] raid6: using algorithm vx128x8 gen() 21860 MB/s 397s [ 2.000002] raid6: .... xor() 14615 MB/s, rmw enabled 397s [ 2.000005] raid6: using s390xc recovery algorithm 397s [ 2.000978] xor: automatically using best checksumming function xc 397s [ 2.028190] Btrfs loaded, zoned=yes, fsverity=yes 397s Scanning for Btrfs filesystems 397s done. 397s Begin: Will now check root file system ... fsck from util-linux 2.39.3 397s [/usr/sbin/fsck.ext4 (1) -- /dev/vda1] fsck.ext4 -a -C0 /dev/vda1 397s cloudimg-rootfs: clean, 85699/2560000 files, 618255/5242619 blocks 397s done. 397s [ 2.046102] EXT4-fs (vda1): mounted filesystem 3a20b47e-d5d8-4abe-a992-c1cb771ee648 ro with ordered data mode. Quota mode: none. 397s done. 397s Begin: Running /scripts/local-bottom ... done. 397s Begin: Running /scripts/init-bottom ... done. 397s [ 2.113431] systemd[1]: systemd 255.4-1ubuntu8.1 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) 397s [ 2.113435] systemd[1]: Detected virtualization kvm. 397s [ 2.113438] systemd[1]: Detected architecture s390x. 397s 397s Welcome to [1mUbuntu 24.04 LTS[0m! 397s 397s [ 2.114160] systemd[1]: Hostname set to . 397s [ 2.175386] (sd-exec-[239]: /usr/lib/systemd/system-generators/s390-cpi-vars failed with exit status 1. 397s [ 2.198010] systemd[1]: Configuration file /run/systemd/system/systemd-networkd-wait-online.service.d/10-netplan.conf is marked world-inaccessible. This has no effect as configuration data is accessible via APIs without restrictions. Proceeding anyway. 397s [ 2.200358] 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. 397s [ 2.258007] systemd[1]: Queued start job for default target graphical.target. 397s [ 2.300557] systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. 397s [[0;32m OK [0m] Created slice [0;1;39msystem-modprobe.slice[0m - Slice /system/modprobe. 397s [ 2.300793] systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. 397s [[0;32m OK [0m] Created slice [0;1;39msystem-serial\x2dget���slice[0m - Slice /system/serial-getty. 397s [ 2.300923] systemd[1]: Created slice user.slice - User and Session Slice. 397s [[0;32m OK [0m] Created slice [0;1;39muser.slice[0m - User and Session Slice. 397s [ 2.300979] systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. 397s [[0;32m OK [0m] Started [0;1;39msystemd-ask-password-wall.���[0md Requests to Wall Directory Watch. 397s [ 2.301086] systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. 397s [[0;32m OK [0m] Set up automount [0;1;39mproc-sys-fs-binfm���[0mormats File System Automount Point. 397s [ 2.301121] systemd[1]: Expecting device dev-ttysclp0.device - /dev/ttysclp0... 397s Expecting device [0;1;39mdev-ttysclp0.device[0m - /dev/ttysclp0... 397s [ 2.301159] systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. 397s [[0;32m OK [0m] Reached target [0;1;39mintegritysetup.targ���[0m Local Integrity Protected Volumes. 397s [ 2.301202] systemd[1]: Reached target remote-fs.target - Remote File Systems. 397s [[0;32m OK [0m] Reached target [0;1;39mremote-fs.target[0m - Remote File Systems. 397s [ 2.301229] systemd[1]: Reached target slices.target - Slice Units. 397s [[0;32m OK [0m] Reached target [0;1;39mslices.target[0m - Slice Units. 397s [ 2.301258] systemd[1]: Reached target swap.target - Swaps. 397s [[0;32m OK [0m] Reached target [0;1;39mswap.target[0m - Swaps. 397s [ 2.301289] systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. 397s [[0;32m OK [0m] Reached target [0;1;39mveritysetup.target[0m - Local Verity Protected Volumes. 397s [ 2.301382] systemd[1]: Listening on multipathd.socket - multipathd control socket. 397s [[0;32m OK [0m] Listening on [0;1;39mmultipathd.socket[0m - multipathd control socket. 397s [ 2.301457] systemd[1]: Listening on syslog.socket - Syslog Socket. 397s [[0;32m OK [0m] Listening on [0;1;39msyslog.socket[0m - Syslog Socket. 397s [ 2.301520] systemd[1]: Listening on systemd-fsckd.socket - fsck to fsckd communication Socket. 397s [[0;32m OK [0m] Listening on [0;1;39msystemd-fsckd.socket[0m ���fsck to fsckd communication Socket. 397s [ 2.301567] systemd[1]: Listening on systemd-initctl.socket - initctl Compatibility Named Pipe. 397s [[0;32m OK [0m] Listening on [0;1;39msystemd-initctl.socke���[0m- initctl Compatibility Named Pipe. 397s [ 2.301633] systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). 397s [[0;32m OK [0m] Listening on [0;1;39msystemd-journald-dev-���socket[0m - Journal Socket (/dev/log). 397s [ 2.301708] systemd[1]: Listening on systemd-journald.socket - Journal Socket. 397s [[0;32m OK [0m] Listening on [0;1;39msystemd-journald.socket[0m - Journal Socket. 397s [ 2.301800] systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. 397s [[0;32m OK [0m] Listening on [0;1;39msystemd-networkd.socket[0m - Network Service Netlink Socket. 397s [ 2.301879] systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. 397s [[0;32m OK [0m] Listening on [0;1;39msystemd-udevd-control.socket[0m - udev Control Socket. 397s [ 2.301941] systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. 397s [[0;32m OK [0m] Listening on [0;1;39msystemd-udevd-kernel.socket[0m - udev Kernel Socket. 397s [ 2.302515] systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... 397s Mounting [0;1;39mdev-hugepages.mount[0m - Huge Pages File System... 397s [ 2.304619] systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... 397s Mounting [0;1;39mdev-mqueue.mount[0m - POSIX Message Queue File System... 397s [ 2.305352] systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... 397s Mounting [0;1;39msys-kernel-debug.mount[0m - Kernel Debug File System... 397s [ 2.307434] systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... 397s Mounting [0;1;39msys-kernel-tracing.mount[0m - Kernel Trace File System... 397s [ 2.309934] systemd[1]: Starting keyboard-setup.service - Set the console keyboard layout... 397s Starting [0;1;39mkeyboard-setup.service[0m - Set the console keyboard layout... 397s [ 2.310853] systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... 397s Starting [0;1;39mkmod-static-nodes.service[0m���eate List of Static Device Nodes... 397s [ 2.312806] systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... 397s Starting [0;1;39mmodprobe@configfs.service[0m - Load Kernel Module configfs... 397s [ 2.313205] systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... 397s Starting [0;1;39mmodprobe@dm_mod.service[0m - Load Kernel Module dm_mod... 397s [ 2.313586] systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... 397s Starting [0;1;39mmodprobe@drm.service[0m - Load Kernel Module drm... 397s [ 2.314631] systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... 397s Starting [0;1;39mmodprobe@efi_pstore.servi���[0m - Load Kernel Module efi_pstore... 397s [ 2.319436] systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... 397s Starting [0;1;39mmodprobe@fuse.service[0m - Load Kernel Module fuse... 397s [ 2.326676] systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... 397s Starting [0;1;39mmodprobe@loop.service[0m - Load Kernel Module loop... 397s [ 2.326722] systemd[1]: netplan-ovs-cleanup.service - OpenVSwitch configuration for cleanup was skipped because of an unmet condition check (ConditionFileIsExecutable=/usr/bin/ovs-vsctl). 397s [ 2.326797] 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). 397s [ 2.327562] systemd[1]: Starting systemd-journald.service - Journal Service... 397s Starting [0;1;39msystemd-journald.service[0m - Journal Service... 397s [ 2.331113] systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... 397s Starting [0;1;39msystemd-modules-load.service[0m - Load Kernel Modules... 397s [ 2.340255] systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... 397s Starting [0;1;39msystemd-remount-fs.servic���[0munt Root and Kernel File Systems... 397s [ 2.344561] systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... 397s Starting [0;1;39msystemd-udev-trigger.service[0m - Coldplug All udev Devices... 397s [ 2.345183] systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. 397s [[0;32m OK [0m] Mounted [0;1;39mdev-hugepages.mount[0m - Huge Pages File System. 397s [ 2.345243] systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. 397s [[0;32m OK [0m] Mounted [0;1;39mdev-mqueue.mount[0m - POSIX Message Queue File System. 397s [ 2.345295] systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. 397s [[0;32m OK [0m] Mounted [0;1;39msys-kernel-debug.mount[0m - Kernel Debug File System. 397s [ 2.345346] systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. 397s [[0;32m OK [0m] Mounted [0;1;39msys-kernel-tracing.mount[0m - Kernel Trace File System. 397s [ 2.345445] systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. 397s [[0;32m OK [0m] Finished [0;1;39mkmod-static-nodes.service[0m���Create List of Static Device Nodes. 397s [ 2.345605] systemd[1]: modprobe@configfs.service: Deactivated successfully. 397s [ 2.345893] systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. 397s [[0;32m OK [0m] Finished [0;1;39mmodprobe@configfs.service[0m - Load Kernel Module configfs. 397s [ 2.346219] systemd[1]: modprobe@dm_mod.service: Deactivated successfully. 397s [ 2.346278] systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. 397s [[0;32m OK [0m] Finished [0;1;39mmodprobe@dm_mod.service[0m - Load Kernel Module dm_mod. 397s [ 2.346525] systemd[1]: modprobe@drm.service: Deactivated successfully. 397s [ 2.348306] systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. 397s [[0;32m OK [0m] Finished [0;1;39mmodprobe@drm.service[0m - Load Kernel Module drm. 397s [ 2.348446] systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. 397s [ 2.348504] systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. 397s [[0;32m OK [0m] Finished [0;1;39mmodprobe@efi_pstore.service[0m - Load Kernel Module efi_pstore. 397s [ 2.348631] systemd[1]: modprobe@fuse.service: Deactivated successfully. 397s [ 2.348687] systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. 397s [[0;32m OK [0m] Finished [0;1;39mmodprobe@fuse.service[0m - Load Kernel Module fuse. 397s [ 2.348819] systemd[1]: modprobe@loop.service: Deactivated successfully. 397s [ 2.348875] systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. 397s [[0;32m OK [0m] Finished [0;1;39mmodprobe@loop.service[0m - Load Kernel Module loop. 397s [ 2.350289] systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... 397s Mounting [0;1;39msys-fs-fuse-connections.mount[0m - FUSE Control File System... 397s [ 2.351709] systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... 397s Mounting [0;1;39msys-kernel-config.mount[0m - Kernel Configuration File System... 397s [ 2.351755] systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. 397s [ 2.373328] systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... 397s Starting [0;1;39msystemd-tmpfiles-setup-de���[0m Device Nodes in /dev gracefully... 397s [ 2.373727] systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. 397s [[0;32m OK [0m] Finished [0;1;39msystemd-modules-load.service[0m - Load Kernel Modules. 397s [ 2.373994] systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. 397s [[0;32m OK [0m] Mounted [0;1;39msys-fs-fuse-connections.mount[0m - FUSE Control File System. 397s [ 2.374054] systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. 397s [[0;32m OK [0m] Mounted [0;1;39msys-kernel-config.mount[0m - Kernel Configuration File System. 397s [ 2.375271] systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. 397s [[0;32m OK [0m] Finished [0;1;39msystemd-udev-trigger.service[0m - Coldplug All udev Devices. 397s [ 2.376133] systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... 397s Starting [0;1;39msystemd-sysctl.service[0m - Apply Kernel Variables... 397s [ 2.376658] systemd-journald[291]: Collecting audit messages is disabled. 397s [ 2.380619] EXT4-fs (vda1): re-mounted 3a20b47e-d5d8-4abe-a992-c1cb771ee648 r/w. Quota mode: none. 397s [ 2.381456] systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. 397s [[0;32m OK [0m] Finished [0;1;39msystemd-remount-fs.servic���[0mmount Root and Kernel File Systems. 397s [ 2.382906] systemd[1]: Starting cloud-init-local.service - Initial cloud-init job (pre-networking)... 397s Starting [0;1;39mcloud-init-local.service[0m ��� cloud-init job (pre-networking)... 397s Starting [0;1;39mmultipathd.service[0m - Devi���pper Multipath Device Controller... 397s Starting [0;1;39msystemd-random-seed.service[0m - Load/Save OS Random Seed... 397s [[0;32m OK [0m] Started [0;1;39msystemd-journald.service[0m - Journal Service. 397s [[0;32m OK [0m] Finished [0;1;39msystemd-tmpfiles-setup-de���[0mic Device Nodes in /dev gracefully. 397s [[0;32m OK [0m] Finished [0;1;39msystemd-sysctl.service[0m - Apply Kernel Variables. 397s [[0;32m OK [0m] Finished [0;1;39mkeyboard-setup.service[0m - Set the console keyboard layout. 397s Starting [0;1;39msystemd-journal-flush.ser���[0msh Journal to Persistent Storage... 397s Starting [0;1;39msystemd-tmpfiles-setup-de���[0meate Static Device Nodes in /dev... 397s [[0;32m OK [0m] Finished [0;1;39msystemd-tmpfiles-setup-de���[0mCreate Static Device Nodes in /dev. 397s Starting [0;1;39msystemd-udevd.service[0m - R���ager for Device Events and Files... 397s [[0;32m OK [0m] Finished [0;1;39msystemd-random-seed.service[0m - Load/Save OS Random Seed. 397s [[0;32m OK [0m] Started [0;1;39mmultipathd.service[0m - Devic���Mapper Multipath Device Controller. 397s [[0;32m OK [0m] Reached target [0;1;39mlocal-fs-pre.target[0m���Preparation for Local File Systems. 397s [[0;32m OK [0m] Reached target [0;1;39mlocal-fs.target[0m - Local File Systems. 397s [[0;32m OK [0m] Listening on [0;1;39msystemd-sysext.socket[0m���tension Image Management (Varlink). 397s Starting [0;1;39mapparmor.service[0m - Load AppArmor profiles... 397s Starting [0;1;39mconsole-setup.service[0m - Set console font and keymap... 397s Starting [0;1;39mplymouth-read-write.servi���[0mymouth To Write Out Runtime Data... 397s Starting [0;1;39msystemd-binfmt.service[0m - Set Up Additional Binary Formats... 397s Starting [0;1;39mufw.service[0m - Uncomplicated firewall... 397s [[0;32m OK [0m] Finished [0;1;39msystemd-journal-flush.ser���[0mlush Journal to Persistent Storage. 397s [[0;32m OK [0m] Finished [0;1;39mconsole-setup.service[0m - Set console font and keymap. 397s [[0;32m OK [0m] Finished [0;1;39mufw.service[0m - Uncomplicated firewall. 397s Starting [0;1;39msystemd-tmpfiles-setup.se���[0me Volatile Files and Directories... 397s Mounting [0;1;39mproc-sys-fs-binfmt_misc.m���[0mcutable File Formats File System... 397s [[0;32m OK [0m] Started [0;1;39msystemd-udevd.service[0m - Ru���anager for Device Events and Files. 397s [[0;32m OK [0m] Finished [0;1;39mplymouth-read-write.servi���[0mPlymouth To Write Out Runtime Data. 397s [[0;32m OK [0m] Started [0;1;39msystemd-ask-password-conso���[0mequests to Console Directory Watch. 397s [[0;32m OK [0m] Reached target [0;1;39mcryptsetup.target[0m - Local Encrypted Volumes. 397s [[0;32m OK [0m] Mounted [0;1;39mproc-sys-fs-binfmt_misc.mo���[0mxecutable File Formats File System. 397s [[0;32m OK [0m] Finished [0;1;39msystemd-binfmt.service[0m - Set Up Additional Binary Formats. 397s [[0;32m OK [0m] Found device [0;1;39mdev-ttysclp0.device[0m - /dev/ttysclp0. 397s [[0;32m OK [0m] Finished [0;1;39msystemd-tmpfiles-setup.se���[0mate Volatile Files and Directories. 397s Starting [0;1;39msystemd-resolved.service[0m - Network Name Resolution... 397s Starting [0;1;39msystemd-timesyncd.service[0m - Network Time Synchronization... 397s Starting [0;1;39msystemd-update-utmp.servi���[0mord System Boot/Shutdown in UTMP... 397s [[0;32m OK [0m] Finished [0;1;39msystemd-update-utmp.servi���[0mecord System Boot/Shutdown in UTMP. 397s [[0;32m OK [0m] Started [0;1;39msystemd-timesyncd.service[0m - Network Time Synchronization. 397s [[0;32m OK [0m] Reached target [0;1;39mtime-set.target[0m - System Time Set. 397s [[0;32m OK [0m] Finished [0;1;39mapparmor.service[0m - Load AppArmor profiles. 397s [[0;32m OK [0m] Started [0;1;39msystemd-resolved.service[0m - Network Name Resolution. 397s [[0;32m OK [0m] Reached target [0;1;39mnss-lookup.target[0m - Host and Network Name Lookups. 397s [ 2.833828] cloud-init[537]: Cloud-init v. 24.1.3-0ubuntu3.3 running 'init-local' at Mon, 12 Aug 2024 23:50:57 +0000. Up 2.82 seconds. 397s [ 13.527893] cloud-init[537]: 2024-08-12 23:51:08,072 - url_helper.py[ERROR]: Timed out, no response from urls: ['http://[fe80::a9fe:a9fe]/openstack', 'http://169.254.169.254/openstack'] 397s [ 13.529528] cloud-init[537]: 2024-08-12 23:51:08,075 - util.py[WARNING]: No active metadata service found 397s [[0;32m OK [0m] Finished [0;1;39mcloud-init-local.service[0m ���al cloud-init job (pre-networking). 397s [[0;32m OK [0m] Reached target [0;1;39mnetwork-pre.target[0m - Preparation for Network. 397s Starting [0;1;39msystemd-networkd.service[0m - Network Configuration... 397s [[0;32m OK [0m] Started [0;1;39msystemd-networkd.service[0m - Network Configuration. 397s [[0;32m OK [0m] Reached target [0;1;39mnetwork.target[0m - Network. 397s Starting [0;1;39msystemd-networkd-wait-onl���[0mait for Network to be Configured... 397s [[0;32m OK [0m] Finished [0;1;39msystemd-networkd-wait-onl���[0m Wait for Network to be Configured. 397s Starting [0;1;39mcloud-init.service[0m - Init���t job (metadata service crawler)... 397s [ 15.638899] cloud-init[613]: Cloud-init v. 24.1.3-0ubuntu3.3 running 'init' at Mon, 12 Aug 2024 23:49:43 +0000. Up 15.62 seconds. 397s [ 15.642903] cloud-init[613]: ci-info: +++++++++++++++++++++++++++++++++++++++Net device info+++++++++++++++++++++++++++++++++++++++ 397s [ 15.642943] cloud-init[613]: ci-info: +--------+------+------------------------------+---------------+--------+-------------------+ 397s [ 15.642975] cloud-init[613]: ci-info: | Device | Up | Address | Mask | Scope | Hw-Address | 397s [ 15.643004] cloud-init[613]: ci-info: +--------+------+------------------------------+---------------+--------+-------------------+ 397s [ 15.643032] cloud-init[613]: ci-info: | enc1 | True | 10.145.232.211 | 255.255.255.0 | global | fa:16:3e:c3:4a:0c | 397s [ 15.643062] cloud-init[613]: ci-info: | enc1 | True | fe80::f816:3eff:fec3:4a0c/64 | . | link | fa:16:3e:c3:4a:0c | 397s [ 15.643095] cloud-init[613]: ci-info: | lo | True | 127.0.0.1 | 255.0.0.0 | host | . | 397s [ 15.643122] cloud-init[613]: ci-info: | lo | True | ::1/128 | . | host | . | 397s [ 15.643148] cloud-init[613]: ci-info: +--------+------+------------------------------+---------------+--------+-------------------+ 397s [ 15.643174] cloud-init[613]: ci-info: ++++++++++++++++++++++++++++++++Route IPv4 info+++++++++++++++++++++++++++++++++ 397s [ 15.643202] cloud-init[613]: ci-info: +-------+-----------------+--------------+-----------------+-----------+-------+ 397s [ 15.643228] cloud-init[613]: ci-info: | Route | Destination | Gateway | Genmask | Interface | Flags | 397s [ 15.643255] cloud-init[613]: ci-info: +-------+-----------------+--------------+-----------------+-----------+-------+ 397s [ 15.643284] cloud-init[613]: ci-info: | 0 | 0.0.0.0 | 10.145.232.1 | 0.0.0.0 | enc1 | UG | 397s [ 15.643313] cloud-init[613]: ci-info: | 1 | 10.145.232.0 | 0.0.0.0 | 255.255.255.0 | enc1 | U | 397s [ 15.643338] cloud-init[613]: ci-info: | 2 | 10.145.232.1 | 0.0.0.0 | 255.255.255.255 | enc1 | UH | 397s [ 15.643364] cloud-init[613]: ci-info: | 3 | 10.145.232.2 | 0.0.0.0 | 255.255.255.255 | enc1 | UH | 397s [ 15.643389] cloud-init[613]: ci-info: | 4 | 91.189.91.131 | 10.145.232.1 | 255.255.255.255 | enc1 | UGH | 397s [ 15.643415] cloud-init[613]: ci-info: | 5 | 91.189.91.132 | 10.145.232.1 | 255.255.255.255 | enc1 | UGH | 397s [ 15.643441] cloud-init[613]: ci-info: | 6 | 169.254.169.254 | 10.145.232.2 | 255.255.255.255 | enc1 | UGH | 397s [ 15.643467] cloud-init[613]: ci-info: +-------+-----------------+--------------+-----------------+-----------+-------+ 397s [ 15.643495] cloud-init[613]: ci-info: +++++++++++++++++++Route IPv6 info+++++++++++++++++++ 397s [ 15.643523] cloud-init[613]: ci-info: +-------+-------------+---------+-----------+-------+ 397s [ 15.643548] cloud-init[613]: ci-info: | Route | Destination | Gateway | Interface | Flags | 397s [ 15.643574] cloud-init[613]: ci-info: +-------+-------------+---------+-----------+-------+ 397s [ 15.643598] cloud-init[613]: ci-info: | 0 | fe80::/64 | :: | enc1 | U | 397s [ 15.643627] cloud-init[613]: ci-info: | 2 | local | :: | enc1 | U | 397s [ 15.643653] cloud-init[613]: ci-info: | 3 | multicast | :: | enc1 | U | 397s [ 15.643680] cloud-init[613]: ci-info: +-------+-------------+---------+-----------+-------+ 397s [ 25.650660] cloud-init[613]: 2024-08-12 23:49:53,490 - url_helper.py[ERROR]: Timed out, no response from urls: ['http://[fe80::a9fe:a9fe]/openstack', 'http://169.254.169.254/openstack'] 397s [ 25.650840] cloud-init[613]: 2024-08-12 23:49:53,490 - util.py[WARNING]: No active metadata service found 397s [[0;32m OK [0m] Stopped [0;1;39msystemd-networkd-wait-onli���[0m Wait for Network to be Configured. 397s Stopping [0;1;39msystemd-networkd-wait-onl���[0mait for Network to be Configured... 397s Stopping [0;1;39msystemd-networkd.service[0m - Network Configuration... 397s [[0;32m OK [0m] Stopped [0;1;39msystemd-networkd.service[0m - Network Configuration. 397s Starting [0;1;39msystemd-networkd.service[0m - Network Configuration... 397s [[0;32m OK [0m] Started [0;1;39msystemd-networkd.service[0m - Network Configuration. 397s Starting [0;1;39msystemd-networkd-wait-onl���[0mait for Network to be Configured... 397s [ 26.152576] cloud-init[613]: 2024-08-12 23:49:53,992 - activators.py[WARNING]: Running ['netplan', 'apply'] resulted in stderr output: Failed to connect to system bus: No such file or directory 397s [ 26.152630] cloud-init[613]: Falling back to a hard restart of systemd-networkd.service 397s [[0;32m OK [0m] Finished [0;1;39msystemd-networkd-wait-onl���[0m Wait for Network to be Configured. 397s [ 26.989193] cloud-init[613]: Generating public/private rsa key pair. 397s [ 26.989272] cloud-init[613]: Your identification has been saved in /etc/ssh/ssh_host_rsa_key 397s [ 26.989307] cloud-init[613]: Your public key has been saved in /etc/ssh/ssh_host_rsa_key.pub 397s [ 26.989343] cloud-init[613]: The key fingerprint is: 397s [ 26.989375] cloud-init[613]: SHA256:2i3+0FU7wtTQkXUYTzMHwu8KfsQt3BM/i0WDvA8jluc root@auto-syncubuntu-noble-24-04-s390x-server-20240710-disk1-img-ad 397s [ 26.989406] cloud-init[613]: The key's randomart image is: 397s [ 26.989452] cloud-init[613]: +---[RSA 3072]----+ 397s [ 26.989483] cloud-init[613]: | .o.+X=| 397s [ 26.989512] cloud-init[613]: | .++o=| 397s [ 26.989541] cloud-init[613]: | o.+ .| 397s [ 26.989577] cloud-init[613]: | o +o+ | 397s [ 26.989617] cloud-init[613]: | S o=+=o.| 397s [ 26.989646] cloud-init[613]: | o o.==*=+.| 397s [ 26.989673] cloud-init[613]: | . +.+o+o*.o| 397s [ 26.989700] cloud-init[613]: | . o. oE o | 397s [ 26.989729] cloud-init[613]: | .... | 397s [ 26.989756] cloud-init[613]: +----[SHA256]-----+ 397s [ 26.989785] cloud-init[613]: Generating public/private ecdsa key pair. 397s [ 26.989813] cloud-init[613]: Your identification has been saved in /etc/ssh/ssh_host_ecdsa_key 397s [ 26.989839] cloud-init[613]: Your public key has been saved in /etc/ssh/ssh_host_ecdsa_key.pub 397s [ 26.989892] cloud-init[613]: The key fingerprint is: 397s [ 26.989922] cloud-init[613]: SHA256:HxiQbzhJz19aIqviG5t5nyZEoKky9NpSBw6s1kjZj90 root@auto-syncubuntu-noble-24-04-s390x-server-20240710-disk1-img-ad 397s [ 26.989961] cloud-init[613]: The key's randomart image is: 397s [ 26.989994] cloud-init[613]: +---[ECDSA 256]---+ 397s [ 26.990038] cloud-init[613]: | .. | 397s [ 26.990072] cloud-init[613]: | . o. | 397s [ 26.990100] cloud-init[613]: |. oo o *. | 397s [ 26.990126] cloud-init[613]: | *oo = *o. o | 397s [ 26.990154] cloud-init[613]: |+.* =..oS+.= | 397s [ 26.990181] cloud-init[613]: |=o * +.E..o. | 397s [ 26.990208] cloud-init[613]: |o.+ o. . . | 397s [ 26.990238] cloud-init[613]: | o ..=+ .. | 397s [ 26.990271] cloud-init[613]: | ..*+ +o | 397s [ 26.990299] cloud-init[613]: +----[SHA256]-----+ 397s [ 26.990327] cloud-init[613]: Generating public/private ed25519 key pair. 397s [ 26.990360] cloud-init[613]: Your identification has been saved in /etc/ssh/ssh_host_ed25519_key 397s [ 26.990395] cloud-init[613]: Your public key has been saved in /etc/ssh/ssh_host_ed25519_key.pub 397s [ 26.990424] cloud-init[613]: The key fingerprint is: 397s [ 26.990452] cloud-init[613]: SHA256:bBpXOQX7L84e3xs/XYaGw2vxCvAAXACYKl1aO++xn/Y root@auto-syncubuntu-noble-24-04-s390x-server-20240710-disk1-img-ad 397s [ 26.990479] cloud-init[613]: The key's randomart image is: 397s [ 26.990505] cloud-init[613]: +--[ED25519 256]--+ 397s [ 26.990531] cloud-init[613]: | o..... ... | 397s [ 26.990559] cloud-init[613]: | o o. . + | 397s [ 26.990585] cloud-init[613]: | o + .o = | 397s [ 26.990612] cloud-init[613]: |o o o o . o | 397s [ 26.990639] cloud-init[613]: |. o. S ... . | 397s [ 26.990665] cloud-init[613]: | o= + =.o o| 397s [ 26.990692] cloud-init[613]: | ..o o o*.oo| 397s [ 26.990719] cloud-init[613]: | o .. +o+.o+| 397s [ 26.990748] cloud-init[613]: | oo.Eo=...=| 397s [ 26.990776] cloud-init[613]: +----[SHA256]-----+ 397s [[0;32m OK [0m] Finished [0;1;39mcloud-init.service[0m - Init���nit job (metadata service crawler). 397s [[0;32m OK [0m] Reached target [0;1;39mcloud-config.target[0m - Cloud-config availability. 397s [[0;32m OK [0m] Reached target [0;1;39mnetwork-online.target[0m - Network is Online. 397s [[0;32m OK [0m] Reached target [0;1;39msysinit.target[0m - System Initialization. 397s [[0;32m OK [0m] Started [0;1;39mapt-daily.timer[0m - Daily apt download activities. 397s [[0;32m OK [0m] Started [0;1;39mapt-daily-upgrade.timer[0m - ���y apt upgrade and clean activities. 397s [[0;32m OK [0m] Started [0;1;39mdpkg-db-backup.timer[0m - Daily dpkg database backup timer. 397s [[0;32m OK [0m] Started [0;1;39me2scrub_all.timer[0m - Period���Metadata Check for All Filesystems. 397s [[0;32m OK [0m] Started [0;1;39mfstrim.timer[0m - Discard unused filesystem blocks once a week. 397s [[0;32m OK [0m] Started [0;1;39mfwupd-refresh.timer[0m - Refresh fwupd metadata regularly. 397s [[0;32m OK [0m] Started [0;1;39mlogrotate.timer[0m - Daily rotation of log files. 397s [[0;32m OK [0m] Started [0;1;39mman-db.timer[0m - Daily man-db regeneration. 397s [[0;32m OK [0m] Started [0;1;39mmotd-news.timer[0m - Message of the Day. 397s [[0;32m OK [0m] Started [0;1;39msysstat-collect.timer[0m - Ru���y accounting tool every 10 minutes. 397s [[0;32m OK [0m] Started [0;1;39msysstat-summary.timer[0m - Ge��� of yesterday's process accounting. 397s [[0;32m OK [0m] Started [0;1;39msystemd-tmpfiles-clean.tim���[0my Cleanup of Temporary Directories. 397s [[0;32m OK [0m] Reached target [0;1;39mpaths.target[0m - Path Units. 397s [[0;32m OK [0m] Reached target [0;1;39mtimers.target[0m - Timer Units. 397s [[0;32m OK [0m] Listening on [0;1;39mcloud-init-hotplugd.s���t[0m - cloud-init hotplug hook socket. 397s [[0;32m OK [0m] Listening on [0;1;39mdbus.socket[0m - D-Bus System Message Bus Socket. 397s Starting [0;1;39mlxd-installer.socket[0m - He���er to install lxd snap on demand... 397s [[0;32m OK [0m] Listening on [0;1;39mssh.socket[0m - OpenBSD Secure Shell server socket. 397s [[0;32m OK [0m] Listening on [0;1;39muuidd.socket[0m - UUID daemon activation socket. 397s Starting [0;1;39mcpi.service[0m - Apply Control Program Identification (CPI)... 397s [[0;32m OK [0m] Listening on [0;1;39mlxd-installer.socket[0m ���lper to install lxd snap on demand. 397s [[0;32m OK [0m] Reached target [0;1;39msockets.target[0m - Socket Units. 397s [[0;32m OK [0m] Reached target [0;1;39mbasic.target[0m - Basic System. 397s Starting [0;1;39msystemd-logind.service[0m - User Login Management... 397s Starting [0;1;39mapport.service[0m - automatic crash report generation... 397s Starting [0;1;39mcloud-config.service[0m - Ap���ttings specified in cloud-config... 397s [[0;32m OK [0m] Started [0;1;39mcron.service[0m - Regular background program processing daemon. 397s Starting [0;1;39mdbus.service[0m - D-Bus System Message Bus... 397s [[0;32m OK [0m] Started [0;1;39mdmesg.service[0m - Save initial kernel messages after boot. 397s Starting [0;1;39mdpkg-db-backup.service[0m - ���ily dpkg database backup service... 397s Starting [0;1;39mdumpconf.service[0m - Configure dump on panic for System z... 397s Starting [0;1;39me2scrub_all.service[0m - Onl���tadata Check for All Filesystems... 397s Starting [0;1;39me2scrub_reap.service[0m - Re���ne ext4 Metadata Check Snapshots... 397s Starting [0;1;39mrc-local.service[0m - /etc/rc.local Compatibility... 397s Starting [0;1;39mrng-tools-debian.service[0m - LSB: rng-tools (Debian variant)... 397s Starting [0;1;39mrsyslog.service[0m - System Logging Service... 397s Starting [0;1;39msysstat.service[0m - Resets System Activity Logs... 397s Starting [0;1;39mudisks2.service[0m - Disk Manager... 397s Starting [0;1;39mlogrotate.service[0m - Rotate log files... 397s [[0;32m OK [0m] Started [0;1;39msystemd-logind.service[0m - User Login Management. 397s [[0;32m OK [0m] Started [0;1;39mdbus.service[0m - D-Bus System Message Bus. 397s [[0;32m OK [0m] Finished [0;1;39mcpi.service[0m - Apply Control Program Identification (CPI). 397s [[0;32m OK [0m] Finished [0;1;39mdumpconf.service[0m - Configure dump on panic for System z. 397s [[0;32m OK [0m] Finished [0;1;39me2scrub_all.service[0m - Onl���Metadata Check for All Filesystems. 397s [[0;32m OK [0m] Finished [0;1;39msysstat.service[0m - Resets System Activity Logs. 397s [[0;32m OK [0m] Started [0;1;39mrc-local.service[0m - /etc/rc.local Compatibility. 397s [[0;32m OK [0m] Finished [0;1;39me2scrub_reap.service[0m - Re���line ext4 Metadata Check Snapshots. 397s [[0;32m OK [0m] Started [0;1;39mrng-tools-debian.service[0m - LSB: rng-tools (Debian variant). 397s [[0;32m OK [0m] Finished [0;1;39mlogrotate.service[0m - Rotate log files. 397s [[0;32m OK [0m] Started [0;1;39mudisks2.service[0m - Disk Manager. 397s [[0;32m OK [0m] Finished [0;1;39mapport.service[0m - automatic crash report generation. 397s [[0;32m OK [0m] Finished [0;1;39mdpkg-db-backup.service[0m - Daily dpkg database backup service. 397s [[0;32m OK [0m] Started [0;1;39mrsyslog.service[0m - System Logging Service. 397s [ 27.447290] cloud-init[858]: Cloud-init v. 24.1.3-0ubuntu3.3 running 'modules:config' at Mon, 12 Aug 2024 23:49:55 +0000. Up 27.42 seconds. 397s [ 27.557669] cloud-init[858]: 2024-08-12 23:49:55,397 - log.py[DEPRECATED]: The following config key(s): ['apt_preserve_sources_list'] is deprecated in 22.1 and scheduled to be removed in 27.1. 397s [ 27.557743] cloud-init[858]: 2024-08-12 23:49:55,397 - log.py[DEPRECATED]: Support for combined old and new apt module keys is deprecated in 22.1 and scheduled to be removed in 27.1. 397s [[0;32m OK [0m] Finished [0;1;39mcloud-config.service[0m - Ap���settings specified in cloud-config. 397s Starting [0;1;39msystemd-user-sessions.service[0m - Permit User Sessions... 397s [[0;32m OK [0m] Finished [0;1;39msystemd-user-sessions.service[0m - Permit User Sessions. 397s Starting [0;1;39mplymouth-quit-wait.servic���[0md until boot process finishes up... 397s Starting [0;1;39mplymouth-quit.service[0m - Terminate Plymouth Boot Screen... 397s [[0;32m OK [0m] Finished [0;1;39mplymouth-quit-wait.servic���[0mold until boot process finishes up. 397s [[0;32m OK [0m] Started [0;1;39mserial-getty@ttysclp0.service[0m - Serial Getty on ttysclp0. 397s Starting [0;1;39msetvtrgb.service[0m - Set console scheme... 397s [[0;32m OK [0m] Finished [0;1;39msetvtrgb.service[0m - Set console scheme. 397s [[0;32m OK [0m] Created slice [0;1;39msystem-getty.slice[0m - Slice /system/getty. 397s [[0;32m OK [0m] Started [0;1;39mgetty@tty1.service[0m - Getty on tty1. 397s [[0;32m OK [0m] Reached target [0;1;39mgetty.target[0m - Login Prompts. 397s [[0;32m OK [0m] Finished [0;1;39mplymouth-quit.service[0m - Terminate Plymouth Boot Screen. 397s [[0;32m OK [0m] Reached target [0;1;39mmulti-user.target[0m - Multi-User System. 397s [[0;32m OK [0m] Reached target [0;1;39mgraphical.target[0m - Graphical Interface. 397s Starting [0;1;39mcloud-final.service[0m - Execute cloud user/final scripts... 397s Starting [0;1;39msystemd-update-utmp-runle���[0m- Record Runlevel Change in UTMP... 397s [[0;32m OK [0m] Finished [0;1;39msystemd-update-utmp-runle���e[0m - Record Runlevel Change in UTMP. 397s [ 27.874566] cloud-init[896]: Cloud-init v. 24.1.3-0ubuntu3.3 running 'modules:final' at Mon, 12 Aug 2024 23:49:55 +0000. Up 27.85 seconds. 397s ci-info: ++++++++++++++++++++++++++++++++++++++++++++++++++++++Authorized keys from /home/ubuntu/.ssh/authorized_keys for user ubuntu++++++++++++++++++++++++++++++++++++++++++++++++++++++ 397s ci-info: +---------+-------------------------------------------------------------------------------------------------+---------+----------------------------------------------------------+ 397s ci-info: | Keytype | Fingerprint (sha256) | Options | Comment | 397s ci-info: +---------+-------------------------------------------------------------------------------------------------+---------+----------------------------------------------------------+ 397s ci-info: | ssh-rsa | 61:a6:32:29:64:e5:f7:31:49:10:12:b9:1b:7b:be:31:18:f7:58:f2:6d:fa:f1:29:94:57:74:27:de:8f:50:1c | - | ubuntu@juju-7f2275-prod-proposed-migration-environment-3 | 397s ci-info: +---------+-------------------------------------------------------------------------------------------------+---------+----------------------------------------------------------+ 397s <14>Aug 12 23:49:55 cloud-init: ############################################################# 397s <14>Aug 12 23:49:55 cloud-init: -----BEGIN SSH HOST KEY FINGERPRINTS----- 397s <14>Aug 12 23:49:55 cloud-init: 256 SHA256:HxiQbzhJz19aIqviG5t5nyZEoKky9NpSBw6s1kjZj90 root@auto-syncubuntu-noble-24-04-s390x-server-20240710-disk1-img-ad (ECDSA) 397s <14>Aug 12 23:49:55 cloud-init: 256 SHA256:bBpXOQX7L84e3xs/XYaGw2vxCvAAXACYKl1aO++xn/Y root@auto-syncubuntu-noble-24-04-s390x-server-20240710-disk1-img-ad (ED25519) 397s <14>Aug 12 23:49:55 cloud-init: 3072 SHA256:2i3+0FU7wtTQkXUYTzMHwu8KfsQt3BM/i0WDvA8jluc root@auto-syncubuntu-noble-24-04-s390x-server-20240710-disk1-img-ad (RSA) 397s <14>Aug 12 23:49:55 cloud-init: -----END SSH HOST KEY FINGERPRINTS----- 397s <14>Aug 12 23:49:55 cloud-init: ############################################################# 397s -----BEGIN SSH HOST KEY KEYS----- 397s ecdsa-sha2-nistp256 AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBF8cqsedZfSDoSgGqQENgJv9TojveR7kd9ysD2LAB8bnS7Cd4ZSWFUAa6gA3stkt/ejwIMDe8bFE4iL2Ah2gDMQ= root@auto-syncubuntu-noble-24-04-s390x-server-20240710-disk1-img-ad 397s ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIML3zKgCSQYThux1dCHdtNpbktWwbAcBcUdVv536+p/t root@auto-syncubuntu-noble-24-04-s390x-server-20240710-disk1-img-ad 397s ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQDBOimUyhluCs0mFWFqKL9dYXpP22mcRM+eTh9q8yNOuwn2K0hA3dQwJRl7+JkmSeZ8MD/+cv/xzFyi0DFFENHnmdU/aS3N6iY9i97JuKm7miU2t9ucn9DSsZ1dXjZnzWHI0S2Z+C05NTl+1djM04/w8UEfS7hSuZJ0Z10VWusXmnbpdQn1A6N0GtTe/TSYi1RwLBVAqc+3lQE0iv4NPgewV74Fq+G46QMlFA6o0yeaswS5JNmifPDjqW9rrRcBDchLamuuom4d7Zgxgmfqmzqg0JjrikV5oPzk85xwGwJXEpPeS1rvXBraC1Dby2xsdRmeHpmr+YVBnnOcGgORz+HiKjCjgCgZdJcKTXEiGw7k7PY6ssGgNg6f7c/Zy8lEsHdJNrvJPBDv66BiRIHB69y37a4vtivwp+WA+FPwDfu53JrZLIcm4KJNbp4+AORM0RCYmKz4M+ycVUPcbJ7b2WSHRWGlKueZagMRb3EfpEBBuojjfu3EodN90XzVqsEMxG0= root@auto-syncubuntu-noble-24-04-s390x-server-20240710-disk1-img-ad 397s -----END SSH HOST KEY KEYS----- 397s [ 27.935024] cloud-init[896]: Cloud-init v. 24.1.3-0ubuntu3.3 finished at Mon, 12 Aug 2024 23:49:55 +0000. Datasource DataSourceNone. Up 27.93 seconds 397s [ 27.935321] cloud-init[896]: 2024-08-12 23:49:55,775 - cc_final_message.py[WARNING]: Used fallback datasource 397s [[0;32m OK [0m] Finished [0;1;39mcloud-final.service[0m - Execute cloud user/final scripts. 397s [[0;32m OK [0m] Reached target [0;1;39mcloud-init.target[0m - Cloud-init target. 397s 397s Ubuntu 24.04 LTS auto-syncubuntu-noble-24-04-s390x-server-20240710-disk1-img-ad ttysclp0 397s 397s auto-syncubuntu-noble-24-04-s390x-server-20240710-disk1-img-ad login: 397s --------------------------------------------------- 397s ------- nova show 26cbe89b-3def-43a1-b48a-c6690ffd50d3 (adt-noble-s390x-cminpack-20240812-232516-juju-7f2275-prod-proposed-migration-environment-2-3f25730b-1ba0-474f-8d67-26424908f61f) ------ 397s +-------------------------------------------+---------------------------------------------------------------------------------------------------------------------------------+ 397s | Property | Value | 397s +-------------------------------------------+---------------------------------------------------------------------------------------------------------------------------------+ 397s | OS-DCF:diskConfig | MANUAL | 397s | OS-EXT-AZ:availability_zone | availability-zone-z15 | 397s | OS-EXT-SRV-ATTR:host | ps6-s390x-n3.ps6.canonical.com | 397s | OS-EXT-SRV-ATTR:hostname | adt-noble-s390x-cminpack-20240812-232516-juju-7f2275-prod-propo | 397s | OS-EXT-SRV-ATTR:hypervisor_hostname | ps6-s390x-n3.ps6.canonical.com | 397s | OS-EXT-SRV-ATTR:instance_name | instance-00221333 | 397s | OS-EXT-SRV-ATTR:kernel_id | | 397s | OS-EXT-SRV-ATTR:launch_index | 0 | 397s | OS-EXT-SRV-ATTR:ramdisk_id | | 397s | OS-EXT-SRV-ATTR:reservation_id | r-9k3izgy6 | 397s | OS-EXT-SRV-ATTR:root_device_name | /dev/vda | 397s | OS-EXT-STS:power_state | 1 | 397s | OS-EXT-STS:task_state | - | 397s | OS-EXT-STS:vm_state | active | 397s | OS-SRV-USG:launched_at | 2024-08-12T23:49:27.000000 | 397s | OS-SRV-USG:terminated_at | - | 397s | accessIPv4 | | 397s | accessIPv6 | | 397s | config_drive | | 397s | created | 2024-08-12T23:49:21Z | 397s | description | - | 397s | flavor:disk | 20 | 397s | flavor:ephemeral | 0 | 397s | flavor:extra_specs | {"aggregate_instance_extra_specs:commit": "builder-s390x", "hw_rng:allowed": "True"} | 397s | flavor:original_name | autopkgtest-s390x | 397s | flavor:ram | 4096 | 397s | flavor:swap | 0 | 397s | flavor:vcpus | 2 | 397s | hostId | ec37a553b99930adeabd5d2edd4ae6556020e28fb4940538a7941b52 | 397s | id | 26cbe89b-3def-43a1-b48a-c6690ffd50d3 | 397s | image | adt/ubuntu-noble-s390x-server-20240713.img (920ef23d-06ec-432b-945e-a14b2b94e2bd) | 397s | key_name | testbed-juju-7f2275-prod-proposed-migration-environment-2 | 397s | locked | False | 397s | locked_reason | - | 397s | metadata | {} | 397s | name | adt-noble-s390x-cminpack-20240812-232516-juju-7f2275-prod-proposed-migration-environment-2-3f25730b-1ba0-474f-8d67-26424908f61f | 397s | net_prod-proposed-migration-s390x network | 10.145.232.211 | 397s | os-extended-volumes:volumes_attached | [] | 397s | progress | 0 | 397s | security_groups | autopkgtest-juju-7f2275-prod-proposed-migration-environment-2@bos03-s390x-20.secgroup | 397s | server_groups | [] | 397s | status | ACTIVE | 397s | tags | [] | 397s | tenant_id | ad292334f14047cc96e9c50587dfe285 | 397s | trusted_image_certificates | - | 397s | updated | 2024-08-12T23:49:28Z | 397s | user_id | fda033b8c7ea463db1c3747f74a59d10 | 397s +-------------------------------------------+---------------------------------------------------------------------------------------------------------------------------------+ 397s --------------------------------------------------- 397s nova [E] No UUID given. Instance won't be deleted! 397s : failure: setup script failed with code 1: /home/ubuntu/autopkgtest/ssh-setup/nova open --flavor autopkgtest-s390x --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-2@bos03-s390x-20.secgroup --name adt-noble-s390x-cminpack-20240812-232516-juju-7f2275-prod-proposed-migration-environment-2-3f25730b-1ba0-474f-8d67-26424908f61f --image adt/ubuntu-noble-s390x-server --keyname testbed-juju-7f2275-prod-proposed-migration-environment-2 --net-id=net_prod-proposed-migration-s390x -e TERM=linux -e 'http_proxy=http://squid.internal:3128' -e 'https_proxy=http://squid.internal:3128' -e 'no_proxy=127.0.0.1,127.0.1.1,login.ubuntu.com,localhost,localdomain,novalocal,internal,archive.ubuntu.com,ports.ubuntu.com,security.ubuntu.com,ddebs.ubuntu.com,changelogs.ubuntu.com,keyserver.ubuntu.com,launchpadlibrarian.net,launchpadcontent.net,launchpad.net,10.24.0.0/24,keystone.ps5.canonical.com,objectstorage.prodstack5.canonical.com' --mirror=http://ftpmaster.internal/ubuntu/ 397s autopkgtest [23:55:21]: ERROR: testbed failure: unexpected eof from the testbed