Console Output

18:41:58 [2025-01-30T18:41:58.908Z] + set -xeuo pipefail
18:41:58 [2025-01-30T18:41:58.908Z] ++ umask
18:41:58 [2025-01-30T18:41:58.908Z] + '[' 0022 = 0000 ']'
18:41:58 [2025-01-30T18:41:58.908Z] + cosa buildextend-live --fast
18:41:58 [2025-01-30T18:41:58.908Z] Targeting build: 42.20250130.dev.0
18:41:59 [2025-01-30T18:41:59.169Z] 2025-01-30 18:41:58,974 INFO - Running command: ['/usr/bin/ostree', 'ls', '--repo', '/home/jenkins/agent/workspace/test-override/tmp/repo', '--nul-filenames-only', '0168071bcadc289e0758b60b5f39210c09db9bd1ff3554d2c15549577f48cae1', '/usr/lib/modules']
18:41:59 [2025-01-30T18:41:59.169Z] 2025-01-30 18:41:58,987 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', '0168071bcadc289e0758b60b5f39210c09db9bd1ff3554d2c15549577f48cae1', '/home/jenkins/agent/workspace/test-override/tmp/buildpost-live/live/images/pxeboot']
18:41:59 [2025-01-30T18:41:59.169Z] 2025-01-30 18:41:59,037 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', '0168071bcadc289e0758b60b5f39210c09db9bd1ff3554d2c15549577f48cae1', '/home/jenkins/agent/workspace/test-override/tmp/buildpost-live/live/images/pxeboot']
18:41:59 [2025-01-30T18:41:59.422Z] 2025-01-30 18:41:59,249 INFO - Running command: ['/usr/bin/ostree', 'cat', '--repo', '/home/jenkins/agent/workspace/test-override/tmp/repo', '0168071bcadc289e0758b60b5f39210c09db9bd1ff3554d2c15549577f48cae1', '/usr/share/coreos-installer/example-config.yaml']
18:41:59 [2025-01-30T18:41:59.422Z] 2025-01-30 18:41:59,268 INFO - Running command: ['/usr/bin/ostree', 'checkout', '--repo', '/home/jenkins/agent/workspace/test-override/tmp/repo', '--user-mode', '--subpath', '/usr/lib/os-release', '0168071bcadc289e0758b60b5f39210c09db9bd1ff3554d2c15549577f48cae1', '/tmp/tmpnoh5k4__']
18:41:59 [2025-01-30T18:41:59.422Z] Generating osmet file for 512b metal image
18:41:59 [2025-01-30T18:41:59.422Z] 2025-01-30 18:41:59,304 INFO - Running command: ['/usr/lib/coreos-assembler/runvm-coreos-installer', 'builds/42.20250130.dev.0/x86_64/fedora-coreos-42.20250130.dev.0-metal.x86_64.raw', '/home/jenkins/agent/workspace/test-override/tmp/buildpost-live/initrd-rootfs/fedora-coreos-42.20250130.dev.0-metal.x86_64.raw.osmet', 'pack', 'osmet', '/dev/disk/by-id/virtio-coreos', '--description', 'Fedora CoreOS 42.20250130.dev.0', '--checksum', 'f9194643f15338c2fd8c8193b47cfd2e425e286edc59cddece92d957d9df7f5b', '--output', '/var/tmp/coreos-installer-output', '--fast']
18:42:17 [2025-01-30T18:42:17.425Z] + RUST_BACKTRACE=full
18:42:17 [2025-01-30T18:42:17.425Z] + chroot /sysroot/ostree/deploy/fedora-coreos/deploy/57093b9e401453407f199966b01ab6f753692c1128d14319ba755910de1e8f53.0 env -C /home/jenkins/agent/workspace/test-override coreos-installer pack osmet /dev/disk/by-id/virtio-coreos --description 'Fedora CoreOS 42.20250130.dev.0' --checksum f9194643f15338c2fd8c8193b47cfd2e425e286edc59cddece92d957d9df7f5b --output /var/tmp/coreos-installer-output --fast
18:42:35 [2025-01-30T18:42:35.441Z] Total OSTree objects scanned from /root: 17831 (17825 mapped, 6 empty)
18:42:35 [2025-01-30T18:42:35.441Z] Total OSTree objects found in /boot: 313
18:42:35 [2025-01-30T18:42:35.441Z] Duplicate extents dropped: 0
18:42:35 [2025-01-30T18:42:35.441Z] Overlapping extents clamped: 0
18:42:35 [2025-01-30T18:42:35.441Z] Total /root extents: 17825
18:42:35 [2025-01-30T18:42:35.441Z] Duplicate extents dropped: 0
18:42:35 [2025-01-30T18:42:35.441Z] Overlapping extents clamped: 0
18:42:35 [2025-01-30T18:42:35.441Z] Total /boot extents: 318
18:42:35 [2025-01-30T18:42:35.441Z] Packing image
18:42:47 [2025-01-30T18:42:47.576Z] Total bytes skipped: 1658864640
18:42:47 [2025-01-30T18:42:47.576Z] Total bytes written: 1089453056
18:42:47 [2025-01-30T18:42:47.576Z] Total bytes written (compressed): 25389824
18:42:47 [2025-01-30T18:42:47.576Z] Verifying that repacked image matches digest
18:42:57 [2025-01-30T18:42:57.481Z] Packing successful!
18:42:57 [2025-01-30T18:42:57.481Z] + '[' -f /var/tmp/coreos-installer-output ']'
18:42:57 [2025-01-30T18:42:57.481Z] + cp /var/tmp/coreos-installer-output /dev/virtio-ports/coreosout
18:42:59 [2025-01-30T18:42:59.980Z] Generating osmet file for 4k metal image
18:42:59 [2025-01-30T18:42:59.980Z] 2025-01-30 18:42:59,423 INFO - Running command: ['/usr/lib/coreos-assembler/runvm-coreos-installer', 'builds/42.20250130.dev.0/x86_64/fedora-coreos-42.20250130.dev.0-metal4k.x86_64.raw', '/home/jenkins/agent/workspace/test-override/tmp/buildpost-live/initrd-rootfs/fedora-coreos-42.20250130.dev.0-metal4k.x86_64.raw.osmet', 'pack', 'osmet', '/dev/disk/by-id/virtio-coreos', '--description', 'Fedora CoreOS 42.20250130.dev.0', '--checksum', '6988c3787c3f8969487736d4f768589c35d64e010f5d5c56180546f3ef5922e8', '--output', '/var/tmp/coreos-installer-output', '--fast']
18:43:17 [2025-01-30T18:43:17.993Z] + RUST_BACKTRACE=full
18:43:17 [2025-01-30T18:43:17.993Z] + chroot /sysroot/ostree/deploy/fedora-coreos/deploy/57093b9e401453407f199966b01ab6f753692c1128d14319ba755910de1e8f53.0 env -C /home/jenkins/agent/workspace/test-override coreos-installer pack osmet /dev/disk/by-id/virtio-coreos --description 'Fedora CoreOS 42.20250130.dev.0' --checksum 6988c3787c3f8969487736d4f768589c35d64e010f5d5c56180546f3ef5922e8 --output /var/tmp/coreos-installer-output --fast
18:43:17 [2025-01-30T18:43:17.993Z] Total OSTree objects scanned from /root: 17831 (17825 mapped, 6 empty)
18:43:17 [2025-01-30T18:43:17.993Z] Total OSTree objects found in /boot: 3
18:43:17 [2025-01-30T18:43:17.993Z] Duplicate extents dropped: 0
18:43:17 [2025-01-30T18:43:17.993Z] Overlapping extents clamped: 0
18:43:17 [2025-01-30T18:43:17.993Z] Total /root extents: 17826
18:43:17 [2025-01-30T18:43:17.993Z] Duplicate extents dropped: 0
18:43:17 [2025-01-30T18:43:17.993Z] Overlapping extents clamped: 0
18:43:17 [2025-01-30T18:43:17.993Z] Total /boot extents: 3
18:43:17 [2025-01-30T18:43:17.993Z] Packing image
18:43:32 [2025-01-30T18:43:32.809Z] Total bytes skipped: 1648017408
18:43:32 [2025-01-30T18:43:32.809Z] Total bytes written: 1100300288
18:43:32 [2025-01-30T18:43:32.809Z] Total bytes written (compressed): 25329984
18:43:32 [2025-01-30T18:43:32.809Z] Verifying that repacked image matches digest
18:43:39 [2025-01-30T18:43:39.319Z] Packing successful!
18:43:39 [2025-01-30T18:43:39.319Z] + '[' -f /var/tmp/coreos-installer-output ']'
18:43:39 [2025-01-30T18:43:39.319Z] + cp /var/tmp/coreos-installer-output /dev/virtio-ports/coreosout
18:43:40 [2025-01-30T18:43:40.671Z] Compressing squashfs with lz4
18:43:40 [2025-01-30T18:43:40.671Z] 2025-01-30 18:43:40,597 INFO - Running command: ['/usr/lib/coreos-assembler/gf-mksquashfs', 'builds/42.20250130.dev.0/x86_64/fedora-coreos-42.20250130.dev.0-metal.x86_64.raw', '/home/jenkins/agent/workspace/test-override/tmp/buildpost-live/initrd-rootfs/root.squashfs', 'lz4']
18:43:40 [2025-01-30T18:43:40.924Z] +++ dirname /home/jenkins/agent/workspace/test-override/tmp/buildpost-live/initrd-rootfs/root.squashfs
18:43:40 [2025-01-30T18:43:40.924Z] ++ mktemp -tdp /home/jenkins/agent/workspace/test-override/tmp/buildpost-live/initrd-rootfs gf-mksquashfs.XXXXXX
18:43:40 [2025-01-30T18:43:40.924Z] + tmpd=/home/jenkins/agent/workspace/test-override/tmp/buildpost-live/initrd-rootfs/gf-mksquashfs.PS5zmG
18:43:40 [2025-01-30T18:43:40.924Z] + tmp_dest=/home/jenkins/agent/workspace/test-override/tmp/buildpost-live/initrd-rootfs/gf-mksquashfs.PS5zmG/image.squashfs
18:43:40 [2025-01-30T18:43:40.924Z] + coreos_gf_run_mount builds/42.20250130.dev.0/x86_64/fedora-coreos-42.20250130.dev.0-metal.x86_64.raw --ro
18:43:40 [2025-01-30T18:43:40.924Z] + local mntarg=mount
18:43:40 [2025-01-30T18:43:40.924Z] + '[' builds/42.20250130.dev.0/x86_64/fedora-coreos-42.20250130.dev.0-metal.x86_64.raw = ro ']'
18:43:40 [2025-01-30T18:43:40.924Z] + coreos_gf_run builds/42.20250130.dev.0/x86_64/fedora-coreos-42.20250130.dev.0-metal.x86_64.raw --ro
18:43:40 [2025-01-30T18:43:40.924Z] + '[' -n '' ']'
18:43:40 [2025-01-30T18:43:40.924Z] + coreos_gf_launch builds/42.20250130.dev.0/x86_64/fedora-coreos-42.20250130.dev.0-metal.x86_64.raw --ro
18:43:40 [2025-01-30T18:43:40.924Z] + '[' -n '' ']'
18:43:40 [2025-01-30T18:43:40.924Z] ++ guestfish --listen -a builds/42.20250130.dev.0/x86_64/fedora-coreos-42.20250130.dev.0-metal.x86_64.raw --ro
18:43:40 [2025-01-30T18:43:40.924Z] + eval 'GUESTFISH_PID=11655; export GUESTFISH_PID'
18:43:40 [2025-01-30T18:43:40.924Z] ++ GUESTFISH_PID=11655
18:43:40 [2025-01-30T18:43:40.924Z] ++ export GUESTFISH_PID
18:43:40 [2025-01-30T18:43:40.924Z] + '[' -z 11655 ']'
18:43:40 [2025-01-30T18:43:40.924Z] ++ kola ncpu
18:43:40 [2025-01-30T18:43:40.924Z] + coreos_gf set-smp 6
18:43:40 [2025-01-30T18:43:40.924Z] + guestfish --remote -- set-smp 6
18:43:40 [2025-01-30T18:43:40.924Z] + coreos_gf run
18:43:40 [2025-01-30T18:43:40.924Z] + guestfish --remote -- run
18:43:42 [2025-01-30T18:43:42.797Z] + GUESTFISH_RUNNING=1
18:43:42 [2025-01-30T18:43:42.797Z] ++ coreos_gf findfs-label root
18:43:42 [2025-01-30T18:43:42.797Z] ++ guestfish --remote -- findfs-label root
18:43:42 [2025-01-30T18:43:42.797Z] + root=/dev/sda4
18:43:42 [2025-01-30T18:43:42.797Z] + coreos_gf mount /dev/sda4 /
18:43:42 [2025-01-30T18:43:42.797Z] + guestfish --remote -- mount /dev/sda4 /
18:43:43 [2025-01-30T18:43:43.351Z] + local boot
18:43:43 [2025-01-30T18:43:43.351Z] ++ coreos_gf findfs-label boot
18:43:43 [2025-01-30T18:43:43.351Z] ++ guestfish --remote -- findfs-label boot
18:43:43 [2025-01-30T18:43:43.351Z] + boot=/dev/sda3
18:43:43 [2025-01-30T18:43:43.351Z] + coreos_gf mount /dev/sda3 /boot
18:43:43 [2025-01-30T18:43:43.351Z] + guestfish --remote -- mount /dev/sda3 /boot
18:43:43 [2025-01-30T18:43:43.351Z] + local partitions
18:43:43 [2025-01-30T18:43:43.351Z] + local label
18:43:43 [2025-01-30T18:43:43.351Z] ++ coreos_gf list-partitions
18:43:43 [2025-01-30T18:43:43.351Z] ++ guestfish --remote -- list-partitions
18:43:43 [2025-01-30T18:43:43.351Z] + partitions='/dev/sda1
18:43:43 [2025-01-30T18:43:43.351Z] /dev/sda2
18:43:43 [2025-01-30T18:43:43.351Z] /dev/sda3
18:43:43 [2025-01-30T18:43:43.351Z] /dev/sda4'
18:43:43 [2025-01-30T18:43:43.351Z] + for pt in $partitions
18:43:43 [2025-01-30T18:43:43.351Z] ++ coreos_gf vfs-label /dev/sda1
18:43:43 [2025-01-30T18:43:43.351Z] ++ guestfish --remote -- vfs-label /dev/sda1
18:43:43 [2025-01-30T18:43:43.604Z] + label=
18:43:43 [2025-01-30T18:43:43.604Z] + '[' '' == EFI-SYSTEM ']'
18:43:43 [2025-01-30T18:43:43.604Z] + for pt in $partitions
18:43:43 [2025-01-30T18:43:43.604Z] ++ coreos_gf vfs-label /dev/sda2
18:43:43 [2025-01-30T18:43:43.604Z] ++ guestfish --remote -- vfs-label /dev/sda2
18:43:43 [2025-01-30T18:43:43.604Z] + label=EFI-SYSTEM
18:43:43 [2025-01-30T18:43:43.604Z] + '[' EFI-SYSTEM == EFI-SYSTEM ']'
18:43:43 [2025-01-30T18:43:43.604Z] + coreos_gf mount /dev/sda2 /boot/efi
18:43:43 [2025-01-30T18:43:43.604Z] + guestfish --remote -- mount /dev/sda2 /boot/efi
18:43:43 [2025-01-30T18:43:43.604Z] + for pt in $partitions
18:43:43 [2025-01-30T18:43:43.604Z] ++ coreos_gf vfs-label /dev/sda3
18:43:43 [2025-01-30T18:43:43.604Z] ++ guestfish --remote -- vfs-label /dev/sda3
18:43:43 [2025-01-30T18:43:43.604Z] + label=boot
18:43:43 [2025-01-30T18:43:43.604Z] + '[' boot == EFI-SYSTEM ']'
18:43:43 [2025-01-30T18:43:43.604Z] + for pt in $partitions
18:43:43 [2025-01-30T18:43:43.604Z] ++ coreos_gf vfs-label /dev/sda4
18:43:43 [2025-01-30T18:43:43.604Z] ++ guestfish --remote -- vfs-label /dev/sda4
18:43:43 [2025-01-30T18:43:43.604Z] + label=root
18:43:43 [2025-01-30T18:43:43.604Z] + '[' root == EFI-SYSTEM ']'
18:43:43 [2025-01-30T18:43:43.604Z] ++ coreos_gf ls /ostree/deploy
18:43:43 [2025-01-30T18:43:43.604Z] ++ guestfish --remote -- ls /ostree/deploy
18:43:43 [2025-01-30T18:43:43.604Z] + stateroot=/ostree/deploy/fedora-coreos
18:43:43 [2025-01-30T18:43:43.604Z] ++ coreos_gf ls /ostree/deploy/fedora-coreos/deploy
18:43:43 [2025-01-30T18:43:43.604Z] ++ guestfish --remote -- ls /ostree/deploy/fedora-coreos/deploy
18:43:43 [2025-01-30T18:43:43.604Z] ++ grep -v .origin
18:43:43 [2025-01-30T18:43:43.604Z] + deploydir=/ostree/deploy/fedora-coreos/deploy/57093b9e401453407f199966b01ab6f753692c1128d14319ba755910de1e8f53.0
18:43:43 [2025-01-30T18:43:43.604Z] + export stateroot deploydir
18:43:43 [2025-01-30T18:43:43.604Z] + coreos_gf download /ostree/repo/config /home/jenkins/agent/workspace/test-override/tmp/buildpost-live/initrd-rootfs/gf-mksquashfs.PS5zmG/config
18:43:43 [2025-01-30T18:43:43.604Z] + guestfish --remote -- download /ostree/repo/config /home/jenkins/agent/workspace/test-override/tmp/buildpost-live/initrd-rootfs/gf-mksquashfs.PS5zmG/config
18:43:43 [2025-01-30T18:43:43.604Z] + grep -v readonly=true /home/jenkins/agent/workspace/test-override/tmp/buildpost-live/initrd-rootfs/gf-mksquashfs.PS5zmG/config
18:43:43 [2025-01-30T18:43:43.604Z] + coreos_gf upload /home/jenkins/agent/workspace/test-override/tmp/buildpost-live/initrd-rootfs/gf-mksquashfs.PS5zmG/config.new /ostree/repo/config
18:43:43 [2025-01-30T18:43:43.604Z] + guestfish --remote -- upload /home/jenkins/agent/workspace/test-override/tmp/buildpost-live/initrd-rootfs/gf-mksquashfs.PS5zmG/config.new /ostree/repo/config
18:43:43 [2025-01-30T18:43:43.856Z] ++ coreos_gf glob-expand '/boot/ostree/*/vmlinuz*'
18:43:43 [2025-01-30T18:43:43.856Z] ++ guestfish --remote -- glob-expand '/boot/ostree/*/vmlinuz*'
18:43:43 [2025-01-30T18:43:43.856Z] + kernel_binary=/boot/ostree/fedora-coreos-63257d7eef98364d6c3105a40d3a74eb36503bebd3d7ec6c6974715191b0250e/vmlinuz-6.13.0-0.rc7.20250114gitc45323b7560e.56.fc42.x86_64
18:43:43 [2025-01-30T18:43:43.856Z] ++ coreos_gf glob-expand '/boot/ostree/*/.*.hmac'
18:43:43 [2025-01-30T18:43:43.856Z] ++ guestfish --remote -- glob-expand '/boot/ostree/*/.*.hmac'
18:43:43 [2025-01-30T18:43:43.856Z] + kernel_hmac=/boot/ostree/fedora-coreos-63257d7eef98364d6c3105a40d3a74eb36503bebd3d7ec6c6974715191b0250e/.vmlinuz-6.13.0-0.rc7.20250114gitc45323b7560e.56.fc42.x86_64.hmac
18:43:43 [2025-01-30T18:43:43.856Z] ++ basename /boot/ostree/fedora-coreos-63257d7eef98364d6c3105a40d3a74eb36503bebd3d7ec6c6974715191b0250e/.vmlinuz-6.13.0-0.rc7.20250114gitc45323b7560e.56.fc42.x86_64.hmac
18:43:43 [2025-01-30T18:43:43.856Z] + coreos_gf ln /boot/ostree/fedora-coreos-63257d7eef98364d6c3105a40d3a74eb36503bebd3d7ec6c6974715191b0250e/.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
18:43:43 [2025-01-30T18:43:43.856Z] + guestfish --remote -- ln /boot/ostree/fedora-coreos-63257d7eef98364d6c3105a40d3a74eb36503bebd3d7ec6c6974715191b0250e/.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
18:43:43 [2025-01-30T18:43:43.856Z] ++ basename /boot/ostree/fedora-coreos-63257d7eef98364d6c3105a40d3a74eb36503bebd3d7ec6c6974715191b0250e/vmlinuz-6.13.0-0.rc7.20250114gitc45323b7560e.56.fc42.x86_64
18:43:43 [2025-01-30T18:43:43.856Z] + coreos_gf ln /boot/ostree/fedora-coreos-63257d7eef98364d6c3105a40d3a74eb36503bebd3d7ec6c6974715191b0250e/vmlinuz-6.13.0-0.rc7.20250114gitc45323b7560e.56.fc42.x86_64 /boot/vmlinuz-6.13.0-0.rc7.20250114gitc45323b7560e.56.fc42.x86_64
18:43:43 [2025-01-30T18:43:43.856Z] + guestfish --remote -- ln /boot/ostree/fedora-coreos-63257d7eef98364d6c3105a40d3a74eb36503bebd3d7ec6c6974715191b0250e/vmlinuz-6.13.0-0.rc7.20250114gitc45323b7560e.56.fc42.x86_64 /boot/vmlinuz-6.13.0-0.rc7.20250114gitc45323b7560e.56.fc42.x86_64
18:43:43 [2025-01-30T18:43:43.856Z] + coreos_gf mksquashfs / /home/jenkins/agent/workspace/test-override/tmp/buildpost-live/initrd-rootfs/gf-mksquashfs.PS5zmG/image.squashfs compress:lz4
18:43:43 [2025-01-30T18:43:43.856Z] + guestfish --remote -- mksquashfs / /home/jenkins/agent/workspace/test-override/tmp/buildpost-live/initrd-rootfs/gf-mksquashfs.PS5zmG/image.squashfs compress:lz4
18:43:51 [2025-01-30T18:43:51.918Z] + coreos_gf_shutdown
18:43:51 [2025-01-30T18:43:51.918Z] + coreos_gf umount-all
18:43:51 [2025-01-30T18:43:51.918Z] + guestfish --remote -- umount-all
18:43:51 [2025-01-30T18:43:51.918Z] + coreos_gf exit
18:43:51 [2025-01-30T18:43:51.918Z] + guestfish --remote -- exit
18:43:51 [2025-01-30T18:43:51.918Z] + GUESTFISH_RUNNING=
18:43:51 [2025-01-30T18:43:51.918Z] + GUESTFISH_PID=
18:43:51 [2025-01-30T18:43:51.918Z] + mv /home/jenkins/agent/workspace/test-override/tmp/buildpost-live/initrd-rootfs/gf-mksquashfs.PS5zmG/image.squashfs /home/jenkins/agent/workspace/test-override/tmp/buildpost-live/initrd-rootfs/root.squashfs
18:43:51 [2025-01-30T18:43:51.918Z] + rm /home/jenkins/agent/workspace/test-override/tmp/buildpost-live/initrd-rootfs/gf-mksquashfs.PS5zmG -rf
18:43:51 [2025-01-30T18:43:51.918Z] + _coreos_gf_cleanup
18:43:51 [2025-01-30T18:43:51.918Z] + guestfish --remote -- exit
18:43:51 [2025-01-30T18:43:51.918Z] + :
18:43:57 [2025-01-30T18:43:57.130Z] 2025-01-30 18:43:56,680 INFO - Running command: ['/usr/lib/coreos-assembler/gf-get-kargs', 'builds/42.20250130.dev.0/x86_64/fedora-coreos-42.20250130.dev.0-metal.x86_64.raw']
18:43:57 [2025-01-30T18:43:57.130Z] + coreos_gf_run_mount builds/42.20250130.dev.0/x86_64/fedora-coreos-42.20250130.dev.0-metal.x86_64.raw --ro
18:43:57 [2025-01-30T18:43:57.130Z] + local mntarg=mount
18:43:57 [2025-01-30T18:43:57.130Z] + '[' builds/42.20250130.dev.0/x86_64/fedora-coreos-42.20250130.dev.0-metal.x86_64.raw = ro ']'
18:43:57 [2025-01-30T18:43:57.130Z] + coreos_gf_run builds/42.20250130.dev.0/x86_64/fedora-coreos-42.20250130.dev.0-metal.x86_64.raw --ro
18:43:57 [2025-01-30T18:43:57.130Z] + '[' -n '' ']'
18:43:57 [2025-01-30T18:43:57.130Z] + coreos_gf_launch builds/42.20250130.dev.0/x86_64/fedora-coreos-42.20250130.dev.0-metal.x86_64.raw --ro
18:43:57 [2025-01-30T18:43:57.130Z] + '[' -n '' ']'
18:43:57 [2025-01-30T18:43:57.130Z] ++ guestfish --listen -a builds/42.20250130.dev.0/x86_64/fedora-coreos-42.20250130.dev.0-metal.x86_64.raw --ro
18:43:57 [2025-01-30T18:43:57.130Z] + eval 'GUESTFISH_PID=11794; export GUESTFISH_PID'
18:43:57 [2025-01-30T18:43:57.131Z] ++ GUESTFISH_PID=11794
18:43:57 [2025-01-30T18:43:57.131Z] ++ export GUESTFISH_PID
18:43:57 [2025-01-30T18:43:57.131Z] + '[' -z 11794 ']'
18:43:57 [2025-01-30T18:43:57.131Z] ++ kola ncpu
18:43:57 [2025-01-30T18:43:57.131Z] + coreos_gf set-smp 6
18:43:57 [2025-01-30T18:43:57.131Z] + guestfish --remote -- set-smp 6
18:43:57 [2025-01-30T18:43:57.131Z] + coreos_gf run
18:43:57 [2025-01-30T18:43:57.131Z] + guestfish --remote -- run
18:43:59 [2025-01-30T18:43:59.008Z] + GUESTFISH_RUNNING=1
18:43:59 [2025-01-30T18:43:59.008Z] ++ coreos_gf findfs-label root
18:43:59 [2025-01-30T18:43:59.008Z] ++ guestfish --remote -- findfs-label root
18:43:59 [2025-01-30T18:43:59.008Z] + root=/dev/sda4
18:43:59 [2025-01-30T18:43:59.008Z] + coreos_gf mount /dev/sda4 /
18:43:59 [2025-01-30T18:43:59.008Z] + guestfish --remote -- mount /dev/sda4 /
18:43:59 [2025-01-30T18:43:59.261Z] + local boot
18:43:59 [2025-01-30T18:43:59.261Z] ++ coreos_gf findfs-label boot
18:43:59 [2025-01-30T18:43:59.261Z] ++ guestfish --remote -- findfs-label boot
18:43:59 [2025-01-30T18:43:59.261Z] + boot=/dev/sda3
18:43:59 [2025-01-30T18:43:59.261Z] + coreos_gf mount /dev/sda3 /boot
18:43:59 [2025-01-30T18:43:59.261Z] + guestfish --remote -- mount /dev/sda3 /boot
18:43:59 [2025-01-30T18:43:59.514Z] + local partitions
18:43:59 [2025-01-30T18:43:59.514Z] + local label
18:43:59 [2025-01-30T18:43:59.514Z] ++ coreos_gf list-partitions
18:43:59 [2025-01-30T18:43:59.514Z] ++ guestfish --remote -- list-partitions
18:43:59 [2025-01-30T18:43:59.514Z] + partitions='/dev/sda1
18:43:59 [2025-01-30T18:43:59.514Z] /dev/sda2
18:43:59 [2025-01-30T18:43:59.514Z] /dev/sda3
18:43:59 [2025-01-30T18:43:59.514Z] /dev/sda4'
18:43:59 [2025-01-30T18:43:59.514Z] + for pt in $partitions
18:43:59 [2025-01-30T18:43:59.514Z] ++ coreos_gf vfs-label /dev/sda1
18:43:59 [2025-01-30T18:43:59.514Z] ++ guestfish --remote -- vfs-label /dev/sda1
18:43:59 [2025-01-30T18:43:59.514Z] + label=
18:43:59 [2025-01-30T18:43:59.514Z] + '[' '' == EFI-SYSTEM ']'
18:43:59 [2025-01-30T18:43:59.514Z] + for pt in $partitions
18:43:59 [2025-01-30T18:43:59.514Z] ++ coreos_gf vfs-label /dev/sda2
18:43:59 [2025-01-30T18:43:59.514Z] ++ guestfish --remote -- vfs-label /dev/sda2
18:43:59 [2025-01-30T18:43:59.514Z] + label=EFI-SYSTEM
18:43:59 [2025-01-30T18:43:59.514Z] + '[' EFI-SYSTEM == EFI-SYSTEM ']'
18:43:59 [2025-01-30T18:43:59.514Z] + coreos_gf mount /dev/sda2 /boot/efi
18:43:59 [2025-01-30T18:43:59.514Z] + guestfish --remote -- mount /dev/sda2 /boot/efi
18:43:59 [2025-01-30T18:43:59.514Z] + for pt in $partitions
18:43:59 [2025-01-30T18:43:59.514Z] ++ coreos_gf vfs-label /dev/sda3
18:43:59 [2025-01-30T18:43:59.514Z] ++ guestfish --remote -- vfs-label /dev/sda3
18:43:59 [2025-01-30T18:43:59.514Z] + label=boot
18:43:59 [2025-01-30T18:43:59.514Z] + '[' boot == EFI-SYSTEM ']'
18:43:59 [2025-01-30T18:43:59.514Z] + for pt in $partitions
18:43:59 [2025-01-30T18:43:59.514Z] ++ coreos_gf vfs-label /dev/sda4
18:43:59 [2025-01-30T18:43:59.514Z] ++ guestfish --remote -- vfs-label /dev/sda4
18:43:59 [2025-01-30T18:43:59.514Z] + label=root
18:43:59 [2025-01-30T18:43:59.514Z] + '[' root == EFI-SYSTEM ']'
18:43:59 [2025-01-30T18:43:59.514Z] ++ coreos_gf ls /ostree/deploy
18:43:59 [2025-01-30T18:43:59.514Z] ++ guestfish --remote -- ls /ostree/deploy
18:43:59 [2025-01-30T18:43:59.514Z] + stateroot=/ostree/deploy/fedora-coreos
18:43:59 [2025-01-30T18:43:59.768Z] ++ coreos_gf ls /ostree/deploy/fedora-coreos/deploy
18:43:59 [2025-01-30T18:43:59.768Z] ++ guestfish --remote -- ls /ostree/deploy/fedora-coreos/deploy
18:43:59 [2025-01-30T18:43:59.768Z] ++ grep -v .origin
18:43:59 [2025-01-30T18:43:59.768Z] + deploydir=/ostree/deploy/fedora-coreos/deploy/57093b9e401453407f199966b01ab6f753692c1128d14319ba755910de1e8f53.0
18:43:59 [2025-01-30T18:43:59.768Z] + export stateroot deploydir
18:43:59 [2025-01-30T18:43:59.768Z] + coreos_gf glob read-file '/boot/loader/entries/ostree*conf'
18:43:59 [2025-01-30T18:43:59.768Z] + guestfish --remote -- glob read-file '/boot/loader/entries/ostree*conf'
18:43:59 [2025-01-30T18:43:59.768Z] + sed -e '/^options/!d' -e 's/^options\s*//'
18:43:59 [2025-01-30T18:43:59.768Z] + coreos_gf_shutdown
18:43:59 [2025-01-30T18:43:59.768Z] + coreos_gf umount-all
18:43:59 [2025-01-30T18:43:59.768Z] + guestfish --remote -- umount-all
18:43:59 [2025-01-30T18:43:59.768Z] + coreos_gf exit
18:43:59 [2025-01-30T18:43:59.768Z] + guestfish --remote -- exit
18:43:59 [2025-01-30T18:43:59.768Z] + GUESTFISH_RUNNING=
18:43:59 [2025-01-30T18:43:59.768Z] + GUESTFISH_PID=
18:43:59 [2025-01-30T18:43:59.768Z] + _coreos_gf_cleanup
18:43:59 [2025-01-30T18:43:59.768Z] + guestfish --remote -- exit
18:43:59 [2025-01-30T18:43:59.768Z] + :
18:43:59 [2025-01-30T18:43:59.768Z] Substituting ISO kernel arguments: rw mitigations=auto,nosmt coreos.liveiso=fedora-coreos-42.20250130.dev.0
18:43:59 [2025-01-30T18:43:59.768Z] src/config/live/zipl.prm -> /home/jenkins/agent/workspace/test-override/tmp/buildpost-live/live/zipl.prm
18:43:59 [2025-01-30T18:43:59.768Z] src/config/live/EFI/fedora/grub.cfg -> /home/jenkins/agent/workspace/test-override/tmp/buildpost-live/live/EFI/fedora/grub.cfg
18:43:59 [2025-01-30T18:43:59.768Z] src/config/live/isolinux/boot.msg -> /home/jenkins/agent/workspace/test-override/tmp/buildpost-live/live/isolinux/boot.msg
18:44:00 [2025-01-30T18:44:00.021Z] src/config/live/isolinux/isolinux.cfg -> /home/jenkins/agent/workspace/test-override/tmp/buildpost-live/live/isolinux/isolinux.cfg
18:44:00 [2025-01-30T18:44:00.021Z] 2025-01-30 18:43:59,770 INFO - Running command: ['/usr/bin/ostree', 'checkout', '--repo', '/home/jenkins/agent/workspace/test-override/tmp/repo', '--user-mode', '--subpath', '/usr/lib/bootupd/updates/EFI', '0168071bcadc289e0758b60b5f39210c09db9bd1ff3554d2c15549577f48cae1', '/home/jenkins/agent/workspace/test-override/tmp/buildpost-live/efi']
18:44:00 [2025-01-30T18:44:00.021Z] 2025-01-30 18:43:59,825 INFO - Running command: ['virt-make-fs', '--type=vfat', '--label=EFI-SYSTEM', '/tmp/tmpbadty2ga.tar', '/home/jenkins/agent/workspace/test-override/tmp/buildpost-live/live/images/efiboot.img']
18:44:01 [2025-01-30T18:44:01.896Z] 2025-01-30 18:44:01,669 INFO - Running command: ['/usr/bin/genisoimage', '-verbose', '-V', 'fedora-coreos-42.20250130.dev.0', '-volset', 'fedora-coreos-42.20250130.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.20250130.dev.0-live.x86_64.iso', '/home/jenkins/agent/workspace/test-override/tmp/buildpost-live/live']
18:44:01 [2025-01-30T18:44:01.896Z] I: -input-charset not specified, using utf-8 (detected in locale settings)
18:44:01 [2025-01-30T18:44:01.896Z] genisoimage 1.1.11 (Linux)
18:44:01 [2025-01-30T18:44:01.896Z] Scanning /home/jenkins/agent/workspace/test-override/tmp/buildpost-live/live
18:44:01 [2025-01-30T18:44:01.896Z] Scanning /home/jenkins/agent/workspace/test-override/tmp/buildpost-live/live/coreos
18:44:01 [2025-01-30T18:44:01.896Z] Scanning /home/jenkins/agent/workspace/test-override/tmp/buildpost-live/live/images
18:44:01 [2025-01-30T18:44:01.896Z] Scanning /home/jenkins/agent/workspace/test-override/tmp/buildpost-live/live/images/pxeboot
18:44:01 [2025-01-30T18:44:01.896Z] Scanning /home/jenkins/agent/workspace/test-override/tmp/buildpost-live/live/isolinux
18:44:01 [2025-01-30T18:44:01.896Z] Scanning /home/jenkins/agent/workspace/test-override/tmp/buildpost-live/live/EFI
18:44:01 [2025-01-30T18:44:01.896Z] Scanning /home/jenkins/agent/workspace/test-override/tmp/buildpost-live/live/EFI/fedora
18:44:01 [2025-01-30T18:44:01.896Z] Writing:   Initial Padblock                        Start Block 0
18:44:01 [2025-01-30T18:44:01.896Z] Done with: Initial Padblock                        Block(s)    16
18:44:01 [2025-01-30T18:44:01.896Z] Writing:   Primary Volume Descriptor               Start Block 16
18:44:01 [2025-01-30T18:44:01.896Z] Done with: Primary Volume Descriptor               Block(s)    1
18:44:01 [2025-01-30T18:44:01.896Z] Writing:   Eltorito Volume Descriptor              Start Block 17
18:44:01 [2025-01-30T18:44:01.896Z] Size of boot image is 4 sectors -> No emulation
18:44:01 [2025-01-30T18:44:01.896Z] Size of boot image is 13104 sectors -> No emulation
18:44:01 [2025-01-30T18:44:01.896Z] Done with: Eltorito Volume Descriptor              Block(s)    1
18:44:01 [2025-01-30T18:44:01.896Z] Writing:   Joliet Volume Descriptor                Start Block 18
18:44:01 [2025-01-30T18:44:01.896Z] Done with: Joliet Volume Descriptor                Block(s)    1
18:44:01 [2025-01-30T18:44:01.896Z] Writing:   End Volume Descriptor                   Start Block 19
18:44:01 [2025-01-30T18:44:01.896Z] Done with: End Volume Descriptor                   Block(s)    1
18:44:01 [2025-01-30T18:44:01.896Z] Writing:   Version block                           Start Block 20
18:44:01 [2025-01-30T18:44:01.896Z] Done with: Version block                           Block(s)    1
18:44:01 [2025-01-30T18:44:01.896Z] Writing:   Path table                              Start Block 21
18:44:01 [2025-01-30T18:44:01.896Z] Done with: Path table                              Block(s)    4
18:44:01 [2025-01-30T18:44:01.896Z] Writing:   Joliet path table                       Start Block 25
18:44:01 [2025-01-30T18:44:01.896Z] Done with: Joliet path table                       Block(s)    4
18:44:01 [2025-01-30T18:44:01.896Z] Writing:   Directory tree                          Start Block 29
18:44:01 [2025-01-30T18:44:01.896Z] Done with: Directory tree                          Block(s)    7
18:44:01 [2025-01-30T18:44:01.896Z] Writing:   Joliet directory tree                   Start Block 36
18:44:01 [2025-01-30T18:44:01.896Z] Done with: Joliet directory tree                   Block(s)    7
18:44:01 [2025-01-30T18:44:01.896Z] Writing:   Directory tree cleanup                  Start Block 43
18:44:01 [2025-01-30T18:44:01.896Z] Done with: Directory tree cleanup                  Block(s)    0
18:44:01 [2025-01-30T18:44:01.896Z] Writing:   Extension record                        Start Block 43
18:44:01 [2025-01-30T18:44:01.896Z] Done with: Extension record                        Block(s)    1
18:44:01 [2025-01-30T18:44:01.896Z] Writing:   The File(s)                             Start Block 44
18:44:01 [2025-01-30T18:44:01.896Z]   0.92% done, estimate finish Thu Jan 30 18:44:01 2025
18:44:01 [2025-01-30T18:44:01.896Z]   1.84% done, estimate finish Thu Jan 30 18:44:01 2025
18:44:01 [2025-01-30T18:44:01.896Z]   2.75% done, estimate finish Thu Jan 30 18:44:01 2025
18:44:01 [2025-01-30T18:44:01.896Z]   3.67% done, estimate finish Thu Jan 30 18:44:01 2025
18:44:01 [2025-01-30T18:44:01.896Z]   4.58% done, estimate finish Thu Jan 30 18:44:01 2025
18:44:01 [2025-01-30T18:44:01.896Z]   5.50% done, estimate finish Thu Jan 30 18:44:01 2025
18:44:01 [2025-01-30T18:44:01.896Z]   6.42% done, estimate finish Thu Jan 30 18:44:01 2025
18:44:01 [2025-01-30T18:44:01.896Z]   7.34% done, estimate finish Thu Jan 30 18:44:01 2025
18:44:01 [2025-01-30T18:44:01.896Z]   8.25% done, estimate finish Thu Jan 30 18:44:01 2025
18:44:01 [2025-01-30T18:44:01.896Z]   9.17% done, estimate finish Thu Jan 30 18:44:01 2025
18:44:01 [2025-01-30T18:44:01.896Z]  10.09% done, estimate finish Thu Jan 30 18:44:01 2025
18:44:01 [2025-01-30T18:44:01.896Z]  11.01% done, estimate finish Thu Jan 30 18:44:01 2025
18:44:01 [2025-01-30T18:44:01.896Z]  11.92% done, estimate finish Thu Jan 30 18:44:01 2025
18:44:01 [2025-01-30T18:44:01.896Z]  12.84% done, estimate finish Thu Jan 30 18:44:01 2025
18:44:01 [2025-01-30T18:44:01.896Z]  13.76% done, estimate finish Thu Jan 30 18:44:01 2025
18:44:01 [2025-01-30T18:44:01.896Z]  14.67% done, estimate finish Thu Jan 30 18:44:01 2025
18:44:01 [2025-01-30T18:44:01.896Z]  15.59% done, estimate finish Thu Jan 30 18:44:01 2025
18:44:01 [2025-01-30T18:44:01.896Z]  16.51% done, estimate finish Thu Jan 30 18:44:01 2025
18:44:01 [2025-01-30T18:44:01.896Z]  17.42% done, estimate finish Thu Jan 30 18:44:01 2025
18:44:01 [2025-01-30T18:44:01.896Z]  18.34% done, estimate finish Thu Jan 30 18:44:01 2025
18:44:01 [2025-01-30T18:44:01.896Z]  19.26% done, estimate finish Thu Jan 30 18:44:01 2025
18:44:01 [2025-01-30T18:44:01.896Z]  20.18% done, estimate finish Thu Jan 30 18:44:01 2025
18:44:01 [2025-01-30T18:44:01.896Z]  21.09% done, estimate finish Thu Jan 30 18:44:01 2025
18:44:01 [2025-01-30T18:44:01.896Z]  22.01% done, estimate finish Thu Jan 30 18:44:01 2025
18:44:01 [2025-01-30T18:44:01.896Z]  22.92% done, estimate finish Thu Jan 30 18:44:01 2025
18:44:02 [2025-01-30T18:44:02.149Z]  23.84% done, estimate finish Thu Jan 30 18:44:01 2025
18:44:02 [2025-01-30T18:44:02.149Z]  24.76% done, estimate finish Thu Jan 30 18:44:01 2025
18:44:02 [2025-01-30T18:44:02.149Z]  25.68% done, estimate finish Thu Jan 30 18:44:01 2025
18:44:02 [2025-01-30T18:44:02.149Z]  26.59% done, estimate finish Thu Jan 30 18:44:01 2025
18:44:02 [2025-01-30T18:44:02.149Z]  27.51% done, estimate finish Thu Jan 30 18:44:01 2025
18:44:02 [2025-01-30T18:44:02.149Z]  28.43% done, estimate finish Thu Jan 30 18:44:01 2025
18:44:02 [2025-01-30T18:44:02.149Z]  29.34% done, estimate finish Thu Jan 30 18:44:01 2025
18:44:02 [2025-01-30T18:44:02.149Z]  30.26% done, estimate finish Thu Jan 30 18:44:01 2025
18:44:02 [2025-01-30T18:44:02.149Z]  31.18% done, estimate finish Thu Jan 30 18:44:01 2025
18:44:02 [2025-01-30T18:44:02.149Z]  32.09% done, estimate finish Thu Jan 30 18:44:01 2025
18:44:02 [2025-01-30T18:44:02.149Z]  33.01% done, estimate finish Thu Jan 30 18:44:04 2025
18:44:02 [2025-01-30T18:44:02.149Z]  33.93% done, estimate finish Thu Jan 30 18:44:03 2025
18:44:02 [2025-01-30T18:44:02.149Z]  34.85% done, estimate finish Thu Jan 30 18:44:03 2025
18:44:02 [2025-01-30T18:44:02.149Z]  35.76% done, estimate finish Thu Jan 30 18:44:03 2025
18:44:02 [2025-01-30T18:44:02.149Z]  36.68% done, estimate finish Thu Jan 30 18:44:03 2025
18:44:02 [2025-01-30T18:44:02.149Z]  37.60% done, estimate finish Thu Jan 30 18:44:03 2025
18:44:02 [2025-01-30T18:44:02.149Z]  38.51% done, estimate finish Thu Jan 30 18:44:03 2025
18:44:02 [2025-01-30T18:44:02.149Z]  39.43% done, estimate finish Thu Jan 30 18:44:03 2025
18:44:02 [2025-01-30T18:44:02.149Z]  40.35% done, estimate finish Thu Jan 30 18:44:03 2025
18:44:02 [2025-01-30T18:44:02.149Z]  41.26% done, estimate finish Thu Jan 30 18:44:03 2025
18:44:02 [2025-01-30T18:44:02.149Z]  42.18% done, estimate finish Thu Jan 30 18:44:03 2025
18:44:02 [2025-01-30T18:44:02.149Z]  43.10% done, estimate finish Thu Jan 30 18:44:03 2025
18:44:02 [2025-01-30T18:44:02.149Z]  44.02% done, estimate finish Thu Jan 30 18:44:03 2025
18:44:02 [2025-01-30T18:44:02.401Z]  44.93% done, estimate finish Thu Jan 30 18:44:03 2025
18:44:02 [2025-01-30T18:44:02.401Z]  45.85% done, estimate finish Thu Jan 30 18:44:03 2025
18:44:02 [2025-01-30T18:44:02.401Z]  46.77% done, estimate finish Thu Jan 30 18:44:03 2025
18:44:02 [2025-01-30T18:44:02.401Z]  47.68% done, estimate finish Thu Jan 30 18:44:03 2025
18:44:02 [2025-01-30T18:44:02.401Z]  48.60% done, estimate finish Thu Jan 30 18:44:03 2025
18:44:02 [2025-01-30T18:44:02.401Z]  49.52% done, estimate finish Thu Jan 30 18:44:03 2025
18:44:02 [2025-01-30T18:44:02.401Z]  50.43% done, estimate finish Thu Jan 30 18:44:02 2025
18:44:02 [2025-01-30T18:44:02.401Z]  51.35% done, estimate finish Thu Jan 30 18:44:02 2025
18:44:02 [2025-01-30T18:44:02.401Z]  52.27% done, estimate finish Thu Jan 30 18:44:02 2025
18:44:02 [2025-01-30T18:44:02.401Z]  53.19% done, estimate finish Thu Jan 30 18:44:02 2025
18:44:02 [2025-01-30T18:44:02.401Z]  54.10% done, estimate finish Thu Jan 30 18:44:02 2025
18:44:02 [2025-01-30T18:44:02.401Z]  55.02% done, estimate finish Thu Jan 30 18:44:02 2025
18:44:02 [2025-01-30T18:44:02.401Z]  55.93% done, estimate finish Thu Jan 30 18:44:02 2025
18:44:02 [2025-01-30T18:44:02.402Z]  56.85% done, estimate finish Thu Jan 30 18:44:02 2025
18:44:02 [2025-01-30T18:44:02.402Z]  57.77% done, estimate finish Thu Jan 30 18:44:02 2025
18:44:02 [2025-01-30T18:44:02.402Z]  58.69% done, estimate finish Thu Jan 30 18:44:02 2025
18:44:02 [2025-01-30T18:44:02.402Z]  59.60% done, estimate finish Thu Jan 30 18:44:02 2025
18:44:02 [2025-01-30T18:44:02.402Z]  60.52% done, estimate finish Thu Jan 30 18:44:02 2025
18:44:02 [2025-01-30T18:44:02.402Z]  61.44% done, estimate finish Thu Jan 30 18:44:02 2025
18:44:02 [2025-01-30T18:44:02.402Z]  62.35% done, estimate finish Thu Jan 30 18:44:02 2025
18:44:02 [2025-01-30T18:44:02.402Z]  63.27% done, estimate finish Thu Jan 30 18:44:02 2025
18:44:02 [2025-01-30T18:44:02.654Z]  64.19% done, estimate finish Thu Jan 30 18:44:02 2025
18:44:02 [2025-01-30T18:44:02.654Z]  65.10% done, estimate finish Thu Jan 30 18:44:02 2025
18:44:02 [2025-01-30T18:44:02.654Z]  66.02% done, estimate finish Thu Jan 30 18:44:02 2025
18:44:02 [2025-01-30T18:44:02.654Z]  66.94% done, estimate finish Thu Jan 30 18:44:02 2025
18:44:02 [2025-01-30T18:44:02.654Z]  67.86% done, estimate finish Thu Jan 30 18:44:02 2025
18:44:02 [2025-01-30T18:44:02.654Z]  68.77% done, estimate finish Thu Jan 30 18:44:02 2025
18:44:02 [2025-01-30T18:44:02.654Z]  69.69% done, estimate finish Thu Jan 30 18:44:02 2025
18:44:02 [2025-01-30T18:44:02.654Z]  70.61% done, estimate finish Thu Jan 30 18:44:02 2025
18:44:02 [2025-01-30T18:44:02.654Z]  71.52% done, estimate finish Thu Jan 30 18:44:02 2025
18:44:02 [2025-01-30T18:44:02.654Z]  72.44% done, estimate finish Thu Jan 30 18:44:02 2025
18:44:02 [2025-01-30T18:44:02.654Z]  73.36% done, estimate finish Thu Jan 30 18:44:02 2025
18:44:02 [2025-01-30T18:44:02.654Z]  74.27% done, estimate finish Thu Jan 30 18:44:02 2025
18:44:02 [2025-01-30T18:44:02.654Z]  75.19% done, estimate finish Thu Jan 30 18:44:02 2025
18:44:02 [2025-01-30T18:44:02.654Z]  76.11% done, estimate finish Thu Jan 30 18:44:02 2025
18:44:02 [2025-01-30T18:44:02.654Z]  77.03% done, estimate finish Thu Jan 30 18:44:02 2025
18:44:02 [2025-01-30T18:44:02.654Z]  77.94% done, estimate finish Thu Jan 30 18:44:02 2025
18:44:02 [2025-01-30T18:44:02.654Z]  78.86% done, estimate finish Thu Jan 30 18:44:02 2025
18:44:02 [2025-01-30T18:44:02.654Z]  79.78% done, estimate finish Thu Jan 30 18:44:02 2025
18:44:02 [2025-01-30T18:44:02.654Z]  80.69% done, estimate finish Thu Jan 30 18:44:02 2025
18:44:02 [2025-01-30T18:44:02.654Z]  81.61% done, estimate finish Thu Jan 30 18:44:02 2025
18:44:02 [2025-01-30T18:44:02.654Z]  82.53% done, estimate finish Thu Jan 30 18:44:02 2025
18:44:02 [2025-01-30T18:44:02.654Z]  83.44% done, estimate finish Thu Jan 30 18:44:02 2025
18:44:02 [2025-01-30T18:44:02.907Z]  84.36% done, estimate finish Thu Jan 30 18:44:02 2025
18:44:02 [2025-01-30T18:44:02.908Z]  85.28% done, estimate finish Thu Jan 30 18:44:02 2025
18:44:02 [2025-01-30T18:44:02.908Z]  86.20% done, estimate finish Thu Jan 30 18:44:02 2025
18:44:02 [2025-01-30T18:44:02.908Z]  87.11% done, estimate finish Thu Jan 30 18:44:02 2025
18:44:02 [2025-01-30T18:44:02.908Z]  88.03% done, estimate finish Thu Jan 30 18:44:02 2025
18:44:02 [2025-01-30T18:44:02.908Z]  88.94% done, estimate finish Thu Jan 30 18:44:02 2025
18:44:02 [2025-01-30T18:44:02.908Z]  89.86% done, estimate finish Thu Jan 30 18:44:02 2025
18:44:02 [2025-01-30T18:44:02.908Z]  90.78% done, estimate finish Thu Jan 30 18:44:02 2025
18:44:02 [2025-01-30T18:44:02.908Z]  91.70% done, estimate finish Thu Jan 30 18:44:02 2025
18:44:02 [2025-01-30T18:44:02.908Z]  92.61% done, estimate finish Thu Jan 30 18:44:02 2025
18:44:02 [2025-01-30T18:44:02.908Z]  93.53% done, estimate finish Thu Jan 30 18:44:02 2025
18:44:02 [2025-01-30T18:44:02.908Z]  94.45% done, estimate finish Thu Jan 30 18:44:02 2025
18:44:02 [2025-01-30T18:44:02.908Z]  95.36% done, estimate finish Thu Jan 30 18:44:02 2025
18:44:02 [2025-01-30T18:44:02.908Z]  96.28% done, estimate finish Thu Jan 30 18:44:02 2025
18:44:02 [2025-01-30T18:44:02.908Z]  97.20% done, estimate finish Thu Jan 30 18:44:02 2025
18:44:02 [2025-01-30T18:44:02.908Z]  98.11% done, estimate finish Thu Jan 30 18:44:02 2025
18:44:02 [2025-01-30T18:44:02.908Z]  99.03% done, estimate finish Thu Jan 30 18:44:02 2025
18:44:02 [2025-01-30T18:44:02.908Z]  99.95% done, estimate finish Thu Jan 30 18:44:02 2025
18:44:02 [2025-01-30T18:44:02.908Z] Total translation table size: 2048
18:44:02 [2025-01-30T18:44:02.908Z] Total rockridge attributes bytes: 3006
18:44:02 [2025-01-30T18:44:02.908Z] Total directory bytes: 12288
18:44:02 [2025-01-30T18:44:02.908Z] Path table size(bytes): 96
18:44:02 [2025-01-30T18:44:02.908Z] Done with: The File(s)                             Block(s)    545095
18:44:02 [2025-01-30T18:44:02.908Z] Writing:   Ending Padblock                         Start Block 545139
18:44:02 [2025-01-30T18:44:02.908Z] Done with: Ending Padblock                         Block(s)    150
18:44:02 [2025-01-30T18:44:02.908Z] Max brk space used 1c000
18:44:02 [2025-01-30T18:44:02.908Z] 545289 extents written (1065 MB)
18:44:02 [2025-01-30T18:44:02.908Z] 2025-01-30 18:44:02,855 INFO - Running command: ['/usr/bin/isohybrid', '--uefi', '/home/jenkins/agent/workspace/test-override/tmp/buildpost-live/fedora-coreos-42.20250130.dev.0-live.x86_64.iso']
18:44:02 [2025-01-30T18:44:02.908Z] isohybrid: Warning: more than 1024 cylinders: 1066
18:44:02 [2025-01-30T18:44:02.908Z] isohybrid: Not all BIOSes will be able to boot this device
18:44:03 [2025-01-30T18:44:03.473Z] 2025-01-30 18:44:03,313 INFO - Running command: ['/usr/bin/genisoimage', '-verbose', '-V', 'fedora-coreos-42.20250130.dev.0', '-volset', 'fedora-coreos-42.20250130.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.20250130.dev.0-live.x86_64.iso.minimal', '/home/jenkins/agent/workspace/test-override/tmp/buildpost-live/live']
18:44:03 [2025-01-30T18:44:03.473Z] I: -input-charset not specified, using utf-8 (detected in locale settings)
18:44:03 [2025-01-30T18:44:03.473Z] genisoimage 1.1.11 (Linux)
18:44:03 [2025-01-30T18:44:03.473Z] Scanning /home/jenkins/agent/workspace/test-override/tmp/buildpost-live/live
18:44:03 [2025-01-30T18:44:03.473Z] Scanning /home/jenkins/agent/workspace/test-override/tmp/buildpost-live/live/coreos
18:44:03 [2025-01-30T18:44:03.473Z] Scanning /home/jenkins/agent/workspace/test-override/tmp/buildpost-live/live/images
18:44:03 [2025-01-30T18:44:03.473Z] Scanning /home/jenkins/agent/workspace/test-override/tmp/buildpost-live/live/images/pxeboot
18:44:03 [2025-01-30T18:44:03.473Z] Scanning /home/jenkins/agent/workspace/test-override/tmp/buildpost-live/live/isolinux
18:44:03 [2025-01-30T18:44:03.473Z] Scanning /home/jenkins/agent/workspace/test-override/tmp/buildpost-live/live/EFI
18:44:03 [2025-01-30T18:44:03.473Z] Scanning /home/jenkins/agent/workspace/test-override/tmp/buildpost-live/live/EFI/fedora
18:44:03 [2025-01-30T18:44:03.473Z] Writing:   Initial Padblock                        Start Block 0
18:44:03 [2025-01-30T18:44:03.473Z] Done with: Initial Padblock                        Block(s)    16
18:44:03 [2025-01-30T18:44:03.473Z] Writing:   Primary Volume Descriptor               Start Block 16
18:44:03 [2025-01-30T18:44:03.473Z] Done with: Primary Volume Descriptor               Block(s)    1
18:44:03 [2025-01-30T18:44:03.473Z] Writing:   Eltorito Volume Descriptor              Start Block 17
18:44:03 [2025-01-30T18:44:03.473Z] Size of boot image is 4 sectors -> No emulation
18:44:03 [2025-01-30T18:44:03.473Z] Size of boot image is 13104 sectors -> No emulation
18:44:03 [2025-01-30T18:44:03.473Z] Done with: Eltorito Volume Descriptor              Block(s)    1
18:44:03 [2025-01-30T18:44:03.473Z] Writing:   Joliet Volume Descriptor                Start Block 18
18:44:03 [2025-01-30T18:44:03.473Z] Done with: Joliet Volume Descriptor                Block(s)    1
18:44:03 [2025-01-30T18:44:03.473Z] Writing:   End Volume Descriptor                   Start Block 19
18:44:03 [2025-01-30T18:44:03.473Z] Done with: End Volume Descriptor                   Block(s)    1
18:44:03 [2025-01-30T18:44:03.473Z] Writing:   Version block                           Start Block 20
18:44:03 [2025-01-30T18:44:03.473Z] Done with: Version block                           Block(s)    1
18:44:03 [2025-01-30T18:44:03.473Z] Writing:   Path table                              Start Block 21
18:44:03 [2025-01-30T18:44:03.473Z] Done with: Path table                              Block(s)    4
18:44:03 [2025-01-30T18:44:03.473Z] Writing:   Joliet path table                       Start Block 25
18:44:03 [2025-01-30T18:44:03.473Z] Done with: Joliet path table                       Block(s)    4
18:44:03 [2025-01-30T18:44:03.473Z] Writing:   Directory tree                          Start Block 29
18:44:03 [2025-01-30T18:44:03.473Z] Done with: Directory tree                          Block(s)    7
18:44:03 [2025-01-30T18:44:03.473Z] Writing:   Joliet directory tree                   Start Block 36
18:44:03 [2025-01-30T18:44:03.473Z] Done with: Joliet directory tree                   Block(s)    7
18:44:03 [2025-01-30T18:44:03.473Z] Writing:   Directory tree cleanup                  Start Block 43
18:44:03 [2025-01-30T18:44:03.473Z] Done with: Directory tree cleanup                  Block(s)    0
18:44:03 [2025-01-30T18:44:03.473Z] Writing:   Extension record                        Start Block 43
18:44:03 [2025-01-30T18:44:03.473Z] Done with: Extension record                        Block(s)    1
18:44:03 [2025-01-30T18:44:03.473Z] Writing:   The File(s)                             Start Block 44
18:44:03 [2025-01-30T18:44:03.473Z]   8.45% done, estimate finish Thu Jan 30 18:44:03 2025
18:44:03 [2025-01-30T18:44:03.473Z]  16.87% done, estimate finish Thu Jan 30 18:44:03 2025
18:44:03 [2025-01-30T18:44:03.473Z]  25.31% done, estimate finish Thu Jan 30 18:44:03 2025
18:44:03 [2025-01-30T18:44:03.473Z]  33.73% done, estimate finish Thu Jan 30 18:44:03 2025
18:44:03 [2025-01-30T18:44:03.473Z]  42.18% done, estimate finish Thu Jan 30 18:44:03 2025
18:44:03 [2025-01-30T18:44:03.473Z]  50.60% done, estimate finish Thu Jan 30 18:44:03 2025
18:44:03 [2025-01-30T18:44:03.473Z]  59.04% done, estimate finish Thu Jan 30 18:44:03 2025
18:44:03 [2025-01-30T18:44:03.473Z]  67.46% done, estimate finish Thu Jan 30 18:44:03 2025
18:44:03 [2025-01-30T18:44:03.473Z]  75.91% done, estimate finish Thu Jan 30 18:44:03 2025
18:44:03 [2025-01-30T18:44:03.473Z]  84.33% done, estimate finish Thu Jan 30 18:44:03 2025
18:44:03 [2025-01-30T18:44:03.473Z]  92.79% done, estimate finish Thu Jan 30 18:44:03 2025
18:44:03 [2025-01-30T18:44:03.473Z] Total translation table size: 2048
18:44:03 [2025-01-30T18:44:03.473Z] Total rockridge attributes bytes: 2842
18:44:03 [2025-01-30T18:44:03.473Z] Total directory bytes: 12288
18:44:03 [2025-01-30T18:44:03.473Z] Path table size(bytes): 96
18:44:03 [2025-01-30T18:44:03.473Z] Done with: The File(s)                             Block(s)    59097
18:44:03 [2025-01-30T18:44:03.473Z] Writing:   Ending Padblock                         Start Block 59141
18:44:03 [2025-01-30T18:44:03.473Z] Done with: Ending Padblock                         Block(s)    150
18:44:03 [2025-01-30T18:44:03.473Z] Max brk space used 1b000
18:44:03 [2025-01-30T18:44:03.473Z] 59291 extents written (115 MB)
18:44:03 [2025-01-30T18:44:03.473Z] 2025-01-30 18:44:03,425 INFO - Running command: ['/usr/bin/isohybrid', '--uefi', '/home/jenkins/agent/workspace/test-override/tmp/buildpost-live/fedora-coreos-42.20250130.dev.0-live.x86_64.iso.minimal']
18:44:03 [2025-01-30T18:44:03.473Z] 2025-01-30 18:44:03,463 INFO - Running command: ['/usr/lib/coreos-assembler/runvm-coreos-installer', 'builds/42.20250130.dev.0/x86_64/fedora-coreos-42.20250130.dev.0-metal.x86_64.raw', '', 'pack', 'minimal-iso', '/home/jenkins/agent/workspace/test-override/tmp/buildpost-live/fedora-coreos-42.20250130.dev.0-live.x86_64.iso', '/home/jenkins/agent/workspace/test-override/tmp/buildpost-live/fedora-coreos-42.20250130.dev.0-live.x86_64.iso.minimal', '--consume']
18:44:21 [2025-01-30T18:44:21.479Z] + RUST_BACKTRACE=full
18:44:21 [2025-01-30T18:44:21.479Z] + chroot /sysroot/ostree/deploy/fedora-coreos/deploy/57093b9e401453407f199966b01ab6f753692c1128d14319ba755910de1e8f53.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.20250130.dev.0-live.x86_64.iso /home/jenkins/agent/workspace/test-override/tmp/buildpost-live/fedora-coreos-42.20250130.dev.0-live.x86_64.iso.minimal --consume
18:44:21 [2025-01-30T18:44:21.479Z] Packing minimal ISO
18:44:21 [2025-01-30T18:44:21.479Z] Matched 17 files of 17
18:44:21 [2025-01-30T18:44:21.479Z] Total bytes skipped: 121008895
18:44:21 [2025-01-30T18:44:21.479Z] Total bytes written: 625921
18:44:21 [2025-01-30T18:44:21.479Z] Total bytes written (compressed): 2808
18:44:21 [2025-01-30T18:44:21.479Z] Verifying that packed image matches digest
18:44:21 [2025-01-30T18:44:21.479Z] Packing successful!
18:44:21 [2025-01-30T18:44:21.479Z] + '[' -f /var/tmp/coreos-installer-output ']'
18:44:24 [2025-01-30T18:44:24.779Z] Updated: builds/42.20250130.dev.0/x86_64/meta.json