search

containers / toolbox

Tool for interactive command line environments on Linux
https://containertoolbx.org/
Apache License 2.0
2.57k stars 219 forks source link

Error: failed to start container #1310

Open baronleonardo opened 1 year ago

baronleonardo commented 1 year ago

Describe the bug can't start any container

Steps how to reproduce the behaviour

  1. run toolbox enter xxx

Output of toolbox --version (v0.0.90+) toolbox version 0.0.99.4

Toolbox package info (rpm -q toolbox) toolbox-0.0.99.4-1.fc38.x86_64

Output of podman version

Client:       Podman Engine
Version:      4.5.1
API Version:  4.5.1
Go Version:   go1.20.4
Built:        Fri May 26 20:58:48 2023
OS/Arch:      linux/amd64

Podman package info (rpm -q podman) podman-4.5.1-1.fc38.x86_64

Info about your OS Fedora Workstation 38

Additional context log

level=debug msg="Running as real user ID 1000"
level=debug msg="Resolved absolute path to the executable as /usr/bin/toolbox"
level=debug msg="Running on a cgroups v2 host"
level=debug msg="Looking for sub-GID and sub-UID ranges for user leo-0"
level=debug msg="TOOLBOX_PATH is /usr/bin/toolbox"
level=debug msg="Migrating to newer Podman"
level=debug msg="Toolbox config directory is /home/leo-0/.config/toolbox"
time="2023-06-11T08:05:56+03:00" level=info msg="podman filtering at log level debug"
time="2023-06-11T08:05:56+03:00" level=debug msg="Called version.PersistentPreRunE(podman --log-level debug version --format json)"
time="2023-06-11T08:05:56+03:00" level=debug msg="Using conmon: \"/usr/bin/conmon\""
time="2023-06-11T08:05:56+03:00" level=debug msg="Initializing boltdb state at /home/leo-0/.local/share/containers/storage/libpod/bolt_state.db"
time="2023-06-11T08:05:56+03:00" level=debug msg="Using graph driver overlay"
time="2023-06-11T08:05:56+03:00" level=debug msg="Using graph root /home/leo-0/.local/share/containers/storage"
time="2023-06-11T08:05:56+03:00" level=debug msg="Using run root /run/user/1000/containers"
time="2023-06-11T08:05:56+03:00" level=debug msg="Using static dir /home/leo-0/.local/share/containers/storage/libpod"
time="2023-06-11T08:05:56+03:00" level=debug msg="Using tmp dir /run/user/1000/libpod/tmp"
time="2023-06-11T08:05:56+03:00" level=debug msg="Using volume path /home/leo-0/.local/share/containers/storage/volumes"
time="2023-06-11T08:05:56+03:00" level=debug msg="Using transient store: false"
time="2023-06-11T08:05:56+03:00" level=debug msg="Not configuring container store"
time="2023-06-11T08:05:56+03:00" level=debug msg="Initializing event backend journald"
time="2023-06-11T08:05:56+03:00" level=debug msg="Configured OCI runtime runj initialization failed: no valid executable found for OCI runtime runj: invalid argument"
time="2023-06-11T08:05:56+03:00" level=debug msg="Configured OCI runtime kata initialization failed: no valid executable found for OCI runtime kata: invalid argument"
time="2023-06-11T08:05:56+03:00" level=debug msg="Configured OCI runtime youki initialization failed: no valid executable found for OCI runtime youki: invalid argument"
time="2023-06-11T08:05:56+03:00" level=debug msg="Configured OCI runtime ocijail initialization failed: no valid executable found for OCI runtime ocijail: invalid argument"
time="2023-06-11T08:05:56+03:00" level=debug msg="Configured OCI runtime crun-wasm initialization failed: no valid executable found for OCI runtime crun-wasm: invalid argument"
time="2023-06-11T08:05:56+03:00" level=debug msg="Configured OCI runtime runc initialization failed: no valid executable found for OCI runtime runc: invalid argument"
time="2023-06-11T08:05:56+03:00" level=debug msg="Configured OCI runtime runsc initialization failed: no valid executable found for OCI runtime runsc: invalid argument"
time="2023-06-11T08:05:56+03:00" level=debug msg="Configured OCI runtime krun initialization failed: no valid executable found for OCI runtime krun: invalid argument"
time="2023-06-11T08:05:56+03:00" level=debug msg="Using OCI runtime \"/usr/bin/crun\""
time="2023-06-11T08:05:56+03:00" level=info msg="Setting parallel job count to 25"
time="2023-06-11T08:05:56+03:00" level=debug msg="Called version.PersistentPostRunE(podman --log-level debug version --format json)"
time="2023-06-11T08:05:56+03:00" level=debug msg="Shutting down engines"
level=debug msg="Current Podman version is 4.5.1"
level=debug msg="Creating runtime directory /run/user/1000/toolbox"
level=debug msg="Old Podman version is 4.5.1"
level=debug msg="Migration not needed: Podman version 4.5.1 is unchanged"
level=debug msg="Setting up configuration"
level=debug msg="Setting up configuration: file /home/leo-0/.config/containers/toolbox.conf not found"
level=debug msg="Resolving container and image names"
level=debug msg="Container: ''"
level=debug msg="Distribution (CLI): ''"
level=debug msg="Image (CLI): ''"
level=debug msg="Release (CLI): ''"
level=debug msg="Resolved container and image names"
level=debug msg="Container: 'fedora-toolbox-38'"
level=debug msg="Image: 'fedora-toolbox:38'"
level=debug msg="Release: '38'"
level=debug msg="Resolving container and image names"
level=debug msg="Container: 'barehand'"
level=debug msg="Distribution (CLI): ''"
level=debug msg="Image (CLI): ''"
level=debug msg="Release (CLI): ''"
level=debug msg="Resolved container and image names"
level=debug msg="Container: 'barehand'"
level=debug msg="Image: 'fedora-toolbox:38'"
level=debug msg="Release: '38'"
level=debug msg="Checking if container barehand exists"
time="2023-06-11T08:05:56+03:00" level=info msg="podman filtering at log level debug"
time="2023-06-11T08:05:56+03:00" level=debug msg="Called exists.PersistentPreRunE(podman --log-level debug container exists barehand)"
time="2023-06-11T08:05:56+03:00" level=debug msg="Using conmon: \"/usr/bin/conmon\""
time="2023-06-11T08:05:56+03:00" level=debug msg="Initializing boltdb state at /home/leo-0/.local/share/containers/storage/libpod/bolt_state.db"
time="2023-06-11T08:05:56+03:00" level=debug msg="Using graph driver overlay"
time="2023-06-11T08:05:56+03:00" level=debug msg="Using graph root /home/leo-0/.local/share/containers/storage"
time="2023-06-11T08:05:56+03:00" level=debug msg="Using run root /run/user/1000/containers"
time="2023-06-11T08:05:56+03:00" level=debug msg="Using static dir /home/leo-0/.local/share/containers/storage/libpod"
time="2023-06-11T08:05:56+03:00" level=debug msg="Using tmp dir /run/user/1000/libpod/tmp"
time="2023-06-11T08:05:56+03:00" level=debug msg="Using volume path /home/leo-0/.local/share/containers/storage/volumes"
time="2023-06-11T08:05:56+03:00" level=debug msg="Using transient store: false"
time="2023-06-11T08:05:56+03:00" level=debug msg="[graphdriver] trying provided driver \"overlay\""
time="2023-06-11T08:05:56+03:00" level=debug msg="Cached value indicated that overlay is supported"
time="2023-06-11T08:05:56+03:00" level=debug msg="Cached value indicated that overlay is supported"
time="2023-06-11T08:05:56+03:00" level=debug msg="Cached value indicated that metacopy is not being used"
time="2023-06-11T08:05:56+03:00" level=debug msg="Cached value indicated that native-diff is usable"
time="2023-06-11T08:05:56+03:00" level=debug msg="backingFs=btrfs, projectQuotaSupported=false, useNativeDiff=true, usingMetacopy=false"
time="2023-06-11T08:05:56+03:00" level=debug msg="Initializing event backend journald"
time="2023-06-11T08:05:56+03:00" level=debug msg="Configured OCI runtime runj initialization failed: no valid executable found for OCI runtime runj: invalid argument"
time="2023-06-11T08:05:56+03:00" level=debug msg="Configured OCI runtime youki initialization failed: no valid executable found for OCI runtime youki: invalid argument"
time="2023-06-11T08:05:56+03:00" level=debug msg="Configured OCI runtime ocijail initialization failed: no valid executable found for OCI runtime ocijail: invalid argument"
time="2023-06-11T08:05:56+03:00" level=debug msg="Configured OCI runtime crun-wasm initialization failed: no valid executable found for OCI runtime crun-wasm: invalid argument"
time="2023-06-11T08:05:56+03:00" level=debug msg="Configured OCI runtime runc initialization failed: no valid executable found for OCI runtime runc: invalid argument"
time="2023-06-11T08:05:56+03:00" level=debug msg="Configured OCI runtime kata initialization failed: no valid executable found for OCI runtime kata: invalid argument"
time="2023-06-11T08:05:56+03:00" level=debug msg="Configured OCI runtime runsc initialization failed: no valid executable found for OCI runtime runsc: invalid argument"
time="2023-06-11T08:05:56+03:00" level=debug msg="Configured OCI runtime krun initialization failed: no valid executable found for OCI runtime krun: invalid argument"
time="2023-06-11T08:05:56+03:00" level=debug msg="Using OCI runtime \"/usr/bin/crun\""
time="2023-06-11T08:05:56+03:00" level=info msg="Setting parallel job count to 25"
time="2023-06-11T08:05:56+03:00" level=debug msg="Called exists.PersistentPostRunE(podman --log-level debug container exists barehand)"
time="2023-06-11T08:05:56+03:00" level=debug msg="Shutting down engines"
level=debug msg="Inspecting mounts of container barehand"
time="2023-06-11T08:05:56+03:00" level=info msg="podman filtering at log level debug"
time="2023-06-11T08:05:56+03:00" level=debug msg="Called inspect.PersistentPreRunE(podman --log-level debug inspect --format json --type container barehand)"
time="2023-06-11T08:05:56+03:00" level=debug msg="Using conmon: \"/usr/bin/conmon\""
time="2023-06-11T08:05:56+03:00" level=debug msg="Initializing boltdb state at /home/leo-0/.local/share/containers/storage/libpod/bolt_state.db"
time="2023-06-11T08:05:56+03:00" level=debug msg="Using graph driver overlay"
time="2023-06-11T08:05:56+03:00" level=debug msg="Using graph root /home/leo-0/.local/share/containers/storage"
time="2023-06-11T08:05:56+03:00" level=debug msg="Using run root /run/user/1000/containers"
time="2023-06-11T08:05:56+03:00" level=debug msg="Using static dir /home/leo-0/.local/share/containers/storage/libpod"
time="2023-06-11T08:05:56+03:00" level=debug msg="Using tmp dir /run/user/1000/libpod/tmp"
time="2023-06-11T08:05:56+03:00" level=debug msg="Using volume path /home/leo-0/.local/share/containers/storage/volumes"
time="2023-06-11T08:05:56+03:00" level=debug msg="Using transient store: false"
time="2023-06-11T08:05:56+03:00" level=debug msg="[graphdriver] trying provided driver \"overlay\""
time="2023-06-11T08:05:56+03:00" level=debug msg="Cached value indicated that overlay is supported"
time="2023-06-11T08:05:56+03:00" level=debug msg="Cached value indicated that overlay is supported"
time="2023-06-11T08:05:56+03:00" level=debug msg="Cached value indicated that metacopy is not being used"
time="2023-06-11T08:05:56+03:00" level=debug msg="Cached value indicated that native-diff is usable"
time="2023-06-11T08:05:56+03:00" level=debug msg="backingFs=btrfs, projectQuotaSupported=false, useNativeDiff=true, usingMetacopy=false"
time="2023-06-11T08:05:56+03:00" level=debug msg="Initializing event backend journald"
time="2023-06-11T08:05:56+03:00" level=debug msg="Configured OCI runtime runsc initialization failed: no valid executable found for OCI runtime runsc: invalid argument"
time="2023-06-11T08:05:56+03:00" level=debug msg="Configured OCI runtime krun initialization failed: no valid executable found for OCI runtime krun: invalid argument"
time="2023-06-11T08:05:56+03:00" level=debug msg="Configured OCI runtime runc initialization failed: no valid executable found for OCI runtime runc: invalid argument"
time="2023-06-11T08:05:56+03:00" level=debug msg="Configured OCI runtime runj initialization failed: no valid executable found for OCI runtime runj: invalid argument"
time="2023-06-11T08:05:56+03:00" level=debug msg="Configured OCI runtime kata initialization failed: no valid executable found for OCI runtime kata: invalid argument"
time="2023-06-11T08:05:56+03:00" level=debug msg="Configured OCI runtime youki initialization failed: no valid executable found for OCI runtime youki: invalid argument"
time="2023-06-11T08:05:56+03:00" level=debug msg="Configured OCI runtime ocijail initialization failed: no valid executable found for OCI runtime ocijail: invalid argument"
time="2023-06-11T08:05:56+03:00" level=debug msg="Configured OCI runtime crun-wasm initialization failed: no valid executable found for OCI runtime crun-wasm: invalid argument"
time="2023-06-11T08:05:56+03:00" level=debug msg="Using OCI runtime \"/usr/bin/crun\""
time="2023-06-11T08:05:56+03:00" level=info msg="Setting parallel job count to 25"
time="2023-06-11T08:05:56+03:00" level=debug msg="Looking up image \"997b52ccbf8544c42851a181e80bcd0f081eff8a879256b67d273a7e07f31f6f\" in local containers storage"
time="2023-06-11T08:05:56+03:00" level=debug msg="Trying \"997b52ccbf8544c42851a181e80bcd0f081eff8a879256b67d273a7e07f31f6f\" ..."
time="2023-06-11T08:05:56+03:00" level=debug msg="parsed reference into \"[overlay@/home/leo-0/.local/share/containers/storage+/run/user/1000/containers]@997b52ccbf8544c42851a181e80bcd0f081eff8a879256b67d273a7e07f31f6f\""
time="2023-06-11T08:05:56+03:00" level=debug msg="Found image \"997b52ccbf8544c42851a181e80bcd0f081eff8a879256b67d273a7e07f31f6f\" as \"997b52ccbf8544c42851a181e80bcd0f081eff8a879256b67d273a7e07f31f6f\" in local containers storage"
time="2023-06-11T08:05:56+03:00" level=debug msg="Found image \"997b52ccbf8544c42851a181e80bcd0f081eff8a879256b67d273a7e07f31f6f\" as \"997b52ccbf8544c42851a181e80bcd0f081eff8a879256b67d273a7e07f31f6f\" in local containers storage ([overlay@/home/leo-0/.local/share/containers/storage+/run/user/1000/containers]@997b52ccbf8544c42851a181e80bcd0f081eff8a879256b67d273a7e07f31f6f)"
time="2023-06-11T08:05:56+03:00" level=debug msg="Called inspect.PersistentPostRunE(podman --log-level debug inspect --format json --type container barehand)"
time="2023-06-11T08:05:56+03:00" level=debug msg="Shutting down engines"
level=debug msg="Starting container barehand"
Error: failed to start container barehand
Yiannis128 commented 1 year ago

Also facing this bug, I found that if you restart your system and delete the toolboxes & images it gets fixed, however, not sure if temporarily or permanently.

janvladimirmostert commented 1 year ago

Simple script to snapshot and restore broken toolbox

podman container export dev -o dev.tar
podman import dev.tar dev
toolbox rm dev
toolbox create --image dev

and then the toolbox works like normal again