output of second run of play kube with podman version 4.4.0-dev

gistfile1.txt

ok: [/home/rmeggins/.cache/linux-system-roles/rhel-9-2.qcow2] => {
    "actions": [
        "/usr/bin/podman play kube --start=true --log-level=debug /etc/containers/ansible-kubernetes.d/httpd2.yml"
    ],
    "changed": false
}

STDERR:

time="2023-02-07T16:16:47-05:00" level=info msg="/usr/bin/podman filtering at log level debug"
time="2023-02-07T16:16:47-05:00" level=debug msg="Called kube.PersistentPreRunE(/usr/bin/podman play kube --start=true --log-level=debug /etc/containers/ansible-kubernetes.d/httpd2.yml)"
time="2023-02-07T16:16:47-05:00" level=debug msg="Using conmon: \"/usr/bin/conmon\""
time="2023-02-07T16:16:47-05:00" level=debug msg="Initializing boltdb state at /var/lib/containers/storage/libpod/bolt_state.db"
time="2023-02-07T16:16:47-05:00" level=debug msg="Using graph driver overlay"
time="2023-02-07T16:16:47-05:00" level=debug msg="Using graph root /var/lib/containers/storage"
time="2023-02-07T16:16:47-05:00" level=debug msg="Using run root /run/containers/storage"
time="2023-02-07T16:16:47-05:00" level=debug msg="Using static dir /var/lib/containers/storage/libpod"
time="2023-02-07T16:16:47-05:00" level=debug msg="Using tmp dir /run/libpod"
time="2023-02-07T16:16:47-05:00" level=debug msg="Using volume path /var/lib/containers/storage/volumes"
time="2023-02-07T16:16:47-05:00" level=debug msg="Using transient store: false"
time="2023-02-07T16:16:47-05:00" level=debug msg="Set libpod namespace to \"\""
time="2023-02-07T16:16:47-05:00" level=debug msg="[graphdriver] trying provided driver \"overlay\""
time="2023-02-07T16:16:47-05:00" level=debug msg="Cached value indicated that overlay is supported"
time="2023-02-07T16:16:47-05:00" level=debug msg="Cached value indicated that overlay is supported"
time="2023-02-07T16:16:47-05:00" level=debug msg="Cached value indicated that metacopy is being used"
time="2023-02-07T16:16:47-05:00" level=debug msg="Cached value indicated that native-diff is not being used"
time="2023-02-07T16:16:47-05:00" 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"
time="2023-02-07T16:16:47-05:00" level=debug msg="backingFs=xfs, projectQuotaSupported=false, useNativeDiff=false, usingMetacopy=true"
time="2023-02-07T16:16:47-05:00" level=debug msg="Initializing event backend journald"
time="2023-02-07T16:16:47-05:00" level=debug msg="Configured OCI runtime runc initialization failed: no valid executable found for OCI runtime runc: invalid argument"
time="2023-02-07T16:16:47-05:00" level=debug msg="Configured OCI runtime runj initialization failed: no valid executable found for OCI runtime runj: invalid argument"
time="2023-02-07T16:16:47-05:00" level=debug msg="Configured OCI runtime crun-wasm initialization failed: no valid executable found for OCI runtime crun-wasm: invalid argument"
time="2023-02-07T16:16:47-05:00" level=debug msg="Configured OCI runtime youki initialization failed: no valid executable found for OCI runtime youki: invalid argument"
time="2023-02-07T16:16:47-05:00" level=debug msg="Configured OCI runtime krun initialization failed: no valid executable found for OCI runtime krun: invalid argument"
time="2023-02-07T16:16:47-05:00" level=debug msg="Configured OCI runtime ocijail initialization failed: no valid executable found for OCI runtime ocijail: invalid argument"
time="2023-02-07T16:16:47-05:00" level=debug msg="Configured OCI runtime kata initialization failed: no valid executable found for OCI runtime kata: invalid argument"
time="2023-02-07T16:16:47-05:00" level=debug msg="Configured OCI runtime runsc initialization failed: no valid executable found for OCI runtime runsc: invalid argument"
time="2023-02-07T16:16:47-05:00" level=debug msg="Using OCI runtime \"/usr/bin/crun\""
time="2023-02-07T16:16:47-05:00" level=info msg="Setting parallel job count to 16"
time="2023-02-07T16:16:47-05:00" level=debug msg="Successfully loaded network podman-default-kube-network: &{podman-default-kube-network 95185e221230cbc3be3ffaff295f7296e0aa0e5a64ef76d1ebe78e36db694a64 bridge podman1 2023-02-07 16:15:36.699173501 -0500 EST [{{{10.89.0.0 ffffff00}} 10.89.0.1 <nil>}] false false true [] map[] map[] map[driver:host-local]}"
time="2023-02-07T16:16:47-05:00" level=debug msg="Successfully loaded 2 networks"
time="2023-02-07T16:16:47-05:00" level=debug msg="Looking up image \"localhost/podman-pause:4.4.0-dev-1675096088\" in local containers storage"
time="2023-02-07T16:16:47-05:00" level=debug msg="Normalized platform linux/amd64 to {amd64 linux  [] }"
time="2023-02-07T16:16:47-05:00" level=debug msg="Trying \"localhost/podman-pause:4.4.0-dev-1675096088\" ..."
time="2023-02-07T16:16:47-05:00" level=debug msg="parsed reference into \"[overlay@/var/lib/containers/storage+/run/containers/storage:overlay.mountopt=nodev,metacopy=on]@91b7db1b48724b39856931a5c7d90cbbf0afd506e4474b163c08d4e6d53c6e33\""
time="2023-02-07T16:16:47-05:00" level=debug msg="Found image \"localhost/podman-pause:4.4.0-dev-1675096088\" as \"localhost/podman-pause:4.4.0-dev-1675096088\" in local containers storage"
time="2023-02-07T16:16:47-05:00" level=debug msg="Found image \"localhost/podman-pause:4.4.0-dev-1675096088\" as \"localhost/podman-pause:4.4.0-dev-1675096088\" in local containers storage ([overlay@/var/lib/containers/storage+/run/containers/storage:overlay.mountopt=nodev,metacopy=on]@91b7db1b48724b39856931a5c7d90cbbf0afd506e4474b163c08d4e6d53c6e33)"
time="2023-02-07T16:16:47-05:00" level=debug msg="exporting opaque data as blob \"sha256:91b7db1b48724b39856931a5c7d90cbbf0afd506e4474b163c08d4e6d53c6e33\""
time="2023-02-07T16:16:47-05:00" level=debug msg="Pod using bridge network mode"
time="2023-02-07T16:16:47-05:00" level=debug msg="Created cgroup path machine.slice/machine-libpod_pod_f6e7de5df4de861e1efbcf2d4c632d375bd061589faaee5f7d1b9ea64e51bf1e.slice for parent machine.slice and name libpod_pod_f6e7de5df4de861e1efbcf2d4c632d375bd061589faaee5f7d1b9ea64e51bf1e"
time="2023-02-07T16:16:47-05:00" level=debug msg="Created cgroup machine.slice/machine-libpod_pod_f6e7de5df4de861e1efbcf2d4c632d375bd061589faaee5f7d1b9ea64e51bf1e.slice"
time="2023-02-07T16:16:47-05:00" level=debug msg="Got pod cgroup as machine.slice/machine-libpod_pod_f6e7de5df4de861e1efbcf2d4c632d375bd061589faaee5f7d1b9ea64e51bf1e.slice"
time="2023-02-07T16:16:47-05:00" level=debug msg="Adding parallel job to stop container ce3c47080de6a3f820a13ce76ec2e2353e74710b28ad0674444dc26dba6b2589"
time="2023-02-07T16:16:47-05:00" level=debug msg="Adding parallel job to stop container f0d71da80e2ee430db1240d6d07cb4f39d2fc1e559846ae9a8b0c479b6c02e49"
time="2023-02-07T16:16:47-05:00" level=debug msg="Stopping ctr ce3c47080de6a3f820a13ce76ec2e2353e74710b28ad0674444dc26dba6b2589 (timeout 0)"
time="2023-02-07T16:16:47-05:00" level=debug msg="Stopping ctr f0d71da80e2ee430db1240d6d07cb4f39d2fc1e559846ae9a8b0c479b6c02e49 (timeout 0)"
time="2023-02-07T16:16:47-05:00" level=debug msg="Stopping container ce3c47080de6a3f820a13ce76ec2e2353e74710b28ad0674444dc26dba6b2589 (PID 22803)"
time="2023-02-07T16:16:47-05:00" level=debug msg="Sending signal 9 to container ce3c47080de6a3f820a13ce76ec2e2353e74710b28ad0674444dc26dba6b2589"
time="2023-02-07T16:16:47-05:00" level=debug msg="Stopping container f0d71da80e2ee430db1240d6d07cb4f39d2fc1e559846ae9a8b0c479b6c02e49 (PID 22808)"
time="2023-02-07T16:16:47-05:00" level=debug msg="Sending signal 9 to container f0d71da80e2ee430db1240d6d07cb4f39d2fc1e559846ae9a8b0c479b6c02e49"
time="2023-02-07T16:16:47-05:00" level=debug msg="Container \"ce3c47080de6a3f820a13ce76ec2e2353e74710b28ad0674444dc26dba6b2589\" state changed from \"stopping\" to \"stopped\" while waiting for it to be stopped: discontinuing stop procedure as another process interfered"
time="2023-02-07T16:16:47-05:00" level=debug msg="Cleaning up container ce3c47080de6a3f820a13ce76ec2e2353e74710b28ad0674444dc26dba6b2589"
time="2023-02-07T16:16:47-05:00" level=debug msg="Tearing down network namespace at /run/netns/netns-81df80b4-12e2-180d-35c1-180ef129fed4 for container ce3c47080de6a3f820a13ce76ec2e2353e74710b28ad0674444dc26dba6b2589"
[DEBUG netavark::commands::teardown] "Tearing down.."
[INFO  netavark::firewall] Using iptables firewall driver
time="2023-02-07T16:16:47-05:00" level=debug msg="Container \"f0d71da80e2ee430db1240d6d07cb4f39d2fc1e559846ae9a8b0c479b6c02e49\" state changed from \"stopping\" to \"stopped\" while waiting for it to be stopped: discontinuing stop procedure as another process interfered"
time="2023-02-07T16:16:47-05:00" level=debug msg="Cleaning up container f0d71da80e2ee430db1240d6d07cb4f39d2fc1e559846ae9a8b0c479b6c02e49"
time="2023-02-07T16:16:47-05:00" level=debug msg="Successfully cleaned up container f0d71da80e2ee430db1240d6d07cb4f39d2fc1e559846ae9a8b0c479b6c02e49"
time="2023-02-07T16:16:47-05:00" level=debug msg="Unmounted container \"f0d71da80e2ee430db1240d6d07cb4f39d2fc1e559846ae9a8b0c479b6c02e49\""
[DEBUG netavark::commands::teardown] "Teardown complete"
time="2023-02-07T16:16:47-05:00" level=debug msg="Successfully cleaned up container ce3c47080de6a3f820a13ce76ec2e2353e74710b28ad0674444dc26dba6b2589"
time="2023-02-07T16:16:47-05:00" level=debug msg="Unmounted container \"ce3c47080de6a3f820a13ce76ec2e2353e74710b28ad0674444dc26dba6b2589\""
time="2023-02-07T16:16:47-05:00" level=debug msg="Adding parallel job to stop container ce3c47080de6a3f820a13ce76ec2e2353e74710b28ad0674444dc26dba6b2589"
time="2023-02-07T16:16:47-05:00" level=debug msg="Adding parallel job to stop container f0d71da80e2ee430db1240d6d07cb4f39d2fc1e559846ae9a8b0c479b6c02e49"
time="2023-02-07T16:16:47-05:00" level=debug msg="Adding parallel job to stop container ce3c47080de6a3f820a13ce76ec2e2353e74710b28ad0674444dc26dba6b2589"
time="2023-02-07T16:16:47-05:00" level=debug msg="Adding parallel job to stop container f0d71da80e2ee430db1240d6d07cb4f39d2fc1e559846ae9a8b0c479b6c02e49"
time="2023-02-07T16:16:47-05:00" level=debug msg="Pod 13f04cf0df5fb5ae1cf630a42b5aaee4104682977fa74aa5d58b3e579838bcf8 has a service 25f97916dae1e276ea06ca3242642a9bf326873e17e8cdec9fe229e9224a48ff: checking if it can be stopped"
time="2023-02-07T16:16:47-05:00" level=debug msg="Strongconnecting node ce3c47080de6a3f820a13ce76ec2e2353e74710b28ad0674444dc26dba6b2589"
time="2023-02-07T16:16:47-05:00" level=debug msg="Pushed ce3c47080de6a3f820a13ce76ec2e2353e74710b28ad0674444dc26dba6b2589 onto stack"
time="2023-02-07T16:16:47-05:00" level=debug msg="Finishing node ce3c47080de6a3f820a13ce76ec2e2353e74710b28ad0674444dc26dba6b2589. Popped ce3c47080de6a3f820a13ce76ec2e2353e74710b28ad0674444dc26dba6b2589 off stack"
time="2023-02-07T16:16:47-05:00" level=debug msg="Strongconnecting node f0d71da80e2ee430db1240d6d07cb4f39d2fc1e559846ae9a8b0c479b6c02e49"
time="2023-02-07T16:16:47-05:00" level=debug msg="Pushed f0d71da80e2ee430db1240d6d07cb4f39d2fc1e559846ae9a8b0c479b6c02e49 onto stack"
time="2023-02-07T16:16:47-05:00" level=debug msg="Finishing node f0d71da80e2ee430db1240d6d07cb4f39d2fc1e559846ae9a8b0c479b6c02e49. Popped f0d71da80e2ee430db1240d6d07cb4f39d2fc1e559846ae9a8b0c479b6c02e49 off stack"
time="2023-02-07T16:16:47-05:00" level=debug msg="Removing container f0d71da80e2ee430db1240d6d07cb4f39d2fc1e559846ae9a8b0c479b6c02e49"
time="2023-02-07T16:16:47-05:00" level=debug msg="Cleaning up container f0d71da80e2ee430db1240d6d07cb4f39d2fc1e559846ae9a8b0c479b6c02e49"
time="2023-02-07T16:16:47-05:00" level=debug msg="Container f0d71da80e2ee430db1240d6d07cb4f39d2fc1e559846ae9a8b0c479b6c02e49 storage is already unmounted, skipping..."
time="2023-02-07T16:16:47-05:00" level=debug msg="Removing all exec sessions for container f0d71da80e2ee430db1240d6d07cb4f39d2fc1e559846ae9a8b0c479b6c02e49"
time="2023-02-07T16:16:47-05:00" level=debug msg="Container f0d71da80e2ee430db1240d6d07cb4f39d2fc1e559846ae9a8b0c479b6c02e49 storage is already unmounted, skipping..."
time="2023-02-07T16:16:47-05:00" level=debug msg="Removing container ce3c47080de6a3f820a13ce76ec2e2353e74710b28ad0674444dc26dba6b2589"
time="2023-02-07T16:16:47-05:00" level=debug msg="Cleaning up container ce3c47080de6a3f820a13ce76ec2e2353e74710b28ad0674444dc26dba6b2589"
time="2023-02-07T16:16:47-05:00" level=debug msg="Network is already cleaned up, skipping..."
time="2023-02-07T16:16:47-05:00" level=debug msg="Container ce3c47080de6a3f820a13ce76ec2e2353e74710b28ad0674444dc26dba6b2589 storage is already unmounted, skipping..."
time="2023-02-07T16:16:47-05:00" level=debug msg="Removing all exec sessions for container ce3c47080de6a3f820a13ce76ec2e2353e74710b28ad0674444dc26dba6b2589"
time="2023-02-07T16:16:47-05:00" level=debug msg="Container ce3c47080de6a3f820a13ce76ec2e2353e74710b28ad0674444dc26dba6b2589 storage is already unmounted, skipping..."
time="2023-02-07T16:16:47-05:00" level=debug msg="Removing pod cgroup machine.slice/machine-libpod_pod_13f04cf0df5fb5ae1cf630a42b5aaee4104682977fa74aa5d58b3e579838bcf8.slice"
time="2023-02-07T16:16:47-05:00" level=debug msg="Shutting down engines"
time="2023-02-07T16:16:47-05:00" level=debug msg="Stopping service container 25f97916dae1e276ea06ca3242642a9bf326873e17e8cdec9fe229e9224a48ff"
time="2023-02-07T16:16:47-05:00" level=debug msg="Stopping ctr 25f97916dae1e276ea06ca3242642a9bf326873e17e8cdec9fe229e9224a48ff (timeout 0)"
time="2023-02-07T16:16:47-05:00" level=debug msg="Stopping container 25f97916dae1e276ea06ca3242642a9bf326873e17e8cdec9fe229e9224a48ff (PID 22734)"
time="2023-02-07T16:16:47-05:00" level=debug msg="Sending signal 9 to container 25f97916dae1e276ea06ca3242642a9bf326873e17e8cdec9fe229e9224a48ff"
time="2023-02-07T16:16:47-05:00" level=debug msg="Container \"25f97916dae1e276ea06ca3242642a9bf326873e17e8cdec9fe229e9224a48ff\" state changed from \"stopping\" to \"stopped\" while waiting for it to be stopped: discontinuing stop procedure as another process interfered"
time="2023-02-07T16:16:47-05:00" level=debug msg="Pod 13f04cf0df5fb5ae1cf630a42b5aaee4104682977fa74aa5d58b3e579838bcf8 has a service 25f97916dae1e276ea06ca3242642a9bf326873e17e8cdec9fe229e9224a48ff: checking if it can be stopped"
time="2023-02-07T16:16:47-05:00" level=debug msg="Stopping service container 25f97916dae1e276ea06ca3242642a9bf326873e17e8cdec9fe229e9224a48ff"
time="2023-02-07T16:16:47-05:00" level=debug msg="Pod 13f04cf0df5fb5ae1cf630a42b5aaee4104682977fa74aa5d58b3e579838bcf8 has a service 25f97916dae1e276ea06ca3242642a9bf326873e17e8cdec9fe229e9224a48ff: checking if it can be stopped"
time="2023-02-07T16:16:47-05:00" level=debug msg="Stopping service container 25f97916dae1e276ea06ca3242642a9bf326873e17e8cdec9fe229e9224a48ff"
time="2023-02-07T16:16:47-05:00" level=debug msg="Pod 13f04cf0df5fb5ae1cf630a42b5aaee4104682977fa74aa5d58b3e579838bcf8 has a service 25f97916dae1e276ea06ca3242642a9bf326873e17e8cdec9fe229e9224a48ff: checking if it can be removed"
time="2023-02-07T16:16:47-05:00" level=debug msg="Removing service container 25f97916dae1e276ea06ca3242642a9bf326873e17e8cdec9fe229e9224a48ff"
time="2023-02-07T16:16:47-05:00" level=debug msg="Removing container 25f97916dae1e276ea06ca3242642a9bf326873e17e8cdec9fe229e9224a48ff"
time="2023-02-07T16:16:47-05:00" level=debug msg="Cleaning up container 25f97916dae1e276ea06ca3242642a9bf326873e17e8cdec9fe229e9224a48ff"
time="2023-02-07T16:16:47-05:00" level=debug msg="Successfully cleaned up container 25f97916dae1e276ea06ca3242642a9bf326873e17e8cdec9fe229e9224a48ff"
time="2023-02-07T16:16:47-05:00" level=debug msg="Unmounted container \"25f97916dae1e276ea06ca3242642a9bf326873e17e8cdec9fe229e9224a48ff\""
time="2023-02-07T16:16:47-05:00" level=debug msg="Removing all exec sessions for container 25f97916dae1e276ea06ca3242642a9bf326873e17e8cdec9fe229e9224a48ff"
time="2023-02-07T16:16:47-05:00" level=debug msg="Container 25f97916dae1e276ea06ca3242642a9bf326873e17e8cdec9fe229e9224a48ff storage is already unmounted, skipping..."