Console Output

Skipping 300 KB.. Full Log

14:48:43 [2025-01-29T14:48:43.968Z]     --- PASS: non-exclusive-test-bucket-0/ext.config.files.license (0.68s)
14:48:43 [2025-01-29T14:48:43.968Z]     --- PASS: non-exclusive-test-bucket-0/ext.config.ignition.journald-log (1.68s)
14:48:43 [2025-01-29T14:48:43.968Z]     --- PASS: non-exclusive-test-bucket-0/ext.config.selinux.stub-resolve-context (1.66s)
14:48:43 [2025-01-29T14:48:43.968Z]     --- PASS: non-exclusive-test-bucket-0/ext.config.containers.cgroups-v2 (0.68s)
14:48:58 [2025-01-29T14:48:58.804Z] --- PASS: ext.config.networking.no-default-initramfs-net-propagation.bootif (41.20s)
14:49:02 [2025-01-29T14:49:02.057Z] --- PASS: ext.config.selinux.enforcing (53.75s)
14:49:40 [2025-01-29T14:49:40.688Z] --- PASS: ext.config.ssh.custom-host-key-permissions (38.55s)
14:49:40 [2025-01-29T14:49:40.688Z] --- PASS: coreos.ignition.mount.partitions (55.35s)
14:49:47 [2025-01-29T14:49:47.200Z] --- PASS: multipath.day2 (73.85s)
14:49:53 [2025-01-29T14:49:53.718Z] --- PASS: kdump.crash.ssh (122.26s)
14:49:53 [2025-01-29T14:49:53.718Z]         cluster.go:151: Running as unit: run-p7169-i7469.service; invocation ID: 2360ba0796ad4905a7a6cda14ea90720
14:50:05 [2025-01-29T14:50:05.858Z] --- PASS: ext.config.networking.kargs-rd-net (29.45s)
14:50:08 [2025-01-29T14:50:08.359Z] === RUN   ostree.hotfix/unlock
14:50:12 [2025-01-29T14:50:12.504Z] === RUN   fcos.filesystem/writablefiles
14:50:13 [2025-01-29T14:50:13.060Z] === RUN   fcos.filesystem/writabledirs
14:50:13 [2025-01-29T14:50:13.314Z] === RUN   fcos.filesystem/stickydirs
14:50:13 [2025-01-29T14:50:13.566Z] === RUN   fcos.filesystem/denylist
14:50:16 [2025-01-29T14:50:16.815Z] === RUN   ostree.hotfix/install
14:50:17 [2025-01-29T14:50:17.068Z] --- PASS: fcos.filesystem (37.80s)
14:50:17 [2025-01-29T14:50:17.068Z]     --- PASS: fcos.filesystem/writablefiles (0.87s)
14:50:17 [2025-01-29T14:50:17.068Z]     --- PASS: fcos.filesystem/writabledirs (0.26s)
14:50:17 [2025-01-29T14:50:17.068Z]     --- PASS: fcos.filesystem/stickydirs (0.29s)
14:50:17 [2025-01-29T14:50:17.068Z]     --- PASS: fcos.filesystem/denylist (0.38s)
14:50:17 [2025-01-29T14:50:17.321Z] === RUN   ostree.hotfix/uninstall
14:50:18 [2025-01-29T14:50:18.242Z] === RUN   ostree.hotfix/persist
14:50:22 [2025-01-29T14:50:22.389Z] --- PASS: ext.config.systemd.condition-needs-update (80.46s)
14:50:37 [2025-01-29T14:50:37.214Z] === RUN   ostree.hotfix/rollback
14:50:41 [2025-01-29T14:50:41.373Z] --- PASS: fcos.network.listeners (24.27s)
14:50:43 [2025-01-29T14:50:43.248Z] === RUN   rpmostree.upgrade-rollback/upgrade
14:50:51 [2025-01-29T14:50:51.313Z] --- PASS: ext.config.networking.ifname-karg.udev-rule-firstboot-propagation (57.01s)
14:51:01 [2025-01-29T14:51:01.228Z] --- PASS: ostree.hotfix (73.38s)
14:51:01 [2025-01-29T14:51:01.228Z]     --- PASS: ostree.hotfix/unlock (8.47s)
14:51:01 [2025-01-29T14:51:01.228Z]     --- PASS: ostree.hotfix/install (0.59s)
14:51:01 [2025-01-29T14:51:01.228Z]     --- PASS: ostree.hotfix/uninstall (0.43s)
14:51:01 [2025-01-29T14:51:01.228Z]     --- PASS: ostree.hotfix/persist (19.00s)
14:51:01 [2025-01-29T14:51:01.228Z]     --- PASS: ostree.hotfix/rollback (19.40s)
14:51:01 [2025-01-29T14:51:01.228Z] --- PASS: rhcos.selinux.boolean.persist (55.51s)
14:51:19 [2025-01-29T14:51:19.250Z] --- PASS: ext.config.networking.hostname.fallback-hostname (26.63s)
14:51:20 [2025-01-29T14:51:20.171Z] === RUN   rpmostree.upgrade-rollback/rollback
14:51:42 [2025-01-29T14:51:42.031Z] --- PASS: ext.config.composefs.enabled (38.39s)
14:51:46 [2025-01-29T14:51:46.176Z] --- PASS: rpmostree.upgrade-rollback (84.18s)
14:51:46 [2025-01-29T14:51:46.176Z]     --- PASS: rpmostree.upgrade-rollback/upgrade (37.04s)
14:51:46 [2025-01-29T14:51:46.176Z]     --- PASS: rpmostree.upgrade-rollback/rollback (22.35s)
14:51:47 [2025-01-29T14:51:47.095Z] --- PASS: ext.config.networking.nmstate.state (45.56s)
14:51:50 [2025-01-29T14:51:50.348Z] --- PASS: ext.config.toolbox (69.06s)
14:51:58 [2025-01-29T14:51:58.419Z] --- PASS: ext.config.networking.nameserver (41.39s)
14:51:59 [2025-01-29T14:51:59.775Z] === RUN   podman.workflow/run
14:52:26 [2025-01-29T14:52:26.235Z] === RUN   basic.uefi-secure/DbusPerms
14:52:26 [2025-01-29T14:52:26.235Z] === RUN   basic.uefi-secure/ServicesActive
14:52:26 [2025-01-29T14:52:26.235Z] === RUN   basic.uefi-secure/ReadOnly
14:52:26 [2025-01-29T14:52:26.235Z] === RUN   basic.uefi-secure/Useradd
14:52:26 [2025-01-29T14:52:26.235Z] === RUN   basic.uefi-secure/MachineID
14:52:26 [2025-01-29T14:52:26.235Z] === RUN   basic.uefi-secure/FCOSGrowpart
14:52:26 [2025-01-29T14:52:26.235Z] --- PASS: rpmostree.status (25.20s)
14:52:26 [2025-01-29T14:52:26.235Z] === RUN   basic.uefi-secure/PortSSH
14:52:26 [2025-01-29T14:52:26.235Z] --- PASS: ext.config.swap.zram-generator (37.69s)
14:52:27 [2025-01-29T14:52:27.154Z] --- PASS: basic.uefi-secure (40.38s)
14:52:27 [2025-01-29T14:52:27.154Z]     --- PASS: basic.uefi-secure/DbusPerms (0.33s)
14:52:27 [2025-01-29T14:52:27.154Z]     --- PASS: basic.uefi-secure/ServicesActive (0.12s)
14:52:27 [2025-01-29T14:52:27.154Z]     --- PASS: basic.uefi-secure/ReadOnly (0.11s)
14:52:27 [2025-01-29T14:52:27.154Z]     --- PASS: basic.uefi-secure/Useradd (0.20s)
14:52:27 [2025-01-29T14:52:27.154Z]     --- PASS: basic.uefi-secure/MachineID (0.12s)
14:52:27 [2025-01-29T14:52:27.154Z]     --- PASS: basic.uefi-secure/FCOSGrowpart (0.14s)
14:52:27 [2025-01-29T14:52:27.154Z]     --- PASS: basic.uefi-secure/PortSSH (0.12s)
14:52:29 [2025-01-29T14:52:29.032Z] --- PASS: ext.config.rpm-ostree-countme (38.41s)
14:52:50 [2025-01-29T14:52:50.891Z] === RUN   basic/FCOSGrowpart
14:52:50 [2025-01-29T14:52:50.891Z] === RUN   basic/PortSSH
14:52:50 [2025-01-29T14:52:50.891Z] === RUN   basic/DbusPerms
14:52:50 [2025-01-29T14:52:50.891Z] === RUN   basic/ServicesActive
14:52:50 [2025-01-29T14:52:50.891Z] === RUN   basic/ReadOnly
14:52:50 [2025-01-29T14:52:50.891Z] === RUN   basic/Useradd
14:52:50 [2025-01-29T14:52:50.891Z] === RUN   basic/MachineID
14:52:52 [2025-01-29T14:52:52.767Z] --- PASS: ext.config.networking.prefer-ignition-networking (29.19s)
14:52:53 [2025-01-29T14:52:53.328Z] --- PASS: basic (26.15s)
14:52:53 [2025-01-29T14:52:53.328Z]     --- PASS: basic/FCOSGrowpart (0.14s)
14:52:53 [2025-01-29T14:52:53.328Z]     --- PASS: basic/PortSSH (0.12s)
14:52:53 [2025-01-29T14:52:53.328Z]     --- PASS: basic/DbusPerms (0.34s)
14:52:53 [2025-01-29T14:52:53.328Z]     --- PASS: basic/ServicesActive (0.13s)
14:52:53 [2025-01-29T14:52:53.328Z]     --- PASS: basic/ReadOnly (0.11s)
14:52:53 [2025-01-29T14:52:53.328Z]     --- PASS: basic/Useradd (0.19s)
14:52:53 [2025-01-29T14:52:53.328Z]     --- PASS: basic/MachineID (0.12s)
14:52:54 [2025-01-29T14:52:54.248Z] --- PASS: coreos.ignition.instantiated.enable-unit (25.39s)
14:53:33 [2025-01-29T14:53:33.016Z] --- PASS: ext.config.ntp.timesyncd.dhcp-propagation (69.10s)
14:53:51 [2025-01-29T14:53:51.026Z] --- PASS: ext.config.disks.lvmdevices (56.80s)
14:53:51 [2025-01-29T14:53:51.026Z] --- PASS: ext.config.var-mount.simple (56.31s)
14:54:03 [2025-01-29T14:54:03.173Z] --- PASS: ext.config.podman.rootless-systemd (67.28s)
14:54:03 [2025-01-29T14:54:03.426Z] === RUN   podman.base/info
14:54:03 [2025-01-29T14:54:03.982Z] === RUN   podman.base/resources
14:54:10 [2025-01-29T14:54:10.490Z] === RUN   rpmostree.install-uninstall/install
14:54:11 [2025-01-29T14:54:11.047Z] === RUN   rootfs.uuid/RandomUUID
14:54:11 [2025-01-29T14:54:11.602Z] --- PASS: podman.base (38.79s)
14:54:11 [2025-01-29T14:54:11.602Z]     --- PASS: podman.base/info (0.41s)
14:54:11 [2025-01-29T14:54:11.602Z]     --- PASS: podman.base/resources (4.45s)
14:54:14 [2025-01-29T14:54:14.859Z] --- PASS: rootfs.uuid (24.84s)
14:54:14 [2025-01-29T14:54:14.859Z]     --- PASS: rootfs.uuid/RandomUUID (0.15s)
14:54:41 [2025-01-29T14:54:41.315Z] --- PASS: ext.config.networking.force-persist-ip (39.72s)
14:54:51 [2025-01-29T14:54:51.231Z] --- PASS: ext.config.ignition.kargs (38.74s)
14:54:56 [2025-01-29T14:54:56.454Z] === RUN   rpmostree.install-uninstall/uninstall
14:55:14 [2025-01-29T14:55:14.497Z] --- PASS: coreos.misc.disk.varlibcontainers (57.19s)
14:55:14 [2025-01-29T14:55:14.497Z]         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'.
14:55:14 [2025-01-29T14:55:14.497Z]         cluster.go:151: Created symlink '/etc/systemd/system/local-fs.target.wants/var-lib-containers.mount' ��� '/etc/systemd/system/var-lib-containers.mount'.
14:55:40 [2025-01-29T14:55:40.961Z] --- PASS: rpmostree.install-uninstall (108.21s)
14:55:40 [2025-01-29T14:55:40.961Z]     --- PASS: rpmostree.install-uninstall/install (45.67s)
14:55:40 [2025-01-29T14:55:40.961Z]     --- PASS: rpmostree.install-uninstall/uninstall (38.25s)
14:57:17 [2025-01-29T14:57:17.302Z] === RUN   podman.workflow/exec
14:57:17 [2025-01-29T14:57:17.302Z] === RUN   podman.workflow/stop
14:57:17 [2025-01-29T14:57:17.302Z] === RUN   podman.workflow/remove
14:57:17 [2025-01-29T14:57:17.859Z] === RUN   podman.workflow/delete
14:57:22 [2025-01-29T14:57:22.018Z] --- PASS: podman.workflow (343.40s)
14:57:22 [2025-01-29T14:57:22.018Z]     --- PASS: podman.workflow/run (306.63s)
14:57:22 [2025-01-29T14:57:22.018Z]             cluster.go:151: Trying to pull quay.io/fedora/fedora:latest...
14:57:22 [2025-01-29T14:57:22.018Z]             cluster.go:151: Getting image source signatures
14:57:22 [2025-01-29T14:57:22.018Z]             cluster.go:151: Copying blob sha256:a52c777f25d4afed9d7958da2f249de731ed6e4479ead4f00621589d0398610c
14:57:22 [2025-01-29T14:57:22.018Z]             cluster.go:151: Copying config sha256:a432b057a522737c229d2aac9b029f55bf2a44eb3f423e4e4ece2acb8a304652
14:57:22 [2025-01-29T14:57:22.018Z]             cluster.go:151: Writing manifest to image destination
14:57:22 [2025-01-29T14:57:22.018Z]     --- PASS: podman.workflow/exec (0.37s)
14:57:22 [2025-01-29T14:57:22.018Z]     --- PASS: podman.workflow/stop (10.66s)
14:57:22 [2025-01-29T14:57:22.018Z]     --- PASS: podman.workflow/remove (0.57s)
14:57:22 [2025-01-29T14:57:22.018Z]     --- PASS: podman.workflow/delete (0.69s)
14:57:22 [2025-01-29T14:57:22.018Z]             cluster.go:151: grep: quay.io/fedora/fedora:latest: No such file or directory
14:57:22 [2025-01-29T14:57:22.018Z]             cluster.go:151: bash: line 2: Deleted:: command not found
14:57:22 [2025-01-29T14:57:22.018Z] PASS, output in /home/jenkins/agent/workspace/test-override/tmp/kola-rhrc4/kola-x86_64
14:57:22 [2025-01-29T14:57:22.023Z] [Pipeline] }
14:57:22 [2025-01-29T14:57:22.027Z] [Pipeline] // withEnv
14:57:22 [2025-01-29T14:57:22.034Z] [Pipeline] withEnv
14:57:22 [2025-01-29T14:57:22.034Z] [Pipeline] {
14:57:22 [2025-01-29T14:57:22.113Z] [Pipeline] sh
14:57:22 [2025-01-29T14:57:22.701Z] + set -xeuo pipefail
14:57:22 [2025-01-29T14:57:22.702Z] ++ umask
14:57:22 [2025-01-29T14:57:22.702Z] + '[' 0022 = 0000 ']'
14:57:22 [2025-01-29T14:57:22.702Z] + cd /home/jenkins/agent/workspace/test-override
14:57:22 [2025-01-29T14:57:22.702Z] + cosa kola run --rerun --allow-rerun-success=tags=needs-internet --build=latest --output-dir=/home/jenkins/agent/workspace/test-override/tmp/kola-rhrc4/kola-reprovision-x86_64 --on-warn-failure-exit-77 --arch=x86_64 --tag=reprovision
14:57:22 [2025-01-29T14:57:22.702Z] kola -p qemu --build latest run --rerun --allow-rerun-success=tags=needs-internet --on-warn-failure-exit-77 --arch=x86_64 --tag=reprovision --output-dir /home/jenkins/agent/workspace/test-override/tmp/kola-rhrc4/kola-reprovision-x86_64
14:57:22 [2025-01-29T14:57:22.956Z] ����  Snoozing kola test pattern "ext.config.rpm-ostree.kernel-replace" until Feb 04 2025
14:57:22 [2025-01-29T14:57:22.957Z]   ���� https://github.com/coreos/fedora-coreos-tracker/issues/1870
14:57:23 [2025-01-29T14:57:23.219Z] === RUN   ext.config.root-reprovision.luks.autosave-xfs
14:57:23 [2025-01-29T14:57:23.219Z] === RUN   ext.config.root-reprovision.filesystem-only
14:57:23 [2025-01-29T14:57:23.219Z] === RUN   ext.config.root-reprovision.luks.512e
14:57:23 [2025-01-29T14:57:23.219Z] === RUN   ext.config.root-reprovision.swap-before-root
14:57:23 [2025-01-29T14:57:23.219Z] === RUN   ext.config.root-reprovision.autosave-xfs
14:57:23 [2025-01-29T14:57:23.219Z] === RUN   coreos.boot-mirror
14:57:23 [2025-01-29T14:57:23.219Z] === RUN   coreos.boot-mirror.luks
14:57:23 [2025-01-29T14:57:23.219Z] === RUN   ext.config.root-reprovision.luks
14:57:23 [2025-01-29T14:57:23.219Z] === RUN   ext.config.root-reprovision.raid1
14:59:29 [2025-01-29T14:59:29.798Z] --- PASS: ext.config.root-reprovision.luks.autosave-xfs (124.46s)
15:01:21 [2025-01-29T15:01:21.587Z] === RUN   coreos.boot-mirror/sanity-check
15:01:21 [2025-01-29T15:01:21.587Z] === RUN   coreos.boot-mirror/detach-primary
15:01:48 [2025-01-29T15:01:48.057Z] === RUN   coreos.boot-mirror/verify-fallback
15:01:53 [2025-01-29T15:01:53.278Z] --- PASS: coreos.boot-mirror (145.19s)
15:01:53 [2025-01-29T15:01:53.278Z]     --- PASS: coreos.boot-mirror/sanity-check (1.96s)
15:01:53 [2025-01-29T15:01:53.278Z]     --- PASS: coreos.boot-mirror/detach-primary (29.90s)
15:01:53 [2025-01-29T15:01:53.278Z]     --- PASS: coreos.boot-mirror/verify-fallback (0.88s)
15:03:29 [2025-01-29T15:03:29.620Z] === RUN   coreos.boot-mirror.luks/sanity-check
15:03:56 [2025-01-29T15:03:56.087Z] === RUN   coreos.boot-mirror.luks/detach-primary
15:04:28 [2025-01-29T15:04:28.078Z] === RUN   coreos.boot-mirror.luks/verify-fallback
15:05:00 [2025-01-29T15:05:00.074Z] --- PASS: coreos.boot-mirror.luks (186.58s)
15:05:00 [2025-01-29T15:05:00.074Z]     --- PASS: coreos.boot-mirror.luks/sanity-check (1.65s)
15:05:00 [2025-01-29T15:05:00.074Z]     --- PASS: coreos.boot-mirror.luks/detach-primary (29.89s)
15:05:00 [2025-01-29T15:05:00.074Z]     --- PASS: coreos.boot-mirror.luks/verify-fallback (0.83s)
15:06:21 [2025-01-29T15:06:21.414Z] --- PASS: ext.config.root-reprovision.swap-before-root (81.60s)
15:07:28 [2025-01-29T15:07:28.987Z] --- PASS: ext.config.root-reprovision.autosave-xfs (63.92s)
15:09:35 [2025-01-29T15:09:35.346Z] --- PASS: ext.config.root-reprovision.luks (125.05s)
15:11:26 [2025-01-29T15:11:26.745Z] --- PASS: ext.config.root-reprovision.raid1 (105.91s)
15:13:18 [2025-01-29T15:13:18.072Z] --- PASS: ext.config.root-reprovision.luks.512e (112.26s)
15:14:39 [2025-01-29T15:14:39.419Z] --- PASS: ext.config.root-reprovision.filesystem-only (81.54s)
15:14:39 [2025-01-29T15:14:39.419Z] PASS, output in /home/jenkins/agent/workspace/test-override/tmp/kola-rhrc4/kola-reprovision-x86_64
15:14:39 [2025-01-29T15:14:39.422Z] [Pipeline] }
15:14:39 [2025-01-29T15:14:39.425Z] [Pipeline] // withEnv
15:14:39 [2025-01-29T15:14:39.482Z] [Pipeline] }
15:14:39 [2025-01-29T15:14:39.504Z] [Pipeline] // parallel
15:14:39 [2025-01-29T15:14:39.539Z] [Pipeline] withEnv
15:14:39 [2025-01-29T15:14:39.540Z] [Pipeline] {
15:14:39 [2025-01-29T15:14:39.546Z] [Pipeline] sh
15:14:40 [2025-01-29T15:14:40.151Z] + set -xeuo pipefail
15:14:40 [2025-01-29T15:14:40.151Z] ++ umask
15:14:40 [2025-01-29T15:14:40.151Z] + '[' 0022 = 0000 ']'
15:14:40 [2025-01-29T15:14:40.151Z] + cd /home/jenkins/agent/workspace/test-override
15:14:40 [2025-01-29T15:14:40.151Z] + cosa shell -- test -d /home/jenkins/agent/workspace/test-override/tmp/kola-rhrc4/kola-upgrade-x86_64
15:14:40 [2025-01-29T15:14:40.152Z] [Pipeline] }
15:14:40 [2025-01-29T15:14:40.155Z] [Pipeline] // withEnv
15:14:40 [2025-01-29T15:14:40.161Z] [Pipeline] withEnv
15:14:40 [2025-01-29T15:14:40.161Z] [Pipeline] {
15:14:40 [2025-01-29T15:14:40.179Z] [Pipeline] sh
15:14:40 [2025-01-29T15:14:40.719Z] + set -xeuo pipefail
15:14:40 [2025-01-29T15:14:40.719Z] ++ umask
15:14:40 [2025-01-29T15:14:40.719Z] + '[' 0022 = 0000 ']'
15:14:40 [2025-01-29T15:14:40.719Z] + cd /home/jenkins/agent/workspace/test-override
15:14:40 [2025-01-29T15:14:40.719Z] + cosa shell -- tar -C /home/jenkins/agent/workspace/test-override/tmp/kola-rhrc4 -c --xz kola-upgrade-x86_64
15:14:41 [2025-01-29T15:14:41.274Z] [Pipeline] }
15:14:41 [2025-01-29T15:14:41.277Z] [Pipeline] // withEnv
15:14:41 [2025-01-29T15:14:41.294Z] [Pipeline] archiveArtifacts
15:14:41 [2025-01-29T15:14:41.296Z] Archiving artifacts
15:14:43 [2025-01-29T15:14:43.790Z] [Pipeline] withEnv
15:14:43 [2025-01-29T15:14:43.791Z] [Pipeline] {
15:14:43 [2025-01-29T15:14:43.794Z] [Pipeline] sh
15:14:44 [2025-01-29T15:14:44.324Z] + set -xeuo pipefail
15:14:44 [2025-01-29T15:14:44.324Z] ++ umask
15:14:44 [2025-01-29T15:14:44.324Z] + '[' 0022 = 0000 ']'
15:14:44 [2025-01-29T15:14:44.324Z] + cd /home/jenkins/agent/workspace/test-override
15:14:44 [2025-01-29T15:14:44.324Z] + cosa shell -- /usr/lib/coreos-assembler/kola-junit --classname kola-upgrade-x86_64 --koladir /home/jenkins/agent/workspace/test-override/tmp/kola-rhrc4/kola-upgrade-x86_64 --output -
15:14:44 [2025-01-29T15:14:44.326Z] [Pipeline] }
15:14:44 [2025-01-29T15:14:44.328Z] [Pipeline] // withEnv
15:14:44 [2025-01-29T15:14:44.346Z] [Pipeline] junit
15:14:44 [2025-01-29T15:14:44.347Z] Recording test results
15:14:44 [2025-01-29T15:14:44.991Z] None of the test reports contained any result
15:14:44 [2025-01-29T15:14:44.993Z] [Pipeline] withEnv
15:14:44 [2025-01-29T15:14:44.993Z] [Pipeline] {
15:14:44 [2025-01-29T15:14:44.996Z] [Pipeline] sh
15:14:45 [2025-01-29T15:14:45.533Z] + set -xeuo pipefail
15:14:45 [2025-01-29T15:14:45.533Z] ++ umask
15:14:45 [2025-01-29T15:14:45.533Z] + '[' 0022 = 0000 ']'
15:14:45 [2025-01-29T15:14:45.533Z] + cd /home/jenkins/agent/workspace/test-override
15:14:45 [2025-01-29T15:14:45.533Z] + cosa shell -- test -d /home/jenkins/agent/workspace/test-override/tmp/kola-rhrc4/kola-x86_64
15:14:45 [2025-01-29T15:14:45.578Z] [Pipeline] }
15:14:45 [2025-01-29T15:14:45.580Z] [Pipeline] // withEnv
15:14:45 [2025-01-29T15:14:45.587Z] [Pipeline] withEnv
15:14:45 [2025-01-29T15:14:45.587Z] [Pipeline] {
15:14:45 [2025-01-29T15:14:45.590Z] [Pipeline] sh
15:14:46 [2025-01-29T15:14:46.121Z] + set -xeuo pipefail
15:14:46 [2025-01-29T15:14:46.122Z] ++ umask
15:14:46 [2025-01-29T15:14:46.122Z] + '[' 0022 = 0000 ']'
15:14:46 [2025-01-29T15:14:46.122Z] + cd /home/jenkins/agent/workspace/test-override
15:14:46 [2025-01-29T15:14:46.122Z] + cosa shell -- tar -C /home/jenkins/agent/workspace/test-override/tmp/kola-rhrc4 -c --xz kola-x86_64
15:14:52 [2025-01-29T15:14:52.637Z] [Pipeline] }
15:14:52 [2025-01-29T15:14:52.639Z] [Pipeline] // withEnv
15:14:52 [2025-01-29T15:14:52.645Z] [Pipeline] archiveArtifacts
15:14:52 [2025-01-29T15:14:52.648Z] Archiving artifacts
15:14:55 [2025-01-29T15:14:55.481Z] [Pipeline] withEnv
15:14:55 [2025-01-29T15:14:55.483Z] [Pipeline] {
15:14:55 [2025-01-29T15:14:55.486Z] [Pipeline] sh
15:14:56 [2025-01-29T15:14:56.029Z] + set -xeuo pipefail
15:14:56 [2025-01-29T15:14:56.029Z] ++ umask
15:14:56 [2025-01-29T15:14:56.029Z] + '[' 0022 = 0000 ']'
15:14:56 [2025-01-29T15:14:56.029Z] + cd /home/jenkins/agent/workspace/test-override
15:14:56 [2025-01-29T15:14:56.029Z] + cosa shell -- /usr/lib/coreos-assembler/kola-junit --classname kola-x86_64 --koladir /home/jenkins/agent/workspace/test-override/tmp/kola-rhrc4/kola-x86_64 --output -
15:14:56 [2025-01-29T15:14:56.032Z] [Pipeline] }
15:14:56 [2025-01-29T15:14:56.043Z] [Pipeline] // withEnv
15:14:56 [2025-01-29T15:14:56.049Z] [Pipeline] junit
15:14:56 [2025-01-29T15:14:56.051Z] Recording test results
15:14:56 [2025-01-29T15:14:56.094Z] None of the test reports contained any result
15:14:56 [2025-01-29T15:14:56.095Z] [Pipeline] withEnv
15:14:56 [2025-01-29T15:14:56.096Z] [Pipeline] {
15:14:56 [2025-01-29T15:14:56.100Z] [Pipeline] sh
15:14:56 [2025-01-29T15:14:56.637Z] + set -xeuo pipefail
15:14:56 [2025-01-29T15:14:56.637Z] ++ umask
15:14:56 [2025-01-29T15:14:56.637Z] + '[' 0022 = 0000 ']'
15:14:56 [2025-01-29T15:14:56.637Z] + cd /home/jenkins/agent/workspace/test-override
15:14:56 [2025-01-29T15:14:56.637Z] + cosa shell -- test -d /home/jenkins/agent/workspace/test-override/tmp/kola-rhrc4/kola-reprovision-x86_64
15:14:56 [2025-01-29T15:14:56.639Z] [Pipeline] }
15:14:56 [2025-01-29T15:14:56.642Z] [Pipeline] // withEnv
15:14:56 [2025-01-29T15:14:56.648Z] [Pipeline] withEnv
15:14:56 [2025-01-29T15:14:56.648Z] [Pipeline] {
15:14:56 [2025-01-29T15:14:56.717Z] [Pipeline] sh
15:14:57 [2025-01-29T15:14:57.245Z] + set -xeuo pipefail
15:14:57 [2025-01-29T15:14:57.245Z] ++ umask
15:14:57 [2025-01-29T15:14:57.245Z] + '[' 0022 = 0000 ']'
15:14:57 [2025-01-29T15:14:57.245Z] + cd /home/jenkins/agent/workspace/test-override
15:14:57 [2025-01-29T15:14:57.245Z] + cosa shell -- tar -C /home/jenkins/agent/workspace/test-override/tmp/kola-rhrc4 -c --xz kola-reprovision-x86_64
15:14:59 [2025-01-29T15:14:59.755Z] [Pipeline] }
15:14:59 [2025-01-29T15:14:59.759Z] [Pipeline] // withEnv
15:14:59 [2025-01-29T15:14:59.765Z] [Pipeline] archiveArtifacts
15:14:59 [2025-01-29T15:14:59.767Z] Archiving artifacts
15:15:00 [2025-01-29T15:15:00.043Z] [Pipeline] withEnv
15:15:00 [2025-01-29T15:15:00.044Z] [Pipeline] {
15:15:00 [2025-01-29T15:15:00.047Z] [Pipeline] sh
15:15:00 [2025-01-29T15:15:00.593Z] + set -xeuo pipefail
15:15:00 [2025-01-29T15:15:00.593Z] ++ umask
15:15:00 [2025-01-29T15:15:00.593Z] + '[' 0022 = 0000 ']'
15:15:00 [2025-01-29T15:15:00.593Z] + cd /home/jenkins/agent/workspace/test-override
15:15:00 [2025-01-29T15:15:00.593Z] + cosa shell -- /usr/lib/coreos-assembler/kola-junit --classname kola-reprovision-x86_64 --koladir /home/jenkins/agent/workspace/test-override/tmp/kola-rhrc4/kola-reprovision-x86_64 --output -
15:15:00 [2025-01-29T15:15:00.594Z] [Pipeline] }
15:15:00 [2025-01-29T15:15:00.597Z] [Pipeline] // withEnv
15:15:00 [2025-01-29T15:15:00.603Z] [Pipeline] junit
15:15:00 [2025-01-29T15:15:00.604Z] Recording test results
15:15:00 [2025-01-29T15:15:00.809Z] None of the test reports contained any result
15:15:00 [2025-01-29T15:15:00.821Z] [Pipeline] stage
15:15:00 [2025-01-29T15:15:00.822Z] [Pipeline] { (x86_64:Build Metal)
15:15:00 [2025-01-29T15:15:00.826Z] [Pipeline] withEnv
15:15:00 [2025-01-29T15:15:00.826Z] [Pipeline] {
15:15:00 [2025-01-29T15:15:00.829Z] [Pipeline] sh
15:15:01 [2025-01-29T15:15:01.357Z] + set -xeuo pipefail
15:15:01 [2025-01-29T15:15:01.357Z] ++ umask
15:15:01 [2025-01-29T15:15:01.357Z] + '[' 0022 = 0000 ']'
15:15:01 [2025-01-29T15:15:01.357Z] + cosa buildextend-metal
15:15:01 [2025-01-29T15:15:01.357Z] info: Missing CAP_SYS_ADMIN; using virt
15:15:01 [2025-01-29T15:15:01.609Z] Config commit: 259333b8cfb98d8d3260759241ae9d56772ed936
15:15:01 [2025-01-29T15:15:01.609Z] Using manifest: /home/jenkins/agent/workspace/test-override/src/config/manifest.yaml
15:15:02 [2025-01-29T15:15:02.530Z] Will build metal
15:15:20 [2025-01-29T15:15:20.535Z] ++ arch
15:15:20 [2025-01-29T15:15:20.536Z] + osbuild-mpp -D 'arch="x86_64"' -D 'artifact_name_prefix="fedora-coreos-42.20250129.dev.0"' -D 'ostree_ref=""' -D 'ostree_repo=""' -D 'ociarchive="/home/jenkins/agent/workspace/test-override/builds/42.20250129.dev.0/x86_64/fedora-coreos-42.20250129.dev.0-ostree.x86_64.ociarchive"' -D 'osname="fedora-coreos"' -D 'container_imgref="ostree-image-signed:oci-archive:/fedora-coreos-42.20250129.dev.0-ostree.x86_64.ociarchive"' -D 'container_repo=""' -D 'container_tag=""' -D 'extra_kargs="mitigations=auto,nosmt"' -D metal_image_size_mb=2621 -D cloud_image_size_mb=10240 -D rootfs_size_mb=2108 /usr/lib/coreos-assembler/osbuild-manifests/coreos.osbuild.x86_64.mpp.yaml /tmp/osbuild-esCP.json
15:15:25 [2025-01-29T15:15:25.758Z] + set +x
15:15:25 [2025-01-29T15:15:25.758Z] + osbuild --out cache/osbuild/out --store cache/osbuild/store --cache-max-size 20GiB --checkpoint deployed-tree --checkpoint tree --checkpoint raw-image --checkpoint metal --checkpoint metal4k --export=metal /tmp/osbuild-esCP.json
15:15:25 [2025-01-29T15:15:25.758Z] 
15:15:25 [2025-01-29T15:15:25.758Z] Filesystem      Size  Used Avail Use% Mounted on
15:15:25 [2025-01-29T15:15:25.758Z] /dev/vdb1        30G  8.1G   22G  27% /home/jenkins/agent/workspace/test-override/cache
15:15:25 [2025-01-29T15:15:25.758Z] 
15:15:25 [2025-01-29T15:15:25.758Z] starting /tmp/osbuild-esCP.jsonPipeline source org.osbuild.curl: 0be7f26519a317a5e14e6f91191b7c79042a5ae2d5ffcc10d318f08214dc66d8
15:15:25 [2025-01-29T15:15:25.758Z] Build
15:15:25 [2025-01-29T15:15:25.758Z]   root: <host>
15:15:25 [2025-01-29T15:15:25.758Z] 
15:15:25 [2025-01-29T15:15:25.758Z] ���  Duration: 1738163725s
15:15:25 [2025-01-29T15:15:25.758Z] Pipeline raw-metal-image: 4a9effa0bef3914032053f5e38b53464c8461edc796a5170f4d579067f97082d
15:15:25 [2025-01-29T15:15:25.758Z] Build
15:15:25 [2025-01-29T15:15:25.758Z]   root: <host>
15:15:25 [2025-01-29T15:15:25.758Z]   runner: org.osbuild.fedora38 (org.osbuild.fedora38)
15:15:25 [2025-01-29T15:15:25.758Z] org.osbuild.copy: 8ba406f598a7f00104c619b65f03269087ba59bc2585b656431da96ac98f092c {
15:15:25 [2025-01-29T15:15:25.758Z]   "paths": [
15:15:25 [2025-01-29T15:15:25.758Z]     {
15:15:25 [2025-01-29T15:15:25.758Z]       "from": "input://tree/disk.img",
15:15:25 [2025-01-29T15:15:25.758Z]       "to": "tree:///disk.img"
15:15:25 [2025-01-29T15:15:25.758Z]     }
15:15:25 [2025-01-29T15:15:25.758Z]   ]
15:15:25 [2025-01-29T15:15:25.758Z] }
15:15:25 [2025-01-29T15:15:25.758Z] Failed to open file "/sys/fs/selinux/checkreqprot": Read-only file system
15:15:26 [2025-01-29T15:15:26.011Z] copying '/run/osbuild/inputs/tree/disk.img' -> '/run/osbuild/tree/disk.img'
15:15:26 [2025-01-29T15:15:26.011Z] 
15:15:26 [2025-01-29T15:15:26.011Z] ���  Duration: 0s
15:15:26 [2025-01-29T15:15:26.011Z] org.osbuild.coreos.platform: 4a9effa0bef3914032053f5e38b53464c8461edc796a5170f4d579067f97082d {
15:15:26 [2025-01-29T15:15:26.011Z]   "platform": "metal"
15:15:26 [2025-01-29T15:15:26.011Z] }
15:15:26 [2025-01-29T15:15:26.263Z] device/disk (org.osbuild.loopback): loop0 acquired (locked: False)
15:15:26 [2025-01-29T15:15:26.263Z] mount/root (org.osbuild.xfs): mounting /dev/loop0p4 -> /home/jenkins/agent/workspace/test-override/cache/osbuild/store/tmp/buildroot-tmp-7o7bm6ia/mounts/
15:15:26 [2025-01-29T15:15:26.516Z] mount/ostree.deployment (org.osbuild.ostree.deployment): Deployment root at 'ostree/deploy/fedora-coreos/deploy/5c17cd7b0c9e1318d314543654d242413e8e08f2c45938f6aae1cb09deedf388.0'
15:15:26 [2025-01-29T15:15:26.768Z] mount/boot (org.osbuild.ext4): mounting /dev/loop0p3 -> /home/jenkins/agent/workspace/test-override/cache/osbuild/store/tmp/buildroot-tmp-7o7bm6ia/mounts/boot
15:15:26 [2025-01-29T15:15:26.768Z] Failed to open file "/sys/fs/selinux/checkreqprot": Read-only file system
15:15:27 [2025-01-29T15:15:27.021Z] mount/boot (org.osbuild.ext4): umount: /home/jenkins/agent/workspace/test-override/cache/osbuild/store/tmp/buildroot-tmp-7o7bm6ia/mounts/boot unmounted
15:15:27 [2025-01-29T15:15:27.021Z] mount/ostree.deployment (org.osbuild.ostree.deployment): mounting /home/jenkins/agent/workspace/test-override/cache/osbuild/store/tmp/buildroot-tmp-7o7bm6ia/mounts/ostree/deploy/fedora-coreos/deploy/5c17cd7b0c9e1318d314543654d242413e8e08f2c45938f6aae1cb09deedf388.0 -> /home/jenkins/agent/workspace/test-override/cache/osbuild/store/tmp/buildroot-tmp-7o7bm6ia/mounts
15:15:27 [2025-01-29T15:15:27.021Z] mount/ostree.deployment (org.osbuild.ostree.deployment): umount: /home/jenkins/agent/workspace/test-override/cache/osbuild/store/tmp/buildroot-tmp-7o7bm6ia/mounts/boot unmounted
15:15:27 [2025-01-29T15:15:27.021Z] mount/ostree.deployment (org.osbuild.ostree.deployment): umount: /home/jenkins/agent/workspace/test-override/cache/osbuild/store/tmp/buildroot-tmp-7o7bm6ia/mounts/var unmounted
15:15:27 [2025-01-29T15:15:27.021Z] mount/ostree.deployment (org.osbuild.ostree.deployment): umount: /home/jenkins/agent/workspace/test-override/cache/osbuild/store/tmp/buildroot-tmp-7o7bm6ia/mounts/sysroot unmounted
15:15:27 [2025-01-29T15:15:27.021Z] mount/ostree.deployment (org.osbuild.ostree.deployment): umount: /home/jenkins/agent/workspace/test-override/cache/osbuild/store/tmp/buildroot-tmp-7o7bm6ia/mounts unmounted
15:15:27 [2025-01-29T15:15:27.021Z] mount/ostree.deployment (org.osbuild.ostree.deployment): umount: /home/jenkins/agent/workspace/test-override/cache/osbuild/store/tmp/buildroot-tmp-7o7bm6ia/mounts unmounted
15:15:27 [2025-01-29T15:15:27.021Z] mount/ostree.deployment (org.osbuild.ostree.deployment): umount: /home/jenkins/agent/workspace/test-override/cache/osbuild/store/tmp/buildroot-tmp-7o7bm6ia/mounts unmounted
15:15:27 [2025-01-29T15:15:27.021Z] mount/root (org.osbuild.xfs): already unmounted: /home/jenkins/agent/workspace/test-override/cache/osbuild/store/tmp/buildroot-tmp-7o7bm6ia/mounts/
15:15:27 [2025-01-29T15:15:27.021Z] 
15:15:27 [2025-01-29T15:15:27.022Z] ���  Duration: 0s
15:15:27 [2025-01-29T15:15:27.022Z] Pipeline metal: 35d5aa7b1da8c9d90bfa3f2286b5d1d9e5a8b0150b0fc264d4caa73f6b2a1b95
15:15:27 [2025-01-29T15:15:27.022Z] Build
15:15:27 [2025-01-29T15:15:27.022Z]   root: <host>
15:15:27 [2025-01-29T15:15:27.022Z]   runner: org.osbuild.fedora38 (org.osbuild.fedora38)
15:15:27 [2025-01-29T15:15:27.022Z] org.osbuild.copy: 35d5aa7b1da8c9d90bfa3f2286b5d1d9e5a8b0150b0fc264d4caa73f6b2a1b95 {
15:15:27 [2025-01-29T15:15:27.022Z]   "paths": [
15:15:27 [2025-01-29T15:15:27.022Z]     {
15:15:27 [2025-01-29T15:15:27.022Z]       "from": "input://tree/disk.img",
15:15:27 [2025-01-29T15:15:27.022Z]       "to": "tree:///fedora-coreos-42.20250129.dev.0-metal.x86_64.raw"
15:15:27 [2025-01-29T15:15:27.022Z]     }
15:15:27 [2025-01-29T15:15:27.022Z]   ]
15:15:27 [2025-01-29T15:15:27.022Z] }
15:15:27 [2025-01-29T15:15:27.274Z] Failed to open file "/sys/fs/selinux/checkreqprot": Read-only file system
15:15:27 [2025-01-29T15:15:27.274Z] copying '/run/osbuild/inputs/tree/disk.img' -> '/run/osbuild/tree/fedora-coreos-42.20250129.dev.0-metal.x86_64.raw'
15:15:27 [2025-01-29T15:15:27.527Z] 
15:15:27 [2025-01-29T15:15:27.527Z] ���  Duration: 0s
15:15:27 [2025-01-29T15:15:27.527Z] manifest /tmp/osbuild-esCP.json finished successfully
15:15:27 [2025-01-29T15:15:27.780Z] oci-archive:	c197c9ee61c31f1aeed376a79e2a03c2054b8ce9080f61c08566842ad0a8facb
15:15:27 [2025-01-29T15:15:27.780Z] deployed-tree:	3e323a1c950686dcf6dadac3cbc5d7c58c02014b6eab542cf3627d43fc74ccbe
15:15:27 [2025-01-29T15:15:27.780Z] tree:     	94d637f8de5b7cad84c69b5af487c53e6981eb9fac35204eda09e5af8ce61440
15:15:27 [2025-01-29T15:15:27.780Z] raw-image:	ff22d43b593a6337ed834121cb855a25554611d8865b14d128e39593b96edd6b
15:15:27 [2025-01-29T15:15:27.780Z] raw-4k-image:	50680732cbb065c694c50acfac4406254e9f22d3912f4d0570875dba9cbbabeb
15:15:27 [2025-01-29T15:15:27.780Z] raw-applehv-image:	8d0388c720eecbccc9809e59537500de1d6a55cc013786710e84ba9864a4b97d
15:15:27 [2025-01-29T15:15:27.780Z] applehv:  	c11e6597f2cfb9e1b96a65b9b4761c68b8fbe1ba0f6fb3eed2825445237a20fa
15:15:27 [2025-01-29T15:15:27.780Z] raw-gcp-image:	46335ae809fe0fa756004f6b74504cff1b5f0315495b65ec043fd164fe6e9dd7
15:15:27 [2025-01-29T15:15:27.780Z] raw-gcp-image-tar:	56eebed501f518683441a38b89f97a60b8f968b9410c3358183af388ff9e4ea3
15:15:27 [2025-01-29T15:15:27.780Z] gcp:      	ae70874df55896fdda7ef734816798388397b82d0d0d483f80cb1afb9029fcdd
15:15:27 [2025-01-29T15:15:27.780Z] raw-hyperv-image:	faf27692ab6dadaf8f0e8a252a2a365aa7fa9b2be4b287947515986dc1986c80
15:15:27 [2025-01-29T15:15:27.780Z] hyperv:   	2a29f4c354774178f7f11229d4b5f646d54319f726461c07e3ce2bdba9abab7f
15:15:27 [2025-01-29T15:15:27.780Z] raw-metal-image:	4a9effa0bef3914032053f5e38b53464c8461edc796a5170f4d579067f97082d
15:15:27 [2025-01-29T15:15:27.780Z] metal:    	35d5aa7b1da8c9d90bfa3f2286b5d1d9e5a8b0150b0fc264d4caa73f6b2a1b95
15:15:27 [2025-01-29T15:15:27.780Z] raw-metal4k-image:	e4db9f0ac56832bf483620a7dbca0b79a2016d22f31e0039261d5e988b3c1dec
15:15:27 [2025-01-29T15:15:27.780Z] metal4k:  	b3524d073848f7cca7e81c9c8c6b8eec8f39dec5980d714312ec323d81c44f57
15:15:27 [2025-01-29T15:15:27.780Z] raw-qemu-image:	a6cec666378ed7cee1af568a48d61c82814abd1310acae1f548609dd9d01f369
15:15:27 [2025-01-29T15:15:27.780Z] qemu:     	6cc5c17a930efe34358cad86feafbbd04a4cab664b81c157c956bd438eb522e3
15:15:27 [2025-01-29T15:15:27.780Z] live:     	ecc17e3c6db2c0c1bdd4576eba3c00c5e4c0a3c2189b34557e886f36bf91fbdc
15:15:27 [2025-01-29T15:15:27.780Z] + set +x
15:15:34 [2025-01-29T15:15:34.291Z] mv: failed to preserve ownership for '/home/jenkins/agent/workspace/test-override/tmp/build.metal/tmp.nmUXAIxTdd/metal/fedora-coreos-42.20250129.dev.0-metal.x86_64.raw': Operation not permitted
15:15:34 [2025-01-29T15:15:34.291Z] mv: failed to preserve ownership for '/home/jenkins/agent/workspace/test-override/tmp/build.metal/tmp.nmUXAIxTdd/metal': Operation not permitted
15:15:40 [2025-01-29T15:15:40.800Z] /home/jenkins/agent/workspace/test-override/builds/42.20250129.dev.0/x86_64/meta.json wrote with version stamp 1738163739984951742
15:15:40 [2025-01-29T15:15:40.800Z] Successfully generated: fedora-coreos-42.20250129.dev.0-metal.x86_64.raw
15:15:40 [2025-01-29T15:15:40.802Z] [Pipeline] }
15:15:40 [2025-01-29T15:15:40.805Z] [Pipeline] // withEnv
15:15:40 [2025-01-29T15:15:40.822Z] [Pipeline] withEnv
15:15:40 [2025-01-29T15:15:40.822Z] [Pipeline] {
15:15:40 [2025-01-29T15:15:40.827Z] [Pipeline] sh
15:15:41 [2025-01-29T15:15:41.360Z] + set -xeuo pipefail
15:15:41 [2025-01-29T15:15:41.360Z] ++ umask
15:15:41 [2025-01-29T15:15:41.360Z] + '[' 0022 = 0000 ']'
15:15:41 [2025-01-29T15:15:41.360Z] + cosa buildextend-metal4k
15:15:41 [2025-01-29T15:15:41.360Z] info: Missing CAP_SYS_ADMIN; using virt
15:15:41 [2025-01-29T15:15:41.614Z] Config commit: 259333b8cfb98d8d3260759241ae9d56772ed936
15:15:41 [2025-01-29T15:15:41.614Z] Using manifest: /home/jenkins/agent/workspace/test-override/src/config/manifest.yaml
15:15:42 [2025-01-29T15:15:42.532Z] Will build metal4k
15:16:00 [2025-01-29T15:16:00.539Z] ++ arch
15:16:00 [2025-01-29T15:16:00.539Z] + osbuild-mpp -D 'arch="x86_64"' -D 'artifact_name_prefix="fedora-coreos-42.20250129.dev.0"' -D 'ostree_ref=""' -D 'ostree_repo=""' -D 'ociarchive="/home/jenkins/agent/workspace/test-override/builds/42.20250129.dev.0/x86_64/fedora-coreos-42.20250129.dev.0-ostree.x86_64.ociarchive"' -D 'osname="fedora-coreos"' -D 'container_imgref="ostree-image-signed:oci-archive:/fedora-coreos-42.20250129.dev.0-ostree.x86_64.ociarchive"' -D 'container_repo=""' -D 'container_tag=""' -D 'extra_kargs="mitigations=auto,nosmt"' -D metal_image_size_mb=2621 -D cloud_image_size_mb=10240 -D rootfs_size_mb=2108 /usr/lib/coreos-assembler/osbuild-manifests/coreos.osbuild.x86_64.mpp.yaml /tmp/osbuild-Zkmf.json
15:16:01 [2025-01-29T15:16:01.458Z] + set +x
15:16:01 [2025-01-29T15:16:01.458Z] + osbuild --out cache/osbuild/out --store cache/osbuild/store --cache-max-size 20GiB --checkpoint deployed-tree --checkpoint tree --checkpoint raw-image --checkpoint metal --checkpoint metal4k --export=metal4k /tmp/osbuild-Zkmf.json
15:16:01 [2025-01-29T15:16:01.458Z] 
15:16:01 [2025-01-29T15:16:01.458Z] Filesystem      Size  Used Avail Use% Mounted on
15:16:01 [2025-01-29T15:16:01.458Z] /dev/vdb1        30G  8.1G   22G  27% /home/jenkins/agent/workspace/test-override/cache
15:16:01 [2025-01-29T15:16:01.458Z] 
15:16:01 [2025-01-29T15:16:01.720Z] starting /tmp/osbuild-Zkmf.jsonPipeline source org.osbuild.curl: 0be7f26519a317a5e14e6f91191b7c79042a5ae2d5ffcc10d318f08214dc66d8
15:16:01 [2025-01-29T15:16:01.720Z] Build
15:16:01 [2025-01-29T15:16:01.720Z]   root: <host>
15:16:01 [2025-01-29T15:16:01.720Z] 
15:16:01 [2025-01-29T15:16:01.720Z] ���  Duration: 1738163761s
15:16:01 [2025-01-29T15:16:01.720Z] Pipeline raw-4k-image: 50680732cbb065c694c50acfac4406254e9f22d3912f4d0570875dba9cbbabeb
15:16:01 [2025-01-29T15:16:01.720Z] Build
15:16:01 [2025-01-29T15:16:01.720Z]   root: <host>
15:16:01 [2025-01-29T15:16:01.720Z]   runner: org.osbuild.fedora38 (org.osbuild.fedora38)
15:16:01 [2025-01-29T15:16:01.720Z] org.osbuild.truncate: b1389bc1c144da0a912504fa1a049d168ce9a7436d886aedb38f06a94c89cb51 {
15:16:01 [2025-01-29T15:16:01.720Z]   "filename": "disk.img",
15:16:01 [2025-01-29T15:16:01.720Z]   "size": "2748317696"
15:16:01 [2025-01-29T15:16:01.720Z] }
15:16:01 [2025-01-29T15:16:01.973Z] Failed to open file "/sys/fs/selinux/checkreqprot": Read-only file system
15:16:02 [2025-01-29T15:16:02.226Z] 
15:16:02 [2025-01-29T15:16:02.226Z] ���  Duration: 0s
15:16:02 [2025-01-29T15:16:02.226Z] org.osbuild.sfdisk: 7f5c201a82ed2bf5897b3fa88901e7bd96450e9d4a2dedcd7623e12ff5ded1a5 {
15:16:02 [2025-01-29T15:16:02.226Z]   "uuid": "00000000-0000-4000-a000-000000000001",
15:16:02 [2025-01-29T15:16:02.226Z]   "label": "gpt",
15:16:02 [2025-01-29T15:16:02.226Z]   "partitions": [
15:16:02 [2025-01-29T15:16:02.226Z]     {
15:16:02 [2025-01-29T15:16:02.226Z]       "start": 256,
15:16:02 [2025-01-29T15:16:02.226Z]       "partnum": 1,
15:16:02 [2025-01-29T15:16:02.226Z]       "size": 256,
15:16:02 [2025-01-29T15:16:02.226Z]       "type": "21686148-6449-6E6F-744E-656564454649",
15:16:02 [2025-01-29T15:16:02.226Z]       "bootable": true,
15:16:02 [2025-01-29T15:16:02.226Z]       "name": "BIOS-BOOT"
15:16:02 [2025-01-29T15:16:02.226Z]     },
15:16:02 [2025-01-29T15:16:02.226Z]     {
15:16:02 [2025-01-29T15:16:02.226Z]       "start": 512,
15:16:02 [2025-01-29T15:16:02.226Z]       "partnum": 2,
15:16:02 [2025-01-29T15:16:02.226Z]       "size": 32512,
15:16:02 [2025-01-29T15:16:02.226Z]       "type": "C12A7328-F81F-11D2-BA4B-00A0C93EC93B",
15:16:02 [2025-01-29T15:16:02.226Z]       "name": "EFI-SYSTEM"
15:16:02 [2025-01-29T15:16:02.226Z]     },
15:16:02 [2025-01-29T15:16:02.226Z]     {
15:16:02 [2025-01-29T15:16:02.226Z]       "start": 33024,
15:16:02 [2025-01-29T15:16:02.226Z]       "partnum": 3,
15:16:02 [2025-01-29T15:16:02.226Z]       "size": 98304,
15:16:02 [2025-01-29T15:16:02.226Z]       "type": "0FC63DAF-8483-4772-8E79-3D69D8477DE4",
15:16:02 [2025-01-29T15:16:02.226Z]       "name": "boot"
15:16:02 [2025-01-29T15:16:02.226Z]     },
15:16:02 [2025-01-29T15:16:02.226Z]     {
15:16:02 [2025-01-29T15:16:02.226Z]       "start": 131328,
15:16:02 [2025-01-29T15:16:02.226Z]       "partnum": 4,
15:16:02 [2025-01-29T15:16:02.226Z]       "size": 539392,
15:16:02 [2025-01-29T15:16:02.226Z]       "type": "0FC63DAF-8483-4772-8E79-3D69D8477DE4",
15:16:02 [2025-01-29T15:16:02.226Z]       "name": "root"
15:16:02 [2025-01-29T15:16:02.226Z]     }
15:16:02 [2025-01-29T15:16:02.226Z]   ]
15:16:02 [2025-01-29T15:16:02.226Z] }
15:16:02 [2025-01-29T15:16:02.226Z] device/device (org.osbuild.loopback): loop0 acquired (locked: False)
15:16:02 [2025-01-29T15:16:02.478Z] Failed to open file "/sys/fs/selinux/checkreqprot": Read-only file system
15:16:02 [2025-01-29T15:16:02.478Z] label: gpt
15:16:02 [2025-01-29T15:16:02.478Z] label-id: 00000000-0000-4000-a000-000000000001
15:16:02 [2025-01-29T15:16:02.478Z] /dev/loop0p1: start="256", size="256", type="21686148-6449-6E6F-744E-656564454649", name="BIOS-BOOT", bootable
15:16:02 [2025-01-29T15:16:02.478Z] /dev/loop0p2: start="512", size="32512", type="C12A7328-F81F-11D2-BA4B-00A0C93EC93B", name="EFI-SYSTEM"
15:16:02 [2025-01-29T15:16:02.478Z] /dev/loop0p3: start="33024", size="98304", type="0FC63DAF-8483-4772-8E79-3D69D8477DE4", name="boot"
15:16:02 [2025-01-29T15:16:02.478Z] /dev/loop0p4: start="131328", size="539392", type="0FC63DAF-8483-4772-8E79-3D69D8477DE4", name="root"
15:16:02 [2025-01-29T15:16:02.479Z] {
15:16:02 [2025-01-29T15:16:02.479Z]    "partitiontable": {
15:16:02 [2025-01-29T15:16:02.479Z]       "label": "gpt",
15:16:02 [2025-01-29T15:16:02.479Z]       "id": "00000000-0000-4000-A000-000000000001",
15:16:02 [2025-01-29T15:16:02.479Z]       "device": "/dev/loop0",
15:16:02 [2025-01-29T15:16:02.479Z]       "unit": "sectors",
15:16:02 [2025-01-29T15:16:02.479Z]       "firstlba": 256,
15:16:02 [2025-01-29T15:16:02.479Z]       "lastlba": 670970,
15:16:02 [2025-01-29T15:16:02.479Z]       "sectorsize": 4096,
15:16:02 [2025-01-29T15:16:02.479Z]       "partitions": [
15:16:02 [2025-01-29T15:16:02.479Z]          {
15:16:02 [2025-01-29T15:16:02.479Z]             "node": "/dev/loop0p1",
15:16:02 [2025-01-29T15:16:02.479Z]             "start": 256,
15:16:02 [2025-01-29T15:16:02.479Z]             "size": 256,
15:16:02 [2025-01-29T15:16:02.479Z]             "type": "21686148-6449-6E6F-744E-656564454649",
15:16:02 [2025-01-29T15:16:02.479Z]             "uuid": "E9D44EBC-BA15-4C1A-8BB7-F94D4BFCC1BE",
15:16:02 [2025-01-29T15:16:02.479Z]             "name": "BIOS-BOOT"
15:16:02 [2025-01-29T15:16:02.479Z]          },{
15:16:02 [2025-01-29T15:16:02.479Z]             "node": "/dev/loop0p2",
15:16:02 [2025-01-29T15:16:02.479Z]             "start": 512,
15:16:02 [2025-01-29T15:16:02.479Z]             "size": 32512,
15:16:02 [2025-01-29T15:16:02.479Z]             "type": "C12A7328-F81F-11D2-BA4B-00A0C93EC93B",
15:16:02 [2025-01-29T15:16:02.479Z]             "uuid": "DA6BE06A-2DEF-4799-8D57-3E0A33118F68",
15:16:02 [2025-01-29T15:16:02.479Z]             "name": "EFI-SYSTEM"
15:16:02 [2025-01-29T15:16:02.479Z]          },{
15:16:02 [2025-01-29T15:16:02.479Z]             "node": "/dev/loop0p3",
15:16:02 [2025-01-29T15:16:02.479Z]             "start": 33024,
15:16:02 [2025-01-29T15:16:02.479Z]             "size": 98304,
15:16:02 [2025-01-29T15:16:02.479Z]             "type": "0FC63DAF-8483-4772-8E79-3D69D8477DE4",
15:16:02 [2025-01-29T15:16:02.479Z]             "uuid": "545DD20A-977F-4F9B-9DD7-3738C35E5617",
15:16:02 [2025-01-29T15:16:02.479Z]             "name": "boot"
15:16:02 [2025-01-29T15:16:02.479Z]          },{
15:16:02 [2025-01-29T15:16:02.479Z]             "node": "/dev/loop0p4",
15:16:02 [2025-01-29T15:16:02.479Z]             "start": 131328,
15:16:02 [2025-01-29T15:16:02.479Z]             "size": 539392,
15:16:02 [2025-01-29T15:16:02.479Z]             "type": "0FC63DAF-8483-4772-8E79-3D69D8477DE4",
15:16:02 [2025-01-29T15:16:02.479Z]             "uuid": "E4161299-DB3D-4C49-9038-FD5FF858AB69",
15:16:02 [2025-01-29T15:16:02.479Z]             "name": "root"
15:16:02 [2025-01-29T15:16:02.479Z]          }
15:16:02 [2025-01-29T15:16:02.479Z]       ]
15:16:02 [2025-01-29T15:16:02.479Z]    }
15:16:02 [2025-01-29T15:16:02.479Z] }
15:16:02 [2025-01-29T15:16:02.479Z] 
15:16:02 [2025-01-29T15:16:02.479Z] ���  Duration: 0s
15:16:02 [2025-01-29T15:16:02.479Z] org.osbuild.mkfs.fat: 1119eb05668522225da16393ee5d8f47bda3c90df903dfb3fa617990f2c1af9a {
15:16:02 [2025-01-29T15:16:02.479Z]   "label": "EFI-SYSTEM",
15:16:02 [2025-01-29T15:16:02.479Z]   "volid": "7B7795E7"
15:16:02 [2025-01-29T15:16:02.479Z] }
15:16:02 [2025-01-29T15:16:02.731Z] device/device (org.osbuild.loopback): loop0 acquired (locked: True)
15:16:02 [2025-01-29T15:16:02.731Z] Failed to open file "/sys/fs/selinux/checkreqprot": Read-only file system
15:16:02 [2025-01-29T15:16:02.984Z] mkfs.fat 4.2 (2021-01-31)
15:16:02 [2025-01-29T15:16:02.984Z] 
15:16:02 [2025-01-29T15:16:02.984Z] ���  Duration: 0s
15:16:02 [2025-01-29T15:16:02.984Z] org.osbuild.mkfs.ext4: e40f26fdd4a6401243a4f7a7e66d71729c7d31f7c04df5fab650d844919fb1ca {
15:16:02 [2025-01-29T15:16:02.984Z]   "uuid": "96d15588-3596-4b3c-adca-a2ff7279ea63",
15:16:02 [2025-01-29T15:16:02.984Z]   "label": "boot",
15:16:02 [2025-01-29T15:16:02.984Z]   "metadata_csum_seed": true
15:16:02 [2025-01-29T15:16:02.984Z] }
15:16:02 [2025-01-29T15:16:02.984Z] device/device (org.osbuild.loopback): loop0 acquired (locked: True)
15:16:03 [2025-01-29T15:16:03.238Z] Failed to open file "/sys/fs/selinux/checkreqprot": Read-only file system
15:16:03 [2025-01-29T15:16:03.238Z] mke2fs 1.47.1 (20-May-2024)
15:16:03 [2025-01-29T15:16:03.238Z] Discarding device blocks:     0/98304           done                            
15:16:03 [2025-01-29T15:16:03.238Z] Creating filesystem with 98304 4k blocks and 98304 inodes
15:16:03 [2025-01-29T15:16:03.238Z] Filesystem UUID: 96d15588-3596-4b3c-adca-a2ff7279ea63
15:16:03 [2025-01-29T15:16:03.238Z] Superblock backups stored on blocks: 
15:16:03 [2025-01-29T15:16:03.238Z] 	32768
15:16:03 [2025-01-29T15:16:03.238Z] 
15:16:03 [2025-01-29T15:16:03.238Z] Allocating group tables: 0/3   done                            
15:16:03 [2025-01-29T15:16:03.238Z] Writing inode tables: 0/3   done                            
15:16:03 [2025-01-29T15:16:03.238Z] Creating journal (4096 blocks): done
15:16:03 [2025-01-29T15:16:03.238Z] Writing superblocks and filesystem accounting information: 0/3   done
15:16:03 [2025-01-29T15:16:03.238Z] 
15:16:03 [2025-01-29T15:16:03.238Z] 
15:16:03 [2025-01-29T15:16:03.238Z] ���  Duration: 0s
15:16:03 [2025-01-29T15:16:03.238Z] org.osbuild.mkfs.xfs: 8ab56f495be7fa8d8f0035e3b5ea9e0881fee203ca75ff2252eda1d77e2cfec8 {
15:16:03 [2025-01-29T15:16:03.238Z]   "uuid": "910678ff-f77e-4a7d-8d53-86f2ac47a823",
15:16:03 [2025-01-29T15:16:03.238Z]   "label": "root"
15:16:03 [2025-01-29T15:16:03.238Z] }
15:16:03 [2025-01-29T15:16:03.490Z] device/device (org.osbuild.loopback): loop0 acquired (locked: True)
15:16:03 [2025-01-29T15:16:03.490Z] Failed to open file "/sys/fs/selinux/checkreqprot": Read-only file system
15:16:03 [2025-01-29T15:16:03.743Z] meta-data=/dev/loop0             isize=512    agcount=4, agsize=134848 blks
15:16:03 [2025-01-29T15:16:03.743Z]          =                       sectsz=4096  attr=2, projid32bit=1
15:16:03 [2025-01-29T15:16:03.743Z]          =                       crc=1        finobt=1, sparse=1, rmapbt=1
15:16:03 [2025-01-29T15:16:03.743Z]          =                       reflink=1    bigtime=1 inobtcount=1 nrext64=1
15:16:03 [2025-01-29T15:16:03.743Z] data     =                       bsize=4096   blocks=539392, imaxpct=25
15:16:03 [2025-01-29T15:16:03.743Z]          =                       sunit=0      swidth=0 blks
15:16:03 [2025-01-29T15:16:03.743Z] naming   =version 2              bsize=4096   ascii-ci=0, ftype=1
15:16:03 [2025-01-29T15:16:03.743Z] log      =internal log           bsize=4096   blocks=16384, version=2
15:16:03 [2025-01-29T15:16:03.743Z]          =                       sectsz=4096  sunit=1 blks, lazy-count=1
15:16:03 [2025-01-29T15:16:03.743Z] realtime =none                   extsz=4096   blocks=0, rtextents=0
15:16:03 [2025-01-29T15:16:03.743Z] Discarding blocks...Done.
15:16:03 [2025-01-29T15:16:03.743Z] 
15:16:03 [2025-01-29T15:16:03.743Z] ���  Duration: 0s
15:16:03 [2025-01-29T15:16:03.743Z] org.osbuild.mkdir: 8802d860c18e3f60f768fc6b9954eec1892a09c887e1b9faa15d0fb9e67219a8 {
15:16:03 [2025-01-29T15:16:03.743Z]   "paths": [
15:16:03 [2025-01-29T15:16:03.743Z]     {
15:16:03 [2025-01-29T15:16:03.743Z]       "path": "mount://root/boot",
15:16:03 [2025-01-29T15:16:03.743Z]       "mode": 493
15:16:03 [2025-01-29T15:16:03.743Z]     },
15:16:03 [2025-01-29T15:16:03.743Z]     {
15:16:03 [2025-01-29T15:16:03.743Z]       "path": "mount://boot/efi",
15:16:03 [2025-01-29T15:16:03.743Z]       "mode": 493
15:16:03 [2025-01-29T15:16:03.743Z]     }
15:16:03 [2025-01-29T15:16:03.743Z]   ]
15:16:03 [2025-01-29T15:16:03.743Z] }
15:16:03 [2025-01-29T15:16:03.743Z] device/disk (org.osbuild.loopback): loop0 acquired (locked: False)
15:16:03 [2025-01-29T15:16:03.996Z] mount/root (org.osbuild.xfs): mounting /dev/loop0p4 -> /home/jenkins/agent/workspace/test-override/cache/osbuild/store/tmp/buildroot-tmp-44edf4ou/mounts/root-mount-point
15:16:03 [2025-01-29T15:16:03.996Z] mount/boot (org.osbuild.ext4): mounting /dev/loop0p3 -> /home/jenkins/agent/workspace/test-override/cache/osbuild/store/tmp/buildroot-tmp-44edf4ou/mounts/boot-mount-point
15:16:03 [2025-01-29T15:16:03.996Z] Failed to open file "/sys/fs/selinux/checkreqprot": Read-only file system
15:16:04 [2025-01-29T15:16:04.252Z] mount/boot (org.osbuild.ext4): umount: /home/jenkins/agent/workspace/test-override/cache/osbuild/store/tmp/buildroot-tmp-44edf4ou/mounts/boot-mount-point unmounted
15:16:04 [2025-01-29T15:16:04.252Z] mount/root (org.osbuild.xfs): umount: /home/jenkins/agent/workspace/test-override/cache/osbuild/store/tmp/buildroot-tmp-44edf4ou/mounts/root-mount-point unmounted
15:16:04 [2025-01-29T15:16:04.252Z] 
15:16:04 [2025-01-29T15:16:04.252Z] ���  Duration: 0s
15:16:04 [2025-01-29T15:16:04.252Z] org.osbuild.selinux: 71e9496f1f49c3594f4f74223c7229ef5dd6605ab28c7cc6787065465589e7ec {
15:16:04 [2025-01-29T15:16:04.252Z]   "file_contexts": "input://tree/etc/selinux/targeted/contexts/files/file_contexts",
15:16:04 [2025-01-29T15:16:04.252Z]   "target": "mount://root/"
15:16:04 [2025-01-29T15:16:04.252Z] }
15:16:04 [2025-01-29T15:16:04.505Z] device/disk (org.osbuild.loopback): loop0 acquired (locked: False)
15:16:04 [2025-01-29T15:16:04.505Z] mount/root (org.osbuild.xfs): mounting /dev/loop0p4 -> /home/jenkins/agent/workspace/test-override/cache/osbuild/store/tmp/buildroot-tmp-w2adk779/mounts/
15:16:04 [2025-01-29T15:16:04.757Z] Failed to open file "/sys/fs/selinux/checkreqprot": Read-only file system
15:16:04 [2025-01-29T15:16:04.757Z] setfiles: /run/osbuild/inputs/tree/etc/selinux/targeted/contexts/files/file_contexts.bin:  line 1 error due to: Non-ASCII characters found
15:16:04 [2025-01-29T15:16:04.757Z] setfiles: /run/osbuild/inputs/tree/etc/selinux/targeted/contexts/files/file_contexts.homedirs.bin:  line 1 error due to: Non-ASCII characters found
15:16:04 [2025-01-29T15:16:04.757Z] mount/root (org.osbuild.xfs): umount: /home/jenkins/agent/workspace/test-override/cache/osbuild/store/tmp/buildroot-tmp-w2adk779/mounts/ unmounted
15:16:05 [2025-01-29T15:16:05.010Z] 
15:16:05 [2025-01-29T15:16:05.010Z] ���  Duration: 0s
15:16:05 [2025-01-29T15:16:05.010Z] org.osbuild.selinux: fae52acdb791b3b0ce96e99cc959df60c445910d229ed59abfbc589cc813e839 {
15:16:05 [2025-01-29T15:16:05.010Z]   "file_contexts": "input://tree/etc/selinux/targeted/contexts/files/file_contexts",
15:16:05 [2025-01-29T15:16:05.010Z]   "target": "mount://root/boot/"
15:16:05 [2025-01-29T15:16:05.010Z] }
15:16:05 [2025-01-29T15:16:05.010Z] device/disk (org.osbuild.loopback): loop0 acquired (locked: False)
15:16:05 [2025-01-29T15:16:05.262Z] mount/root (org.osbuild.xfs): mounting /dev/loop0p4 -> /home/jenkins/agent/workspace/test-override/cache/osbuild/store/tmp/buildroot-tmp-7jc9pdd1/mounts/
15:16:05 [2025-01-29T15:16:05.262Z] mount/boot (org.osbuild.ext4): mounting /dev/loop0p3 -> /home/jenkins/agent/workspace/test-override/cache/osbuild/store/tmp/buildroot-tmp-7jc9pdd1/mounts/boot
15:16:05 [2025-01-29T15:16:05.516Z] Failed to open file "/sys/fs/selinux/checkreqprot": Read-only file system
15:16:05 [2025-01-29T15:16:05.516Z] setfiles: /run/osbuild/inputs/tree/etc/selinux/targeted/contexts/files/file_contexts.bin:  line 1 error due to: Non-ASCII characters found
15:16:05 [2025-01-29T15:16:05.516Z] setfiles: /run/osbuild/inputs/tree/etc/selinux/targeted/contexts/files/file_contexts.homedirs.bin:  line 1 error due to: Non-ASCII characters found
15:16:05 [2025-01-29T15:16:05.516Z] mount/boot (org.osbuild.ext4): umount: /home/jenkins/agent/workspace/test-override/cache/osbuild/store/tmp/buildroot-tmp-7jc9pdd1/mounts/boot unmounted
15:16:05 [2025-01-29T15:16:05.516Z] mount/root (org.osbuild.xfs): umount: /home/jenkins/agent/workspace/test-override/cache/osbuild/store/tmp/buildroot-tmp-7jc9pdd1/mounts/ unmounted
15:16:05 [2025-01-29T15:16:05.769Z] 
15:16:05 [2025-01-29T15:16:05.769Z] ���  Duration: 0s
15:16:05 [2025-01-29T15:16:05.769Z] org.osbuild.copy: bc7898eb99d8c01c95b7420e2710a5ef206784a7d8f701cbc3c23d0d40aec910 {
15:16:05 [2025-01-29T15:16:05.769Z]   "paths": [
15:16:05 [2025-01-29T15:16:05.769Z]     {
15:16:05 [2025-01-29T15:16:05.769Z]       "from": "input://tree/",
15:16:05 [2025-01-29T15:16:05.769Z]       "to": "mount://root/"
15:16:05 [2025-01-29T15:16:05.769Z]     }
15:16:05 [2025-01-29T15:16:05.769Z]   ]
15:16:05 [2025-01-29T15:16:05.769Z] }
15:16:05 [2025-01-29T15:16:05.769Z] device/disk (org.osbuild.loopback): loop0 acquired (locked: False)
15:16:06 [2025-01-29T15:16:06.022Z] mount/root (org.osbuild.xfs): mounting /dev/loop0p4 -> /home/jenkins/agent/workspace/test-override/cache/osbuild/store/tmp/buildroot-tmp-a0otvh2q/mounts/
15:16:06 [2025-01-29T15:16:06.022Z] mount/boot (org.osbuild.ext4): mounting /dev/loop0p3 -> /home/jenkins/agent/workspace/test-override/cache/osbuild/store/tmp/buildroot-tmp-a0otvh2q/mounts/boot
15:16:06 [2025-01-29T15:16:06.022Z] mount/efi (org.osbuild.fat): mounting /dev/loop0p2 -> /home/jenkins/agent/workspace/test-override/cache/osbuild/store/tmp/buildroot-tmp-a0otvh2q/mounts/boot/efi
15:16:06 [2025-01-29T15:16:06.274Z] Failed to open file "/sys/fs/selinux/checkreqprot": Read-only file system
15:16:06 [2025-01-29T15:16:06.274Z] copying '/run/osbuild/inputs/tree/.' -> '/run/osbuild/mounts/.'
15:16:11 [2025-01-29T15:16:11.492Z] 
15:16:11 [2025-01-29T15:16:11.492Z] Filesystem      Size  Used Avail Use% Mounted on
15:16:11 [2025-01-29T15:16:11.492Z] /dev/vdb1        30G  8.8G   22G  30% /home/jenkins/agent/workspace/test-override/cache
15:16:11 [2025-01-29T15:16:11.492Z] 
15:16:21 [2025-01-29T15:16:21.398Z] 
15:16:21 [2025-01-29T15:16:21.398Z] Filesystem      Size  Used Avail Use% Mounted on
15:16:21 [2025-01-29T15:16:21.398Z] /dev/vdb1        30G  9.5G   21G  32% /home/jenkins/agent/workspace/test-override/cache
15:16:21 [2025-01-29T15:16:21.398Z] 
15:16:31 [2025-01-29T15:16:31.307Z] mount/efi (org.osbuild.fat): umount: /home/jenkins/agent/workspace/test-override/cache/osbuild/store/tmp/buildroot-tmp-a0otvh2q/mounts/boot/efi unmounted
15:16:31 [2025-01-29T15:16:31.307Z] mount/boot (org.osbuild.ext4): umount: /home/jenkins/agent/workspace/test-override/cache/osbuild/store/tmp/buildroot-tmp-a0otvh2q/mounts/boot unmounted
15:16:31 [2025-01-29T15:16:31.560Z] 
15:16:31 [2025-01-29T15:16:31.560Z] Filesystem      Size  Used Avail Use% Mounted on
15:16:31 [2025-01-29T15:16:31.560Z] /dev/vdb1        30G  9.8G   21G  33% /home/jenkins/agent/workspace/test-override/cache
15:16:31 [2025-01-29T15:16:31.560Z] 
15:16:31 [2025-01-29T15:16:31.560Z] mount/root (org.osbuild.xfs): umount: /home/jenkins/agent/workspace/test-override/cache/osbuild/store/tmp/buildroot-tmp-a0otvh2q/mounts/ unmounted
15:16:31 [2025-01-29T15:16:31.813Z] 
15:16:31 [2025-01-29T15:16:31.813Z] ���  Duration: 26s
15:16:31 [2025-01-29T15:16:31.813Z] org.osbuild.bootupd: ad6c1f68afe8dc1f11b4e5321fc33184f2d3876b5baf23b5be53e196c8a064e8 {
15:16:31 [2025-01-29T15:16:31.813Z]   "static-configs": true,
15:16:31 [2025-01-29T15:16:31.813Z]   "deployment": {
15:16:31 [2025-01-29T15:16:31.813Z]     "default": true
15:16:31 [2025-01-29T15:16:31.813Z]   }
15:16:31 [2025-01-29T15:16:31.813Z] }
15:16:31 [2025-01-29T15:16:31.813Z] device/disk (org.osbuild.loopback): loop0 acquired (locked: False)
15:16:31 [2025-01-29T15:16:31.813Z] mount/root (org.osbuild.xfs): mounting /dev/loop0p4 -> /home/jenkins/agent/workspace/test-override/cache/osbuild/store/tmp/buildroot-tmp-3_lcrros/mounts/
15:16:32 [2025-01-29T15:16:32.067Z] mount/boot (org.osbuild.ext4): mounting /dev/loop0p3 -> /home/jenkins/agent/workspace/test-override/cache/osbuild/store/tmp/buildroot-tmp-3_lcrros/mounts/boot
15:16:32 [2025-01-29T15:16:32.067Z] mount/efi (org.osbuild.fat): mounting /dev/loop0p2 -> /home/jenkins/agent/workspace/test-override/cache/osbuild/store/tmp/buildroot-tmp-3_lcrros/mounts/boot/efi
15:16:32 [2025-01-29T15:16:32.320Z] Failed to open file "/sys/fs/selinux/checkreqprot": Read-only file system
15:16:32 [2025-01-29T15:16:32.320Z] Skip installing component BIOS without target device
15:16:32 [2025-01-29T15:16:32.320Z] Installed 40_coreos-ignition.cfg
15:16:32 [2025-01-29T15:16:32.320Z] Installed 70_coreos-user.cfg
15:16:32 [2025-01-29T15:16:32.320Z] Installed: grub.cfg
15:16:32 [2025-01-29T15:16:32.320Z] Installed: "fedora/grub.cfg"
15:16:32 [2025-01-29T15:16:32.573Z] mount/efi (org.osbuild.fat): umount: /home/jenkins/agent/workspace/test-override/cache/osbuild/store/tmp/buildroot-tmp-3_lcrros/mounts/boot/efi unmounted
15:16:32 [2025-01-29T15:16:32.573Z] mount/boot (org.osbuild.ext4): umount: /home/jenkins/agent/workspace/test-override/cache/osbuild/store/tmp/buildroot-tmp-3_lcrros/mounts/boot unmounted
15:16:32 [2025-01-29T15:16:32.573Z] mount/root (org.osbuild.xfs): umount: /home/jenkins/agent/workspace/test-override/cache/osbuild/store/tmp/buildroot-tmp-3_lcrros/mounts/ unmounted
15:16:32 [2025-01-29T15:16:32.573Z] 
15:16:32 [2025-01-29T15:16:32.573Z] ���  Duration: 0s
15:16:32 [2025-01-29T15:16:32.573Z] org.osbuild.chattr: 50680732cbb065c694c50acfac4406254e9f22d3912f4d0570875dba9cbbabeb {
15:16:32 [2025-01-29T15:16:32.573Z]   "items": {
15:16:32 [2025-01-29T15:16:32.573Z]     "mount://root/": {
15:16:32 [2025-01-29T15:16:32.573Z]       "immutable": true
15:16:32 [2025-01-29T15:16:32.573Z]     }
15:16:32 [2025-01-29T15:16:32.573Z]   }
15:16:32 [2025-01-29T15:16:32.573Z] }
15:16:32 [2025-01-29T15:16:32.826Z] device/disk (org.osbuild.loopback): loop0 acquired (locked: False)
15:16:32 [2025-01-29T15:16:32.826Z] mount/root (org.osbuild.xfs): mounting /dev/loop0p4 -> /home/jenkins/agent/workspace/test-override/cache/osbuild/store/tmp/buildroot-tmp-8f_msuqa/mounts/
15:16:32 [2025-01-29T15:16:32.826Z] mount/ostree.deployment (org.osbuild.ostree.deployment): Deployment root at 'ostree/deploy/fedora-coreos/deploy/5c17cd7b0c9e1318d314543654d242413e8e08f2c45938f6aae1cb09deedf388.0'
15:16:33 [2025-01-29T15:16:33.079Z] Failed to open file "/sys/fs/selinux/checkreqprot": Read-only file system
15:16:33 [2025-01-29T15:16:33.079Z] mount/ostree.deployment (org.osbuild.ostree.deployment): mounting /home/jenkins/agent/workspace/test-override/cache/osbuild/store/tmp/buildroot-tmp-8f_msuqa/mounts/ostree/deploy/fedora-coreos/deploy/5c17cd7b0c9e1318d314543654d242413e8e08f2c45938f6aae1cb09deedf388.0 -> /home/jenkins/agent/workspace/test-override/cache/osbuild/store/tmp/buildroot-tmp-8f_msuqa/mounts
15:16:33 [2025-01-29T15:16:33.079Z] mount/ostree.deployment (org.osbuild.ostree.deployment): umount: /home/jenkins/agent/workspace/test-override/cache/osbuild/store/tmp/buildroot-tmp-8f_msuqa/mounts/boot unmounted
15:16:33 [2025-01-29T15:16:33.079Z] mount/ostree.deployment (org.osbuild.ostree.deployment): umount: /home/jenkins/agent/workspace/test-override/cache/osbuild/store/tmp/buildroot-tmp-8f_msuqa/mounts/var unmounted
15:16:33 [2025-01-29T15:16:33.079Z] mount/ostree.deployment (org.osbuild.ostree.deployment): umount: /home/jenkins/agent/workspace/test-override/cache/osbuild/store/tmp/buildroot-tmp-8f_msuqa/mounts/sysroot unmounted
15:16:33 [2025-01-29T15:16:33.079Z] mount/ostree.deployment (org.osbuild.ostree.deployment): umount: /home/jenkins/agent/workspace/test-override/cache/osbuild/store/tmp/buildroot-tmp-8f_msuqa/mounts unmounted
15:16:33 [2025-01-29T15:16:33.079Z] mount/ostree.deployment (org.osbuild.ostree.deployment): umount: /home/jenkins/agent/workspace/test-override/cache/osbuild/store/tmp/buildroot-tmp-8f_msuqa/mounts unmounted
15:16:33 [2025-01-29T15:16:33.332Z] mount/ostree.deployment (org.osbuild.ostree.deployment): umount: /home/jenkins/agent/workspace/test-override/cache/osbuild/store/tmp/buildroot-tmp-8f_msuqa/mounts unmounted
15:16:33 [2025-01-29T15:16:33.332Z] mount/root (org.osbuild.xfs): already unmounted: /home/jenkins/agent/workspace/test-override/cache/osbuild/store/tmp/buildroot-tmp-8f_msuqa/mounts/
15:16:33 [2025-01-29T15:16:33.332Z] 
15:16:33 [2025-01-29T15:16:33.332Z] ���  Duration: 0s
15:16:33 [2025-01-29T15:16:33.332Z] Pipeline raw-metal4k-image: e4db9f0ac56832bf483620a7dbca0b79a2016d22f31e0039261d5e988b3c1dec
15:16:33 [2025-01-29T15:16:33.332Z] Build
15:16:33 [2025-01-29T15:16:33.332Z]   root: <host>
15:16:33 [2025-01-29T15:16:33.332Z]   runner: org.osbuild.fedora38 (org.osbuild.fedora38)
15:16:33 [2025-01-29T15:16:33.332Z] org.osbuild.copy: 4b421fe1e4f0b558d4451ad66301a1a5c040bb5b68d97a33ebf3c5aa4fb94b0d {
15:16:33 [2025-01-29T15:16:33.332Z]   "paths": [
15:16:33 [2025-01-29T15:16:33.332Z]     {
15:16:33 [2025-01-29T15:16:33.332Z]       "from": "input://tree/disk.img",
15:16:33 [2025-01-29T15:16:33.332Z]       "to": "tree:///disk.img"
15:16:33 [2025-01-29T15:16:33.332Z]     }
15:16:33 [2025-01-29T15:16:33.332Z]   ]
15:16:33 [2025-01-29T15:16:33.332Z] }
15:16:33 [2025-01-29T15:16:33.584Z] Failed to open file "/sys/fs/selinux/checkreqprot": Read-only file system
15:16:33 [2025-01-29T15:16:33.584Z] copying '/run/osbuild/inputs/tree/disk.img' -> '/run/osbuild/tree/disk.img'
15:16:33 [2025-01-29T15:16:33.584Z] 
15:16:33 [2025-01-29T15:16:33.584Z] ���  Duration: 0s
15:16:33 [2025-01-29T15:16:33.584Z] org.osbuild.coreos.platform: e4db9f0ac56832bf483620a7dbca0b79a2016d22f31e0039261d5e988b3c1dec {
15:16:33 [2025-01-29T15:16:33.584Z]   "platform": "metal"
15:16:33 [2025-01-29T15:16:33.584Z] }
15:16:33 [2025-01-29T15:16:33.837Z] device/disk (org.osbuild.loopback): loop0 acquired (locked: False)
15:16:33 [2025-01-29T15:16:33.837Z] mount/root (org.osbuild.xfs): mounting /dev/loop0p4 -> /home/jenkins/agent/workspace/test-override/cache/osbuild/store/tmp/buildroot-tmp-oznw74gw/mounts/
15:16:33 [2025-01-29T15:16:33.837Z] mount/ostree.deployment (org.osbuild.ostree.deployment): Deployment root at 'ostree/deploy/fedora-coreos/deploy/5c17cd7b0c9e1318d314543654d242413e8e08f2c45938f6aae1cb09deedf388.0'
15:16:34 [2025-01-29T15:16:34.090Z] mount/boot (org.osbuild.ext4): mounting /dev/loop0p3 -> /home/jenkins/agent/workspace/test-override/cache/osbuild/store/tmp/buildroot-tmp-oznw74gw/mounts/boot
15:16:34 [2025-01-29T15:16:34.090Z] Failed to open file "/sys/fs/selinux/checkreqprot": Read-only file system
15:16:34 [2025-01-29T15:16:34.342Z] mount/boot (org.osbuild.ext4): umount: /home/jenkins/agent/workspace/test-override/cache/osbuild/store/tmp/buildroot-tmp-oznw74gw/mounts/boot unmounted
15:16:34 [2025-01-29T15:16:34.342Z] mount/ostree.deployment (org.osbuild.ostree.deployment): mounting /home/jenkins/agent/workspace/test-override/cache/osbuild/store/tmp/buildroot-tmp-oznw74gw/mounts/ostree/deploy/fedora-coreos/deploy/5c17cd7b0c9e1318d314543654d242413e8e08f2c45938f6aae1cb09deedf388.0 -> /home/jenkins/agent/workspace/test-override/cache/osbuild/store/tmp/buildroot-tmp-oznw74gw/mounts
15:16:34 [2025-01-29T15:16:34.342Z] mount/ostree.deployment (org.osbuild.ostree.deployment): umount: /home/jenkins/agent/workspace/test-override/cache/osbuild/store/tmp/buildroot-tmp-oznw74gw/mounts/boot unmounted
15:16:34 [2025-01-29T15:16:34.342Z] mount/ostree.deployment (org.osbuild.ostree.deployment): umount: /home/jenkins/agent/workspace/test-override/cache/osbuild/store/tmp/buildroot-tmp-oznw74gw/mounts/var unmounted
15:16:34 [2025-01-29T15:16:34.342Z] mount/ostree.deployment (org.osbuild.ostree.deployment): umount: /home/jenkins/agent/workspace/test-override/cache/osbuild/store/tmp/buildroot-tmp-oznw74gw/mounts/sysroot unmounted
15:16:34 [2025-01-29T15:16:34.342Z] mount/ostree.deployment (org.osbuild.ostree.deployment): umount: /home/jenkins/agent/workspace/test-override/cache/osbuild/store/tmp/buildroot-tmp-oznw74gw/mounts unmounted
15:16:34 [2025-01-29T15:16:34.342Z] mount/ostree.deployment (org.osbuild.ostree.deployment): umount: /home/jenkins/agent/workspace/test-override/cache/osbuild/store/tmp/buildroot-tmp-oznw74gw/mounts unmounted
15:16:34 [2025-01-29T15:16:34.342Z] mount/ostree.deployment (org.osbuild.ostree.deployment): umount: /home/jenkins/agent/workspace/test-override/cache/osbuild/store/tmp/buildroot-tmp-oznw74gw/mounts unmounted
15:16:34 [2025-01-29T15:16:34.342Z] mount/root (org.osbuild.xfs): already unmounted: /home/jenkins/agent/workspace/test-override/cache/osbuild/store/tmp/buildroot-tmp-oznw74gw/mounts/
15:16:34 [2025-01-29T15:16:34.342Z] 
15:16:34 [2025-01-29T15:16:34.342Z] ���  Duration: 0s
15:16:34 [2025-01-29T15:16:34.342Z] Pipeline metal4k: b3524d073848f7cca7e81c9c8c6b8eec8f39dec5980d714312ec323d81c44f57
15:16:34 [2025-01-29T15:16:34.342Z] Build
15:16:34 [2025-01-29T15:16:34.342Z]   root: <host>
15:16:34 [2025-01-29T15:16:34.342Z]   runner: org.osbuild.fedora38 (org.osbuild.fedora38)
15:16:34 [2025-01-29T15:16:34.342Z] org.osbuild.copy: b3524d073848f7cca7e81c9c8c6b8eec8f39dec5980d714312ec323d81c44f57 {
15:16:34 [2025-01-29T15:16:34.342Z]   "paths": [
15:16:34 [2025-01-29T15:16:34.342Z]     {
15:16:34 [2025-01-29T15:16:34.342Z]       "from": "input://tree/disk.img",
15:16:34 [2025-01-29T15:16:34.342Z]       "to": "tree:///fedora-coreos-42.20250129.dev.0-metal4k.x86_64.raw"
15:16:34 [2025-01-29T15:16:34.342Z]     }
15:16:34 [2025-01-29T15:16:34.342Z]   ]
15:16:34 [2025-01-29T15:16:34.342Z] }
15:16:34 [2025-01-29T15:16:34.595Z] Failed to open file "/sys/fs/selinux/checkreqprot": Read-only file system
15:16:34 [2025-01-29T15:16:34.595Z] copying '/run/osbuild/inputs/tree/disk.img' -> '/run/osbuild/tree/fedora-coreos-42.20250129.dev.0-metal4k.x86_64.raw'
15:16:34 [2025-01-29T15:16:34.847Z] 
15:16:34 [2025-01-29T15:16:34.847Z] ���  Duration: 0s
15:16:34 [2025-01-29T15:16:34.847Z] manifest /tmp/osbuild-Zkmf.json finished successfully
15:16:35 [2025-01-29T15:16:35.101Z] oci-archive:	c197c9ee61c31f1aeed376a79e2a03c2054b8ce9080f61c08566842ad0a8facb
15:16:35 [2025-01-29T15:16:35.101Z] deployed-tree:	3e323a1c950686dcf6dadac3cbc5d7c58c02014b6eab542cf3627d43fc74ccbe
15:16:35 [2025-01-29T15:16:35.101Z] tree:     	94d637f8de5b7cad84c69b5af487c53e6981eb9fac35204eda09e5af8ce61440
15:16:35 [2025-01-29T15:16:35.101Z] raw-image:	ff22d43b593a6337ed834121cb855a25554611d8865b14d128e39593b96edd6b
15:16:35 [2025-01-29T15:16:35.101Z] raw-4k-image:	50680732cbb065c694c50acfac4406254e9f22d3912f4d0570875dba9cbbabeb
15:16:35 [2025-01-29T15:16:35.101Z] raw-applehv-image:	8d0388c720eecbccc9809e59537500de1d6a55cc013786710e84ba9864a4b97d
15:16:35 [2025-01-29T15:16:35.101Z] applehv:  	c11e6597f2cfb9e1b96a65b9b4761c68b8fbe1ba0f6fb3eed2825445237a20fa
15:16:35 [2025-01-29T15:16:35.101Z] raw-gcp-image:	46335ae809fe0fa756004f6b74504cff1b5f0315495b65ec043fd164fe6e9dd7
15:16:35 [2025-01-29T15:16:35.101Z] raw-gcp-image-tar:	56eebed501f518683441a38b89f97a60b8f968b9410c3358183af388ff9e4ea3
15:16:35 [2025-01-29T15:16:35.101Z] gcp:      	ae70874df55896fdda7ef734816798388397b82d0d0d483f80cb1afb9029fcdd
15:16:35 [2025-01-29T15:16:35.101Z] raw-hyperv-image:	faf27692ab6dadaf8f0e8a252a2a365aa7fa9b2be4b287947515986dc1986c80
15:16:35 [2025-01-29T15:16:35.101Z] hyperv:   	2a29f4c354774178f7f11229d4b5f646d54319f726461c07e3ce2bdba9abab7f
15:16:35 [2025-01-29T15:16:35.101Z] raw-metal-image:	4a9effa0bef3914032053f5e38b53464c8461edc796a5170f4d579067f97082d
15:16:35 [2025-01-29T15:16:35.101Z] metal:    	35d5aa7b1da8c9d90bfa3f2286b5d1d9e5a8b0150b0fc264d4caa73f6b2a1b95
15:16:35 [2025-01-29T15:16:35.101Z] raw-metal4k-image:	e4db9f0ac56832bf483620a7dbca0b79a2016d22f31e0039261d5e988b3c1dec
15:16:35 [2025-01-29T15:16:35.101Z] metal4k:  	b3524d073848f7cca7e81c9c8c6b8eec8f39dec5980d714312ec323d81c44f57
15:16:35 [2025-01-29T15:16:35.101Z] raw-qemu-image:	a6cec666378ed7cee1af568a48d61c82814abd1310acae1f548609dd9d01f369
15:16:35 [2025-01-29T15:16:35.101Z] qemu:     	6cc5c17a930efe34358cad86feafbbd04a4cab664b81c157c956bd438eb522e3
15:16:35 [2025-01-29T15:16:35.101Z] live:     	ecc17e3c6db2c0c1bdd4576eba3c00c5e4c0a3c2189b34557e886f36bf91fbdc
15:16:35 [2025-01-29T15:16:35.101Z] + set +x
15:16:40 [2025-01-29T15:16:40.325Z] mv: failed to preserve ownership for '/home/jenkins/agent/workspace/test-override/tmp/build.metal4k/tmp.CruXjuUgHR/metal4k/fedora-coreos-42.20250129.dev.0-metal4k.x86_64.raw': Operation not permitted
15:16:40 [2025-01-29T15:16:40.325Z] mv: failed to preserve ownership for '/home/jenkins/agent/workspace/test-override/tmp/build.metal4k/tmp.CruXjuUgHR/metal4k': Operation not permitted
15:16:46 [2025-01-29T15:16:46.841Z] /home/jenkins/agent/workspace/test-override/builds/42.20250129.dev.0/x86_64/meta.json wrote with version stamp 1738163806425795553
15:16:46 [2025-01-29T15:16:46.841Z] Successfully generated: fedora-coreos-42.20250129.dev.0-metal4k.x86_64.raw
15:16:46 [2025-01-29T15:16:46.843Z] [Pipeline] }
15:16:46 [2025-01-29T15:16:46.846Z] [Pipeline] // withEnv
15:16:46 [2025-01-29T15:16:46.859Z] [Pipeline] }
15:16:46 [2025-01-29T15:16:46.863Z] [Pipeline] // stage
15:16:46 [2025-01-29T15:16:46.868Z] [Pipeline] stage
15:16:46 [2025-01-29T15:16:46.868Z] [Pipeline] { (x86_64:Build Live)
15:16:46 [2025-01-29T15:16:46.871Z] [Pipeline] withEnv
15:16:46 [2025-01-29T15:16:46.871Z] [Pipeline] {
15:16:46 [2025-01-29T15:16:46.874Z] [Pipeline] sh
15:16:47 [2025-01-29T15:16:47.405Z] + set -xeuo pipefail
15:16:47 [2025-01-29T15:16:47.405Z] ++ umask
15:16:47 [2025-01-29T15:16:47.405Z] + '[' 0022 = 0000 ']'
15:16:47 [2025-01-29T15:16:47.405Z] + cosa buildextend-live --fast
15:16:47 [2025-01-29T15:16:47.405Z] Targeting build: 42.20250129.dev.0
15:16:47 [2025-01-29T15:16:47.660Z] 2025-01-29 15:16:47,457 INFO - Running command: ['/usr/bin/ostree', 'ls', '--repo', '/home/jenkins/agent/workspace/test-override/tmp/repo', '--nul-filenames-only', '776c9f657fc044f86bad8848e3fcefe1a88a7c436932587bc41c52070d9b7106', '/usr/lib/modules']
15:16:47 [2025-01-29T15:16:47.661Z] 2025-01-29 15:16:47,467 INFO - Running command: ['/usr/bin/ostree', 'checkout', '--force-copy', '--repo', '/home/jenkins/agent/workspace/test-override/tmp/repo', '--user-mode', '--subpath', '/usr/lib/modules/6.13.0-0.rc7.20250114gitc45323b7560e.56.fc42.x86_64/vmlinuz', '776c9f657fc044f86bad8848e3fcefe1a88a7c436932587bc41c52070d9b7106', '/home/jenkins/agent/workspace/test-override/tmp/buildpost-live/live/images/pxeboot']
15:16:47 [2025-01-29T15:16:47.661Z] 2025-01-29 15:16:47,559 INFO - Running command: ['/usr/bin/ostree', 'checkout', '--force-copy', '--repo', '/home/jenkins/agent/workspace/test-override/tmp/repo', '--user-mode', '--subpath', '/usr/lib/modules/6.13.0-0.rc7.20250114gitc45323b7560e.56.fc42.x86_64/initramfs.img', '776c9f657fc044f86bad8848e3fcefe1a88a7c436932587bc41c52070d9b7106', '/home/jenkins/agent/workspace/test-override/tmp/buildpost-live/live/images/pxeboot']
15:16:48 [2025-01-29T15:16:48.217Z] 2025-01-29 15:16:48,018 INFO - Running command: ['/usr/bin/ostree', 'cat', '--repo', '/home/jenkins/agent/workspace/test-override/tmp/repo', '776c9f657fc044f86bad8848e3fcefe1a88a7c436932587bc41c52070d9b7106', '/usr/share/coreos-installer/example-config.yaml']
15:16:48 [2025-01-29T15:16:48.217Z] 2025-01-29 15:16:48,033 INFO - Running command: ['/usr/bin/ostree', 'checkout', '--repo', '/home/jenkins/agent/workspace/test-override/tmp/repo', '--user-mode', '--subpath', '/usr/lib/os-release', '776c9f657fc044f86bad8848e3fcefe1a88a7c436932587bc41c52070d9b7106', '/tmp/tmppg5en7x3']
15:16:48 [2025-01-29T15:16:48.217Z] Generating osmet file for 512b metal image
15:16:48 [2025-01-29T15:16:48.217Z] 2025-01-29 15:16:48,049 INFO - Running command: ['/usr/lib/coreos-assembler/runvm-coreos-installer', 'builds/42.20250129.dev.0/x86_64/fedora-coreos-42.20250129.dev.0-metal.x86_64.raw', '/home/jenkins/agent/workspace/test-override/tmp/buildpost-live/initrd-rootfs/fedora-coreos-42.20250129.dev.0-metal.x86_64.raw.osmet', 'pack', 'osmet', '/dev/disk/by-id/virtio-coreos', '--description', 'Fedora CoreOS 42.20250129.dev.0', '--checksum', '2f1b598e570b8e7b95c0827336e6ad916ac216ad5239e12323fe5723ef6f9a14', '--output', '/var/tmp/coreos-installer-output', '--fast']
15:17:06 [2025-01-29T15:17:06.239Z] + RUST_BACKTRACE=full
15:17:06 [2025-01-29T15:17:06.239Z] + chroot /sysroot/ostree/deploy/fedora-coreos/deploy/5c17cd7b0c9e1318d314543654d242413e8e08f2c45938f6aae1cb09deedf388.0 env -C /home/jenkins/agent/workspace/test-override coreos-installer pack osmet /dev/disk/by-id/virtio-coreos --description 'Fedora CoreOS 42.20250129.dev.0' --checksum 2f1b598e570b8e7b95c0827336e6ad916ac216ad5239e12323fe5723ef6f9a14 --output /var/tmp/coreos-installer-output --fast
15:17:07 [2025-01-29T15:17:07.592Z] Total OSTree objects scanned from /root: 17829 (17823 mapped, 6 empty)
15:17:07 [2025-01-29T15:17:07.592Z] Total OSTree objects found in /boot: 313
15:17:07 [2025-01-29T15:17:07.592Z] Duplicate extents dropped: 0
15:17:07 [2025-01-29T15:17:07.592Z] Overlapping extents clamped: 0
15:17:07 [2025-01-29T15:17:07.592Z] Total /root extents: 17823
15:17:07 [2025-01-29T15:17:07.592Z] Duplicate extents dropped: 0
15:17:07 [2025-01-29T15:17:07.592Z] Overlapping extents clamped: 0
15:17:07 [2025-01-29T15:17:07.592Z] Total /boot extents: 318
15:17:07 [2025-01-29T15:17:07.592Z] Packing image
15:17:22 [2025-01-29T15:17:22.404Z] Total bytes skipped: 1658821632
15:17:22 [2025-01-29T15:17:22.404Z] Total bytes written: 1089496064
15:17:22 [2025-01-29T15:17:22.404Z] Total bytes written (compressed): 25565632
15:17:22 [2025-01-29T15:17:22.404Z] Verifying that repacked image matches digest
15:17:30 [2025-01-29T15:17:30.459Z] Packing successful!
15:17:30 [2025-01-29T15:17:30.459Z] + '[' -f /var/tmp/coreos-installer-output ']'
15:17:30 [2025-01-29T15:17:30.459Z] + cp /var/tmp/coreos-installer-output /dev/virtio-ports/coreosout
15:17:31 [2025-01-29T15:17:31.813Z] Generating osmet file for 4k metal image
15:17:31 [2025-01-29T15:17:31.813Z] 2025-01-29 15:17:31,658 INFO - Running command: ['/usr/lib/coreos-assembler/runvm-coreos-installer', 'builds/42.20250129.dev.0/x86_64/fedora-coreos-42.20250129.dev.0-metal4k.x86_64.raw', '/home/jenkins/agent/workspace/test-override/tmp/buildpost-live/initrd-rootfs/fedora-coreos-42.20250129.dev.0-metal4k.x86_64.raw.osmet', 'pack', 'osmet', '/dev/disk/by-id/virtio-coreos', '--description', 'Fedora CoreOS 42.20250129.dev.0', '--checksum', '8e5a7ed3b36151fd051b994b09d4cbd8a0d0ccd783f6b0f9fbad7214130a2a51', '--output', '/var/tmp/coreos-installer-output', '--fast']
15:17:49 [2025-01-29T15:17:49.817Z] + RUST_BACKTRACE=full
15:17:49 [2025-01-29T15:17:49.817Z] + chroot /sysroot/ostree/deploy/fedora-coreos/deploy/5c17cd7b0c9e1318d314543654d242413e8e08f2c45938f6aae1cb09deedf388.0 env -C /home/jenkins/agent/workspace/test-override coreos-installer pack osmet /dev/disk/by-id/virtio-coreos --description 'Fedora CoreOS 42.20250129.dev.0' --checksum 8e5a7ed3b36151fd051b994b09d4cbd8a0d0ccd783f6b0f9fbad7214130a2a51 --output /var/tmp/coreos-installer-output --fast
15:17:49 [2025-01-29T15:17:49.817Z] Total OSTree objects scanned from /root: 17829 (17823 mapped, 6 empty)
15:17:49 [2025-01-29T15:17:49.817Z] Total OSTree objects found in /boot: 3
15:17:49 [2025-01-29T15:17:49.817Z] Duplicate extents dropped: 0
15:17:49 [2025-01-29T15:17:49.817Z] Overlapping extents clamped: 0
15:17:49 [2025-01-29T15:17:49.817Z] Total /root extents: 17824
15:17:49 [2025-01-29T15:17:49.817Z] Duplicate extents dropped: 0
15:17:49 [2025-01-29T15:17:49.817Z] Overlapping extents clamped: 0
15:17:49 [2025-01-29T15:17:49.817Z] Total /boot extents: 3
15:17:49 [2025-01-29T15:17:49.817Z] Packing image
15:18:01 [2025-01-29T15:18:01.957Z] Total bytes skipped: 1647972352
15:18:01 [2025-01-29T15:18:01.957Z] Total bytes written: 1100345344
15:18:01 [2025-01-29T15:18:01.957Z] Total bytes written (compressed): 25209064
15:18:01 [2025-01-29T15:18:01.957Z] Verifying that repacked image matches digest
15:18:11 [2025-01-29T15:18:11.877Z] Packing successful!
15:18:11 [2025-01-29T15:18:11.877Z] + '[' -f /var/tmp/coreos-installer-output ']'
15:18:11 [2025-01-29T15:18:11.877Z] + cp /var/tmp/coreos-installer-output /dev/virtio-ports/coreosout
15:18:12 [2025-01-29T15:18:12.794Z] Compressing squashfs with lz4
15:18:12 [2025-01-29T15:18:12.794Z] 2025-01-29 15:18:12,552 INFO - Running command: ['/usr/lib/coreos-assembler/gf-mksquashfs', 'builds/42.20250129.dev.0/x86_64/fedora-coreos-42.20250129.dev.0-metal.x86_64.raw', '/home/jenkins/agent/workspace/test-override/tmp/buildpost-live/initrd-rootfs/root.squashfs', 'lz4']
15:18:12 [2025-01-29T15:18:12.794Z] +++ dirname /home/jenkins/agent/workspace/test-override/tmp/buildpost-live/initrd-rootfs/root.squashfs
15:18:12 [2025-01-29T15:18:12.794Z] ++ mktemp -tdp /home/jenkins/agent/workspace/test-override/tmp/buildpost-live/initrd-rootfs gf-mksquashfs.XXXXXX
15:18:12 [2025-01-29T15:18:12.794Z] + tmpd=/home/jenkins/agent/workspace/test-override/tmp/buildpost-live/initrd-rootfs/gf-mksquashfs.vKVx4Y
15:18:12 [2025-01-29T15:18:12.794Z] + tmp_dest=/home/jenkins/agent/workspace/test-override/tmp/buildpost-live/initrd-rootfs/gf-mksquashfs.vKVx4Y/image.squashfs
15:18:12 [2025-01-29T15:18:12.794Z] + coreos_gf_run_mount builds/42.20250129.dev.0/x86_64/fedora-coreos-42.20250129.dev.0-metal.x86_64.raw --ro
15:18:12 [2025-01-29T15:18:12.794Z] + local mntarg=mount
15:18:12 [2025-01-29T15:18:12.794Z] + '[' builds/42.20250129.dev.0/x86_64/fedora-coreos-42.20250129.dev.0-metal.x86_64.raw = ro ']'
15:18:12 [2025-01-29T15:18:12.794Z] + coreos_gf_run builds/42.20250129.dev.0/x86_64/fedora-coreos-42.20250129.dev.0-metal.x86_64.raw --ro
15:18:12 [2025-01-29T15:18:12.794Z] + '[' -n '' ']'
15:18:12 [2025-01-29T15:18:12.794Z] + coreos_gf_launch builds/42.20250129.dev.0/x86_64/fedora-coreos-42.20250129.dev.0-metal.x86_64.raw --ro
15:18:12 [2025-01-29T15:18:12.794Z] + '[' -n '' ']'
15:18:12 [2025-01-29T15:18:12.794Z] ++ guestfish --listen -a builds/42.20250129.dev.0/x86_64/fedora-coreos-42.20250129.dev.0-metal.x86_64.raw --ro
15:18:12 [2025-01-29T15:18:12.794Z] + eval 'GUESTFISH_PID=11998; export GUESTFISH_PID'
15:18:12 [2025-01-29T15:18:12.794Z] ++ GUESTFISH_PID=11998
15:18:12 [2025-01-29T15:18:12.794Z] ++ export GUESTFISH_PID
15:18:12 [2025-01-29T15:18:12.794Z] + '[' -z 11998 ']'
15:18:12 [2025-01-29T15:18:12.794Z] ++ kola ncpu
15:18:12 [2025-01-29T15:18:12.794Z] + coreos_gf set-smp 6
15:18:12 [2025-01-29T15:18:12.794Z] + guestfish --remote -- set-smp 6
15:18:13 [2025-01-29T15:18:13.047Z] + coreos_gf run
15:18:13 [2025-01-29T15:18:13.047Z] + guestfish --remote -- run
15:18:14 [2025-01-29T15:18:14.920Z] + GUESTFISH_RUNNING=1
15:18:14 [2025-01-29T15:18:14.920Z] ++ coreos_gf findfs-label root
15:18:14 [2025-01-29T15:18:14.920Z] ++ guestfish --remote -- findfs-label root
15:18:14 [2025-01-29T15:18:14.920Z] + root=/dev/sda4
15:18:14 [2025-01-29T15:18:14.920Z] + coreos_gf mount /dev/sda4 /
15:18:14 [2025-01-29T15:18:14.920Z] + guestfish --remote -- mount /dev/sda4 /
15:18:15 [2025-01-29T15:18:15.172Z] + local boot
15:18:15 [2025-01-29T15:18:15.172Z] ++ coreos_gf findfs-label boot
15:18:15 [2025-01-29T15:18:15.172Z] ++ guestfish --remote -- findfs-label boot
15:18:15 [2025-01-29T15:18:15.172Z] + boot=/dev/sda3
15:18:15 [2025-01-29T15:18:15.172Z] + coreos_gf mount /dev/sda3 /boot
15:18:15 [2025-01-29T15:18:15.172Z] + guestfish --remote -- mount /dev/sda3 /boot
15:18:15 [2025-01-29T15:18:15.172Z] + local partitions
15:18:15 [2025-01-29T15:18:15.172Z] + local label
15:18:15 [2025-01-29T15:18:15.172Z] ++ coreos_gf list-partitions
15:18:15 [2025-01-29T15:18:15.172Z] ++ guestfish --remote -- list-partitions
15:18:15 [2025-01-29T15:18:15.172Z] + partitions='/dev/sda1
15:18:15 [2025-01-29T15:18:15.172Z] /dev/sda2
15:18:15 [2025-01-29T15:18:15.172Z] /dev/sda3
15:18:15 [2025-01-29T15:18:15.172Z] /dev/sda4'
15:18:15 [2025-01-29T15:18:15.172Z] + for pt in $partitions
15:18:15 [2025-01-29T15:18:15.172Z] ++ coreos_gf vfs-label /dev/sda1
15:18:15 [2025-01-29T15:18:15.172Z] ++ guestfish --remote -- vfs-label /dev/sda1
15:18:15 [2025-01-29T15:18:15.425Z] + label=
15:18:15 [2025-01-29T15:18:15.425Z] + '[' '' == EFI-SYSTEM ']'
15:18:15 [2025-01-29T15:18:15.425Z] + for pt in $partitions
15:18:15 [2025-01-29T15:18:15.425Z] ++ coreos_gf vfs-label /dev/sda2
15:18:15 [2025-01-29T15:18:15.425Z] ++ guestfish --remote -- vfs-label /dev/sda2
15:18:15 [2025-01-29T15:18:15.425Z] + label=EFI-SYSTEM
15:18:15 [2025-01-29T15:18:15.425Z] + '[' EFI-SYSTEM == EFI-SYSTEM ']'
15:18:15 [2025-01-29T15:18:15.425Z] + coreos_gf mount /dev/sda2 /boot/efi
15:18:15 [2025-01-29T15:18:15.425Z] + guestfish --remote -- mount /dev/sda2 /boot/efi
15:18:15 [2025-01-29T15:18:15.425Z] + for pt in $partitions
15:18:15 [2025-01-29T15:18:15.425Z] ++ coreos_gf vfs-label /dev/sda3
15:18:15 [2025-01-29T15:18:15.425Z] ++ guestfish --remote -- vfs-label /dev/sda3
15:18:15 [2025-01-29T15:18:15.425Z] + label=boot
15:18:15 [2025-01-29T15:18:15.425Z] + '[' boot == EFI-SYSTEM ']'
15:18:15 [2025-01-29T15:18:15.425Z] + for pt in $partitions
15:18:15 [2025-01-29T15:18:15.425Z] ++ coreos_gf vfs-label /dev/sda4
15:18:15 [2025-01-29T15:18:15.425Z] ++ guestfish --remote -- vfs-label /dev/sda4
15:18:15 [2025-01-29T15:18:15.425Z] + label=root
15:18:15 [2025-01-29T15:18:15.425Z] + '[' root == EFI-SYSTEM ']'
15:18:15 [2025-01-29T15:18:15.425Z] ++ coreos_gf ls /ostree/deploy
15:18:15 [2025-01-29T15:18:15.425Z] ++ guestfish --remote -- ls /ostree/deploy
15:18:15 [2025-01-29T15:18:15.425Z] + stateroot=/ostree/deploy/fedora-coreos
15:18:15 [2025-01-29T15:18:15.425Z] ++ coreos_gf ls /ostree/deploy/fedora-coreos/deploy
15:18:15 [2025-01-29T15:18:15.425Z] ++ guestfish --remote -- ls /ostree/deploy/fedora-coreos/deploy
15:18:15 [2025-01-29T15:18:15.425Z] ++ grep -v .origin
15:18:15 [2025-01-29T15:18:15.425Z] + deploydir=/ostree/deploy/fedora-coreos/deploy/5c17cd7b0c9e1318d314543654d242413e8e08f2c45938f6aae1cb09deedf388.0
15:18:15 [2025-01-29T15:18:15.425Z] + export stateroot deploydir
15:18:15 [2025-01-29T15:18:15.425Z] + coreos_gf download /ostree/repo/config /home/jenkins/agent/workspace/test-override/tmp/buildpost-live/initrd-rootfs/gf-mksquashfs.vKVx4Y/config
15:18:15 [2025-01-29T15:18:15.425Z] + guestfish --remote -- download /ostree/repo/config /home/jenkins/agent/workspace/test-override/tmp/buildpost-live/initrd-rootfs/gf-mksquashfs.vKVx4Y/config
15:18:15 [2025-01-29T15:18:15.679Z] + grep -v readonly=true /home/jenkins/agent/workspace/test-override/tmp/buildpost-live/initrd-rootfs/gf-mksquashfs.vKVx4Y/config
15:18:15 [2025-01-29T15:18:15.679Z] + coreos_gf upload /home/jenkins/agent/workspace/test-override/tmp/buildpost-live/initrd-rootfs/gf-mksquashfs.vKVx4Y/config.new /ostree/repo/config
15:18:15 [2025-01-29T15:18:15.679Z] + guestfish --remote -- upload /home/jenkins/agent/workspace/test-override/tmp/buildpost-live/initrd-rootfs/gf-mksquashfs.vKVx4Y/config.new /ostree/repo/config
15:18:15 [2025-01-29T15:18:15.679Z] ++ coreos_gf glob-expand '/boot/ostree/*/vmlinuz*'
15:18:15 [2025-01-29T15:18:15.679Z] ++ guestfish --remote -- glob-expand '/boot/ostree/*/vmlinuz*'
15:18:15 [2025-01-29T15:18:15.679Z] + kernel_binary=/boot/ostree/fedora-coreos-81d06f781a5fa74f44094c5dd118a74aaed655209617c8e97599619155f0bc4e/vmlinuz-6.13.0-0.rc7.20250114gitc45323b7560e.56.fc42.x86_64
15:18:15 [2025-01-29T15:18:15.679Z] ++ coreos_gf glob-expand '/boot/ostree/*/.*.hmac'
15:18:15 [2025-01-29T15:18:15.679Z] ++ guestfish --remote -- glob-expand '/boot/ostree/*/.*.hmac'
15:18:15 [2025-01-29T15:18:15.679Z] + kernel_hmac=/boot/ostree/fedora-coreos-81d06f781a5fa74f44094c5dd118a74aaed655209617c8e97599619155f0bc4e/.vmlinuz-6.13.0-0.rc7.20250114gitc45323b7560e.56.fc42.x86_64.hmac
15:18:15 [2025-01-29T15:18:15.679Z] ++ basename /boot/ostree/fedora-coreos-81d06f781a5fa74f44094c5dd118a74aaed655209617c8e97599619155f0bc4e/.vmlinuz-6.13.0-0.rc7.20250114gitc45323b7560e.56.fc42.x86_64.hmac
15:18:15 [2025-01-29T15:18:15.679Z] + coreos_gf ln /boot/ostree/fedora-coreos-81d06f781a5fa74f44094c5dd118a74aaed655209617c8e97599619155f0bc4e/.vmlinuz-6.13.0-0.rc7.20250114gitc45323b7560e.56.fc42.x86_64.hmac /boot/.vmlinuz-6.13.0-0.rc7.20250114gitc45323b7560e.56.fc42.x86_64.hmac
15:18:15 [2025-01-29T15:18:15.679Z] + guestfish --remote -- ln /boot/ostree/fedora-coreos-81d06f781a5fa74f44094c5dd118a74aaed655209617c8e97599619155f0bc4e/.vmlinuz-6.13.0-0.rc7.20250114gitc45323b7560e.56.fc42.x86_64.hmac /boot/.vmlinuz-6.13.0-0.rc7.20250114gitc45323b7560e.56.fc42.x86_64.hmac
15:18:15 [2025-01-29T15:18:15.679Z] ++ basename /boot/ostree/fedora-coreos-81d06f781a5fa74f44094c5dd118a74aaed655209617c8e97599619155f0bc4e/vmlinuz-6.13.0-0.rc7.20250114gitc45323b7560e.56.fc42.x86_64
15:18:15 [2025-01-29T15:18:15.679Z] + coreos_gf ln /boot/ostree/fedora-coreos-81d06f781a5fa74f44094c5dd118a74aaed655209617c8e97599619155f0bc4e/vmlinuz-6.13.0-0.rc7.20250114gitc45323b7560e.56.fc42.x86_64 /boot/vmlinuz-6.13.0-0.rc7.20250114gitc45323b7560e.56.fc42.x86_64
15:18:15 [2025-01-29T15:18:15.679Z] + guestfish --remote -- ln /boot/ostree/fedora-coreos-81d06f781a5fa74f44094c5dd118a74aaed655209617c8e97599619155f0bc4e/vmlinuz-6.13.0-0.rc7.20250114gitc45323b7560e.56.fc42.x86_64 /boot/vmlinuz-6.13.0-0.rc7.20250114gitc45323b7560e.56.fc42.x86_64
15:18:15 [2025-01-29T15:18:15.679Z] + coreos_gf mksquashfs / /home/jenkins/agent/workspace/test-override/tmp/buildpost-live/initrd-rootfs/gf-mksquashfs.vKVx4Y/image.squashfs compress:lz4
15:18:15 [2025-01-29T15:18:15.679Z] + guestfish --remote -- mksquashfs / /home/jenkins/agent/workspace/test-override/tmp/buildpost-live/initrd-rootfs/gf-mksquashfs.vKVx4Y/image.squashfs compress:lz4
15:18:23 [2025-01-29T15:18:23.733Z] + coreos_gf_shutdown
15:18:23 [2025-01-29T15:18:23.733Z] + coreos_gf umount-all
15:18:23 [2025-01-29T15:18:23.733Z] + guestfish --remote -- umount-all
15:18:23 [2025-01-29T15:18:23.997Z] + coreos_gf exit
15:18:23 [2025-01-29T15:18:23.997Z] + guestfish --remote -- exit
15:18:24 [2025-01-29T15:18:24.249Z] + GUESTFISH_RUNNING=
15:18:24 [2025-01-29T15:18:24.249Z] + GUESTFISH_PID=
15:18:24 [2025-01-29T15:18:24.249Z] + mv /home/jenkins/agent/workspace/test-override/tmp/buildpost-live/initrd-rootfs/gf-mksquashfs.vKVx4Y/image.squashfs /home/jenkins/agent/workspace/test-override/tmp/buildpost-live/initrd-rootfs/root.squashfs
15:18:24 [2025-01-29T15:18:24.249Z] + rm /home/jenkins/agent/workspace/test-override/tmp/buildpost-live/initrd-rootfs/gf-mksquashfs.vKVx4Y -rf
15:18:24 [2025-01-29T15:18:24.249Z] + _coreos_gf_cleanup
15:18:24 [2025-01-29T15:18:24.249Z] + guestfish --remote -- exit
15:18:24 [2025-01-29T15:18:24.502Z] + :
15:18:29 [2025-01-29T15:18:29.732Z] 2025-01-29 15:18:29,249 INFO - Running command: ['/usr/lib/coreos-assembler/gf-get-kargs', 'builds/42.20250129.dev.0/x86_64/fedora-coreos-42.20250129.dev.0-metal.x86_64.raw']
15:18:29 [2025-01-29T15:18:29.732Z] + coreos_gf_run_mount builds/42.20250129.dev.0/x86_64/fedora-coreos-42.20250129.dev.0-metal.x86_64.raw --ro
15:18:29 [2025-01-29T15:18:29.732Z] + local mntarg=mount
15:18:29 [2025-01-29T15:18:29.732Z] + '[' builds/42.20250129.dev.0/x86_64/fedora-coreos-42.20250129.dev.0-metal.x86_64.raw = ro ']'
15:18:29 [2025-01-29T15:18:29.732Z] + coreos_gf_run builds/42.20250129.dev.0/x86_64/fedora-coreos-42.20250129.dev.0-metal.x86_64.raw --ro
15:18:29 [2025-01-29T15:18:29.732Z] + '[' -n '' ']'
15:18:29 [2025-01-29T15:18:29.732Z] + coreos_gf_launch builds/42.20250129.dev.0/x86_64/fedora-coreos-42.20250129.dev.0-metal.x86_64.raw --ro
15:18:29 [2025-01-29T15:18:29.732Z] + '[' -n '' ']'
15:18:29 [2025-01-29T15:18:29.732Z] ++ guestfish --listen -a builds/42.20250129.dev.0/x86_64/fedora-coreos-42.20250129.dev.0-metal.x86_64.raw --ro
15:18:29 [2025-01-29T15:18:29.732Z] + eval 'GUESTFISH_PID=12139; export GUESTFISH_PID'
15:18:29 [2025-01-29T15:18:29.732Z] ++ GUESTFISH_PID=12139
15:18:29 [2025-01-29T15:18:29.732Z] ++ export GUESTFISH_PID
15:18:29 [2025-01-29T15:18:29.732Z] + '[' -z 12139 ']'
15:18:29 [2025-01-29T15:18:29.732Z] ++ kola ncpu
15:18:29 [2025-01-29T15:18:29.732Z] + coreos_gf set-smp 6
15:18:29 [2025-01-29T15:18:29.732Z] + guestfish --remote -- set-smp 6
15:18:29 [2025-01-29T15:18:29.732Z] + coreos_gf run
15:18:29 [2025-01-29T15:18:29.732Z] + guestfish --remote -- run
15:18:31 [2025-01-29T15:18:31.606Z] + GUESTFISH_RUNNING=1
15:18:31 [2025-01-29T15:18:31.606Z] ++ coreos_gf findfs-label root
15:18:31 [2025-01-29T15:18:31.606Z] ++ guestfish --remote -- findfs-label root
15:18:31 [2025-01-29T15:18:31.606Z] + root=/dev/sda4
15:18:31 [2025-01-29T15:18:31.606Z] + coreos_gf mount /dev/sda4 /
15:18:31 [2025-01-29T15:18:31.606Z] + guestfish --remote -- mount /dev/sda4 /
15:18:31 [2025-01-29T15:18:31.859Z] + local boot
15:18:31 [2025-01-29T15:18:31.859Z] ++ coreos_gf findfs-label boot
15:18:31 [2025-01-29T15:18:31.859Z] ++ guestfish --remote -- findfs-label boot
15:18:31 [2025-01-29T15:18:31.859Z] + boot=/dev/sda3
15:18:31 [2025-01-29T15:18:31.859Z] + coreos_gf mount /dev/sda3 /boot
15:18:31 [2025-01-29T15:18:31.859Z] + guestfish --remote -- mount /dev/sda3 /boot
15:18:31 [2025-01-29T15:18:31.859Z] + local partitions
15:18:31 [2025-01-29T15:18:31.859Z] + local label
15:18:31 [2025-01-29T15:18:31.859Z] ++ coreos_gf list-partitions
15:18:31 [2025-01-29T15:18:31.859Z] ++ guestfish --remote -- list-partitions
15:18:31 [2025-01-29T15:18:31.859Z] + partitions='/dev/sda1
15:18:31 [2025-01-29T15:18:31.859Z] /dev/sda2
15:18:31 [2025-01-29T15:18:31.859Z] /dev/sda3
15:18:31 [2025-01-29T15:18:31.859Z] /dev/sda4'
15:18:31 [2025-01-29T15:18:31.859Z] + for pt in $partitions
15:18:32 [2025-01-29T15:18:32.112Z] ++ coreos_gf vfs-label /dev/sda1
15:18:32 [2025-01-29T15:18:32.113Z] ++ guestfish --remote -- vfs-label /dev/sda1
15:18:32 [2025-01-29T15:18:32.113Z] + label=
15:18:32 [2025-01-29T15:18:32.113Z] + '[' '' == EFI-SYSTEM ']'
15:18:32 [2025-01-29T15:18:32.113Z] + for pt in $partitions
15:18:32 [2025-01-29T15:18:32.113Z] ++ coreos_gf vfs-label /dev/sda2
15:18:32 [2025-01-29T15:18:32.113Z] ++ guestfish --remote -- vfs-label /dev/sda2
15:18:32 [2025-01-29T15:18:32.113Z] + label=EFI-SYSTEM
15:18:32 [2025-01-29T15:18:32.113Z] + '[' EFI-SYSTEM == EFI-SYSTEM ']'
15:18:32 [2025-01-29T15:18:32.113Z] + coreos_gf mount /dev/sda2 /boot/efi
15:18:32 [2025-01-29T15:18:32.113Z] + guestfish --remote -- mount /dev/sda2 /boot/efi
15:18:32 [2025-01-29T15:18:32.113Z] + for pt in $partitions
15:18:32 [2025-01-29T15:18:32.113Z] ++ coreos_gf vfs-label /dev/sda3
15:18:32 [2025-01-29T15:18:32.113Z] ++ guestfish --remote -- vfs-label /dev/sda3
15:18:32 [2025-01-29T15:18:32.113Z] + label=boot
15:18:32 [2025-01-29T15:18:32.113Z] + '[' boot == EFI-SYSTEM ']'
15:18:32 [2025-01-29T15:18:32.113Z] + for pt in $partitions
15:18:32 [2025-01-29T15:18:32.113Z] ++ coreos_gf vfs-label /dev/sda4
15:18:32 [2025-01-29T15:18:32.113Z] ++ guestfish --remote -- vfs-label /dev/sda4
15:18:32 [2025-01-29T15:18:32.113Z] + label=root
15:18:32 [2025-01-29T15:18:32.113Z] + '[' root == EFI-SYSTEM ']'
15:18:32 [2025-01-29T15:18:32.113Z] ++ coreos_gf ls /ostree/deploy
15:18:32 [2025-01-29T15:18:32.113Z] ++ guestfish --remote -- ls /ostree/deploy
15:18:32 [2025-01-29T15:18:32.113Z] + stateroot=/ostree/deploy/fedora-coreos
15:18:32 [2025-01-29T15:18:32.113Z] ++ coreos_gf ls /ostree/deploy/fedora-coreos/deploy
15:18:32 [2025-01-29T15:18:32.113Z] ++ guestfish --remote -- ls /ostree/deploy/fedora-coreos/deploy
15:18:32 [2025-01-29T15:18:32.113Z] ++ grep -v .origin
15:18:32 [2025-01-29T15:18:32.113Z] + deploydir=/ostree/deploy/fedora-coreos/deploy/5c17cd7b0c9e1318d314543654d242413e8e08f2c45938f6aae1cb09deedf388.0
15:18:32 [2025-01-29T15:18:32.113Z] + export stateroot deploydir
15:18:32 [2025-01-29T15:18:32.113Z] + coreos_gf glob read-file '/boot/loader/entries/ostree*conf'
15:18:32 [2025-01-29T15:18:32.113Z] + guestfish --remote -- glob read-file '/boot/loader/entries/ostree*conf'
15:18:32 [2025-01-29T15:18:32.113Z] + sed -e '/^options/!d' -e 's/^options\s*//'
15:18:32 [2025-01-29T15:18:32.367Z] + coreos_gf_shutdown
15:18:32 [2025-01-29T15:18:32.367Z] + coreos_gf umount-all
15:18:32 [2025-01-29T15:18:32.367Z] + guestfish --remote -- umount-all
15:18:32 [2025-01-29T15:18:32.367Z] + coreos_gf exit
15:18:32 [2025-01-29T15:18:32.367Z] + guestfish --remote -- exit
15:18:32 [2025-01-29T15:18:32.367Z] + GUESTFISH_RUNNING=
15:18:32 [2025-01-29T15:18:32.367Z] + GUESTFISH_PID=
15:18:32 [2025-01-29T15:18:32.367Z] + _coreos_gf_cleanup
15:18:32 [2025-01-29T15:18:32.367Z] + guestfish --remote -- exit
15:18:32 [2025-01-29T15:18:32.367Z] + :
15:18:32 [2025-01-29T15:18:32.367Z] Substituting ISO kernel arguments: rw mitigations=auto,nosmt coreos.liveiso=fedora-coreos-42.20250129.dev.0
15:18:32 [2025-01-29T15:18:32.619Z] src/config/live/zipl.prm -> /home/jenkins/agent/workspace/test-override/tmp/buildpost-live/live/zipl.prm
15:18:32 [2025-01-29T15:18:32.619Z] src/config/live/EFI/fedora/grub.cfg -> /home/jenkins/agent/workspace/test-override/tmp/buildpost-live/live/EFI/fedora/grub.cfg
15:18:32 [2025-01-29T15:18:32.619Z] src/config/live/isolinux/boot.msg -> /home/jenkins/agent/workspace/test-override/tmp/buildpost-live/live/isolinux/boot.msg
15:18:32 [2025-01-29T15:18:32.619Z] src/config/live/isolinux/isolinux.cfg -> /home/jenkins/agent/workspace/test-override/tmp/buildpost-live/live/isolinux/isolinux.cfg
15:18:32 [2025-01-29T15:18:32.619Z] 2025-01-29 15:18:32,490 INFO - Running command: ['/usr/bin/ostree', 'checkout', '--repo', '/home/jenkins/agent/workspace/test-override/tmp/repo', '--user-mode', '--subpath', '/usr/lib/bootupd/updates/EFI', '776c9f657fc044f86bad8848e3fcefe1a88a7c436932587bc41c52070d9b7106', '/home/jenkins/agent/workspace/test-override/tmp/buildpost-live/efi']
15:18:33 [2025-01-29T15:18:33.186Z] 2025-01-29 15:18:32,932 INFO - Running command: ['virt-make-fs', '--type=vfat', '--label=EFI-SYSTEM', '/tmp/tmpyqwenyu5.tar', '/home/jenkins/agent/workspace/test-override/tmp/buildpost-live/live/images/efiboot.img']
15:18:35 [2025-01-29T15:18:35.685Z] 2025-01-29 15:18:35,494 INFO - Running command: ['/usr/bin/genisoimage', '-verbose', '-V', 'fedora-coreos-42.20250129.dev.0', '-volset', 'fedora-coreos-42.20250129.dev.0', '-rational-rock', '-J', '-joliet-long', '-eltorito-boot', 'isolinux/isolinux.bin', '-eltorito-catalog', 'isolinux/boot.cat', '-no-emul-boot', '-boot-load-size', '4', '-boot-info-table', '-eltorito-alt-boot', '-efi-boot', 'images/efiboot.img', '-no-emul-boot', '-o', '/home/jenkins/agent/workspace/test-override/tmp/buildpost-live/fedora-coreos-42.20250129.dev.0-live.x86_64.iso', '/home/jenkins/agent/workspace/test-override/tmp/buildpost-live/live']
15:18:35 [2025-01-29T15:18:35.685Z] I: -input-charset not specified, using utf-8 (detected in locale settings)
15:18:35 [2025-01-29T15:18:35.685Z] genisoimage 1.1.11 (Linux)
15:18:35 [2025-01-29T15:18:35.685Z] Scanning /home/jenkins/agent/workspace/test-override/tmp/buildpost-live/live
15:18:35 [2025-01-29T15:18:35.685Z] Scanning /home/jenkins/agent/workspace/test-override/tmp/buildpost-live/live/coreos
15:18:35 [2025-01-29T15:18:35.685Z] Scanning /home/jenkins/agent/workspace/test-override/tmp/buildpost-live/live/images
15:18:35 [2025-01-29T15:18:35.685Z] Scanning /home/jenkins/agent/workspace/test-override/tmp/buildpost-live/live/images/pxeboot
15:18:35 [2025-01-29T15:18:35.685Z] Scanning /home/jenkins/agent/workspace/test-override/tmp/buildpost-live/live/isolinux
15:18:35 [2025-01-29T15:18:35.685Z] Scanning /home/jenkins/agent/workspace/test-override/tmp/buildpost-live/live/EFI
15:18:35 [2025-01-29T15:18:35.685Z] Scanning /home/jenkins/agent/workspace/test-override/tmp/buildpost-live/live/EFI/fedora
15:18:35 [2025-01-29T15:18:35.685Z] Writing:   Initial Padblock                        Start Block 0
15:18:35 [2025-01-29T15:18:35.685Z] Done with: Initial Padblock                        Block(s)    16
15:18:35 [2025-01-29T15:18:35.685Z] Writing:   Primary Volume Descriptor               Start Block 16
15:18:35 [2025-01-29T15:18:35.685Z] Done with: Primary Volume Descriptor               Block(s)    1
15:18:35 [2025-01-29T15:18:35.685Z] Writing:   Eltorito Volume Descriptor              Start Block 17
15:18:35 [2025-01-29T15:18:35.685Z] Size of boot image is 4 sectors -> No emulation
15:18:35 [2025-01-29T15:18:35.685Z] Size of boot image is 13104 sectors -> No emulation
15:18:35 [2025-01-29T15:18:35.685Z] Done with: Eltorito Volume Descriptor              Block(s)    1
15:18:35 [2025-01-29T15:18:35.685Z] Writing:   Joliet Volume Descriptor                Start Block 18
15:18:35 [2025-01-29T15:18:35.685Z] Done with: Joliet Volume Descriptor                Block(s)    1
15:18:35 [2025-01-29T15:18:35.685Z] Writing:   End Volume Descriptor                   Start Block 19
15:18:35 [2025-01-29T15:18:35.685Z] Done with: End Volume Descriptor                   Block(s)    1
15:18:35 [2025-01-29T15:18:35.685Z] Writing:   Version block                           Start Block 20
15:18:35 [2025-01-29T15:18:35.685Z] Done with: Version block                           Block(s)    1
15:18:35 [2025-01-29T15:18:35.685Z] Writing:   Path table                              Start Block 21
15:18:35 [2025-01-29T15:18:35.685Z] Done with: Path table                              Block(s)    4
15:18:35 [2025-01-29T15:18:35.685Z] Writing:   Joliet path table                       Start Block 25
15:18:35 [2025-01-29T15:18:35.685Z] Done with: Joliet path table                       Block(s)    4
15:18:35 [2025-01-29T15:18:35.685Z] Writing:   Directory tree                          Start Block 29
15:18:35 [2025-01-29T15:18:35.685Z] Done with: Directory tree                          Block(s)    7
15:18:35 [2025-01-29T15:18:35.685Z] Writing:   Joliet directory tree                   Start Block 36
15:18:35 [2025-01-29T15:18:35.685Z] Done with: Joliet directory tree                   Block(s)    7
15:18:35 [2025-01-29T15:18:35.685Z] Writing:   Directory tree cleanup                  Start Block 43
15:18:35 [2025-01-29T15:18:35.685Z] Done with: Directory tree cleanup                  Block(s)    0
15:18:35 [2025-01-29T15:18:35.685Z] Writing:   Extension record                        Start Block 43
15:18:35 [2025-01-29T15:18:35.685Z] Done with: Extension record                        Block(s)    1
15:18:35 [2025-01-29T15:18:35.685Z] Writing:   The File(s)                             Start Block 44
15:18:35 [2025-01-29T15:18:35.685Z]   0.92% done, estimate finish Wed Jan 29 15:18:35 2025
15:18:35 [2025-01-29T15:18:35.685Z]   1.84% done, estimate finish Wed Jan 29 15:18:35 2025
15:18:35 [2025-01-29T15:18:35.685Z]   2.75% done, estimate finish Wed Jan 29 15:18:35 2025
15:18:35 [2025-01-29T15:18:35.685Z]   3.67% done, estimate finish Wed Jan 29 15:18:35 2025
15:18:35 [2025-01-29T15:18:35.685Z]   4.58% done, estimate finish Wed Jan 29 15:18:35 2025
15:18:35 [2025-01-29T15:18:35.685Z]   5.50% done, estimate finish Wed Jan 29 15:18:35 2025
15:18:35 [2025-01-29T15:18:35.685Z]   6.42% done, estimate finish Wed Jan 29 15:18:35 2025
15:18:35 [2025-01-29T15:18:35.685Z]   7.34% done, estimate finish Wed Jan 29 15:18:35 2025
15:18:35 [2025-01-29T15:18:35.685Z]   8.25% done, estimate finish Wed Jan 29 15:18:35 2025
15:18:35 [2025-01-29T15:18:35.685Z]   9.17% done, estimate finish Wed Jan 29 15:18:35 2025
15:18:35 [2025-01-29T15:18:35.949Z]  10.09% done, estimate finish Wed Jan 29 15:18:35 2025
15:18:35 [2025-01-29T15:18:35.949Z]  11.01% done, estimate finish Wed Jan 29 15:18:35 2025
15:18:35 [2025-01-29T15:18:35.949Z]  11.92% done, estimate finish Wed Jan 29 15:18:35 2025
15:18:35 [2025-01-29T15:18:35.949Z]  12.84% done, estimate finish Wed Jan 29 15:18:35 2025
15:18:35 [2025-01-29T15:18:35.949Z]  13.76% done, estimate finish Wed Jan 29 15:18:35 2025
15:18:35 [2025-01-29T15:18:35.949Z]  14.67% done, estimate finish Wed Jan 29 15:18:35 2025
15:18:35 [2025-01-29T15:18:35.949Z]  15.59% done, estimate finish Wed Jan 29 15:18:35 2025
15:18:35 [2025-01-29T15:18:35.949Z]  16.51% done, estimate finish Wed Jan 29 15:18:35 2025
15:18:35 [2025-01-29T15:18:35.949Z]  17.42% done, estimate finish Wed Jan 29 15:18:35 2025
15:18:35 [2025-01-29T15:18:35.949Z]  18.34% done, estimate finish Wed Jan 29 15:18:35 2025
15:18:35 [2025-01-29T15:18:35.949Z]  19.26% done, estimate finish Wed Jan 29 15:18:35 2025
15:18:35 [2025-01-29T15:18:35.949Z]  20.18% done, estimate finish Wed Jan 29 15:18:35 2025
15:18:35 [2025-01-29T15:18:35.949Z]  21.09% done, estimate finish Wed Jan 29 15:18:35 2025
15:18:35 [2025-01-29T15:18:35.949Z]  22.01% done, estimate finish Wed Jan 29 15:18:35 2025
15:18:35 [2025-01-29T15:18:35.949Z]  22.92% done, estimate finish Wed Jan 29 15:18:35 2025
15:18:35 [2025-01-29T15:18:35.949Z]  23.84% done, estimate finish Wed Jan 29 15:18:35 2025
15:18:35 [2025-01-29T15:18:35.949Z]  24.76% done, estimate finish Wed Jan 29 15:18:35 2025
15:18:35 [2025-01-29T15:18:35.949Z]  25.68% done, estimate finish Wed Jan 29 15:18:35 2025
15:18:35 [2025-01-29T15:18:35.950Z]  26.59% done, estimate finish Wed Jan 29 15:18:35 2025
15:18:35 [2025-01-29T15:18:35.950Z]  27.51% done, estimate finish Wed Jan 29 15:18:35 2025
15:18:35 [2025-01-29T15:18:35.950Z]  28.43% done, estimate finish Wed Jan 29 15:18:35 2025
15:18:35 [2025-01-29T15:18:35.950Z]  29.35% done, estimate finish Wed Jan 29 15:18:35 2025
15:18:35 [2025-01-29T15:18:35.950Z]  30.26% done, estimate finish Wed Jan 29 15:18:35 2025
15:18:35 [2025-01-29T15:18:35.950Z]  31.18% done, estimate finish Wed Jan 29 15:18:35 2025
15:18:35 [2025-01-29T15:18:35.950Z]  32.09% done, estimate finish Wed Jan 29 15:18:35 2025
15:18:35 [2025-01-29T15:18:35.950Z]  33.01% done, estimate finish Wed Jan 29 15:18:35 2025
15:18:35 [2025-01-29T15:18:35.950Z]  33.93% done, estimate finish Wed Jan 29 15:18:35 2025
15:18:36 [2025-01-29T15:18:36.201Z]  34.85% done, estimate finish Wed Jan 29 15:18:35 2025
15:18:36 [2025-01-29T15:18:36.201Z]  35.76% done, estimate finish Wed Jan 29 15:18:35 2025
15:18:36 [2025-01-29T15:18:36.201Z]  36.68% done, estimate finish Wed Jan 29 15:18:35 2025
15:18:36 [2025-01-29T15:18:36.201Z]  37.60% done, estimate finish Wed Jan 29 15:18:35 2025
15:18:36 [2025-01-29T15:18:36.201Z]  38.51% done, estimate finish Wed Jan 29 15:18:35 2025
15:18:36 [2025-01-29T15:18:36.201Z]  39.43% done, estimate finish Wed Jan 29 15:18:37 2025
15:18:36 [2025-01-29T15:18:36.201Z]  40.35% done, estimate finish Wed Jan 29 15:18:37 2025
15:18:36 [2025-01-29T15:18:36.201Z]  41.26% done, estimate finish Wed Jan 29 15:18:37 2025
15:18:36 [2025-01-29T15:18:36.201Z]  42.18% done, estimate finish Wed Jan 29 15:18:37 2025
15:18:36 [2025-01-29T15:18:36.201Z]  43.10% done, estimate finish Wed Jan 29 15:18:37 2025
15:18:36 [2025-01-29T15:18:36.201Z]  44.02% done, estimate finish Wed Jan 29 15:18:37 2025
15:18:36 [2025-01-29T15:18:36.201Z]  44.93% done, estimate finish Wed Jan 29 15:18:37 2025
15:18:36 [2025-01-29T15:18:36.201Z]  45.85% done, estimate finish Wed Jan 29 15:18:37 2025
15:18:36 [2025-01-29T15:18:36.201Z]  46.77% done, estimate finish Wed Jan 29 15:18:37 2025
15:18:36 [2025-01-29T15:18:36.202Z]  47.68% done, estimate finish Wed Jan 29 15:18:37 2025
15:18:36 [2025-01-29T15:18:36.202Z]  48.60% done, estimate finish Wed Jan 29 15:18:37 2025
15:18:36 [2025-01-29T15:18:36.202Z]  49.52% done, estimate finish Wed Jan 29 15:18:37 2025
15:18:36 [2025-01-29T15:18:36.202Z]  50.43% done, estimate finish Wed Jan 29 15:18:36 2025
15:18:36 [2025-01-29T15:18:36.202Z]  51.35% done, estimate finish Wed Jan 29 15:18:36 2025
15:18:36 [2025-01-29T15:18:36.202Z]  52.27% done, estimate finish Wed Jan 29 15:18:36 2025
15:18:36 [2025-01-29T15:18:36.202Z]  53.19% done, estimate finish Wed Jan 29 15:18:36 2025
15:18:36 [2025-01-29T15:18:36.202Z]  54.10% done, estimate finish Wed Jan 29 15:18:36 2025
15:18:36 [2025-01-29T15:18:36.202Z]  55.02% done, estimate finish Wed Jan 29 15:18:36 2025
15:18:36 [2025-01-29T15:18:36.454Z]  55.94% done, estimate finish Wed Jan 29 15:18:36 2025
15:18:36 [2025-01-29T15:18:36.454Z]  56.85% done, estimate finish Wed Jan 29 15:18:36 2025
15:18:36 [2025-01-29T15:18:36.454Z]  57.77% done, estimate finish Wed Jan 29 15:18:36 2025
15:18:36 [2025-01-29T15:18:36.454Z]  58.69% done, estimate finish Wed Jan 29 15:18:36 2025
15:18:36 [2025-01-29T15:18:36.454Z]  59.60% done, estimate finish Wed Jan 29 15:18:36 2025
15:18:36 [2025-01-29T15:18:36.454Z]  60.52% done, estimate finish Wed Jan 29 15:18:36 2025
15:18:36 [2025-01-29T15:18:36.454Z]  61.44% done, estimate finish Wed Jan 29 15:18:36 2025
15:18:36 [2025-01-29T15:18:36.454Z]  62.36% done, estimate finish Wed Jan 29 15:18:36 2025
15:18:36 [2025-01-29T15:18:36.454Z]  63.27% done, estimate finish Wed Jan 29 15:18:36 2025
15:18:36 [2025-01-29T15:18:36.454Z]  64.19% done, estimate finish Wed Jan 29 15:18:36 2025
15:18:36 [2025-01-29T15:18:36.454Z]  65.10% done, estimate finish Wed Jan 29 15:18:36 2025
15:18:36 [2025-01-29T15:18:36.454Z]  66.02% done, estimate finish Wed Jan 29 15:18:36 2025
15:18:36 [2025-01-29T15:18:36.454Z]  66.94% done, estimate finish Wed Jan 29 15:18:36 2025
15:18:36 [2025-01-29T15:18:36.454Z]  67.86% done, estimate finish Wed Jan 29 15:18:36 2025
15:18:36 [2025-01-29T15:18:36.454Z]  68.77% done, estimate finish Wed Jan 29 15:18:36 2025
15:18:36 [2025-01-29T15:18:36.454Z]  69.69% done, estimate finish Wed Jan 29 15:18:36 2025
15:18:36 [2025-01-29T15:18:36.454Z]  70.61% done, estimate finish Wed Jan 29 15:18:36 2025
15:18:36 [2025-01-29T15:18:36.454Z]  71.53% done, estimate finish Wed Jan 29 15:18:36 2025
15:18:36 [2025-01-29T15:18:36.454Z]  72.44% done, estimate finish Wed Jan 29 15:18:36 2025
15:18:36 [2025-01-29T15:18:36.454Z]  73.36% done, estimate finish Wed Jan 29 15:18:36 2025
15:18:36 [2025-01-29T15:18:36.454Z]  74.27% done, estimate finish Wed Jan 29 15:18:36 2025
15:18:36 [2025-01-29T15:18:36.454Z]  75.19% done, estimate finish Wed Jan 29 15:18:36 2025
15:18:36 [2025-01-29T15:18:36.454Z]  76.11% done, estimate finish Wed Jan 29 15:18:36 2025
15:18:36 [2025-01-29T15:18:36.454Z]  77.03% done, estimate finish Wed Jan 29 15:18:36 2025
15:18:36 [2025-01-29T15:18:36.708Z]  77.94% done, estimate finish Wed Jan 29 15:18:36 2025
15:18:36 [2025-01-29T15:18:36.708Z]  78.86% done, estimate finish Wed Jan 29 15:18:36 2025
15:18:36 [2025-01-29T15:18:36.708Z]  79.78% done, estimate finish Wed Jan 29 15:18:36 2025
15:18:36 [2025-01-29T15:18:36.708Z]  80.69% done, estimate finish Wed Jan 29 15:18:36 2025
15:18:36 [2025-01-29T15:18:36.708Z]  81.61% done, estimate finish Wed Jan 29 15:18:36 2025
15:18:36 [2025-01-29T15:18:36.708Z]  82.53% done, estimate finish Wed Jan 29 15:18:36 2025
15:18:36 [2025-01-29T15:18:36.708Z]  83.44% done, estimate finish Wed Jan 29 15:18:36 2025
15:18:36 [2025-01-29T15:18:36.708Z]  84.36% done, estimate finish Wed Jan 29 15:18:36 2025
15:18:36 [2025-01-29T15:18:36.708Z]  85.28% done, estimate finish Wed Jan 29 15:18:36 2025
15:18:36 [2025-01-29T15:18:36.708Z]  86.20% done, estimate finish Wed Jan 29 15:18:36 2025
15:18:36 [2025-01-29T15:18:36.708Z]  87.11% done, estimate finish Wed Jan 29 15:18:36 2025
15:18:36 [2025-01-29T15:18:36.708Z]  88.03% done, estimate finish Wed Jan 29 15:18:36 2025
15:18:36 [2025-01-29T15:18:36.708Z]  88.95% done, estimate finish Wed Jan 29 15:18:36 2025
15:18:36 [2025-01-29T15:18:36.708Z]  89.86% done, estimate finish Wed Jan 29 15:18:36 2025
15:18:36 [2025-01-29T15:18:36.708Z]  90.78% done, estimate finish Wed Jan 29 15:18:36 2025
15:18:36 [2025-01-29T15:18:36.708Z]  91.70% done, estimate finish Wed Jan 29 15:18:36 2025
15:18:36 [2025-01-29T15:18:36.708Z]  92.61% done, estimate finish Wed Jan 29 15:18:36 2025
15:18:36 [2025-01-29T15:18:36.708Z]  93.53% done, estimate finish Wed Jan 29 15:18:36 2025
15:18:36 [2025-01-29T15:18:36.708Z]  94.45% done, estimate finish Wed Jan 29 15:18:36 2025
15:18:36 [2025-01-29T15:18:36.708Z]  95.37% done, estimate finish Wed Jan 29 15:18:36 2025
15:18:36 [2025-01-29T15:18:36.708Z]  96.28% done, estimate finish Wed Jan 29 15:18:36 2025
15:18:36 [2025-01-29T15:18:36.708Z]  97.20% done, estimate finish Wed Jan 29 15:18:36 2025
15:18:36 [2025-01-29T15:18:36.960Z]  98.12% done, estimate finish Wed Jan 29 15:18:36 2025
15:18:36 [2025-01-29T15:18:36.960Z]  99.03% done, estimate finish Wed Jan 29 15:18:36 2025
15:18:36 [2025-01-29T15:18:36.960Z]  99.95% done, estimate finish Wed Jan 29 15:18:36 2025
15:18:36 [2025-01-29T15:18:36.960Z] Total translation table size: 2048
15:18:36 [2025-01-29T15:18:36.960Z] Total rockridge attributes bytes: 3006
15:18:36 [2025-01-29T15:18:36.960Z] Total directory bytes: 12288
15:18:36 [2025-01-29T15:18:36.960Z] Path table size(bytes): 96
15:18:36 [2025-01-29T15:18:36.960Z] Done with: The File(s)                             Block(s)    545087
15:18:36 [2025-01-29T15:18:36.960Z] Writing:   Ending Padblock                         Start Block 545131
15:18:36 [2025-01-29T15:18:36.960Z] Done with: Ending Padblock                         Block(s)    150
15:18:36 [2025-01-29T15:18:36.960Z] Max brk space used 1c000
15:18:36 [2025-01-29T15:18:36.960Z] 545281 extents written (1065 MB)
15:18:36 [2025-01-29T15:18:36.960Z] 2025-01-29 15:18:36,753 INFO - Running command: ['/usr/bin/isohybrid', '--uefi', '/home/jenkins/agent/workspace/test-override/tmp/buildpost-live/fedora-coreos-42.20250129.dev.0-live.x86_64.iso']
15:18:36 [2025-01-29T15:18:36.960Z] isohybrid: Warning: more than 1024 cylinders: 1066
15:18:36 [2025-01-29T15:18:36.960Z] isohybrid: Not all BIOSes will be able to boot this device
15:18:37 [2025-01-29T15:18:37.515Z] 2025-01-29 15:18:37,220 INFO - Running command: ['/usr/bin/genisoimage', '-verbose', '-V', 'fedora-coreos-42.20250129.dev.0', '-volset', 'fedora-coreos-42.20250129.dev.0', '-rational-rock', '-J', '-joliet-long', '-eltorito-boot', 'isolinux/isolinux.bin', '-eltorito-catalog', 'isolinux/boot.cat', '-no-emul-boot', '-boot-load-size', '4', '-boot-info-table', '-eltorito-alt-boot', '-efi-boot', 'images/efiboot.img', '-no-emul-boot', '-o', '/home/jenkins/agent/workspace/test-override/tmp/buildpost-live/fedora-coreos-42.20250129.dev.0-live.x86_64.iso.minimal', '/home/jenkins/agent/workspace/test-override/tmp/buildpost-live/live']
15:18:37 [2025-01-29T15:18:37.515Z] I: -input-charset not specified, using utf-8 (detected in locale settings)
15:18:37 [2025-01-29T15:18:37.515Z] genisoimage 1.1.11 (Linux)
15:18:37 [2025-01-29T15:18:37.515Z] Scanning /home/jenkins/agent/workspace/test-override/tmp/buildpost-live/live
15:18:37 [2025-01-29T15:18:37.515Z] Scanning /home/jenkins/agent/workspace/test-override/tmp/buildpost-live/live/coreos
15:18:37 [2025-01-29T15:18:37.515Z] Scanning /home/jenkins/agent/workspace/test-override/tmp/buildpost-live/live/images
15:18:37 [2025-01-29T15:18:37.515Z] Scanning /home/jenkins/agent/workspace/test-override/tmp/buildpost-live/live/images/pxeboot
15:18:37 [2025-01-29T15:18:37.515Z] Scanning /home/jenkins/agent/workspace/test-override/tmp/buildpost-live/live/isolinux
15:18:37 [2025-01-29T15:18:37.515Z] Scanning /home/jenkins/agent/workspace/test-override/tmp/buildpost-live/live/EFI
15:18:37 [2025-01-29T15:18:37.515Z] Scanning /home/jenkins/agent/workspace/test-override/tmp/buildpost-live/live/EFI/fedora
15:18:37 [2025-01-29T15:18:37.515Z] Writing:   Initial Padblock                        Start Block 0
15:18:37 [2025-01-29T15:18:37.515Z] Done with: Initial Padblock                        Block(s)    16
15:18:37 [2025-01-29T15:18:37.515Z] Writing:   Primary Volume Descriptor               Start Block 16
15:18:37 [2025-01-29T15:18:37.515Z] Done with: Primary Volume Descriptor               Block(s)    1
15:18:37 [2025-01-29T15:18:37.515Z] Writing:   Eltorito Volume Descriptor              Start Block 17
15:18:37 [2025-01-29T15:18:37.515Z] Size of boot image is 4 sectors -> No emulation
15:18:37 [2025-01-29T15:18:37.515Z] Size of boot image is 13104 sectors -> No emulation
15:18:37 [2025-01-29T15:18:37.515Z] Done with: Eltorito Volume Descriptor              Block(s)    1
15:18:37 [2025-01-29T15:18:37.515Z] Writing:   Joliet Volume Descriptor                Start Block 18
15:18:37 [2025-01-29T15:18:37.515Z] Done with: Joliet Volume Descriptor                Block(s)    1
15:18:37 [2025-01-29T15:18:37.515Z] Writing:   End Volume Descriptor                   Start Block 19
15:18:37 [2025-01-29T15:18:37.515Z] Done with: End Volume Descriptor                   Block(s)    1
15:18:37 [2025-01-29T15:18:37.515Z] Writing:   Version block                           Start Block 20
15:18:37 [2025-01-29T15:18:37.515Z] Done with: Version block                           Block(s)    1
15:18:37 [2025-01-29T15:18:37.515Z] Writing:   Path table                              Start Block 21
15:18:37 [2025-01-29T15:18:37.515Z] Done with: Path table                              Block(s)    4
15:18:37 [2025-01-29T15:18:37.515Z] Writing:   Joliet path table                       Start Block 25
15:18:37 [2025-01-29T15:18:37.515Z] Done with: Joliet path table                       Block(s)    4
15:18:37 [2025-01-29T15:18:37.515Z] Writing:   Directory tree                          Start Block 29
15:18:37 [2025-01-29T15:18:37.515Z] Done with: Directory tree                          Block(s)    7
15:18:37 [2025-01-29T15:18:37.515Z] Writing:   Joliet directory tree                   Start Block 36
15:18:37 [2025-01-29T15:18:37.515Z] Done with: Joliet directory tree                   Block(s)    7
15:18:37 [2025-01-29T15:18:37.515Z] Writing:   Directory tree cleanup                  Start Block 43
15:18:37 [2025-01-29T15:18:37.515Z] Done with: Directory tree cleanup                  Block(s)    0
15:18:37 [2025-01-29T15:18:37.515Z] Writing:   Extension record                        Start Block 43
15:18:37 [2025-01-29T15:18:37.515Z] Done with: Extension record                        Block(s)    1
15:18:37 [2025-01-29T15:18:37.515Z] Writing:   The File(s)                             Start Block 44
15:18:37 [2025-01-29T15:18:37.515Z]   8.45% done, estimate finish Wed Jan 29 15:18:37 2025
15:18:37 [2025-01-29T15:18:37.515Z]  16.87% done, estimate finish Wed Jan 29 15:18:37 2025
15:18:37 [2025-01-29T15:18:37.515Z]  25.31% done, estimate finish Wed Jan 29 15:18:37 2025
15:18:37 [2025-01-29T15:18:37.515Z]  33.73% done, estimate finish Wed Jan 29 15:18:37 2025
15:18:37 [2025-01-29T15:18:37.515Z]  42.18% done, estimate finish Wed Jan 29 15:18:37 2025
15:18:37 [2025-01-29T15:18:37.515Z]  50.60% done, estimate finish Wed Jan 29 15:18:37 2025
15:18:37 [2025-01-29T15:18:37.515Z]  59.05% done, estimate finish Wed Jan 29 15:18:37 2025
15:18:37 [2025-01-29T15:18:37.515Z]  67.47% done, estimate finish Wed Jan 29 15:18:37 2025
15:18:37 [2025-01-29T15:18:37.515Z]  75.91% done, estimate finish Wed Jan 29 15:18:37 2025
15:18:37 [2025-01-29T15:18:37.515Z]  84.33% done, estimate finish Wed Jan 29 15:18:37 2025
15:18:37 [2025-01-29T15:18:37.515Z]  92.79% done, estimate finish Wed Jan 29 15:18:37 2025
15:18:37 [2025-01-29T15:18:37.515Z] Total translation table size: 2048
15:18:37 [2025-01-29T15:18:37.515Z] Total rockridge attributes bytes: 2842
15:18:37 [2025-01-29T15:18:37.515Z] Total directory bytes: 12288
15:18:37 [2025-01-29T15:18:37.515Z] Path table size(bytes): 96
15:18:37 [2025-01-29T15:18:37.515Z] Done with: The File(s)                             Block(s)    59096
15:18:37 [2025-01-29T15:18:37.515Z] Writing:   Ending Padblock                         Start Block 59140
15:18:37 [2025-01-29T15:18:37.515Z] Done with: Ending Padblock                         Block(s)    150
15:18:37 [2025-01-29T15:18:37.515Z] Max brk space used 1b000
15:18:37 [2025-01-29T15:18:37.515Z] 59290 extents written (115 MB)
15:18:37 [2025-01-29T15:18:37.515Z] 2025-01-29 15:18:37,327 INFO - Running command: ['/usr/bin/isohybrid', '--uefi', '/home/jenkins/agent/workspace/test-override/tmp/buildpost-live/fedora-coreos-42.20250129.dev.0-live.x86_64.iso.minimal']
15:18:37 [2025-01-29T15:18:37.515Z] 2025-01-29 15:18:37,365 INFO - Running command: ['/usr/lib/coreos-assembler/runvm-coreos-installer', 'builds/42.20250129.dev.0/x86_64/fedora-coreos-42.20250129.dev.0-metal.x86_64.raw', '', 'pack', 'minimal-iso', '/home/jenkins/agent/workspace/test-override/tmp/buildpost-live/fedora-coreos-42.20250129.dev.0-live.x86_64.iso', '/home/jenkins/agent/workspace/test-override/tmp/buildpost-live/fedora-coreos-42.20250129.dev.0-live.x86_64.iso.minimal', '--consume']
15:18:55 [2025-01-29T15:18:55.525Z] + RUST_BACKTRACE=full
15:18:55 [2025-01-29T15:18:55.525Z] + chroot /sysroot/ostree/deploy/fedora-coreos/deploy/5c17cd7b0c9e1318d314543654d242413e8e08f2c45938f6aae1cb09deedf388.0 env -C /home/jenkins/agent/workspace/test-override coreos-installer pack minimal-iso /home/jenkins/agent/workspace/test-override/tmp/buildpost-live/fedora-coreos-42.20250129.dev.0-live.x86_64.iso /home/jenkins/agent/workspace/test-override/tmp/buildpost-live/fedora-coreos-42.20250129.dev.0-live.x86_64.iso.minimal --consume
15:18:55 [2025-01-29T15:18:55.525Z] Packing minimal ISO
15:18:55 [2025-01-29T15:18:55.525Z] Matched 17 files of 17
15:18:55 [2025-01-29T15:18:55.525Z] Total bytes skipped: 121006359
15:18:55 [2025-01-29T15:18:55.525Z] Total bytes written: 628457
15:18:55 [2025-01-29T15:18:55.525Z] Total bytes written (compressed): 2800
15:18:55 [2025-01-29T15:18:55.525Z] Verifying that packed image matches digest
15:18:55 [2025-01-29T15:18:55.525Z] Packing successful!
15:18:55 [2025-01-29T15:18:55.525Z] + '[' -f /var/tmp/coreos-installer-output ']'
15:18:58 [2025-01-29T15:18:58.023Z] Updated: builds/42.20250129.dev.0/x86_64/meta.json
15:18:58 [2025-01-29T15:18:58.026Z] [Pipeline] }
15:18:58 [2025-01-29T15:18:58.029Z] [Pipeline] // withEnv
15:18:58 [2025-01-29T15:18:58.046Z] [Pipeline] withEnv
15:18:58 [2025-01-29T15:18:58.046Z] [Pipeline] {
15:18:58 [2025-01-29T15:18:58.085Z] [Pipeline] sh
15:18:58 [2025-01-29T15:18:58.680Z] + set -xeuo pipefail
15:18:58 [2025-01-29T15:18:58.680Z] ++ umask
15:18:58 [2025-01-29T15:18:58.680Z] + '[' 0022 = 0000 ']'
15:18:58 [2025-01-29T15:18:58.680Z] + cosa shell -- env XZ_DEFAULTS=--memlimit=4G cosa compress --artifact=metal
15:18:58 [2025-01-29T15:18:58.680Z] Targeting build: 42.20250129.dev.0
15:18:58 [2025-01-29T15:18:58.933Z] Compressing: builds/42.20250129.dev.0/x86_64
15:18:58 [2025-01-29T15:18:58.933Z] 2025-01-29 15:18:58,726 INFO - Running command: ['xz', '-c9', '-T6', 'builds/42.20250129.dev.0/x86_64/fedora-coreos-42.20250129.dev.0-metal.x86_64.raw']
15:23:50 [2025-01-29T15:23:50.461Z] Compressed: fedora-coreos-42.20250129.dev.0-metal.x86_64.raw.xz
15:23:50 [2025-01-29T15:23:50.462Z] Skipped compressing artifacts: ostree oci-manifest live-iso live-kernel live-initramfs live-rootfs
15:23:50 [2025-01-29T15:23:50.462Z] Updated: builds/42.20250129.dev.0/x86_64/meta.json
15:23:50 [2025-01-29T15:23:50.465Z] [Pipeline] }
15:23:50 [2025-01-29T15:23:50.468Z] [Pipeline] // withEnv
15:23:50 [2025-01-29T15:23:50.515Z] [Pipeline] }
15:23:50 [2025-01-29T15:23:50.559Z] [Pipeline] // stage
15:23:50 [2025-01-29T15:23:50.591Z] [Pipeline] stage
15:23:50 [2025-01-29T15:23:50.591Z] [Pipeline] { (x86_64:kola:testiso)
15:23:50 [2025-01-29T15:23:50.656Z] [Pipeline] withEnv
15:23:50 [2025-01-29T15:23:50.656Z] [Pipeline] {
15:23:50 [2025-01-29T15:23:50.660Z] [Pipeline] sh
15:23:51 [2025-01-29T15:23:51.257Z] + set -euo pipefail
15:23:51 [2025-01-29T15:23:51.257Z] ++ umask
15:23:51 [2025-01-29T15:23:51.257Z] + '[' 0022 = 0000 ']'
15:23:51 [2025-01-29T15:23:51.257Z] + uuidgen
15:23:51 [2025-01-29T15:23:51.257Z] + cut -f1 -d-
15:23:51 [2025-01-29T15:23:51.261Z] [Pipeline] }
15:23:51 [2025-01-29T15:23:51.264Z] [Pipeline] // withEnv
15:23:51 [2025-01-29T15:23:51.320Z] [Pipeline] withEnv
15:23:51 [2025-01-29T15:23:51.320Z] [Pipeline] {
15:23:51 [2025-01-29T15:23:51.332Z] [Pipeline] sh
15:23:51 [2025-01-29T15:23:51.864Z] + set -euo pipefail
15:23:51 [2025-01-29T15:23:51.865Z] ++ umask
15:23:51 [2025-01-29T15:23:51.865Z] + '[' 0022 = 0000 ']'
15:23:51 [2025-01-29T15:23:51.865Z] + cd /home/jenkins/agent/workspace/test-override
15:23:51 [2025-01-29T15:23:51.865Z] + cosa shell -- mktemp -d /home/jenkins/agent/workspace/test-override/tmp/kola-XXXXX
15:23:51 [2025-01-29T15:23:51.867Z] [Pipeline] }
15:23:51 [2025-01-29T15:23:51.870Z] [Pipeline] // withEnv
15:23:51 [2025-01-29T15:23:51.877Z] [Pipeline] withEnv
15:23:51 [2025-01-29T15:23:51.877Z] [Pipeline] {
15:23:51 [2025-01-29T15:23:51.900Z] [Pipeline] sh
15:23:52 [2025-01-29T15:23:52.427Z] + set -xeuo pipefail
15:23:52 [2025-01-29T15:23:52.427Z] ++ umask
15:23:52 [2025-01-29T15:23:52.427Z] + '[' 0022 = 0000 ']'
15:23:52 [2025-01-29T15:23:52.427Z] + cd /home/jenkins/agent/workspace/test-override
15:23:52 [2025-01-29T15:23:52.427Z] + cosa kola testiso --inst-insecure --output-dir /home/jenkins/agent/workspace/test-override/tmp/kola-YxCur/kola-testiso-x86_64
15:23:52 [2025-01-29T15:23:52.427Z] kola -p qemu testiso --inst-insecure --output-dir /home/jenkins/agent/workspace/test-override/tmp/kola-YxCur/kola-testiso-x86_64
15:23:52 [2025-01-29T15:23:52.681Z] ����  Snoozing kola test pattern "ext.config.rpm-ostree.kernel-replace" until Feb 04 2025
15:23:52 [2025-01-29T15:23:52.681Z]   ���� https://github.com/coreos/fedora-coreos-tracker/issues/1870
15:23:52 [2025-01-29T15:23:52.681Z] Ignoring verification of signature on metal image
15:23:52 [2025-01-29T15:23:52.681Z] Running test: iso-as-disk.bios
15:24:19 [2025-01-29T15:24:19.134Z] PASS: iso-as-disk.bios (18.374s)
15:24:19 [2025-01-29T15:24:19.135Z] Running test: iso-as-disk.uefi
15:24:45 [2025-01-29T15:24:45.586Z] PASS: iso-as-disk.uefi (22.435s)
15:24:45 [2025-01-29T15:24:45.586Z] Running test: iso-as-disk.uefi-secure
15:25:12 [2025-01-29T15:25:12.059Z] PASS: iso-as-disk.uefi-secure (23.177s)
15:25:12 [2025-01-29T15:25:12.059Z] Running test: iso-as-disk.4k.uefi
15:25:38 [2025-01-29T15:25:38.517Z] PASS: iso-as-disk.4k.uefi (22.508s)
15:25:38 [2025-01-29T15:25:38.517Z] Running test: iso-install.bios
15:30:14 [2025-01-29T15:30:14.947Z] PASS: iso-install.bios (4m24.158s)
15:30:14 [2025-01-29T15:30:14.947Z] Running test: iso-live-login.bios
15:30:53 [2025-01-29T15:30:53.562Z] The file: journal.txt does not exist
15:30:53 [2025-01-29T15:30:53.562Z] PASS: iso-live-login.bios (31.142s)
15:30:53 [2025-01-29T15:30:53.562Z] Running test: iso-live-login.uefi
15:31:20 [2025-01-29T15:31:20.020Z] The file: journal.txt does not exist
15:31:20 [2025-01-29T15:31:20.020Z] PASS: iso-live-login.uefi (26.38s)
15:31:20 [2025-01-29T15:31:20.020Z] Running test: iso-live-login.uefi-secure
15:31:52 [2025-01-29T15:31:52.005Z] The file: journal.txt does not exist
15:31:52 [2025-01-29T15:31:52.005Z] PASS: iso-live-login.uefi-secure (26.943s)
15:31:52 [2025-01-29T15:31:52.005Z] Running test: iso-live-login.4k.uefi
15:32:23 [2025-01-29T15:32:23.985Z] The file: journal.txt does not exist
15:32:23 [2025-01-29T15:32:23.985Z] PASS: iso-live-login.4k.uefi (26.256s)
15:32:23 [2025-01-29T15:32:23.985Z] Running test: iso-offline-install.bios
15:36:45 [2025-01-29T15:36:45.487Z] PASS: iso-offline-install.bios (4m7.13s)
15:36:45 [2025-01-29T15:36:45.488Z] Running test: iso-offline-install.mpath.bios
15:41:06 [2025-01-29T15:41:06.902Z] PASS: iso-offline-install.mpath.bios (4m10.872s)
15:41:06 [2025-01-29T15:41:06.902Z] Running test: iso-offline-install-fromram.4k.uefi
15:45:28 [2025-01-29T15:45:28.313Z] PASS: iso-offline-install-fromram.4k.uefi (4m15.116s)
15:45:28 [2025-01-29T15:45:28.313Z] Running test: iso-offline-install-iscsi.ibft.uefi
15:49:19 [2025-01-29T15:49:19.727Z] PASS: iso-offline-install-iscsi.ibft.uefi (3m44.103s)
15:49:19 [2025-01-29T15:49:19.727Z] Running test: iso-offline-install-iscsi.ibft-with-mpath.bios
15:53:26 [2025-01-29T15:53:26.126Z] PASS: iso-offline-install-iscsi.ibft-with-mpath.bios (3m51.47s)
15:53:26 [2025-01-29T15:53:26.126Z] Running test: iso-offline-install-iscsi.manual.bios
15:57:17 [2025-01-29T15:57:17.559Z] PASS: iso-offline-install-iscsi.manual.bios (3m47.838s)
15:57:17 [2025-01-29T15:57:17.559Z] Running test: miniso-install.bios
16:01:54 [2025-01-29T16:01:54.037Z] PASS: miniso-install.bios (4m24.114s)
16:01:54 [2025-01-29T16:01:54.037Z] Running test: miniso-install.nm.bios
16:06:46 [2025-01-29T16:06:46.170Z] PASS: miniso-install.nm.bios (4m31.524s)
16:06:46 [2025-01-29T16:06:46.170Z] Running test: miniso-install.4k.uefi
16:11:22 [2025-01-29T16:11:22.604Z] PASS: miniso-install.4k.uefi (4m30.942s)
16:11:22 [2025-01-29T16:11:22.604Z] Running test: miniso-install.4k.nm.uefi
16:16:14 [2025-01-29T16:16:14.038Z] PASS: miniso-install.4k.nm.uefi (4m37.633s)
16:16:14 [2025-01-29T16:16:14.038Z] Running test: pxe-offline-install.bios
16:20:50 [2025-01-29T16:20:50.941Z] PASS: pxe-offline-install.bios (4m21.955s)
16:20:50 [2025-01-29T16:20:50.941Z] Running test: pxe-offline-install.4k.uefi
16:25:27 [2025-01-29T16:25:27.367Z] PASS: pxe-offline-install.4k.uefi (4m30.747s)
16:25:27 [2025-01-29T16:25:27.367Z] Running test: pxe-online-install.bios
16:30:03 [2025-01-29T16:30:03.779Z] PASS: pxe-online-install.bios (4m21.11s)
16:30:03 [2025-01-29T16:30:03.779Z] Running test: pxe-online-install.4k.uefi
16:34:40 [2025-01-29T16:34:40.196Z] PASS: pxe-online-install.4k.uefi (4m23.705s)
16:34:40 [2025-01-29T16:34:40.210Z] [Pipeline] }
16:34:40 [2025-01-29T16:34:40.213Z] [Pipeline] // withEnv
16:34:40 [2025-01-29T16:34:40.220Z] [Pipeline] withEnv
16:34:40 [2025-01-29T16:34:40.221Z] [Pipeline] {
16:34:40 [2025-01-29T16:34:40.224Z] [Pipeline] sh
16:34:41 [2025-01-29T16:34:41.025Z] + set -xeuo pipefail
16:34:41 [2025-01-29T16:34:41.025Z] ++ umask
16:34:41 [2025-01-29T16:34:41.025Z] + '[' 0022 = 0000 ']'
16:34:41 [2025-01-29T16:34:41.025Z] + cd /home/jenkins/agent/workspace/test-override
16:34:41 [2025-01-29T16:34:41.025Z] + cosa shell -- tar -C /home/jenkins/agent/workspace/test-override/tmp/kola-YxCur -c --xz kola-testiso-x86_64
16:34:53 [2025-01-29T16:34:53.356Z] [Pipeline] }
16:34:53 [2025-01-29T16:34:53.359Z] [Pipeline] // withEnv
16:34:53 [2025-01-29T16:34:53.380Z] [Pipeline] archiveArtifacts
16:34:53 [2025-01-29T16:34:53.382Z] Archiving artifacts
16:34:54 [2025-01-29T16:34:54.561Z] [Pipeline] withEnv
16:34:54 [2025-01-29T16:34:54.562Z] [Pipeline] {
16:34:54 [2025-01-29T16:34:54.575Z] [Pipeline] sh
16:34:55 [2025-01-29T16:34:55.107Z] + set -xeuo pipefail
16:34:55 [2025-01-29T16:34:55.107Z] ++ umask
16:34:55 [2025-01-29T16:34:55.107Z] + '[' 0022 = 0000 ']'
16:34:55 [2025-01-29T16:34:55.107Z] + cd /home/jenkins/agent/workspace/test-override
16:34:55 [2025-01-29T16:34:55.107Z] + cosa shell -- /usr/lib/coreos-assembler/kola-junit --classname kola-testiso-x86_64 --koladir /home/jenkins/agent/workspace/test-override/tmp/kola-YxCur/kola-testiso-x86_64 --output -
16:34:55 [2025-01-29T16:34:55.109Z] [Pipeline] }
16:34:55 [2025-01-29T16:34:55.112Z] [Pipeline] // withEnv
16:34:55 [2025-01-29T16:34:55.117Z] [Pipeline] junit
16:34:55 [2025-01-29T16:34:55.118Z] Recording test results
16:34:55 [2025-01-29T16:34:55.228Z] None of the test reports contained any result
16:34:55 [2025-01-29T16:34:55.228Z] [Pipeline] }
16:34:55 [2025-01-29T16:34:55.240Z] [Pipeline] // stage
16:34:55 [2025-01-29T16:34:55.243Z] [Pipeline] stage
16:34:55 [2025-01-29T16:34:55.243Z] [Pipeline] { (x86_64:Destroy Remote)
16:34:55 [2025-01-29T16:34:55.253Z] [Pipeline] }
16:34:55 [2025-01-29T16:34:55.277Z] [Pipeline] // stage
16:34:55 [2025-01-29T16:34:55.280Z] [Pipeline] }
16:34:55 [2025-01-29T16:34:55.294Z] [Pipeline] // parallel
16:34:55 [2025-01-29T16:34:55.319Z] [Pipeline] }
16:34:55 [2025-01-29T16:34:55.328Z] [Pipeline] // timeout
16:34:55 [2025-01-29T16:34:55.331Z] [Pipeline] }
16:34:55 [2025-01-29T16:34:55.345Z] [Pipeline] // container
16:34:55 [2025-01-29T16:34:55.354Z] [Pipeline] }
16:34:55 [2025-01-29T16:34:55.369Z] [Pipeline] // node
16:34:55 [2025-01-29T16:34:55.396Z] [Pipeline] }
16:34:55 [2025-01-29T16:34:55.446Z] [Pipeline] // podTemplate
16:34:55 [2025-01-29T16:34:55.475Z] [Pipeline] End of Pipeline
16:34:55 [2025-01-29T16:34:55.612Z] Finished: SUCCESS