Console Output

00:11:39 [2025-03-28T00:11:39.133Z] + set -xeuo pipefail
00:11:39 [2025-03-28T00:11:39.133Z] ++ umask
00:11:39 [2025-03-28T00:11:39.133Z] + '[' 0022 = 0000 ']'
00:11:39 [2025-03-28T00:11:39.133Z] + cd /home/jenkins/agent/workspace/test-override
00:11:39 [2025-03-28T00:11:39.133Z] + cosa kola run --rerun --allow-rerun-success=tags=needs-internet --build=latest --output-dir=/home/jenkins/agent/workspace/test-override/tmp/kola-Wwbks/kola-x86_64 --on-warn-failure-exit-77 --arch=x86_64 '--tag=!reprovision' --parallel=5
00:11:39 [2025-03-28T00:11:39.133Z] kola -p qemu --build latest run --rerun --allow-rerun-success=tags=needs-internet --on-warn-failure-exit-77 --arch=x86_64 --tag=!reprovision --parallel=5 --output-dir /home/jenkins/agent/workspace/test-override/tmp/kola-Wwbks/kola-x86_64
00:11:39 [2025-03-28T00:11:39.690Z] === RUN   podman.base
00:11:39 [2025-03-28T00:11:39.690Z] === RUN   ext.config.networking.ifname-karg.everyboot-systemd-link-file
00:11:39 [2025-03-28T00:11:39.690Z] === RUN   ext.config.var-mount.simple
00:11:39 [2025-03-28T00:11:39.690Z] === RUN   ext.config.rpm-ostree-countme
00:11:39 [2025-03-28T00:11:39.690Z] === RUN   ext.config.selinux.enforcing
00:11:39 [2025-03-28T00:11:39.690Z] === RUN   ext.config.docker.basic
00:11:39 [2025-03-28T00:11:39.690Z] === RUN   ext.config.networking.no-default-initramfs-net-propagation.bootif
00:11:39 [2025-03-28T00:11:39.690Z] === RUN   basic
00:11:39 [2025-03-28T00:11:39.690Z] === RUN   ext.config.networking.nameserver
00:11:39 [2025-03-28T00:11:39.690Z] === RUN   ext.config.var-mount.scsi-id
00:11:39 [2025-03-28T00:11:39.690Z] === RUN   ext.config.boot.grub2-install
00:11:39 [2025-03-28T00:11:39.690Z] === RUN   rootfs.uuid
00:11:39 [2025-03-28T00:11:39.690Z] === RUN   coreos.ignition.symlink
00:11:39 [2025-03-28T00:11:39.690Z] === RUN   coreos.selinux.boolean
00:11:39 [2025-03-28T00:11:39.690Z] === RUN   non-exclusive-test-bucket-0
00:11:39 [2025-03-28T00:11:39.690Z] === RUN   ext.config.networking.hostname.fallback-hostname
00:11:39 [2025-03-28T00:11:39.690Z] === RUN   ext.config.ignition.resource.remote
00:11:39 [2025-03-28T00:11:39.690Z] === RUN   ext.config.ntp.chrony.dhcp-propagation
00:11:39 [2025-03-28T00:11:39.690Z] === RUN   ext.config.networking.force-persist-ip
00:11:39 [2025-03-28T00:11:39.690Z] === RUN   ext.config.disks.lvmdevices
00:11:39 [2025-03-28T00:11:39.690Z] === RUN   coreos.ignition.v2.users
00:11:39 [2025-03-28T00:11:39.690Z] === RUN   ostree.hotfix
00:11:39 [2025-03-28T00:11:39.690Z] === RUN   ext.config.networking.rd-net-timeout-carrier
00:11:39 [2025-03-28T00:11:39.690Z] === RUN   coreos.ignition.mount.partitions
00:11:39 [2025-03-28T00:11:39.690Z] === RUN   multipath.partition
00:11:39 [2025-03-28T00:11:39.690Z] === RUN   ext.config.ignition.delete-config
00:11:39 [2025-03-28T00:11:39.690Z] === RUN   ostree.unlock
00:11:39 [2025-03-28T00:11:39.690Z] === RUN   ext.config.ignition.systemd-unmasking
00:11:39 [2025-03-28T00:11:39.690Z] === RUN   ext.config.extensions.package
00:11:39 [2025-03-28T00:11:39.690Z] === RUN   ext.config.containers.quadlet
00:11:39 [2025-03-28T00:11:39.690Z] === RUN   coreos.misc.disk.varlibcontainers
00:11:39 [2025-03-28T00:11:39.690Z] === RUN   ext.config.ignition.kargs
00:11:39 [2025-03-28T00:11:39.690Z] === RUN   ext.config.systemd.condition-needs-update
00:11:39 [2025-03-28T00:11:39.690Z] === RUN   kdump.crash.ssh
00:11:39 [2025-03-28T00:11:39.690Z] === RUN   rpmostree.status
00:11:39 [2025-03-28T00:11:39.690Z] === RUN   multipath.day2
00:11:39 [2025-03-28T00:11:39.690Z] === RUN   coreos.ignition.failure
00:11:39 [2025-03-28T00:11:39.690Z] === RUN   ext.config.ntp.timesyncd.dhcp-propagation
00:11:39 [2025-03-28T00:11:39.690Z] === RUN   coreos.ignition.once
00:11:39 [2025-03-28T00:11:39.690Z] === RUN   coreos.auth.verify
00:11:39 [2025-03-28T00:11:39.690Z] === RUN   basic.uefi
00:11:39 [2025-03-28T00:11:39.690Z] === RUN   ext.config.systemd.network-online
00:11:39 [2025-03-28T00:11:39.690Z] === RUN   ext.config.reboot
00:11:39 [2025-03-28T00:11:39.690Z] === RUN   podman.workflow
00:11:39 [2025-03-28T00:11:39.690Z] === RUN   ext.config.networking.ifname-karg.udev-rule-firstboot-propagation
00:11:39 [2025-03-28T00:11:39.690Z] === RUN   multipath.day1
00:11:39 [2025-03-28T00:11:39.690Z] === RUN   basic.nvme
00:11:39 [2025-03-28T00:11:39.690Z] === RUN   ext.config.podman.rootless-systemd
00:11:39 [2025-03-28T00:11:39.690Z] === RUN   coreos.ignition.mount.disks
00:11:39 [2025-03-28T00:11:39.690Z] === RUN   ext.config.networking.mtu-on-bond-kargs
00:11:39 [2025-03-28T00:11:39.690Z] === RUN   coreos.ignition.groups
00:11:39 [2025-03-28T00:11:39.690Z] === RUN   ext.config.clhm.ignition-warnings
00:11:39 [2025-03-28T00:11:39.690Z] === RUN   ext.config.swap.zram-generator
00:11:39 [2025-03-28T00:11:39.690Z] === RUN   ext.config.networking.no-persist-ip
00:11:39 [2025-03-28T00:11:39.690Z] === RUN   ext.config.ignition.remote
00:11:39 [2025-03-28T00:11:39.690Z] === RUN   fcos.network.listeners
00:11:39 [2025-03-28T00:11:39.690Z] === RUN   rhcos.selinux.boolean.persist
00:11:39 [2025-03-28T00:11:39.690Z] === RUN   podman.network-single
00:11:39 [2025-03-28T00:11:39.690Z] === RUN   ext.config.networking.mtu-on-bond-ignition
00:11:39 [2025-03-28T00:11:39.690Z] === RUN   ext.config.ignition.stable-boot
00:11:39 [2025-03-28T00:11:39.690Z] === RUN   rpmostree.install-uninstall
00:11:39 [2025-03-28T00:11:39.690Z] === RUN   ext.config.boot.bootupd-validate
00:11:39 [2025-03-28T00:11:39.690Z] === RUN   coreos.ignition.instantiated.enable-unit
00:11:39 [2025-03-28T00:11:39.690Z] === RUN   ext.config.networking.nmstate.state
00:11:39 [2025-03-28T00:11:39.690Z] === RUN   basic.uefi-secure
00:11:39 [2025-03-28T00:11:39.690Z] === RUN   ext.config.networking.bridge-static-via-kargs
00:11:39 [2025-03-28T00:11:39.690Z] === RUN   ext.config.networking.team-dhcp-via-ignition
00:11:39 [2025-03-28T00:11:39.690Z] === RUN   coreos.unique.boot.ignition.failure
00:11:39 [2025-03-28T00:11:39.690Z] === RUN   ext.config.ignition.systemd-enable-units
00:11:39 [2025-03-28T00:11:39.690Z] === RUN   ext.config.gshadow
00:11:39 [2025-03-28T00:11:39.690Z] === RUN   ext.config.ssh.custom-host-key-permissions
00:11:39 [2025-03-28T00:11:39.690Z] === RUN   ext.config.butane.grub-users
00:11:39 [2025-03-28T00:11:39.690Z] === RUN   rpmostree.upgrade-rollback
00:11:39 [2025-03-28T00:11:39.690Z] === RUN   coreos.ignition.sethostname
00:11:39 [2025-03-28T00:11:39.690Z] === RUN   fcos.users.shells
00:11:39 [2025-03-28T00:11:39.690Z] === RUN   ext.config.firewall.iptables-legacy
00:11:39 [2025-03-28T00:11:39.690Z] === RUN   ext.config.networking.kargs-rd-net
00:11:39 [2025-03-28T00:11:39.690Z] === RUN   ext.config.var-mount.luks
00:11:39 [2025-03-28T00:11:39.690Z] === RUN   ext.config.kdump.crash
00:11:39 [2025-03-28T00:11:39.690Z] === RUN   ext.config.ignition.systemd-disable
00:11:39 [2025-03-28T00:11:39.690Z] === RUN   ext.config.networking.prefer-ignition-networking
00:11:39 [2025-03-28T00:11:39.690Z] === RUN   fcos.filesystem
00:11:39 [2025-03-28T00:11:39.690Z] === RUN   ext.config.toolbox
00:11:39 [2025-03-28T00:11:39.690Z] === RUN   coreos.unique.boot.failure
00:11:39 [2025-03-28T00:11:39.690Z] === RUN   ext.config.networking.nmstate.policy
00:11:39 [2025-03-28T00:11:39.690Z] === RUN   ostree.remote
00:12:18 [2025-03-28T00:12:18.348Z] === RUN   podman.base/info
00:12:18 [2025-03-28T00:12:18.348Z] === RUN   podman.base/resources
00:12:21 [2025-03-28T00:12:21.609Z] --- PASS: podman.base (41.63s)
00:12:21 [2025-03-28T00:12:21.609Z]     --- PASS: podman.base/info (0.44s)
00:12:21 [2025-03-28T00:12:21.609Z]     --- PASS: podman.base/resources (4.98s)
00:12:24 [2025-03-28T00:12:24.856Z] --- PASS: ext.config.networking.team-dhcp-via-ignition (45.11s)
00:12:39 [2025-03-28T00:12:39.688Z] --- PASS: ext.config.networking.rd-net-timeout-carrier (58.39s)
00:12:42 [2025-03-28T00:12:42.947Z] --- PASS: ext.config.networking.ifname-karg.udev-rule-firstboot-propagation (63.09s)
00:12:42 [2025-03-28T00:12:42.947Z] mke2fs 1.47.1 (20-May-2024)
00:12:57 [2025-03-28T00:12:57.770Z] --- PASS: coreos.unique.boot.failure (13.97s)
00:12:57 [2025-03-28T00:12:57.770Z] === RUN   ostree.remote/add
00:12:57 [2025-03-28T00:12:57.770Z] === RUN   ostree.remote/list
00:12:57 [2025-03-28T00:12:57.770Z] === RUN   ostree.remote/show-url
00:12:58 [2025-03-28T00:12:58.023Z] === RUN   ostree.remote/refs
00:12:58 [2025-03-28T00:12:58.276Z] === RUN   ostree.remote/summary
00:12:58 [2025-03-28T00:12:58.529Z] === RUN   ostree.remote/delete
00:13:02 [2025-03-28T00:13:02.693Z] --- PASS: ostree.remote (37.58s)
00:13:02 [2025-03-28T00:13:02.693Z]     --- PASS: ostree.remote/add (0.28s)
00:13:02 [2025-03-28T00:13:02.693Z]     --- PASS: ostree.remote/list (0.12s)
00:13:02 [2025-03-28T00:13:02.693Z]     --- PASS: ostree.remote/show-url (0.22s)
00:13:02 [2025-03-28T00:13:02.693Z]     --- PASS: ostree.remote/refs (0.15s)
00:13:02 [2025-03-28T00:13:02.693Z]     --- PASS: ostree.remote/summary (0.33s)
00:13:02 [2025-03-28T00:13:02.693Z]     --- PASS: ostree.remote/delete (0.49s)
00:13:41 [2025-03-28T00:13:41.312Z] === RUN   fcos.filesystem/writablefiles
00:13:41 [2025-03-28T00:13:41.312Z] === RUN   fcos.filesystem/writabledirs
00:13:41 [2025-03-28T00:13:41.312Z] === RUN   fcos.filesystem/stickydirs
00:13:41 [2025-03-28T00:13:41.312Z] === RUN   fcos.filesystem/denylist
00:13:41 [2025-03-28T00:13:41.312Z] --- PASS: fcos.filesystem (38.31s)
00:13:41 [2025-03-28T00:13:41.312Z]     --- PASS: fcos.filesystem/writablefiles (1.11s)
00:13:41 [2025-03-28T00:13:41.312Z]     --- PASS: fcos.filesystem/writabledirs (0.26s)
00:13:41 [2025-03-28T00:13:41.312Z]     --- PASS: fcos.filesystem/stickydirs (0.30s)
00:13:41 [2025-03-28T00:13:41.312Z]     --- PASS: fcos.filesystem/denylist (0.37s)
00:13:56 [2025-03-28T00:13:56.136Z] --- PASS: ext.config.var-mount.luks (93.76s)
00:14:01 [2025-03-28T00:14:01.375Z] --- PASS: kdump.crash.ssh (141.21s)
00:14:01 [2025-03-28T00:14:01.375Z]         cluster.go:151: Running as unit: run-p7214-i7215.service; invocation ID: fbb3681439b34a019d7f06ed0ee17916
00:14:19 [2025-03-28T00:14:19.388Z] systemctl status kola-runext.service:
00:14:19 [2025-03-28T00:14:19.388Z] �� kola-runext.service
00:14:19 [2025-03-28T00:14:19.388Z]      Loaded: loaded (/etc/systemd/system/kola-runext.service; static)
00:14:19 [2025-03-28T00:14:19.388Z]     Drop-In: /usr/lib/systemd/system/service.d
00:14:19 [2025-03-28T00:14:19.388Z]              ������10-timeout-abort.conf, 50-keep-warm.conf
00:14:19 [2025-03-28T00:14:19.388Z]      Active: failed (Result: exit-code) since Fri 2025-03-28 00:14:17 UTC; 293ms ago
00:14:19 [2025-03-28T00:14:19.388Z]    Duration: 45.942s
00:14:19 [2025-03-28T00:14:19.388Z]  Invocation: b29de91d26704311bf6b8e191b8d9076
00:14:19 [2025-03-28T00:14:19.388Z]     Process: 2288 ExecStart=/usr/local/bin/kola-runext-test.sh (code=exited, status=1/FAILURE)
00:14:19 [2025-03-28T00:14:19.388Z]    Main PID: 2288 (code=exited, status=1/FAILURE)
00:14:19 [2025-03-28T00:14:19.388Z]    Mem peak: 2.5M
00:14:19 [2025-03-28T00:14:19.388Z]         CPU: 61ms
00:14:19 [2025-03-28T00:14:19.388Z] 
00:14:19 [2025-03-28T00:14:19.388Z] Mar 28 00:13:31 qemu0 systemd[1]: Started kola-runext.service.
00:14:19 [2025-03-28T00:14:19.388Z] Mar 28 00:13:31 qemu0 kola-runext-test.sh[2288]: + . /var/opt/kola/extdata/commonlib.sh
00:14:19 [2025-03-28T00:14:19.388Z] Mar 28 00:13:31 qemu0 kola-runext-test.sh[2288]: ++ IFS=' '
00:14:19 [2025-03-28T00:14:19.388Z] Mar 28 00:13:31 qemu0 kola-runext-test.sh[2288]: ++ read -r -a cmdline
00:14:19 [2025-03-28T00:14:19.388Z] Mar 28 00:13:31 qemu0 kola-runext-test.sh[2289]: ++ seq 1 5
00:14:19 [2025-03-28T00:14:19.388Z] Mar 28 00:13:31 qemu0 kola-runext-test.sh[2288]: + for i in $(seq 1 5)
00:14:19 [2025-03-28T00:14:19.388Z] Mar 28 00:13:31 qemu0 kola-runext-test.sh[2288]: + machinectl shell core@ /bin/toolbox create --assumeyes
00:14:19 [2025-03-28T00:14:19.388Z] Mar 28 00:13:31 qemu0 machinectl[2290]: Connected to the local host. Press ^] three times within 1s to exit session.
00:14:19 [2025-03-28T00:14:19.388Z] Mar 28 00:14:16 qemu0 machinectl[2290]: Connection to the local host terminated.
00:14:19 [2025-03-28T00:14:19.388Z] Mar 28 00:14:16 qemu0 kola-runext-test.sh[2644]: ++ machinectl shell core@ /bin/toolbox list --containers
00:14:19 [2025-03-28T00:14:19.388Z] Mar 28 00:14:16 qemu0 kola-runext-test.sh[2645]: ++ grep --count fedora-toolbox-
00:14:19 [2025-03-28T00:14:19.388Z] Mar 28 00:14:16 qemu0 machinectl[2644]: Connected to the local host. Press ^] three times within 1s to exit session.
00:14:19 [2025-03-28T00:14:19.388Z] Mar 28 00:14:16 qemu0 machinectl[2644]: Connection to the local host terminated.
00:14:19 [2025-03-28T00:14:19.388Z] Mar 28 00:14:16 qemu0 kola-runext-test.sh[2288]: + [[ 1 -ne 1 ]]
00:14:19 [2025-03-28T00:14:19.388Z] Mar 28 00:14:16 qemu0 kola-runext-test.sh[2288]: + break
00:14:19 [2025-03-28T00:14:19.388Z] Mar 28 00:14:16 qemu0 kola-runext-test.sh[2669]: ++ machinectl shell core@ /bin/toolbox list --containers
00:14:19 [2025-03-28T00:14:19.388Z] Mar 28 00:14:16 qemu0 kola-runext-test.sh[2670]: ++ grep --count fedora-toolbox-
00:14:19 [2025-03-28T00:14:19.388Z] Mar 28 00:14:16 qemu0 machinectl[2669]: Connected to the local host. Press ^] three times within 1s to exit session.
00:14:19 [2025-03-28T00:14:19.388Z] Mar 28 00:14:16 qemu0 machinectl[2669]: Connection to the local host terminated.
00:14:19 [2025-03-28T00:14:19.388Z] Mar 28 00:14:16 qemu0 kola-runext-test.sh[2288]: + [[ 1 -ne 1 ]]
00:14:19 [2025-03-28T00:14:19.388Z] Mar 28 00:14:16 qemu0 kola-runext-test.sh[2288]: + ok toolbox create
00:14:19 [2025-03-28T00:14:19.388Z] Mar 28 00:14:16 qemu0 kola-runext-test.sh[2288]: + echo ok toolbox create
00:14:19 [2025-03-28T00:14:19.388Z] Mar 28 00:14:16 qemu0 kola-runext-test.sh[2288]: ok toolbox create
00:14:19 [2025-03-28T00:14:19.388Z] Mar 28 00:14:16 qemu0 kola-runext-test.sh[2288]: + machinectl shell core@ /bin/toolbox run touch ok_toolbox
00:14:19 [2025-03-28T00:14:19.388Z] Mar 28 00:14:16 qemu0 machinectl[2692]: Connected to the local host. Press ^] three times within 1s to exit session.
00:14:19 [2025-03-28T00:14:19.388Z] Mar 28 00:14:17 qemu0 kola-runext-test.sh[2692]: Error: failed to invoke command touch in container fedora-toolbox-43
00:14:19 [2025-03-28T00:14:19.388Z] Mar 28 00:14:17 qemu0 machinectl[2692]: Connection to the local host terminated.
00:14:19 [2025-03-28T00:14:19.388Z] Mar 28 00:14:17 qemu0 kola-runext-test.sh[2288]: + [[ ! -f /home/core/ok_toolbox ]]
00:14:19 [2025-03-28T00:14:19.388Z] Mar 28 00:14:17 qemu0 kola-runext-test.sh[2288]: + fatal 'Could not run a simple command inside a toolbox'
00:14:19 [2025-03-28T00:14:19.388Z] Mar 28 00:14:17 qemu0 kola-runext-test.sh[2288]: + echo 'Could not run a simple command inside a toolbox'
00:14:19 [2025-03-28T00:14:19.388Z] Mar 28 00:14:17 qemu0 kola-runext-test.sh[2288]: Could not run a simple command inside a toolbox
00:14:19 [2025-03-28T00:14:19.388Z] Mar 28 00:14:17 qemu0 kola-runext-test.sh[2288]: + exit 1
00:14:19 [2025-03-28T00:14:19.388Z] Mar 28 00:14:17 qemu0 systemd[1]: kola-runext.service: Main process exited, code=exited, status=1/FAILURE
00:14:19 [2025-03-28T00:14:19.388Z] Mar 28 00:14:17 qemu0 systemd[1]: kola-runext.service: Failed with result 'exit-code'.
00:14:22 [2025-03-28T00:14:22.647Z] --- FAIL: ext.config.toolbox (85.49s)
00:14:22 [2025-03-28T00:14:22.647Z]         cluster.go:151: Error: Unit kola-runext.service exited with code 1
00:14:22 [2025-03-28T00:14:22.647Z]         cluster.go:151: 2025-03-28T00:14:17Z cli: Unit kola-runext.service exited with code 1
00:14:22 [2025-03-28T00:14:22.647Z]         harness.go:1260: kolet failed: : kolet run-test-unit failed: Process exited with status 1
00:14:23 [2025-03-28T00:14:23.202Z] --- PASS: ext.config.networking.prefer-ignition-networking (42.66s)
00:14:35 [2025-03-28T00:14:35.352Z] --- PASS: ext.config.ignition.systemd-disable (38.80s)
00:14:35 [2025-03-28T00:14:35.352Z] --- PASS: ext.config.networking.nmstate.policy (116.81s)
00:15:07 [2025-03-28T00:15:07.357Z] === RUN   rootfs.uuid/RandomUUID
00:15:11 [2025-03-28T00:15:11.517Z] --- PASS: rootfs.uuid (37.18s)
00:15:11 [2025-03-28T00:15:11.517Z]     --- PASS: rootfs.uuid/RandomUUID (0.16s)
00:15:12 [2025-03-28T00:15:12.870Z] --- PASS: ext.config.ignition.kargs (37.79s)
00:15:30 [2025-03-28T00:15:30.885Z] --- PASS: ext.config.extensions.package (66.53s)
00:15:45 [2025-03-28T00:15:45.702Z] === RUN   ostree.hotfix/unlock
00:15:57 [2025-03-28T00:15:57.863Z] --- PASS: ext.config.systemd.condition-needs-update (93.18s)
00:15:59 [2025-03-28T00:15:59.740Z] --- PASS: ext.config.kdump.crash (118.77s)
00:16:00 [2025-03-28T00:16:00.297Z] === RUN   ostree.hotfix/install
00:16:01 [2025-03-28T00:16:01.217Z] === RUN   ostree.hotfix/uninstall
00:16:01 [2025-03-28T00:16:01.797Z] === RUN   ostree.hotfix/persist
00:16:08 [2025-03-28T00:16:08.311Z] --- PASS: ext.config.containers.quadlet (38.91s)
00:16:08 [2025-03-28T00:16:08.868Z] --- PASS: coreos.misc.disk.varlibcontainers (57.75s)
00:16:08 [2025-03-28T00:16:08.868Z]         cluster.go:151: Created symlink '/etc/systemd/system/var-lib-containers.mount.wants/systemd-mkfs@dev-disk-by-id-virtio-disk1.service' ��� '/etc/systemd/system/systemd-mkfs@dev-disk-by-id-virtio-disk1.service'.
00:16:08 [2025-03-28T00:16:08.868Z]         cluster.go:151: Created symlink '/etc/systemd/system/local-fs.target.wants/var-lib-containers.mount' ��� '/etc/systemd/system/var-lib-containers.mount'.
00:16:23 [2025-03-28T00:16:23.685Z] === RUN   ostree.hotfix/rollback
00:16:33 [2025-03-28T00:16:33.607Z] --- PASS: fcos.network.listeners (24.66s)
00:16:33 [2025-03-28T00:16:33.607Z] --- PASS: coreos.ignition.v2.users (36.59s)
00:16:45 [2025-03-28T00:16:45.754Z] --- PASS: ostree.hotfix (92.34s)
00:16:45 [2025-03-28T00:16:45.754Z]     --- PASS: ostree.hotfix/unlock (14.49s)
00:16:45 [2025-03-28T00:16:45.754Z]     --- PASS: ostree.hotfix/install (0.73s)
00:16:45 [2025-03-28T00:16:45.754Z]     --- PASS: ostree.hotfix/uninstall (0.45s)
00:16:45 [2025-03-28T00:16:45.754Z]     --- PASS: ostree.hotfix/persist (19.22s)
00:16:45 [2025-03-28T00:16:45.754Z]     --- PASS: ostree.hotfix/rollback (20.02s)
00:16:50 [2025-03-28T00:16:50.989Z] --- PASS: ext.config.networking.force-persist-ip (41.53s)
00:16:57 [2025-03-28T00:16:57.496Z] --- PASS: ext.config.disks.lvmdevices (56.87s)
00:17:15 [2025-03-28T00:17:15.512Z] --- PASS: ext.config.networking.bridge-static-via-kargs (41.52s)
00:17:19 [2025-03-28T00:17:19.198Z] === RUN   basic.uefi-secure/FCOSGrowpart
00:17:19 [2025-03-28T00:17:19.198Z] === RUN   basic.uefi-secure/PortSSH
00:17:19 [2025-03-28T00:17:19.451Z] === RUN   basic.uefi-secure/DbusPerms
00:17:19 [2025-03-28T00:17:19.704Z] === RUN   basic.uefi-secure/ServicesActive
00:17:19 [2025-03-28T00:17:19.957Z] === RUN   basic.uefi-secure/ReadOnly
00:17:19 [2025-03-28T00:17:19.957Z] === RUN   basic.uefi-secure/Useradd
00:17:20 [2025-03-28T00:17:20.209Z] === RUN   basic.uefi-secure/MachineID
00:17:24 [2025-03-28T00:17:24.352Z] --- PASS: basic.uefi-secure (38.75s)
00:17:24 [2025-03-28T00:17:24.352Z]     --- PASS: basic.uefi-secure/FCOSGrowpart (0.15s)
00:17:24 [2025-03-28T00:17:24.352Z]     --- PASS: basic.uefi-secure/PortSSH (0.12s)
00:17:24 [2025-03-28T00:17:24.352Z]     --- PASS: basic.uefi-secure/DbusPerms (0.35s)
00:17:24 [2025-03-28T00:17:24.352Z]     --- PASS: basic.uefi-secure/ServicesActive (0.13s)
00:17:24 [2025-03-28T00:17:24.352Z]     --- PASS: basic.uefi-secure/ReadOnly (0.12s)
00:17:24 [2025-03-28T00:17:24.352Z]     --- PASS: basic.uefi-secure/Useradd (0.19s)
00:17:24 [2025-03-28T00:17:24.352Z]     --- PASS: basic.uefi-secure/MachineID (0.11s)
00:17:29 [2025-03-28T00:17:29.593Z] --- PASS: ext.config.ignition.resource.remote (38.52s)
00:17:44 [2025-03-28T00:17:44.414Z] --- PASS: ext.config.ntp.chrony.dhcp-propagation (70.21s)
00:17:52 [2025-03-28T00:17:52.489Z] --- PASS: ext.config.networking.nmstate.state (55.00s)
00:17:52 [2025-03-28T00:17:52.489Z] --- PASS: ext.config.networking.hostname.fallback-hostname (38.71s)
00:17:53 [2025-03-28T00:17:53.842Z] --- PASS: coreos.ignition.groups (24.89s)
00:18:01 [2025-03-28T00:18:01.911Z] --- PASS: coreos.ignition.instantiated.enable-unit (37.09s)
00:18:23 [2025-03-28T00:18:23.778Z] --- PASS: ext.config.boot.bootupd-validate (39.63s)
00:18:26 [2025-03-28T00:18:26.284Z] === RUN   rpmostree.install-uninstall/install
00:18:44 [2025-03-28T00:18:44.302Z] === RUN   non-exclusive-test-bucket-0/ext.config.files.fcos_users
00:18:44 [2025-03-28T00:18:44.302Z] --- PASS: ext.config.ignition.remote (48.01s)
00:18:44 [2025-03-28T00:18:44.302Z] === RUN   non-exclusive-test-bucket-0/ext.config.files.sudoers
00:18:44 [2025-03-28T00:18:44.302Z] === RUN   non-exclusive-test-bucket-0/ext.config.files.initrd.compression
00:18:45 [2025-03-28T00:18:45.666Z] === RUN   non-exclusive-test-bucket-0/ext.config.firewall.iptables
00:18:47 [2025-03-28T00:18:47.035Z] === RUN   non-exclusive-test-bucket-0/ext.config.kdump.service
00:18:48 [2025-03-28T00:18:48.910Z] === RUN   non-exclusive-test-bucket-0/ext.config.selinux.file-context-policy-match
00:18:53 [2025-03-28T00:18:53.083Z] === RUN   non-exclusive-test-bucket-0/ext.config.networking.network-online-service
00:18:54 [2025-03-28T00:18:54.000Z] === RUN   non-exclusive-test-bucket-0/ext.config.selinux.unmodified-policy
00:18:55 [2025-03-28T00:18:55.873Z] --- PASS: coreos.selinux.boolean (54.74s)
00:18:55 [2025-03-28T00:18:55.873Z] === RUN   non-exclusive-test-bucket-0/ext.config.files.fcos_groups
00:18:57 [2025-03-28T00:18:57.757Z] === RUN   non-exclusive-test-bucket-0/ext.config.networking.tls
00:19:01 [2025-03-28T00:19:01.912Z] --- PASS: ext.config.ignition.stable-boot (39.00s)
00:19:02 [2025-03-28T00:19:02.165Z] === RUN   non-exclusive-test-bucket-0/ext.config.selinux.podman-tmpfs-context
00:19:10 [2025-03-28T00:19:10.241Z] === RUN   non-exclusive-test-bucket-0/ext.config.ignition.journald-log
00:19:10 [2025-03-28T00:19:10.796Z] === RUN   non-exclusive-test-bucket-0/ext.config.files.validate-symlinks
00:19:13 [2025-03-28T00:19:13.301Z] === RUN   rpmostree.install-uninstall/uninstall
00:19:18 [2025-03-28T00:19:18.534Z] --- PASS: coreos.ignition.symlink (36.11s)
00:19:23 [2025-03-28T00:19:23.754Z] === RUN   non-exclusive-test-bucket-0/ext.config.files.system-generators
00:19:25 [2025-03-28T00:19:25.647Z] === RUN   non-exclusive-test-bucket-0/ext.config.disks.root-boot-ro
00:19:27 [2025-03-28T00:19:27.522Z] === RUN   non-exclusive-test-bucket-0/ext.config.containers.cgroups-v2
00:19:28 [2025-03-28T00:19:28.874Z] === RUN   non-exclusive-test-bucket-0/ext.config.files.rpmdb-sqlite
00:19:29 [2025-03-28T00:19:29.843Z] === RUN   non-exclusive-test-bucket-0/ext.config.rpm-ostree.container-deps
00:19:31 [2025-03-28T00:19:31.727Z] === RUN   non-exclusive-test-bucket-0/ext.config.files.license
00:19:31 [2025-03-28T00:19:31.980Z] === RUN   non-exclusive-test-bucket-0/ext.config.files.aleph-version
00:19:32 [2025-03-28T00:19:32.898Z] === RUN   non-exclusive-test-bucket-0/ext.config.files.setgid
00:19:34 [2025-03-28T00:19:34.771Z] === RUN   non-exclusive-test-bucket-0/ext.config.files.etc-permissions
00:19:36 [2025-03-28T00:19:36.141Z] === RUN   non-exclusive-test-bucket-0/ext.config.disks.tmpfs
00:19:36 [2025-03-28T00:19:36.697Z] --- PASS: ext.config.networking.no-persist-ip (41.19s)
00:19:36 [2025-03-28T00:19:36.950Z] === RUN   non-exclusive-test-bucket-0/ext.config.files.file-directory-permissions
00:19:38 [2025-03-28T00:19:38.824Z] === RUN   non-exclusive-test-bucket-0/ext.config.kubernetes.kube-watch
00:19:41 [2025-03-28T00:19:41.344Z] === RUN   non-exclusive-test-bucket-0/ext.config.disks.partition-scheme
00:19:43 [2025-03-28T00:19:43.216Z] === RUN   non-exclusive-test-bucket-0/ext.config.boot.bootupd
00:19:43 [2025-03-28T00:19:43.469Z] --- PASS: ext.config.networking.mtu-on-bond-ignition (41.48s)
00:19:44 [2025-03-28T00:19:44.821Z] === RUN   non-exclusive-test-bucket-0/ext.config.files.initrd.executables
00:19:48 [2025-03-28T00:19:48.977Z] === RUN   non-exclusive-test-bucket-0/ext.config.systemd.no-systemd-firstboot
00:19:49 [2025-03-28T00:19:49.896Z] === RUN   non-exclusive-test-bucket-0/ext.config.clhm.network-device-info
00:19:50 [2025-03-28T00:19:50.452Z] === RUN   non-exclusive-test-bucket-0/ext.config.logging.printk
00:19:50 [2025-03-28T00:19:50.714Z] === RUN   rpmostree.upgrade-rollback/upgrade
00:19:52 [2025-03-28T00:19:52.085Z] === RUN   non-exclusive-test-bucket-0/ext.config.composefs.enabled
00:19:53 [2025-03-28T00:19:53.960Z] === RUN   non-exclusive-test-bucket-0/ext.config.ntp.chrony.coreos-platform-chrony-config
00:19:55 [2025-03-28T00:19:55.836Z] === RUN   non-exclusive-test-bucket-0/ext.config.files.logrotate-service
00:19:56 [2025-03-28T00:19:56.392Z] --- PASS: rpmostree.install-uninstall (123.73s)
00:19:56 [2025-03-28T00:19:56.392Z]     --- PASS: rpmostree.install-uninstall/install (47.35s)
00:19:56 [2025-03-28T00:19:56.392Z]     --- PASS: rpmostree.install-uninstall/uninstall (39.51s)
00:19:57 [2025-03-28T00:19:57.328Z] === RUN   non-exclusive-test-bucket-0/ext.config.networking.no-default-initramfs-net-propagation.default
00:19:58 [2025-03-28T00:19:58.693Z] === RUN   non-exclusive-test-bucket-0/ext.config.networking.nm-start
00:20:00 [2025-03-28T00:20:00.570Z] === RUN   non-exclusive-test-bucket-0/ext.config.selinux.stub-resolve-context
00:20:01 [2025-03-28T00:20:01.126Z] === RUN   non-exclusive-test-bucket-0/ext.config.files.amd-ucode-firmware
00:20:01 [2025-03-28T00:20:01.683Z] === RUN   non-exclusive-test-bucket-0/ext.config.podman.rootless-pasta-networking
00:20:08 [2025-03-28T00:20:08.216Z] === RUN   non-exclusive-test-bucket-0/ext.config.networking.default-network-behavior-change
00:20:09 [2025-03-28T00:20:09.137Z] === RUN   non-exclusive-test-bucket-0/ext.config.files.fwupd-refresh-timer
00:20:10 [2025-03-28T00:20:10.508Z] === RUN   non-exclusive-test-bucket-0/ext.config.files.initrd.expected-contents
00:20:14 [2025-03-28T00:20:14.657Z] === RUN   non-exclusive-test-bucket-0/ext.config.files.root-immutable-bit
00:20:14 [2025-03-28T00:20:14.909Z] === RUN   non-exclusive-test-bucket-0/ext.config.files.setuid
00:20:16 [2025-03-28T00:20:16.781Z] === RUN   non-exclusive-test-bucket-0/ext.config.swap.zram-default
00:20:17 [2025-03-28T00:20:17.353Z] === RUN   non-exclusive-test-bucket-0/ext.config.kubernetes.systemd-env-read
00:20:19 [2025-03-28T00:20:19.228Z] === RUN   non-exclusive-test-bucket-0/ext.config.networking.resolv.systemd-resolved
00:20:20 [2025-03-28T00:20:20.591Z] === RUN   non-exclusive-test-bucket-0/ext.config.files.remove-manifest-files
00:20:21 [2025-03-28T00:20:21.145Z] === RUN   non-exclusive-test-bucket-0/ext.config.podman.dns
00:20:23 [2025-03-28T00:20:23.033Z] === RUN   non-exclusive-test-bucket-0/ext.config.files.yum-repo-dir
00:20:23 [2025-03-28T00:20:23.588Z] === RUN   non-exclusive-test-bucket-0/ext.config.security.coreos-update-ca-trust
00:20:24 [2025-03-28T00:20:24.947Z] --- PASS: ext.config.networking.kargs-rd-net (41.36s)
00:20:25 [2025-03-28T00:20:25.199Z] === RUN   non-exclusive-test-bucket-0/ext.config.files.console-config
00:20:27 [2025-03-28T00:20:27.096Z] === RUN   non-exclusive-test-bucket-0/ext.config.security.passwd
00:20:28 [2025-03-28T00:20:28.030Z] === RUN   rpmostree.upgrade-rollback/rollback
00:20:29 [2025-03-28T00:20:29.390Z] === RUN   non-exclusive-test-bucket-0/ext.config.selinux.usrlocal-context
00:20:31 [2025-03-28T00:20:31.263Z] === RUN   non-exclusive-test-bucket-0/ext.config.networking.nm-ifcfg-rh-plugin
00:20:32 [2025-03-28T00:20:32.630Z] === RUN   non-exclusive-test-bucket-0/ext.config.disks.systemd-repart-service
00:20:33 [2025-03-28T00:20:33.988Z] --- PASS: ext.config.swap.zram-generator (37.86s)
00:20:34 [2025-03-28T00:20:34.240Z] === RUN   non-exclusive-test-bucket-0/ext.config.networking.dnsmasq-service
00:20:35 [2025-03-28T00:20:35.157Z] === RUN   non-exclusive-test-bucket-0/ext.config.files.root-bash
00:20:35 [2025-03-28T00:20:35.711Z] === RUN   non-exclusive-test-bucket-0/ext.config.disks.growfs
00:20:36 [2025-03-28T00:20:36.266Z] === RUN   non-exclusive-test-bucket-0/ext.config.systemd.default-unit-timeouts
00:20:38 [2025-03-28T00:20:38.163Z] === RUN   non-exclusive-test-bucket-0/ext.config.content-origins
00:20:39 [2025-03-28T00:20:39.523Z] === RUN   non-exclusive-test-bucket-0/ext.config.disks.root-prjquota
00:20:41 [2025-03-28T00:20:41.396Z] === RUN   non-exclusive-test-bucket-0/ext.config.networking.nm-dhcp-client
00:20:43 [2025-03-28T00:20:43.294Z] === RUN   non-exclusive-test-bucket-0/ext.config.networking.nic-naming
00:20:43 [2025-03-28T00:20:43.546Z] === RUN   non-exclusive-test-bucket-0/ext.config.files.check-symlink
00:20:45 [2025-03-28T00:20:45.418Z] === RUN   non-exclusive-test-bucket-0/ext.config.files.kernel-headers
00:20:45 [2025-03-28T00:20:45.982Z] === RUN   non-exclusive-test-bucket-0/ext.config.disks.no-google-device-links
00:20:51 [2025-03-28T00:20:51.228Z] --- PASS: non-exclusive-test-bucket-0 (179.63s)
00:20:51 [2025-03-28T00:20:51.228Z]     --- PASS: non-exclusive-test-bucket-0/ext.config.files.fcos_users (1.67s)
00:20:51 [2025-03-28T00:20:51.228Z]     --- PASS: non-exclusive-test-bucket-0/ext.config.files.sudoers (0.64s)
00:20:51 [2025-03-28T00:20:51.228Z]     --- PASS: non-exclusive-test-bucket-0/ext.config.files.initrd.compression (1.61s)
00:20:51 [2025-03-28T00:20:51.228Z]     --- PASS: non-exclusive-test-bucket-0/ext.config.firewall.iptables (1.62s)
00:20:51 [2025-03-28T00:20:51.228Z]     --- PASS: non-exclusive-test-bucket-0/ext.config.kdump.service (1.69s)
00:20:51 [2025-03-28T00:20:51.228Z]     --- PASS: non-exclusive-test-bucket-0/ext.config.selinux.file-context-policy-match (3.64s)
00:20:51 [2025-03-28T00:20:51.228Z]     --- PASS: non-exclusive-test-bucket-0/ext.config.networking.network-online-service (1.65s)
00:20:51 [2025-03-28T00:20:51.228Z]     --- PASS: non-exclusive-test-bucket-0/ext.config.selinux.unmodified-policy (1.74s)
00:20:51 [2025-03-28T00:20:51.228Z]     --- PASS: non-exclusive-test-bucket-0/ext.config.files.fcos_groups (1.69s)
00:20:51 [2025-03-28T00:20:51.228Z]     --- PASS: non-exclusive-test-bucket-0/ext.config.networking.tls (4.70s)
00:20:51 [2025-03-28T00:20:51.228Z]     --- PASS: non-exclusive-test-bucket-0/ext.config.selinux.podman-tmpfs-context (6.77s)
00:20:51 [2025-03-28T00:20:51.228Z]     --- PASS: non-exclusive-test-bucket-0/ext.config.ignition.journald-log (1.74s)
00:20:51 [2025-03-28T00:20:51.228Z]     --- PASS: non-exclusive-test-bucket-0/ext.config.files.validate-symlinks (12.98s)
00:20:51 [2025-03-28T00:20:51.228Z]     --- PASS: non-exclusive-test-bucket-0/ext.config.files.system-generators (1.73s)
00:20:51 [2025-03-28T00:20:51.228Z]     --- PASS: non-exclusive-test-bucket-0/ext.config.disks.root-boot-ro (1.73s)
00:20:51 [2025-03-28T00:20:51.228Z]     --- PASS: non-exclusive-test-bucket-0/ext.config.containers.cgroups-v2 (1.70s)
00:20:51 [2025-03-28T00:20:51.228Z]     --- PASS: non-exclusive-test-bucket-0/ext.config.files.rpmdb-sqlite (0.73s)
00:20:51 [2025-03-28T00:20:51.228Z]     --- PASS: non-exclusive-test-bucket-0/ext.config.rpm-ostree.container-deps (1.74s)
00:20:51 [2025-03-28T00:20:51.228Z]     --- PASS: non-exclusive-test-bucket-0/ext.config.files.license (0.73s)
00:20:51 [2025-03-28T00:20:51.228Z]     --- PASS: non-exclusive-test-bucket-0/ext.config.files.aleph-version (0.70s)
00:20:51 [2025-03-28T00:20:51.228Z]     --- PASS: non-exclusive-test-bucket-0/ext.config.files.setgid (1.67s)
00:20:51 [2025-03-28T00:20:51.228Z]     --- PASS: non-exclusive-test-bucket-0/ext.config.files.etc-permissions (1.64s)
00:20:51 [2025-03-28T00:20:51.228Z]     --- PASS: non-exclusive-test-bucket-0/ext.config.disks.tmpfs (0.75s)
00:20:51 [2025-03-28T00:20:51.228Z]     --- PASS: non-exclusive-test-bucket-0/ext.config.files.file-directory-permissions (1.74s)
00:20:51 [2025-03-28T00:20:51.228Z]     --- PASS: non-exclusive-test-bucket-0/ext.config.kubernetes.kube-watch (2.67s)
00:20:51 [2025-03-28T00:20:51.228Z]     --- PASS: non-exclusive-test-bucket-0/ext.config.disks.partition-scheme (1.73s)
00:20:51 [2025-03-28T00:20:51.228Z]     --- PASS: non-exclusive-test-bucket-0/ext.config.boot.bootupd (1.66s)
00:20:51 [2025-03-28T00:20:51.228Z]     --- PASS: non-exclusive-test-bucket-0/ext.config.files.initrd.executables (3.62s)
00:20:51 [2025-03-28T00:20:51.228Z]     --- PASS: non-exclusive-test-bucket-0/ext.config.systemd.no-systemd-firstboot (1.65s)
00:20:51 [2025-03-28T00:20:51.228Z]     --- PASS: non-exclusive-test-bucket-0/ext.config.clhm.network-device-info (0.66s)
00:20:51 [2025-03-28T00:20:51.228Z]     --- PASS: non-exclusive-test-bucket-0/ext.config.logging.printk (1.63s)
00:20:51 [2025-03-28T00:20:51.228Z]     --- PASS: non-exclusive-test-bucket-0/ext.config.composefs.enabled (1.63s)
00:20:51 [2025-03-28T00:20:51.228Z]     --- PASS: non-exclusive-test-bucket-0/ext.config.ntp.chrony.coreos-platform-chrony-config (1.64s)
00:20:51 [2025-03-28T00:20:51.228Z]     --- PASS: non-exclusive-test-bucket-0/ext.config.files.logrotate-service (1.64s)
00:20:51 [2025-03-28T00:20:51.228Z]     --- PASS: non-exclusive-test-bucket-0/ext.config.networking.no-default-initramfs-net-propagation.default (1.62s)
00:20:51 [2025-03-28T00:20:51.228Z]     --- PASS: non-exclusive-test-bucket-0/ext.config.networking.nm-start (1.62s)
00:20:51 [2025-03-28T00:20:51.228Z]     --- PASS: non-exclusive-test-bucket-0/ext.config.selinux.stub-resolve-context (0.62s)
00:20:51 [2025-03-28T00:20:51.228Z]     --- PASS: non-exclusive-test-bucket-0/ext.config.files.amd-ucode-firmware (0.63s)
00:20:51 [2025-03-28T00:20:51.228Z]     --- PASS: non-exclusive-test-bucket-0/ext.config.podman.rootless-pasta-networking (5.67s)
00:20:51 [2025-03-28T00:20:51.228Z]     --- PASS: non-exclusive-test-bucket-0/ext.config.networking.default-network-behavior-change (1.64s)
00:20:51 [2025-03-28T00:20:51.228Z]     --- PASS: non-exclusive-test-bucket-0/ext.config.files.fwupd-refresh-timer (1.73s)
00:20:51 [2025-03-28T00:20:51.228Z]     --- PASS: non-exclusive-test-bucket-0/ext.config.files.initrd.expected-contents (3.70s)
00:20:51 [2025-03-28T00:20:51.228Z]     --- PASS: non-exclusive-test-bucket-0/ext.config.files.root-immutable-bit (0.64s)
00:20:51 [2025-03-28T00:20:51.228Z]     --- PASS: non-exclusive-test-bucket-0/ext.config.files.setuid (1.64s)
00:20:51 [2025-03-28T00:20:51.228Z]     --- PASS: non-exclusive-test-bucket-0/ext.config.swap.zram-default (0.65s)
00:20:51 [2025-03-28T00:20:51.228Z]     --- PASS: non-exclusive-test-bucket-0/ext.config.kubernetes.systemd-env-read (1.65s)
00:20:51 [2025-03-28T00:20:51.228Z]     --- PASS: non-exclusive-test-bucket-0/ext.config.networking.resolv.systemd-resolved (1.63s)
00:20:51 [2025-03-28T00:20:51.228Z]     --- PASS: non-exclusive-test-bucket-0/ext.config.files.remove-manifest-files (0.61s)
00:20:51 [2025-03-28T00:20:51.228Z]     --- PASS: non-exclusive-test-bucket-0/ext.config.podman.dns (1.64s)
00:20:51 [2025-03-28T00:20:51.228Z]     --- PASS: non-exclusive-test-bucket-0/ext.config.files.yum-repo-dir (0.65s)
00:20:51 [2025-03-28T00:20:51.228Z]     --- PASS: non-exclusive-test-bucket-0/ext.config.security.coreos-update-ca-trust (1.64s)
00:20:51 [2025-03-28T00:20:51.228Z]     --- PASS: non-exclusive-test-bucket-0/ext.config.files.console-config (1.63s)
00:20:51 [2025-03-28T00:20:51.228Z]     --- PASS: non-exclusive-test-bucket-0/ext.config.security.passwd (2.67s)
00:20:51 [2025-03-28T00:20:51.228Z]     --- PASS: non-exclusive-test-bucket-0/ext.config.selinux.usrlocal-context (1.64s)
00:20:51 [2025-03-28T00:20:51.228Z]     --- PASS: non-exclusive-test-bucket-0/ext.config.networking.nm-ifcfg-rh-plugin (1.62s)
00:20:51 [2025-03-28T00:20:51.228Z]     --- PASS: non-exclusive-test-bucket-0/ext.config.disks.systemd-repart-service (1.65s)
00:20:51 [2025-03-28T00:20:51.228Z]     --- PASS: non-exclusive-test-bucket-0/ext.config.networking.dnsmasq-service (0.65s)
00:20:51 [2025-03-28T00:20:51.228Z]     --- PASS: non-exclusive-test-bucket-0/ext.config.files.root-bash (0.64s)
00:20:51 [2025-03-28T00:20:51.228Z]     --- PASS: non-exclusive-test-bucket-0/ext.config.disks.growfs (0.61s)
00:20:51 [2025-03-28T00:20:51.228Z]     --- PASS: non-exclusive-test-bucket-0/ext.config.systemd.default-unit-timeouts (1.69s)
00:20:51 [2025-03-28T00:20:51.228Z]     --- PASS: non-exclusive-test-bucket-0/ext.config.content-origins (1.72s)
00:20:51 [2025-03-28T00:20:51.228Z]     --- PASS: non-exclusive-test-bucket-0/ext.config.disks.root-prjquota (1.67s)
00:20:51 [2025-03-28T00:20:51.228Z]     --- PASS: non-exclusive-test-bucket-0/ext.config.networking.nm-dhcp-client (1.65s)
00:20:51 [2025-03-28T00:20:51.228Z]     --- PASS: non-exclusive-test-bucket-0/ext.config.networking.nic-naming (0.68s)
00:20:51 [2025-03-28T00:20:51.228Z]     --- PASS: non-exclusive-test-bucket-0/ext.config.files.check-symlink (1.62s)
00:20:51 [2025-03-28T00:20:51.228Z]     --- PASS: non-exclusive-test-bucket-0/ext.config.files.kernel-headers (0.67s)
00:20:51 [2025-03-28T00:20:51.228Z]     --- PASS: non-exclusive-test-bucket-0/ext.config.disks.no-google-device-links (1.62s)
00:20:53 [2025-03-28T00:20:53.727Z] --- PASS: rpmostree.upgrade-rollback (96.01s)
00:20:53 [2025-03-28T00:20:53.727Z]     --- PASS: rpmostree.upgrade-rollback/upgrade (37.31s)
00:20:53 [2025-03-28T00:20:53.727Z]     --- PASS: rpmostree.upgrade-rollback/rollback (22.26s)
00:21:15 [2025-03-28T00:21:15.616Z] --- PASS: ext.config.firewall.iptables-legacy (38.79s)
00:21:25 [2025-03-28T00:21:25.829Z] --- PASS: rhcos.selinux.boolean.persist (60.64s)
00:21:27 [2025-03-28T00:21:27.182Z] --- PASS: fcos.users.shells (35.80s)
00:22:05 [2025-03-28T00:22:05.825Z] --- PASS: coreos.ignition.sethostname (36.17s)
00:22:05 [2025-03-28T00:22:05.825Z] --- PASS: ext.config.ignition.systemd-unmasking (38.72s)
00:22:08 [2025-03-28T00:22:08.325Z] --- PASS: multipath.partition (55.27s)
00:22:26 [2025-03-28T00:22:26.346Z] --- PASS: ext.config.ignition.delete-config (92.59s)
00:22:36 [2025-03-28T00:22:36.269Z] --- PASS: ext.config.clhm.ignition-warnings (26.97s)
00:22:36 [2025-03-28T00:22:36.523Z] === RUN   ostree.unlock/unlock
00:22:36 [2025-03-28T00:22:36.780Z] --- PASS: podman.network-single (180.14s)
00:22:37 [2025-03-28T00:22:37.335Z] === RUN   ostree.unlock/install
00:22:37 [2025-03-28T00:22:37.901Z] === RUN   ostree.unlock/uninstall
00:22:38 [2025-03-28T00:22:38.154Z] === RUN   ostree.unlock/discard
00:22:56 [2025-03-28T00:22:56.181Z] --- PASS: ext.config.gshadow (26.90s)
00:22:58 [2025-03-28T00:22:58.696Z] --- PASS: coreos.ignition.mount.partitions (55.24s)
00:23:00 [2025-03-28T00:23:00.571Z] --- PASS: ostree.unlock (56.17s)
00:23:00 [2025-03-28T00:23:00.571Z]     --- PASS: ostree.unlock/unlock (0.61s)
00:23:00 [2025-03-28T00:23:00.571Z]     --- PASS: ostree.unlock/install (0.60s)
00:23:00 [2025-03-28T00:23:00.571Z]     --- PASS: ostree.unlock/uninstall (0.45s)
00:23:00 [2025-03-28T00:23:00.571Z]     --- PASS: ostree.unlock/discard (18.65s)
00:23:01 [2025-03-28T00:23:01.924Z] --- PASS: coreos.auth.verify (24.78s)
00:23:05 [2025-03-28T00:23:05.184Z] --- PASS: ext.config.networking.no-default-initramfs-net-propagation.bootif (29.69s)
00:23:31 [2025-03-28T00:23:31.662Z] --- PASS: ext.config.boot.grub2-install (26.68s)
00:23:31 [2025-03-28T00:23:31.662Z] --- PASS: ext.config.ignition.systemd-enable-units (30.27s)
00:23:33 [2025-03-28T00:23:33.015Z] === RUN   podman.workflow/run
00:23:47 [2025-03-28T00:23:47.851Z] --- PASS: ext.config.ssh.custom-host-key-permissions (41.47s)
00:23:57 [2025-03-28T00:23:57.774Z] --- PASS: ext.config.butane.grub-users (62.95s)
00:24:07 [2025-03-28T00:24:07.708Z] --- PASS: ext.config.systemd.network-online (38.62s)
00:24:12 [2025-03-28T00:24:12.939Z] --- PASS: ext.config.var-mount.scsi-id (43.84s)
00:24:39 [2025-03-28T00:24:39.406Z] --- PASS: ext.config.networking.nameserver (29.53s)
00:24:51 [2025-03-28T00:24:51.565Z] --- PASS: ext.config.rpm-ostree-countme (39.29s)
00:24:52 [2025-03-28T00:24:52.919Z] --- PASS: ext.config.reboot (56.64s)
00:24:54 [2025-03-28T00:24:54.810Z] --- PASS: ext.config.podman.rootless-systemd (68.29s)
00:25:21 [2025-03-28T00:25:21.275Z] --- PASS: ext.config.networking.mtu-on-bond-kargs (41.07s)
00:25:25 [2025-03-28T00:25:25.419Z] === RUN   basic/FCOSGrowpart
00:25:25 [2025-03-28T00:25:25.419Z] === RUN   basic/PortSSH
00:25:25 [2025-03-28T00:25:25.419Z] === RUN   basic/DbusPerms
00:25:25 [2025-03-28T00:25:25.687Z] === RUN   basic/ServicesActive
00:25:25 [2025-03-28T00:25:25.687Z] === RUN   basic/ReadOnly
00:25:25 [2025-03-28T00:25:25.940Z] === RUN   basic/Useradd
00:25:26 [2025-03-28T00:25:26.193Z] === RUN   basic/MachineID
00:25:29 [2025-03-28T00:25:29.452Z] --- PASS: basic (38.11s)
00:25:29 [2025-03-28T00:25:29.452Z]     --- PASS: basic/FCOSGrowpart (0.16s)
00:25:29 [2025-03-28T00:25:29.452Z]     --- PASS: basic/PortSSH (0.12s)
00:25:29 [2025-03-28T00:25:29.452Z]     --- PASS: basic/DbusPerms (0.37s)
00:25:29 [2025-03-28T00:25:29.452Z]     --- PASS: basic/ServicesActive (0.14s)
00:25:29 [2025-03-28T00:25:29.452Z]     --- PASS: basic/ReadOnly (0.13s)
00:25:29 [2025-03-28T00:25:29.452Z]     --- PASS: basic/Useradd (0.20s)
00:25:29 [2025-03-28T00:25:29.452Z]     --- PASS: basic/MachineID (0.12s)
00:25:37 [2025-03-28T00:25:37.525Z] --- PASS: coreos.ignition.once (42.92s)
00:25:47 [2025-03-28T00:25:47.464Z] --- PASS: coreos.ignition.failure (16.77s)
00:25:47 [2025-03-28T00:25:47.718Z] --- PASS: coreos.ignition.mount.disks (54.91s)
00:26:02 [2025-03-28T00:26:02.547Z] --- PASS: ext.config.docker.basic (43.02s)
00:26:10 [2025-03-28T00:26:10.600Z] === RUN   basic.nvme/FCOSGrowpart
00:26:10 [2025-03-28T00:26:10.854Z] === RUN   basic.nvme/PortSSH
00:26:11 [2025-03-28T00:26:11.107Z] === RUN   basic.nvme/DbusPerms
00:26:11 [2025-03-28T00:26:11.374Z] === RUN   basic.nvme/ServicesActive
00:26:11 [2025-03-28T00:26:11.374Z] === RUN   basic.nvme/ReadOnly
00:26:11 [2025-03-28T00:26:11.627Z] === RUN   basic.nvme/Useradd
00:26:11 [2025-03-28T00:26:11.879Z] === RUN   basic.nvme/MachineID
00:26:15 [2025-03-28T00:26:15.125Z] --- PASS: basic.nvme (37.70s)
00:26:15 [2025-03-28T00:26:15.125Z]     --- PASS: basic.nvme/FCOSGrowpart (0.15s)
00:26:15 [2025-03-28T00:26:15.125Z]     --- PASS: basic.nvme/PortSSH (0.12s)
00:26:15 [2025-03-28T00:26:15.125Z]     --- PASS: basic.nvme/DbusPerms (0.34s)
00:26:15 [2025-03-28T00:26:15.125Z]     --- PASS: basic.nvme/ServicesActive (0.13s)
00:26:15 [2025-03-28T00:26:15.125Z]     --- PASS: basic.nvme/ReadOnly (0.12s)
00:26:15 [2025-03-28T00:26:15.125Z]     --- PASS: basic.nvme/Useradd (0.20s)
00:26:15 [2025-03-28T00:26:15.125Z]     --- PASS: basic.nvme/MachineID (0.12s)
00:26:33 [2025-03-28T00:26:33.144Z] --- PASS: coreos.unique.boot.ignition.failure (15.22s)
00:26:43 [2025-03-28T00:26:43.073Z] --- PASS: ext.config.selinux.enforcing (55.11s)
00:27:04 [2025-03-28T00:27:04.960Z] --- PASS: ext.config.ntp.timesyncd.dhcp-propagation (75.32s)
00:27:19 [2025-03-28T00:27:19.781Z] --- PASS: rpmostree.status (37.43s)
00:27:25 [2025-03-28T00:27:25.017Z] --- PASS: multipath.day2 (83.30s)
00:27:27 [2025-03-28T00:27:27.578Z] --- PASS: ext.config.var-mount.simple (56.71s)
00:27:59 [2025-03-28T00:27:59.587Z] === RUN   basic.uefi/PortSSH
00:27:59 [2025-03-28T00:27:59.587Z] === RUN   basic.uefi/DbusPerms
00:27:59 [2025-03-28T00:27:59.587Z] === RUN   basic.uefi/ServicesActive
00:27:59 [2025-03-28T00:27:59.587Z] === RUN   basic.uefi/ReadOnly
00:27:59 [2025-03-28T00:27:59.587Z] === RUN   basic.uefi/Useradd
00:27:59 [2025-03-28T00:27:59.587Z] === RUN   basic.uefi/MachineID
00:27:59 [2025-03-28T00:27:59.587Z] === RUN   basic.uefi/FCOSGrowpart
00:28:02 [2025-03-28T00:28:02.850Z] --- PASS: basic.uefi (38.79s)
00:28:02 [2025-03-28T00:28:02.850Z]     --- PASS: basic.uefi/PortSSH (0.12s)
00:28:02 [2025-03-28T00:28:02.850Z]     --- PASS: basic.uefi/DbusPerms (0.35s)
00:28:02 [2025-03-28T00:28:02.850Z]     --- PASS: basic.uefi/ServicesActive (0.13s)
00:28:02 [2025-03-28T00:28:02.850Z]     --- PASS: basic.uefi/ReadOnly (0.11s)
00:28:02 [2025-03-28T00:28:02.850Z]     --- PASS: basic.uefi/Useradd (0.21s)
00:28:02 [2025-03-28T00:28:02.850Z]     --- PASS: basic.uefi/MachineID (0.11s)
00:28:02 [2025-03-28T00:28:02.850Z]     --- PASS: basic.uefi/FCOSGrowpart (0.15s)
00:28:03 [2025-03-28T00:28:03.103Z] --- PASS: ext.config.networking.ifname-karg.everyboot-systemd-link-file (60.04s)
00:28:15 [2025-03-28T00:28:15.267Z] --- PASS: multipath.day1 (56.39s)
00:28:41 [2025-03-28T00:28:41.741Z] === RUN   podman.workflow/exec
00:28:41 [2025-03-28T00:28:41.741Z] === RUN   podman.workflow/stop
00:28:51 [2025-03-28T00:28:51.659Z] === RUN   podman.workflow/remove
00:28:51 [2025-03-28T00:28:51.659Z] === RUN   podman.workflow/delete
00:28:55 [2025-03-28T00:28:55.821Z] --- PASS: podman.workflow (355.68s)
00:28:55 [2025-03-28T00:28:55.821Z]     --- PASS: podman.workflow/run (307.29s)
00:28:55 [2025-03-28T00:28:55.821Z]             cluster.go:151: Trying to pull quay.io/fedora/fedora:latest...
00:28:55 [2025-03-28T00:28:55.821Z]             cluster.go:151: Getting image source signatures
00:28:55 [2025-03-28T00:28:55.821Z]             cluster.go:151: Copying blob sha256:797a0cd5771f5ad11c70304d62f634eaa866679ad9b9b58dcf9746b1f895d742
00:28:55 [2025-03-28T00:28:55.821Z]             cluster.go:151: Copying config sha256:45f699910992976ce3f9165b914e681d222be989185ebf1f933fc1d678f1e47f
00:28:55 [2025-03-28T00:28:55.821Z]             cluster.go:151: Writing manifest to image destination
00:28:55 [2025-03-28T00:28:55.821Z]     --- PASS: podman.workflow/exec (0.34s)
00:28:55 [2025-03-28T00:28:55.821Z]     --- PASS: podman.workflow/stop (10.67s)
00:28:55 [2025-03-28T00:28:55.821Z]     --- PASS: podman.workflow/remove (0.56s)
00:28:55 [2025-03-28T00:28:55.821Z]     --- PASS: podman.workflow/delete (0.70s)
00:28:55 [2025-03-28T00:28:55.821Z]             cluster.go:151: grep: quay.io/fedora/fedora:latest: No such file or directory
00:28:55 [2025-03-28T00:28:55.821Z]             cluster.go:151: bash: line 2: Deleted:: command not found
00:28:55 [2025-03-28T00:28:55.821Z] FAIL, output in /home/jenkins/agent/workspace/test-override/tmp/kola-Wwbks/kola-x86_64
00:28:55 [2025-03-28T00:28:55.821Z] 
00:28:55 [2025-03-28T00:28:55.821Z] 
00:28:55 [2025-03-28T00:28:55.821Z] ======== Re-running failed tests (flake detection) ========
00:28:55 [2025-03-28T00:28:55.821Z] 
00:28:56 [2025-03-28T00:28:56.074Z] === RUN   ext.config.toolbox
00:30:03 [2025-03-28T00:30:03.694Z] systemctl status kola-runext.service:
00:30:03 [2025-03-28T00:30:03.694Z] �� kola-runext.service
00:30:03 [2025-03-28T00:30:03.694Z]      Loaded: loaded (/etc/systemd/system/kola-runext.service; static)
00:30:03 [2025-03-28T00:30:03.694Z]     Drop-In: /usr/lib/systemd/system/service.d
00:30:03 [2025-03-28T00:30:03.694Z]              ������10-timeout-abort.conf, 50-keep-warm.conf
00:30:03 [2025-03-28T00:30:03.694Z]      Active: failed (Result: exit-code) since Fri 2025-03-28 00:30:00 UTC; 941ms ago
00:30:03 [2025-03-28T00:30:03.694Z]    Duration: 42.250s
00:30:03 [2025-03-28T00:30:03.694Z]  Invocation: bd97ac573f714608bc736fb429d72dda
00:30:03 [2025-03-28T00:30:03.694Z]     Process: 2288 ExecStart=/usr/local/bin/kola-runext-test.sh (code=exited, status=1/FAILURE)
00:30:03 [2025-03-28T00:30:03.694Z]    Main PID: 2288 (code=exited, status=1/FAILURE)
00:30:03 [2025-03-28T00:30:03.694Z]    Mem peak: 2.5M
00:30:03 [2025-03-28T00:30:03.694Z]         CPU: 51ms
00:30:03 [2025-03-28T00:30:03.694Z] 
00:30:03 [2025-03-28T00:30:03.694Z] Mar 28 00:29:18 qemu0 systemd[1]: Started kola-runext.service.
00:30:03 [2025-03-28T00:30:03.694Z] Mar 28 00:29:18 qemu0 kola-runext-test.sh[2288]: + . /var/opt/kola/extdata/commonlib.sh
00:30:03 [2025-03-28T00:30:03.694Z] Mar 28 00:29:18 qemu0 kola-runext-test.sh[2288]: ++ IFS=' '
00:30:03 [2025-03-28T00:30:03.694Z] Mar 28 00:29:18 qemu0 kola-runext-test.sh[2288]: ++ read -r -a cmdline
00:30:03 [2025-03-28T00:30:03.694Z] Mar 28 00:29:18 qemu0 kola-runext-test.sh[2289]: ++ seq 1 5
00:30:03 [2025-03-28T00:30:03.694Z] Mar 28 00:29:18 qemu0 kola-runext-test.sh[2288]: + for i in $(seq 1 5)
00:30:03 [2025-03-28T00:30:03.694Z] Mar 28 00:29:18 qemu0 kola-runext-test.sh[2288]: + machinectl shell core@ /bin/toolbox create --assumeyes
00:30:03 [2025-03-28T00:30:03.694Z] Mar 28 00:29:18 qemu0 machinectl[2290]: Connected to the local host. Press ^] three times within 1s to exit session.
00:30:03 [2025-03-28T00:30:03.694Z] Mar 28 00:29:59 qemu0 machinectl[2290]: Connection to the local host terminated.
00:30:03 [2025-03-28T00:30:03.694Z] Mar 28 00:29:59 qemu0 kola-runext-test.sh[2652]: ++ machinectl shell core@ /bin/toolbox list --containers
00:30:03 [2025-03-28T00:30:03.694Z] Mar 28 00:29:59 qemu0 kola-runext-test.sh[2654]: ++ grep --count fedora-toolbox-
00:30:03 [2025-03-28T00:30:03.694Z] Mar 28 00:29:59 qemu0 machinectl[2652]: Connected to the local host. Press ^] three times within 1s to exit session.
00:30:03 [2025-03-28T00:30:03.694Z] Mar 28 00:29:59 qemu0 machinectl[2652]: Connection to the local host terminated.
00:30:03 [2025-03-28T00:30:03.694Z] Mar 28 00:29:59 qemu0 kola-runext-test.sh[2288]: + [[ 1 -ne 1 ]]
00:30:03 [2025-03-28T00:30:03.694Z] Mar 28 00:29:59 qemu0 kola-runext-test.sh[2288]: + break
00:30:03 [2025-03-28T00:30:03.694Z] Mar 28 00:29:59 qemu0 kola-runext-test.sh[2677]: ++ machinectl shell core@ /bin/toolbox list --containers
00:30:03 [2025-03-28T00:30:03.694Z] Mar 28 00:29:59 qemu0 kola-runext-test.sh[2679]: ++ grep --count fedora-toolbox-
00:30:03 [2025-03-28T00:30:03.694Z] Mar 28 00:29:59 qemu0 machinectl[2677]: Connected to the local host. Press ^] three times within 1s to exit session.
00:30:03 [2025-03-28T00:30:03.694Z] Mar 28 00:30:00 qemu0 machinectl[2677]: Connection to the local host terminated.
00:30:03 [2025-03-28T00:30:03.694Z] Mar 28 00:30:00 qemu0 kola-runext-test.sh[2288]: + [[ 1 -ne 1 ]]
00:30:03 [2025-03-28T00:30:03.694Z] Mar 28 00:30:00 qemu0 kola-runext-test.sh[2288]: + ok toolbox create
00:30:03 [2025-03-28T00:30:03.694Z] Mar 28 00:30:00 qemu0 kola-runext-test.sh[2288]: + echo ok toolbox create
00:30:03 [2025-03-28T00:30:03.694Z] Mar 28 00:30:00 qemu0 kola-runext-test.sh[2288]: ok toolbox create
00:30:03 [2025-03-28T00:30:03.694Z] Mar 28 00:30:00 qemu0 kola-runext-test.sh[2288]: + machinectl shell core@ /bin/toolbox run touch ok_toolbox
00:30:03 [2025-03-28T00:30:03.694Z] Mar 28 00:30:00 qemu0 machinectl[2701]: Connected to the local host. Press ^] three times within 1s to exit session.
00:30:03 [2025-03-28T00:30:03.694Z] Mar 28 00:30:00 qemu0 kola-runext-test.sh[2701]: Error: failed to invoke command touch in container fedora-toolbox-43
00:30:03 [2025-03-28T00:30:03.694Z] Mar 28 00:30:00 qemu0 machinectl[2701]: Connection to the local host terminated.
00:30:03 [2025-03-28T00:30:03.694Z] Mar 28 00:30:00 qemu0 kola-runext-test.sh[2288]: + [[ ! -f /home/core/ok_toolbox ]]
00:30:03 [2025-03-28T00:30:03.694Z] Mar 28 00:30:00 qemu0 kola-runext-test.sh[2288]: + fatal 'Could not run a simple command inside a toolbox'
00:30:03 [2025-03-28T00:30:03.694Z] Mar 28 00:30:00 qemu0 kola-runext-test.sh[2288]: + echo 'Could not run a simple command inside a toolbox'
00:30:03 [2025-03-28T00:30:03.694Z] Mar 28 00:30:00 qemu0 kola-runext-test.sh[2288]: Could not run a simple command inside a toolbox
00:30:03 [2025-03-28T00:30:03.694Z] Mar 28 00:30:00 qemu0 kola-runext-test.sh[2288]: + exit 1
00:30:03 [2025-03-28T00:30:03.694Z] Mar 28 00:30:00 qemu0 systemd[1]: kola-runext.service: Main process exited, code=exited, status=1/FAILURE
00:30:03 [2025-03-28T00:30:03.694Z] Mar 28 00:30:00 qemu0 systemd[1]: kola-runext.service: Failed with result 'exit-code'.
00:30:05 [2025-03-28T00:30:05.569Z] --- FAIL: ext.config.toolbox (69.51s)
00:30:05 [2025-03-28T00:30:05.569Z]         cluster.go:151: Error: Unit kola-runext.service exited with code 1
00:30:05 [2025-03-28T00:30:05.569Z]         cluster.go:151: 2025-03-28T00:30:01Z cli: Unit kola-runext.service exited with code 1
00:30:05 [2025-03-28T00:30:05.569Z]         harness.go:1260: kolet failed: : kolet run-test-unit failed: Process exited with status 1
00:30:05 [2025-03-28T00:30:05.569Z] FAIL, output in /home/jenkins/agent/workspace/test-override/tmp/kola-Wwbks/kola-x86_64/rerun
00:30:05 [2025-03-28T00:30:05.569Z] Error: harness: test suite failed
00:30:05 [2025-03-28T00:30:05.569Z] 2025-03-28T00:30:05Z cli: harness: test suite failed
00:30:05 [2025-03-28T00:30:05.569Z] failed to execute cmd-kola: exit status 1