Console Output

18:03:09 [2025-01-21T18:03:09.343Z] + set -xeuo pipefail
18:03:09 [2025-01-21T18:03:09.343Z] ++ umask
18:03:09 [2025-01-21T18:03:09.343Z] + '[' 0022 = 0000 ']'
18:03:09 [2025-01-21T18:03:09.343Z] + cosa buildextend-live --fast
18:03:09 [2025-01-21T18:03:09.343Z] Targeting build: 42.20250121.dev.0
18:03:09 [2025-01-21T18:03:09.596Z] 2025-01-21 18:03:09,411 INFO - Running command: ['/usr/bin/ostree', 'ls', '--repo', '/home/jenkins/agent/workspace/test-override/tmp/repo', '--nul-filenames-only', '14912cc3f74fb7b4e4af7f63bc88fc6b4555f12163dfa7376b6a400ae3723733', '/usr/lib/modules']
18:03:09 [2025-01-21T18:03:09.597Z] 2025-01-21 18:03:09,421 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', '14912cc3f74fb7b4e4af7f63bc88fc6b4555f12163dfa7376b6a400ae3723733', '/home/jenkins/agent/workspace/test-override/tmp/buildpost-live/live/images/pxeboot']
18:03:09 [2025-01-21T18:03:09.597Z] 2025-01-21 18:03:09,472 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', '14912cc3f74fb7b4e4af7f63bc88fc6b4555f12163dfa7376b6a400ae3723733', '/home/jenkins/agent/workspace/test-override/tmp/buildpost-live/live/images/pxeboot']
18:03:09 [2025-01-21T18:03:09.850Z] 2025-01-21 18:03:09,669 INFO - Running command: ['/usr/bin/ostree', 'cat', '--repo', '/home/jenkins/agent/workspace/test-override/tmp/repo', '14912cc3f74fb7b4e4af7f63bc88fc6b4555f12163dfa7376b6a400ae3723733', '/usr/share/coreos-installer/example-config.yaml']
18:03:09 [2025-01-21T18:03:09.850Z] 2025-01-21 18:03:09,687 INFO - Running command: ['/usr/bin/ostree', 'checkout', '--repo', '/home/jenkins/agent/workspace/test-override/tmp/repo', '--user-mode', '--subpath', '/usr/lib/os-release', '14912cc3f74fb7b4e4af7f63bc88fc6b4555f12163dfa7376b6a400ae3723733', '/tmp/tmpfnot8wre']
18:03:09 [2025-01-21T18:03:09.850Z] Generating osmet file for 512b metal image
18:03:09 [2025-01-21T18:03:09.850Z] 2025-01-21 18:03:09,704 INFO - Running command: ['/usr/lib/coreos-assembler/runvm-coreos-installer', 'builds/42.20250121.dev.0/x86_64/fedora-coreos-42.20250121.dev.0-metal.x86_64.raw', '/home/jenkins/agent/workspace/test-override/tmp/buildpost-live/initrd-rootfs/fedora-coreos-42.20250121.dev.0-metal.x86_64.raw.osmet', 'pack', 'osmet', '/dev/disk/by-id/virtio-coreos', '--description', 'Fedora CoreOS 42.20250121.dev.0', '--checksum', '200996f93bfa92428c547f5004e24fe2b6d0692978ed0d83069d36b6a9206e87', '--output', '/var/tmp/coreos-installer-output', '--fast']
18:03:27 [2025-01-21T18:03:27.868Z] + RUST_BACKTRACE=full
18:03:27 [2025-01-21T18:03:27.868Z] + chroot /sysroot/ostree/deploy/fedora-coreos/deploy/9bc09d31474689a9a0031f45d86ad29f06f10f55e06be82f912daa3649a6de55.0 env -C /home/jenkins/agent/workspace/test-override coreos-installer pack osmet /dev/disk/by-id/virtio-coreos --description 'Fedora CoreOS 42.20250121.dev.0' --checksum 200996f93bfa92428c547f5004e24fe2b6d0692978ed0d83069d36b6a9206e87 --output /var/tmp/coreos-installer-output --fast
18:03:40 [2025-01-21T18:03:40.157Z] Total OSTree objects scanned from /root: 17819 (17813 mapped, 6 empty)
18:03:40 [2025-01-21T18:03:40.157Z] Total OSTree objects found in /boot: 315
18:03:40 [2025-01-21T18:03:40.157Z] Duplicate extents dropped: 0
18:03:40 [2025-01-21T18:03:40.157Z] Overlapping extents clamped: 0
18:03:40 [2025-01-21T18:03:40.157Z] Total /root extents: 17816
18:03:40 [2025-01-21T18:03:40.157Z] Duplicate extents dropped: 0
18:03:40 [2025-01-21T18:03:40.157Z] Overlapping extents clamped: 0
18:03:40 [2025-01-21T18:03:40.157Z] Total /boot extents: 320
18:03:40 [2025-01-21T18:03:40.157Z] Packing image
18:04:02 [2025-01-21T18:04:02.019Z] Total bytes skipped: 1648705536
18:04:02 [2025-01-21T18:04:02.019Z] Total bytes written: 1085980672
18:04:02 [2025-01-21T18:04:02.019Z] Total bytes written (compressed): 25556592
18:04:02 [2025-01-21T18:04:02.019Z] Verifying that repacked image matches digest
18:04:10 [2025-01-21T18:04:10.073Z] Packing successful!
18:04:10 [2025-01-21T18:04:10.073Z] + '[' -f /var/tmp/coreos-installer-output ']'
18:04:10 [2025-01-21T18:04:10.073Z] + cp /var/tmp/coreos-installer-output /dev/virtio-ports/coreosout
18:04:10 [2025-01-21T18:04:10.991Z] Generating osmet file for 4k metal image
18:04:10 [2025-01-21T18:04:10.991Z] 2025-01-21 18:04:10,952 INFO - Running command: ['/usr/lib/coreos-assembler/runvm-coreos-installer', 'builds/42.20250121.dev.0/x86_64/fedora-coreos-42.20250121.dev.0-metal4k.x86_64.raw', '/home/jenkins/agent/workspace/test-override/tmp/buildpost-live/initrd-rootfs/fedora-coreos-42.20250121.dev.0-metal4k.x86_64.raw.osmet', 'pack', 'osmet', '/dev/disk/by-id/virtio-coreos', '--description', 'Fedora CoreOS 42.20250121.dev.0', '--checksum', '2bad145c8c010efd3214a0d1260ff9e2c5e3ddd600c634f7f4ea5d87e702a666', '--output', '/var/tmp/coreos-installer-output', '--fast']
18:04:29 [2025-01-21T18:04:29.010Z] + RUST_BACKTRACE=full
18:04:29 [2025-01-21T18:04:29.011Z] + chroot /sysroot/ostree/deploy/fedora-coreos/deploy/9bc09d31474689a9a0031f45d86ad29f06f10f55e06be82f912daa3649a6de55.0 env -C /home/jenkins/agent/workspace/test-override coreos-installer pack osmet /dev/disk/by-id/virtio-coreos --description 'Fedora CoreOS 42.20250121.dev.0' --checksum 2bad145c8c010efd3214a0d1260ff9e2c5e3ddd600c634f7f4ea5d87e702a666 --output /var/tmp/coreos-installer-output --fast
18:04:29 [2025-01-21T18:04:29.011Z] Total OSTree objects scanned from /root: 17819 (17813 mapped, 6 empty)
18:04:29 [2025-01-21T18:04:29.011Z] Total OSTree objects found in /boot: 3
18:04:29 [2025-01-21T18:04:29.011Z] Duplicate extents dropped: 0
18:04:29 [2025-01-21T18:04:29.011Z] Overlapping extents clamped: 0
18:04:29 [2025-01-21T18:04:29.011Z] Total /root extents: 17814
18:04:29 [2025-01-21T18:04:29.011Z] Duplicate extents dropped: 0
18:04:29 [2025-01-21T18:04:29.011Z] Overlapping extents clamped: 0
18:04:29 [2025-01-21T18:04:29.011Z] Total /boot extents: 4
18:04:29 [2025-01-21T18:04:29.011Z] Packing image
18:04:43 [2025-01-21T18:04:43.811Z] Total bytes skipped: 1637842944
18:04:43 [2025-01-21T18:04:43.811Z] Total bytes written: 1096843264
18:04:43 [2025-01-21T18:04:43.811Z] Total bytes written (compressed): 25721984
18:04:43 [2025-01-21T18:04:43.811Z] Verifying that repacked image matches digest
18:04:50 [2025-01-21T18:04:50.323Z] Packing successful!
18:04:50 [2025-01-21T18:04:50.323Z] + '[' -f /var/tmp/coreos-installer-output ']'
18:04:50 [2025-01-21T18:04:50.323Z] + cp /var/tmp/coreos-installer-output /dev/virtio-ports/coreosout
18:04:55 [2025-01-21T18:04:55.540Z] Compressing squashfs with lz4
18:04:55 [2025-01-21T18:04:55.540Z] 2025-01-21 18:04:55,079 INFO - Running command: ['/usr/lib/coreos-assembler/gf-mksquashfs', 'builds/42.20250121.dev.0/x86_64/fedora-coreos-42.20250121.dev.0-metal.x86_64.raw', '/home/jenkins/agent/workspace/test-override/tmp/buildpost-live/initrd-rootfs/root.squashfs', 'lz4']
18:04:55 [2025-01-21T18:04:55.540Z] +++ dirname /home/jenkins/agent/workspace/test-override/tmp/buildpost-live/initrd-rootfs/root.squashfs
18:04:55 [2025-01-21T18:04:55.540Z] ++ mktemp -tdp /home/jenkins/agent/workspace/test-override/tmp/buildpost-live/initrd-rootfs gf-mksquashfs.XXXXXX
18:04:55 [2025-01-21T18:04:55.540Z] + tmpd=/home/jenkins/agent/workspace/test-override/tmp/buildpost-live/initrd-rootfs/gf-mksquashfs.Z9sy5t
18:04:55 [2025-01-21T18:04:55.540Z] + tmp_dest=/home/jenkins/agent/workspace/test-override/tmp/buildpost-live/initrd-rootfs/gf-mksquashfs.Z9sy5t/image.squashfs
18:04:55 [2025-01-21T18:04:55.540Z] + coreos_gf_run_mount builds/42.20250121.dev.0/x86_64/fedora-coreos-42.20250121.dev.0-metal.x86_64.raw --ro
18:04:55 [2025-01-21T18:04:55.540Z] + local mntarg=mount
18:04:55 [2025-01-21T18:04:55.540Z] + '[' builds/42.20250121.dev.0/x86_64/fedora-coreos-42.20250121.dev.0-metal.x86_64.raw = ro ']'
18:04:55 [2025-01-21T18:04:55.540Z] + coreos_gf_run builds/42.20250121.dev.0/x86_64/fedora-coreos-42.20250121.dev.0-metal.x86_64.raw --ro
18:04:55 [2025-01-21T18:04:55.544Z] + '[' -n '' ']'
18:04:55 [2025-01-21T18:04:55.544Z] + coreos_gf_launch builds/42.20250121.dev.0/x86_64/fedora-coreos-42.20250121.dev.0-metal.x86_64.raw --ro
18:04:55 [2025-01-21T18:04:55.544Z] + '[' -n '' ']'
18:04:55 [2025-01-21T18:04:55.544Z] ++ guestfish --listen -a builds/42.20250121.dev.0/x86_64/fedora-coreos-42.20250121.dev.0-metal.x86_64.raw --ro
18:04:55 [2025-01-21T18:04:55.544Z] + eval 'GUESTFISH_PID=12406; export GUESTFISH_PID'
18:04:55 [2025-01-21T18:04:55.544Z] ++ GUESTFISH_PID=12406
18:04:55 [2025-01-21T18:04:55.544Z] ++ export GUESTFISH_PID
18:04:55 [2025-01-21T18:04:55.544Z] + '[' -z 12406 ']'
18:04:55 [2025-01-21T18:04:55.544Z] ++ kola ncpu
18:04:55 [2025-01-21T18:04:55.544Z] + coreos_gf set-smp 6
18:04:55 [2025-01-21T18:04:55.544Z] + guestfish --remote -- set-smp 6
18:04:55 [2025-01-21T18:04:55.544Z] + coreos_gf run
18:04:55 [2025-01-21T18:04:55.544Z] + guestfish --remote -- run
18:05:03 [2025-01-21T18:05:03.605Z] + GUESTFISH_RUNNING=1
18:05:03 [2025-01-21T18:05:03.605Z] ++ coreos_gf findfs-label root
18:05:03 [2025-01-21T18:05:03.605Z] ++ guestfish --remote -- findfs-label root
18:05:03 [2025-01-21T18:05:03.605Z] + root=/dev/sda4
18:05:03 [2025-01-21T18:05:03.605Z] + coreos_gf mount /dev/sda4 /
18:05:03 [2025-01-21T18:05:03.605Z] + guestfish --remote -- mount /dev/sda4 /
18:05:03 [2025-01-21T18:05:03.605Z] + local boot
18:05:03 [2025-01-21T18:05:03.605Z] ++ coreos_gf findfs-label boot
18:05:03 [2025-01-21T18:05:03.605Z] ++ guestfish --remote -- findfs-label boot
18:05:03 [2025-01-21T18:05:03.605Z] + boot=/dev/sda3
18:05:03 [2025-01-21T18:05:03.605Z] + coreos_gf mount /dev/sda3 /boot
18:05:03 [2025-01-21T18:05:03.605Z] + guestfish --remote -- mount /dev/sda3 /boot
18:05:03 [2025-01-21T18:05:03.605Z] + local partitions
18:05:03 [2025-01-21T18:05:03.605Z] + local label
18:05:03 [2025-01-21T18:05:03.605Z] ++ coreos_gf list-partitions
18:05:03 [2025-01-21T18:05:03.605Z] ++ guestfish --remote -- list-partitions
18:05:03 [2025-01-21T18:05:03.605Z] + partitions='/dev/sda1
18:05:03 [2025-01-21T18:05:03.605Z] /dev/sda2
18:05:03 [2025-01-21T18:05:03.605Z] /dev/sda3
18:05:03 [2025-01-21T18:05:03.605Z] /dev/sda4'
18:05:03 [2025-01-21T18:05:03.605Z] + for pt in $partitions
18:05:03 [2025-01-21T18:05:03.605Z] ++ coreos_gf vfs-label /dev/sda1
18:05:03 [2025-01-21T18:05:03.605Z] ++ guestfish --remote -- vfs-label /dev/sda1
18:05:03 [2025-01-21T18:05:03.605Z] + label=
18:05:03 [2025-01-21T18:05:03.605Z] + '[' '' == EFI-SYSTEM ']'
18:05:03 [2025-01-21T18:05:03.605Z] + for pt in $partitions
18:05:03 [2025-01-21T18:05:03.605Z] ++ coreos_gf vfs-label /dev/sda2
18:05:03 [2025-01-21T18:05:03.605Z] ++ guestfish --remote -- vfs-label /dev/sda2
18:05:03 [2025-01-21T18:05:03.605Z] + label=EFI-SYSTEM
18:05:03 [2025-01-21T18:05:03.605Z] + '[' EFI-SYSTEM == EFI-SYSTEM ']'
18:05:03 [2025-01-21T18:05:03.605Z] + coreos_gf mount /dev/sda2 /boot/efi
18:05:03 [2025-01-21T18:05:03.605Z] + guestfish --remote -- mount /dev/sda2 /boot/efi
18:05:03 [2025-01-21T18:05:03.605Z] + for pt in $partitions
18:05:03 [2025-01-21T18:05:03.605Z] ++ coreos_gf vfs-label /dev/sda3
18:05:03 [2025-01-21T18:05:03.605Z] ++ guestfish --remote -- vfs-label /dev/sda3
18:05:03 [2025-01-21T18:05:03.605Z] + label=boot
18:05:03 [2025-01-21T18:05:03.605Z] + '[' boot == EFI-SYSTEM ']'
18:05:03 [2025-01-21T18:05:03.605Z] + for pt in $partitions
18:05:03 [2025-01-21T18:05:03.605Z] ++ coreos_gf vfs-label /dev/sda4
18:05:03 [2025-01-21T18:05:03.605Z] ++ guestfish --remote -- vfs-label /dev/sda4
18:05:03 [2025-01-21T18:05:03.605Z] + label=root
18:05:03 [2025-01-21T18:05:03.605Z] + '[' root == EFI-SYSTEM ']'
18:05:03 [2025-01-21T18:05:03.605Z] ++ coreos_gf ls /ostree/deploy
18:05:03 [2025-01-21T18:05:03.605Z] ++ guestfish --remote -- ls /ostree/deploy
18:05:03 [2025-01-21T18:05:03.605Z] + stateroot=/ostree/deploy/fedora-coreos
18:05:03 [2025-01-21T18:05:03.605Z] ++ coreos_gf ls /ostree/deploy/fedora-coreos/deploy
18:05:03 [2025-01-21T18:05:03.605Z] ++ guestfish --remote -- ls /ostree/deploy/fedora-coreos/deploy
18:05:03 [2025-01-21T18:05:03.605Z] ++ grep -v .origin
18:05:03 [2025-01-21T18:05:03.605Z] + deploydir=/ostree/deploy/fedora-coreos/deploy/9bc09d31474689a9a0031f45d86ad29f06f10f55e06be82f912daa3649a6de55.0
18:05:03 [2025-01-21T18:05:03.605Z] + export stateroot deploydir
18:05:03 [2025-01-21T18:05:03.605Z] + coreos_gf download /ostree/repo/config /home/jenkins/agent/workspace/test-override/tmp/buildpost-live/initrd-rootfs/gf-mksquashfs.Z9sy5t/config
18:05:03 [2025-01-21T18:05:03.605Z] + guestfish --remote -- download /ostree/repo/config /home/jenkins/agent/workspace/test-override/tmp/buildpost-live/initrd-rootfs/gf-mksquashfs.Z9sy5t/config
18:05:03 [2025-01-21T18:05:03.605Z] + grep -v readonly=true /home/jenkins/agent/workspace/test-override/tmp/buildpost-live/initrd-rootfs/gf-mksquashfs.Z9sy5t/config
18:05:03 [2025-01-21T18:05:03.605Z] + coreos_gf upload /home/jenkins/agent/workspace/test-override/tmp/buildpost-live/initrd-rootfs/gf-mksquashfs.Z9sy5t/config.new /ostree/repo/config
18:05:03 [2025-01-21T18:05:03.605Z] + guestfish --remote -- upload /home/jenkins/agent/workspace/test-override/tmp/buildpost-live/initrd-rootfs/gf-mksquashfs.Z9sy5t/config.new /ostree/repo/config
18:05:03 [2025-01-21T18:05:03.605Z] ++ coreos_gf glob-expand '/boot/ostree/*/vmlinuz*'
18:05:03 [2025-01-21T18:05:03.605Z] ++ guestfish --remote -- glob-expand '/boot/ostree/*/vmlinuz*'
18:05:03 [2025-01-21T18:05:03.605Z] + kernel_binary=/boot/ostree/fedora-coreos-99a9e532f4c3064abed5db78a1652558484807bd097506b2b23f04a1b6b69169/vmlinuz-6.13.0-0.rc7.20250114gitc45323b7560e.56.fc42.x86_64
18:05:03 [2025-01-21T18:05:03.606Z] ++ coreos_gf glob-expand '/boot/ostree/*/.*.hmac'
18:05:03 [2025-01-21T18:05:03.606Z] ++ guestfish --remote -- glob-expand '/boot/ostree/*/.*.hmac'
18:05:03 [2025-01-21T18:05:03.606Z] + kernel_hmac=/boot/ostree/fedora-coreos-99a9e532f4c3064abed5db78a1652558484807bd097506b2b23f04a1b6b69169/.vmlinuz-6.13.0-0.rc7.20250114gitc45323b7560e.56.fc42.x86_64.hmac
18:05:03 [2025-01-21T18:05:03.606Z] ++ basename /boot/ostree/fedora-coreos-99a9e532f4c3064abed5db78a1652558484807bd097506b2b23f04a1b6b69169/.vmlinuz-6.13.0-0.rc7.20250114gitc45323b7560e.56.fc42.x86_64.hmac
18:05:03 [2025-01-21T18:05:03.606Z] + coreos_gf ln /boot/ostree/fedora-coreos-99a9e532f4c3064abed5db78a1652558484807bd097506b2b23f04a1b6b69169/.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:05:03 [2025-01-21T18:05:03.606Z] + guestfish --remote -- ln /boot/ostree/fedora-coreos-99a9e532f4c3064abed5db78a1652558484807bd097506b2b23f04a1b6b69169/.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:05:03 [2025-01-21T18:05:03.606Z] ++ basename /boot/ostree/fedora-coreos-99a9e532f4c3064abed5db78a1652558484807bd097506b2b23f04a1b6b69169/vmlinuz-6.13.0-0.rc7.20250114gitc45323b7560e.56.fc42.x86_64
18:05:03 [2025-01-21T18:05:03.606Z] + coreos_gf ln /boot/ostree/fedora-coreos-99a9e532f4c3064abed5db78a1652558484807bd097506b2b23f04a1b6b69169/vmlinuz-6.13.0-0.rc7.20250114gitc45323b7560e.56.fc42.x86_64 /boot/vmlinuz-6.13.0-0.rc7.20250114gitc45323b7560e.56.fc42.x86_64
18:05:03 [2025-01-21T18:05:03.606Z] + guestfish --remote -- ln /boot/ostree/fedora-coreos-99a9e532f4c3064abed5db78a1652558484807bd097506b2b23f04a1b6b69169/vmlinuz-6.13.0-0.rc7.20250114gitc45323b7560e.56.fc42.x86_64 /boot/vmlinuz-6.13.0-0.rc7.20250114gitc45323b7560e.56.fc42.x86_64
18:05:03 [2025-01-21T18:05:03.606Z] + coreos_gf mksquashfs / /home/jenkins/agent/workspace/test-override/tmp/buildpost-live/initrd-rootfs/gf-mksquashfs.Z9sy5t/image.squashfs compress:lz4
18:05:03 [2025-01-21T18:05:03.606Z] + guestfish --remote -- mksquashfs / /home/jenkins/agent/workspace/test-override/tmp/buildpost-live/initrd-rootfs/gf-mksquashfs.Z9sy5t/image.squashfs compress:lz4
18:05:11 [2025-01-21T18:05:11.693Z] + coreos_gf_shutdown
18:05:11 [2025-01-21T18:05:11.693Z] + coreos_gf umount-all
18:05:11 [2025-01-21T18:05:11.693Z] + guestfish --remote -- umount-all
18:05:11 [2025-01-21T18:05:11.693Z] + coreos_gf exit
18:05:11 [2025-01-21T18:05:11.693Z] + guestfish --remote -- exit
18:05:11 [2025-01-21T18:05:11.693Z] + GUESTFISH_RUNNING=
18:05:11 [2025-01-21T18:05:11.693Z] + GUESTFISH_PID=
18:05:11 [2025-01-21T18:05:11.693Z] + mv /home/jenkins/agent/workspace/test-override/tmp/buildpost-live/initrd-rootfs/gf-mksquashfs.Z9sy5t/image.squashfs /home/jenkins/agent/workspace/test-override/tmp/buildpost-live/initrd-rootfs/root.squashfs
18:05:11 [2025-01-21T18:05:11.693Z] + rm /home/jenkins/agent/workspace/test-override/tmp/buildpost-live/initrd-rootfs/gf-mksquashfs.Z9sy5t -rf
18:05:11 [2025-01-21T18:05:11.693Z] + _coreos_gf_cleanup
18:05:11 [2025-01-21T18:05:11.693Z] + guestfish --remote -- exit
18:05:11 [2025-01-21T18:05:11.693Z] + :
18:05:16 [2025-01-21T18:05:16.911Z] 2025-01-21 18:05:16,801 INFO - Running command: ['/usr/lib/coreos-assembler/gf-get-kargs', 'builds/42.20250121.dev.0/x86_64/fedora-coreos-42.20250121.dev.0-metal.x86_64.raw']
18:05:17 [2025-01-21T18:05:17.163Z] + coreos_gf_run_mount builds/42.20250121.dev.0/x86_64/fedora-coreos-42.20250121.dev.0-metal.x86_64.raw --ro
18:05:17 [2025-01-21T18:05:17.163Z] + local mntarg=mount
18:05:17 [2025-01-21T18:05:17.163Z] + '[' builds/42.20250121.dev.0/x86_64/fedora-coreos-42.20250121.dev.0-metal.x86_64.raw = ro ']'
18:05:17 [2025-01-21T18:05:17.163Z] + coreos_gf_run builds/42.20250121.dev.0/x86_64/fedora-coreos-42.20250121.dev.0-metal.x86_64.raw --ro
18:05:17 [2025-01-21T18:05:17.163Z] + '[' -n '' ']'
18:05:17 [2025-01-21T18:05:17.163Z] + coreos_gf_launch builds/42.20250121.dev.0/x86_64/fedora-coreos-42.20250121.dev.0-metal.x86_64.raw --ro
18:05:17 [2025-01-21T18:05:17.163Z] + '[' -n '' ']'
18:05:17 [2025-01-21T18:05:17.163Z] ++ guestfish --listen -a builds/42.20250121.dev.0/x86_64/fedora-coreos-42.20250121.dev.0-metal.x86_64.raw --ro
18:05:17 [2025-01-21T18:05:17.163Z] + eval 'GUESTFISH_PID=12549; export GUESTFISH_PID'
18:05:17 [2025-01-21T18:05:17.163Z] ++ GUESTFISH_PID=12549
18:05:17 [2025-01-21T18:05:17.163Z] ++ export GUESTFISH_PID
18:05:17 [2025-01-21T18:05:17.163Z] + '[' -z 12549 ']'
18:05:17 [2025-01-21T18:05:17.163Z] ++ kola ncpu
18:05:17 [2025-01-21T18:05:17.163Z] + coreos_gf set-smp 6
18:05:17 [2025-01-21T18:05:17.163Z] + guestfish --remote -- set-smp 6
18:05:17 [2025-01-21T18:05:17.163Z] + coreos_gf run
18:05:17 [2025-01-21T18:05:17.163Z] + guestfish --remote -- run
18:05:19 [2025-01-21T18:05:19.036Z] + GUESTFISH_RUNNING=1
18:05:19 [2025-01-21T18:05:19.036Z] ++ coreos_gf findfs-label root
18:05:19 [2025-01-21T18:05:19.036Z] ++ guestfish --remote -- findfs-label root
18:05:19 [2025-01-21T18:05:19.036Z] + root=/dev/sda4
18:05:19 [2025-01-21T18:05:19.036Z] + coreos_gf mount /dev/sda4 /
18:05:19 [2025-01-21T18:05:19.036Z] + guestfish --remote -- mount /dev/sda4 /
18:05:19 [2025-01-21T18:05:19.294Z] + local boot
18:05:19 [2025-01-21T18:05:19.294Z] ++ coreos_gf findfs-label boot
18:05:19 [2025-01-21T18:05:19.294Z] ++ guestfish --remote -- findfs-label boot
18:05:19 [2025-01-21T18:05:19.294Z] + boot=/dev/sda3
18:05:19 [2025-01-21T18:05:19.294Z] + coreos_gf mount /dev/sda3 /boot
18:05:19 [2025-01-21T18:05:19.294Z] + guestfish --remote -- mount /dev/sda3 /boot
18:05:19 [2025-01-21T18:05:19.547Z] + local partitions
18:05:19 [2025-01-21T18:05:19.547Z] + local label
18:05:19 [2025-01-21T18:05:19.547Z] ++ coreos_gf list-partitions
18:05:19 [2025-01-21T18:05:19.547Z] ++ guestfish --remote -- list-partitions
18:05:19 [2025-01-21T18:05:19.547Z] + partitions='/dev/sda1
18:05:19 [2025-01-21T18:05:19.547Z] /dev/sda2
18:05:19 [2025-01-21T18:05:19.547Z] /dev/sda3
18:05:19 [2025-01-21T18:05:19.547Z] /dev/sda4'
18:05:19 [2025-01-21T18:05:19.547Z] + for pt in $partitions
18:05:19 [2025-01-21T18:05:19.547Z] ++ coreos_gf vfs-label /dev/sda1
18:05:19 [2025-01-21T18:05:19.547Z] ++ guestfish --remote -- vfs-label /dev/sda1
18:05:19 [2025-01-21T18:05:19.547Z] + label=
18:05:19 [2025-01-21T18:05:19.547Z] + '[' '' == EFI-SYSTEM ']'
18:05:19 [2025-01-21T18:05:19.547Z] + for pt in $partitions
18:05:19 [2025-01-21T18:05:19.547Z] ++ coreos_gf vfs-label /dev/sda2
18:05:19 [2025-01-21T18:05:19.547Z] ++ guestfish --remote -- vfs-label /dev/sda2
18:05:19 [2025-01-21T18:05:19.547Z] + label=EFI-SYSTEM
18:05:19 [2025-01-21T18:05:19.547Z] + '[' EFI-SYSTEM == EFI-SYSTEM ']'
18:05:19 [2025-01-21T18:05:19.547Z] + coreos_gf mount /dev/sda2 /boot/efi
18:05:19 [2025-01-21T18:05:19.547Z] + guestfish --remote -- mount /dev/sda2 /boot/efi
18:05:19 [2025-01-21T18:05:19.547Z] + for pt in $partitions
18:05:19 [2025-01-21T18:05:19.547Z] ++ coreos_gf vfs-label /dev/sda3
18:05:19 [2025-01-21T18:05:19.547Z] ++ guestfish --remote -- vfs-label /dev/sda3
18:05:20 [2025-01-21T18:05:20.102Z] + label=boot
18:05:20 [2025-01-21T18:05:20.102Z] + '[' boot == EFI-SYSTEM ']'
18:05:20 [2025-01-21T18:05:20.102Z] + for pt in $partitions
18:05:20 [2025-01-21T18:05:20.102Z] ++ coreos_gf vfs-label /dev/sda4
18:05:20 [2025-01-21T18:05:20.102Z] ++ guestfish --remote -- vfs-label /dev/sda4
18:05:20 [2025-01-21T18:05:20.102Z] + label=root
18:05:20 [2025-01-21T18:05:20.102Z] + '[' root == EFI-SYSTEM ']'
18:05:20 [2025-01-21T18:05:20.102Z] ++ coreos_gf ls /ostree/deploy
18:05:20 [2025-01-21T18:05:20.102Z] ++ guestfish --remote -- ls /ostree/deploy
18:05:20 [2025-01-21T18:05:20.102Z] + stateroot=/ostree/deploy/fedora-coreos
18:05:20 [2025-01-21T18:05:20.102Z] ++ coreos_gf ls /ostree/deploy/fedora-coreos/deploy
18:05:20 [2025-01-21T18:05:20.102Z] ++ guestfish --remote -- ls /ostree/deploy/fedora-coreos/deploy
18:05:20 [2025-01-21T18:05:20.102Z] ++ grep -v .origin
18:05:20 [2025-01-21T18:05:20.102Z] + deploydir=/ostree/deploy/fedora-coreos/deploy/9bc09d31474689a9a0031f45d86ad29f06f10f55e06be82f912daa3649a6de55.0
18:05:20 [2025-01-21T18:05:20.102Z] + export stateroot deploydir
18:05:20 [2025-01-21T18:05:20.102Z] + coreos_gf glob read-file '/boot/loader/entries/ostree*conf'
18:05:20 [2025-01-21T18:05:20.102Z] + guestfish --remote -- glob read-file '/boot/loader/entries/ostree*conf'
18:05:20 [2025-01-21T18:05:20.102Z] + sed -e '/^options/!d' -e 's/^options\s*//'
18:05:20 [2025-01-21T18:05:20.102Z] + coreos_gf_shutdown
18:05:20 [2025-01-21T18:05:20.102Z] + coreos_gf umount-all
18:05:20 [2025-01-21T18:05:20.102Z] + guestfish --remote -- umount-all
18:05:20 [2025-01-21T18:05:20.354Z] + coreos_gf exit
18:05:20 [2025-01-21T18:05:20.354Z] + guestfish --remote -- exit
18:05:20 [2025-01-21T18:05:20.354Z] + GUESTFISH_RUNNING=
18:05:20 [2025-01-21T18:05:20.354Z] + GUESTFISH_PID=
18:05:20 [2025-01-21T18:05:20.354Z] + _coreos_gf_cleanup
18:05:20 [2025-01-21T18:05:20.354Z] + guestfish --remote -- exit
18:05:20 [2025-01-21T18:05:20.354Z] + :
18:05:20 [2025-01-21T18:05:20.354Z] Substituting ISO kernel arguments: rw mitigations=auto,nosmt coreos.liveiso=fedora-coreos-42.20250121.dev.0
18:05:20 [2025-01-21T18:05:20.354Z] src/config/live/zipl.prm -> /home/jenkins/agent/workspace/test-override/tmp/buildpost-live/live/zipl.prm
18:05:20 [2025-01-21T18:05:20.354Z] src/config/live/EFI/fedora/grub.cfg -> /home/jenkins/agent/workspace/test-override/tmp/buildpost-live/live/EFI/fedora/grub.cfg
18:05:20 [2025-01-21T18:05:20.354Z] src/config/live/isolinux/boot.msg -> /home/jenkins/agent/workspace/test-override/tmp/buildpost-live/live/isolinux/boot.msg
18:05:20 [2025-01-21T18:05:20.355Z] src/config/live/isolinux/isolinux.cfg -> /home/jenkins/agent/workspace/test-override/tmp/buildpost-live/live/isolinux/isolinux.cfg
18:05:20 [2025-01-21T18:05:20.355Z] 2025-01-21 18:05:20,295 INFO - Running command: ['/usr/bin/ostree', 'checkout', '--repo', '/home/jenkins/agent/workspace/test-override/tmp/repo', '--user-mode', '--subpath', '/usr/lib/bootupd/updates/EFI', '14912cc3f74fb7b4e4af7f63bc88fc6b4555f12163dfa7376b6a400ae3723733', '/home/jenkins/agent/workspace/test-override/tmp/buildpost-live/efi']
18:05:20 [2025-01-21T18:05:20.355Z] 2025-01-21 18:05:20,349 INFO - Running command: ['virt-make-fs', '--type=vfat', '--label=EFI-SYSTEM', '/tmp/tmp11vinf5r.tar', '/home/jenkins/agent/workspace/test-override/tmp/buildpost-live/live/images/efiboot.img']
18:05:22 [2025-01-21T18:05:22.227Z] 2025-01-21 18:05:22,171 INFO - Running command: ['/usr/bin/genisoimage', '-verbose', '-V', 'fedora-coreos-42.20250121.dev.0', '-volset', 'fedora-coreos-42.20250121.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.20250121.dev.0-live.x86_64.iso', '/home/jenkins/agent/workspace/test-override/tmp/buildpost-live/live']
18:05:22 [2025-01-21T18:05:22.227Z] I: -input-charset not specified, using utf-8 (detected in locale settings)
18:05:22 [2025-01-21T18:05:22.227Z] genisoimage 1.1.11 (Linux)
18:05:22 [2025-01-21T18:05:22.227Z] Scanning /home/jenkins/agent/workspace/test-override/tmp/buildpost-live/live
18:05:22 [2025-01-21T18:05:22.227Z] Scanning /home/jenkins/agent/workspace/test-override/tmp/buildpost-live/live/coreos
18:05:22 [2025-01-21T18:05:22.227Z] Scanning /home/jenkins/agent/workspace/test-override/tmp/buildpost-live/live/images
18:05:22 [2025-01-21T18:05:22.227Z] Scanning /home/jenkins/agent/workspace/test-override/tmp/buildpost-live/live/images/pxeboot
18:05:22 [2025-01-21T18:05:22.227Z] Scanning /home/jenkins/agent/workspace/test-override/tmp/buildpost-live/live/isolinux
18:05:22 [2025-01-21T18:05:22.227Z] Scanning /home/jenkins/agent/workspace/test-override/tmp/buildpost-live/live/EFI
18:05:22 [2025-01-21T18:05:22.227Z] Scanning /home/jenkins/agent/workspace/test-override/tmp/buildpost-live/live/EFI/fedora
18:05:22 [2025-01-21T18:05:22.227Z] Writing:   Initial Padblock                        Start Block 0
18:05:22 [2025-01-21T18:05:22.227Z] Done with: Initial Padblock                        Block(s)    16
18:05:22 [2025-01-21T18:05:22.227Z] Writing:   Primary Volume Descriptor               Start Block 16
18:05:22 [2025-01-21T18:05:22.227Z] Done with: Primary Volume Descriptor               Block(s)    1
18:05:22 [2025-01-21T18:05:22.227Z] Writing:   Eltorito Volume Descriptor              Start Block 17
18:05:22 [2025-01-21T18:05:22.227Z] Size of boot image is 4 sectors -> No emulation
18:05:22 [2025-01-21T18:05:22.227Z] Size of boot image is 13216 sectors -> No emulation
18:05:22 [2025-01-21T18:05:22.227Z] Done with: Eltorito Volume Descriptor              Block(s)    1
18:05:22 [2025-01-21T18:05:22.227Z] Writing:   Joliet Volume Descriptor                Start Block 18
18:05:22 [2025-01-21T18:05:22.227Z] Done with: Joliet Volume Descriptor                Block(s)    1
18:05:22 [2025-01-21T18:05:22.227Z] Writing:   End Volume Descriptor                   Start Block 19
18:05:22 [2025-01-21T18:05:22.227Z] Done with: End Volume Descriptor                   Block(s)    1
18:05:22 [2025-01-21T18:05:22.227Z] Writing:   Version block                           Start Block 20
18:05:22 [2025-01-21T18:05:22.227Z] Done with: Version block                           Block(s)    1
18:05:22 [2025-01-21T18:05:22.227Z] Writing:   Path table                              Start Block 21
18:05:22 [2025-01-21T18:05:22.227Z] Done with: Path table                              Block(s)    4
18:05:22 [2025-01-21T18:05:22.227Z] Writing:   Joliet path table                       Start Block 25
18:05:22 [2025-01-21T18:05:22.227Z] Done with: Joliet path table                       Block(s)    4
18:05:22 [2025-01-21T18:05:22.227Z] Writing:   Directory tree                          Start Block 29
18:05:22 [2025-01-21T18:05:22.227Z] Done with: Directory tree                          Block(s)    7
18:05:22 [2025-01-21T18:05:22.227Z] Writing:   Joliet directory tree                   Start Block 36
18:05:22 [2025-01-21T18:05:22.227Z] Done with: Joliet directory tree                   Block(s)    7
18:05:22 [2025-01-21T18:05:22.227Z] Writing:   Directory tree cleanup                  Start Block 43
18:05:22 [2025-01-21T18:05:22.227Z] Done with: Directory tree cleanup                  Block(s)    0
18:05:22 [2025-01-21T18:05:22.227Z] Writing:   Extension record                        Start Block 43
18:05:22 [2025-01-21T18:05:22.227Z] Done with: Extension record                        Block(s)    1
18:05:22 [2025-01-21T18:05:22.227Z] Writing:   The File(s)                             Start Block 44
18:05:22 [2025-01-21T18:05:22.227Z]   0.93% done, estimate finish Tue Jan 21 18:05:22 2025
18:05:22 [2025-01-21T18:05:22.227Z]   1.85% done, estimate finish Tue Jan 21 18:05:22 2025
18:05:22 [2025-01-21T18:05:22.227Z]   2.78% done, estimate finish Tue Jan 21 18:05:22 2025
18:05:22 [2025-01-21T18:05:22.227Z]   3.70% done, estimate finish Tue Jan 21 18:05:22 2025
18:05:22 [2025-01-21T18:05:22.227Z]   4.63% done, estimate finish Tue Jan 21 18:05:22 2025
18:05:22 [2025-01-21T18:05:22.480Z]   5.55% done, estimate finish Tue Jan 21 18:05:22 2025
18:05:22 [2025-01-21T18:05:22.480Z]   6.48% done, estimate finish Tue Jan 21 18:05:22 2025
18:05:22 [2025-01-21T18:05:22.480Z]   7.40% done, estimate finish Tue Jan 21 18:05:22 2025
18:05:22 [2025-01-21T18:05:22.480Z]   8.33% done, estimate finish Tue Jan 21 18:05:22 2025
18:05:22 [2025-01-21T18:05:22.480Z]   9.26% done, estimate finish Tue Jan 21 18:05:22 2025
18:05:22 [2025-01-21T18:05:22.480Z]  10.18% done, estimate finish Tue Jan 21 18:05:22 2025
18:05:22 [2025-01-21T18:05:22.480Z]  11.11% done, estimate finish Tue Jan 21 18:05:22 2025
18:05:22 [2025-01-21T18:05:22.480Z]  12.03% done, estimate finish Tue Jan 21 18:05:22 2025
18:05:22 [2025-01-21T18:05:22.480Z]  12.96% done, estimate finish Tue Jan 21 18:05:22 2025
18:05:22 [2025-01-21T18:05:22.480Z]  13.88% done, estimate finish Tue Jan 21 18:05:22 2025
18:05:22 [2025-01-21T18:05:22.480Z]  14.81% done, estimate finish Tue Jan 21 18:05:22 2025
18:05:22 [2025-01-21T18:05:22.480Z]  15.73% done, estimate finish Tue Jan 21 18:05:22 2025
18:05:22 [2025-01-21T18:05:22.480Z]  16.66% done, estimate finish Tue Jan 21 18:05:22 2025
18:05:22 [2025-01-21T18:05:22.480Z]  17.59% done, estimate finish Tue Jan 21 18:05:22 2025
18:05:22 [2025-01-21T18:05:22.480Z]  18.51% done, estimate finish Tue Jan 21 18:05:22 2025
18:05:22 [2025-01-21T18:05:22.480Z]  19.44% done, estimate finish Tue Jan 21 18:05:22 2025
18:05:22 [2025-01-21T18:05:22.480Z]  20.36% done, estimate finish Tue Jan 21 18:05:22 2025
18:05:22 [2025-01-21T18:05:22.480Z]  21.29% done, estimate finish Tue Jan 21 18:05:22 2025
18:05:22 [2025-01-21T18:05:22.480Z]  22.21% done, estimate finish Tue Jan 21 18:05:22 2025
18:05:22 [2025-01-21T18:05:22.480Z]  23.14% done, estimate finish Tue Jan 21 18:05:22 2025
18:05:22 [2025-01-21T18:05:22.480Z]  24.07% done, estimate finish Tue Jan 21 18:05:22 2025
18:05:22 [2025-01-21T18:05:22.480Z]  24.99% done, estimate finish Tue Jan 21 18:05:22 2025
18:05:22 [2025-01-21T18:05:22.480Z]  25.92% done, estimate finish Tue Jan 21 18:05:22 2025
18:05:22 [2025-01-21T18:05:22.480Z]  26.84% done, estimate finish Tue Jan 21 18:05:22 2025
18:05:22 [2025-01-21T18:05:22.480Z]  27.77% done, estimate finish Tue Jan 21 18:05:22 2025
18:05:22 [2025-01-21T18:05:22.480Z]  28.69% done, estimate finish Tue Jan 21 18:05:22 2025
18:05:22 [2025-01-21T18:05:22.480Z]  29.62% done, estimate finish Tue Jan 21 18:05:22 2025
18:05:22 [2025-01-21T18:05:22.480Z]  30.54% done, estimate finish Tue Jan 21 18:05:22 2025
18:05:22 [2025-01-21T18:05:22.480Z]  31.47% done, estimate finish Tue Jan 21 18:05:22 2025
18:05:22 [2025-01-21T18:05:22.480Z]  32.39% done, estimate finish Tue Jan 21 18:05:22 2025
18:05:22 [2025-01-21T18:05:22.732Z]  33.32% done, estimate finish Tue Jan 21 18:05:22 2025
18:05:22 [2025-01-21T18:05:22.732Z]  34.24% done, estimate finish Tue Jan 21 18:05:22 2025
18:05:22 [2025-01-21T18:05:22.732Z]  35.17% done, estimate finish Tue Jan 21 18:05:22 2025
18:05:22 [2025-01-21T18:05:22.732Z]  36.10% done, estimate finish Tue Jan 21 18:05:22 2025
18:05:22 [2025-01-21T18:05:22.732Z]  37.02% done, estimate finish Tue Jan 21 18:05:22 2025
18:05:22 [2025-01-21T18:05:22.733Z]  37.95% done, estimate finish Tue Jan 21 18:05:22 2025
18:05:22 [2025-01-21T18:05:22.733Z]  38.87% done, estimate finish Tue Jan 21 18:05:22 2025
18:05:22 [2025-01-21T18:05:22.733Z]  39.80% done, estimate finish Tue Jan 21 18:05:22 2025
18:05:22 [2025-01-21T18:05:22.733Z]  40.72% done, estimate finish Tue Jan 21 18:05:22 2025
18:05:22 [2025-01-21T18:05:22.733Z]  41.65% done, estimate finish Tue Jan 21 18:05:22 2025
18:05:22 [2025-01-21T18:05:22.733Z]  42.58% done, estimate finish Tue Jan 21 18:05:22 2025
18:05:22 [2025-01-21T18:05:22.733Z]  43.50% done, estimate finish Tue Jan 21 18:05:22 2025
18:05:22 [2025-01-21T18:05:22.733Z]  44.43% done, estimate finish Tue Jan 21 18:05:22 2025
18:05:22 [2025-01-21T18:05:22.733Z]  45.35% done, estimate finish Tue Jan 21 18:05:22 2025
18:05:22 [2025-01-21T18:05:22.733Z]  46.28% done, estimate finish Tue Jan 21 18:05:22 2025
18:05:22 [2025-01-21T18:05:22.733Z]  47.20% done, estimate finish Tue Jan 21 18:05:22 2025
18:05:22 [2025-01-21T18:05:22.733Z]  48.13% done, estimate finish Tue Jan 21 18:05:22 2025
18:05:22 [2025-01-21T18:05:22.733Z]  49.05% done, estimate finish Tue Jan 21 18:05:22 2025
18:05:22 [2025-01-21T18:05:22.733Z]  49.98% done, estimate finish Tue Jan 21 18:05:22 2025
18:05:22 [2025-01-21T18:05:22.733Z]  50.90% done, estimate finish Tue Jan 21 18:05:22 2025
18:05:22 [2025-01-21T18:05:22.733Z]  51.83% done, estimate finish Tue Jan 21 18:05:22 2025
18:05:22 [2025-01-21T18:05:22.733Z]  52.75% done, estimate finish Tue Jan 21 18:05:22 2025
18:05:22 [2025-01-21T18:05:22.733Z]  53.68% done, estimate finish Tue Jan 21 18:05:22 2025
18:05:22 [2025-01-21T18:05:22.733Z]  54.61% done, estimate finish Tue Jan 21 18:05:22 2025
18:05:22 [2025-01-21T18:05:22.733Z]  55.53% done, estimate finish Tue Jan 21 18:05:22 2025
18:05:22 [2025-01-21T18:05:22.990Z]  56.46% done, estimate finish Tue Jan 21 18:05:22 2025
18:05:22 [2025-01-21T18:05:22.990Z]  57.38% done, estimate finish Tue Jan 21 18:05:22 2025
18:05:22 [2025-01-21T18:05:22.990Z]  58.31% done, estimate finish Tue Jan 21 18:05:22 2025
18:05:22 [2025-01-21T18:05:22.990Z]  59.23% done, estimate finish Tue Jan 21 18:05:22 2025
18:05:22 [2025-01-21T18:05:22.990Z]  60.16% done, estimate finish Tue Jan 21 18:05:22 2025
18:05:22 [2025-01-21T18:05:22.990Z]  61.09% done, estimate finish Tue Jan 21 18:05:22 2025
18:05:22 [2025-01-21T18:05:22.990Z]  62.01% done, estimate finish Tue Jan 21 18:05:22 2025
18:05:22 [2025-01-21T18:05:22.990Z]  62.94% done, estimate finish Tue Jan 21 18:05:22 2025
18:05:22 [2025-01-21T18:05:22.990Z]  63.86% done, estimate finish Tue Jan 21 18:05:22 2025
18:05:22 [2025-01-21T18:05:22.990Z]  64.79% done, estimate finish Tue Jan 21 18:05:22 2025
18:05:22 [2025-01-21T18:05:22.990Z]  65.71% done, estimate finish Tue Jan 21 18:05:22 2025
18:05:22 [2025-01-21T18:05:22.991Z]  66.64% done, estimate finish Tue Jan 21 18:05:22 2025
18:05:22 [2025-01-21T18:05:22.991Z]  67.56% done, estimate finish Tue Jan 21 18:05:22 2025
18:05:22 [2025-01-21T18:05:22.991Z]  68.49% done, estimate finish Tue Jan 21 18:05:22 2025
18:05:22 [2025-01-21T18:05:22.991Z]  69.41% done, estimate finish Tue Jan 21 18:05:22 2025
18:05:22 [2025-01-21T18:05:22.991Z]  70.34% done, estimate finish Tue Jan 21 18:05:22 2025
18:05:22 [2025-01-21T18:05:22.991Z]  71.26% done, estimate finish Tue Jan 21 18:05:22 2025
18:05:22 [2025-01-21T18:05:22.991Z]  72.19% done, estimate finish Tue Jan 21 18:05:22 2025
18:05:22 [2025-01-21T18:05:22.991Z]  73.12% done, estimate finish Tue Jan 21 18:05:22 2025
18:05:22 [2025-01-21T18:05:22.991Z]  74.04% done, estimate finish Tue Jan 21 18:05:22 2025
18:05:22 [2025-01-21T18:05:22.991Z]  74.97% done, estimate finish Tue Jan 21 18:05:22 2025
18:05:22 [2025-01-21T18:05:22.991Z]  75.89% done, estimate finish Tue Jan 21 18:05:22 2025
18:05:22 [2025-01-21T18:05:22.991Z]  76.82% done, estimate finish Tue Jan 21 18:05:22 2025
18:05:22 [2025-01-21T18:05:22.991Z]  77.74% done, estimate finish Tue Jan 21 18:05:22 2025
18:05:22 [2025-01-21T18:05:22.991Z]  78.67% done, estimate finish Tue Jan 21 18:05:22 2025
18:05:23 [2025-01-21T18:05:23.243Z]  79.60% done, estimate finish Tue Jan 21 18:05:22 2025
18:05:23 [2025-01-21T18:05:23.243Z]  80.52% done, estimate finish Tue Jan 21 18:05:23 2025
18:05:23 [2025-01-21T18:05:23.243Z]  81.45% done, estimate finish Tue Jan 21 18:05:23 2025
18:05:23 [2025-01-21T18:05:23.243Z]  82.37% done, estimate finish Tue Jan 21 18:05:23 2025
18:05:23 [2025-01-21T18:05:23.243Z]  83.30% done, estimate finish Tue Jan 21 18:05:23 2025
18:05:23 [2025-01-21T18:05:23.243Z]  84.22% done, estimate finish Tue Jan 21 18:05:23 2025
18:05:23 [2025-01-21T18:05:23.243Z]  85.15% done, estimate finish Tue Jan 21 18:05:23 2025
18:05:23 [2025-01-21T18:05:23.243Z]  86.07% done, estimate finish Tue Jan 21 18:05:23 2025
18:05:23 [2025-01-21T18:05:23.243Z]  87.00% done, estimate finish Tue Jan 21 18:05:23 2025
18:05:23 [2025-01-21T18:05:23.243Z]  87.92% done, estimate finish Tue Jan 21 18:05:23 2025
18:05:23 [2025-01-21T18:05:23.243Z]  88.85% done, estimate finish Tue Jan 21 18:05:23 2025
18:05:23 [2025-01-21T18:05:23.243Z]  89.77% done, estimate finish Tue Jan 21 18:05:23 2025
18:05:23 [2025-01-21T18:05:23.243Z]  90.70% done, estimate finish Tue Jan 21 18:05:23 2025
18:05:23 [2025-01-21T18:05:23.243Z]  91.63% done, estimate finish Tue Jan 21 18:05:23 2025
18:05:23 [2025-01-21T18:05:23.243Z]  92.55% done, estimate finish Tue Jan 21 18:05:23 2025
18:05:23 [2025-01-21T18:05:23.244Z]  93.48% done, estimate finish Tue Jan 21 18:05:23 2025
18:05:23 [2025-01-21T18:05:23.244Z]  94.40% done, estimate finish Tue Jan 21 18:05:23 2025
18:05:23 [2025-01-21T18:05:23.244Z]  95.33% done, estimate finish Tue Jan 21 18:05:23 2025
18:05:23 [2025-01-21T18:05:23.244Z]  96.25% done, estimate finish Tue Jan 21 18:05:23 2025
18:05:23 [2025-01-21T18:05:23.244Z]  97.18% done, estimate finish Tue Jan 21 18:05:23 2025
18:05:23 [2025-01-21T18:05:23.244Z]  98.11% done, estimate finish Tue Jan 21 18:05:23 2025
18:05:23 [2025-01-21T18:05:23.518Z]  99.03% done, estimate finish Tue Jan 21 18:05:23 2025
18:05:23 [2025-01-21T18:05:23.518Z]  99.96% done, estimate finish Tue Jan 21 18:05:23 2025
18:05:23 [2025-01-21T18:05:23.518Z] Total translation table size: 2048
18:05:23 [2025-01-21T18:05:23.518Z] Total rockridge attributes bytes: 3006
18:05:23 [2025-01-21T18:05:23.518Z] Total directory bytes: 12288
18:05:23 [2025-01-21T18:05:23.518Z] Path table size(bytes): 96
18:05:23 [2025-01-21T18:05:23.518Z] Done with: The File(s)                             Block(s)    540054
18:05:23 [2025-01-21T18:05:23.518Z] Writing:   Ending Padblock                         Start Block 540098
18:05:23 [2025-01-21T18:05:23.518Z] Done with: Ending Padblock                         Block(s)    150
18:05:23 [2025-01-21T18:05:23.518Z] Max brk space used 1c000
18:05:23 [2025-01-21T18:05:23.518Z] 540248 extents written (1055 MB)
18:05:23 [2025-01-21T18:05:23.518Z] 2025-01-21 18:05:23,260 INFO - Running command: ['/usr/bin/isohybrid', '--uefi', '/home/jenkins/agent/workspace/test-override/tmp/buildpost-live/fedora-coreos-42.20250121.dev.0-live.x86_64.iso']
18:05:23 [2025-01-21T18:05:23.518Z] isohybrid: Warning: more than 1024 cylinders: 1056
18:05:23 [2025-01-21T18:05:23.518Z] isohybrid: Not all BIOSes will be able to boot this device
18:05:24 [2025-01-21T18:05:24.436Z] 2025-01-21 18:05:24,277 INFO - Running command: ['/usr/bin/genisoimage', '-verbose', '-V', 'fedora-coreos-42.20250121.dev.0', '-volset', 'fedora-coreos-42.20250121.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.20250121.dev.0-live.x86_64.iso.minimal', '/home/jenkins/agent/workspace/test-override/tmp/buildpost-live/live']
18:05:24 [2025-01-21T18:05:24.437Z] I: -input-charset not specified, using utf-8 (detected in locale settings)
18:05:24 [2025-01-21T18:05:24.437Z] genisoimage 1.1.11 (Linux)
18:05:24 [2025-01-21T18:05:24.437Z] Scanning /home/jenkins/agent/workspace/test-override/tmp/buildpost-live/live
18:05:24 [2025-01-21T18:05:24.437Z] Scanning /home/jenkins/agent/workspace/test-override/tmp/buildpost-live/live/coreos
18:05:24 [2025-01-21T18:05:24.437Z] Scanning /home/jenkins/agent/workspace/test-override/tmp/buildpost-live/live/images
18:05:24 [2025-01-21T18:05:24.437Z] Scanning /home/jenkins/agent/workspace/test-override/tmp/buildpost-live/live/images/pxeboot
18:05:24 [2025-01-21T18:05:24.437Z] Scanning /home/jenkins/agent/workspace/test-override/tmp/buildpost-live/live/isolinux
18:05:24 [2025-01-21T18:05:24.437Z] Scanning /home/jenkins/agent/workspace/test-override/tmp/buildpost-live/live/EFI
18:05:24 [2025-01-21T18:05:24.437Z] Scanning /home/jenkins/agent/workspace/test-override/tmp/buildpost-live/live/EFI/fedora
18:05:24 [2025-01-21T18:05:24.437Z] Writing:   Initial Padblock                        Start Block 0
18:05:24 [2025-01-21T18:05:24.437Z] Done with: Initial Padblock                        Block(s)    16
18:05:24 [2025-01-21T18:05:24.437Z] Writing:   Primary Volume Descriptor               Start Block 16
18:05:24 [2025-01-21T18:05:24.437Z] Done with: Primary Volume Descriptor               Block(s)    1
18:05:24 [2025-01-21T18:05:24.437Z] Writing:   Eltorito Volume Descriptor              Start Block 17
18:05:24 [2025-01-21T18:05:24.437Z] Size of boot image is 4 sectors -> No emulation
18:05:24 [2025-01-21T18:05:24.437Z] Size of boot image is 13216 sectors -> No emulation
18:05:24 [2025-01-21T18:05:24.437Z] Done with: Eltorito Volume Descriptor              Block(s)    1
18:05:24 [2025-01-21T18:05:24.437Z] Writing:   Joliet Volume Descriptor                Start Block 18
18:05:24 [2025-01-21T18:05:24.437Z] Done with: Joliet Volume Descriptor                Block(s)    1
18:05:24 [2025-01-21T18:05:24.437Z] Writing:   End Volume Descriptor                   Start Block 19
18:05:24 [2025-01-21T18:05:24.437Z] Done with: End Volume Descriptor                   Block(s)    1
18:05:24 [2025-01-21T18:05:24.437Z] Writing:   Version block                           Start Block 20
18:05:24 [2025-01-21T18:05:24.437Z] Done with: Version block                           Block(s)    1
18:05:24 [2025-01-21T18:05:24.437Z] Writing:   Path table                              Start Block 21
18:05:24 [2025-01-21T18:05:24.437Z] Done with: Path table                              Block(s)    4
18:05:24 [2025-01-21T18:05:24.437Z] Writing:   Joliet path table                       Start Block 25
18:05:24 [2025-01-21T18:05:24.437Z] Done with: Joliet path table                       Block(s)    4
18:05:24 [2025-01-21T18:05:24.437Z] Writing:   Directory tree                          Start Block 29
18:05:24 [2025-01-21T18:05:24.437Z] Done with: Directory tree                          Block(s)    7
18:05:24 [2025-01-21T18:05:24.437Z] Writing:   Joliet directory tree                   Start Block 36
18:05:24 [2025-01-21T18:05:24.437Z] Done with: Joliet directory tree                   Block(s)    7
18:05:24 [2025-01-21T18:05:24.437Z] Writing:   Directory tree cleanup                  Start Block 43
18:05:24 [2025-01-21T18:05:24.437Z] Done with: Directory tree cleanup                  Block(s)    0
18:05:24 [2025-01-21T18:05:24.437Z] Writing:   Extension record                        Start Block 43
18:05:24 [2025-01-21T18:05:24.437Z] Done with: Extension record                        Block(s)    1
18:05:24 [2025-01-21T18:05:24.437Z] Writing:   The File(s)                             Start Block 44
18:05:24 [2025-01-21T18:05:24.437Z]   8.51% done, estimate finish Tue Jan 21 18:05:24 2025
18:05:24 [2025-01-21T18:05:24.437Z]  17.03% done, estimate finish Tue Jan 21 18:05:24 2025
18:05:24 [2025-01-21T18:05:24.437Z]  25.53% done, estimate finish Tue Jan 21 18:05:24 2025
18:05:24 [2025-01-21T18:05:24.437Z]  34.05% done, estimate finish Tue Jan 21 18:05:24 2025
18:05:24 [2025-01-21T18:05:24.437Z]  42.54% done, estimate finish Tue Jan 21 18:05:24 2025
18:05:24 [2025-01-21T18:05:24.437Z]  51.06% done, estimate finish Tue Jan 21 18:05:24 2025
18:05:24 [2025-01-21T18:05:24.437Z]  59.55% done, estimate finish Tue Jan 21 18:05:24 2025
18:05:24 [2025-01-21T18:05:24.437Z]  68.07% done, estimate finish Tue Jan 21 18:05:24 2025
18:05:24 [2025-01-21T18:05:24.437Z]  76.56% done, estimate finish Tue Jan 21 18:05:24 2025
18:05:24 [2025-01-21T18:05:24.437Z]  85.08% done, estimate finish Tue Jan 21 18:05:24 2025
18:05:24 [2025-01-21T18:05:24.437Z]  93.59% done, estimate finish Tue Jan 21 18:05:24 2025
18:05:24 [2025-01-21T18:05:24.437Z] Total translation table size: 2048
18:05:24 [2025-01-21T18:05:24.437Z] Total rockridge attributes bytes: 2842
18:05:24 [2025-01-21T18:05:24.437Z] Total directory bytes: 12288
18:05:24 [2025-01-21T18:05:24.437Z] Path table size(bytes): 96
18:05:24 [2025-01-21T18:05:24.437Z] Done with: The File(s)                             Block(s)    58585
18:05:24 [2025-01-21T18:05:24.437Z] Writing:   Ending Padblock                         Start Block 58629
18:05:24 [2025-01-21T18:05:24.437Z] Done with: Ending Padblock                         Block(s)    150
18:05:24 [2025-01-21T18:05:24.437Z] Max brk space used 1b000
18:05:24 [2025-01-21T18:05:24.437Z] 58779 extents written (114 MB)
18:05:24 [2025-01-21T18:05:24.437Z] 2025-01-21 18:05:24,396 INFO - Running command: ['/usr/bin/isohybrid', '--uefi', '/home/jenkins/agent/workspace/test-override/tmp/buildpost-live/fedora-coreos-42.20250121.dev.0-live.x86_64.iso.minimal']
18:05:27 [2025-01-21T18:05:27.682Z] 2025-01-21 18:05:27,181 INFO - Running command: ['/usr/lib/coreos-assembler/runvm-coreos-installer', 'builds/42.20250121.dev.0/x86_64/fedora-coreos-42.20250121.dev.0-metal.x86_64.raw', '', 'pack', 'minimal-iso', '/home/jenkins/agent/workspace/test-override/tmp/buildpost-live/fedora-coreos-42.20250121.dev.0-live.x86_64.iso', '/home/jenkins/agent/workspace/test-override/tmp/buildpost-live/fedora-coreos-42.20250121.dev.0-live.x86_64.iso.minimal', '--consume']
18:05:49 [2025-01-21T18:05:49.749Z] + RUST_BACKTRACE=full
18:05:49 [2025-01-21T18:05:49.749Z] + chroot /sysroot/ostree/deploy/fedora-coreos/deploy/9bc09d31474689a9a0031f45d86ad29f06f10f55e06be82f912daa3649a6de55.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.20250121.dev.0-live.x86_64.iso /home/jenkins/agent/workspace/test-override/tmp/buildpost-live/fedora-coreos-42.20250121.dev.0-live.x86_64.iso.minimal --consume
18:05:49 [2025-01-21T18:05:49.749Z] Packing minimal ISO
18:05:49 [2025-01-21T18:05:49.749Z] Matched 17 files of 17
18:05:49 [2025-01-21T18:05:49.749Z] Total bytes skipped: 119959547
18:05:49 [2025-01-21T18:05:49.749Z] Total bytes written: 626693
18:05:49 [2025-01-21T18:05:49.749Z] Total bytes written (compressed): 2792
18:05:49 [2025-01-21T18:05:49.749Z] Verifying that packed image matches digest
18:05:49 [2025-01-21T18:05:49.749Z] Packing successful!
18:05:49 [2025-01-21T18:05:49.749Z] + '[' -f /var/tmp/coreos-installer-output ']'
18:05:51 [2025-01-21T18:05:51.628Z] Updated: builds/42.20250121.dev.0/x86_64/meta.json