[INFO] fetching crate vmexec 0.5.3...
[INFO] testing vmexec-0.5.3 against beta-2026-04-21 for beta-1.96-2
[INFO] extracting crate vmexec 0.5.3 into /workspace/builds/worker-1-tc2/source
[INFO] started tweaking crates.io crate vmexec 0.5.3
[INFO] removed 0 missing tests
[INFO] finished tweaking crates.io crate vmexec 0.5.3
[INFO] tweaked toml for crates.io crate vmexec 0.5.3 written to /workspace/builds/worker-1-tc2/source/Cargo.toml
[INFO] validating manifest of crates.io crate vmexec 0.5.3 on toolchain beta-2026-04-21
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+beta-2026-04-21" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate crates.io crate vmexec 0.5.3 already has a lockfile, it will not be regenerated
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+beta-2026-04-21" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+beta-2026-04-21" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] b43c53791072acb774f7f4f8fe0350eef68009cfe9e9ae6da75b6ae65e651015
[INFO] running `Command { std: "docker" "start" "-a" "b43c53791072acb774f7f4f8fe0350eef68009cfe9e9ae6da75b6ae65e651015", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "b43c53791072acb774f7f4f8fe0350eef68009cfe9e9ae6da75b6ae65e651015", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "b43c53791072acb774f7f4f8fe0350eef68009cfe9e9ae6da75b6ae65e651015", kill_on_drop: false }`
[INFO] [stdout] b43c53791072acb774f7f4f8fe0350eef68009cfe9e9ae6da75b6ae65e651015
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "RUSTDOCFLAGS=--cap-lints=warn" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+beta-2026-04-21" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] a092cea9f096a314620c6252e11ae88f2cac5c93bc1b800afbce2127fc1539b8
[INFO] running `Command { std: "docker" "start" "-a" "a092cea9f096a314620c6252e11ae88f2cac5c93bc1b800afbce2127fc1539b8", kill_on_drop: false }`
[INFO] [stderr]    Compiling libc v0.2.183
[INFO] [stderr]    Compiling typenum v1.19.0
[INFO] [stderr]    Compiling smallvec v1.15.1
[INFO] [stderr]    Compiling pem-rfc7468 v0.7.0
[INFO] [stderr]    Compiling const-oid v0.10.2
[INFO] [stderr]    Compiling aws-lc-rs v1.16.2
[INFO] [stderr]    Compiling tokio-macros v2.6.1
[INFO] [stderr]    Compiling zerovec-derive v0.11.2
[INFO] [stderr]    Compiling zerofrom-derive v0.1.6
[INFO] [stderr]    Compiling yoke-derive v0.8.1
[INFO] [stderr]    Compiling futures-util v0.3.32
[INFO] [stderr]    Compiling spin v0.9.8
[INFO] [stderr]    Compiling rustix v1.1.4
[INFO] [stderr]    Compiling untrusted v0.7.1
[INFO] [stderr]    Compiling pem-rfc7468 v1.0.0
[INFO] [stderr]    Compiling lazy_static v1.5.0
[INFO] [stderr]    Compiling der v0.7.10
[INFO] [stderr]    Compiling cmov v0.5.0-pre.0
[INFO] [stderr]    Compiling der v0.8.0
[INFO] [stderr]    Compiling rustls v0.23.37
[INFO] [stderr]    Compiling rand_core v0.10.0-rc-3
[INFO] [stderr]    Compiling darling_core v0.23.0
[INFO] [stderr]    Compiling ctutils v0.3.2
[INFO] [stderr]    Compiling indexmap v2.13.0
[INFO] [stderr]    Compiling curve25519-dalek v4.1.3
[INFO] [stderr]    Compiling nix v0.29.0
[INFO] [stderr]    Compiling crypto-bigint v0.7.0-rc.18
[INFO] [stderr]    Compiling sharded-slab v0.1.7
[INFO] [stderr]    Compiling curve25519-dalek-derive v0.1.1
[INFO] [stderr]    Compiling nix v0.31.2
[INFO] [stderr]    Compiling jobserver v0.1.34
[INFO] [stderr]    Compiling num-bigint-dig v0.8.6
[INFO] [stderr]    Compiling spki v0.8.0-rc.4
[INFO] [stderr]    Compiling owo-colors v4.3.0
[INFO] [stderr]    Compiling cc v1.2.58
[INFO] [stderr]    Compiling terminal_size v0.4.4
[INFO] [stderr]    Compiling serde v1.0.228
[INFO] [stderr]    Compiling num-iter v0.1.45
[INFO] [stderr]    Compiling generic-array v0.14.7
[INFO] [stderr]    Compiling hybrid-array v0.4.8
[INFO] [stderr]    Compiling pkcs1 v0.8.0-rc.4
[INFO] [stderr]    Compiling pkcs8 v0.11.0-rc.11
[INFO] [stderr]    Compiling clap_builder v4.6.0
[INFO] [stderr]    Compiling clap_derive v4.6.0
[INFO] [stderr]    Compiling color-spantrace v0.3.0
[INFO] [stderr]    Compiling zerofrom v0.1.6
[INFO] [stderr]    Compiling crypto-common v0.1.7
[INFO] [stderr]    Compiling block-buffer v0.10.4
[INFO] [stderr]    Compiling block-padding v0.3.3
[INFO] [stderr]    Compiling yoke v0.8.1
[INFO] [stderr]    Compiling eyre v0.6.12
[INFO] [stderr]    Compiling digest v0.10.7
[INFO] [stderr]    Compiling spki v0.7.3
[INFO] [stderr]    Compiling inout v0.1.4
[INFO] [stderr]    Compiling zerovec v0.11.5
[INFO] [stderr]    Compiling cipher v0.4.4
[INFO] [stderr]    Compiling zerotrie v0.2.3
[INFO] [stderr]    Compiling universal-hash v0.5.1
[INFO] [stderr]    Compiling aead v0.5.2
[INFO] [stderr]    Compiling polyval v0.6.2
[INFO] [stderr]    Compiling sha2 v0.10.9
[INFO] [stderr]    Compiling hmac v0.12.1
[INFO] [stderr]    Compiling aes v0.8.4
[INFO] [stderr]    Compiling cbc v0.1.2
[INFO] [stderr]    Compiling salsa20 v0.10.2
[INFO] [stderr]    Compiling pbkdf2 v0.12.2
[INFO] [stderr]    Compiling hkdf v0.12.4
[INFO] [stderr]    Compiling crypto-common v0.2.1
[INFO] [stderr]    Compiling block-buffer v0.12.0
[INFO] [stderr]    Compiling rfc6979 v0.4.0
[INFO] [stderr]    Compiling blake2 v0.10.6
[INFO] [stderr]    Compiling ctr v0.9.2
[INFO] [stderr]    Compiling getrandom v0.2.17
[INFO] [stderr]    Compiling errno v0.3.14
[INFO] [stderr]    Compiling mio v1.2.0
[INFO] [stderr]    Compiling socket2 v0.6.3
[INFO] [stderr]    Compiling getrandom v0.3.4
[INFO] [stderr]    Compiling rand_core v0.6.4
[INFO] [stderr]    Compiling signal-hook-registry v1.4.8
[INFO] [stderr]    Compiling scrypt v0.11.0
[INFO] [stderr]    Compiling tinystr v0.8.2
[INFO] [stderr]    Compiling cmake v0.1.58
[INFO] [stderr]    Compiling potential_utf v0.1.4
[INFO] [stderr]    Compiling rand_core v0.9.5
[INFO] [stderr]    Compiling icu_locale_core v2.1.1
[INFO] [stderr]    Compiling ff v0.13.1
[INFO] [stderr]    Compiling signature v2.2.0
[INFO] [stderr]    Compiling crypto-bigint v0.5.5
[INFO] [stderr]    Compiling tokio v1.50.0
[INFO] [stderr]    Compiling pkcs5 v0.7.1
[INFO] [stderr]    Compiling group v0.13.0
[INFO] [stderr]    Compiling icu_collections v2.1.1
[INFO] [stderr]    Compiling rand_chacha v0.3.1
[INFO] [stderr]    Compiling rand_chacha v0.9.0
[INFO] [stderr]    Compiling digest v0.11.2
[INFO] [stderr]    Compiling ssh-encoding v0.2.0
[INFO] [stderr]    Compiling ghash v0.5.1
[INFO] [stderr]    Compiling time v0.3.47
[INFO] [stderr]    Compiling aws-lc-sys v0.39.1
[INFO] [stderr]    Compiling aes-gcm v0.10.3
[INFO] [stderr]    Compiling rand v0.8.5
[INFO] [stderr]    Compiling pkcs8 v0.10.2
[INFO] [stderr]    Compiling rand v0.9.2
[INFO] [stderr]    Compiling icu_provider v2.1.1
[INFO] [stderr]    Compiling sec1 v0.7.3
[INFO] [stderr]    Compiling ed25519 v2.2.3
[INFO] [stderr]    Compiling icu_normalizer v2.1.1
[INFO] [stderr]    Compiling icu_properties v2.1.2
[INFO] [stderr]    Compiling signature v3.0.0-rc.6
[INFO] [stderr]    Compiling sha2 v0.11.0
[INFO] [stderr]    Compiling darling_macro v0.23.0
[INFO] [stderr]    Compiling elliptic-curve v0.13.8
[INFO] [stderr]    Compiling crypto-primes v0.7.0-pre.6
[INFO] [stderr]    Compiling password-hash v0.5.0
[INFO] [stderr]    Compiling darling v0.23.0
[INFO] [stderr]    Compiling tracing-subscriber v0.3.23
[INFO] [stderr]    Compiling futures-executor v0.3.32
[INFO] [stderr]    Compiling parking_lot_core v0.9.12
[INFO] [stderr]    Compiling blowfish v0.9.1
[INFO] [stderr]    Compiling chacha20 v0.9.1
[INFO] [stderr]    Compiling primeorder v0.13.6
[INFO] [stderr]    Compiling ecdsa v0.16.9
[INFO] [stderr]    Compiling poly1305 v0.8.0
[INFO] [stderr]    Compiling portable-atomic v1.13.1
[INFO] [stderr]    Compiling keccak v0.1.6
[INFO] [stderr]    Compiling sha3 v0.10.8
[INFO] [stderr]    Compiling p521 v0.13.3
[INFO] [stderr]    Compiling p256 v0.13.2
[INFO] [stderr]    Compiling ssh-cipher v0.2.0
[INFO] [stderr]    Compiling p384 v0.13.1
[INFO] [stderr]    Compiling idna_adapter v1.2.1
[INFO] [stderr]    Compiling idna v1.1.0
[INFO] [stderr]    Compiling ed25519-dalek v2.2.0
[INFO] [stderr]    Compiling rsa v0.10.0-rc.12
[INFO] [stderr]    Compiling bcrypt-pbkdf v0.10.0
[INFO] [stderr]    Compiling parking_lot v0.12.5
[INFO] [stderr]    Compiling futures v0.3.32
[INFO] [stderr]    Compiling serde_with_macros v3.18.0
[INFO] [stderr]    Compiling argon2 v0.5.3
[INFO] [stderr]    Compiling clap v4.6.0
[INFO] [stderr]    Compiling url v2.5.8
[INFO] [stderr]    Compiling sha1 v0.11.0
[INFO] [stderr]    Compiling kem v0.3.0-pre.0
[INFO] [stderr]    Compiling tracing-error v0.2.1
[INFO] [stderr]    Compiling getrandom v0.4.2
[INFO] [stderr]    Compiling sha1 v0.10.6
[INFO] [stderr]    Compiling hybrid-array v0.2.3
[INFO] [stderr]    Compiling indenter v0.3.4
[INFO] [stderr]    Compiling iri-string v0.7.11
[INFO] [stderr]    Compiling internal-russh-forked-ssh-key v0.6.16+upstream-0.6.7
[INFO] [stderr]    Compiling dirs-sys v0.5.0
[INFO] [stderr]    Compiling vmm-sys-util v0.15.0
[INFO] [stderr]    Compiling backtrace v0.3.76
[INFO] [stderr]    Compiling generic-array v1.3.5
[INFO] [stderr]    Compiling uuid v1.21.0
[INFO] [stderr]    Compiling serde_with v3.18.0
[INFO] [stderr]    Compiling vsock v0.5.3
[INFO] [stderr]    Compiling russh-cryptovec v0.58.0
[INFO] [stderr]    Compiling crossterm v0.29.0
[INFO] [stderr]    Compiling console v0.16.3
[INFO] [stderr]    Compiling num-bigint v0.4.6
[INFO] [stderr]    Compiling vm-memory v0.17.1
[INFO] [stderr]    Compiling sysinfo v0.35.2
[INFO] [stderr]    Compiling ml-kem v0.2.3
[INFO] [stderr]    Compiling md-5 v0.10.6
[INFO] [stderr]    Compiling strum_macros v0.28.0
[INFO] [stderr]    Compiling serde_bytes v0.11.19
[INFO] [stderr]    Compiling delegate v0.13.5
[INFO] [stderr]    Compiling enum_dispatch v0.3.13
[INFO] [stderr]    Compiling unit-prefix v0.5.2
[INFO] [stderr]    Compiling roff v1.1.1
[INFO] [stderr]    Compiling unicode-segmentation v1.13.2
[INFO] [stderr]    Compiling md5 v0.7.0
[INFO] [stderr]    Compiling numtoa v0.2.4
[INFO] [stderr]    Compiling hex-literal v0.4.1
[INFO] [stderr]    Compiling clap_mangen v0.3.0
[INFO] [stderr]    Compiling indicatif v0.18.4
[INFO] [stderr]    Compiling comfy-table v7.2.2
[INFO] [stderr]    Compiling color-eyre v0.6.5
[INFO] [stderr]    Compiling tokio-util v0.7.18
[INFO] [stderr]    Compiling tower v0.5.3
[INFO] [stderr]    Compiling russh-util v0.52.0
[INFO] [stderr]    Compiling termion v4.0.6
[INFO] [stderr]    Compiling h2 v0.4.13
[INFO] [stderr]    Compiling tokio-vsock v0.7.2
[INFO] [stderr]    Compiling dir-lock v0.5.0
[INFO] [stderr]    Compiling tokio-fd v0.3.0
[INFO] [stderr]    Compiling tower-http v0.6.8
[INFO] [stderr]    Compiling rattler_digest v1.2.3
[INFO] [stderr]    Compiling vhost v0.16.0
[INFO] [stderr]    Compiling directories v6.0.0
[INFO] [stderr]    Compiling daemonize-me v2.0.2
[INFO] [stderr]    Compiling strum v0.28.0
[INFO] [stderr]    Compiling clap_complete v4.6.0
[INFO] [stderr]    Compiling nanoid v0.4.0
[INFO] [stderr]    Compiling sysinfo v0.38.4
[INFO] [stderr]    Compiling which v8.0.2
[INFO] [stderr]    Compiling humansize v2.1.3
[INFO] [stderr]    Compiling humantime v2.3.0
[INFO] [stderr]    Compiling shell-escape v0.1.5
[INFO] [stderr]    Compiling hyper v1.8.1
[INFO] [stderr]    Compiling hyper-util v0.1.20
[INFO] [stderr]    Compiling rustls-webpki v0.103.10
[INFO] [stderr]    Compiling russh v0.58.1
[INFO] [stderr]    Compiling tokio-rustls v0.26.4
[INFO] [stderr]    Compiling rustls-platform-verifier v0.6.2
[INFO] [stderr]    Compiling hyper-rustls v0.27.7
[INFO] [stderr]    Compiling reqwest v0.13.2
[INFO] [stderr]    Compiling vmexec v0.5.3 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 1m 26s
[INFO] running `Command { std: "docker" "inspect" "a092cea9f096a314620c6252e11ae88f2cac5c93bc1b800afbce2127fc1539b8", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "a092cea9f096a314620c6252e11ae88f2cac5c93bc1b800afbce2127fc1539b8", kill_on_drop: false }`
[INFO] [stdout] a092cea9f096a314620c6252e11ae88f2cac5c93bc1b800afbce2127fc1539b8
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "RUSTDOCFLAGS=--cap-lints=warn" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+beta-2026-04-21" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 936def554ffc5df5355b8014eadfb34003371ebdfd3b95fcfbf3add975131cd6
[INFO] running `Command { std: "docker" "start" "-a" "936def554ffc5df5355b8014eadfb34003371ebdfd3b95fcfbf3add975131cd6", kill_on_drop: false }`
[INFO] [stderr]    Compiling regex-automata v0.4.14
[INFO] [stderr]    Compiling winnow v1.0.0
[INFO] [stderr]    Compiling toml_datetime v1.1.0+spec-1.1.0
[INFO] [stderr]    Compiling tower v0.5.3
[INFO] [stderr]    Compiling hyper-util v0.1.20
[INFO] [stderr]    Compiling rstest_macros v0.26.1
[INFO] [stderr]    Compiling predicates-core v1.0.10
[INFO] [stderr]    Compiling regex v1.12.3
[INFO] [stderr]    Compiling float-cmp v0.10.0
[INFO] [stderr]    Compiling relative-path v1.9.3
[INFO] [stderr]    Compiling assert_cmd v2.2.0
[INFO] [stderr]    Compiling normalize-line-endings v0.3.0
[INFO] [stderr]    Compiling termtree v0.5.1
[INFO] [stderr]    Compiling difflib v0.4.0
[INFO] [stderr]    Compiling russh v0.58.1
[INFO] [stderr]    Compiling anstream v0.6.21
[INFO] [stderr]    Compiling tokio-vsock v0.7.2
[INFO] [stderr]    Compiling wait-timeout v0.2.1
[INFO] [stderr]    Compiling predicates-tree v1.0.13
[INFO] [stderr]    Compiling diff v0.1.13
[INFO] [stderr]    Compiling yansi v1.0.1
[INFO] [stderr]    Compiling futures-timer v3.0.3
[INFO] [stderr]    Compiling tower-http v0.6.8
[INFO] [stderr]    Compiling pretty_assertions v1.4.1
[INFO] [stderr]    Compiling toml_parser v1.1.0+spec-1.1.0
[INFO] [stderr]    Compiling hyper-rustls v0.27.7
[INFO] [stderr]    Compiling reqwest v0.13.2
[INFO] [stderr]    Compiling toml_edit v0.25.8+spec-1.1.0
[INFO] [stderr]    Compiling proc-macro-crate v3.5.0
[INFO] [stderr]    Compiling matchers v0.2.0
[INFO] [stderr]    Compiling bstr v1.12.1
[INFO] [stderr]    Compiling tracing-subscriber v0.3.23
[INFO] [stderr]    Compiling predicates v3.1.4
[INFO] [stderr]    Compiling tracing-error v0.2.1
[INFO] [stderr]    Compiling rstest v0.26.1
[INFO] [stderr]    Compiling color-spantrace v0.3.0
[INFO] [stderr]    Compiling color-eyre v0.6.5
[INFO] [stderr]    Compiling vmexec v0.5.3 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 38.00s
[INFO] running `Command { std: "docker" "inspect" "936def554ffc5df5355b8014eadfb34003371ebdfd3b95fcfbf3add975131cd6", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "936def554ffc5df5355b8014eadfb34003371ebdfd3b95fcfbf3add975131cd6", kill_on_drop: false }`
[INFO] [stdout] 936def554ffc5df5355b8014eadfb34003371ebdfd3b95fcfbf3add975131cd6
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "RUSTDOCFLAGS=--cap-lints=warn" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+beta-2026-04-21" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] edb3db068df6c597b6e5df24d751927bd942ade083d3ff0a5b7939eeca9d10ed
[INFO] running `Command { std: "docker" "start" "-a" "edb3db068df6c597b6e5df24d751927bd942ade083d3ff0a5b7939eeca9d10ed", kill_on_drop: false }`
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.63s
[INFO] [stderr]      Running unittests src/main.rs (/opt/rustwide/target/debug/deps/vmexec-178dfe70489018de)
[INFO] [stdout] 
[INFO] [stdout] running 46 tests
[INFO] [stdout] test cli::tests::test_parse_bind_volume_invalid::case_2 ... ok
[INFO] [stdout] test cli::tests::test_parse_bind_volume_invalid::case_3 ... ok
[INFO] [stdout] test cli::tests::test_parse_bind_volume_invalid::case_4 ... ok
[INFO] [stdout] test cli::tests::test_parse_bind_volume_invalid::case_5 ... ok
[INFO] [stdout] test cli::tests::test_parse_bind_volume_invalid::case_1 ... ok
[INFO] [stdout] test cli::tests::test_parse_bind_volume_valid::case_1 ... ok
[INFO] [stdout] test cli::tests::test_parse_bind_volume_valid::case_2 ... ok
[INFO] [stdout] test cli::tests::test_parse_bind_volume_valid::case_3 ... ok
[INFO] [stdout] test cli::tests::test_parse_bind_volume_valid::case_4 ... ok
[INFO] [stdout] test cli::tests::test_parse_env_var_invalid::case_1 ... ok
[INFO] [stdout] test cli::tests::test_parse_env_var_invalid::case_3 ... ok
[INFO] [stdout] test cli::tests::test_parse_env_var_valid::case_1 ... ok
[INFO] [stdout] test cli::tests::test_parse_env_var_valid::case_2 ... ok
[INFO] [stdout] test cli::tests::test_parse_env_var_invalid::case_2 ... ok
[INFO] [stdout] test cli::tests::test_parse_memory_invalid::case_2 ... ok
[INFO] [stdout] test cli::tests::test_parse_memory_invalid::case_1 ... ok
[INFO] [stdout] test cli::tests::test_parse_memory_invalid::case_3 ... ok
[INFO] [stdout] test cli::tests::test_parse_memory_invalid::case_4 ... ok
[INFO] [stdout] test cli::tests::test_parse_memory_valid::case_1 ... ok
[INFO] [stdout] test cli::tests::test_parse_memory_valid::case_2 ... ok
[INFO] [stdout] test cli::tests::test_parse_memory_valid::case_3 ... ok
[INFO] [stdout] test cli::tests::test_parse_memory_valid::case_4 ... ok
[INFO] [stdout] test cli::tests::test_parse_os_type_or_image_path::case_1 ... ok
[INFO] [stdout] test cli::tests::test_parse_memory_invalid::case_5 ... ok
[INFO] [stdout] test cli::tests::test_parse_os_type_or_image_path::case_2 ... ok
[INFO] [stdout] test cli::tests::test_parse_pmem_invalid::case_1 ... ok
[INFO] [stdout] test cli::tests::test_parse_os_type_or_image_path_invalid::case_1 ... ok
[INFO] [stderr]      Running tests/basic.rs (/opt/rustwide/target/debug/deps/basic-b80f04d4e430cd41)
[INFO] [stdout] test cli::tests::test_parse_pmem_valid::case_1 ... ok
[INFO] [stdout] test cli::tests::test_parse_publish_port_invalid::case_1 ... ok
[INFO] [stdout] test cli::tests::test_parse_publish_port_invalid::case_2 ... ok
[INFO] [stdout] test cli::tests::test_parse_publish_port_invalid::case_3 ... ok
[INFO] [stdout] test cli::tests::test_parse_publish_port_invalid::case_4 ... ok
[INFO] [stdout] test cli::tests::test_parse_publish_port_invalid::case_5 ... ok
[INFO] [stdout] test cli::tests::test_parse_pmem_valid::case_2 ... ok
[INFO] [stdout] test cli::tests::test_parse_publish_port_valid::case_1 ... ok
[INFO] [stdout] test cli::tests::test_parse_publish_port_valid::case_2 ... ok
[INFO] [stdout] test cli::tests::test_parse_publish_port_valid::case_3 ... ok
[INFO] [stdout] test cli::tests::test_parse_publish_port_valid::case_4 ... ok
[INFO] [stdout] test vms::tests::test_vmid_from_str_invalid::case_1 ... ok
[INFO] [stdout] test vms::tests::test_vmid_from_str_invalid::case_2 ... ok
[INFO] [stdout] test vms::tests::test_vmid_from_str_invalid::case_3 ... ok
[INFO] [stdout] test vms::tests::test_vmid_from_str_invalid::case_4 ... ok
[INFO] [stdout] test vms::tests::test_vmid_from_str_invalid::case_5 ... ok
[INFO] [stdout] test vms::tests::test_vmid_from_str_valid::case_1 ... ok
[INFO] [stdout] test vms::tests::test_vmid_from_str_valid::case_2 ... ok
[INFO] [stdout] test vms::tests::test_vmid_from_str_valid::case_3 ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 46 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 4 tests
[INFO] [stdout] test ps_command ... ok
[INFO] [stdout] test run_command_detached ... FAILED
[INFO] [stdout] test exec_command_and_stop ... FAILED
[INFO] [stdout] test run_command ... FAILED
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout] 
[INFO] [stdout] ---- run_command_detached stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'run_command_detached' (68) panicked at /rustc/8489f1adbe1c8f8921cf18b9962cf565237a5489/library/core/src/ops/function.rs:250:5:
[INFO] [stdout] Unexpected failure.
[INFO] [stdout] code=1
[INFO] [stdout] stderr=``````
[INFO] [stdout] Error: 
[INFO] [stdout]    0: \x1b[91mCouldn\'t find qemu-system-x86_64 in PATH\x1b[0m
[INFO] [stdout]    1: \x1b[91mcannot find binary path\x1b[0m
[INFO] [stdout] 
[INFO] [stdout] Location:
[INFO] [stdout]    \x1b[35msrc/utils.rs\x1b[0m:\x1b[35m171\x1b[0m
[INFO] [stdout] 
[INFO] [stdout]   ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ BACKTRACE ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━
[INFO] [stdout]   \x1b[96m                              ⋮ 4 frames hidden ⋮                               \x1b[0m
[INFO] [stdout]    5: \x1b[91m<E as eyre::context::ext::StdError>::ext_report\x1b[0m\x1b[90m::h916e2b046f9e0e90\x1b[0m
[INFO] [stdout]       at \x1b[35m/opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/eyre-0.6.12/src/context.rs\x1b[0m:\x1b[35m26\x1b[0m
[INFO] [stdout]         24 │             D: Display + Send + Sync + \'static,
[INFO] [stdout]         25 │         {
[INFO] [stdout]   \x1b[37;1m      26\x1b[0m \x1b[37;1m>\x1b[0m \x1b[37;1m            Report::from_msg(msg, self)\x1b[0m
[INFO] [stdout]         27 │         }
[INFO] [stdout]         28 │     }
[INFO] [stdout]   \x1b[96m                               ⋮ 1 frame hidden ⋮                               \x1b[0m
[INFO] [stdout]    7: \x1b[91mvmexec::utils::find_required_tools\x1b[0m\x1b[90m::hd5b0c5707761bb63\x1b[0m
[INFO] [stdout]       at \x1b[35m/opt/rustwide/workdir/src/utils.rs\x1b[0m:\x1b[35m171\x1b[0m
[INFO] [stdout]        169 │     // Find QEMUU
[INFO] [stdout]        170 │     let qemu_path = which::which_global(\"qemu-system-x86_64\")
[INFO] [stdout]   \x1b[37;1m     171\x1b[0m \x1b[37;1m>\x1b[0m \x1b[37;1m        .wrap_err(\"Couldn\'t find qemu-system-x86_64 in PATH\")?;\x1b[0m
[INFO] [stdout]        172 │ 
[INFO] [stdout]        173 │     // Find virtiofsd
[INFO] [stdout]    8: \x1b[91mvmexec::run_command\x1b[0m\x1b[90m::hcc2af1188d864d38\x1b[0m
[INFO] [stdout]       at \x1b[35m/opt/rustwide/workdir/src/main.rs\x1b[0m:\x1b[35m371\x1b[0m
[INFO] [stdout]        369 │ fn run_command(run_args: RunCommand) -> Result<Option<u32>> {
[INFO] [stdout]        370 │     // Make sure the tools we need are actually installed.
[INFO] [stdout]   \x1b[37;1m     371\x1b[0m \x1b[37;1m>\x1b[0m \x1b[37;1m    let tool_paths = find_required_tools()?;\x1b[0m
[INFO] [stdout]        372 │ 
[INFO] [stdout]        373 │     // Check whether KSM is active.
[INFO] [stdout]    9: \x1b[91mvmexec::main\x1b[0m\x1b[90m::h716de3d663de88fb\x1b[0m
[INFO] [stdout]       at \x1b[35m/opt/rustwide/workdir/src/main.rs\x1b[0m:\x1b[35m543\x1b[0m
[INFO] [stdout]        541 │         Command::Exec(exec_args) => exec_command(exec_args)?,
[INFO] [stdout]        542 │         Command::Run(run_args) => {
[INFO] [stdout]   \x1b[37;1m     543\x1b[0m \x1b[37;1m>\x1b[0m \x1b[37;1m            let exit_code = run_command(run_args)?;\x1b[0m
[INFO] [stdout]        544 │             if let Some(child_code) = exit_code {
[INFO] [stdout]        545 │                 process::exit(child_code.try_into()?);
[INFO] [stdout]   10: \x1b[32mcore::ops::function::FnOnce::call_once\x1b[0m\x1b[90m::ha8f31c3443b29a80\x1b[0m
[INFO] [stdout]       at \x1b[35m/rustc/8489f1adbe1c8f8921cf18b9962cf565237a5489/library/core/src/ops/function.rs\x1b[0m:\x1b[35m250\x1b[0m
[INFO] [stdout]   11: \x1b[32mstd::sys::backtrace::__rust_begin_short_backtrace\x1b[0m\x1b[90m::h8fcf821ab45ee1a9\x1b[0m
[INFO] [stdout]       at \x1b[35m/rustc/8489f1adbe1c8f8921cf18b9962cf565237a5489/library/std/src/sys/backtrace.rs\x1b[0m:\x1b[35m166\x1b[0m
[INFO] [stdout]   \x1b[96m                              ⋮ 14 frames hidden ⋮                              \x1b[0m
[INFO] [stdout] 
[INFO] [stdout] Run with COLORBT_SHOW_HIDDEN=1 environment variable to disable frame filtering.
[INFO] [stdout] ```
[INFO] [stdout] ```
[INFO] [stdout] command=`"/opt/rustwide/target/debug/vmexec" "run" "archlinux" "--detach" "--disable-kvm" "--rm" "--ssh-timeout" "180" "echo" "hello yes"`
[INFO] [stdout] code=1
[INFO] [stdout] stdout=""
[INFO] [stdout] stderr=```
[INFO] [stdout] Error: 
[INFO] [stdout]    0: \x1b[91mCouldn\'t find qemu-system-x86_64 in PATH\x1b[0m
[INFO] [stdout]    1: \x1b[91mcannot find binary path\x1b[0m
[INFO] [stdout] 
[INFO] [stdout] Location:
[INFO] [stdout]    \x1b[35msrc/utils.rs\x1b[0m:\x1b[35m171\x1b[0m
[INFO] [stdout] 
[INFO] [stdout]   ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ BACKTRACE ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━
[INFO] [stdout]   \x1b[96m                              ⋮ 4 frames hidden ⋮                               \x1b[0m
[INFO] [stdout]    5: \x1b[91m<E as eyre::context::ext::StdError>::ext_report\x1b[0m\x1b[90m::h916e2b046f9e0e90\x1b[0m
[INFO] [stdout]       at \x1b[35m/opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/eyre-0.6.12/src/context.rs\x1b[0m:\x1b[35m26\x1b[0m
[INFO] [stdout]         24 │             D: Display + Send + Sync + \'static,
[INFO] [stdout]         25 │         {
[INFO] [stdout]   \x1b[37;1m      26\x1b[0m \x1b[37;1m>\x1b[0m \x1b[37;1m            Report::from_msg(msg, self)\x1b[0m
[INFO] [stdout]         27 │         }
[INFO] [stdout]         28 │     }
[INFO] [stdout]   \x1b[96m                               ⋮ 1 frame hidden ⋮                               \x1b[0m
[INFO] [stdout]    7: \x1b[91mvmexec::utils::find_required_tools\x1b[0m\x1b[90m::hd5b0c5707761bb63\x1b[0m
[INFO] [stdout]       at \x1b[35m/opt/rustwide/workdir/src/utils.rs\x1b[0m:\x1b[35m171\x1b[0m
[INFO] [stdout]        169 │     // Find QEMUU
[INFO] [stdout]        170 │     let qemu_path = which::which_global(\"qemu-system-x86_64\")
[INFO] [stdout]   \x1b[37;1m     171\x1b[0m \x1b[37;1m>\x1b[0m \x1b[37;1m        .wrap_err(\"Couldn\'t find qemu-system-x86_64 in PATH\")?;\x1b[0m
[INFO] [stdout]        172 │ 
[INFO] [stdout]        173 │     // Find virtiofsd
[INFO] [stdout]    8: \x1b[91mvmexec::run_command\x1b[0m\x1b[90m::hcc2af1188d864d38\x1b[0m
[INFO] [stdout]       at \x1b[35m/opt/rustwide/workdir/src/main.rs\x1b[0m:\x1b[35m371\x1b[0m
[INFO] [stdout]        369 │ fn run_command(run_args: RunCommand) -> Result<Option<u32>> {
[INFO] [stdout]        370 │     // Make sure the tools we need are actually installed.
[INFO] [stdout]   \x1b[37;1m     371\x1b[0m \x1b[37;1m>\x1b[0m \x1b[37;1m    let tool_paths = find_required_tools()?;\x1b[0m
[INFO] [stdout]        372 │ 
[INFO] [stdout]        373 │     // Check whether KSM is active.
[INFO] [stdout]    9: \x1b[91mvmexec::main\x1b[0m\x1b[90m::h716de3d663de88fb\x1b[0m
[INFO] [stdout]       at \x1b[35m/opt/rustwide/workdir/src/main.rs\x1b[0m:\x1b[35m543\x1b[0m
[INFO] [stdout]        541 │         Command::Exec(exec_args) => exec_command(exec_args)?,
[INFO] [stdout]        542 │         Command::Run(run_args) => {
[INFO] [stdout]   \x1b[37;1m     543\x1b[0m \x1b[37;1m>\x1b[0m \x1b[37;1m            let exit_code = run_command(run_args)?;\x1b[0m
[INFO] [stdout]        544 │             if let Some(child_code) = exit_code {
[INFO] [stdout]        545 │                 process::exit(child_code.try_into()?);
[INFO] [stdout]   10: \x1b[32mcore::ops::function::FnOnce::call_once\x1b[0m\x1b[90m::ha8f31c3443b29a80\x1b[0m
[INFO] [stdout]       at \x1b[35m/rustc/8489f1adbe1c8f8921cf18b9962cf565237a5489/library/core/src/ops/function.rs\x1b[0m:\x1b[35m250\x1b[0m
[INFO] [stdout]   11: \x1b[32mstd::sys::backtrace::__rust_begin_short_backtrace\x1b[0m\x1b[90m::h8fcf821ab45ee1a9\x1b[0m
[INFO] [stdout]       at \x1b[35m/rustc/8489f1adbe1c8f8921cf18b9962cf565237a5489/library/std/src/sys/backtrace.rs\x1b[0m:\x1b[35m166\x1b[0m
[INFO] [stdout]   \x1b[96m                              ⋮ 14 frames hidden ⋮                              \x1b[0m
[INFO] [stdout] 
[INFO] [stdout] Run with COLORBT_SHOW_HIDDEN=1 environment variable to disable frame filtering.
[INFO] [stdout] ```
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x602f3b48a7ea - std[128c3efe2914e152]::backtrace_rs::backtrace::libunwind::trace
[INFO] [stdout]                                at /rustc/8489f1adbe1c8f8921cf18b9962cf565237a5489/library/std/src/../../backtrace/src/backtrace/libunwind.rs:117:9
[INFO] [stdout]    1:     0x602f3b48a7ea - std[128c3efe2914e152]::backtrace_rs::backtrace::trace_unsynchronized::<std[128c3efe2914e152]::sys::backtrace::_print_fmt::{closure#1}>
[INFO] [stdout]                                at /rustc/8489f1adbe1c8f8921cf18b9962cf565237a5489/library/std/src/../../backtrace/src/backtrace/mod.rs:66:14
[INFO] [stdout]    2:     0x602f3b48a7ea - std[128c3efe2914e152]::sys::backtrace::_print_fmt
[INFO] [stdout]                                at /rustc/8489f1adbe1c8f8921cf18b9962cf565237a5489/library/std/src/sys/backtrace.rs:74:9
[INFO] [stdout]    3:     0x602f3b48a7ea - <<std[128c3efe2914e152]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[6771d259883166e6]::fmt::Display>::fmt
[INFO] [stdout]                                at /rustc/8489f1adbe1c8f8921cf18b9962cf565237a5489/library/std/src/sys/backtrace.rs:44:26
[INFO] [stdout]    4:     0x602f3b4a0dca - <core[6771d259883166e6]::fmt::rt::Argument>::fmt
[INFO] [stdout]                                at /rustc/8489f1adbe1c8f8921cf18b9962cf565237a5489/library/core/src/fmt/rt.rs:152:76
[INFO] [stdout]    5:     0x602f3b4a0dca - core[6771d259883166e6]::fmt::write
[INFO] [stdout]    6:     0x602f3b490da2 - std[128c3efe2914e152]::io::default_write_fmt::<alloc[fef50e8eecedd288]::vec::Vec<u8>>
[INFO] [stdout]                                at /rustc/8489f1adbe1c8f8921cf18b9962cf565237a5489/library/std/src/io/mod.rs:621:11
[INFO] [stdout]    7:     0x602f3b490da2 - <alloc[fef50e8eecedd288]::vec::Vec<u8> as std[128c3efe2914e152]::io::Write>::write_fmt
[INFO] [stdout]                                at /rustc/8489f1adbe1c8f8921cf18b9962cf565237a5489/library/std/src/io/mod.rs:1976:13
[INFO] [stdout]    8:     0x602f3b4692bf - <std[128c3efe2914e152]::sys::backtrace::BacktraceLock>::print
[INFO] [stdout]                                at /rustc/8489f1adbe1c8f8921cf18b9962cf565237a5489/library/std/src/sys/backtrace.rs:47:9
[INFO] [stdout]    9:     0x602f3b4692bf - std[128c3efe2914e152]::panicking::default_hook::{closure#0}
[INFO] [stdout]                                at /rustc/8489f1adbe1c8f8921cf18b9962cf565237a5489/library/std/src/panicking.rs:292:27
[INFO] [stdout]   10:     0x602f3b4829e9 - std[128c3efe2914e152]::panicking::default_hook
[INFO] [stdout]                                at /rustc/8489f1adbe1c8f8921cf18b9962cf565237a5489/library/std/src/panicking.rs:316:9
[INFO] [stdout]   11:     0x602f3b3d4fbc - <alloc[fef50e8eecedd288]::boxed::Box<dyn for<'a, 'b> core[6771d259883166e6]::ops::function::Fn<(&'a std[128c3efe2914e152]::panic::PanicHookInfo<'b>,), Output = ()> + core[6771d259883166e6]::marker::Sync + core[6771d259883166e6]::marker::Send> as core[6771d259883166e6]::ops::function::Fn<(&std[128c3efe2914e152]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/8489f1adbe1c8f8921cf18b9962cf565237a5489/library/alloc/src/boxed.rs:2263:9
[INFO] [stdout]   12:     0x602f3b3d4fbc - test[b137923399915ecf]::test_main_with_exit_callback::<test[b137923399915ecf]::test_main::{closure#0}>::{closure#0}
[INFO] [stdout]                                at /rustc/8489f1adbe1c8f8921cf18b9962cf565237a5489/library/test/src/lib.rs:145:21
[INFO] [stdout]   13:     0x602f3b482ba2 - <alloc[fef50e8eecedd288]::boxed::Box<dyn for<'a, 'b> core[6771d259883166e6]::ops::function::Fn<(&'a std[128c3efe2914e152]::panic::PanicHookInfo<'b>,), Output = ()> + core[6771d259883166e6]::marker::Sync + core[6771d259883166e6]::marker::Send> as core[6771d259883166e6]::ops::function::Fn<(&std[128c3efe2914e152]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/8489f1adbe1c8f8921cf18b9962cf565237a5489/library/alloc/src/boxed.rs:2263:9
[INFO] [stdout]   14:     0x602f3b482ba2 - std[128c3efe2914e152]::panicking::panic_with_hook
[INFO] [stdout]                                at /rustc/8489f1adbe1c8f8921cf18b9962cf565237a5489/library/std/src/panicking.rs:833:13
[INFO] [stdout]   15:     0x602f3b469378 - std[128c3efe2914e152]::panicking::panic_handler::{closure#0}
[INFO] [stdout]                                at /rustc/8489f1adbe1c8f8921cf18b9962cf565237a5489/library/std/src/panicking.rs:698:13
[INFO] [stdout]   16:     0x602f3b45e589 - std[128c3efe2914e152]::sys::backtrace::__rust_end_short_backtrace::<std[128c3efe2914e152]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]                                at /rustc/8489f1adbe1c8f8921cf18b9962cf565237a5489/library/std/src/sys/backtrace.rs:182:18
[INFO] [stdout]   17:     0x602f3b46a08d - __rustc[752cc74e29381ccc]::rust_begin_unwind
[INFO] [stdout]                                at /rustc/8489f1adbe1c8f8921cf18b9962cf565237a5489/library/std/src/panicking.rs:689:5
[INFO] [stdout]   18:     0x602f3b4a15dc - core[6771d259883166e6]::panicking::panic_fmt
[INFO] [stdout]                                at /rustc/8489f1adbe1c8f8921cf18b9962cf565237a5489/library/core/src/panicking.rs:80:14
[INFO] [stdout]   19:     0x602f3b41e938 - core::panicking::panic_display::h30b7e2dd31e53695
[INFO] [stdout]                                at /rustc/8489f1adbe1c8f8921cf18b9962cf565237a5489/library/core/src/panicking.rs:259:5
[INFO] [stdout]   20:     0x602f3b41af40 - assert_cmd::assert::AssertError::panic::he005a72b4e6c48f5
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/assert_cmd-2.2.0/src/assert.rs:1036:9
[INFO] [stdout]   21:     0x602f3b41bc87 - core::ops::function::FnOnce::call_once::h53b6837c9d74cf89
[INFO] [stdout]                                at /rustc/8489f1adbe1c8f8921cf18b9962cf565237a5489/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   22:     0x602f3b40f954 - core::result::Result<T,E>::unwrap_or_else::h4b92189a67947301
[INFO] [stdout]                                at /rustc/8489f1adbe1c8f8921cf18b9962cf565237a5489/library/core/src/result.rs:1622:23
[INFO] [stdout]   23:     0x602f3b41b8c0 - assert_cmd::assert::Assert::success::h8e36f179dc38accb
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/assert_cmd-2.2.0/src/assert.rs:161:28
[INFO] [stdout]   24:     0x602f3b3c70e8 - basic::run_command_detached::hacc75de627238a61
[INFO] [stdout]                                at /opt/rustwide/workdir/tests/basic.rs:115:10
[INFO] [stdout]   25:     0x602f3b3c7587 - basic::run_command_detached::{{closure}}::h5c5c59e74af0c5ab
[INFO] [stdout]                                at /opt/rustwide/workdir/tests/basic.rs:105:30
[INFO] [stdout]   26:     0x602f3b3c45c6 - core::ops::function::FnOnce::call_once::h83fa6c64388ca9b2
[INFO] [stdout]                                at /rustc/8489f1adbe1c8f8921cf18b9962cf565237a5489/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   27:     0x602f3b3c90bb - <fn() -> core[6771d259883166e6]::result::Result<(), alloc[fef50e8eecedd288]::string::String> as core[6771d259883166e6]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/8489f1adbe1c8f8921cf18b9962cf565237a5489/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   28:     0x602f3b3c90bb - test[b137923399915ecf]::__rust_begin_short_backtrace::<core[6771d259883166e6]::result::Result<(), alloc[fef50e8eecedd288]::string::String>, fn() -> core[6771d259883166e6]::result::Result<(), alloc[fef50e8eecedd288]::string::String>>
[INFO] [stdout]                                at /rustc/8489f1adbe1c8f8921cf18b9962cf565237a5489/library/test/src/lib.rs:663:18
[INFO] [stdout]   29:     0x602f3b3d5a8b - test[b137923399915ecf]::run_test_in_process::{closure#0}
[INFO] [stdout]                                at /rustc/8489f1adbe1c8f8921cf18b9962cf565237a5489/library/test/src/lib.rs:686:74
[INFO] [stdout]   30:     0x602f3b3d5a8b - <core[6771d259883166e6]::panic::unwind_safe::AssertUnwindSafe<test[b137923399915ecf]::run_test_in_process::{closure#0}> as core[6771d259883166e6]::ops::function::FnOnce<()>>::call_once
[INFO] [stderr] error: test failed, to rerun pass `--test basic`
[INFO] [stdout]                                at /rustc/8489f1adbe1c8f8921cf18b9962cf565237a5489/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   31:     0x602f3b3d5a8b - std[128c3efe2914e152]::panicking::catch_unwind::do_call::<core[6771d259883166e6]::panic::unwind_safe::AssertUnwindSafe<test[b137923399915ecf]::run_test_in_process::{closure#0}>, core[6771d259883166e6]::result::Result<(), alloc[fef50e8eecedd288]::string::String>>
[INFO] [stdout]                                at /rustc/8489f1adbe1c8f8921cf18b9962cf565237a5489/library/std/src/panicking.rs:581:40
[INFO] [stdout]   32:     0x602f3b3d5a8b - std[128c3efe2914e152]::panicking::catch_unwind::<core[6771d259883166e6]::result::Result<(), alloc[fef50e8eecedd288]::string::String>, core[6771d259883166e6]::panic::unwind_safe::AssertUnwindSafe<test[b137923399915ecf]::run_test_in_process::{closure#0}>>
[INFO] [stdout]                                at /rustc/8489f1adbe1c8f8921cf18b9962cf565237a5489/library/std/src/panicking.rs:544:19
[INFO] [stdout]   33:     0x602f3b3d5a8b - std[128c3efe2914e152]::panic::catch_unwind::<core[6771d259883166e6]::panic::unwind_safe::AssertUnwindSafe<test[b137923399915ecf]::run_test_in_process::{closure#0}>, core[6771d259883166e6]::result::Result<(), alloc[fef50e8eecedd288]::string::String>>
[INFO] [stdout]                                at /rustc/8489f1adbe1c8f8921cf18b9962cf565237a5489/library/std/src/panic.rs:359:14
[INFO] [stdout]   34:     0x602f3b3d5a8b - test[b137923399915ecf]::run_test_in_process
[INFO] [stdout]                                at /rustc/8489f1adbe1c8f8921cf18b9962cf565237a5489/library/test/src/lib.rs:686:27
[INFO] [stdout]   35:     0x602f3b3d5a8b - test[b137923399915ecf]::run_test::{closure#0}
[INFO] [stdout]                                at /rustc/8489f1adbe1c8f8921cf18b9962cf565237a5489/library/test/src/lib.rs:607:43
[INFO] [stdout]   36:     0x602f3b3cfee4 - test[b137923399915ecf]::run_test::{closure#1}
[INFO] [stdout]                                at /rustc/8489f1adbe1c8f8921cf18b9962cf565237a5489/library/test/src/lib.rs:637:41
[INFO] [stdout]   37:     0x602f3b3cfee4 - std[128c3efe2914e152]::sys::backtrace::__rust_begin_short_backtrace::<test[b137923399915ecf]::run_test::{closure#1}, ()>
[INFO] [stdout]                                at /rustc/8489f1adbe1c8f8921cf18b9962cf565237a5489/library/std/src/sys/backtrace.rs:166:18
[INFO] [stdout]   38:     0x602f3b3d8692 - std[128c3efe2914e152]::thread::lifecycle::spawn_unchecked::<test[b137923399915ecf]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}
[INFO] [stdout]                                at /rustc/8489f1adbe1c8f8921cf18b9962cf565237a5489/library/std/src/thread/lifecycle.rs:91:13
[INFO] [stdout]   39:     0x602f3b3d8692 - <core[6771d259883166e6]::panic::unwind_safe::AssertUnwindSafe<std[128c3efe2914e152]::thread::lifecycle::spawn_unchecked<test[b137923399915ecf]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}> as core[6771d259883166e6]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/8489f1adbe1c8f8921cf18b9962cf565237a5489/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   40:     0x602f3b3d8692 - std[128c3efe2914e152]::panicking::catch_unwind::do_call::<core[6771d259883166e6]::panic::unwind_safe::AssertUnwindSafe<std[128c3efe2914e152]::thread::lifecycle::spawn_unchecked<test[b137923399915ecf]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/8489f1adbe1c8f8921cf18b9962cf565237a5489/library/std/src/panicking.rs:581:40
[INFO] [stdout]   41:     0x602f3b3d8692 - std[128c3efe2914e152]::panicking::catch_unwind::<(), core[6771d259883166e6]::panic::unwind_safe::AssertUnwindSafe<std[128c3efe2914e152]::thread::lifecycle::spawn_unchecked<test[b137923399915ecf]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>>
[INFO] [stdout]                                at /rustc/8489f1adbe1c8f8921cf18b9962cf565237a5489/library/std/src/panicking.rs:544:19
[INFO] [stdout]   42:     0x602f3b3d8692 - std[128c3efe2914e152]::panic::catch_unwind::<core[6771d259883166e6]::panic::unwind_safe::AssertUnwindSafe<std[128c3efe2914e152]::thread::lifecycle::spawn_unchecked<test[b137923399915ecf]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/8489f1adbe1c8f8921cf18b9962cf565237a5489/library/std/src/panic.rs:359:14
[INFO] [stdout]   43:     0x602f3b3d8692 - std[128c3efe2914e152]::thread::lifecycle::spawn_unchecked::<test[b137923399915ecf]::run_test::{closure#1}, ()>::{closure#1}
[INFO] [stdout]                                at /rustc/8489f1adbe1c8f8921cf18b9962cf565237a5489/library/std/src/thread/lifecycle.rs:89:26
[INFO] [stdout]   44:     0x602f3b3d8692 - <std[128c3efe2914e152]::thread::lifecycle::spawn_unchecked<test[b137923399915ecf]::run_test::{closure#1}, ()>::{closure#1} as core[6771d259883166e6]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]                                at /rustc/8489f1adbe1c8f8921cf18b9962cf565237a5489/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   45:     0x602f3b48a04f - <alloc[fef50e8eecedd288]::boxed::Box<dyn core[6771d259883166e6]::ops::function::FnOnce<(), Output = ()> + core[6771d259883166e6]::marker::Send> as core[6771d259883166e6]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/8489f1adbe1c8f8921cf18b9962cf565237a5489/library/alloc/src/boxed.rs:2249:9
[INFO] [stdout]   46:     0x602f3b48a04f - <std[128c3efe2914e152]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]                                at /rustc/8489f1adbe1c8f8921cf18b9962cf565237a5489/library/std/src/sys/thread/unix.rs:118:17
[INFO] [stdout]   47:     0x78e844310aa4 - <unknown>
[INFO] [stdout]   48:     0x78e84439da64 - clone
[INFO] [stdout]   49:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- exec_command_and_stop stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'exec_command_and_stop' (64) panicked at /rustc/8489f1adbe1c8f8921cf18b9962cf565237a5489/library/core/src/ops/function.rs:250:5:
[INFO] [stdout] Unexpected failure.
[INFO] [stdout] code=1
[INFO] [stdout] stderr=``````
[INFO] [stdout] Error: 
[INFO] [stdout]    0: \x1b[91mCouldn\'t find qemu-system-x86_64 in PATH\x1b[0m
[INFO] [stdout]    1: \x1b[91mcannot find binary path\x1b[0m
[INFO] [stdout] 
[INFO] [stdout] Location:
[INFO] [stdout]    \x1b[35msrc/utils.rs\x1b[0m:\x1b[35m171\x1b[0m
[INFO] [stdout] 
[INFO] [stdout]   ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ BACKTRACE ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━
[INFO] [stdout]   \x1b[96m                              ⋮ 4 frames hidden ⋮                               \x1b[0m
[INFO] [stdout]    5: \x1b[91m<E as eyre::context::ext::StdError>::ext_report\x1b[0m\x1b[90m::h916e2b046f9e0e90\x1b[0m
[INFO] [stdout]       at \x1b[35m/opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/eyre-0.6.12/src/context.rs\x1b[0m:\x1b[35m26\x1b[0m
[INFO] [stdout]         24 │             D: Display + Send + Sync + \'static,
[INFO] [stdout]         25 │         {
[INFO] [stdout]   \x1b[37;1m      26\x1b[0m \x1b[37;1m>\x1b[0m \x1b[37;1m            Report::from_msg(msg, self)\x1b[0m
[INFO] [stdout]         27 │         }
[INFO] [stdout]         28 │     }
[INFO] [stdout]   \x1b[96m                               ⋮ 1 frame hidden ⋮                               \x1b[0m
[INFO] [stdout]    7: \x1b[91mvmexec::utils::find_required_tools\x1b[0m\x1b[90m::hd5b0c5707761bb63\x1b[0m
[INFO] [stdout]       at \x1b[35m/opt/rustwide/workdir/src/utils.rs\x1b[0m:\x1b[35m171\x1b[0m
[INFO] [stdout]        169 │     // Find QEMUU
[INFO] [stdout]        170 │     let qemu_path = which::which_global(\"qemu-system-x86_64\")
[INFO] [stdout]   \x1b[37;1m     171\x1b[0m \x1b[37;1m>\x1b[0m \x1b[37;1m        .wrap_err(\"Couldn\'t find qemu-system-x86_64 in PATH\")?;\x1b[0m
[INFO] [stdout]        172 │ 
[INFO] [stdout]        173 │     // Find virtiofsd
[INFO] [stdout]    8: \x1b[91mvmexec::run_command\x1b[0m\x1b[90m::hcc2af1188d864d38\x1b[0m
[INFO] [stdout]       at \x1b[35m/opt/rustwide/workdir/src/main.rs\x1b[0m:\x1b[35m371\x1b[0m
[INFO] [stdout]        369 │ fn run_command(run_args: RunCommand) -> Result<Option<u32>> {
[INFO] [stdout]        370 │     // Make sure the tools we need are actually installed.
[INFO] [stdout]   \x1b[37;1m     371\x1b[0m \x1b[37;1m>\x1b[0m \x1b[37;1m    let tool_paths = find_required_tools()?;\x1b[0m
[INFO] [stdout]        372 │ 
[INFO] [stdout]        373 │     // Check whether KSM is active.
[INFO] [stdout]    9: \x1b[91mvmexec::main\x1b[0m\x1b[90m::h716de3d663de88fb\x1b[0m
[INFO] [stdout]       at \x1b[35m/opt/rustwide/workdir/src/main.rs\x1b[0m:\x1b[35m543\x1b[0m
[INFO] [stdout]        541 │         Command::Exec(exec_args) => exec_command(exec_args)?,
[INFO] [stdout]        542 │         Command::Run(run_args) => {
[INFO] [stdout]   \x1b[37;1m     543\x1b[0m \x1b[37;1m>\x1b[0m \x1b[37;1m            let exit_code = run_command(run_args)?;\x1b[0m
[INFO] [stdout]        544 │             if let Some(child_code) = exit_code {
[INFO] [stdout]        545 │                 process::exit(child_code.try_into()?);
[INFO] [stdout]   10: \x1b[32mcore::ops::function::FnOnce::call_once\x1b[0m\x1b[90m::ha8f31c3443b29a80\x1b[0m
[INFO] [stdout]       at \x1b[35m/rustc/8489f1adbe1c8f8921cf18b9962cf565237a5489/library/core/src/ops/function.rs\x1b[0m:\x1b[35m250\x1b[0m
[INFO] [stdout]   11: \x1b[32mstd::sys::backtrace::__rust_begin_short_backtrace\x1b[0m\x1b[90m::h8fcf821ab45ee1a9\x1b[0m
[INFO] [stdout]       at \x1b[35m/rustc/8489f1adbe1c8f8921cf18b9962cf565237a5489/library/std/src/sys/backtrace.rs\x1b[0m:\x1b[35m166\x1b[0m
[INFO] [stdout]   \x1b[96m                              ⋮ 14 frames hidden ⋮                              \x1b[0m
[INFO] [stdout] 
[INFO] [stdout] Run with COLORBT_SHOW_HIDDEN=1 environment variable to disable frame filtering.
[INFO] [stdout] ```
[INFO] [stdout] ```
[INFO] [stdout] command=`"/opt/rustwide/target/debug/vmexec" "run" "archlinux" "--detach" "--rm" "--disable-kvm" "--ssh-timeout" "180" "sleep" "180"`
[INFO] [stdout] code=1
[INFO] [stdout] stdout=""
[INFO] [stdout] stderr=```
[INFO] [stdout] Error: 
[INFO] [stdout]    0: \x1b[91mCouldn\'t find qemu-system-x86_64 in PATH\x1b[0m
[INFO] [stdout]    1: \x1b[91mcannot find binary path\x1b[0m
[INFO] [stdout] 
[INFO] [stdout] Location:
[INFO] [stdout]    \x1b[35msrc/utils.rs\x1b[0m:\x1b[35m171\x1b[0m
[INFO] [stdout] 
[INFO] [stdout]   ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ BACKTRACE ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━
[INFO] [stdout]   \x1b[96m                              ⋮ 4 frames hidden ⋮                               \x1b[0m
[INFO] [stdout]    5: \x1b[91m<E as eyre::context::ext::StdError>::ext_report\x1b[0m\x1b[90m::h916e2b046f9e0e90\x1b[0m
[INFO] [stdout]       at \x1b[35m/opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/eyre-0.6.12/src/context.rs\x1b[0m:\x1b[35m26\x1b[0m
[INFO] [stdout]         24 │             D: Display + Send + Sync + \'static,
[INFO] [stdout]         25 │         {
[INFO] [stdout]   \x1b[37;1m      26\x1b[0m \x1b[37;1m>\x1b[0m \x1b[37;1m            Report::from_msg(msg, self)\x1b[0m
[INFO] [stdout]         27 │         }
[INFO] [stdout]         28 │     }
[INFO] [stdout]   \x1b[96m                               ⋮ 1 frame hidden ⋮                               \x1b[0m
[INFO] [stdout]    7: \x1b[91mvmexec::utils::find_required_tools\x1b[0m\x1b[90m::hd5b0c5707761bb63\x1b[0m
[INFO] [stdout]       at \x1b[35m/opt/rustwide/workdir/src/utils.rs\x1b[0m:\x1b[35m171\x1b[0m
[INFO] [stdout]        169 │     // Find QEMUU
[INFO] [stdout]        170 │     let qemu_path = which::which_global(\"qemu-system-x86_64\")
[INFO] [stdout]   \x1b[37;1m     171\x1b[0m \x1b[37;1m>\x1b[0m \x1b[37;1m        .wrap_err(\"Couldn\'t find qemu-system-x86_64 in PATH\")?;\x1b[0m
[INFO] [stdout]        172 │ 
[INFO] [stdout]        173 │     // Find virtiofsd
[INFO] [stdout]    8: \x1b[91mvmexec::run_command\x1b[0m\x1b[90m::hcc2af1188d864d38\x1b[0m
[INFO] [stdout]       at \x1b[35m/opt/rustwide/workdir/src/main.rs\x1b[0m:\x1b[35m371\x1b[0m
[INFO] [stdout]        369 │ fn run_command(run_args: RunCommand) -> Result<Option<u32>> {
[INFO] [stdout]        370 │     // Make sure the tools we need are actually installed.
[INFO] [stdout]   \x1b[37;1m     371\x1b[0m \x1b[37;1m>\x1b[0m \x1b[37;1m    let tool_paths = find_required_tools()?;\x1b[0m
[INFO] [stdout]        372 │ 
[INFO] [stdout]        373 │     // Check whether KSM is active.
[INFO] [stdout]    9: \x1b[91mvmexec::main\x1b[0m\x1b[90m::h716de3d663de88fb\x1b[0m
[INFO] [stdout]       at \x1b[35m/opt/rustwide/workdir/src/main.rs\x1b[0m:\x1b[35m543\x1b[0m
[INFO] [stdout]        541 │         Command::Exec(exec_args) => exec_command(exec_args)?,
[INFO] [stdout]        542 │         Command::Run(run_args) => {
[INFO] [stdout]   \x1b[37;1m     543\x1b[0m \x1b[37;1m>\x1b[0m \x1b[37;1m            let exit_code = run_command(run_args)?;\x1b[0m
[INFO] [stdout]        544 │             if let Some(child_code) = exit_code {
[INFO] [stdout]        545 │                 process::exit(child_code.try_into()?);
[INFO] [stdout]   10: \x1b[32mcore::ops::function::FnOnce::call_once\x1b[0m\x1b[90m::ha8f31c3443b29a80\x1b[0m
[INFO] [stdout]       at \x1b[35m/rustc/8489f1adbe1c8f8921cf18b9962cf565237a5489/library/core/src/ops/function.rs\x1b[0m:\x1b[35m250\x1b[0m
[INFO] [stdout]   11: \x1b[32mstd::sys::backtrace::__rust_begin_short_backtrace\x1b[0m\x1b[90m::h8fcf821ab45ee1a9\x1b[0m
[INFO] [stdout]       at \x1b[35m/rustc/8489f1adbe1c8f8921cf18b9962cf565237a5489/library/std/src/sys/backtrace.rs\x1b[0m:\x1b[35m166\x1b[0m
[INFO] [stdout]   \x1b[96m                              ⋮ 14 frames hidden ⋮                              \x1b[0m
[INFO] [stdout] 
[INFO] [stdout] Run with COLORBT_SHOW_HIDDEN=1 environment variable to disable frame filtering.
[INFO] [stdout] ```
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x602f3b48a7ea - std[128c3efe2914e152]::backtrace_rs::backtrace::libunwind::trace
[INFO] [stdout]                                at /rustc/8489f1adbe1c8f8921cf18b9962cf565237a5489/library/std/src/../../backtrace/src/backtrace/libunwind.rs:117:9
[INFO] [stdout]    1:     0x602f3b48a7ea - std[128c3efe2914e152]::backtrace_rs::backtrace::trace_unsynchronized::<std[128c3efe2914e152]::sys::backtrace::_print_fmt::{closure#1}>
[INFO] [stdout]                                at /rustc/8489f1adbe1c8f8921cf18b9962cf565237a5489/library/std/src/../../backtrace/src/backtrace/mod.rs:66:14
[INFO] [stdout]    2:     0x602f3b48a7ea - std[128c3efe2914e152]::sys::backtrace::_print_fmt
[INFO] [stdout]                                at /rustc/8489f1adbe1c8f8921cf18b9962cf565237a5489/library/std/src/sys/backtrace.rs:74:9
[INFO] [stdout]    3:     0x602f3b48a7ea - <<std[128c3efe2914e152]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[6771d259883166e6]::fmt::Display>::fmt
[INFO] [stdout]                                at /rustc/8489f1adbe1c8f8921cf18b9962cf565237a5489/library/std/src/sys/backtrace.rs:44:26
[INFO] [stdout]    4:     0x602f3b4a0dca - <core[6771d259883166e6]::fmt::rt::Argument>::fmt
[INFO] [stdout]                                at /rustc/8489f1adbe1c8f8921cf18b9962cf565237a5489/library/core/src/fmt/rt.rs:152:76
[INFO] [stdout]    5:     0x602f3b4a0dca - core[6771d259883166e6]::fmt::write
[INFO] [stdout]    6:     0x602f3b490da2 - std[128c3efe2914e152]::io::default_write_fmt::<alloc[fef50e8eecedd288]::vec::Vec<u8>>
[INFO] [stdout]                                at /rustc/8489f1adbe1c8f8921cf18b9962cf565237a5489/library/std/src/io/mod.rs:621:11
[INFO] [stdout]    7:     0x602f3b490da2 - <alloc[fef50e8eecedd288]::vec::Vec<u8> as std[128c3efe2914e152]::io::Write>::write_fmt
[INFO] [stdout]                                at /rustc/8489f1adbe1c8f8921cf18b9962cf565237a5489/library/std/src/io/mod.rs:1976:13
[INFO] [stdout]    8:     0x602f3b4692bf - <std[128c3efe2914e152]::sys::backtrace::BacktraceLock>::print
[INFO] [stdout]                                at /rustc/8489f1adbe1c8f8921cf18b9962cf565237a5489/library/std/src/sys/backtrace.rs:47:9
[INFO] [stdout]    9:     0x602f3b4692bf - std[128c3efe2914e152]::panicking::default_hook::{closure#0}
[INFO] [stdout]                                at /rustc/8489f1adbe1c8f8921cf18b9962cf565237a5489/library/std/src/panicking.rs:292:27
[INFO] [stdout]   10:     0x602f3b4829e9 - std[128c3efe2914e152]::panicking::default_hook
[INFO] [stdout]                                at /rustc/8489f1adbe1c8f8921cf18b9962cf565237a5489/library/std/src/panicking.rs:316:9
[INFO] [stdout]   11:     0x602f3b3d4fbc - <alloc[fef50e8eecedd288]::boxed::Box<dyn for<'a, 'b> core[6771d259883166e6]::ops::function::Fn<(&'a std[128c3efe2914e152]::panic::PanicHookInfo<'b>,), Output = ()> + core[6771d259883166e6]::marker::Sync + core[6771d259883166e6]::marker::Send> as core[6771d259883166e6]::ops::function::Fn<(&std[128c3efe2914e152]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/8489f1adbe1c8f8921cf18b9962cf565237a5489/library/alloc/src/boxed.rs:2263:9
[INFO] [stdout]   12:     0x602f3b3d4fbc - test[b137923399915ecf]::test_main_with_exit_callback::<test[b137923399915ecf]::test_main::{closure#0}>::{closure#0}
[INFO] [stdout]                                at /rustc/8489f1adbe1c8f8921cf18b9962cf565237a5489/library/test/src/lib.rs:145:21
[INFO] [stdout]   13:     0x602f3b482ba2 - <alloc[fef50e8eecedd288]::boxed::Box<dyn for<'a, 'b> core[6771d259883166e6]::ops::function::Fn<(&'a std[128c3efe2914e152]::panic::PanicHookInfo<'b>,), Output = ()> + core[6771d259883166e6]::marker::Sync + core[6771d259883166e6]::marker::Send> as core[6771d259883166e6]::ops::function::Fn<(&std[128c3efe2914e152]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/8489f1adbe1c8f8921cf18b9962cf565237a5489/library/alloc/src/boxed.rs:2263:9
[INFO] [stdout]   14:     0x602f3b482ba2 - std[128c3efe2914e152]::panicking::panic_with_hook
[INFO] [stdout]                                at /rustc/8489f1adbe1c8f8921cf18b9962cf565237a5489/library/std/src/panicking.rs:833:13
[INFO] [stdout]   15:     0x602f3b469378 - std[128c3efe2914e152]::panicking::panic_handler::{closure#0}
[INFO] [stdout]                                at /rustc/8489f1adbe1c8f8921cf18b9962cf565237a5489/library/std/src/panicking.rs:698:13
[INFO] [stdout]   16:     0x602f3b45e589 - std[128c3efe2914e152]::sys::backtrace::__rust_end_short_backtrace::<std[128c3efe2914e152]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]                                at /rustc/8489f1adbe1c8f8921cf18b9962cf565237a5489/library/std/src/sys/backtrace.rs:182:18
[INFO] [stdout]   17:     0x602f3b46a08d - __rustc[752cc74e29381ccc]::rust_begin_unwind
[INFO] [stdout]                                at /rustc/8489f1adbe1c8f8921cf18b9962cf565237a5489/library/std/src/panicking.rs:689:5
[INFO] [stdout]   18:     0x602f3b4a15dc - core[6771d259883166e6]::panicking::panic_fmt
[INFO] [stdout]                                at /rustc/8489f1adbe1c8f8921cf18b9962cf565237a5489/library/core/src/panicking.rs:80:14
[INFO] [stdout]   19:     0x602f3b41e938 - core::panicking::panic_display::h30b7e2dd31e53695
[INFO] [stdout]                                at /rustc/8489f1adbe1c8f8921cf18b9962cf565237a5489/library/core/src/panicking.rs:259:5
[INFO] [stdout]   20:     0x602f3b41af40 - assert_cmd::assert::AssertError::panic::he005a72b4e6c48f5
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/assert_cmd-2.2.0/src/assert.rs:1036:9
[INFO] [stdout]   21:     0x602f3b41bc87 - core::ops::function::FnOnce::call_once::h53b6837c9d74cf89
[INFO] [stdout]                                at /rustc/8489f1adbe1c8f8921cf18b9962cf565237a5489/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   22:     0x602f3b40f954 - core::result::Result<T,E>::unwrap_or_else::h4b92189a67947301
[INFO] [stdout]                                at /rustc/8489f1adbe1c8f8921cf18b9962cf565237a5489/library/core/src/result.rs:1622:23
[INFO] [stdout]   23:     0x602f3b41b8c0 - assert_cmd::assert::Assert::success::h8e36f179dc38accb
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/assert_cmd-2.2.0/src/assert.rs:161:28
[INFO] [stdout]   24:     0x602f3b3c77b0 - basic::exec_command_and_stop::h94b38ccda8278d77
[INFO] [stdout]                                at /opt/rustwide/workdir/tests/basic.rs:29:10
[INFO] [stdout]   25:     0x602f3b3c8337 - basic::exec_command_and_stop::{{closure}}::h64555ff4a55ae0c9
[INFO] [stdout]                                at /opt/rustwide/workdir/tests/basic.rs:19:31
[INFO] [stdout]   26:     0x602f3b3c4536 - core::ops::function::FnOnce::call_once::h430a693a7a0903ad
[INFO] [stdout]                                at /rustc/8489f1adbe1c8f8921cf18b9962cf565237a5489/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   27:     0x602f3b3c90bb - <fn() -> core[6771d259883166e6]::result::Result<(), alloc[fef50e8eecedd288]::string::String> as core[6771d259883166e6]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/8489f1adbe1c8f8921cf18b9962cf565237a5489/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   28:     0x602f3b3c90bb - test[b137923399915ecf]::__rust_begin_short_backtrace::<core[6771d259883166e6]::result::Result<(), alloc[fef50e8eecedd288]::string::String>, fn() -> core[6771d259883166e6]::result::Result<(), alloc[fef50e8eecedd288]::string::String>>
[INFO] [stdout]                                at /rustc/8489f1adbe1c8f8921cf18b9962cf565237a5489/library/test/src/lib.rs:663:18
[INFO] [stdout]   29:     0x602f3b3d5a8b - test[b137923399915ecf]::run_test_in_process::{closure#0}
[INFO] [stdout]                                at /rustc/8489f1adbe1c8f8921cf18b9962cf565237a5489/library/test/src/lib.rs:686:74
[INFO] [stdout]   30:     0x602f3b3d5a8b - <core[6771d259883166e6]::panic::unwind_safe::AssertUnwindSafe<test[b137923399915ecf]::run_test_in_process::{closure#0}> as core[6771d259883166e6]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/8489f1adbe1c8f8921cf18b9962cf565237a5489/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   31:     0x602f3b3d5a8b - std[128c3efe2914e152]::panicking::catch_unwind::do_call::<core[6771d259883166e6]::panic::unwind_safe::AssertUnwindSafe<test[b137923399915ecf]::run_test_in_process::{closure#0}>, core[6771d259883166e6]::result::Result<(), alloc[fef50e8eecedd288]::string::String>>
[INFO] [stdout]                                at /rustc/8489f1adbe1c8f8921cf18b9962cf565237a5489/library/std/src/panicking.rs:581:40
[INFO] [stdout]   32:     0x602f3b3d5a8b - std[128c3efe2914e152]::panicking::catch_unwind::<core[6771d259883166e6]::result::Result<(), alloc[fef50e8eecedd288]::string::String>, core[6771d259883166e6]::panic::unwind_safe::AssertUnwindSafe<test[b137923399915ecf]::run_test_in_process::{closure#0}>>
[INFO] [stdout]                                at /rustc/8489f1adbe1c8f8921cf18b9962cf565237a5489/library/std/src/panicking.rs:544:19
[INFO] [stdout]   33:     0x602f3b3d5a8b - std[128c3efe2914e152]::panic::catch_unwind::<core[6771d259883166e6]::panic::unwind_safe::AssertUnwindSafe<test[b137923399915ecf]::run_test_in_process::{closure#0}>, core[6771d259883166e6]::result::Result<(), alloc[fef50e8eecedd288]::string::String>>
[INFO] [stdout]                                at /rustc/8489f1adbe1c8f8921cf18b9962cf565237a5489/library/std/src/panic.rs:359:14
[INFO] [stdout]   34:     0x602f3b3d5a8b - test[b137923399915ecf]::run_test_in_process
[INFO] [stdout]                                at /rustc/8489f1adbe1c8f8921cf18b9962cf565237a5489/library/test/src/lib.rs:686:27
[INFO] [stdout]   35:     0x602f3b3d5a8b - test[b137923399915ecf]::run_test::{closure#0}
[INFO] [stdout]                                at /rustc/8489f1adbe1c8f8921cf18b9962cf565237a5489/library/test/src/lib.rs:607:43
[INFO] [stdout]   36:     0x602f3b3cfee4 - test[b137923399915ecf]::run_test::{closure#1}
[INFO] [stdout]                                at /rustc/8489f1adbe1c8f8921cf18b9962cf565237a5489/library/test/src/lib.rs:637:41
[INFO] [stdout]   37:     0x602f3b3cfee4 - std[128c3efe2914e152]::sys::backtrace::__rust_begin_short_backtrace::<test[b137923399915ecf]::run_test::{closure#1}, ()>
[INFO] [stdout]                                at /rustc/8489f1adbe1c8f8921cf18b9962cf565237a5489/library/std/src/sys/backtrace.rs:166:18
[INFO] [stdout]   38:     0x602f3b3d8692 - std[128c3efe2914e152]::thread::lifecycle::spawn_unchecked::<test[b137923399915ecf]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}
[INFO] [stdout]                                at /rustc/8489f1adbe1c8f8921cf18b9962cf565237a5489/library/std/src/thread/lifecycle.rs:91:13
[INFO] [stdout]   39:     0x602f3b3d8692 - <core[6771d259883166e6]::panic::unwind_safe::AssertUnwindSafe<std[128c3efe2914e152]::thread::lifecycle::spawn_unchecked<test[b137923399915ecf]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}> as core[6771d259883166e6]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/8489f1adbe1c8f8921cf18b9962cf565237a5489/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   40:     0x602f3b3d8692 - std[128c3efe2914e152]::panicking::catch_unwind::do_call::<core[6771d259883166e6]::panic::unwind_safe::AssertUnwindSafe<std[128c3efe2914e152]::thread::lifecycle::spawn_unchecked<test[b137923399915ecf]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/8489f1adbe1c8f8921cf18b9962cf565237a5489/library/std/src/panicking.rs:581:40
[INFO] [stdout]   41:     0x602f3b3d8692 - std[128c3efe2914e152]::panicking::catch_unwind::<(), core[6771d259883166e6]::panic::unwind_safe::AssertUnwindSafe<std[128c3efe2914e152]::thread::lifecycle::spawn_unchecked<test[b137923399915ecf]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>>
[INFO] [stdout]                                at /rustc/8489f1adbe1c8f8921cf18b9962cf565237a5489/library/std/src/panicking.rs:544:19
[INFO] [stdout]   42:     0x602f3b3d8692 - std[128c3efe2914e152]::panic::catch_unwind::<core[6771d259883166e6]::panic::unwind_safe::AssertUnwindSafe<std[128c3efe2914e152]::thread::lifecycle::spawn_unchecked<test[b137923399915ecf]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/8489f1adbe1c8f8921cf18b9962cf565237a5489/library/std/src/panic.rs:359:14
[INFO] [stdout]   43:     0x602f3b3d8692 - std[128c3efe2914e152]::thread::lifecycle::spawn_unchecked::<test[b137923399915ecf]::run_test::{closure#1}, ()>::{closure#1}
[INFO] [stdout]                                at /rustc/8489f1adbe1c8f8921cf18b9962cf565237a5489/library/std/src/thread/lifecycle.rs:89:26
[INFO] [stdout]   44:     0x602f3b3d8692 - <std[128c3efe2914e152]::thread::lifecycle::spawn_unchecked<test[b137923399915ecf]::run_test::{closure#1}, ()>::{closure#1} as core[6771d259883166e6]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]                                at /rustc/8489f1adbe1c8f8921cf18b9962cf565237a5489/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   45:     0x602f3b48a04f - <alloc[fef50e8eecedd288]::boxed::Box<dyn core[6771d259883166e6]::ops::function::FnOnce<(), Output = ()> + core[6771d259883166e6]::marker::Send> as core[6771d259883166e6]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/8489f1adbe1c8f8921cf18b9962cf565237a5489/library/alloc/src/boxed.rs:2249:9
[INFO] [stdout]   46:     0x602f3b48a04f - <std[128c3efe2914e152]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]                                at /rustc/8489f1adbe1c8f8921cf18b9962cf565237a5489/library/std/src/sys/thread/unix.rs:118:17
[INFO] [stdout]   47:     0x78e844310aa4 - <unknown>
[INFO] [stdout]   48:     0x78e84439da64 - clone
[INFO] [stdout]   49:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- run_command stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'run_command' (66) panicked at /rustc/8489f1adbe1c8f8921cf18b9962cf565237a5489/library/core/src/ops/function.rs:250:5:
[INFO] [stdout] Unexpected failure.
[INFO] [stdout] code=1
[INFO] [stdout] stderr=``````
[INFO] [stdout] Error: 
[INFO] [stdout]    0: \x1b[91mCouldn\'t find qemu-system-x86_64 in PATH\x1b[0m
[INFO] [stdout]    1: \x1b[91mcannot find binary path\x1b[0m
[INFO] [stdout] 
[INFO] [stdout] Location:
[INFO] [stdout]    \x1b[35msrc/utils.rs\x1b[0m:\x1b[35m171\x1b[0m
[INFO] [stdout] 
[INFO] [stdout]   ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ BACKTRACE ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━
[INFO] [stdout]   \x1b[96m                              ⋮ 4 frames hidden ⋮                               \x1b[0m
[INFO] [stdout]    5: \x1b[91m<E as eyre::context::ext::StdError>::ext_report\x1b[0m\x1b[90m::h916e2b046f9e0e90\x1b[0m
[INFO] [stdout]       at \x1b[35m/opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/eyre-0.6.12/src/context.rs\x1b[0m:\x1b[35m26\x1b[0m
[INFO] [stdout]         24 │             D: Display + Send + Sync + \'static,
[INFO] [stdout]         25 │         {
[INFO] [stdout]   \x1b[37;1m      26\x1b[0m \x1b[37;1m>\x1b[0m \x1b[37;1m            Report::from_msg(msg, self)\x1b[0m
[INFO] [stdout]         27 │         }
[INFO] [stdout]         28 │     }
[INFO] [stdout]   \x1b[96m                               ⋮ 1 frame hidden ⋮                               \x1b[0m
[INFO] [stdout]    7: \x1b[91mvmexec::utils::find_required_tools\x1b[0m\x1b[90m::hd5b0c5707761bb63\x1b[0m
[INFO] [stdout]       at \x1b[35m/opt/rustwide/workdir/src/utils.rs\x1b[0m:\x1b[35m171\x1b[0m
[INFO] [stdout]        169 │     // Find QEMUU
[INFO] [stdout]        170 │     let qemu_path = which::which_global(\"qemu-system-x86_64\")
[INFO] [stdout]   \x1b[37;1m     171\x1b[0m \x1b[37;1m>\x1b[0m \x1b[37;1m        .wrap_err(\"Couldn\'t find qemu-system-x86_64 in PATH\")?;\x1b[0m
[INFO] [stdout]        172 │ 
[INFO] [stdout]        173 │     // Find virtiofsd
[INFO] [stdout]    8: \x1b[91mvmexec::run_command\x1b[0m\x1b[90m::hcc2af1188d864d38\x1b[0m
[INFO] [stdout]       at \x1b[35m/opt/rustwide/workdir/src/main.rs\x1b[0m:\x1b[35m371\x1b[0m
[INFO] [stdout]        369 │ fn run_command(run_args: RunCommand) -> Result<Option<u32>> {
[INFO] [stdout]        370 │     // Make sure the tools we need are actually installed.
[INFO] [stdout]   \x1b[37;1m     371\x1b[0m \x1b[37;1m>\x1b[0m \x1b[37;1m    let tool_paths = find_required_tools()?;\x1b[0m
[INFO] [stdout]        372 │ 
[INFO] [stdout]        373 │     // Check whether KSM is active.
[INFO] [stdout]    9: \x1b[91mvmexec::main\x1b[0m\x1b[90m::h716de3d663de88fb\x1b[0m
[INFO] [stdout]       at \x1b[35m/opt/rustwide/workdir/src/main.rs\x1b[0m:\x1b[35m543\x1b[0m
[INFO] [stdout]        541 │         Command::Exec(exec_args) => exec_command(exec_args)?,
[INFO] [stdout]        542 │         Command::Run(run_args) => {
[INFO] [stdout]   \x1b[37;1m     543\x1b[0m \x1b[37;1m>\x1b[0m \x1b[37;1m            let exit_code = run_command(run_args)?;\x1b[0m
[INFO] [stdout]        544 │             if let Some(child_code) = exit_code {
[INFO] [stdout]        545 │                 process::exit(child_code.try_into()?);
[INFO] [stdout]   10: \x1b[32mcore::ops::function::FnOnce::call_once\x1b[0m\x1b[90m::ha8f31c3443b29a80\x1b[0m
[INFO] [stdout]       at \x1b[35m/rustc/8489f1adbe1c8f8921cf18b9962cf565237a5489/library/core/src/ops/function.rs\x1b[0m:\x1b[35m250\x1b[0m
[INFO] [stdout]   11: \x1b[32mstd::sys::backtrace::__rust_begin_short_backtrace\x1b[0m\x1b[90m::h8fcf821ab45ee1a9\x1b[0m
[INFO] [stdout]       at \x1b[35m/rustc/8489f1adbe1c8f8921cf18b9962cf565237a5489/library/std/src/sys/backtrace.rs\x1b[0m:\x1b[35m166\x1b[0m
[INFO] [stdout]   \x1b[96m                              ⋮ 14 frames hidden ⋮                              \x1b[0m
[INFO] [stdout] 
[INFO] [stdout] Run with COLORBT_SHOW_HIDDEN=1 environment variable to disable frame filtering.
[INFO] [stdout] ```
[INFO] [stdout] ```
[INFO] [stdout] command=`"/opt/rustwide/target/debug/vmexec" "run" "archlinux" "--vmid" "testcmd" "--disable-kvm" "--rm" "--ssh-timeout" "180" "echo" "hello yes"`
[INFO] [stdout] code=1
[INFO] [stdout] stdout=""
[INFO] [stdout] stderr=```
[INFO] [stdout] Error: 
[INFO] [stdout]    0: \x1b[91mCouldn\'t find qemu-system-x86_64 in PATH\x1b[0m
[INFO] [stdout]    1: \x1b[91mcannot find binary path\x1b[0m
[INFO] [stdout] 
[INFO] [stdout] Location:
[INFO] [stdout]    \x1b[35msrc/utils.rs\x1b[0m:\x1b[35m171\x1b[0m
[INFO] [stdout] 
[INFO] [stdout]   ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ BACKTRACE ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━
[INFO] [stdout]   \x1b[96m                              ⋮ 4 frames hidden ⋮                               \x1b[0m
[INFO] [stdout]    5: \x1b[91m<E as eyre::context::ext::StdError>::ext_report\x1b[0m\x1b[90m::h916e2b046f9e0e90\x1b[0m
[INFO] [stdout]       at \x1b[35m/opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/eyre-0.6.12/src/context.rs\x1b[0m:\x1b[35m26\x1b[0m
[INFO] [stdout]         24 │             D: Display + Send + Sync + \'static,
[INFO] [stdout]         25 │         {
[INFO] [stdout]   \x1b[37;1m      26\x1b[0m \x1b[37;1m>\x1b[0m \x1b[37;1m            Report::from_msg(msg, self)\x1b[0m
[INFO] [stdout]         27 │         }
[INFO] [stdout]         28 │     }
[INFO] [stdout]   \x1b[96m                               ⋮ 1 frame hidden ⋮                               \x1b[0m
[INFO] [stdout]    7: \x1b[91mvmexec::utils::find_required_tools\x1b[0m\x1b[90m::hd5b0c5707761bb63\x1b[0m
[INFO] [stdout]       at \x1b[35m/opt/rustwide/workdir/src/utils.rs\x1b[0m:\x1b[35m171\x1b[0m
[INFO] [stdout]        169 │     // Find QEMUU
[INFO] [stdout]        170 │     let qemu_path = which::which_global(\"qemu-system-x86_64\")
[INFO] [stdout]   \x1b[37;1m     171\x1b[0m \x1b[37;1m>\x1b[0m \x1b[37;1m        .wrap_err(\"Couldn\'t find qemu-system-x86_64 in PATH\")?;\x1b[0m
[INFO] [stdout]        172 │ 
[INFO] [stdout]        173 │     // Find virtiofsd
[INFO] [stdout]    8: \x1b[91mvmexec::run_command\x1b[0m\x1b[90m::hcc2af1188d864d38\x1b[0m
[INFO] [stdout]       at \x1b[35m/opt/rustwide/workdir/src/main.rs\x1b[0m:\x1b[35m371\x1b[0m
[INFO] [stdout]        369 │ fn run_command(run_args: RunCommand) -> Result<Option<u32>> {
[INFO] [stdout]        370 │     // Make sure the tools we need are actually installed.
[INFO] [stdout]   \x1b[37;1m     371\x1b[0m \x1b[37;1m>\x1b[0m \x1b[37;1m    let tool_paths = find_required_tools()?;\x1b[0m
[INFO] [stdout]        372 │ 
[INFO] [stdout]        373 │     // Check whether KSM is active.
[INFO] [stdout]    9: \x1b[91mvmexec::main\x1b[0m\x1b[90m::h716de3d663de88fb\x1b[0m
[INFO] [stdout]       at \x1b[35m/opt/rustwide/workdir/src/main.rs\x1b[0m:\x1b[35m543\x1b[0m
[INFO] [stdout]        541 │         Command::Exec(exec_args) => exec_command(exec_args)?,
[INFO] [stdout]        542 │         Command::Run(run_args) => {
[INFO] [stdout]   \x1b[37;1m     543\x1b[0m \x1b[37;1m>\x1b[0m \x1b[37;1m            let exit_code = run_command(run_args)?;\x1b[0m
[INFO] [stdout]        544 │             if let Some(child_code) = exit_code {
[INFO] [stdout]        545 │                 process::exit(child_code.try_into()?);
[INFO] [stdout]   10: \x1b[32mcore::ops::function::FnOnce::call_once\x1b[0m\x1b[90m::ha8f31c3443b29a80\x1b[0m
[INFO] [stdout]       at \x1b[35m/rustc/8489f1adbe1c8f8921cf18b9962cf565237a5489/library/core/src/ops/function.rs\x1b[0m:\x1b[35m250\x1b[0m
[INFO] [stdout]   11: \x1b[32mstd::sys::backtrace::__rust_begin_short_backtrace\x1b[0m\x1b[90m::h8fcf821ab45ee1a9\x1b[0m
[INFO] [stdout]       at \x1b[35m/rustc/8489f1adbe1c8f8921cf18b9962cf565237a5489/library/std/src/sys/backtrace.rs\x1b[0m:\x1b[35m166\x1b[0m
[INFO] [stdout]   \x1b[96m                              ⋮ 14 frames hidden ⋮                              \x1b[0m
[INFO] [stdout] 
[INFO] [stdout] Run with COLORBT_SHOW_HIDDEN=1 environment variable to disable frame filtering.
[INFO] [stdout] ```
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x602f3b48a7ea - std[128c3efe2914e152]::backtrace_rs::backtrace::libunwind::trace
[INFO] [stdout]                                at /rustc/8489f1adbe1c8f8921cf18b9962cf565237a5489/library/std/src/../../backtrace/src/backtrace/libunwind.rs:117:9
[INFO] [stdout]    1:     0x602f3b48a7ea - std[128c3efe2914e152]::backtrace_rs::backtrace::trace_unsynchronized::<std[128c3efe2914e152]::sys::backtrace::_print_fmt::{closure#1}>
[INFO] [stdout]                                at /rustc/8489f1adbe1c8f8921cf18b9962cf565237a5489/library/std/src/../../backtrace/src/backtrace/mod.rs:66:14
[INFO] [stdout]    2:     0x602f3b48a7ea - std[128c3efe2914e152]::sys::backtrace::_print_fmt
[INFO] [stdout]                                at /rustc/8489f1adbe1c8f8921cf18b9962cf565237a5489/library/std/src/sys/backtrace.rs:74:9
[INFO] [stdout]    3:     0x602f3b48a7ea - <<std[128c3efe2914e152]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[6771d259883166e6]::fmt::Display>::fmt
[INFO] [stdout]                                at /rustc/8489f1adbe1c8f8921cf18b9962cf565237a5489/library/std/src/sys/backtrace.rs:44:26
[INFO] [stdout]    4:     0x602f3b4a0dca - <core[6771d259883166e6]::fmt::rt::Argument>::fmt
[INFO] [stdout]                                at /rustc/8489f1adbe1c8f8921cf18b9962cf565237a5489/library/core/src/fmt/rt.rs:152:76
[INFO] [stdout]    5:     0x602f3b4a0dca - core[6771d259883166e6]::fmt::write
[INFO] [stdout]    6:     0x602f3b490da2 - std[128c3efe2914e152]::io::default_write_fmt::<alloc[fef50e8eecedd288]::vec::Vec<u8>>
[INFO] [stdout]                                at /rustc/8489f1adbe1c8f8921cf18b9962cf565237a5489/library/std/src/io/mod.rs:621:11
[INFO] [stdout]    7:     0x602f3b490da2 - <alloc[fef50e8eecedd288]::vec::Vec<u8> as std[128c3efe2914e152]::io::Write>::write_fmt
[INFO] [stdout]                                at /rustc/8489f1adbe1c8f8921cf18b9962cf565237a5489/library/std/src/io/mod.rs:1976:13
[INFO] [stdout]    8:     0x602f3b4692bf - <std[128c3efe2914e152]::sys::backtrace::BacktraceLock>::print
[INFO] [stdout]                                at /rustc/8489f1adbe1c8f8921cf18b9962cf565237a5489/library/std/src/sys/backtrace.rs:47:9
[INFO] [stdout]    9:     0x602f3b4692bf - std[128c3efe2914e152]::panicking::default_hook::{closure#0}
[INFO] [stdout]                                at /rustc/8489f1adbe1c8f8921cf18b9962cf565237a5489/library/std/src/panicking.rs:292:27
[INFO] [stdout]   10:     0x602f3b4829e9 - std[128c3efe2914e152]::panicking::default_hook
[INFO] [stdout]                                at /rustc/8489f1adbe1c8f8921cf18b9962cf565237a5489/library/std/src/panicking.rs:316:9
[INFO] [stdout]   11:     0x602f3b3d4fbc - <alloc[fef50e8eecedd288]::boxed::Box<dyn for<'a, 'b> core[6771d259883166e6]::ops::function::Fn<(&'a std[128c3efe2914e152]::panic::PanicHookInfo<'b>,), Output = ()> + core[6771d259883166e6]::marker::Sync + core[6771d259883166e6]::marker::Send> as core[6771d259883166e6]::ops::function::Fn<(&std[128c3efe2914e152]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/8489f1adbe1c8f8921cf18b9962cf565237a5489/library/alloc/src/boxed.rs:2263:9
[INFO] [stdout]   12:     0x602f3b3d4fbc - test[b137923399915ecf]::test_main_with_exit_callback::<test[b137923399915ecf]::test_main::{closure#0}>::{closure#0}
[INFO] [stdout]                                at /rustc/8489f1adbe1c8f8921cf18b9962cf565237a5489/library/test/src/lib.rs:145:21
[INFO] [stdout]   13:     0x602f3b482ba2 - <alloc[fef50e8eecedd288]::boxed::Box<dyn for<'a, 'b> core[6771d259883166e6]::ops::function::Fn<(&'a std[128c3efe2914e152]::panic::PanicHookInfo<'b>,), Output = ()> + core[6771d259883166e6]::marker::Sync + core[6771d259883166e6]::marker::Send> as core[6771d259883166e6]::ops::function::Fn<(&std[128c3efe2914e152]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/8489f1adbe1c8f8921cf18b9962cf565237a5489/library/alloc/src/boxed.rs:2263:9
[INFO] [stdout]   14:     0x602f3b482ba2 - std[128c3efe2914e152]::panicking::panic_with_hook
[INFO] [stdout]                                at /rustc/8489f1adbe1c8f8921cf18b9962cf565237a5489/library/std/src/panicking.rs:833:13
[INFO] [stdout]   15:     0x602f3b469378 - std[128c3efe2914e152]::panicking::panic_handler::{closure#0}
[INFO] [stdout]                                at /rustc/8489f1adbe1c8f8921cf18b9962cf565237a5489/library/std/src/panicking.rs:698:13
[INFO] [stdout]   16:     0x602f3b45e589 - std[128c3efe2914e152]::sys::backtrace::__rust_end_short_backtrace::<std[128c3efe2914e152]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]                                at /rustc/8489f1adbe1c8f8921cf18b9962cf565237a5489/library/std/src/sys/backtrace.rs:182:18
[INFO] [stdout]   17:     0x602f3b46a08d - __rustc[752cc74e29381ccc]::rust_begin_unwind
[INFO] [stdout]                                at /rustc/8489f1adbe1c8f8921cf18b9962cf565237a5489/library/std/src/panicking.rs:689:5
[INFO] [stdout]   18:     0x602f3b4a15dc - core[6771d259883166e6]::panicking::panic_fmt
[INFO] [stdout]                                at /rustc/8489f1adbe1c8f8921cf18b9962cf565237a5489/library/core/src/panicking.rs:80:14
[INFO] [stdout]   19:     0x602f3b41e938 - core::panicking::panic_display::h30b7e2dd31e53695
[INFO] [stdout]                                at /rustc/8489f1adbe1c8f8921cf18b9962cf565237a5489/library/core/src/panicking.rs:259:5
[INFO] [stdout]   20:     0x602f3b41af40 - assert_cmd::assert::AssertError::panic::he005a72b4e6c48f5
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/assert_cmd-2.2.0/src/assert.rs:1036:9
[INFO] [stdout]   21:     0x602f3b41bc87 - core::ops::function::FnOnce::call_once::h53b6837c9d74cf89
[INFO] [stdout]                                at /rustc/8489f1adbe1c8f8921cf18b9962cf565237a5489/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   22:     0x602f3b40f954 - core::result::Result<T,E>::unwrap_or_else::h4b92189a67947301
[INFO] [stdout]                                at /rustc/8489f1adbe1c8f8921cf18b9962cf565237a5489/library/core/src/result.rs:1622:23
[INFO] [stdout]   23:     0x602f3b41b8c0 - assert_cmd::assert::Assert::success::h8e36f179dc38accb
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/assert_cmd-2.2.0/src/assert.rs:161:28
[INFO] [stdout]   24:     0x602f3b3c6c6d - basic::run_command::h8559152174e27608
[INFO] [stdout]                                at /opt/rustwide/workdir/tests/basic.rs:89:10
[INFO] [stdout]   25:     0x602f3b3c6ef7 - basic::run_command::{{closure}}::ha65dc76c179dc82d
[INFO] [stdout]                                at /opt/rustwide/workdir/tests/basic.rs:78:21
[INFO] [stdout]   26:     0x602f3b3c4576 - core::ops::function::FnOnce::call_once::h71fbbcfea3cde72c
[INFO] [stdout]                                at /rustc/8489f1adbe1c8f8921cf18b9962cf565237a5489/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   27:     0x602f3b3c90bb - <fn() -> core[6771d259883166e6]::result::Result<(), alloc[fef50e8eecedd288]::string::String> as core[6771d259883166e6]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/8489f1adbe1c8f8921cf18b9962cf565237a5489/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   28:     0x602f3b3c90bb - test[b137923399915ecf]::__rust_begin_short_backtrace::<core[6771d259883166e6]::result::Result<(), alloc[fef50e8eecedd288]::string::String>, fn() -> core[6771d259883166e6]::result::Result<(), alloc[fef50e8eecedd288]::string::String>>
[INFO] [stdout]                                at /rustc/8489f1adbe1c8f8921cf18b9962cf565237a5489/library/test/src/lib.rs:663:18
[INFO] [stdout]   29:     0x602f3b3d5a8b - test[b137923399915ecf]::run_test_in_process::{closure#0}
[INFO] [stdout]                                at /rustc/8489f1adbe1c8f8921cf18b9962cf565237a5489/library/test/src/lib.rs:686:74
[INFO] [stdout]   30:     0x602f3b3d5a8b - <core[6771d259883166e6]::panic::unwind_safe::AssertUnwindSafe<test[b137923399915ecf]::run_test_in_process::{closure#0}> as core[6771d259883166e6]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/8489f1adbe1c8f8921cf18b9962cf565237a5489/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   31:     0x602f3b3d5a8b - std[128c3efe2914e152]::panicking::catch_unwind::do_call::<core[6771d259883166e6]::panic::unwind_safe::AssertUnwindSafe<test[b137923399915ecf]::run_test_in_process::{closure#0}>, core[6771d259883166e6]::result::Result<(), alloc[fef50e8eecedd288]::string::String>>
[INFO] [stdout]                                at /rustc/8489f1adbe1c8f8921cf18b9962cf565237a5489/library/std/src/panicking.rs:581:40
[INFO] [stdout]   32:     0x602f3b3d5a8b - std[128c3efe2914e152]::panicking::catch_unwind::<core[6771d259883166e6]::result::Result<(), alloc[fef50e8eecedd288]::string::String>, core[6771d259883166e6]::panic::unwind_safe::AssertUnwindSafe<test[b137923399915ecf]::run_test_in_process::{closure#0}>>
[INFO] [stdout]                                at /rustc/8489f1adbe1c8f8921cf18b9962cf565237a5489/library/std/src/panicking.rs:544:19
[INFO] [stdout]   33:     0x602f3b3d5a8b - std[128c3efe2914e152]::panic::catch_unwind::<core[6771d259883166e6]::panic::unwind_safe::AssertUnwindSafe<test[b137923399915ecf]::run_test_in_process::{closure#0}>, core[6771d259883166e6]::result::Result<(), alloc[fef50e8eecedd288]::string::String>>
[INFO] [stdout]                                at /rustc/8489f1adbe1c8f8921cf18b9962cf565237a5489/library/std/src/panic.rs:359:14
[INFO] [stdout]   34:     0x602f3b3d5a8b - test[b137923399915ecf]::run_test_in_process
[INFO] [stdout]                                at /rustc/8489f1adbe1c8f8921cf18b9962cf565237a5489/library/test/src/lib.rs:686:27
[INFO] [stdout]   35:     0x602f3b3d5a8b - test[b137923399915ecf]::run_test::{closure#0}
[INFO] [stdout]                                at /rustc/8489f1adbe1c8f8921cf18b9962cf565237a5489/library/test/src/lib.rs:607:43
[INFO] [stdout]   36:     0x602f3b3cfee4 - test[b137923399915ecf]::run_test::{closure#1}
[INFO] [stdout]                                at /rustc/8489f1adbe1c8f8921cf18b9962cf565237a5489/library/test/src/lib.rs:637:41
[INFO] [stdout]   37:     0x602f3b3cfee4 - std[128c3efe2914e152]::sys::backtrace::__rust_begin_short_backtrace::<test[b137923399915ecf]::run_test::{closure#1}, ()>
[INFO] [stdout]                                at /rustc/8489f1adbe1c8f8921cf18b9962cf565237a5489/library/std/src/sys/backtrace.rs:166:18
[INFO] [stdout]   38:     0x602f3b3d8692 - std[128c3efe2914e152]::thread::lifecycle::spawn_unchecked::<test[b137923399915ecf]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}
[INFO] [stdout]                                at /rustc/8489f1adbe1c8f8921cf18b9962cf565237a5489/library/std/src/thread/lifecycle.rs:91:13
[INFO] [stdout]   39:     0x602f3b3d8692 - <core[6771d259883166e6]::panic::unwind_safe::AssertUnwindSafe<std[128c3efe2914e152]::thread::lifecycle::spawn_unchecked<test[b137923399915ecf]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}> as core[6771d259883166e6]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/8489f1adbe1c8f8921cf18b9962cf565237a5489/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   40:     0x602f3b3d8692 - std[128c3efe2914e152]::panicking::catch_unwind::do_call::<core[6771d259883166e6]::panic::unwind_safe::AssertUnwindSafe<std[128c3efe2914e152]::thread::lifecycle::spawn_unchecked<test[b137923399915ecf]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/8489f1adbe1c8f8921cf18b9962cf565237a5489/library/std/src/panicking.rs:581:40
[INFO] [stdout]   41:     0x602f3b3d8692 - std[128c3efe2914e152]::panicking::catch_unwind::<(), core[6771d259883166e6]::panic::unwind_safe::AssertUnwindSafe<std[128c3efe2914e152]::thread::lifecycle::spawn_unchecked<test[b137923399915ecf]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>>
[INFO] [stdout]                                at /rustc/8489f1adbe1c8f8921cf18b9962cf565237a5489/library/std/src/panicking.rs:544:19
[INFO] [stdout]   42:     0x602f3b3d8692 - std[128c3efe2914e152]::panic::catch_unwind::<core[6771d259883166e6]::panic::unwind_safe::AssertUnwindSafe<std[128c3efe2914e152]::thread::lifecycle::spawn_unchecked<test[b137923399915ecf]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/8489f1adbe1c8f8921cf18b9962cf565237a5489/library/std/src/panic.rs:359:14
[INFO] [stdout]   43:     0x602f3b3d8692 - std[128c3efe2914e152]::thread::lifecycle::spawn_unchecked::<test[b137923399915ecf]::run_test::{closure#1}, ()>::{closure#1}
[INFO] [stdout]                                at /rustc/8489f1adbe1c8f8921cf18b9962cf565237a5489/library/std/src/thread/lifecycle.rs:89:26
[INFO] [stdout]   44:     0x602f3b3d8692 - <std[128c3efe2914e152]::thread::lifecycle::spawn_unchecked<test[b137923399915ecf]::run_test::{closure#1}, ()>::{closure#1} as core[6771d259883166e6]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]                                at /rustc/8489f1adbe1c8f8921cf18b9962cf565237a5489/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   45:     0x602f3b48a04f - <alloc[fef50e8eecedd288]::boxed::Box<dyn core[6771d259883166e6]::ops::function::FnOnce<(), Output = ()> + core[6771d259883166e6]::marker::Send> as core[6771d259883166e6]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/8489f1adbe1c8f8921cf18b9962cf565237a5489/library/alloc/src/boxed.rs:2249:9
[INFO] [stdout]   46:     0x602f3b48a04f - <std[128c3efe2914e152]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]                                at /rustc/8489f1adbe1c8f8921cf18b9962cf565237a5489/library/std/src/sys/thread/unix.rs:118:17
[INFO] [stdout]   47:     0x78e844310aa4 - <unknown>
[INFO] [stdout]   48:     0x78e84439da64 - clone
[INFO] [stdout]   49:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout]     exec_command_and_stop
[INFO] [stdout]     run_command
[INFO] [stdout]     run_command_detached
[INFO] [stdout] 
[INFO] [stdout] test result: FAILED. 1 passed; 3 failed; 0 ignored; 0 measured; 0 filtered out; finished in 1.31s
[INFO] [stdout] 
[INFO] running `Command { std: "docker" "inspect" "edb3db068df6c597b6e5df24d751927bd942ade083d3ff0a5b7939eeca9d10ed", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "edb3db068df6c597b6e5df24d751927bd942ade083d3ff0a5b7939eeca9d10ed", kill_on_drop: false }`
[INFO] [stdout] edb3db068df6c597b6e5df24d751927bd942ade083d3ff0a5b7939eeca9d10ed
