Console Output
Skipping 229 KB..
Full LogTestTestTestTestTestTestTestTestTestTestTestTestTestTestTestTest (hide)Test (hide)Test (hide)Test (hide)Test (hide)TestTestTestTestTest (hide)Test (hide)Test (hide)Test (hide)Test (hide)Test (Report Completion) (hide)Test (hide)TestTestTest (hide)TestTestTestTestTestTestTestTestTestTestTestTestTestTestTestTestTestTestTestTestTestTestTestTestTestTestTestTestTestTestTestTestTestTestTestTestTestTestTestTestTestTestTestTestTestTestTestTestTestTestTestTestTestTestTestTestTestTestTestTestTestTestTestTestTestTestTestTestTestTestTestTestTestTestTestTestTestTestTestTestTestTestTestTestTestTestTestTestTestTestTestTestTestTestTestTestTestTestTestTestTestTestTestTestTestTestTestTestTestTestTestTestTestTestTestTestTestTestTestTestTestTestTestTestTestTestTestTestTestTestTestTestTestTestTestTestTestTestTestTestTestTestTestTestTestTestTestTestTestTestTestTestTestTestTestTestTestTestTestTestTestTestTestTestTestTestTestTestTestTestTestTestTestTestTestTestTestTestTestTestTestTestTestTestTestTestTestTestTestTestTestTestTestTestTestTestTest 00:07:13 [2025-03-28T00:07:13.707Z] ostree diff commit to: 83865029e16e4bd7d0391f312202d9b8304052c7053807f9d0b1b49b0e94c02d
00:07:13 [2025-03-28T00:07:13.707Z] Upgraded:
00:07:13 [2025-03-28T00:07:13.707Z] kernel 6.14.0-0.rc7.20250321gitb3ee1e460951.60.fc43 -> 6.15.0-0.rc0.20250327git1a9239bb4253.5.fc43
00:07:13 [2025-03-28T00:07:13.707Z] kernel-core 6.14.0-0.rc7.20250321gitb3ee1e460951.60.fc43 -> 6.15.0-0.rc0.20250327git1a9239bb4253.5.fc43
00:07:13 [2025-03-28T00:07:13.707Z] kernel-modules 6.14.0-0.rc7.20250321gitb3ee1e460951.60.fc43 -> 6.15.0-0.rc0.20250327git1a9239bb4253.5.fc43
00:07:13 [2025-03-28T00:07:13.707Z] kernel-modules-core 6.14.0-0.rc7.20250321gitb3ee1e460951.60.fc43 -> 6.15.0-0.rc0.20250327git1a9239bb4253.5.fc43
00:07:13 [2025-03-28T00:07:13.707Z]
00:07:13 [2025-03-28T00:07:13.707Z] New image input checksum: 5d6b2dcb76bf147fcd92fedbbb3b54e110438c93ea4f97cabf28eb98ea21e409
00:07:13 [2025-03-28T00:07:13.707Z] None
00:07:13 [2025-03-28T00:07:13.707Z] New build ID: 43.20250327.dev.0
00:07:13 [2025-03-28T00:07:13.707Z] Running: rpm-ostree compose container-encapsulate --max-layers=50 --format-version=1 --repo=/home/jenkins/agent/workspace/test-override/tmp/repo --label=coreos-assembler.image-config-checksum=c3645355120b999e10e5bf1b5dfd2a15bc3c4534eaa1e88b6017849d93e3020b --label=coreos-assembler.image-input-checksum=5d6b2dcb76bf147fcd92fedbbb3b54e110438c93ea4f97cabf28eb98ea21e409 --label=org.opencontainers.image.source=https://github.com/coreos/fedora-coreos-config --label=org.opencontainers.image.revision=8a53dfa64a81e7714634757bcfa7d0a63b5f5d8e --copymeta-opt=fedora-coreos.stream 43.20250327.dev.0 oci-archive:fedora-coreos-43.20250327.dev.0-ostree.x86_64.ociarchive.tmp:latest
00:07:28 [2025-03-28T00:07:28.524Z] Building package mapping...done
00:07:28 [2025-03-28T00:07:28.524Z] 21510 objects in 445 packages (284 source)
00:07:28 [2025-03-28T00:07:28.524Z] rpm size: 1406570592
00:07:28 [2025-03-28T00:07:28.524Z] Earliest changed package: shim-x64-15.8-3.x86_64 at 2024-03-19 20:21:09 UTC
00:07:28 [2025-03-28T00:07:28.524Z] Duplicates: 1307
00:07:28 [2025-03-28T00:07:28.524Z] Multiple owners: 108
00:08:15 [2025-03-28T00:08:15.113Z] Generating container image...done
00:08:15 [2025-03-28T00:08:15.113Z] Pushed digest: sha256:4386c209bbd67c2abb7c72da092c553099718120206b416927163d01233b0716
00:08:15 [2025-03-28T00:08:15.113Z] Total objects: 25498
00:08:15 [2025-03-28T00:08:15.113Z] No unreachable objects
00:08:15 [2025-03-28T00:08:15.113Z] renamed 'commitmeta.json' -> '/home/jenkins/agent/workspace/test-override/builds/43.20250327.dev.0/x86_64/commitmeta.json'
00:08:15 [2025-03-28T00:08:15.113Z] renamed 'ostree-commit-object' -> '/home/jenkins/agent/workspace/test-override/builds/43.20250327.dev.0/x86_64/ostree-commit-object'
00:08:15 [2025-03-28T00:08:15.113Z] renamed 'manifest-lock.generated.x86_64.json' -> '/home/jenkins/agent/workspace/test-override/builds/43.20250327.dev.0/x86_64/manifest-lock.generated.x86_64.json'
00:08:15 [2025-03-28T00:08:15.113Z] renamed 'coreos-assembler-config-git.json' -> '/home/jenkins/agent/workspace/test-override/builds/43.20250327.dev.0/x86_64/coreos-assembler-config-git.json'
00:08:15 [2025-03-28T00:08:15.113Z] renamed 'coreos-assembler-config.tar.gz' -> '/home/jenkins/agent/workspace/test-override/builds/43.20250327.dev.0/x86_64/coreos-assembler-config.tar.gz'
00:08:15 [2025-03-28T00:08:15.113Z] renamed 'fedora-coreos-43.20250327.dev.0-ostree.x86_64.ociarchive' -> '/home/jenkins/agent/workspace/test-override/builds/43.20250327.dev.0/x86_64/fedora-coreos-43.20250327.dev.0-ostree.x86_64.ociarchive'
00:08:15 [2025-03-28T00:08:15.113Z] renamed 'fedora-coreos-43.20250327.dev.0-ostree.x86_64-manifest.json' -> '/home/jenkins/agent/workspace/test-override/builds/43.20250327.dev.0/x86_64/fedora-coreos-43.20250327.dev.0-ostree.x86_64-manifest.json'
00:08:15 [2025-03-28T00:08:15.113Z] renamed 'meta.json' -> '/home/jenkins/agent/workspace/test-override/builds/43.20250327.dev.0/x86_64/meta.json'
00:08:15 [2025-03-28T00:08:15.113Z] info: Missing CAP_SYS_ADMIN; using virt
00:08:15 [2025-03-28T00:08:15.113Z] Config commit: 8a53dfa64a81e7714634757bcfa7d0a63b5f5d8e
00:08:15 [2025-03-28T00:08:15.113Z] Using manifest: /home/jenkins/agent/workspace/test-override/src/config/manifest.yaml
00:08:16 [2025-03-28T00:08:16.031Z] Will build qemu
00:08:17 [2025-03-28T00:08:17.408Z] Estimating disk size...
00:08:19 [2025-03-28T00:08:19.280Z] Disk sizes: metal: 2632M (estimated), cloud: 10240M
00:08:37 [2025-03-28T00:08:37.292Z] ++ arch
00:08:37 [2025-03-28T00:08:37.292Z] + osbuild-mpp -D 'arch="x86_64"' -D 'artifact_name_prefix="fedora-coreos-43.20250327.dev.0"' -D 'build_version="43.20250327.dev.0"' -D 'ostree_ref=""' -D 'ostree_repo=""' -D 'ociarchive="/home/jenkins/agent/workspace/test-override/builds/43.20250327.dev.0/x86_64/fedora-coreos-43.20250327.dev.0-ostree.x86_64.ociarchive"' -D 'osname="fedora-coreos"' -D 'container_imgref="ostree-remote-registry:fedora:quay.io/fedora/fedora-coreos:rawhide"' -D 'container_repo=""' -D 'container_tag=""' -D 'extra_kargs="mitigations=auto,nosmt"' -D metal_image_size_mb=2632 -D cloud_image_size_mb=10240 -D rootfs_size_mb=2119 /usr/lib/coreos-assembler/osbuild-manifests/coreos.osbuild.x86_64.mpp.yaml /tmp/osbuild-FCLH.json
00:08:37 [2025-03-28T00:08:37.849Z] + set +x
00:08:37 [2025-03-28T00:08:37.849Z] [33m
00:08:37 [2025-03-28T00:08:37.849Z] + osbuild --out cache/osbuild/out --store cache/osbuild/store --cache-max-size 20GiB --checkpoint deployed-tree --checkpoint tree --checkpoint raw-image --checkpoint metal --checkpoint metal4k --export=qemu /tmp/osbuild-FCLH.json
00:08:37 [2025-03-28T00:08:37.849Z] Filesystem Size Used Avail Use% Mounted on
00:08:37 [2025-03-28T00:08:37.849Z] /dev/vdb1 40G 3.1G 37G 8% /home/jenkins/agent/workspace/test-override/cache
00:08:37 [2025-03-28T00:08:37.849Z] [39m
00:08:38 [2025-03-28T00:08:38.102Z] starting /tmp/osbuild-FCLH.jsonPipeline source org.osbuild.curl: 41c57bc84af36541216dc4cb679d48366a2ab3618c2d00be8b5c1b6b980c495e
00:08:38 [2025-03-28T00:08:38.102Z] Build
00:08:38 [2025-03-28T00:08:38.102Z] root: <host>
00:08:41 [2025-03-28T00:08:41.363Z] source/org.osbuild.curl (org.osbuild.curl): Downloaded file:///home/jenkins/agent/workspace/test-override/builds/43.20250327.dev.0/x86_64/fedora-coreos-43.20250327.dev.0-ostree.x86_64.ociarchive
00:08:41 [2025-03-28T00:08:41.363Z]
00:08:41 [2025-03-28T00:08:41.363Z] ��� Duration: 1743120520s
00:08:41 [2025-03-28T00:08:41.363Z] Pipeline oci-archive: 94635573eec34b92fce6810776f9b6ae9a191fd9182ae9ee4e23d0771988f7a9
00:08:41 [2025-03-28T00:08:41.363Z] Build
00:08:41 [2025-03-28T00:08:41.363Z] root: <host>
00:08:41 [2025-03-28T00:08:41.363Z] runner: org.osbuild.fedora38 (org.osbuild.fedora38)
00:08:41 [2025-03-28T00:08:41.363Z] org.osbuild.copy: 94635573eec34b92fce6810776f9b6ae9a191fd9182ae9ee4e23d0771988f7a9 {
00:08:41 [2025-03-28T00:08:41.363Z] "paths": [
00:08:41 [2025-03-28T00:08:41.363Z] {
00:08:41 [2025-03-28T00:08:41.363Z] "from": "input://inlinefile/sha256:cd64b40ce56b510c8be641076b1eb611a2e4f2b8fcb8030fcf9898a8041d4c67",
00:08:41 [2025-03-28T00:08:41.363Z] "to": "tree:///coreos.ociarchive"
00:08:41 [2025-03-28T00:08:41.363Z] }
00:08:41 [2025-03-28T00:08:41.363Z] ]
00:08:41 [2025-03-28T00:08:41.363Z] }
00:08:41 [2025-03-28T00:08:41.363Z] Failed to open file "/sys/fs/selinux/checkreqprot": Read-only file system
00:08:41 [2025-03-28T00:08:41.363Z] copying '/run/osbuild/inputs/inlinefile/sha256:cd64b40ce56b510c8be641076b1eb611a2e4f2b8fcb8030fcf9898a8041d4c67' -> '/run/osbuild/tree/coreos.ociarchive'
00:08:41 [2025-03-28T00:08:41.617Z]
00:08:41 [2025-03-28T00:08:41.617Z] ��� Duration: 0s
00:08:41 [2025-03-28T00:08:41.617Z] Pipeline deployed-tree: e142dd00e9e89f8de697bfb89b69e665ebeb615fa55632943187bc180945f194
00:08:41 [2025-03-28T00:08:41.617Z] Build
00:08:41 [2025-03-28T00:08:41.617Z] root: <host>
00:08:41 [2025-03-28T00:08:41.617Z] runner: org.osbuild.fedora38 (org.osbuild.fedora38)
00:08:41 [2025-03-28T00:08:41.617Z] org.osbuild.container-deploy: e142dd00e9e89f8de697bfb89b69e665ebeb615fa55632943187bc180945f194 {}
00:08:41 [2025-03-28T00:08:41.869Z] input/images (org.osbuild.containers): target /home/jenkins/agent/workspace/test-override/cache/osbuild/store/tmp/buildroot-tmp-opi7xq3j/inputs/images
00:08:41 [2025-03-28T00:08:41.869Z] Failed to open file "/sys/fs/selinux/checkreqprot": Read-only file system
00:08:42 [2025-03-28T00:08:42.424Z] time="2025-03-28T00:08:41Z" level=info msg="Not using native diff for overlay, this may cause degraded performance for building images: kernel has CONFIG_OVERLAY_FS_REDIRECT_DIR enabled"
00:08:44 [2025-03-28T00:08:44.921Z] Getting image source signatures
00:08:44 [2025-03-28T00:08:44.921Z] Copying blob sha256:072a1c3343478d35d4eff4767f693d2ee9de55b4d93372259865bc318041b26c
00:08:46 [2025-03-28T00:08:46.285Z] Copying blob sha256:aab5ce09aecad74a10ca3002622fbde68a1fd068fc08b79bd8035aaaf3a6b980
00:08:47 [2025-03-28T00:08:47.654Z] Copying blob sha256:e9ad2e9d953d003666e353a345ff1b45c86515c39da4a7929d427d2d96dd8449
00:08:47 [2025-03-28T00:08:47.906Z] [33m
00:08:47 [2025-03-28T00:08:47.906Z] Filesystem Size Used Avail Use% Mounted on
00:08:47 [2025-03-28T00:08:47.906Z] /dev/vdb1 40G 4.8G 36G 12% /home/jenkins/agent/workspace/test-override/cache
00:08:47 [2025-03-28T00:08:47.906Z] [39m
00:08:48 [2025-03-28T00:08:48.824Z] Copying blob sha256:488dc13e201c92dcd3094783e2d40d97287423e990e91ce10a86b7c337311409
00:08:50 [2025-03-28T00:08:50.178Z] Copying blob sha256:082f8382384e7acb55147e958f0c7c8afe0482a059b3d137d58a54599ff9dfcc
00:08:51 [2025-03-28T00:08:51.548Z] Copying blob sha256:70c3aefdccaed7bd16fe635e97d467ff99cb4274cfdb3c80e546f831cf61a6d9
00:08:52 [2025-03-28T00:08:52.104Z] Copying blob sha256:ec84542bb743849d1962aace2e92b69e76db7a5e146a692a1a77996c2c5624cd
00:08:53 [2025-03-28T00:08:53.022Z] Copying blob sha256:3afc79a17c5227fb874ff4bc56ef7cc07221402522e789e4723738517d0119c1
00:08:53 [2025-03-28T00:08:53.579Z] Copying blob sha256:ffa7178e57c5df905c94005f7be2f16605092da1877c8ef171153a468929cdbb
00:08:54 [2025-03-28T00:08:54.135Z] Copying blob sha256:be7b499d203798de8b25193dfcb0fa4d1b6e5ab6a2c4e87fec2e9553dd2752bf
00:08:54 [2025-03-28T00:08:54.388Z] Copying blob sha256:f29802b0738633ca380855b417ce0f9cd7a3e0aca386857acd5c73fd04d79fc9
00:08:54 [2025-03-28T00:08:54.944Z] Copying blob sha256:538eac782ff2557ab57fe3738aa3ea3da1ea032f82b610d6e0b9f45fe13a6ec8
00:08:55 [2025-03-28T00:08:55.198Z] Copying blob sha256:873fdbf26fce6e14a87b98920a535a5cdbab8db5c592e9e3381df7cadcda777d
00:08:55 [2025-03-28T00:08:55.766Z] Copying blob sha256:5014ba8db032a235276c6ff9a7c4bc45afc5a35393f6033bd9e978d29b86d27c
00:08:56 [2025-03-28T00:08:56.321Z] Copying blob sha256:879d6c56b75540589baaebcecfb8bd9daadbbbdc54af89438583ffdcc5e9819d
00:08:56 [2025-03-28T00:08:56.574Z] Copying blob sha256:435500fc40970578d93d6ea5f1dcf2afa76bb0fb4c8c0225651550e4208cc9ac
00:08:57 [2025-03-28T00:08:57.130Z] Copying blob sha256:f37cee604a9c4a21998eb982b2d3175d5dfb215a9e513b7d1e7d7f5f8a6f2fa5
00:08:57 [2025-03-28T00:08:57.686Z] Copying blob sha256:1ff36ac9273e660cc8501ddd72030d4ce0c4bc62145fe55d1052ac8f42254053
00:08:57 [2025-03-28T00:08:57.939Z] [33m
00:08:57 [2025-03-28T00:08:57.939Z] Filesystem Size Used Avail Use% Mounted on
00:08:57 [2025-03-28T00:08:57.939Z] /dev/vdb1 40G 5.8G 35G 15% /home/jenkins/agent/workspace/test-override/cache
00:08:57 [2025-03-28T00:08:57.939Z] [39m
00:08:57 [2025-03-28T00:08:57.939Z] Copying blob sha256:620ecb26196082fac713ca45778c7e343f9f7b358b25f15b4fcd24676af0e038
00:08:58 [2025-03-28T00:08:58.192Z] Copying blob sha256:0a313c0ac65131d27840ed141f78aa1c91ac3c59cb1d5e6d57bf92b0f9c8c471
00:08:58 [2025-03-28T00:08:58.445Z] Copying blob sha256:16c86f09e5cba7bcb96d6b18bac50a9e4a23f3b72468a5a806573ceb6396efa9
00:08:58 [2025-03-28T00:08:58.698Z] Copying blob sha256:bd9b22dc8c8b071e3c101be4d62ff61eb199511887ebd09608408d5f5b5a4e0e
00:08:58 [2025-03-28T00:08:58.951Z] Copying blob sha256:05ef3638ea664cfabd3ed901f9ce79e6453f5ae61b327e8c23bbeb9ac81718e9
00:08:59 [2025-03-28T00:08:59.203Z] Copying blob sha256:e5213377723e56a4ca9c0e1af17e7d6da4f6b87de1f5f0b58ab1b4d51f0c1fed
00:08:59 [2025-03-28T00:08:59.203Z] Copying blob sha256:bd309b0b9bacfc9cc80212bd3d4793b2465589e72a37a97825b28817bc35b9b3
00:08:59 [2025-03-28T00:08:59.469Z] Copying blob sha256:f4104e37af78c074f16b05a2215a9b2fa672f2fefa4d6711d24c2343a9b4efa6
00:08:59 [2025-03-28T00:08:59.722Z] Copying blob sha256:a732cbddfe6765c2c604e1211ce2846f8132d35ed4a07c8aa587ca69b4d5aa31
00:08:59 [2025-03-28T00:08:59.975Z] Copying blob sha256:0e2c69551c56efd9f1b5694c899441f90a76aedcd57820c3c797280992242ebb
00:08:59 [2025-03-28T00:08:59.975Z] Copying blob sha256:585f6fa150114ccc0754a5e5e055175f8ef289800607459e0a0148ada99d5fad
00:09:00 [2025-03-28T00:09:00.530Z] Copying blob sha256:c0ae93518ce3c5b4e70e572be60c6ffff6f611313a655b1d3d476bbb78a4e5db
00:09:00 [2025-03-28T00:09:00.783Z] Copying blob sha256:34194a611306427d10d33696b176dfdf3b3f9edc599940b2a5ac07964d9da28e
00:09:00 [2025-03-28T00:09:00.783Z] Copying blob sha256:8d86b4ecd801ae1ac0bba87e2155055e5b85dafea836ce44487e142ebc73b753
00:09:01 [2025-03-28T00:09:01.044Z] Copying blob sha256:ec0b74d6d2ab0c146a689008eaaa7b90271009062dcb3c5dfc4dcea773522e9b
00:09:01 [2025-03-28T00:09:01.962Z] Copying blob sha256:f213e99c7f5f11b4ce2e2f04df4bd28579d0313b019073b99d3963b5c0b3ac3e
00:09:02 [2025-03-28T00:09:02.215Z] Copying blob sha256:b502114461f0e72bbaba638b96d90d6f51bf16772610c672ad352a567ba484db
00:09:02 [2025-03-28T00:09:02.467Z] Copying blob sha256:911db97a8a8e52b9e550b3e691378b7755360bccd55e3e52865531ad12bed0c6
00:09:02 [2025-03-28T00:09:02.719Z] Copying blob sha256:49b48448abab7166b5a73cad0fc5513e70049005f5085034439dc81e26910d13
00:09:02 [2025-03-28T00:09:02.719Z] Copying blob sha256:266459a9b49b62df95315282cc10b6e2b678f1df2c8202ac171f731eb131302e
00:09:03 [2025-03-28T00:09:03.650Z] Copying blob sha256:e35eec452047c1f91c41383c22d4c887a9b2e53bbf2fd92aa8db70c2f43f67f9
00:09:04 [2025-03-28T00:09:04.206Z] Copying blob sha256:efb2276c31830804550ebacc3e7c1c42fe920fd950f0efd6e7a25f896b69c953
00:09:05 [2025-03-28T00:09:05.125Z] Copying blob sha256:2b0715bab84e25e147a8d681c5b3f3543093112cbd7c971bedb807741fe0108b
00:09:05 [2025-03-28T00:09:05.377Z] Copying blob sha256:e8ea8632cd08a3fe1b90521be6c7d7c22e6c31abfebc3657cadeda042c6e4f7c
00:09:05 [2025-03-28T00:09:05.630Z] Copying blob sha256:d9da370b6bb651031bf6e4f7682e6cd10a98164cef622538ef253ee391f81c52
00:09:05 [2025-03-28T00:09:05.883Z] Copying blob sha256:e99ae8300c71b14ddb8eb9a65ec2fd9b8fab968c1d1b0e9f82ce3c6004123525
00:09:05 [2025-03-28T00:09:05.883Z] Copying blob sha256:300f4b42b2e6e4fa7b433d9806d2a63e02e5ef256d32701f333aaf1927ed9aad
00:09:06 [2025-03-28T00:09:06.438Z] Copying blob sha256:9df31db5a5071623a421db7f69e7d475dcd09dd92308433a926be7a68a10c5c2
00:09:06 [2025-03-28T00:09:06.994Z] Copying blob sha256:7925ba21825dbf1fc0ccec0d3ada3256ad2300f20b36f6750a4efdeaf14cb10d
00:09:07 [2025-03-28T00:09:07.934Z] Copying blob sha256:ea7aa2dc5c98d4e64306a59475a56eba6cbbf27919b0c65d8e069251efbbae47
00:09:07 [2025-03-28T00:09:07.934Z] [33m
00:09:07 [2025-03-28T00:09:07.934Z] Filesystem Size Used Avail Use% Mounted on
00:09:07 [2025-03-28T00:09:07.934Z] /dev/vdb1 40G 6.5G 34G 17% /home/jenkins/agent/workspace/test-override/cache
00:09:07 [2025-03-28T00:09:07.935Z] [39m
00:09:07 [2025-03-28T00:09:07.935Z] Copying blob sha256:ebdc67d30521d0c8aed00ab5dee6e312755e7355bd23d68937b7d21cf74382bd
00:09:09 [2025-03-28T00:09:09.287Z] Copying blob sha256:fa3165b603d1c1be1ee412cb6f8ec579581b6190a21d8d12748a4787e8f849b5
00:09:11 [2025-03-28T00:09:11.161Z] Copying blob sha256:9dad063a624b62064bf25dbbc2e802e472d636056f661f2a0be73efd8a4da98b
00:09:11 [2025-03-28T00:09:11.161Z] Copying config sha256:ddcac175fcacc797ab15507d94d058cad88358ab3c384440843bb048cec318f0
00:09:11 [2025-03-28T00:09:11.161Z] Writing manifest to image destination
00:09:19 [2025-03-28T00:09:19.227Z] [33m
00:09:19 [2025-03-28T00:09:19.227Z] Filesystem Size Used Avail Use% Mounted on
00:09:19 [2025-03-28T00:09:19.227Z] /dev/vdb1 40G 6.6G 34G 17% /home/jenkins/agent/workspace/test-override/cache
00:09:19 [2025-03-28T00:09:19.227Z] [39m
00:09:27 [2025-03-28T00:09:27.292Z] ddcac175fcacc797ab15507d94d058cad88358ab3c384440843bb048cec318f0
00:09:27 [2025-03-28T00:09:27.847Z] [33m
00:09:27 [2025-03-28T00:09:27.847Z] Filesystem Size Used Avail Use% Mounted on
00:09:27 [2025-03-28T00:09:27.847Z] /dev/vdb1 40G 6.7G 34G 17% /home/jenkins/agent/workspace/test-override/cache
00:09:27 [2025-03-28T00:09:27.847Z] [39m
00:09:31 [2025-03-28T00:09:31.990Z] Untagged: docker.io/library/tmp-container-deploy-29227865847563:latest
00:09:31 [2025-03-28T00:09:31.990Z] Deleted: ddcac175fcacc797ab15507d94d058cad88358ab3c384440843bb048cec318f0
00:09:31 [2025-03-28T00:09:31.990Z]
00:09:31 [2025-03-28T00:09:31.990Z] ��� Duration: 50s
00:09:38 [2025-03-28T00:09:38.511Z] Pipeline tree: 7df576b0dccd6df33a7b9b98ee4eb54b1049194b444048bd824925a666079b75
00:09:38 [2025-03-28T00:09:38.511Z] Build
00:09:38 [2025-03-28T00:09:38.511Z] root: <host>
00:09:38 [2025-03-28T00:09:38.511Z] runner: org.osbuild.fedora38 (org.osbuild.fedora38)
00:09:38 [2025-03-28T00:09:38.511Z] source-epoch: Mon Aug 1 23:42:11 2022 [1659397331]
00:09:38 [2025-03-28T00:09:38.511Z] org.osbuild.selinux: afbe34c8d422321c92294b0b0fd3e7ce93f52c4c56515dc41d1976a5d505a747 {
00:09:38 [2025-03-28T00:09:38.511Z] "file_contexts": "input://tree/etc/selinux/targeted/contexts/files/file_contexts",
00:09:38 [2025-03-28T00:09:38.511Z] "target": "tree:///"
00:09:38 [2025-03-28T00:09:38.511Z] }
00:09:38 [2025-03-28T00:09:38.511Z] Failed to open file "/sys/fs/selinux/checkreqprot": Read-only file system
00:09:38 [2025-03-28T00:09:38.511Z] [33m
00:09:38 [2025-03-28T00:09:38.511Z] Filesystem Size Used Avail Use% Mounted on
00:09:38 [2025-03-28T00:09:38.511Z] /dev/vdb1 40G 5.3G 35G 14% /home/jenkins/agent/workspace/test-override/cache
00:09:38 [2025-03-28T00:09:38.511Z] [39m
00:09:38 [2025-03-28T00:09:38.511Z] setfiles: /run/osbuild/inputs/tree/etc/selinux/targeted/contexts/files/file_contexts.bin: line 1 error due to: Non-ASCII characters found
00:09:38 [2025-03-28T00:09:38.511Z] setfiles: /run/osbuild/inputs/tree/etc/selinux/targeted/contexts/files/file_contexts.homedirs.bin: line 1 error due to: Non-ASCII characters found
00:09:38 [2025-03-28T00:09:38.511Z]
00:09:38 [2025-03-28T00:09:38.511Z] ��� Duration: 0s
00:09:38 [2025-03-28T00:09:38.511Z] org.osbuild.ostree.init-fs: 2f4aae7f0415166ffc7d407e31e3401f542462cbd55bc5a4a2b4e58e67875774 {}
00:09:38 [2025-03-28T00:09:38.511Z] Failed to open file "/sys/fs/selinux/checkreqprot": Read-only file system
00:09:38 [2025-03-28T00:09:38.511Z] ostree admin init-fs --modern /run/osbuild/tree --sysroot=/run/osbuild/tree
00:09:38 [2025-03-28T00:09:38.511Z]
00:09:38 [2025-03-28T00:09:38.511Z] ��� Duration: 0s
00:09:38 [2025-03-28T00:09:38.511Z] org.osbuild.ostree.os-init: c291f68579935e347984a3f7933a9a3156c909d55fad45df8304c050b7157e24 {
00:09:38 [2025-03-28T00:09:38.511Z] "osname": "fedora-coreos"
00:09:38 [2025-03-28T00:09:38.511Z] }
00:09:38 [2025-03-28T00:09:38.511Z] Failed to open file "/sys/fs/selinux/checkreqprot": Read-only file system
00:09:38 [2025-03-28T00:09:38.764Z] ostree admin os-init fedora-coreos --sysroot=/run/osbuild/tree
00:09:38 [2025-03-28T00:09:38.764Z]
00:09:38 [2025-03-28T00:09:38.764Z] ��� Duration: 0s
00:09:38 [2025-03-28T00:09:38.764Z] org.osbuild.ostree.config: e2eb644a3f0f257d651ff4fb0c17afc79ff5fc9d420871268e17e26e2bdef26b {
00:09:38 [2025-03-28T00:09:38.764Z] "repo": "/ostree/repo",
00:09:38 [2025-03-28T00:09:38.764Z] "config": {
00:09:38 [2025-03-28T00:09:38.764Z] "sysroot": {
00:09:38 [2025-03-28T00:09:38.764Z] "readonly": true,
00:09:38 [2025-03-28T00:09:38.764Z] "bootloader": "none",
00:09:38 [2025-03-28T00:09:38.764Z] "bls-append-except-default": "grub_users=\"\"",
00:09:38 [2025-03-28T00:09:38.764Z] "bootprefix": true
00:09:38 [2025-03-28T00:09:38.764Z] }
00:09:38 [2025-03-28T00:09:38.764Z] }
00:09:38 [2025-03-28T00:09:38.764Z] }
00:09:38 [2025-03-28T00:09:38.764Z] Failed to open file "/sys/fs/selinux/checkreqprot": Read-only file system
00:09:39 [2025-03-28T00:09:39.018Z] ostree config set sysroot.bootloader none --repo=/run/osbuild/tree/ostree/repo
00:09:39 [2025-03-28T00:09:39.018Z] ostree config set sysroot.bootprefix true --repo=/run/osbuild/tree/ostree/repo
00:09:39 [2025-03-28T00:09:39.018Z] ostree config set sysroot.readonly true --repo=/run/osbuild/tree/ostree/repo
00:09:39 [2025-03-28T00:09:39.018Z] ostree config set sysroot.bls-append-except-default grub_users="" --repo=/run/osbuild/tree/ostree/repo
00:09:39 [2025-03-28T00:09:39.018Z]
00:09:39 [2025-03-28T00:09:39.018Z] ��� Duration: 0s
00:09:39 [2025-03-28T00:09:39.018Z] org.osbuild.mkdir: 1eee6441654952f37c0058bad780928937e75ad070bad75319073d7291873f5b {
00:09:39 [2025-03-28T00:09:39.018Z] "paths": [
00:09:39 [2025-03-28T00:09:39.018Z] {
00:09:39 [2025-03-28T00:09:39.018Z] "path": "/boot/efi",
00:09:39 [2025-03-28T00:09:39.018Z] "mode": 493
00:09:39 [2025-03-28T00:09:39.018Z] }
00:09:39 [2025-03-28T00:09:39.018Z] ]
00:09:39 [2025-03-28T00:09:39.018Z] }
00:09:39 [2025-03-28T00:09:39.271Z] Failed to open file "/sys/fs/selinux/checkreqprot": Read-only file system
00:09:39 [2025-03-28T00:09:39.271Z]
00:09:39 [2025-03-28T00:09:39.271Z] ��� Duration: 0s
00:09:39 [2025-03-28T00:09:39.271Z] org.osbuild.ignition: 309908ad71d108cdc80b085c7b72683e7499cc311b6c09796c4338b0db7bd304 {}
00:09:39 [2025-03-28T00:09:39.524Z] Failed to open file "/sys/fs/selinux/checkreqprot": Read-only file system
00:09:39 [2025-03-28T00:09:39.524Z]
00:09:39 [2025-03-28T00:09:39.524Z] ��� Duration: 0s
00:09:39 [2025-03-28T00:09:39.524Z] org.osbuild.ostree.deploy.container: ccab646f68561ea283bf2f64fbce18638f75a87cc663af0d3e1f3af31735bdf5 {
00:09:39 [2025-03-28T00:09:39.524Z] "osname": "fedora-coreos",
00:09:39 [2025-03-28T00:09:39.524Z] "target_imgref": "ostree-remote-registry:fedora:quay.io/fedora/fedora-coreos:rawhide",
00:09:39 [2025-03-28T00:09:39.524Z] "mounts": [
00:09:39 [2025-03-28T00:09:39.524Z] "/boot",
00:09:39 [2025-03-28T00:09:39.524Z] "/boot/efi"
00:09:39 [2025-03-28T00:09:39.524Z] ],
00:09:39 [2025-03-28T00:09:39.524Z] "kernel_opts": [
00:09:39 [2025-03-28T00:09:39.524Z] "rw",
00:09:39 [2025-03-28T00:09:39.524Z] "$ignition_firstboot",
00:09:39 [2025-03-28T00:09:39.524Z] "mitigations=auto,nosmt"
00:09:39 [2025-03-28T00:09:39.524Z] ]
00:09:39 [2025-03-28T00:09:39.524Z] }
00:09:39 [2025-03-28T00:09:39.777Z] input/images (org.osbuild.containers): target /home/jenkins/agent/workspace/test-override/cache/osbuild/store/tmp/buildroot-tmp-unv0hox4/inputs/images
00:09:39 [2025-03-28T00:09:39.777Z] Failed to open file "/sys/fs/selinux/checkreqprot": Read-only file system
00:09:39 [2025-03-28T00:09:39.777Z] ostree container image deploy --imgref=ostree-unverified-image:oci-archive:/tmp/tmptt1ezope/image --stateroot=fedora-coreos --target-imgref=ostree-remote-registry:fedora:quay.io/fedora/fedora-coreos:rawhide --karg=rw --karg=$ignition_firstboot --karg=mitigations=auto,nosmt --sysroot=/run/osbuild/tree
00:09:49 [2025-03-28T00:09:49.699Z] [33m
00:09:49 [2025-03-28T00:09:49.699Z] Filesystem Size Used Avail Use% Mounted on
00:09:49 [2025-03-28T00:09:49.699Z] /dev/vdb1 40G 6.6G 34G 17% /home/jenkins/agent/workspace/test-override/cache
00:09:49 [2025-03-28T00:09:49.699Z] [39m
00:09:59 [2025-03-28T00:09:59.627Z] [33m
00:09:59 [2025-03-28T00:09:59.627Z] Filesystem Size Used Avail Use% Mounted on
00:09:59 [2025-03-28T00:09:59.627Z] /dev/vdb1 40G 7.3G 33G 19% /home/jenkins/agent/workspace/test-override/cache
00:09:59 [2025-03-28T00:09:59.627Z] [39m
00:10:03 [2025-03-28T00:10:03.770Z] /proc/self/fd/21/usr/etc/selinux/targeted/contexts/files/file_contexts.bin: line 1 error due to: Non-ASCII characters found
00:10:03 [2025-03-28T00:10:03.770Z] /proc/self/fd/21/usr/etc/selinux/targeted/contexts/files/file_contexts.homedirs.bin: line 1 error due to: Non-ASCII characters found
00:10:08 [2025-03-28T00:10:08.998Z] [33m
00:10:08 [2025-03-28T00:10:08.998Z] Filesystem Size Used Avail Use% Mounted on
00:10:08 [2025-03-28T00:10:08.998Z] /dev/vdb1 40G 6.8G 34G 17% /home/jenkins/agent/workspace/test-override/cache
00:10:08 [2025-03-28T00:10:08.998Z] [39m
00:10:17 [2025-03-28T00:10:17.061Z] /proc/self/fd/16/usr/etc/selinux/targeted/contexts/files/file_contexts.bin: line 1 error due to: Non-ASCII characters found
00:10:17 [2025-03-28T00:10:17.061Z] /proc/self/fd/16/usr/etc/selinux/targeted/contexts/files/file_contexts.homedirs.bin: line 1 error due to: Non-ASCII characters found
00:10:17 [2025-03-28T00:10:17.061Z] /proc/self/fd/16/etc/selinux/targeted/contexts/files/file_contexts.bin: line 1 error due to: Non-ASCII characters found
00:10:17 [2025-03-28T00:10:17.061Z] /proc/self/fd/16/etc/selinux/targeted/contexts/files/file_contexts.homedirs.bin: line 1 error due to: Non-ASCII characters found
00:10:17 [2025-03-28T00:10:17.061Z] /proc/self/fd/16/etc/selinux/targeted/contexts/files/file_contexts.bin: line 1 error due to: Non-ASCII characters found
00:10:17 [2025-03-28T00:10:17.061Z] /proc/self/fd/16/etc/selinux/targeted/contexts/files/file_contexts.homedirs.bin: line 1 error due to: Non-ASCII characters found
00:10:18 [2025-03-28T00:10:18.414Z] [33m
00:10:18 [2025-03-28T00:10:18.414Z] Filesystem Size Used Avail Use% Mounted on
00:10:18 [2025-03-28T00:10:18.414Z] /dev/vdb1 40G 6.8G 34G 17% /home/jenkins/agent/workspace/test-override/cache
00:10:18 [2025-03-28T00:10:18.414Z] [39m
00:10:18 [2025-03-28T00:10:18.414Z]
00:10:18 [2025-03-28T00:10:18.414Z] ��� Duration: 38s
00:10:18 [2025-03-28T00:10:18.414Z] org.osbuild.ostree.aleph: 2ecf5316a6e294ba78b9121ed8fbadb93edd8064770f54ae4514ed0ad90c8858 {
00:10:18 [2025-03-28T00:10:18.414Z] "coreos_compat": true,
00:10:18 [2025-03-28T00:10:18.414Z] "deployment": {
00:10:18 [2025-03-28T00:10:18.414Z] "default": true
00:10:18 [2025-03-28T00:10:18.414Z] }
00:10:18 [2025-03-28T00:10:18.414Z] }
00:10:18 [2025-03-28T00:10:18.667Z] Failed to open file "/sys/fs/selinux/checkreqprot": Read-only file system
00:10:18 [2025-03-28T00:10:18.667Z] ostree container image metadata --repo=/run/osbuild/tree/ostree/repo docker://quay.io/fedora/fedora-coreos:rawhide
00:10:18 [2025-03-28T00:10:18.667Z] ostree container image metadata --repo=/run/osbuild/tree/ostree/repo docker://quay.io/fedora/fedora-coreos:rawhide --config
00:10:18 [2025-03-28T00:10:18.919Z]
00:10:18 [2025-03-28T00:10:18.919Z] ��� Duration: 0s
00:10:18 [2025-03-28T00:10:18.919Z] org.osbuild.ostree.selinux: 7df576b0dccd6df33a7b9b98ee4eb54b1049194b444048bd824925a666079b75 {
00:10:18 [2025-03-28T00:10:18.919Z] "deployment": {
00:10:18 [2025-03-28T00:10:18.919Z] "default": true
00:10:18 [2025-03-28T00:10:18.919Z] }
00:10:18 [2025-03-28T00:10:18.919Z] }
00:10:18 [2025-03-28T00:10:18.919Z] Failed to open file "/sys/fs/selinux/checkreqprot": Read-only file system
00:10:18 [2025-03-28T00:10:18.919Z] setfiles: /run/osbuild/tree/ostree/deploy/fedora-coreos/deploy/4bc944162bb563023b70f1c6b76e899eec4910379340de909a78e1e6eda7b20d.0/etc/selinux/targeted/contexts/files/file_contexts.bin: line 1 error due to: Non-ASCII characters found
00:10:19 [2025-03-28T00:10:19.171Z] setfiles: /run/osbuild/tree/ostree/deploy/fedora-coreos/deploy/4bc944162bb563023b70f1c6b76e899eec4910379340de909a78e1e6eda7b20d.0/etc/selinux/targeted/contexts/files/file_contexts.homedirs.bin: line 1 error due to: Non-ASCII characters found
00:10:19 [2025-03-28T00:10:19.171Z] setfiles: /run/osbuild/tree/ostree/deploy/fedora-coreos/deploy/4bc944162bb563023b70f1c6b76e899eec4910379340de909a78e1e6eda7b20d.0/etc/selinux/targeted/contexts/files/file_contexts.bin: line 1 error due to: Non-ASCII characters found
00:10:19 [2025-03-28T00:10:19.171Z] setfiles: /run/osbuild/tree/ostree/deploy/fedora-coreos/deploy/4bc944162bb563023b70f1c6b76e899eec4910379340de909a78e1e6eda7b20d.0/etc/selinux/targeted/contexts/files/file_contexts.homedirs.bin: line 1 error due to: Non-ASCII characters found
00:10:19 [2025-03-28T00:10:19.424Z] setfiles: /run/osbuild/tree/ostree/deploy/fedora-coreos/deploy/4bc944162bb563023b70f1c6b76e899eec4910379340de909a78e1e6eda7b20d.0/etc/selinux/targeted/contexts/files/file_contexts.bin: line 1 error due to: Non-ASCII characters found
00:10:19 [2025-03-28T00:10:19.425Z] setfiles: /run/osbuild/tree/ostree/deploy/fedora-coreos/deploy/4bc944162bb563023b70f1c6b76e899eec4910379340de909a78e1e6eda7b20d.0/etc/selinux/targeted/contexts/files/file_contexts.homedirs.bin: line 1 error due to: Non-ASCII characters found
00:10:19 [2025-03-28T00:10:19.425Z]
00:10:19 [2025-03-28T00:10:19.425Z] ��� Duration: 0s
00:10:27 [2025-03-28T00:10:27.489Z] Pipeline raw-image: ba2008156c051aa2df2cf40ed93a7ee3b7525b37ced59c9f6d091d15469fab9e
00:10:27 [2025-03-28T00:10:27.489Z] Build
00:10:27 [2025-03-28T00:10:27.489Z] root: <host>
00:10:27 [2025-03-28T00:10:27.489Z] runner: org.osbuild.fedora38 (org.osbuild.fedora38)
00:10:27 [2025-03-28T00:10:27.489Z] org.osbuild.truncate: 0d6ba9463fba57287bce008e0aed7b18b414d0d0b5c05c9298054f0c30ef83d3 {
00:10:27 [2025-03-28T00:10:27.489Z] "filename": "disk.img",
00:10:27 [2025-03-28T00:10:27.489Z] "size": "2759852032"
00:10:27 [2025-03-28T00:10:27.489Z] }
00:10:27 [2025-03-28T00:10:27.489Z] Failed to open file "/sys/fs/selinux/checkreqprot": Read-only file system
00:10:27 [2025-03-28T00:10:27.489Z]
00:10:27 [2025-03-28T00:10:27.489Z] ��� Duration: 0s
00:10:27 [2025-03-28T00:10:27.489Z] org.osbuild.sfdisk: 987dfd185013aff7b16baf42f21593b5b4eb9a6504194319360bf1ea23c12da6 {
00:10:27 [2025-03-28T00:10:27.489Z] "uuid": "00000000-0000-4000-a000-000000000001",
00:10:27 [2025-03-28T00:10:27.489Z] "label": "gpt",
00:10:27 [2025-03-28T00:10:27.489Z] "partitions": [
00:10:27 [2025-03-28T00:10:27.489Z] {
00:10:27 [2025-03-28T00:10:27.489Z] "start": 2048,
00:10:27 [2025-03-28T00:10:27.489Z] "partnum": 1,
00:10:27 [2025-03-28T00:10:27.489Z] "size": 2048,
00:10:27 [2025-03-28T00:10:27.489Z] "type": "21686148-6449-6E6F-744E-656564454649",
00:10:27 [2025-03-28T00:10:27.489Z] "bootable": true,
00:10:27 [2025-03-28T00:10:27.489Z] "name": "BIOS-BOOT"
00:10:27 [2025-03-28T00:10:27.489Z] },
00:10:27 [2025-03-28T00:10:27.489Z] {
00:10:27 [2025-03-28T00:10:27.489Z] "start": 4096,
00:10:27 [2025-03-28T00:10:27.489Z] "partnum": 2,
00:10:27 [2025-03-28T00:10:27.489Z] "size": 260096,
00:10:27 [2025-03-28T00:10:27.489Z] "type": "C12A7328-F81F-11D2-BA4B-00A0C93EC93B",
00:10:27 [2025-03-28T00:10:27.489Z] "name": "EFI-SYSTEM"
00:10:27 [2025-03-28T00:10:27.489Z] },
00:10:27 [2025-03-28T00:10:27.489Z] {
00:10:27 [2025-03-28T00:10:27.489Z] "start": 264192,
00:10:27 [2025-03-28T00:10:27.489Z] "partnum": 3,
00:10:27 [2025-03-28T00:10:27.489Z] "size": 786432,
00:10:27 [2025-03-28T00:10:27.489Z] "type": "0FC63DAF-8483-4772-8E79-3D69D8477DE4",
00:10:27 [2025-03-28T00:10:27.489Z] "name": "boot"
00:10:27 [2025-03-28T00:10:27.489Z] },
00:10:27 [2025-03-28T00:10:27.489Z] {
00:10:27 [2025-03-28T00:10:27.489Z] "start": 1050624,
00:10:27 [2025-03-28T00:10:27.489Z] "partnum": 4,
00:10:27 [2025-03-28T00:10:27.489Z] "size": 4337664,
00:10:27 [2025-03-28T00:10:27.489Z] "type": "0FC63DAF-8483-4772-8E79-3D69D8477DE4",
00:10:27 [2025-03-28T00:10:27.489Z] "name": "root"
00:10:27 [2025-03-28T00:10:27.489Z] }
00:10:27 [2025-03-28T00:10:27.489Z] ]
00:10:27 [2025-03-28T00:10:27.489Z] }
00:10:27 [2025-03-28T00:10:27.489Z] device/device (org.osbuild.loopback): loop0 acquired (locked: False)
00:10:27 [2025-03-28T00:10:27.489Z] Failed to open file "/sys/fs/selinux/checkreqprot": Read-only file system
00:10:27 [2025-03-28T00:10:27.489Z] label: gpt
00:10:27 [2025-03-28T00:10:27.489Z] label-id: 00000000-0000-4000-a000-000000000001
00:10:27 [2025-03-28T00:10:27.489Z] /dev/loop0p1: start="2048", size="2048", type="21686148-6449-6E6F-744E-656564454649", name="BIOS-BOOT", bootable
00:10:27 [2025-03-28T00:10:27.489Z] /dev/loop0p2: start="4096", size="260096", type="C12A7328-F81F-11D2-BA4B-00A0C93EC93B", name="EFI-SYSTEM"
00:10:27 [2025-03-28T00:10:27.489Z] /dev/loop0p3: start="264192", size="786432", type="0FC63DAF-8483-4772-8E79-3D69D8477DE4", name="boot"
00:10:27 [2025-03-28T00:10:27.489Z] /dev/loop0p4: start="1050624", size="4337664", type="0FC63DAF-8483-4772-8E79-3D69D8477DE4", name="root"
00:10:27 [2025-03-28T00:10:27.489Z] {
00:10:27 [2025-03-28T00:10:27.489Z] "partitiontable": {
00:10:27 [2025-03-28T00:10:27.489Z] "label": "gpt",
00:10:27 [2025-03-28T00:10:27.489Z] "id": "00000000-0000-4000-A000-000000000001",
00:10:27 [2025-03-28T00:10:27.489Z] "device": "/dev/loop0",
00:10:27 [2025-03-28T00:10:27.489Z] "unit": "sectors",
00:10:27 [2025-03-28T00:10:27.489Z] "firstlba": 2048,
00:10:27 [2025-03-28T00:10:27.489Z] "lastlba": 5390302,
00:10:27 [2025-03-28T00:10:27.489Z] "sectorsize": 512,
00:10:27 [2025-03-28T00:10:27.489Z] "partitions": [
00:10:27 [2025-03-28T00:10:27.489Z] {
00:10:27 [2025-03-28T00:10:27.489Z] "node": "/dev/loop0p1",
00:10:27 [2025-03-28T00:10:27.489Z] "start": 2048,
00:10:27 [2025-03-28T00:10:27.489Z] "size": 2048,
00:10:27 [2025-03-28T00:10:27.489Z] "type": "21686148-6449-6E6F-744E-656564454649",
00:10:27 [2025-03-28T00:10:27.489Z] "uuid": "AB83F302-B8DF-4052-B8E4-DFA1E45383D1",
00:10:27 [2025-03-28T00:10:27.489Z] "name": "BIOS-BOOT"
00:10:27 [2025-03-28T00:10:27.489Z] },{
00:10:27 [2025-03-28T00:10:27.489Z] "node": "/dev/loop0p2",
00:10:27 [2025-03-28T00:10:27.489Z] "start": 4096,
00:10:27 [2025-03-28T00:10:27.489Z] "size": 260096,
00:10:27 [2025-03-28T00:10:27.489Z] "type": "C12A7328-F81F-11D2-BA4B-00A0C93EC93B",
00:10:27 [2025-03-28T00:10:27.489Z] "uuid": "2B08B486-C2C0-424D-995E-99835DE072CA",
00:10:27 [2025-03-28T00:10:27.489Z] "name": "EFI-SYSTEM"
00:10:27 [2025-03-28T00:10:27.489Z] },{
00:10:27 [2025-03-28T00:10:27.489Z] "node": "/dev/loop0p3",
00:10:27 [2025-03-28T00:10:27.489Z] "start": 264192,
00:10:27 [2025-03-28T00:10:27.489Z] "size": 786432,
00:10:27 [2025-03-28T00:10:27.489Z] "type": "0FC63DAF-8483-4772-8E79-3D69D8477DE4",
00:10:27 [2025-03-28T00:10:27.489Z] "uuid": "5F55530C-A65F-4F04-AFBC-F0BD429B7AF8",
00:10:27 [2025-03-28T00:10:27.489Z] "name": "boot"
00:10:27 [2025-03-28T00:10:27.489Z] },{
00:10:27 [2025-03-28T00:10:27.489Z] "node": "/dev/loop0p4",
00:10:27 [2025-03-28T00:10:27.489Z] "start": 1050624,
00:10:27 [2025-03-28T00:10:27.489Z] "size": 4337664,
00:10:27 [2025-03-28T00:10:27.489Z] "type": "0FC63DAF-8483-4772-8E79-3D69D8477DE4",
00:10:27 [2025-03-28T00:10:27.489Z] "uuid": "BAD3CBA3-4151-41EA-8742-FF4303B582ED",
00:10:27 [2025-03-28T00:10:27.489Z] "name": "root"
00:10:27 [2025-03-28T00:10:27.489Z] }
00:10:27 [2025-03-28T00:10:27.489Z] ]
00:10:27 [2025-03-28T00:10:27.489Z] }
00:10:27 [2025-03-28T00:10:27.489Z] }
00:10:27 [2025-03-28T00:10:27.489Z]
00:10:27 [2025-03-28T00:10:27.489Z] ��� Duration: 0s
00:10:27 [2025-03-28T00:10:27.489Z] org.osbuild.mkfs.fat: 80336c082cd84284caec00037bb91cbffe05c266013f7c6be6d8f391f294e75e {
00:10:27 [2025-03-28T00:10:27.489Z] "label": "EFI-SYSTEM",
00:10:27 [2025-03-28T00:10:27.489Z] "volid": "7B7795E7"
00:10:27 [2025-03-28T00:10:27.489Z] }
00:10:27 [2025-03-28T00:10:27.489Z] device/device (org.osbuild.loopback): loop0 acquired (locked: True)
00:10:27 [2025-03-28T00:10:27.489Z] Failed to open file "/sys/fs/selinux/checkreqprot": Read-only file system
00:10:27 [2025-03-28T00:10:27.489Z] mkfs.fat 4.2 (2021-01-31)
00:10:27 [2025-03-28T00:10:27.489Z]
00:10:27 [2025-03-28T00:10:27.489Z] ��� Duration: 0s
00:10:27 [2025-03-28T00:10:27.489Z] org.osbuild.mkfs.ext4: 91a410ba752b9e8d4a850f998f75cde741f8bc604ce884b817cbe43a8a25f844 {
00:10:27 [2025-03-28T00:10:27.489Z] "uuid": "96d15588-3596-4b3c-adca-a2ff7279ea63",
00:10:27 [2025-03-28T00:10:27.489Z] "label": "boot",
00:10:27 [2025-03-28T00:10:27.489Z] "metadata_csum_seed": true
00:10:27 [2025-03-28T00:10:27.489Z] }
00:10:27 [2025-03-28T00:10:27.489Z] device/device (org.osbuild.loopback): loop0 acquired (locked: True)
00:10:27 [2025-03-28T00:10:27.489Z] Failed to open file "/sys/fs/selinux/checkreqprot": Read-only file system
00:10:27 [2025-03-28T00:10:27.742Z] mke2fs 1.47.1 (20-May-2024)
00:10:27 [2025-03-28T00:10:27.742Z] Discarding device blocks: 0/393216 done
00:10:27 [2025-03-28T00:10:27.742Z] Creating filesystem with 393216 1k blocks and 98304 inodes
00:10:27 [2025-03-28T00:10:27.742Z] Filesystem UUID: 96d15588-3596-4b3c-adca-a2ff7279ea63
00:10:27 [2025-03-28T00:10:27.742Z] Superblock backups stored on blocks:
00:10:27 [2025-03-28T00:10:27.742Z] 8193, 24577, 40961, 57345, 73729, 204801, 221185
00:10:27 [2025-03-28T00:10:27.742Z]
00:10:27 [2025-03-28T00:10:27.742Z] Allocating group tables: 0/48 done
00:10:27 [2025-03-28T00:10:27.742Z] Writing inode tables: 0/48 done
00:10:27 [2025-03-28T00:10:27.742Z] Creating journal (8192 blocks): done
00:10:27 [2025-03-28T00:10:27.742Z] Writing superblocks and filesystem accounting information: 0/48 done
00:10:27 [2025-03-28T00:10:27.742Z]
00:10:27 [2025-03-28T00:10:27.742Z]
00:10:27 [2025-03-28T00:10:27.742Z] ��� Duration: 0s
00:10:27 [2025-03-28T00:10:27.742Z] org.osbuild.mkfs.xfs: 77d3a16b9db4361126876a323458bfa2c86eafb725a813e2083307d600e1c693 {
00:10:27 [2025-03-28T00:10:27.742Z] "uuid": "910678ff-f77e-4a7d-8d53-86f2ac47a823",
00:10:27 [2025-03-28T00:10:27.742Z] "label": "root"
00:10:27 [2025-03-28T00:10:27.742Z] }
00:10:27 [2025-03-28T00:10:27.742Z] device/device (org.osbuild.loopback): loop0 acquired (locked: True)
00:10:27 [2025-03-28T00:10:27.994Z] Failed to open file "/sys/fs/selinux/checkreqprot": Read-only file system
00:10:27 [2025-03-28T00:10:27.994Z] meta-data=/dev/loop0 isize=512 agcount=4, agsize=135552 blks
00:10:27 [2025-03-28T00:10:27.994Z] = sectsz=512 attr=2, projid32bit=1
00:10:27 [2025-03-28T00:10:27.994Z] = crc=1 finobt=1, sparse=1, rmapbt=1
00:10:27 [2025-03-28T00:10:27.994Z] = reflink=1 bigtime=1 inobtcount=1 nrext64=1
00:10:27 [2025-03-28T00:10:27.994Z] data = bsize=4096 blocks=542208, imaxpct=25
00:10:27 [2025-03-28T00:10:27.994Z] = sunit=0 swidth=0 blks
00:10:27 [2025-03-28T00:10:27.994Z] naming =version 2 bsize=4096 ascii-ci=0, ftype=1
00:10:27 [2025-03-28T00:10:27.994Z] log =internal log bsize=4096 blocks=16384, version=2
00:10:27 [2025-03-28T00:10:27.994Z] = sectsz=512 sunit=0 blks, lazy-count=1
00:10:27 [2025-03-28T00:10:27.994Z] realtime =none extsz=4096 blocks=0, rtextents=0
00:10:27 [2025-03-28T00:10:27.994Z] Discarding blocks...Done.
00:10:28 [2025-03-28T00:10:28.247Z]
00:10:28 [2025-03-28T00:10:28.247Z] ��� Duration: 0s
00:10:28 [2025-03-28T00:10:28.247Z] org.osbuild.mkdir: d0aef9aa32f8c92eef63286f5266f54fda62f3f7bdf7bee480626efc5ccbb935 {
00:10:28 [2025-03-28T00:10:28.247Z] "paths": [
00:10:28 [2025-03-28T00:10:28.247Z] {
00:10:28 [2025-03-28T00:10:28.247Z] "path": "mount://root/boot",
00:10:28 [2025-03-28T00:10:28.247Z] "mode": 493
00:10:28 [2025-03-28T00:10:28.247Z] },
00:10:28 [2025-03-28T00:10:28.247Z] {
00:10:28 [2025-03-28T00:10:28.247Z] "path": "mount://boot/efi",
00:10:28 [2025-03-28T00:10:28.247Z] "mode": 493
00:10:28 [2025-03-28T00:10:28.247Z] }
00:10:28 [2025-03-28T00:10:28.247Z] ]
00:10:28 [2025-03-28T00:10:28.247Z] }
00:10:28 [2025-03-28T00:10:28.247Z] device/disk (org.osbuild.loopback): loop0 acquired (locked: False)
00:10:28 [2025-03-28T00:10:28.247Z] mount/root (org.osbuild.xfs): mounting /dev/loop0p4 -> /home/jenkins/agent/workspace/test-override/cache/osbuild/store/tmp/buildroot-tmp-9yonjrsj/mounts/root-mount-point
00:10:28 [2025-03-28T00:10:28.505Z] mount/boot (org.osbuild.ext4): mounting /dev/loop0p3 -> /home/jenkins/agent/workspace/test-override/cache/osbuild/store/tmp/buildroot-tmp-9yonjrsj/mounts/boot-mount-point
00:10:28 [2025-03-28T00:10:28.505Z] [33m
00:10:28 [2025-03-28T00:10:28.505Z] Filesystem Size Used Avail Use% Mounted on
00:10:28 [2025-03-28T00:10:28.505Z] /dev/vdb1 40G 6.9G 34G 18% /home/jenkins/agent/workspace/test-override/cache
00:10:28 [2025-03-28T00:10:28.505Z] [39m
00:10:28 [2025-03-28T00:10:28.505Z] Failed to open file "/sys/fs/selinux/checkreqprot": Read-only file system
00:10:28 [2025-03-28T00:10:28.757Z] mount/boot (org.osbuild.ext4): umount: /home/jenkins/agent/workspace/test-override/cache/osbuild/store/tmp/buildroot-tmp-9yonjrsj/mounts/boot-mount-point unmounted
00:10:28 [2025-03-28T00:10:28.757Z] mount/root (org.osbuild.xfs): umount: /home/jenkins/agent/workspace/test-override/cache/osbuild/store/tmp/buildroot-tmp-9yonjrsj/mounts/root-mount-point unmounted
00:10:28 [2025-03-28T00:10:28.757Z]
00:10:28 [2025-03-28T00:10:28.757Z] ��� Duration: 0s
00:10:28 [2025-03-28T00:10:28.757Z] org.osbuild.selinux: 716e641d7a5818d2ad23c29d6bbd21f13b9f744db832fa7e9df679de1f1d1342 {
00:10:28 [2025-03-28T00:10:28.757Z] "file_contexts": "input://tree/etc/selinux/targeted/contexts/files/file_contexts",
00:10:28 [2025-03-28T00:10:28.757Z] "target": "mount://root/"
00:10:28 [2025-03-28T00:10:28.757Z] }
00:10:29 [2025-03-28T00:10:29.010Z] device/disk (org.osbuild.loopback): loop0 acquired (locked: False)
00:10:29 [2025-03-28T00:10:29.010Z] mount/root (org.osbuild.xfs): mounting /dev/loop0p4 -> /home/jenkins/agent/workspace/test-override/cache/osbuild/store/tmp/buildroot-tmp-j9i78u2v/mounts/
00:10:29 [2025-03-28T00:10:29.263Z] Failed to open file "/sys/fs/selinux/checkreqprot": Read-only file system
00:10:29 [2025-03-28T00:10:29.263Z] setfiles: /run/osbuild/inputs/tree/etc/selinux/targeted/contexts/files/file_contexts.bin: line 1 error due to: Non-ASCII characters found
00:10:29 [2025-03-28T00:10:29.263Z] setfiles: /run/osbuild/inputs/tree/etc/selinux/targeted/contexts/files/file_contexts.homedirs.bin: line 1 error due to: Non-ASCII characters found
00:10:29 [2025-03-28T00:10:29.531Z] mount/root (org.osbuild.xfs): umount: /home/jenkins/agent/workspace/test-override/cache/osbuild/store/tmp/buildroot-tmp-j9i78u2v/mounts/ unmounted
00:10:29 [2025-03-28T00:10:29.531Z]
00:10:29 [2025-03-28T00:10:29.531Z] ��� Duration: 0s
00:10:29 [2025-03-28T00:10:29.531Z] org.osbuild.selinux: 50ead09c89e52696fe6438facf888d4f8505d4efc8d81a13b973c5f4cee58623 {
00:10:29 [2025-03-28T00:10:29.531Z] "file_contexts": "input://tree/etc/selinux/targeted/contexts/files/file_contexts",
00:10:29 [2025-03-28T00:10:29.531Z] "target": "mount://root/boot/"
00:10:29 [2025-03-28T00:10:29.531Z] }
00:10:29 [2025-03-28T00:10:29.531Z] device/disk (org.osbuild.loopback): loop0 acquired (locked: False)
00:10:29 [2025-03-28T00:10:29.784Z] mount/root (org.osbuild.xfs): mounting /dev/loop0p4 -> /home/jenkins/agent/workspace/test-override/cache/osbuild/store/tmp/buildroot-tmp-v4zxnehr/mounts/
00:10:29 [2025-03-28T00:10:29.784Z] mount/boot (org.osbuild.ext4): mounting /dev/loop0p3 -> /home/jenkins/agent/workspace/test-override/cache/osbuild/store/tmp/buildroot-tmp-v4zxnehr/mounts/boot
00:10:30 [2025-03-28T00:10:30.038Z] Failed to open file "/sys/fs/selinux/checkreqprot": Read-only file system
00:10:30 [2025-03-28T00:10:30.038Z] setfiles: /run/osbuild/inputs/tree/etc/selinux/targeted/contexts/files/file_contexts.bin: line 1 error due to: Non-ASCII characters found
00:10:30 [2025-03-28T00:10:30.038Z] setfiles: /run/osbuild/inputs/tree/etc/selinux/targeted/contexts/files/file_contexts.homedirs.bin: line 1 error due to: Non-ASCII characters found
00:10:30 [2025-03-28T00:10:30.038Z] mount/boot (org.osbuild.ext4): umount: /home/jenkins/agent/workspace/test-override/cache/osbuild/store/tmp/buildroot-tmp-v4zxnehr/mounts/boot unmounted
00:10:30 [2025-03-28T00:10:30.038Z] mount/root (org.osbuild.xfs): umount: /home/jenkins/agent/workspace/test-override/cache/osbuild/store/tmp/buildroot-tmp-v4zxnehr/mounts/ unmounted
00:10:30 [2025-03-28T00:10:30.290Z]
00:10:30 [2025-03-28T00:10:30.290Z] ��� Duration: 0s
00:10:30 [2025-03-28T00:10:30.290Z] org.osbuild.copy: a4926f92ae1c0978b1a018ac2bff8a41f4b630edd6edb661fa52df3484b6ccdf {
00:10:30 [2025-03-28T00:10:30.290Z] "paths": [
00:10:30 [2025-03-28T00:10:30.290Z] {
00:10:30 [2025-03-28T00:10:30.290Z] "from": "input://tree/",
00:10:30 [2025-03-28T00:10:30.290Z] "to": "mount://root/"
00:10:30 [2025-03-28T00:10:30.290Z] }
00:10:30 [2025-03-28T00:10:30.290Z] ]
00:10:30 [2025-03-28T00:10:30.290Z] }
00:10:30 [2025-03-28T00:10:30.290Z] device/disk (org.osbuild.loopback): loop0 acquired (locked: False)
00:10:30 [2025-03-28T00:10:30.543Z] mount/root (org.osbuild.xfs): mounting /dev/loop0p4 -> /home/jenkins/agent/workspace/test-override/cache/osbuild/store/tmp/buildroot-tmp-2gu9skg3/mounts/
00:10:30 [2025-03-28T00:10:30.543Z] mount/boot (org.osbuild.ext4): mounting /dev/loop0p3 -> /home/jenkins/agent/workspace/test-override/cache/osbuild/store/tmp/buildroot-tmp-2gu9skg3/mounts/boot
00:10:30 [2025-03-28T00:10:30.543Z] mount/efi (org.osbuild.fat): mounting /dev/loop0p2 -> /home/jenkins/agent/workspace/test-override/cache/osbuild/store/tmp/buildroot-tmp-2gu9skg3/mounts/boot/efi
00:10:30 [2025-03-28T00:10:30.796Z] Failed to open file "/sys/fs/selinux/checkreqprot": Read-only file system
00:10:30 [2025-03-28T00:10:30.796Z] copying '/run/osbuild/inputs/tree/.' -> '/run/osbuild/mounts/.'
00:10:38 [2025-03-28T00:10:38.884Z] [33m
00:10:38 [2025-03-28T00:10:38.884Z] Filesystem Size Used Avail Use% Mounted on
00:10:38 [2025-03-28T00:10:38.884Z] /dev/vdb1 40G 8.4G 32G 21% /home/jenkins/agent/workspace/test-override/cache
00:10:38 [2025-03-28T00:10:38.884Z] [39m
00:10:42 [2025-03-28T00:10:42.142Z] mount/efi (org.osbuild.fat): umount: /home/jenkins/agent/workspace/test-override/cache/osbuild/store/tmp/buildroot-tmp-2gu9skg3/mounts/boot/efi unmounted
00:10:42 [2025-03-28T00:10:42.142Z] mount/boot (org.osbuild.ext4): umount: /home/jenkins/agent/workspace/test-override/cache/osbuild/store/tmp/buildroot-tmp-2gu9skg3/mounts/boot unmounted
00:10:42 [2025-03-28T00:10:42.698Z] mount/root (org.osbuild.xfs): umount: /home/jenkins/agent/workspace/test-override/cache/osbuild/store/tmp/buildroot-tmp-2gu9skg3/mounts/ unmounted
00:10:42 [2025-03-28T00:10:42.698Z]
00:10:42 [2025-03-28T00:10:42.698Z] ��� Duration: 12s
00:10:42 [2025-03-28T00:10:42.698Z] org.osbuild.bootupd: 19524152c34bc0e8a244eb1c62821f418ca4d6f708a2426478bba2908a0ed635 {
00:10:42 [2025-03-28T00:10:42.698Z] "bios": {
00:10:42 [2025-03-28T00:10:42.698Z] "device": "disk"
00:10:42 [2025-03-28T00:10:42.698Z] },
00:10:42 [2025-03-28T00:10:42.698Z] "static-configs": true,
00:10:42 [2025-03-28T00:10:42.698Z] "deployment": {
00:10:42 [2025-03-28T00:10:42.698Z] "default": true
00:10:42 [2025-03-28T00:10:42.698Z] }
00:10:42 [2025-03-28T00:10:42.698Z] }
00:10:42 [2025-03-28T00:10:42.951Z] device/disk (org.osbuild.loopback): loop0 acquired (locked: False)
00:10:42 [2025-03-28T00:10:42.951Z] mount/root (org.osbuild.xfs): mounting /dev/loop0p4 -> /home/jenkins/agent/workspace/test-override/cache/osbuild/store/tmp/buildroot-tmp-5y7kg1k5/mounts/
00:10:43 [2025-03-28T00:10:43.205Z] mount/boot (org.osbuild.ext4): mounting /dev/loop0p3 -> /home/jenkins/agent/workspace/test-override/cache/osbuild/store/tmp/buildroot-tmp-5y7kg1k5/mounts/boot
00:10:43 [2025-03-28T00:10:43.205Z] mount/efi (org.osbuild.fat): mounting /dev/loop0p2 -> /home/jenkins/agent/workspace/test-override/cache/osbuild/store/tmp/buildroot-tmp-5y7kg1k5/mounts/boot/efi
00:10:43 [2025-03-28T00:10:43.458Z] Failed to open file "/sys/fs/selinux/checkreqprot": Read-only file system
00:10:44 [2025-03-28T00:10:44.827Z] Installed 05_ignition.cfg
00:10:44 [2025-03-28T00:10:44.827Z] Installed: grub.cfg
00:10:44 [2025-03-28T00:10:44.827Z] Installed: "fedora/grub.cfg"
00:10:44 [2025-03-28T00:10:44.827Z] mount/efi (org.osbuild.fat): umount: /home/jenkins/agent/workspace/test-override/cache/osbuild/store/tmp/buildroot-tmp-5y7kg1k5/mounts/boot/efi unmounted
00:10:44 [2025-03-28T00:10:44.827Z] mount/boot (org.osbuild.ext4): umount: /home/jenkins/agent/workspace/test-override/cache/osbuild/store/tmp/buildroot-tmp-5y7kg1k5/mounts/boot unmounted
00:10:44 [2025-03-28T00:10:44.827Z] mount/root (org.osbuild.xfs): umount: /home/jenkins/agent/workspace/test-override/cache/osbuild/store/tmp/buildroot-tmp-5y7kg1k5/mounts/ unmounted
00:10:44 [2025-03-28T00:10:44.827Z]
00:10:44 [2025-03-28T00:10:44.827Z] ��� Duration: 2s
00:10:44 [2025-03-28T00:10:44.827Z] org.osbuild.chattr: ba2008156c051aa2df2cf40ed93a7ee3b7525b37ced59c9f6d091d15469fab9e {
00:10:44 [2025-03-28T00:10:44.827Z] "items": {
00:10:44 [2025-03-28T00:10:44.827Z] "mount://root/": {
00:10:44 [2025-03-28T00:10:44.827Z] "immutable": true
00:10:44 [2025-03-28T00:10:44.827Z] }
00:10:44 [2025-03-28T00:10:44.827Z] }
00:10:44 [2025-03-28T00:10:44.827Z] }
00:10:45 [2025-03-28T00:10:45.080Z] device/disk (org.osbuild.loopback): loop0 acquired (locked: False)
00:10:45 [2025-03-28T00:10:45.080Z] mount/root (org.osbuild.xfs): mounting /dev/loop0p4 -> /home/jenkins/agent/workspace/test-override/cache/osbuild/store/tmp/buildroot-tmp-iyhtwhp1/mounts/
00:10:45 [2025-03-28T00:10:45.080Z] mount/ostree.deployment (org.osbuild.ostree.deployment): Deployment root at 'ostree/deploy/fedora-coreos/deploy/4bc944162bb563023b70f1c6b76e899eec4910379340de909a78e1e6eda7b20d.0'
00:10:45 [2025-03-28T00:10:45.336Z] Failed to open file "/sys/fs/selinux/checkreqprot": Read-only file system
00:10:45 [2025-03-28T00:10:45.336Z] mount/ostree.deployment (org.osbuild.ostree.deployment): mounting /home/jenkins/agent/workspace/test-override/cache/osbuild/store/tmp/buildroot-tmp-iyhtwhp1/mounts/ostree/deploy/fedora-coreos/deploy/4bc944162bb563023b70f1c6b76e899eec4910379340de909a78e1e6eda7b20d.0 -> /home/jenkins/agent/workspace/test-override/cache/osbuild/store/tmp/buildroot-tmp-iyhtwhp1/mounts
00:10:45 [2025-03-28T00:10:45.336Z] mount/ostree.deployment (org.osbuild.ostree.deployment): umount: /home/jenkins/agent/workspace/test-override/cache/osbuild/store/tmp/buildroot-tmp-iyhtwhp1/mounts/boot unmounted
00:10:45 [2025-03-28T00:10:45.589Z] mount/ostree.deployment (org.osbuild.ostree.deployment): umount: /home/jenkins/agent/workspace/test-override/cache/osbuild/store/tmp/buildroot-tmp-iyhtwhp1/mounts/var unmounted
00:10:45 [2025-03-28T00:10:45.589Z] mount/ostree.deployment (org.osbuild.ostree.deployment): umount: /home/jenkins/agent/workspace/test-override/cache/osbuild/store/tmp/buildroot-tmp-iyhtwhp1/mounts/sysroot unmounted
00:10:45 [2025-03-28T00:10:45.589Z] mount/ostree.deployment (org.osbuild.ostree.deployment): umount: /home/jenkins/agent/workspace/test-override/cache/osbuild/store/tmp/buildroot-tmp-iyhtwhp1/mounts unmounted
00:10:45 [2025-03-28T00:10:45.589Z] mount/ostree.deployment (org.osbuild.ostree.deployment): umount: /home/jenkins/agent/workspace/test-override/cache/osbuild/store/tmp/buildroot-tmp-iyhtwhp1/mounts unmounted
00:10:45 [2025-03-28T00:10:45.589Z] mount/ostree.deployment (org.osbuild.ostree.deployment): umount: /home/jenkins/agent/workspace/test-override/cache/osbuild/store/tmp/buildroot-tmp-iyhtwhp1/mounts unmounted
00:10:45 [2025-03-28T00:10:45.589Z] mount/root (org.osbuild.xfs): already unmounted: /home/jenkins/agent/workspace/test-override/cache/osbuild/store/tmp/buildroot-tmp-iyhtwhp1/mounts/
00:10:45 [2025-03-28T00:10:45.589Z]
00:10:45 [2025-03-28T00:10:45.589Z] ��� Duration: 0s
00:10:45 [2025-03-28T00:10:45.589Z] Pipeline raw-qemu-image: 2dd3108e044ee3fef64da6263c8269352316b13c11656055a508e4034968c8ea
00:10:45 [2025-03-28T00:10:45.589Z] Build
00:10:45 [2025-03-28T00:10:45.589Z] root: <host>
00:10:45 [2025-03-28T00:10:45.589Z] runner: org.osbuild.fedora38 (org.osbuild.fedora38)
00:10:45 [2025-03-28T00:10:45.589Z] org.osbuild.copy: a61c492a15c6f62c026528a6511b7e30caf0385357e336dcd9f0100c8dc718e7 {
00:10:45 [2025-03-28T00:10:45.589Z] "paths": [
00:10:45 [2025-03-28T00:10:45.589Z] {
00:10:45 [2025-03-28T00:10:45.589Z] "from": "input://tree/disk.img",
00:10:45 [2025-03-28T00:10:45.589Z] "to": "tree:///disk.img"
00:10:45 [2025-03-28T00:10:45.589Z] }
00:10:45 [2025-03-28T00:10:45.589Z] ]
00:10:45 [2025-03-28T00:10:45.589Z] }
00:10:45 [2025-03-28T00:10:45.842Z] Failed to open file "/sys/fs/selinux/checkreqprot": Read-only file system
00:10:45 [2025-03-28T00:10:45.842Z] copying '/run/osbuild/inputs/tree/disk.img' -> '/run/osbuild/tree/disk.img'
00:10:46 [2025-03-28T00:10:46.095Z]
00:10:46 [2025-03-28T00:10:46.095Z] ��� Duration: 0s
00:10:46 [2025-03-28T00:10:46.095Z] org.osbuild.truncate: b26ec09259998b56aff1702603bd675b0cb544aa2816179072807a6b18f8ef77 {
00:10:46 [2025-03-28T00:10:46.095Z] "filename": "disk.img",
00:10:46 [2025-03-28T00:10:46.095Z] "size": "10737418240"
00:10:46 [2025-03-28T00:10:46.095Z] }
00:10:46 [2025-03-28T00:10:46.095Z] Failed to open file "/sys/fs/selinux/checkreqprot": Read-only file system
00:10:46 [2025-03-28T00:10:46.348Z]
00:10:46 [2025-03-28T00:10:46.348Z] ��� Duration: 0s
00:10:46 [2025-03-28T00:10:46.348Z] org.osbuild.coreos.platform: 2dd3108e044ee3fef64da6263c8269352316b13c11656055a508e4034968c8ea {
00:10:46 [2025-03-28T00:10:46.348Z] "platform": "qemu"
00:10:46 [2025-03-28T00:10:46.348Z] }
00:10:46 [2025-03-28T00:10:46.348Z] device/disk (org.osbuild.loopback): loop0 acquired (locked: False)
00:10:46 [2025-03-28T00:10:46.601Z] mount/root (org.osbuild.xfs): mounting /dev/loop0p4 -> /home/jenkins/agent/workspace/test-override/cache/osbuild/store/tmp/buildroot-tmp-e9sfh0qs/mounts/
00:10:46 [2025-03-28T00:10:46.601Z] mount/ostree.deployment (org.osbuild.ostree.deployment): Deployment root at 'ostree/deploy/fedora-coreos/deploy/4bc944162bb563023b70f1c6b76e899eec4910379340de909a78e1e6eda7b20d.0'
00:10:46 [2025-03-28T00:10:46.854Z] mount/boot (org.osbuild.ext4): mounting /dev/loop0p3 -> /home/jenkins/agent/workspace/test-override/cache/osbuild/store/tmp/buildroot-tmp-e9sfh0qs/mounts/boot
00:10:46 [2025-03-28T00:10:46.854Z] Failed to open file "/sys/fs/selinux/checkreqprot": Read-only file system
00:10:47 [2025-03-28T00:10:47.107Z] mount/boot (org.osbuild.ext4): umount: /home/jenkins/agent/workspace/test-override/cache/osbuild/store/tmp/buildroot-tmp-e9sfh0qs/mounts/boot unmounted
00:10:47 [2025-03-28T00:10:47.107Z] mount/ostree.deployment (org.osbuild.ostree.deployment): mounting /home/jenkins/agent/workspace/test-override/cache/osbuild/store/tmp/buildroot-tmp-e9sfh0qs/mounts/ostree/deploy/fedora-coreos/deploy/4bc944162bb563023b70f1c6b76e899eec4910379340de909a78e1e6eda7b20d.0 -> /home/jenkins/agent/workspace/test-override/cache/osbuild/store/tmp/buildroot-tmp-e9sfh0qs/mounts
00:10:47 [2025-03-28T00:10:47.107Z] mount/ostree.deployment (org.osbuild.ostree.deployment): umount: /home/jenkins/agent/workspace/test-override/cache/osbuild/store/tmp/buildroot-tmp-e9sfh0qs/mounts/boot unmounted
00:10:47 [2025-03-28T00:10:47.107Z] mount/ostree.deployment (org.osbuild.ostree.deployment): umount: /home/jenkins/agent/workspace/test-override/cache/osbuild/store/tmp/buildroot-tmp-e9sfh0qs/mounts/var unmounted
00:10:47 [2025-03-28T00:10:47.107Z] mount/ostree.deployment (org.osbuild.ostree.deployment): umount: /home/jenkins/agent/workspace/test-override/cache/osbuild/store/tmp/buildroot-tmp-e9sfh0qs/mounts/sysroot unmounted
00:10:47 [2025-03-28T00:10:47.107Z] mount/ostree.deployment (org.osbuild.ostree.deployment): umount: /home/jenkins/agent/workspace/test-override/cache/osbuild/store/tmp/buildroot-tmp-e9sfh0qs/mounts unmounted
00:10:47 [2025-03-28T00:10:47.107Z] mount/ostree.deployment (org.osbuild.ostree.deployment): umount: /home/jenkins/agent/workspace/test-override/cache/osbuild/store/tmp/buildroot-tmp-e9sfh0qs/mounts unmounted
00:10:47 [2025-03-28T00:10:47.107Z] mount/ostree.deployment (org.osbuild.ostree.deployment): umount: /home/jenkins/agent/workspace/test-override/cache/osbuild/store/tmp/buildroot-tmp-e9sfh0qs/mounts unmounted
00:10:47 [2025-03-28T00:10:47.107Z] mount/root (org.osbuild.xfs): already unmounted: /home/jenkins/agent/workspace/test-override/cache/osbuild/store/tmp/buildroot-tmp-e9sfh0qs/mounts/
00:10:47 [2025-03-28T00:10:47.107Z]
00:10:47 [2025-03-28T00:10:47.107Z] ��� Duration: 0s
00:10:47 [2025-03-28T00:10:47.107Z] Pipeline qemu: 005a573ec6e231947ae6fa45eefe7addf3abc0d601758e8bf2a196f6d6a8f95b
00:10:47 [2025-03-28T00:10:47.107Z] Build
00:10:47 [2025-03-28T00:10:47.107Z] root: <host>
00:10:47 [2025-03-28T00:10:47.107Z] runner: org.osbuild.fedora38 (org.osbuild.fedora38)
00:10:47 [2025-03-28T00:10:47.107Z] org.osbuild.qemu: 005a573ec6e231947ae6fa45eefe7addf3abc0d601758e8bf2a196f6d6a8f95b {
00:10:47 [2025-03-28T00:10:47.107Z] "filename": "fedora-coreos-43.20250327.dev.0-qemu.x86_64.qcow2",
00:10:47 [2025-03-28T00:10:47.107Z] "format": {
00:10:47 [2025-03-28T00:10:47.107Z] "type": "qcow2",
00:10:47 [2025-03-28T00:10:47.107Z] "compression": false,
00:10:47 [2025-03-28T00:10:47.107Z] "compat": "1.1"
00:10:47 [2025-03-28T00:10:47.107Z] }
00:10:47 [2025-03-28T00:10:47.107Z] }
00:10:47 [2025-03-28T00:10:47.360Z] Failed to open file "/sys/fs/selinux/checkreqprot": Read-only file system
00:10:49 [2025-03-28T00:10:49.251Z] [33m
00:10:49 [2025-03-28T00:10:49.251Z] Filesystem Size Used Avail Use% Mounted on
00:10:49 [2025-03-28T00:10:49.251Z] /dev/vdb1 40G 9.3G 31G 24% /home/jenkins/agent/workspace/test-override/cache
00:10:49 [2025-03-28T00:10:49.251Z] [39m
00:10:51 [2025-03-28T00:10:51.126Z]
00:10:51 [2025-03-28T00:10:51.126Z] ��� Duration: 3s
00:10:51 [2025-03-28T00:10:51.126Z] manifest /tmp/osbuild-FCLH.json finished successfully
00:10:59 [2025-03-28T00:10:59.194Z] oci-archive: 94635573eec34b92fce6810776f9b6ae9a191fd9182ae9ee4e23d0771988f7a9
00:10:59 [2025-03-28T00:10:59.194Z] deployed-tree: e142dd00e9e89f8de697bfb89b69e665ebeb615fa55632943187bc180945f194
00:10:59 [2025-03-28T00:10:59.194Z] tree: 7df576b0dccd6df33a7b9b98ee4eb54b1049194b444048bd824925a666079b75
00:10:59 [2025-03-28T00:10:59.194Z] raw-image: ba2008156c051aa2df2cf40ed93a7ee3b7525b37ced59c9f6d091d15469fab9e
00:10:59 [2025-03-28T00:10:59.194Z] raw-4k-image: 4212ae9ce1758666b16efe35d92e4eaf460fc37ff44147359ea739ea111502a9
00:10:59 [2025-03-28T00:10:59.194Z] raw-aliyun-image: f696519c3c804ba73eabd32790a5497bb8b51c9b3ffc113c2bd98928e0ae7d5a
00:10:59 [2025-03-28T00:10:59.194Z] aliyun: 6e9dace22d8389ee8a0e8459cc4718c450e3d11620916080a2a2ae1d139bb7b9
00:10:59 [2025-03-28T00:10:59.194Z] raw-applehv-image: 50f70120d34d6846d421369d62b635fc3a052df81c43983bb662aed5f59dd681
00:10:59 [2025-03-28T00:10:59.194Z] applehv: 0a0095f09b9354ee07e3a6fb4adcdd17ad4b4d9445360ed2823c24fc7765cbe5
00:10:59 [2025-03-28T00:10:59.194Z] raw-aws-image: 30781738aa35453960d930d20cf9633f71ef9bcf25a86c4754f6e7ea457437b6
00:10:59 [2025-03-28T00:10:59.194Z] aws: b9eefd2baf84882c5ad5b8099afce5042e13fb2290cd7ea8b7ee527bc1b7cc03
00:10:59 [2025-03-28T00:10:59.194Z] raw-azure-image: 52c9e31dbb35ab422ed1111a80f86991a82b6e523f3ca0dc2bae6dccf7084a85
00:10:59 [2025-03-28T00:10:59.194Z] azure: fc0854a8955eff3ba117978a7c1f294f810b0a49a4b70e98444e91f27b19ce58
00:10:59 [2025-03-28T00:10:59.194Z] raw-azurestack-image: 9abad572af0cb69cbfd43e8305459f5c6899ee8ecca9641db9f60642b8630a46
00:10:59 [2025-03-28T00:10:59.194Z] azurestack: eb1d40a3717e74b55f560a3b96fb6465d3ceeb0898e9e00035dccf3b922f247a
00:10:59 [2025-03-28T00:10:59.194Z] raw-digitalocean-image: 3483ca88199fea2ebbe35090196079ddda46e3316efd3781f386363b32953409
00:10:59 [2025-03-28T00:10:59.194Z] digitalocean: eb804c5925f85aa94f70e49791a6c49a67b591069ab6dda03dd19d7452fedb44
00:10:59 [2025-03-28T00:10:59.194Z] raw-exoscale-image: 435cb133d5bf29024485ad853621b5cf2553dd65a3251d56388f5ced14a69bdd
00:10:59 [2025-03-28T00:10:59.194Z] exoscale: 6d9abcd87461386bc6420b79f76c2b06b800774b2051c6bb8431cc17030f1d20
00:10:59 [2025-03-28T00:10:59.194Z] raw-gcp-image: ac1251b4bac602401e0663ca51a72eb829003ed33b3ed708beba7cefe9f64f30
00:10:59 [2025-03-28T00:10:59.194Z] raw-gcp-image-tar: 932a380f5ac299fc592bb3e7eb3435ecfdcdc36e8c1083ca12fa0795920ea2d2
00:10:59 [2025-03-28T00:10:59.194Z] gcp: 47f9428200ec5a7af2d186c861cdbc4b107069d0b2fb7b11ed7b15cd1a17fb5d
00:10:59 [2025-03-28T00:10:59.194Z] raw-hetzner-image: 4d693edb37d4821fb858cd24fec0421cc8a53230786ef548e857d47e072611f5
00:10:59 [2025-03-28T00:10:59.194Z] hetzner: e9e699bfa5494dbaa35d4106b9ba50e9334fb656891701bf115dbb667138f606
00:10:59 [2025-03-28T00:10:59.195Z] raw-hyperv-image: 0a2f274e060f45be2e4fddb1bf8b2f09046192e34d477c2717e1c0dd03bea6a2
00:10:59 [2025-03-28T00:10:59.195Z] hyperv: fc320a175509bb04e8052a9b8d9e88a241bcf6bbefc8b49229dd3e7651b206e6
00:10:59 [2025-03-28T00:10:59.195Z] raw-ibmcloud-image: 0d9cf6387f2dd634baeb1e4816c805b9a113bf51057703b8a7e338884dc87350
00:10:59 [2025-03-28T00:10:59.195Z] ibmcloud: 9b326a391fbc8fb4d798b60f20f4a9eaf296987677fd76c999e8451873302f49
00:10:59 [2025-03-28T00:10:59.195Z] raw-kubevirt-image: f29f0471196a36eeb800e780da9f5494242d854b013ac20f103ba181534e5e13
00:10:59 [2025-03-28T00:10:59.195Z] qemu-kubevirt-image: c61ec07e18d1e8eaaf4761d4067750871d03d0d2d5a4f145038a2457c8567612
00:10:59 [2025-03-28T00:10:59.195Z] kubevirt: 60894c2500950df5cf1387b81b4a53ec15a51a90ca27e8bfbf3ca1aeebcbb109
00:10:59 [2025-03-28T00:10:59.195Z] raw-openstack-image: 439c070b720ec03897f3c5ff728a4b5bc1be40ae2554146165ef2a8a1f366633
00:10:59 [2025-03-28T00:10:59.195Z] openstack: cbb4341b46f9ca5f57dc049c0019996cbfc2ba494a0cb0fe34d419dfda42bd2e
00:10:59 [2025-03-28T00:10:59.195Z] raw-metal-image: a73a2f4cc3e864aba56f3bb9efe11dbc6832725e3e21ef3f79d605111fff0b8a
00:10:59 [2025-03-28T00:10:59.195Z] metal: 947393d792b431c7108459d601d47d3479a133b9d9cbfa40f4c1c80807f503ae
00:10:59 [2025-03-28T00:10:59.195Z] raw-metal4k-image: 31e1ecb5c919624ff5d0e9237d7647dbe780e04d498ddf2ccf0c267dd7898d4f
00:10:59 [2025-03-28T00:10:59.195Z] metal4k: e70d482c8137944fa2b2a5f495e3d734901a83b7595cb239c6add3b19edfbd5a
00:10:59 [2025-03-28T00:10:59.195Z] raw-nutanix-image: c12fc6b8c98a2e98e795f11fb723730092cba54671d551e5b3c4431c57699361
00:10:59 [2025-03-28T00:10:59.195Z] nutanix: 80d2ad38a90f146eee8f3320b4c70f58f6be725162a5f1fcab68efadf778cc45
00:10:59 [2025-03-28T00:10:59.195Z] raw-qemu-image: 2dd3108e044ee3fef64da6263c8269352316b13c11656055a508e4034968c8ea
00:10:59 [2025-03-28T00:10:59.195Z] qemu: 005a573ec6e231947ae6fa45eefe7addf3abc0d601758e8bf2a196f6d6a8f95b
00:10:59 [2025-03-28T00:10:59.195Z] raw-vultr-image: 84966d6cec0b4808a6bce558b1d348d9b6a4b56b8a7c52237fe6d154edb3e00d
00:10:59 [2025-03-28T00:10:59.195Z] vultr: a9082e408faa09f89558821918faf79fad95d81592cc74bf03c0ce4c72211d26
00:10:59 [2025-03-28T00:10:59.195Z] live: e89888d91717ccc8b5d230056ab16ddccbaa7cda6dd7b27bb6c9172821f99390
00:10:59 [2025-03-28T00:10:59.195Z] + set +x
00:11:03 [2025-03-28T00:11:03.338Z] mv: failed to preserve ownership for '/home/jenkins/agent/workspace/test-override/tmp/build.qemu/tmp.24xUOs0dtU/qemu/fedora-coreos-43.20250327.dev.0-qemu.x86_64.qcow2': Operation not permitted
00:11:03 [2025-03-28T00:11:03.338Z] mv: failed to preserve ownership for '/home/jenkins/agent/workspace/test-override/tmp/build.qemu/tmp.24xUOs0dtU/qemu': Operation not permitted
00:11:35 [2025-03-28T00:11:35.328Z] /home/jenkins/agent/workspace/test-override/builds/43.20250327.dev.0/x86_64/meta.json wrote with version stamp 1743120692124481359
00:11:35 [2025-03-28T00:11:35.328Z] Successfully generated: fedora-coreos-43.20250327.dev.0-qemu.x86_64.qcow2
00:11:35 [2025-03-28T00:11:35.345Z] [Pipeline] }
00:11:35 [2025-03-28T00:11:35.348Z] [Pipeline] // withEnv
00:11:35 [2025-03-28T00:11:35.403Z] [Pipeline] }
00:11:35 [2025-03-28T00:11:35.431Z] [Pipeline] // stage
00:11:35 [2025-03-28T00:11:35.529Z] [Pipeline] withEnv
00:11:35 [2025-03-28T00:11:35.529Z] [Pipeline] {
00:11:35 [2025-03-28T00:11:35.533Z] [Pipeline] sh
00:11:36 [2025-03-28T00:11:36.123Z] + set -euo pipefail
00:11:36 [2025-03-28T00:11:36.123Z] ++ umask
00:11:36 [2025-03-28T00:11:36.123Z] + '[' 0022 = 0000 ']'
00:11:36 [2025-03-28T00:11:36.123Z] + uuidgen
00:11:36 [2025-03-28T00:11:36.123Z] + cut -f1 -d-
00:11:36 [2025-03-28T00:11:36.126Z] [Pipeline] }
00:11:36 [2025-03-28T00:11:36.129Z] [Pipeline] // withEnv
00:11:36 [2025-03-28T00:11:36.133Z] [Pipeline] withEnv
00:11:36 [2025-03-28T00:11:36.134Z] [Pipeline] {
00:11:36 [2025-03-28T00:11:36.140Z] [Pipeline] sh
00:11:36 [2025-03-28T00:11:36.673Z] + set -euo pipefail
00:11:36 [2025-03-28T00:11:36.673Z] ++ umask
00:11:36 [2025-03-28T00:11:36.673Z] + '[' 0022 = 0000 ']'
00:11:36 [2025-03-28T00:11:36.673Z] + cd /home/jenkins/agent/workspace/test-override
00:11:36 [2025-03-28T00:11:36.673Z] + cosa shell -- mktemp -d /home/jenkins/agent/workspace/test-override/tmp/kola-XXXXX
00:11:36 [2025-03-28T00:11:36.675Z] [Pipeline] }
00:11:36 [2025-03-28T00:11:36.678Z] [Pipeline] // withEnv
00:11:36 [2025-03-28T00:11:36.730Z] [Pipeline] parallel
00:11:36 [2025-03-28T00:11:36.731Z] [Pipeline] { (Branch: x86_64:kola)
00:11:36 [2025-03-28T00:11:36.731Z] [Pipeline] { (Branch: x86_64:kola:upgrade)
00:11:36 [2025-03-28T00:11:36.763Z] [Pipeline] withEnv
00:11:36 [2025-03-28T00:11:36.764Z] [Pipeline] {
00:11:36 [2025-03-28T00:11:36.773Z] [Pipeline] withEnv
00:11:36 [2025-03-28T00:11:36.773Z] [Pipeline] {
00:11:36 [2025-03-28T00:11:36.776Z] [Pipeline] sh
00:11:37 [2025-03-28T00:11:37.051Z] [Pipeline] sh
00:11:37 [2025-03-28T00:11:37.318Z] + set -xeuo pipefail
00:11:37 [2025-03-28T00:11:37.318Z] ++ umask
00:11:37 [2025-03-28T00:11:37.318Z] + '[' 0022 = 0000 ']'
00:11:37 [2025-03-28T00:11:37.318Z] + test -d /home/jenkins/agent/workspace/test-override/tests/kola
00:11:37 [2025-03-28T00:11:37.342Z] [Pipeline] }
00:11:37 [2025-03-28T00:11:37.415Z] [Pipeline] // withEnv
00:11:37 [2025-03-28T00:11:37.421Z] [Pipeline] withEnv
00:11:37 [2025-03-28T00:11:37.422Z] [Pipeline] {
00:11:37 [2025-03-28T00:11:37.455Z] [Pipeline] sh
00:11:37 [2025-03-28T00:11:37.724Z] + set -xeuo pipefail
00:11:37 [2025-03-28T00:11:37.724Z] ++ umask
00:11:37 [2025-03-28T00:11:37.724Z] + '[' 0022 = 0000 ']'
00:11:37 [2025-03-28T00:11:37.724Z] + cd /home/jenkins/agent/workspace/test-override
00:11:37 [2025-03-28T00:11:37.724Z] + cosa kola run-upgrade --rerun --allow-rerun-success=tags=needs-internet --build=latest --output-dir=/home/jenkins/agent/workspace/test-override/tmp/kola-Wwbks/kola-upgrade-x86_64 --on-warn-failure-exit-77 --arch=x86_64 --upgrades
00:11:37 [2025-03-28T00:11:37.724Z] 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-Wwbks/kola-upgrade-x86_64 --qemu-image-dir tmp/kola-qemu-cache -v --find-parent-image
00:11:37 [2025-03-28T00:11:37.724Z] 2025-03-28T00:11:37Z cli: Started logging at level INFO
00:11:37 [2025-03-28T00:11:37.724Z] 2025-03-28T00:11:37Z cli: Started logging at level INFO
00:11:38 [2025-03-28T00:11:38.235Z] + set -euo pipefail
00:11:38 [2025-03-28T00:11:38.235Z] ++ umask
00:11:38 [2025-03-28T00:11:38.235Z] + '[' 0022 = 0000 ']'
00:11:38 [2025-03-28T00:11:38.235Z] + kola list --json
00:11:38 [2025-03-28T00:11:38.235Z] + jq -r '.[].Name'
00:11:38 [2025-03-28T00:11:38.279Z] Downloading image from https://builds.coreos.fedoraproject.org/prod/streams/rawhide/builds/43.20250326.91.0/x86_64/fedora-coreos-43.20250326.91.0-qemu.x86_64.qcow2.xz
00:11:38 [2025-03-28T00:11:38.279Z] Downloading signature from https://builds.coreos.fedoraproject.org/prod/streams/rawhide/builds/43.20250326.91.0/x86_64/fedora-coreos-43.20250326.91.0-qemu.x86_64.qcow2.xz.sig
00:11:38 [2025-03-28T00:11:38.491Z] [Pipeline] }
00:11:38 [2025-03-28T00:11:38.493Z] [Pipeline] // withEnv
00:11:38 [2025-03-28T00:11:38.500Z] [Pipeline] withEnv
00:11:38 [2025-03-28T00:11:38.501Z] [Pipeline] {
00:11:38 [2025-03-28T00:11:38.605Z] [Pipeline] sh
00:11:39 [2025-03-28T00:11:39.133Z] + set -xeuo pipefail
00:11:39 [2025-03-28T00:11:39.133Z] ++ umask
00:11:39 [2025-03-28T00:11:39.133Z] + '[' 0022 = 0000 ']'
00:11:39 [2025-03-28T00:11:39.133Z] + cd /home/jenkins/agent/workspace/test-override
00:11:39 [2025-03-28T00:11:39.133Z] + cosa kola run --rerun --allow-rerun-success=tags=needs-internet --build=latest --output-dir=/home/jenkins/agent/workspace/test-override/tmp/kola-Wwbks/kola-x86_64 --on-warn-failure-exit-77 --arch=x86_64 '--tag=!reprovision' --parallel=5
00:11:39 [2025-03-28T00:11:39.133Z] kola -p qemu --build latest run --rerun --allow-rerun-success=tags=needs-internet --on-warn-failure-exit-77 --arch=x86_64 --tag=!reprovision --parallel=5 --output-dir /home/jenkins/agent/workspace/test-override/tmp/kola-Wwbks/kola-x86_64
00:11:39 [2025-03-28T00:11:39.690Z] === RUN podman.base
00:11:39 [2025-03-28T00:11:39.690Z] === RUN ext.config.networking.ifname-karg.everyboot-systemd-link-file
00:11:39 [2025-03-28T00:11:39.690Z] === RUN ext.config.var-mount.simple
00:11:39 [2025-03-28T00:11:39.690Z] === RUN ext.config.rpm-ostree-countme
00:11:39 [2025-03-28T00:11:39.690Z] === RUN ext.config.selinux.enforcing
00:11:39 [2025-03-28T00:11:39.690Z] === RUN ext.config.docker.basic
00:11:39 [2025-03-28T00:11:39.690Z] === RUN ext.config.networking.no-default-initramfs-net-propagation.bootif
00:11:39 [2025-03-28T00:11:39.690Z] === RUN basic
00:11:39 [2025-03-28T00:11:39.690Z] === RUN ext.config.networking.nameserver
00:11:39 [2025-03-28T00:11:39.690Z] === RUN ext.config.var-mount.scsi-id
00:11:39 [2025-03-28T00:11:39.690Z] === RUN ext.config.boot.grub2-install
00:11:39 [2025-03-28T00:11:39.690Z] === RUN rootfs.uuid
00:11:39 [2025-03-28T00:11:39.690Z] === RUN coreos.ignition.symlink
00:11:39 [2025-03-28T00:11:39.690Z] === RUN coreos.selinux.boolean
00:11:39 [2025-03-28T00:11:39.690Z] === RUN non-exclusive-test-bucket-0
00:11:39 [2025-03-28T00:11:39.690Z] === RUN ext.config.networking.hostname.fallback-hostname
00:11:39 [2025-03-28T00:11:39.690Z] === RUN ext.config.ignition.resource.remote
00:11:39 [2025-03-28T00:11:39.690Z] === RUN ext.config.ntp.chrony.dhcp-propagation
00:11:39 [2025-03-28T00:11:39.690Z] === RUN ext.config.networking.force-persist-ip
00:11:39 [2025-03-28T00:11:39.690Z] === RUN ext.config.disks.lvmdevices
00:11:39 [2025-03-28T00:11:39.690Z] === RUN coreos.ignition.v2.users
00:11:39 [2025-03-28T00:11:39.690Z] === RUN ostree.hotfix
00:11:39 [2025-03-28T00:11:39.690Z] === RUN ext.config.networking.rd-net-timeout-carrier
00:11:39 [2025-03-28T00:11:39.690Z] === RUN coreos.ignition.mount.partitions
00:11:39 [2025-03-28T00:11:39.690Z] === RUN multipath.partition
00:11:39 [2025-03-28T00:11:39.690Z] === RUN ext.config.ignition.delete-config
00:11:39 [2025-03-28T00:11:39.690Z] === RUN ostree.unlock
00:11:39 [2025-03-28T00:11:39.690Z] === RUN ext.config.ignition.systemd-unmasking
00:11:39 [2025-03-28T00:11:39.690Z] === RUN ext.config.extensions.package
00:11:39 [2025-03-28T00:11:39.690Z] === RUN ext.config.containers.quadlet
00:11:39 [2025-03-28T00:11:39.690Z] === RUN coreos.misc.disk.varlibcontainers
00:11:39 [2025-03-28T00:11:39.690Z] === RUN ext.config.ignition.kargs
00:11:39 [2025-03-28T00:11:39.690Z] === RUN ext.config.systemd.condition-needs-update
00:11:39 [2025-03-28T00:11:39.690Z] === RUN kdump.crash.ssh
00:11:39 [2025-03-28T00:11:39.690Z] === RUN rpmostree.status
00:11:39 [2025-03-28T00:11:39.690Z] === RUN multipath.day2
00:11:39 [2025-03-28T00:11:39.690Z] === RUN coreos.ignition.failure
00:11:39 [2025-03-28T00:11:39.690Z] === RUN ext.config.ntp.timesyncd.dhcp-propagation
00:11:39 [2025-03-28T00:11:39.690Z] === RUN coreos.ignition.once
00:11:39 [2025-03-28T00:11:39.690Z] === RUN coreos.auth.verify
00:11:39 [2025-03-28T00:11:39.690Z] === RUN basic.uefi
00:11:39 [2025-03-28T00:11:39.690Z] === RUN ext.config.systemd.network-online
00:11:39 [2025-03-28T00:11:39.690Z] === RUN ext.config.reboot
00:11:39 [2025-03-28T00:11:39.690Z] === RUN podman.workflow
00:11:39 [2025-03-28T00:11:39.690Z] === RUN ext.config.networking.ifname-karg.udev-rule-firstboot-propagation
00:11:39 [2025-03-28T00:11:39.690Z] === RUN multipath.day1
00:11:39 [2025-03-28T00:11:39.690Z] === RUN basic.nvme
00:11:39 [2025-03-28T00:11:39.690Z] === RUN ext.config.podman.rootless-systemd
00:11:39 [2025-03-28T00:11:39.690Z] === RUN coreos.ignition.mount.disks
00:11:39 [2025-03-28T00:11:39.690Z] === RUN ext.config.networking.mtu-on-bond-kargs
00:11:39 [2025-03-28T00:11:39.690Z] === RUN coreos.ignition.groups
00:11:39 [2025-03-28T00:11:39.690Z] === RUN ext.config.clhm.ignition-warnings
00:11:39 [2025-03-28T00:11:39.690Z] === RUN ext.config.swap.zram-generator
00:11:39 [2025-03-28T00:11:39.690Z] === RUN ext.config.networking.no-persist-ip
00:11:39 [2025-03-28T00:11:39.690Z] === RUN ext.config.ignition.remote
00:11:39 [2025-03-28T00:11:39.690Z] === RUN fcos.network.listeners
00:11:39 [2025-03-28T00:11:39.690Z] === RUN rhcos.selinux.boolean.persist
00:11:39 [2025-03-28T00:11:39.690Z] === RUN podman.network-single
00:11:39 [2025-03-28T00:11:39.690Z] === RUN ext.config.networking.mtu-on-bond-ignition
00:11:39 [2025-03-28T00:11:39.690Z] === RUN ext.config.ignition.stable-boot
00:11:39 [2025-03-28T00:11:39.690Z] === RUN rpmostree.install-uninstall
00:11:39 [2025-03-28T00:11:39.690Z] === RUN ext.config.boot.bootupd-validate
00:11:39 [2025-03-28T00:11:39.690Z] === RUN coreos.ignition.instantiated.enable-unit
00:11:39 [2025-03-28T00:11:39.690Z] === RUN ext.config.networking.nmstate.state
00:11:39 [2025-03-28T00:11:39.690Z] === RUN basic.uefi-secure
00:11:39 [2025-03-28T00:11:39.690Z] === RUN ext.config.networking.bridge-static-via-kargs
00:11:39 [2025-03-28T00:11:39.690Z] === RUN ext.config.networking.team-dhcp-via-ignition
00:11:39 [2025-03-28T00:11:39.690Z] === RUN coreos.unique.boot.ignition.failure
00:11:39 [2025-03-28T00:11:39.690Z] === RUN ext.config.ignition.systemd-enable-units
00:11:39 [2025-03-28T00:11:39.690Z] === RUN ext.config.gshadow
00:11:39 [2025-03-28T00:11:39.690Z] === RUN ext.config.ssh.custom-host-key-permissions
00:11:39 [2025-03-28T00:11:39.690Z] === RUN ext.config.butane.grub-users
00:11:39 [2025-03-28T00:11:39.690Z] === RUN rpmostree.upgrade-rollback
00:11:39 [2025-03-28T00:11:39.690Z] === RUN coreos.ignition.sethostname
00:11:39 [2025-03-28T00:11:39.690Z] === RUN fcos.users.shells
00:11:39 [2025-03-28T00:11:39.690Z] === RUN ext.config.firewall.iptables-legacy
00:11:39 [2025-03-28T00:11:39.690Z] === RUN ext.config.networking.kargs-rd-net
00:11:39 [2025-03-28T00:11:39.690Z] === RUN ext.config.var-mount.luks
00:11:39 [2025-03-28T00:11:39.690Z] === RUN ext.config.kdump.crash
00:11:39 [2025-03-28T00:11:39.690Z] === RUN ext.config.ignition.systemd-disable
00:11:39 [2025-03-28T00:11:39.690Z] === RUN ext.config.networking.prefer-ignition-networking
00:11:39 [2025-03-28T00:11:39.690Z] === RUN fcos.filesystem
00:11:39 [2025-03-28T00:11:39.690Z] === RUN ext.config.toolbox
00:11:39 [2025-03-28T00:11:39.690Z] === RUN coreos.unique.boot.failure
00:11:39 [2025-03-28T00:11:39.690Z] === RUN ext.config.networking.nmstate.policy
00:11:39 [2025-03-28T00:11:39.690Z] === RUN ostree.remote
00:11:40 [2025-03-28T00:11:40.174Z] Read disk 24.0 MiB/741.2 MiB (3%)
00:11:41 [2025-03-28T00:11:41.092Z] Read disk 54.3 MiB/741.2 MiB (7%)
00:11:42 [2025-03-28T00:11:42.011Z] Read disk 80.0 MiB/741.2 MiB (10%)
00:11:43 [2025-03-28T00:11:43.364Z] Read disk 104.0 MiB/741.2 MiB (14%)
00:11:44 [2025-03-28T00:11:44.284Z] Read disk 130.4 MiB/741.2 MiB (17%)
00:11:45 [2025-03-28T00:11:45.203Z] Read disk 149.4 MiB/741.2 MiB (20%)
00:11:46 [2025-03-28T00:11:46.122Z] Read disk 174.4 MiB/741.2 MiB (23%)
00:11:47 [2025-03-28T00:11:47.053Z] Read disk 198.5 MiB/741.2 MiB (26%)
00:11:48 [2025-03-28T00:11:48.406Z] Read disk 221.5 MiB/741.2 MiB (29%)
00:11:49 [2025-03-28T00:11:49.324Z] Read disk 242.1 MiB/741.2 MiB (32%)
00:11:50 [2025-03-28T00:11:50.242Z] Read disk 266.6 MiB/741.2 MiB (35%)
00:11:51 [2025-03-28T00:11:51.159Z] Read disk 297.6 MiB/741.2 MiB (40%)
00:11:52 [2025-03-28T00:11:52.076Z] Read disk 325.4 MiB/741.2 MiB (43%)
00:11:53 [2025-03-28T00:11:53.427Z] Read disk 347.1 MiB/741.2 MiB (46%)
00:11:54 [2025-03-28T00:11:54.352Z] Read disk 371.5 MiB/741.2 MiB (50%)
00:11:55 [2025-03-28T00:11:55.284Z] Read disk 400.0 MiB/741.2 MiB (53%)
00:11:56 [2025-03-28T00:11:56.201Z] Read disk 424.0 MiB/741.2 MiB (57%)
00:11:57 [2025-03-28T00:11:57.553Z] Read disk 440.0 MiB/741.2 MiB (59%)
00:11:58 [2025-03-28T00:11:58.497Z] Read disk 462.7 MiB/741.2 MiB (62%)
00:11:59 [2025-03-28T00:11:59.416Z] Read disk 478.7 MiB/741.2 MiB (64%)
00:12:00 [2025-03-28T00:12:00.333Z] Read disk 498.5 MiB/741.2 MiB (67%)
00:12:01 [2025-03-28T00:12:01.686Z] Read disk 519.6 MiB/741.2 MiB (70%)
00:12:02 [2025-03-28T00:12:02.614Z] Read disk 545.7 MiB/741.2 MiB (73%)
00:12:03 [2025-03-28T00:12:03.546Z] Read disk 563.7 MiB/741.2 MiB (76%)
00:12:04 [2025-03-28T00:12:04.465Z] Read disk 584.1 MiB/741.2 MiB (78%)
00:12:05 [2025-03-28T00:12:05.818Z] Read disk 608.0 MiB/741.2 MiB (82%)
00:12:06 [2025-03-28T00:12:06.736Z] Read disk 634.0 MiB/741.2 MiB (85%)
00:12:07 [2025-03-28T00:12:07.682Z] Read disk 652.7 MiB/741.2 MiB (88%)
00:12:08 [2025-03-28T00:12:08.603Z] Read disk 678.4 MiB/741.2 MiB (91%)
00:12:09 [2025-03-28T00:12:09.956Z] Read disk 700.2 MiB/741.2 MiB (94%)
00:12:10 [2025-03-28T00:12:10.875Z] Read disk 728.0 MiB/741.2 MiB (98%)
00:12:11 [2025-03-28T00:12:11.430Z] Read disk 741.2 MiB/741.2 MiB (100%)
00:12:11 [2025-03-28T00:12:11.430Z] Read disk 741.2 MiB/741.2 MiB (100%)
00:12:11 [2025-03-28T00:12:11.430Z] Read disk 741.2 MiB/741.2 MiB (100%)
00:12:11 [2025-03-28T00:12:11.430Z] gpg: Signature made Wed Mar 26 13:21:02 2025 UTC
00:12:11 [2025-03-28T00:12:11.430Z] gpg: using RSA key C6E7F081CF80E13146676E88829B606631645531
00:12:11 [2025-03-28T00:12:11.430Z] gpg: Good signature from "Fedora (43) <fedora-43-primary@fedoraproject.org>" [ultimate]
00:12:11 [2025-03-28T00:12:11.430Z] tmp/kola-qemu-cache/fedora-coreos-43.20250326.91.0-qemu.x86_64.qcow2
00:12:11 [2025-03-28T00:12:11.430Z] === RUN fcos.upgrade.basic
00:12:18 [2025-03-28T00:12:18.348Z] === RUN podman.base/info
00:12:18 [2025-03-28T00:12:18.348Z] === RUN podman.base/resources
00:12:21 [2025-03-28T00:12:21.609Z] --- [32mPASS[0m: podman.base (41.63s)
00:12:21 [2025-03-28T00:12:21.609Z] --- [32mPASS[0m: podman.base/info (0.44s)
00:12:21 [2025-03-28T00:12:21.609Z] --- [32mPASS[0m: podman.base/resources (4.98s)
00:12:24 [2025-03-28T00:12:24.856Z] --- [32mPASS[0m: ext.config.networking.team-dhcp-via-ignition (45.11s)
00:12:39 [2025-03-28T00:12:39.688Z] --- [32mPASS[0m: ext.config.networking.rd-net-timeout-carrier (58.39s)
00:12:42 [2025-03-28T00:12:42.947Z] --- [32mPASS[0m: ext.config.networking.ifname-karg.udev-rule-firstboot-propagation (63.09s)
00:12:42 [2025-03-28T00:12:42.947Z] mke2fs 1.47.1 (20-May-2024)
00:12:50 [2025-03-28T00:12:50.048Z] === RUN fcos.upgrade.basic/setup
00:12:52 [2025-03-28T00:12:52.546Z] === RUN fcos.upgrade.basic/upgrade-from-previous
00:12:57 [2025-03-28T00:12:57.770Z] --- [32mPASS[0m: coreos.unique.boot.failure (13.97s)
00:12:57 [2025-03-28T00:12:57.770Z] === RUN ostree.remote/add
00:12:57 [2025-03-28T00:12:57.770Z] === RUN ostree.remote/list
00:12:57 [2025-03-28T00:12:57.770Z] === RUN ostree.remote/show-url
00:12:58 [2025-03-28T00:12:58.023Z] === RUN ostree.remote/refs
00:12:58 [2025-03-28T00:12:58.276Z] === RUN ostree.remote/summary
00:12:58 [2025-03-28T00:12:58.529Z] === RUN ostree.remote/delete
00:13:02 [2025-03-28T00:13:02.693Z] --- [32mPASS[0m: ostree.remote (37.58s)
00:13:02 [2025-03-28T00:13:02.693Z] --- [32mPASS[0m: ostree.remote/add (0.28s)
00:13:02 [2025-03-28T00:13:02.693Z] --- [32mPASS[0m: ostree.remote/list (0.12s)
00:13:02 [2025-03-28T00:13:02.693Z] --- [32mPASS[0m: ostree.remote/show-url (0.22s)
00:13:02 [2025-03-28T00:13:02.693Z] --- [32mPASS[0m: ostree.remote/refs (0.15s)
00:13:02 [2025-03-28T00:13:02.693Z] --- [32mPASS[0m: ostree.remote/summary (0.33s)
00:13:02 [2025-03-28T00:13:02.693Z] --- [32mPASS[0m: ostree.remote/delete (0.49s)
00:13:41 [2025-03-28T00:13:41.312Z] === RUN fcos.filesystem/writablefiles
00:13:41 [2025-03-28T00:13:41.312Z] === RUN fcos.filesystem/writabledirs
00:13:41 [2025-03-28T00:13:41.312Z] === RUN fcos.filesystem/stickydirs
00:13:41 [2025-03-28T00:13:41.312Z] === RUN fcos.filesystem/denylist
00:13:41 [2025-03-28T00:13:41.312Z] --- [32mPASS[0m: fcos.filesystem (38.31s)
00:13:41 [2025-03-28T00:13:41.312Z] --- [32mPASS[0m: fcos.filesystem/writablefiles (1.11s)
00:13:41 [2025-03-28T00:13:41.312Z] --- [32mPASS[0m: fcos.filesystem/writabledirs (0.26s)
00:13:41 [2025-03-28T00:13:41.312Z] --- [32mPASS[0m: fcos.filesystem/stickydirs (0.30s)
00:13:41 [2025-03-28T00:13:41.312Z] --- [32mPASS[0m: fcos.filesystem/denylist (0.37s)
00:13:56 [2025-03-28T00:13:56.136Z] --- [32mPASS[0m: ext.config.var-mount.luks (93.76s)
00:14:00 [2025-03-28T00:14:00.147Z] === RUN fcos.upgrade.basic/upgrade-from-current
00:14:01 [2025-03-28T00:14:01.375Z] --- [32mPASS[0m: kdump.crash.ssh (141.21s)
00:14:01 [2025-03-28T00:14:01.375Z] cluster.go:151: Running as unit: run-p7214-i7215.service; invocation ID: fbb3681439b34a019d7f06ed0ee17916
00:14:19 [2025-03-28T00:14:19.388Z] systemctl status kola-runext.service:
00:14:19 [2025-03-28T00:14:19.388Z] �� kola-runext.service
00:14:19 [2025-03-28T00:14:19.388Z] Loaded: loaded (/etc/systemd/system/kola-runext.service; static)
00:14:19 [2025-03-28T00:14:19.388Z] Drop-In: /usr/lib/systemd/system/service.d
00:14:19 [2025-03-28T00:14:19.388Z] ������10-timeout-abort.conf, 50-keep-warm.conf
00:14:19 [2025-03-28T00:14:19.388Z] Active: failed (Result: exit-code) since Fri 2025-03-28 00:14:17 UTC; 293ms ago
00:14:19 [2025-03-28T00:14:19.388Z] Duration: 45.942s
00:14:19 [2025-03-28T00:14:19.388Z] Invocation: b29de91d26704311bf6b8e191b8d9076
00:14:19 [2025-03-28T00:14:19.388Z] Process: 2288 ExecStart=/usr/local/bin/kola-runext-test.sh (code=exited, status=1/FAILURE)
00:14:19 [2025-03-28T00:14:19.388Z] Main PID: 2288 (code=exited, status=1/FAILURE)
00:14:19 [2025-03-28T00:14:19.388Z] Mem peak: 2.5M
00:14:19 [2025-03-28T00:14:19.388Z] CPU: 61ms
00:14:19 [2025-03-28T00:14:19.388Z]
00:14:19 [2025-03-28T00:14:19.388Z] Mar 28 00:13:31 qemu0 systemd[1]: Started kola-runext.service.
00:14:19 [2025-03-28T00:14:19.388Z] Mar 28 00:13:31 qemu0 kola-runext-test.sh[2288]: + . /var/opt/kola/extdata/commonlib.sh
00:14:19 [2025-03-28T00:14:19.388Z] Mar 28 00:13:31 qemu0 kola-runext-test.sh[2288]: ++ IFS=' '
00:14:19 [2025-03-28T00:14:19.388Z] Mar 28 00:13:31 qemu0 kola-runext-test.sh[2288]: ++ read -r -a cmdline
00:14:19 [2025-03-28T00:14:19.388Z] Mar 28 00:13:31 qemu0 kola-runext-test.sh[2289]: ++ seq 1 5
00:14:19 [2025-03-28T00:14:19.388Z] Mar 28 00:13:31 qemu0 kola-runext-test.sh[2288]: + for i in $(seq 1 5)
00:14:19 [2025-03-28T00:14:19.388Z] Mar 28 00:13:31 qemu0 kola-runext-test.sh[2288]: + machinectl shell core@ /bin/toolbox create --assumeyes
00:14:19 [2025-03-28T00:14:19.388Z] Mar 28 00:13:31 qemu0 machinectl[2290]: Connected to the local host. Press ^] three times within 1s to exit session.
00:14:19 [2025-03-28T00:14:19.388Z] Mar 28 00:14:16 qemu0 machinectl[2290]: Connection to the local host terminated.
00:14:19 [2025-03-28T00:14:19.388Z] Mar 28 00:14:16 qemu0 kola-runext-test.sh[2644]: ++ machinectl shell core@ /bin/toolbox list --containers
00:14:19 [2025-03-28T00:14:19.388Z] Mar 28 00:14:16 qemu0 kola-runext-test.sh[2645]: ++ grep --count fedora-toolbox-
00:14:19 [2025-03-28T00:14:19.388Z] Mar 28 00:14:16 qemu0 machinectl[2644]: Connected to the local host. Press ^] three times within 1s to exit session.
00:14:19 [2025-03-28T00:14:19.388Z] Mar 28 00:14:16 qemu0 machinectl[2644]: Connection to the local host terminated.
00:14:19 [2025-03-28T00:14:19.388Z] Mar 28 00:14:16 qemu0 kola-runext-test.sh[2288]: + [[ 1 -ne 1 ]]
00:14:19 [2025-03-28T00:14:19.388Z] Mar 28 00:14:16 qemu0 kola-runext-test.sh[2288]: + break
00:14:19 [2025-03-28T00:14:19.388Z] Mar 28 00:14:16 qemu0 kola-runext-test.sh[2669]: ++ machinectl shell core@ /bin/toolbox list --containers
00:14:19 [2025-03-28T00:14:19.388Z] Mar 28 00:14:16 qemu0 kola-runext-test.sh[2670]: ++ grep --count fedora-toolbox-
00:14:19 [2025-03-28T00:14:19.388Z] Mar 28 00:14:16 qemu0 machinectl[2669]: Connected to the local host. Press ^] three times within 1s to exit session.
00:14:19 [2025-03-28T00:14:19.388Z] Mar 28 00:14:16 qemu0 machinectl[2669]: Connection to the local host terminated.
00:14:19 [2025-03-28T00:14:19.388Z] Mar 28 00:14:16 qemu0 kola-runext-test.sh[2288]: + [[ 1 -ne 1 ]]
00:14:19 [2025-03-28T00:14:19.388Z] Mar 28 00:14:16 qemu0 kola-runext-test.sh[2288]: + ok toolbox create
00:14:19 [2025-03-28T00:14:19.388Z] Mar 28 00:14:16 qemu0 kola-runext-test.sh[2288]: + echo ok toolbox create
00:14:19 [2025-03-28T00:14:19.388Z] Mar 28 00:14:16 qemu0 kola-runext-test.sh[2288]: ok toolbox create
00:14:19 [2025-03-28T00:14:19.388Z] Mar 28 00:14:16 qemu0 kola-runext-test.sh[2288]: + machinectl shell core@ /bin/toolbox run touch ok_toolbox
00:14:19 [2025-03-28T00:14:19.388Z] Mar 28 00:14:16 qemu0 machinectl[2692]: Connected to the local host. Press ^] three times within 1s to exit session.
00:14:19 [2025-03-28T00:14:19.388Z] Mar 28 00:14:17 qemu0 kola-runext-test.sh[2692]: Error: failed to invoke command touch in container fedora-toolbox-43
00:14:19 [2025-03-28T00:14:19.388Z] Mar 28 00:14:17 qemu0 machinectl[2692]: Connection to the local host terminated.
00:14:19 [2025-03-28T00:14:19.388Z] Mar 28 00:14:17 qemu0 kola-runext-test.sh[2288]: + [[ ! -f /home/core/ok_toolbox ]]
00:14:19 [2025-03-28T00:14:19.388Z] Mar 28 00:14:17 qemu0 kola-runext-test.sh[2288]: + fatal 'Could not run a simple command inside a toolbox'
00:14:19 [2025-03-28T00:14:19.388Z] Mar 28 00:14:17 qemu0 kola-runext-test.sh[2288]: + echo 'Could not run a simple command inside a toolbox'
00:14:19 [2025-03-28T00:14:19.388Z] Mar 28 00:14:17 qemu0 kola-runext-test.sh[2288]: Could not run a simple command inside a toolbox
00:14:19 [2025-03-28T00:14:19.388Z] Mar 28 00:14:17 qemu0 kola-runext-test.sh[2288]: + exit 1
00:14:19 [2025-03-28T00:14:19.388Z] Mar 28 00:14:17 qemu0 systemd[1]: kola-runext.service: Main process exited, code=exited, status=1/FAILURE
00:14:19 [2025-03-28T00:14:19.388Z] Mar 28 00:14:17 qemu0 systemd[1]: kola-runext.service: Failed with result 'exit-code'.
00:14:22 [2025-03-28T00:14:22.647Z] --- [31mFAIL[0m: ext.config.toolbox (85.49s)
00:14:22 [2025-03-28T00:14:22.647Z] cluster.go:151: Error: Unit kola-runext.service exited with code 1
00:14:22 [2025-03-28T00:14:22.647Z] cluster.go:151: 2025-03-28T00:14:17Z cli: Unit kola-runext.service exited with code 1
00:14:22 [2025-03-28T00:14:22.647Z] harness.go:1260: kolet failed: : kolet run-test-unit failed: Process exited with status 1
00:14:23 [2025-03-28T00:14:23.202Z] --- [32mPASS[0m: ext.config.networking.prefer-ignition-networking (42.66s)
00:14:32 [2025-03-28T00:14:32.136Z] --- [32mPASS[0m: fcos.upgrade.basic (138.39s)
00:14:32 [2025-03-28T00:14:32.136Z] --- [32mPASS[0m: fcos.upgrade.basic/setup (6.91s)
00:14:32 [2025-03-28T00:14:32.136Z] --- [32mPASS[0m: fcos.upgrade.basic/upgrade-from-previous (57.78s)
00:14:32 [2025-03-28T00:14:32.136Z] --- [32mPASS[0m: fcos.upgrade.basic/upgrade-from-current (36.24s)
00:14:32 [2025-03-28T00:14:32.136Z] PASS, output in /home/jenkins/agent/workspace/test-override/tmp/kola-Wwbks/kola-upgrade-x86_64
00:14:32 [2025-03-28T00:14:32.138Z] [Pipeline] }
00:14:32 [2025-03-28T00:14:32.142Z] [Pipeline] // withEnv
00:14:32 [2025-03-28T00:14:32.194Z] [Pipeline] }
00:14:35 [2025-03-28T00:14:35.352Z] --- [32mPASS[0m: ext.config.ignition.systemd-disable (38.80s)
00:14:35 [2025-03-28T00:14:35.352Z] --- [32mPASS[0m: ext.config.networking.nmstate.policy (116.81s)
00:15:07 [2025-03-28T00:15:07.357Z] === RUN rootfs.uuid/RandomUUID
00:15:11 [2025-03-28T00:15:11.517Z] --- [32mPASS[0m: rootfs.uuid (37.18s)
00:15:11 [2025-03-28T00:15:11.517Z] --- [32mPASS[0m: rootfs.uuid/RandomUUID (0.16s)
00:15:12 [2025-03-28T00:15:12.870Z] --- [32mPASS[0m: ext.config.ignition.kargs (37.79s)
00:15:30 [2025-03-28T00:15:30.885Z] --- [32mPASS[0m: ext.config.extensions.package (66.53s)
00:15:45 [2025-03-28T00:15:45.702Z] === RUN ostree.hotfix/unlock
00:15:57 [2025-03-28T00:15:57.863Z] --- [32mPASS[0m: ext.config.systemd.condition-needs-update (93.18s)
00:15:59 [2025-03-28T00:15:59.740Z] --- [32mPASS[0m: ext.config.kdump.crash (118.77s)
00:16:00 [2025-03-28T00:16:00.297Z] === RUN ostree.hotfix/install
00:16:01 [2025-03-28T00:16:01.217Z] === RUN ostree.hotfix/uninstall
00:16:01 [2025-03-28T00:16:01.797Z] === RUN ostree.hotfix/persist
00:16:08 [2025-03-28T00:16:08.311Z] --- [32mPASS[0m: ext.config.containers.quadlet (38.91s)
00:16:08 [2025-03-28T00:16:08.868Z] --- [32mPASS[0m: coreos.misc.disk.varlibcontainers (57.75s)
00:16:08 [2025-03-28T00:16:08.868Z] cluster.go:151: Created symlink '/etc/systemd/system/var-lib-containers.mount.wants/systemd-mkfs@dev-disk-by-id-virtio-disk1.service' ��� '/etc/systemd/system/systemd-mkfs@dev-disk-by-id-virtio-disk1.service'.
00:16:08 [2025-03-28T00:16:08.868Z] cluster.go:151: Created symlink '/etc/systemd/system/local-fs.target.wants/var-lib-containers.mount' ��� '/etc/systemd/system/var-lib-containers.mount'.
00:16:23 [2025-03-28T00:16:23.685Z] === RUN ostree.hotfix/rollback
00:16:33 [2025-03-28T00:16:33.607Z] --- [32mPASS[0m: fcos.network.listeners (24.66s)
00:16:33 [2025-03-28T00:16:33.607Z] --- [32mPASS[0m: coreos.ignition.v2.users (36.59s)
00:16:45 [2025-03-28T00:16:45.754Z] --- [32mPASS[0m: ostree.hotfix (92.34s)
00:16:45 [2025-03-28T00:16:45.754Z] --- [32mPASS[0m: ostree.hotfix/unlock (14.49s)
00:16:45 [2025-03-28T00:16:45.754Z] --- [32mPASS[0m: ostree.hotfix/install (0.73s)
00:16:45 [2025-03-28T00:16:45.754Z] --- [32mPASS[0m: ostree.hotfix/uninstall (0.45s)
00:16:45 [2025-03-28T00:16:45.754Z] --- [32mPASS[0m: ostree.hotfix/persist (19.22s)
00:16:45 [2025-03-28T00:16:45.754Z] --- [32mPASS[0m: ostree.hotfix/rollback (20.02s)
00:16:50 [2025-03-28T00:16:50.989Z] --- [32mPASS[0m: ext.config.networking.force-persist-ip (41.53s)
00:16:57 [2025-03-28T00:16:57.496Z] --- [32mPASS[0m: ext.config.disks.lvmdevices (56.87s)
00:17:15 [2025-03-28T00:17:15.512Z] --- [32mPASS[0m: ext.config.networking.bridge-static-via-kargs (41.52s)
00:17:19 [2025-03-28T00:17:19.198Z] === RUN basic.uefi-secure/FCOSGrowpart
00:17:19 [2025-03-28T00:17:19.198Z] === RUN basic.uefi-secure/PortSSH
00:17:19 [2025-03-28T00:17:19.451Z] === RUN basic.uefi-secure/DbusPerms
00:17:19 [2025-03-28T00:17:19.704Z] === RUN basic.uefi-secure/ServicesActive
00:17:19 [2025-03-28T00:17:19.957Z] === RUN basic.uefi-secure/ReadOnly
00:17:19 [2025-03-28T00:17:19.957Z] === RUN basic.uefi-secure/Useradd
00:17:20 [2025-03-28T00:17:20.209Z] === RUN basic.uefi-secure/MachineID
00:17:24 [2025-03-28T00:17:24.352Z] --- [32mPASS[0m: basic.uefi-secure (38.75s)
00:17:24 [2025-03-28T00:17:24.352Z] --- [32mPASS[0m: basic.uefi-secure/FCOSGrowpart (0.15s)
00:17:24 [2025-03-28T00:17:24.352Z] --- [32mPASS[0m: basic.uefi-secure/PortSSH (0.12s)
00:17:24 [2025-03-28T00:17:24.352Z] --- [32mPASS[0m: basic.uefi-secure/DbusPerms (0.35s)
00:17:24 [2025-03-28T00:17:24.352Z] --- [32mPASS[0m: basic.uefi-secure/ServicesActive (0.13s)
00:17:24 [2025-03-28T00:17:24.352Z] --- [32mPASS[0m: basic.uefi-secure/ReadOnly (0.12s)
00:17:24 [2025-03-28T00:17:24.352Z] --- [32mPASS[0m: basic.uefi-secure/Useradd (0.19s)
00:17:24 [2025-03-28T00:17:24.352Z] --- [32mPASS[0m: basic.uefi-secure/MachineID (0.11s)
00:17:29 [2025-03-28T00:17:29.593Z] --- [32mPASS[0m: ext.config.ignition.resource.remote (38.52s)
00:17:44 [2025-03-28T00:17:44.414Z] --- [32mPASS[0m: ext.config.ntp.chrony.dhcp-propagation (70.21s)
00:17:52 [2025-03-28T00:17:52.489Z] --- [32mPASS[0m: ext.config.networking.nmstate.state (55.00s)
00:17:52 [2025-03-28T00:17:52.489Z] --- [32mPASS[0m: ext.config.networking.hostname.fallback-hostname (38.71s)
00:17:53 [2025-03-28T00:17:53.842Z] --- [32mPASS[0m: coreos.ignition.groups (24.89s)
00:18:01 [2025-03-28T00:18:01.911Z] --- [32mPASS[0m: coreos.ignition.instantiated.enable-unit (37.09s)
00:18:23 [2025-03-28T00:18:23.778Z] --- [32mPASS[0m: ext.config.boot.bootupd-validate (39.63s)
00:18:26 [2025-03-28T00:18:26.284Z] === RUN rpmostree.install-uninstall/install
00:18:44 [2025-03-28T00:18:44.302Z] === RUN non-exclusive-test-bucket-0/ext.config.files.fcos_users
00:18:44 [2025-03-28T00:18:44.302Z] --- [32mPASS[0m: ext.config.ignition.remote (48.01s)
00:18:44 [2025-03-28T00:18:44.302Z] === RUN non-exclusive-test-bucket-0/ext.config.files.sudoers
00:18:44 [2025-03-28T00:18:44.302Z] === RUN non-exclusive-test-bucket-0/ext.config.files.initrd.compression
00:18:45 [2025-03-28T00:18:45.666Z] === RUN non-exclusive-test-bucket-0/ext.config.firewall.iptables
00:18:47 [2025-03-28T00:18:47.035Z] === RUN non-exclusive-test-bucket-0/ext.config.kdump.service
00:18:48 [2025-03-28T00:18:48.910Z] === RUN non-exclusive-test-bucket-0/ext.config.selinux.file-context-policy-match
00:18:53 [2025-03-28T00:18:53.083Z] === RUN non-exclusive-test-bucket-0/ext.config.networking.network-online-service
00:18:54 [2025-03-28T00:18:54.000Z] === RUN non-exclusive-test-bucket-0/ext.config.selinux.unmodified-policy
00:18:55 [2025-03-28T00:18:55.873Z] --- [32mPASS[0m: coreos.selinux.boolean (54.74s)
00:18:55 [2025-03-28T00:18:55.873Z] === RUN non-exclusive-test-bucket-0/ext.config.files.fcos_groups
00:18:57 [2025-03-28T00:18:57.757Z] === RUN non-exclusive-test-bucket-0/ext.config.networking.tls
00:19:01 [2025-03-28T00:19:01.912Z] --- [32mPASS[0m: ext.config.ignition.stable-boot (39.00s)
00:19:02 [2025-03-28T00:19:02.165Z] === RUN non-exclusive-test-bucket-0/ext.config.selinux.podman-tmpfs-context
00:19:10 [2025-03-28T00:19:10.241Z] === RUN non-exclusive-test-bucket-0/ext.config.ignition.journald-log
00:19:10 [2025-03-28T00:19:10.796Z] === RUN non-exclusive-test-bucket-0/ext.config.files.validate-symlinks
00:19:13 [2025-03-28T00:19:13.301Z] === RUN rpmostree.install-uninstall/uninstall
00:19:18 [2025-03-28T00:19:18.534Z] --- [32mPASS[0m: coreos.ignition.symlink (36.11s)
00:19:23 [2025-03-28T00:19:23.754Z] === RUN non-exclusive-test-bucket-0/ext.config.files.system-generators
00:19:25 [2025-03-28T00:19:25.647Z] === RUN non-exclusive-test-bucket-0/ext.config.disks.root-boot-ro
00:19:27 [2025-03-28T00:19:27.522Z] === RUN non-exclusive-test-bucket-0/ext.config.containers.cgroups-v2
00:19:28 [2025-03-28T00:19:28.874Z] === RUN non-exclusive-test-bucket-0/ext.config.files.rpmdb-sqlite
00:19:29 [2025-03-28T00:19:29.843Z] === RUN non-exclusive-test-bucket-0/ext.config.rpm-ostree.container-deps
00:19:31 [2025-03-28T00:19:31.727Z] === RUN non-exclusive-test-bucket-0/ext.config.files.license
00:19:31 [2025-03-28T00:19:31.980Z] === RUN non-exclusive-test-bucket-0/ext.config.files.aleph-version
00:19:32 [2025-03-28T00:19:32.898Z] === RUN non-exclusive-test-bucket-0/ext.config.files.setgid
00:19:34 [2025-03-28T00:19:34.771Z] === RUN non-exclusive-test-bucket-0/ext.config.files.etc-permissions
00:19:36 [2025-03-28T00:19:36.141Z] === RUN non-exclusive-test-bucket-0/ext.config.disks.tmpfs
00:19:36 [2025-03-28T00:19:36.697Z] --- [32mPASS[0m: ext.config.networking.no-persist-ip (41.19s)
00:19:36 [2025-03-28T00:19:36.950Z] === RUN non-exclusive-test-bucket-0/ext.config.files.file-directory-permissions
00:19:38 [2025-03-28T00:19:38.824Z] === RUN non-exclusive-test-bucket-0/ext.config.kubernetes.kube-watch
00:19:41 [2025-03-28T00:19:41.344Z] === RUN non-exclusive-test-bucket-0/ext.config.disks.partition-scheme
00:19:43 [2025-03-28T00:19:43.216Z] === RUN non-exclusive-test-bucket-0/ext.config.boot.bootupd
00:19:43 [2025-03-28T00:19:43.469Z] --- [32mPASS[0m: ext.config.networking.mtu-on-bond-ignition (41.48s)
00:19:44 [2025-03-28T00:19:44.821Z] === RUN non-exclusive-test-bucket-0/ext.config.files.initrd.executables
00:19:48 [2025-03-28T00:19:48.977Z] === RUN non-exclusive-test-bucket-0/ext.config.systemd.no-systemd-firstboot
00:19:49 [2025-03-28T00:19:49.896Z] === RUN non-exclusive-test-bucket-0/ext.config.clhm.network-device-info
00:19:50 [2025-03-28T00:19:50.452Z] === RUN non-exclusive-test-bucket-0/ext.config.logging.printk
00:19:50 [2025-03-28T00:19:50.714Z] === RUN rpmostree.upgrade-rollback/upgrade
00:19:52 [2025-03-28T00:19:52.085Z] === RUN non-exclusive-test-bucket-0/ext.config.composefs.enabled
00:19:53 [2025-03-28T00:19:53.960Z] === RUN non-exclusive-test-bucket-0/ext.config.ntp.chrony.coreos-platform-chrony-config
00:19:55 [2025-03-28T00:19:55.836Z] === RUN non-exclusive-test-bucket-0/ext.config.files.logrotate-service
00:19:56 [2025-03-28T00:19:56.392Z] --- [32mPASS[0m: rpmostree.install-uninstall (123.73s)
00:19:56 [2025-03-28T00:19:56.392Z] --- [32mPASS[0m: rpmostree.install-uninstall/install (47.35s)
00:19:56 [2025-03-28T00:19:56.392Z] --- [32mPASS[0m: rpmostree.install-uninstall/uninstall (39.51s)
00:19:57 [2025-03-28T00:19:57.328Z] === RUN non-exclusive-test-bucket-0/ext.config.networking.no-default-initramfs-net-propagation.default
00:19:58 [2025-03-28T00:19:58.693Z] === RUN non-exclusive-test-bucket-0/ext.config.networking.nm-start
00:20:00 [2025-03-28T00:20:00.570Z] === RUN non-exclusive-test-bucket-0/ext.config.selinux.stub-resolve-context
00:20:01 [2025-03-28T00:20:01.126Z] === RUN non-exclusive-test-bucket-0/ext.config.files.amd-ucode-firmware
00:20:01 [2025-03-28T00:20:01.683Z] === RUN non-exclusive-test-bucket-0/ext.config.podman.rootless-pasta-networking
00:20:08 [2025-03-28T00:20:08.216Z] === RUN non-exclusive-test-bucket-0/ext.config.networking.default-network-behavior-change
00:20:09 [2025-03-28T00:20:09.137Z] === RUN non-exclusive-test-bucket-0/ext.config.files.fwupd-refresh-timer
00:20:10 [2025-03-28T00:20:10.508Z] === RUN non-exclusive-test-bucket-0/ext.config.files.initrd.expected-contents
00:20:14 [2025-03-28T00:20:14.657Z] === RUN non-exclusive-test-bucket-0/ext.config.files.root-immutable-bit
00:20:14 [2025-03-28T00:20:14.909Z] === RUN non-exclusive-test-bucket-0/ext.config.files.setuid
00:20:16 [2025-03-28T00:20:16.781Z] === RUN non-exclusive-test-bucket-0/ext.config.swap.zram-default
00:20:17 [2025-03-28T00:20:17.353Z] === RUN non-exclusive-test-bucket-0/ext.config.kubernetes.systemd-env-read
00:20:19 [2025-03-28T00:20:19.228Z] === RUN non-exclusive-test-bucket-0/ext.config.networking.resolv.systemd-resolved
00:20:20 [2025-03-28T00:20:20.591Z] === RUN non-exclusive-test-bucket-0/ext.config.files.remove-manifest-files
00:20:21 [2025-03-28T00:20:21.145Z] === RUN non-exclusive-test-bucket-0/ext.config.podman.dns
00:20:23 [2025-03-28T00:20:23.033Z] === RUN non-exclusive-test-bucket-0/ext.config.files.yum-repo-dir
00:20:23 [2025-03-28T00:20:23.588Z] === RUN non-exclusive-test-bucket-0/ext.config.security.coreos-update-ca-trust
00:20:24 [2025-03-28T00:20:24.947Z] --- [32mPASS[0m: ext.config.networking.kargs-rd-net (41.36s)
00:20:25 [2025-03-28T00:20:25.199Z] === RUN non-exclusive-test-bucket-0/ext.config.files.console-config
00:20:27 [2025-03-28T00:20:27.096Z] === RUN non-exclusive-test-bucket-0/ext.config.security.passwd
00:20:28 [2025-03-28T00:20:28.030Z] === RUN rpmostree.upgrade-rollback/rollback
00:20:29 [2025-03-28T00:20:29.390Z] === RUN non-exclusive-test-bucket-0/ext.config.selinux.usrlocal-context
00:20:31 [2025-03-28T00:20:31.263Z] === RUN non-exclusive-test-bucket-0/ext.config.networking.nm-ifcfg-rh-plugin
00:20:32 [2025-03-28T00:20:32.630Z] === RUN non-exclusive-test-bucket-0/ext.config.disks.systemd-repart-service
00:20:33 [2025-03-28T00:20:33.988Z] --- [32mPASS[0m: ext.config.swap.zram-generator (37.86s)
00:20:34 [2025-03-28T00:20:34.240Z] === RUN non-exclusive-test-bucket-0/ext.config.networking.dnsmasq-service
00:20:35 [2025-03-28T00:20:35.157Z] === RUN non-exclusive-test-bucket-0/ext.config.files.root-bash
00:20:35 [2025-03-28T00:20:35.711Z] === RUN non-exclusive-test-bucket-0/ext.config.disks.growfs
00:20:36 [2025-03-28T00:20:36.266Z] === RUN non-exclusive-test-bucket-0/ext.config.systemd.default-unit-timeouts
00:20:38 [2025-03-28T00:20:38.163Z] === RUN non-exclusive-test-bucket-0/ext.config.content-origins
00:20:39 [2025-03-28T00:20:39.523Z] === RUN non-exclusive-test-bucket-0/ext.config.disks.root-prjquota
00:20:41 [2025-03-28T00:20:41.396Z] === RUN non-exclusive-test-bucket-0/ext.config.networking.nm-dhcp-client
00:20:43 [2025-03-28T00:20:43.294Z] === RUN non-exclusive-test-bucket-0/ext.config.networking.nic-naming
00:20:43 [2025-03-28T00:20:43.546Z] === RUN non-exclusive-test-bucket-0/ext.config.files.check-symlink
00:20:45 [2025-03-28T00:20:45.418Z] === RUN non-exclusive-test-bucket-0/ext.config.files.kernel-headers
00:20:45 [2025-03-28T00:20:45.982Z] === RUN non-exclusive-test-bucket-0/ext.config.disks.no-google-device-links
00:20:51 [2025-03-28T00:20:51.228Z] --- [32mPASS[0m: non-exclusive-test-bucket-0 (179.63s)
00:20:51 [2025-03-28T00:20:51.228Z] --- [32mPASS[0m: non-exclusive-test-bucket-0/ext.config.files.fcos_users (1.67s)
00:20:51 [2025-03-28T00:20:51.228Z] --- [32mPASS[0m: non-exclusive-test-bucket-0/ext.config.files.sudoers (0.64s)
00:20:51 [2025-03-28T00:20:51.228Z] --- [32mPASS[0m: non-exclusive-test-bucket-0/ext.config.files.initrd.compression (1.61s)
00:20:51 [2025-03-28T00:20:51.228Z] --- [32mPASS[0m: non-exclusive-test-bucket-0/ext.config.firewall.iptables (1.62s)
00:20:51 [2025-03-28T00:20:51.228Z] --- [32mPASS[0m: non-exclusive-test-bucket-0/ext.config.kdump.service (1.69s)
00:20:51 [2025-03-28T00:20:51.228Z] --- [32mPASS[0m: non-exclusive-test-bucket-0/ext.config.selinux.file-context-policy-match (3.64s)
00:20:51 [2025-03-28T00:20:51.228Z] --- [32mPASS[0m: non-exclusive-test-bucket-0/ext.config.networking.network-online-service (1.65s)
00:20:51 [2025-03-28T00:20:51.228Z] --- [32mPASS[0m: non-exclusive-test-bucket-0/ext.config.selinux.unmodified-policy (1.74s)
00:20:51 [2025-03-28T00:20:51.228Z] --- [32mPASS[0m: non-exclusive-test-bucket-0/ext.config.files.fcos_groups (1.69s)
00:20:51 [2025-03-28T00:20:51.228Z] --- [32mPASS[0m: non-exclusive-test-bucket-0/ext.config.networking.tls (4.70s)
00:20:51 [2025-03-28T00:20:51.228Z] --- [32mPASS[0m: non-exclusive-test-bucket-0/ext.config.selinux.podman-tmpfs-context (6.77s)
00:20:51 [2025-03-28T00:20:51.228Z] --- [32mPASS[0m: non-exclusive-test-bucket-0/ext.config.ignition.journald-log (1.74s)
00:20:51 [2025-03-28T00:20:51.228Z] --- [32mPASS[0m: non-exclusive-test-bucket-0/ext.config.files.validate-symlinks (12.98s)
00:20:51 [2025-03-28T00:20:51.228Z] --- [32mPASS[0m: non-exclusive-test-bucket-0/ext.config.files.system-generators (1.73s)
00:20:51 [2025-03-28T00:20:51.228Z] --- [32mPASS[0m: non-exclusive-test-bucket-0/ext.config.disks.root-boot-ro (1.73s)
00:20:51 [2025-03-28T00:20:51.228Z] --- [32mPASS[0m: non-exclusive-test-bucket-0/ext.config.containers.cgroups-v2 (1.70s)
00:20:51 [2025-03-28T00:20:51.228Z] --- [32mPASS[0m: non-exclusive-test-bucket-0/ext.config.files.rpmdb-sqlite (0.73s)
00:20:51 [2025-03-28T00:20:51.228Z] --- [32mPASS[0m: non-exclusive-test-bucket-0/ext.config.rpm-ostree.container-deps (1.74s)
00:20:51 [2025-03-28T00:20:51.228Z] --- [32mPASS[0m: non-exclusive-test-bucket-0/ext.config.files.license (0.73s)
00:20:51 [2025-03-28T00:20:51.228Z] --- [32mPASS[0m: non-exclusive-test-bucket-0/ext.config.files.aleph-version (0.70s)
00:20:51 [2025-03-28T00:20:51.228Z] --- [32mPASS[0m: non-exclusive-test-bucket-0/ext.config.files.setgid (1.67s)
00:20:51 [2025-03-28T00:20:51.228Z] --- [32mPASS[0m: non-exclusive-test-bucket-0/ext.config.files.etc-permissions (1.64s)
00:20:51 [2025-03-28T00:20:51.228Z] --- [32mPASS[0m: non-exclusive-test-bucket-0/ext.config.disks.tmpfs (0.75s)
00:20:51 [2025-03-28T00:20:51.228Z] --- [32mPASS[0m: non-exclusive-test-bucket-0/ext.config.files.file-directory-permissions (1.74s)
00:20:51 [2025-03-28T00:20:51.228Z] --- [32mPASS[0m: non-exclusive-test-bucket-0/ext.config.kubernetes.kube-watch (2.67s)
00:20:51 [2025-03-28T00:20:51.228Z] --- [32mPASS[0m: non-exclusive-test-bucket-0/ext.config.disks.partition-scheme (1.73s)
00:20:51 [2025-03-28T00:20:51.228Z] --- [32mPASS[0m: non-exclusive-test-bucket-0/ext.config.boot.bootupd (1.66s)
00:20:51 [2025-03-28T00:20:51.228Z] --- [32mPASS[0m: non-exclusive-test-bucket-0/ext.config.files.initrd.executables (3.62s)
00:20:51 [2025-03-28T00:20:51.228Z] --- [32mPASS[0m: non-exclusive-test-bucket-0/ext.config.systemd.no-systemd-firstboot (1.65s)
00:20:51 [2025-03-28T00:20:51.228Z] --- [32mPASS[0m: non-exclusive-test-bucket-0/ext.config.clhm.network-device-info (0.66s)
00:20:51 [2025-03-28T00:20:51.228Z] --- [32mPASS[0m: non-exclusive-test-bucket-0/ext.config.logging.printk (1.63s)
00:20:51 [2025-03-28T00:20:51.228Z] --- [32mPASS[0m: non-exclusive-test-bucket-0/ext.config.composefs.enabled (1.63s)
00:20:51 [2025-03-28T00:20:51.228Z] --- [32mPASS[0m: non-exclusive-test-bucket-0/ext.config.ntp.chrony.coreos-platform-chrony-config (1.64s)
00:20:51 [2025-03-28T00:20:51.228Z] --- [32mPASS[0m: non-exclusive-test-bucket-0/ext.config.files.logrotate-service (1.64s)
00:20:51 [2025-03-28T00:20:51.228Z] --- [32mPASS[0m: non-exclusive-test-bucket-0/ext.config.networking.no-default-initramfs-net-propagation.default (1.62s)
00:20:51 [2025-03-28T00:20:51.228Z] --- [32mPASS[0m: non-exclusive-test-bucket-0/ext.config.networking.nm-start (1.62s)
00:20:51 [2025-03-28T00:20:51.228Z] --- [32mPASS[0m: non-exclusive-test-bucket-0/ext.config.selinux.stub-resolve-context (0.62s)
00:20:51 [2025-03-28T00:20:51.228Z] --- [32mPASS[0m: non-exclusive-test-bucket-0/ext.config.files.amd-ucode-firmware (0.63s)
00:20:51 [2025-03-28T00:20:51.228Z] --- [32mPASS[0m: non-exclusive-test-bucket-0/ext.config.podman.rootless-pasta-networking (5.67s)
00:20:51 [2025-03-28T00:20:51.228Z] --- [32mPASS[0m: non-exclusive-test-bucket-0/ext.config.networking.default-network-behavior-change (1.64s)
00:20:51 [2025-03-28T00:20:51.228Z] --- [32mPASS[0m: non-exclusive-test-bucket-0/ext.config.files.fwupd-refresh-timer (1.73s)
00:20:51 [2025-03-28T00:20:51.228Z] --- [32mPASS[0m: non-exclusive-test-bucket-0/ext.config.files.initrd.expected-contents (3.70s)
00:20:51 [2025-03-28T00:20:51.228Z] --- [32mPASS[0m: non-exclusive-test-bucket-0/ext.config.files.root-immutable-bit (0.64s)
00:20:51 [2025-03-28T00:20:51.228Z] --- [32mPASS[0m: non-exclusive-test-bucket-0/ext.config.files.setuid (1.64s)
00:20:51 [2025-03-28T00:20:51.228Z] --- [32mPASS[0m: non-exclusive-test-bucket-0/ext.config.swap.zram-default (0.65s)
00:20:51 [2025-03-28T00:20:51.228Z] --- [32mPASS[0m: non-exclusive-test-bucket-0/ext.config.kubernetes.systemd-env-read (1.65s)
00:20:51 [2025-03-28T00:20:51.228Z] --- [32mPASS[0m: non-exclusive-test-bucket-0/ext.config.networking.resolv.systemd-resolved (1.63s)
00:20:51 [2025-03-28T00:20:51.228Z] --- [32mPASS[0m: non-exclusive-test-bucket-0/ext.config.files.remove-manifest-files (0.61s)
00:20:51 [2025-03-28T00:20:51.228Z] --- [32mPASS[0m: non-exclusive-test-bucket-0/ext.config.podman.dns (1.64s)
00:20:51 [2025-03-28T00:20:51.228Z] --- [32mPASS[0m: non-exclusive-test-bucket-0/ext.config.files.yum-repo-dir (0.65s)
00:20:51 [2025-03-28T00:20:51.228Z] --- [32mPASS[0m: non-exclusive-test-bucket-0/ext.config.security.coreos-update-ca-trust (1.64s)
00:20:51 [2025-03-28T00:20:51.228Z] --- [32mPASS[0m: non-exclusive-test-bucket-0/ext.config.files.console-config (1.63s)
00:20:51 [2025-03-28T00:20:51.228Z] --- [32mPASS[0m: non-exclusive-test-bucket-0/ext.config.security.passwd (2.67s)
00:20:51 [2025-03-28T00:20:51.228Z] --- [32mPASS[0m: non-exclusive-test-bucket-0/ext.config.selinux.usrlocal-context (1.64s)
00:20:51 [2025-03-28T00:20:51.228Z] --- [32mPASS[0m: non-exclusive-test-bucket-0/ext.config.networking.nm-ifcfg-rh-plugin (1.62s)
00:20:51 [2025-03-28T00:20:51.228Z] --- [32mPASS[0m: non-exclusive-test-bucket-0/ext.config.disks.systemd-repart-service (1.65s)
00:20:51 [2025-03-28T00:20:51.228Z] --- [32mPASS[0m: non-exclusive-test-bucket-0/ext.config.networking.dnsmasq-service (0.65s)
00:20:51 [2025-03-28T00:20:51.228Z] --- [32mPASS[0m: non-exclusive-test-bucket-0/ext.config.files.root-bash (0.64s)
00:20:51 [2025-03-28T00:20:51.228Z] --- [32mPASS[0m: non-exclusive-test-bucket-0/ext.config.disks.growfs (0.61s)
00:20:51 [2025-03-28T00:20:51.228Z] --- [32mPASS[0m: non-exclusive-test-bucket-0/ext.config.systemd.default-unit-timeouts (1.69s)
00:20:51 [2025-03-28T00:20:51.228Z] --- [32mPASS[0m: non-exclusive-test-bucket-0/ext.config.content-origins (1.72s)
00:20:51 [2025-03-28T00:20:51.228Z] --- [32mPASS[0m: non-exclusive-test-bucket-0/ext.config.disks.root-prjquota (1.67s)
00:20:51 [2025-03-28T00:20:51.228Z] --- [32mPASS[0m: non-exclusive-test-bucket-0/ext.config.networking.nm-dhcp-client (1.65s)
00:20:51 [2025-03-28T00:20:51.228Z] --- [32mPASS[0m: non-exclusive-test-bucket-0/ext.config.networking.nic-naming (0.68s)
00:20:51 [2025-03-28T00:20:51.228Z] --- [32mPASS[0m: non-exclusive-test-bucket-0/ext.config.files.check-symlink (1.62s)
00:20:51 [2025-03-28T00:20:51.228Z] --- [32mPASS[0m: non-exclusive-test-bucket-0/ext.config.files.kernel-headers (0.67s)
00:20:51 [2025-03-28T00:20:51.228Z] --- [32mPASS[0m: non-exclusive-test-bucket-0/ext.config.disks.no-google-device-links (1.62s)
00:20:53 [2025-03-28T00:20:53.727Z] --- [32mPASS[0m: rpmostree.upgrade-rollback (96.01s)
00:20:53 [2025-03-28T00:20:53.727Z] --- [32mPASS[0m: rpmostree.upgrade-rollback/upgrade (37.31s)
00:20:53 [2025-03-28T00:20:53.727Z] --- [32mPASS[0m: rpmostree.upgrade-rollback/rollback (22.26s)
00:21:15 [2025-03-28T00:21:15.616Z] --- [32mPASS[0m: ext.config.firewall.iptables-legacy (38.79s)
00:21:25 [2025-03-28T00:21:25.829Z] --- [32mPASS[0m: rhcos.selinux.boolean.persist (60.64s)
00:21:27 [2025-03-28T00:21:27.182Z] --- [32mPASS[0m: fcos.users.shells (35.80s)
00:22:05 [2025-03-28T00:22:05.825Z] --- [32mPASS[0m: coreos.ignition.sethostname (36.17s)
00:22:05 [2025-03-28T00:22:05.825Z] --- [32mPASS[0m: ext.config.ignition.systemd-unmasking (38.72s)
00:22:08 [2025-03-28T00:22:08.325Z] --- [32mPASS[0m: multipath.partition (55.27s)
00:22:26 [2025-03-28T00:22:26.346Z] --- [32mPASS[0m: ext.config.ignition.delete-config (92.59s)
00:22:36 [2025-03-28T00:22:36.269Z] --- [32mPASS[0m: ext.config.clhm.ignition-warnings (26.97s)
00:22:36 [2025-03-28T00:22:36.523Z] === RUN ostree.unlock/unlock
00:22:36 [2025-03-28T00:22:36.780Z] --- [32mPASS[0m: podman.network-single (180.14s)
00:22:37 [2025-03-28T00:22:37.335Z] === RUN ostree.unlock/install
00:22:37 [2025-03-28T00:22:37.901Z] === RUN ostree.unlock/uninstall
00:22:38 [2025-03-28T00:22:38.154Z] === RUN ostree.unlock/discard
00:22:56 [2025-03-28T00:22:56.181Z] --- [32mPASS[0m: ext.config.gshadow (26.90s)
00:22:58 [2025-03-28T00:22:58.696Z] --- [32mPASS[0m: coreos.ignition.mount.partitions (55.24s)
00:23:00 [2025-03-28T00:23:00.571Z] --- [32mPASS[0m: ostree.unlock (56.17s)
00:23:00 [2025-03-28T00:23:00.571Z] --- [32mPASS[0m: ostree.unlock/unlock (0.61s)
00:23:00 [2025-03-28T00:23:00.571Z] --- [32mPASS[0m: ostree.unlock/install (0.60s)
00:23:00 [2025-03-28T00:23:00.571Z] --- [32mPASS[0m: ostree.unlock/uninstall (0.45s)
00:23:00 [2025-03-28T00:23:00.571Z] --- [32mPASS[0m: ostree.unlock/discard (18.65s)
00:23:01 [2025-03-28T00:23:01.924Z] --- [32mPASS[0m: coreos.auth.verify (24.78s)
00:23:05 [2025-03-28T00:23:05.184Z] --- [32mPASS[0m: ext.config.networking.no-default-initramfs-net-propagation.bootif (29.69s)
00:23:31 [2025-03-28T00:23:31.662Z] --- [32mPASS[0m: ext.config.boot.grub2-install (26.68s)
00:23:31 [2025-03-28T00:23:31.662Z] --- [32mPASS[0m: ext.config.ignition.systemd-enable-units (30.27s)
00:23:33 [2025-03-28T00:23:33.015Z] === RUN podman.workflow/run
00:23:47 [2025-03-28T00:23:47.851Z] --- [32mPASS[0m: ext.config.ssh.custom-host-key-permissions (41.47s)
00:23:57 [2025-03-28T00:23:57.774Z] --- [32mPASS[0m: ext.config.butane.grub-users (62.95s)
00:24:07 [2025-03-28T00:24:07.708Z] --- [32mPASS[0m: ext.config.systemd.network-online (38.62s)
00:24:12 [2025-03-28T00:24:12.939Z] --- [32mPASS[0m: ext.config.var-mount.scsi-id (43.84s)
00:24:39 [2025-03-28T00:24:39.406Z] --- [32mPASS[0m: ext.config.networking.nameserver (29.53s)
00:24:51 [2025-03-28T00:24:51.565Z] --- [32mPASS[0m: ext.config.rpm-ostree-countme (39.29s)
00:24:52 [2025-03-28T00:24:52.919Z] --- [32mPASS[0m: ext.config.reboot (56.64s)
00:24:54 [2025-03-28T00:24:54.810Z] --- [32mPASS[0m: ext.config.podman.rootless-systemd (68.29s)
00:25:21 [2025-03-28T00:25:21.275Z] --- [32mPASS[0m: ext.config.networking.mtu-on-bond-kargs (41.07s)
00:25:25 [2025-03-28T00:25:25.419Z] === RUN basic/FCOSGrowpart
00:25:25 [2025-03-28T00:25:25.419Z] === RUN basic/PortSSH
00:25:25 [2025-03-28T00:25:25.419Z] === RUN basic/DbusPerms
00:25:25 [2025-03-28T00:25:25.687Z] === RUN basic/ServicesActive
00:25:25 [2025-03-28T00:25:25.687Z] === RUN basic/ReadOnly
00:25:25 [2025-03-28T00:25:25.940Z] === RUN basic/Useradd
00:25:26 [2025-03-28T00:25:26.193Z] === RUN basic/MachineID
00:25:29 [2025-03-28T00:25:29.452Z] --- [32mPASS[0m: basic (38.11s)
00:25:29 [2025-03-28T00:25:29.452Z] --- [32mPASS[0m: basic/FCOSGrowpart (0.16s)
00:25:29 [2025-03-28T00:25:29.452Z] --- [32mPASS[0m: basic/PortSSH (0.12s)
00:25:29 [2025-03-28T00:25:29.452Z] --- [32mPASS[0m: basic/DbusPerms (0.37s)
00:25:29 [2025-03-28T00:25:29.452Z] --- [32mPASS[0m: basic/ServicesActive (0.14s)
00:25:29 [2025-03-28T00:25:29.452Z] --- [32mPASS[0m: basic/ReadOnly (0.13s)
00:25:29 [2025-03-28T00:25:29.452Z] --- [32mPASS[0m: basic/Useradd (0.20s)
00:25:29 [2025-03-28T00:25:29.452Z] --- [32mPASS[0m: basic/MachineID (0.12s)
00:25:37 [2025-03-28T00:25:37.525Z] --- [32mPASS[0m: coreos.ignition.once (42.92s)
00:25:47 [2025-03-28T00:25:47.464Z] --- [32mPASS[0m: coreos.ignition.failure (16.77s)
00:25:47 [2025-03-28T00:25:47.718Z] --- [32mPASS[0m: coreos.ignition.mount.disks (54.91s)
00:26:02 [2025-03-28T00:26:02.547Z] --- [32mPASS[0m: ext.config.docker.basic (43.02s)
00:26:10 [2025-03-28T00:26:10.600Z] === RUN basic.nvme/FCOSGrowpart
00:26:10 [2025-03-28T00:26:10.854Z] === RUN basic.nvme/PortSSH
00:26:11 [2025-03-28T00:26:11.107Z] === RUN basic.nvme/DbusPerms
00:26:11 [2025-03-28T00:26:11.374Z] === RUN basic.nvme/ServicesActive
00:26:11 [2025-03-28T00:26:11.374Z] === RUN basic.nvme/ReadOnly
00:26:11 [2025-03-28T00:26:11.627Z] === RUN basic.nvme/Useradd
00:26:11 [2025-03-28T00:26:11.879Z] === RUN basic.nvme/MachineID
00:26:15 [2025-03-28T00:26:15.125Z] --- [32mPASS[0m: basic.nvme (37.70s)
00:26:15 [2025-03-28T00:26:15.125Z] --- [32mPASS[0m: basic.nvme/FCOSGrowpart (0.15s)
00:26:15 [2025-03-28T00:26:15.125Z] --- [32mPASS[0m: basic.nvme/PortSSH (0.12s)
00:26:15 [2025-03-28T00:26:15.125Z] --- [32mPASS[0m: basic.nvme/DbusPerms (0.34s)
00:26:15 [2025-03-28T00:26:15.125Z] --- [32mPASS[0m: basic.nvme/ServicesActive (0.13s)
00:26:15 [2025-03-28T00:26:15.125Z] --- [32mPASS[0m: basic.nvme/ReadOnly (0.12s)
00:26:15 [2025-03-28T00:26:15.125Z] --- [32mPASS[0m: basic.nvme/Useradd (0.20s)
00:26:15 [2025-03-28T00:26:15.125Z] --- [32mPASS[0m: basic.nvme/MachineID (0.12s)
00:26:33 [2025-03-28T00:26:33.144Z] --- [32mPASS[0m: coreos.unique.boot.ignition.failure (15.22s)
00:26:43 [2025-03-28T00:26:43.073Z] --- [32mPASS[0m: ext.config.selinux.enforcing (55.11s)
00:27:04 [2025-03-28T00:27:04.960Z] --- [32mPASS[0m: ext.config.ntp.timesyncd.dhcp-propagation (75.32s)
00:27:19 [2025-03-28T00:27:19.781Z] --- [32mPASS[0m: rpmostree.status (37.43s)
00:27:25 [2025-03-28T00:27:25.017Z] --- [32mPASS[0m: multipath.day2 (83.30s)
00:27:27 [2025-03-28T00:27:27.578Z] --- [32mPASS[0m: ext.config.var-mount.simple (56.71s)
00:27:59 [2025-03-28T00:27:59.587Z] === RUN basic.uefi/PortSSH
00:27:59 [2025-03-28T00:27:59.587Z] === RUN basic.uefi/DbusPerms
00:27:59 [2025-03-28T00:27:59.587Z] === RUN basic.uefi/ServicesActive
00:27:59 [2025-03-28T00:27:59.587Z] === RUN basic.uefi/ReadOnly
00:27:59 [2025-03-28T00:27:59.587Z] === RUN basic.uefi/Useradd
00:27:59 [2025-03-28T00:27:59.587Z] === RUN basic.uefi/MachineID
00:27:59 [2025-03-28T00:27:59.587Z] === RUN basic.uefi/FCOSGrowpart
00:28:02 [2025-03-28T00:28:02.850Z] --- [32mPASS[0m: basic.uefi (38.79s)
00:28:02 [2025-03-28T00:28:02.850Z] --- [32mPASS[0m: basic.uefi/PortSSH (0.12s)
00:28:02 [2025-03-28T00:28:02.850Z] --- [32mPASS[0m: basic.uefi/DbusPerms (0.35s)
00:28:02 [2025-03-28T00:28:02.850Z] --- [32mPASS[0m: basic.uefi/ServicesActive (0.13s)
00:28:02 [2025-03-28T00:28:02.850Z] --- [32mPASS[0m: basic.uefi/ReadOnly (0.11s)
00:28:02 [2025-03-28T00:28:02.850Z] --- [32mPASS[0m: basic.uefi/Useradd (0.21s)
00:28:02 [2025-03-28T00:28:02.850Z] --- [32mPASS[0m: basic.uefi/MachineID (0.11s)
00:28:02 [2025-03-28T00:28:02.850Z] --- [32mPASS[0m: basic.uefi/FCOSGrowpart (0.15s)
00:28:03 [2025-03-28T00:28:03.103Z] --- [32mPASS[0m: ext.config.networking.ifname-karg.everyboot-systemd-link-file (60.04s)
00:28:15 [2025-03-28T00:28:15.267Z] --- [32mPASS[0m: multipath.day1 (56.39s)
00:28:41 [2025-03-28T00:28:41.741Z] === RUN podman.workflow/exec
00:28:41 [2025-03-28T00:28:41.741Z] === RUN podman.workflow/stop
00:28:51 [2025-03-28T00:28:51.659Z] === RUN podman.workflow/remove
00:28:51 [2025-03-28T00:28:51.659Z] === RUN podman.workflow/delete
00:28:55 [2025-03-28T00:28:55.821Z] --- [32mPASS[0m: podman.workflow (355.68s)
00:28:55 [2025-03-28T00:28:55.821Z] --- [32mPASS[0m: podman.workflow/run (307.29s)
00:28:55 [2025-03-28T00:28:55.821Z] cluster.go:151: Trying to pull quay.io/fedora/fedora:latest...
00:28:55 [2025-03-28T00:28:55.821Z] cluster.go:151: Getting image source signatures
00:28:55 [2025-03-28T00:28:55.821Z] cluster.go:151: Copying blob sha256:797a0cd5771f5ad11c70304d62f634eaa866679ad9b9b58dcf9746b1f895d742
00:28:55 [2025-03-28T00:28:55.821Z] cluster.go:151: Copying config sha256:45f699910992976ce3f9165b914e681d222be989185ebf1f933fc1d678f1e47f
00:28:55 [2025-03-28T00:28:55.821Z] cluster.go:151: Writing manifest to image destination
00:28:55 [2025-03-28T00:28:55.821Z] --- [32mPASS[0m: podman.workflow/exec (0.34s)
00:28:55 [2025-03-28T00:28:55.821Z] --- [32mPASS[0m: podman.workflow/stop (10.67s)
00:28:55 [2025-03-28T00:28:55.821Z] --- [32mPASS[0m: podman.workflow/remove (0.56s)
00:28:55 [2025-03-28T00:28:55.821Z] --- [32mPASS[0m: podman.workflow/delete (0.70s)
00:28:55 [2025-03-28T00:28:55.821Z] cluster.go:151: grep: quay.io/fedora/fedora:latest: No such file or directory
00:28:55 [2025-03-28T00:28:55.821Z] cluster.go:151: bash: line 2: Deleted:: command not found
00:28:55 [2025-03-28T00:28:55.821Z] FAIL, output in /home/jenkins/agent/workspace/test-override/tmp/kola-Wwbks/kola-x86_64
00:28:55 [2025-03-28T00:28:55.821Z]
00:28:55 [2025-03-28T00:28:55.821Z]
00:28:55 [2025-03-28T00:28:55.821Z] ======== Re-running failed tests (flake detection) ========
00:28:55 [2025-03-28T00:28:55.821Z]
00:28:56 [2025-03-28T00:28:56.074Z] === RUN ext.config.toolbox
00:30:03 [2025-03-28T00:30:03.694Z] systemctl status kola-runext.service:
00:30:03 [2025-03-28T00:30:03.694Z] �� kola-runext.service
00:30:03 [2025-03-28T00:30:03.694Z] Loaded: loaded (/etc/systemd/system/kola-runext.service; static)
00:30:03 [2025-03-28T00:30:03.694Z] Drop-In: /usr/lib/systemd/system/service.d
00:30:03 [2025-03-28T00:30:03.694Z] ������10-timeout-abort.conf, 50-keep-warm.conf
00:30:03 [2025-03-28T00:30:03.694Z] Active: failed (Result: exit-code) since Fri 2025-03-28 00:30:00 UTC; 941ms ago
00:30:03 [2025-03-28T00:30:03.694Z] Duration: 42.250s
00:30:03 [2025-03-28T00:30:03.694Z] Invocation: bd97ac573f714608bc736fb429d72dda
00:30:03 [2025-03-28T00:30:03.694Z] Process: 2288 ExecStart=/usr/local/bin/kola-runext-test.sh (code=exited, status=1/FAILURE)
00:30:03 [2025-03-28T00:30:03.694Z] Main PID: 2288 (code=exited, status=1/FAILURE)
00:30:03 [2025-03-28T00:30:03.694Z] Mem peak: 2.5M
00:30:03 [2025-03-28T00:30:03.694Z] CPU: 51ms
00:30:03 [2025-03-28T00:30:03.694Z]
00:30:03 [2025-03-28T00:30:03.694Z] Mar 28 00:29:18 qemu0 systemd[1]: Started kola-runext.service.
00:30:03 [2025-03-28T00:30:03.694Z] Mar 28 00:29:18 qemu0 kola-runext-test.sh[2288]: + . /var/opt/kola/extdata/commonlib.sh
00:30:03 [2025-03-28T00:30:03.694Z] Mar 28 00:29:18 qemu0 kola-runext-test.sh[2288]: ++ IFS=' '
00:30:03 [2025-03-28T00:30:03.694Z] Mar 28 00:29:18 qemu0 kola-runext-test.sh[2288]: ++ read -r -a cmdline
00:30:03 [2025-03-28T00:30:03.694Z] Mar 28 00:29:18 qemu0 kola-runext-test.sh[2289]: ++ seq 1 5
00:30:03 [2025-03-28T00:30:03.694Z] Mar 28 00:29:18 qemu0 kola-runext-test.sh[2288]: + for i in $(seq 1 5)
00:30:03 [2025-03-28T00:30:03.694Z] Mar 28 00:29:18 qemu0 kola-runext-test.sh[2288]: + machinectl shell core@ /bin/toolbox create --assumeyes
00:30:03 [2025-03-28T00:30:03.694Z] Mar 28 00:29:18 qemu0 machinectl[2290]: Connected to the local host. Press ^] three times within 1s to exit session.
00:30:03 [2025-03-28T00:30:03.694Z] Mar 28 00:29:59 qemu0 machinectl[2290]: Connection to the local host terminated.
00:30:03 [2025-03-28T00:30:03.694Z] Mar 28 00:29:59 qemu0 kola-runext-test.sh[2652]: ++ machinectl shell core@ /bin/toolbox list --containers
00:30:03 [2025-03-28T00:30:03.694Z] Mar 28 00:29:59 qemu0 kola-runext-test.sh[2654]: ++ grep --count fedora-toolbox-
00:30:03 [2025-03-28T00:30:03.694Z] Mar 28 00:29:59 qemu0 machinectl[2652]: Connected to the local host. Press ^] three times within 1s to exit session.
00:30:03 [2025-03-28T00:30:03.694Z] Mar 28 00:29:59 qemu0 machinectl[2652]: Connection to the local host terminated.
00:30:03 [2025-03-28T00:30:03.694Z] Mar 28 00:29:59 qemu0 kola-runext-test.sh[2288]: + [[ 1 -ne 1 ]]
00:30:03 [2025-03-28T00:30:03.694Z] Mar 28 00:29:59 qemu0 kola-runext-test.sh[2288]: + break
00:30:03 [2025-03-28T00:30:03.694Z] Mar 28 00:29:59 qemu0 kola-runext-test.sh[2677]: ++ machinectl shell core@ /bin/toolbox list --containers
00:30:03 [2025-03-28T00:30:03.694Z] Mar 28 00:29:59 qemu0 kola-runext-test.sh[2679]: ++ grep --count fedora-toolbox-
00:30:03 [2025-03-28T00:30:03.694Z] Mar 28 00:29:59 qemu0 machinectl[2677]: Connected to the local host. Press ^] three times within 1s to exit session.
00:30:03 [2025-03-28T00:30:03.694Z] Mar 28 00:30:00 qemu0 machinectl[2677]: Connection to the local host terminated.
00:30:03 [2025-03-28T00:30:03.694Z] Mar 28 00:30:00 qemu0 kola-runext-test.sh[2288]: + [[ 1 -ne 1 ]]
00:30:03 [2025-03-28T00:30:03.694Z] Mar 28 00:30:00 qemu0 kola-runext-test.sh[2288]: + ok toolbox create
00:30:03 [2025-03-28T00:30:03.694Z] Mar 28 00:30:00 qemu0 kola-runext-test.sh[2288]: + echo ok toolbox create
00:30:03 [2025-03-28T00:30:03.694Z] Mar 28 00:30:00 qemu0 kola-runext-test.sh[2288]: ok toolbox create
00:30:03 [2025-03-28T00:30:03.694Z] Mar 28 00:30:00 qemu0 kola-runext-test.sh[2288]: + machinectl shell core@ /bin/toolbox run touch ok_toolbox
00:30:03 [2025-03-28T00:30:03.694Z] Mar 28 00:30:00 qemu0 machinectl[2701]: Connected to the local host. Press ^] three times within 1s to exit session.
00:30:03 [2025-03-28T00:30:03.694Z] Mar 28 00:30:00 qemu0 kola-runext-test.sh[2701]: Error: failed to invoke command touch in container fedora-toolbox-43
00:30:03 [2025-03-28T00:30:03.694Z] Mar 28 00:30:00 qemu0 machinectl[2701]: Connection to the local host terminated.
00:30:03 [2025-03-28T00:30:03.694Z] Mar 28 00:30:00 qemu0 kola-runext-test.sh[2288]: + [[ ! -f /home/core/ok_toolbox ]]
00:30:03 [2025-03-28T00:30:03.694Z] Mar 28 00:30:00 qemu0 kola-runext-test.sh[2288]: + fatal 'Could not run a simple command inside a toolbox'
00:30:03 [2025-03-28T00:30:03.694Z] Mar 28 00:30:00 qemu0 kola-runext-test.sh[2288]: + echo 'Could not run a simple command inside a toolbox'
00:30:03 [2025-03-28T00:30:03.694Z] Mar 28 00:30:00 qemu0 kola-runext-test.sh[2288]: Could not run a simple command inside a toolbox
00:30:03 [2025-03-28T00:30:03.694Z] Mar 28 00:30:00 qemu0 kola-runext-test.sh[2288]: + exit 1
00:30:03 [2025-03-28T00:30:03.694Z] Mar 28 00:30:00 qemu0 systemd[1]: kola-runext.service: Main process exited, code=exited, status=1/FAILURE
00:30:03 [2025-03-28T00:30:03.694Z] Mar 28 00:30:00 qemu0 systemd[1]: kola-runext.service: Failed with result 'exit-code'.
00:30:05 [2025-03-28T00:30:05.569Z] --- [31mFAIL[0m: ext.config.toolbox (69.51s)
00:30:05 [2025-03-28T00:30:05.569Z] cluster.go:151: Error: Unit kola-runext.service exited with code 1
00:30:05 [2025-03-28T00:30:05.569Z] cluster.go:151: 2025-03-28T00:30:01Z cli: Unit kola-runext.service exited with code 1
00:30:05 [2025-03-28T00:30:05.569Z] harness.go:1260: kolet failed: : kolet run-test-unit failed: Process exited with status 1
00:30:05 [2025-03-28T00:30:05.569Z] FAIL, output in /home/jenkins/agent/workspace/test-override/tmp/kola-Wwbks/kola-x86_64/rerun
00:30:05 [2025-03-28T00:30:05.569Z] Error: harness: test suite failed
00:30:05 [2025-03-28T00:30:05.569Z] 2025-03-28T00:30:05Z cli: harness: test suite failed
00:30:05 [2025-03-28T00:30:05.569Z] failed to execute cmd-kola: exit status 1
00:30:05 [2025-03-28T00:30:05.573Z] [Pipeline] }
00:30:05 [2025-03-28T00:30:05.576Z] [Pipeline] // withEnv
00:30:05 [2025-03-28T00:30:05.638Z] [Pipeline] error
00:30:05 [2025-03-28T00:30:05.639Z] [Pipeline] }
00:30:05 [2025-03-28T00:30:05.639Z] Failed in branch x86_64:kola
00:30:05 [2025-03-28T00:30:05.656Z] [Pipeline] // parallel
00:30:05 [2025-03-28T00:30:05.698Z] [Pipeline] withEnv
00:30:05 [2025-03-28T00:30:05.698Z] [Pipeline] {
00:30:05 [2025-03-28T00:30:05.702Z] [Pipeline] sh
00:30:06 [2025-03-28T00:30:06.297Z] + set -xeuo pipefail
00:30:06 [2025-03-28T00:30:06.297Z] ++ umask
00:30:06 [2025-03-28T00:30:06.297Z] + '[' 0022 = 0000 ']'
00:30:06 [2025-03-28T00:30:06.297Z] + cd /home/jenkins/agent/workspace/test-override
00:30:06 [2025-03-28T00:30:06.297Z] + cosa shell -- test -d /home/jenkins/agent/workspace/test-override/tmp/kola-Wwbks/kola-upgrade-x86_64
00:30:06 [2025-03-28T00:30:06.298Z] [Pipeline] }
00:30:06 [2025-03-28T00:30:06.302Z] [Pipeline] // withEnv
00:30:06 [2025-03-28T00:30:06.308Z] [Pipeline] withEnv
00:30:06 [2025-03-28T00:30:06.308Z] [Pipeline] {
00:30:06 [2025-03-28T00:30:06.355Z] [Pipeline] sh
00:30:06 [2025-03-28T00:30:06.880Z] + set -xeuo pipefail
00:30:06 [2025-03-28T00:30:06.881Z] ++ umask
00:30:06 [2025-03-28T00:30:06.881Z] + '[' 0022 = 0000 ']'
00:30:06 [2025-03-28T00:30:06.881Z] + cd /home/jenkins/agent/workspace/test-override
00:30:06 [2025-03-28T00:30:06.881Z] + cosa shell -- tar -C /home/jenkins/agent/workspace/test-override/tmp/kola-Wwbks -c --xz kola-upgrade-x86_64
00:30:07 [2025-03-28T00:30:07.150Z] [Pipeline] }
00:30:07 [2025-03-28T00:30:07.153Z] [Pipeline] // withEnv
00:30:07 [2025-03-28T00:30:07.159Z] [Pipeline] archiveArtifacts
00:30:07 [2025-03-28T00:30:07.162Z] Archiving artifacts
00:30:07 [2025-03-28T00:30:07.801Z] [Pipeline] withEnv
00:30:07 [2025-03-28T00:30:07.802Z] [Pipeline] {
00:30:07 [2025-03-28T00:30:07.806Z] [Pipeline] sh
00:30:08 [2025-03-28T00:30:08.342Z] + set -xeuo pipefail
00:30:08 [2025-03-28T00:30:08.342Z] ++ umask
00:30:08 [2025-03-28T00:30:08.342Z] + '[' 0022 = 0000 ']'
00:30:08 [2025-03-28T00:30:08.342Z] + cd /home/jenkins/agent/workspace/test-override
00:30:08 [2025-03-28T00:30:08.342Z] + cosa shell -- /usr/lib/coreos-assembler/kola-junit --classname kola-upgrade-x86_64 --koladir /home/jenkins/agent/workspace/test-override/tmp/kola-Wwbks/kola-upgrade-x86_64 --output -
00:30:08 [2025-03-28T00:30:08.344Z] [Pipeline] }
00:30:08 [2025-03-28T00:30:08.346Z] [Pipeline] // withEnv
00:30:08 [2025-03-28T00:30:08.362Z] [Pipeline] junit
00:30:08 [2025-03-28T00:30:08.363Z] Recording test results
00:30:09 [2025-03-28T00:30:09.060Z] None of the test reports contained any result
00:30:09 [2025-03-28T00:30:09.062Z] [Pipeline] withEnv
00:30:09 [2025-03-28T00:30:09.062Z] [Pipeline] {
00:30:09 [2025-03-28T00:30:09.065Z] [Pipeline] sh
00:30:09 [2025-03-28T00:30:09.617Z] + set -xeuo pipefail
00:30:09 [2025-03-28T00:30:09.617Z] ++ umask
00:30:09 [2025-03-28T00:30:09.617Z] + '[' 0022 = 0000 ']'
00:30:09 [2025-03-28T00:30:09.617Z] + cd /home/jenkins/agent/workspace/test-override
00:30:09 [2025-03-28T00:30:09.617Z] + cosa shell -- test -d /home/jenkins/agent/workspace/test-override/tmp/kola-Wwbks/kola-x86_64
00:30:09 [2025-03-28T00:30:09.619Z] [Pipeline] }
00:30:09 [2025-03-28T00:30:09.622Z] [Pipeline] // withEnv
00:30:09 [2025-03-28T00:30:09.635Z] [Pipeline] withEnv
00:30:09 [2025-03-28T00:30:09.636Z] [Pipeline] {
00:30:09 [2025-03-28T00:30:09.639Z] [Pipeline] sh
00:30:10 [2025-03-28T00:30:10.164Z] + set -xeuo pipefail
00:30:10 [2025-03-28T00:30:10.164Z] ++ umask
00:30:10 [2025-03-28T00:30:10.164Z] + '[' 0022 = 0000 ']'
00:30:10 [2025-03-28T00:30:10.164Z] + cd /home/jenkins/agent/workspace/test-override
00:30:10 [2025-03-28T00:30:10.164Z] + cosa shell -- tar -C /home/jenkins/agent/workspace/test-override/tmp/kola-Wwbks -c --xz kola-x86_64
00:30:16 [2025-03-28T00:30:16.685Z] [Pipeline] }
00:30:16 [2025-03-28T00:30:16.687Z] [Pipeline] // withEnv
00:30:16 [2025-03-28T00:30:16.725Z] [Pipeline] archiveArtifacts
00:30:16 [2025-03-28T00:30:16.727Z] Archiving artifacts
00:30:19 [2025-03-28T00:30:19.646Z] [Pipeline] withEnv
00:30:19 [2025-03-28T00:30:19.685Z] [Pipeline] {
00:30:19 [2025-03-28T00:30:19.689Z] [Pipeline] sh
00:30:20 [2025-03-28T00:30:20.223Z] + set -xeuo pipefail
00:30:20 [2025-03-28T00:30:20.223Z] ++ umask
00:30:20 [2025-03-28T00:30:20.223Z] + '[' 0022 = 0000 ']'
00:30:20 [2025-03-28T00:30:20.223Z] + cd /home/jenkins/agent/workspace/test-override
00:30:20 [2025-03-28T00:30:20.223Z] + cosa shell -- /usr/lib/coreos-assembler/kola-junit --classname kola-x86_64 --koladir /home/jenkins/agent/workspace/test-override/tmp/kola-Wwbks/kola-x86_64 --output -
00:30:20 [2025-03-28T00:30:20.225Z] [Pipeline] }
00:30:20 [2025-03-28T00:30:20.239Z] [Pipeline] // withEnv
00:30:20 [2025-03-28T00:30:20.244Z] [Pipeline] junit
00:30:20 [2025-03-28T00:30:20.245Z] Recording test results
00:30:20 [2025-03-28T00:30:20.473Z] [Pipeline] }
00:30:20 [2025-03-28T00:30:20.483Z] Failed in branch x86_64
00:30:20 [2025-03-28T00:30:20.487Z] [Pipeline] // parallel
00:30:20 [2025-03-28T00:30:20.573Z] [Pipeline] stage
00:30:20 [2025-03-28T00:30:20.573Z] [Pipeline] { (Report Completion)
00:30:20 [2025-03-28T00:30:20.587Z] [Pipeline] withCredentials
00:30:20 [2025-03-28T00:30:20.634Z] Masking supported pattern matches of $RDB_PASSWORD
00:30:20 [2025-03-28T00:30:20.634Z] [Pipeline] {
00:30:20 [2025-03-28T00:30:20.639Z] [Pipeline] withEnv
00:30:20 [2025-03-28T00:30:20.639Z] [Pipeline] {
00:30:20 [2025-03-28T00:30:20.669Z] [Pipeline] sh
00:30:21 [2025-03-28T00:30:21.211Z] + set -xeuo pipefail
00:30:21 [2025-03-28T00:30:21.211Z] ++ umask
00:30:21 [2025-03-28T00:30:21.211Z] + '[' 0022 = 0000 ']'
00:30:21 [2025-03-28T00:30:21.211Z] + /usr/lib/coreos-assembler/resultsdb-report --testcase cosa.build-and-test --testcase-url https://jenkins-coreos-ci.apps.ocp.fedoraproject.org//job/test-override --testrun-url https://jenkins-coreos-ci.apps.ocp.fedoraproject.org/job/test-override/1585/display/redirect --outcome FAILED --advisory FEDORA-2025-c5fdb0a0de --stream rawhide
00:30:21 [2025-03-28T00:30:21.770Z] [Pipeline] }
00:30:21 [2025-03-28T00:30:21.773Z] [Pipeline] // withEnv
00:30:21 [2025-03-28T00:30:21.826Z] [Pipeline] }
00:30:21 [2025-03-28T00:30:21.840Z] [Pipeline] // withCredentials
00:30:21 [2025-03-28T00:30:21.928Z] [Pipeline] withCredentials
00:30:22 [2025-03-28T00:30:22.000Z] Masking supported pattern matches of $TOKEN
00:30:22 [2025-03-28T00:30:22.000Z] [Pipeline] {
00:30:22 [2025-03-28T00:30:22.016Z] [Pipeline] withEnv
00:30:22 [2025-03-28T00:30:22.016Z] [Pipeline] {
00:30:22 [2025-03-28T00:30:22.066Z] [Pipeline] sh
00:30:22 [2025-03-28T00:30:22.592Z] + set -xeuo pipefail
00:30:22 [2025-03-28T00:30:22.592Z] ++ umask
00:30:22 [2025-03-28T00:30:22.592Z] + '[' 0022 = 0000 ']'
00:30:22 [2025-03-28T00:30:22.592Z] + curl -X POST -H 'Content-Type: application/json' -u **** https://maubot.apps.ocp.fedoraproject.org/plugin/nonbot-coreos/send --silent -d '
00:30:22 [2025-03-28T00:30:22.592Z] {
00:30:22 [2025-03-28T00:30:22.592Z] "body": "���� kernel-6.15.0-0.rc0.20250327git1a9239bb4253.5.fc43 - [����](https://jenkins-coreos-ci.apps.ocp.fedoraproject.org/job/test-override/1585/display/redirect) [����](https://bodhi.fedoraproject.org/updates/FEDORA-2025-c5fdb0a0de)"
00:30:22 [2025-03-28T00:30:22.592Z] }'
00:30:23 [2025-03-28T00:30:23.150Z] [Pipeline] }
00:30:23 [2025-03-28T00:30:23.153Z] [Pipeline] // withEnv
00:30:23 [2025-03-28T00:30:23.158Z] [Pipeline] }
00:30:23 [2025-03-28T00:30:23.170Z] [Pipeline] // withCredentials
00:30:23 [2025-03-28T00:30:23.230Z] [Pipeline] }
00:30:23 [2025-03-28T00:30:23.286Z] [Pipeline] // stage
00:30:23 [2025-03-28T00:30:23.290Z] [Pipeline] }
00:30:23 [2025-03-28T00:30:23.293Z] [Pipeline] // timeout
00:30:23 [2025-03-28T00:30:23.304Z] [Pipeline] }
00:30:23 [2025-03-28T00:30:23.307Z] [Pipeline] // container
00:30:23 [2025-03-28T00:30:23.311Z] [Pipeline] }
00:30:23 [2025-03-28T00:30:23.390Z] [Pipeline] // node
00:30:23 [2025-03-28T00:30:23.412Z] [Pipeline] }
00:30:23 [2025-03-28T00:30:23.445Z] [Pipeline] // podTemplate
00:30:23 [2025-03-28T00:30:23.475Z] [Pipeline] End of Pipeline
00:30:23 [2025-03-28T00:30:23.531Z] ERROR: Script returned exit code 1
00:30:23 [2025-03-28T00:30:23.657Z] Finished: FAILURE
![[Jenkins]](/static/1a3f9de9/images/svgs/logo.svg){kind=logo}
