Console Output

01:21:42 [2024-11-23T01:21:42.905Z] + set -xeuo pipefail
01:21:42 [2024-11-23T01:21:42.905Z] ++ umask
01:21:42 [2024-11-23T01:21:42.905Z] + '[' 0022 = 0000 ']'
01:21:42 [2024-11-23T01:21:42.905Z] + cd /home/jenkins/agent/workspace/test-override
01:21:42 [2024-11-23T01:21:42.905Z] + cosa kola run-upgrade --rerun --allow-rerun-success=tags=needs-internet --build=latest --output-dir=/home/jenkins/agent/workspace/test-override/tmp/kola-7xdCe/kola-upgrade-x86_64 --on-warn-failure-exit-77 --arch=x86_64 --upgrades
01:21:42 [2024-11-23T01:21:42.905Z] kola -p qemu --build latest run-upgrade --rerun --allow-rerun-success=tags=needs-internet --on-warn-failure-exit-77 --arch=x86_64 --output-dir /home/jenkins/agent/workspace/test-override/tmp/kola-7xdCe/kola-upgrade-x86_64 --qemu-image-dir tmp/kola-qemu-cache -v --find-parent-image
01:21:42 [2024-11-23T01:21:42.905Z] 2024-11-23T01:21:42Z cli: Started logging at level INFO
01:21:42 [2024-11-23T01:21:42.905Z] 2024-11-23T01:21:42Z cli: Started logging at level INFO
01:21:43 [2024-11-23T01:21:43.157Z] Downloading image from https://builds.coreos.fedoraproject.org/prod/streams/rawhide/builds/42.20241121.91.1/x86_64/fedora-coreos-42.20241121.91.1-qemu.x86_64.qcow2.xz
01:21:43 [2024-11-23T01:21:43.157Z] Downloading signature from https://builds.coreos.fedoraproject.org/prod/streams/rawhide/builds/42.20241121.91.1/x86_64/fedora-coreos-42.20241121.91.1-qemu.x86_64.qcow2.xz.sig
01:21:44 [2024-11-23T01:21:44.509Z] Read disk 101.5 MiB/718.0 MiB (14%)
01:21:45 [2024-11-23T01:21:45.425Z] Read disk 118.3 MiB/718.0 MiB (16%)
01:21:46 [2024-11-23T01:21:46.342Z] Read disk 156.0 MiB/718.0 MiB (21%)
01:21:47 [2024-11-23T01:21:47.274Z] Read disk 172.7 MiB/718.0 MiB (24%)
01:21:48 [2024-11-23T01:21:48.626Z] Read disk 188.7 MiB/718.0 MiB (26%)
01:21:49 [2024-11-23T01:21:49.543Z] Read disk 205.0 MiB/718.0 MiB (28%)
01:21:50 [2024-11-23T01:21:50.460Z] Read disk 220.3 MiB/718.0 MiB (30%)
01:21:51 [2024-11-23T01:21:51.377Z] Read disk 239.2 MiB/718.0 MiB (33%)
01:21:52 [2024-11-23T01:21:52.295Z] Read disk 256.0 MiB/718.0 MiB (35%)
01:21:53 [2024-11-23T01:21:53.652Z] Read disk 272.3 MiB/718.0 MiB (37%)
01:21:54 [2024-11-23T01:21:54.569Z] Read disk 288.0 MiB/718.0 MiB (40%)
01:21:55 [2024-11-23T01:21:55.486Z] Read disk 329.2 MiB/718.0 MiB (45%)
01:21:56 [2024-11-23T01:21:56.403Z] Read disk 394.0 MiB/718.0 MiB (54%)
01:21:57 [2024-11-23T01:21:57.340Z] Read disk 412.1 MiB/718.0 MiB (57%)
01:21:58 [2024-11-23T01:21:58.691Z] Read disk 444.7 MiB/718.0 MiB (61%)
01:21:59 [2024-11-23T01:21:59.608Z] Read disk 461.2 MiB/718.0 MiB (64%)
01:22:00 [2024-11-23T01:22:00.526Z] Read disk 481.2 MiB/718.0 MiB (67%)
01:22:01 [2024-11-23T01:22:01.443Z] Read disk 500.6 MiB/718.0 MiB (69%)
01:22:02 [2024-11-23T01:22:02.373Z] Read disk 517.0 MiB/718.0 MiB (71%)
01:22:03 [2024-11-23T01:22:03.290Z] Read disk 537.4 MiB/718.0 MiB (74%)
01:22:04 [2024-11-23T01:22:04.642Z] Read disk 553.8 MiB/718.0 MiB (77%)
01:22:05 [2024-11-23T01:22:05.559Z] Read disk 567.1 MiB/718.0 MiB (78%)
01:22:06 [2024-11-23T01:22:06.476Z] Read disk 587.4 MiB/718.0 MiB (81%)
01:22:07 [2024-11-23T01:22:07.392Z] Read disk 602.3 MiB/718.0 MiB (83%)
01:22:08 [2024-11-23T01:22:08.323Z] Read disk 634.5 MiB/718.0 MiB (88%)
01:22:09 [2024-11-23T01:22:09.683Z] Read disk 650.9 MiB/718.0 MiB (90%)
01:22:10 [2024-11-23T01:22:10.601Z] Read disk 665.8 MiB/718.0 MiB (92%)
01:22:11 [2024-11-23T01:22:11.517Z] Read disk 687.9 MiB/718.0 MiB (95%)
01:22:12 [2024-11-23T01:22:12.434Z] Read disk 707.3 MiB/718.0 MiB (98%)
01:22:12 [2024-11-23T01:22:12.989Z] Read disk 718.0 MiB/718.0 MiB (100%)
01:22:12 [2024-11-23T01:22:12.989Z] Read disk 718.0 MiB/718.0 MiB (100%)
01:22:12 [2024-11-23T01:22:12.989Z] Read disk 718.0 MiB/718.0 MiB (100%)
01:22:12 [2024-11-23T01:22:12.989Z] gpg: Signature made Thu Nov 21 16:04:26 2024 UTC
01:22:12 [2024-11-23T01:22:12.989Z] gpg:                using RSA key B0F4950458F69E1150C6C5EDC8AC4916105EF944
01:22:12 [2024-11-23T01:22:12.989Z] gpg: checking the trustdb
01:22:12 [2024-11-23T01:22:12.989Z] gpg: marginals needed: 3  completes needed: 1  trust model: pgp
01:22:12 [2024-11-23T01:22:12.989Z] gpg: depth: 0  valid:   5  signed:   0  trust: 0-, 0q, 0n, 0m, 0f, 5u
01:22:12 [2024-11-23T01:22:12.989Z] gpg: Good signature from "Fedora (42) <fedora-42-primary@fedoraproject.org>" [ultimate]
01:22:14 [2024-11-23T01:22:14.874Z] tmp/kola-qemu-cache/fedora-coreos-42.20241121.91.1-qemu.x86_64.qcow2
01:22:15 [2024-11-23T01:22:15.126Z] === RUN   fcos.upgrade.basic
01:22:41 [2024-11-23T01:22:41.591Z] === RUN   fcos.upgrade.basic/setup
01:22:44 [2024-11-23T01:22:44.088Z] === RUN   fcos.upgrade.basic/upgrade-from-previous
01:33:20 [2024-11-23T01:33:20.610Z] === RUN   fcos.upgrade.basic/upgrade-from-current
01:33:20 [2024-11-23T01:33:20.610Z] --- FAIL: fcos.upgrade.basic (661.75s)
01:33:20 [2024-11-23T01:33:20.610Z]     --- PASS: fcos.upgrade.basic/setup (4.30s)
01:33:20 [2024-11-23T01:33:20.610Z]     --- FAIL: fcos.upgrade.basic/upgrade-from-previous (629.59s)
01:33:20 [2024-11-23T01:33:20.610Z]             basic.go:303: failed waiting for machine reboot: machine "6026888b-51dd-423c-9aa6-6d07e5d8d0e3" failed to start: ssh journalctl failed: time limit exceeded
01:33:20 [2024-11-23T01:33:20.610Z]     --- SKIP: fcos.upgrade.basic/upgrade-from-current (0.00s)
01:33:20 [2024-11-23T01:33:20.610Z]             cluster.go:54: A previous test has already failed
01:33:20 [2024-11-23T01:33:20.610Z]         harness.go:1740: Found dracut fatal on machine 6026888b-51dd-423c-9aa6-6d07e5d8d0e3 console
01:33:20 [2024-11-23T01:33:20.610Z]         harness.go:1740: Found kernel warning (crypto/testmgr.c:6048 alg_test.cold+0x84/0xcf
) on machine 6026888b-51dd-423c-9aa6-6d07e5d8d0e3 console
01:33:20 [2024-11-23T01:33:20.610Z] FAIL, output in /home/jenkins/agent/workspace/test-override/tmp/kola-7xdCe/kola-upgrade-x86_64
01:33:20 [2024-11-23T01:33:20.610Z] 
01:33:20 [2024-11-23T01:33:20.610Z] 
01:33:20 [2024-11-23T01:33:20.610Z] ======== Re-running failed tests (flake detection) ========
01:33:20 [2024-11-23T01:33:20.610Z] 
01:33:20 [2024-11-23T01:33:20.610Z] === RUN   fcos.upgrade.basic
01:33:42 [2024-11-23T01:33:42.450Z] === RUN   fcos.upgrade.basic/setup
01:33:44 [2024-11-23T01:33:44.961Z] === RUN   fcos.upgrade.basic/upgrade-from-previous
01:44:21 [2024-11-23T01:44:21.852Z] === RUN   fcos.upgrade.basic/upgrade-from-current
01:44:21 [2024-11-23T01:44:21.852Z] --- FAIL: fcos.upgrade.basic (657.89s)
01:44:21 [2024-11-23T01:44:21.852Z]     --- PASS: fcos.upgrade.basic/setup (4.32s)
01:44:21 [2024-11-23T01:44:21.852Z]     --- FAIL: fcos.upgrade.basic/upgrade-from-previous (626.85s)
01:44:21 [2024-11-23T01:44:21.852Z]             basic.go:303: failed waiting for machine reboot: machine "bb9f7ffd-a0cf-42dd-aee0-22d9473f8a29" failed to start: ssh journalctl failed: time limit exceeded
01:44:21 [2024-11-23T01:44:21.852Z]     --- SKIP: fcos.upgrade.basic/upgrade-from-current (0.00s)
01:44:21 [2024-11-23T01:44:21.852Z]             cluster.go:54: A previous test has already failed
01:44:21 [2024-11-23T01:44:21.852Z]         harness.go:1740: Found dracut fatal on machine bb9f7ffd-a0cf-42dd-aee0-22d9473f8a29 console
01:44:21 [2024-11-23T01:44:21.852Z]         harness.go:1740: Found kernel warning (crypto/testmgr.c:6048 alg_test.cold+0x84/0xcf
) on machine bb9f7ffd-a0cf-42dd-aee0-22d9473f8a29 console
01:44:21 [2024-11-23T01:44:21.852Z] FAIL, output in /home/jenkins/agent/workspace/test-override/tmp/kola-7xdCe/kola-upgrade-x86_64/rerun
01:44:21 [2024-11-23T01:44:21.852Z] Error: harness: test suite failed
01:44:21 [2024-11-23T01:44:21.852Z] 2024-11-23T01:44:14Z cli: harness: test suite failed
01:44:21 [2024-11-23T01:44:21.852Z] failed to execute cmd-kola: exit status 1