[INFO] fetching crate vmexec 0.5.3...
[INFO] testing vmexec-0.5.3 against 1.95.0 for beta-1.96-2
[INFO] extracting crate vmexec 0.5.3 into /workspace/builds/worker-1-tc1/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-tc1/source/Cargo.toml
[INFO] validating manifest of crates.io crate vmexec 0.5.3 on toolchain 1.95.0
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+1.95.0" "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" "+1.95.0" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr]     Updating crates.io index
[INFO] [stderr]  Downloading crates ...
[INFO] [stderr]   Downloaded vmm-sys-util v0.15.0
[INFO] [stderr]   Downloaded pageant v0.2.0
[INFO] [stderr]   Downloaded dir-lock v0.5.0
[INFO] [stderr]   Downloaded blowfish v0.9.1
[INFO] [stderr]   Downloaded roff v1.1.1
[INFO] [stderr]   Downloaded rattler_digest v1.2.3
[INFO] [stderr]   Downloaded ml-kem v0.2.3
[INFO] [stderr]   Downloaded pkcs1 v0.8.0-rc.4
[INFO] [stderr]   Downloaded rsa v0.10.0-rc.12
[INFO] [stderr]   Downloaded internal-russh-forked-ssh-key v0.6.16+upstream-0.6.7
[INFO] [stderr]   Downloaded p521 v0.13.3
[INFO] [stderr]   Downloaded der v0.8.0
[INFO] [stderr]   Downloaded crypto-primes v0.7.0-pre.6
[INFO] [stderr]   Downloaded russh v0.58.1
[INFO] [stderr]   Downloaded vhost v0.16.0
[INFO] [stderr]   Downloaded vm-memory v0.17.1
[INFO] [stderr]   Downloaded pkcs8 v0.11.0-rc.11
[INFO] [stderr]   Downloaded hybrid-array v0.4.8
[INFO] [stderr]   Downloaded generic-array v1.3.5
[INFO] [stderr]   Downloaded cmov v0.5.0-pre.0
[INFO] [stderr]   Downloaded ctutils v0.3.2
[INFO] [stderr]   Downloaded pem-rfc7468 v1.0.0
[INFO] [stderr]   Downloaded russh-cryptovec v0.58.0
[INFO] [stderr]   Downloaded clap_mangen v0.3.0
[INFO] [stderr]   Downloaded crypto-bigint v0.7.0-rc.18
[INFO] [stderr]   Downloaded daemonize-me v2.0.2
[INFO] [stderr]   Downloaded bcrypt-pbkdf v0.10.0
[INFO] [stderr]   Downloaded base16ct v1.0.0
[INFO] [stderr]   Downloaded spki v0.8.0-rc.4
[INFO] [stderr]   Downloaded wasm-bindgen-futures v0.4.65
[INFO] [stderr]   Downloaded tokio-vsock v0.7.2
[INFO] [stderr]   Downloaded ssh-cipher v0.2.0
[INFO] [stderr]   Downloaded serdect v0.4.2
[INFO] [stderr]   Downloaded termion v4.0.6
[INFO] [stderr]   Downloaded kem v0.3.0-pre.0
[INFO] [stderr]   Downloaded web-sys v0.3.92
[INFO] [stderr]   Downloaded delegate v0.13.5
[INFO] [stderr]   Downloaded rand_core v0.10.0-rc-3
[INFO] [stderr]   Downloaded signature v3.0.0-rc.6
[INFO] [stderr]   Downloaded ssh-encoding v0.2.0
[INFO] [stderr]   Downloaded tokio-fd v0.3.0
[INFO] [stderr]   Downloaded russh-util v0.52.0
[INFO] [stderr]   Downloaded numtoa v0.2.4
[INFO] [stderr]   Downloaded vsock v0.5.3
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-tc1/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" "+1.95.0" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] 1a9c6957ac9f236cc40c0f10248dac3639a19d45de58f575e531fdb31ba24f4a
[INFO] running `Command { std: "docker" "start" "-a" "1a9c6957ac9f236cc40c0f10248dac3639a19d45de58f575e531fdb31ba24f4a", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "1a9c6957ac9f236cc40c0f10248dac3639a19d45de58f575e531fdb31ba24f4a", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "1a9c6957ac9f236cc40c0f10248dac3639a19d45de58f575e531fdb31ba24f4a", kill_on_drop: false }`
[INFO] [stdout] 1a9c6957ac9f236cc40c0f10248dac3639a19d45de58f575e531fdb31ba24f4a
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-tc1/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" "+1.95.0" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 956f849f3cbb4f4a6c85d47dff41106eb8f98c67c806d2001baa216bdf924fac
[INFO] running `Command { std: "docker" "start" "-a" "956f849f3cbb4f4a6c85d47dff41106eb8f98c67c806d2001baa216bdf924fac", 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 rustls v0.23.37
[INFO] [stderr]    Compiling der v0.8.0
[INFO] [stderr]    Compiling cmov v0.5.0-pre.0
[INFO] [stderr]    Compiling rand_core v0.10.0-rc-3
[INFO] [stderr]    Compiling indexmap v2.13.0
[INFO] [stderr]    Compiling der v0.7.10
[INFO] [stderr]    Compiling ctutils v0.3.2
[INFO] [stderr]    Compiling darling_core v0.23.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 nix v0.31.2
[INFO] [stderr]    Compiling curve25519-dalek-derive v0.1.1
[INFO] [stderr]    Compiling jobserver v0.1.34
[INFO] [stderr]    Compiling generic-array v0.14.7
[INFO] [stderr]    Compiling hybrid-array v0.4.8
[INFO] [stderr]    Compiling cc v1.2.58
[INFO] [stderr]    Compiling num-bigint-dig v0.8.6
[INFO] [stderr]    Compiling owo-colors v4.3.0
[INFO] [stderr]    Compiling spki v0.8.0-rc.4
[INFO] [stderr]    Compiling serde v1.0.228
[INFO] [stderr]    Compiling block-buffer v0.12.0
[INFO] [stderr]    Compiling crypto-common v0.2.1
[INFO] [stderr]    Compiling spki v0.7.3
[INFO] [stderr]    Compiling terminal_size v0.4.4
[INFO] [stderr]    Compiling zerofrom v0.1.6
[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 yoke v0.8.1
[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 zerovec v0.11.5
[INFO] [stderr]    Compiling zerotrie v0.2.3
[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 digest v0.10.7
[INFO] [stderr]    Compiling rand_core v0.6.4
[INFO] [stderr]    Compiling inout v0.1.4
[INFO] [stderr]    Compiling signal-hook-registry v1.4.8
[INFO] [stderr]    Compiling cipher v0.4.4
[INFO] [stderr]    Compiling universal-hash v0.5.1
[INFO] [stderr]    Compiling digest v0.11.2
[INFO] [stderr]    Compiling sha2 v0.10.9
[INFO] [stderr]    Compiling hmac v0.12.1
[INFO] [stderr]    Compiling cmake v0.1.58
[INFO] [stderr]    Compiling aes v0.8.4
[INFO] [stderr]    Compiling tokio v1.50.0
[INFO] [stderr]    Compiling salsa20 v0.10.2
[INFO] [stderr]    Compiling cbc v0.1.2
[INFO] [stderr]    Compiling ff v0.13.1
[INFO] [stderr]    Compiling pbkdf2 v0.12.2
[INFO] [stderr]    Compiling tinystr v0.8.2
[INFO] [stderr]    Compiling hkdf v0.12.4
[INFO] [stderr]    Compiling group v0.13.0
[INFO] [stderr]    Compiling crypto-bigint v0.5.5
[INFO] [stderr]    Compiling signature v2.2.0
[INFO] [stderr]    Compiling potential_utf v0.1.4
[INFO] [stderr]    Compiling scrypt v0.11.0
[INFO] [stderr]    Compiling rfc6979 v0.4.0
[INFO] [stderr]    Compiling icu_collections v2.1.1
[INFO] [stderr]    Compiling icu_locale_core v2.1.1
[INFO] [stderr]    Compiling rand_chacha v0.3.1
[INFO] [stderr]    Compiling getrandom v0.3.4
[INFO] [stderr]    Compiling polyval v0.6.2
[INFO] [stderr]    Compiling ssh-encoding v0.2.0
[INFO] [stderr]    Compiling ctr v0.9.2
[INFO] [stderr]    Compiling pkcs5 v0.7.1
[INFO] [stderr]    Compiling ghash v0.5.1
[INFO] [stderr]    Compiling blake2 v0.10.6
[INFO] [stderr]    Compiling aead v0.5.2
[INFO] [stderr]    Compiling time v0.3.47
[INFO] [stderr]    Compiling rand v0.8.5
[INFO] [stderr]    Compiling rand_core v0.9.5
[INFO] [stderr]    Compiling aes-gcm v0.10.3
[INFO] [stderr]    Compiling aws-lc-sys v0.39.1
[INFO] [stderr]    Compiling rand_chacha v0.9.0
[INFO] [stderr]    Compiling sha2 v0.11.0
[INFO] [stderr]    Compiling signature v3.0.0-rc.6
[INFO] [stderr]    Compiling password-hash v0.5.0
[INFO] [stderr]    Compiling pkcs8 v0.10.2
[INFO] [stderr]    Compiling blowfish v0.9.1
[INFO] [stderr]    Compiling icu_provider v2.1.1
[INFO] [stderr]    Compiling crypto-primes v0.7.0-pre.6
[INFO] [stderr]    Compiling icu_properties v2.1.2
[INFO] [stderr]    Compiling sec1 v0.7.3
[INFO] [stderr]    Compiling icu_normalizer v2.1.1
[INFO] [stderr]    Compiling ed25519 v2.2.3
[INFO] [stderr]    Compiling rand v0.9.2
[INFO] [stderr]    Compiling chacha20 v0.9.1
[INFO] [stderr]    Compiling elliptic-curve v0.13.8
[INFO] [stderr]    Compiling darling_macro v0.23.0
[INFO] [stderr]    Compiling tracing-subscriber v0.3.23
[INFO] [stderr]    Compiling poly1305 v0.8.0
[INFO] [stderr]    Compiling futures-executor v0.3.32
[INFO] [stderr]    Compiling darling v0.23.0
[INFO] [stderr]    Compiling parking_lot_core v0.9.12
[INFO] [stderr]    Compiling ecdsa v0.16.9
[INFO] [stderr]    Compiling primeorder v0.13.6
[INFO] [stderr]    Compiling num-iter v0.1.45
[INFO] [stderr]    Compiling clap_derive v4.6.0
[INFO] [stderr]    Compiling color-spantrace v0.3.0
[INFO] [stderr]    Compiling portable-atomic v1.13.1
[INFO] [stderr]    Compiling eyre v0.6.12
[INFO] [stderr]    Compiling idna_adapter v1.2.1
[INFO] [stderr]    Compiling keccak v0.1.6
[INFO] [stderr]    Compiling rsa v0.10.0-rc.12
[INFO] [stderr]    Compiling p521 v0.13.3
[INFO] [stderr]    Compiling idna v1.1.0
[INFO] [stderr]    Compiling p256 v0.13.2
[INFO] [stderr]    Compiling p384 v0.13.1
[INFO] [stderr]    Compiling sha3 v0.10.8
[INFO] [stderr]    Compiling ed25519-dalek v2.2.0
[INFO] [stderr]    Compiling tracing-error v0.2.1
[INFO] [stderr]    Compiling url v2.5.8
[INFO] [stderr]    Compiling parking_lot v0.12.5
[INFO] [stderr]    Compiling futures v0.3.32
[INFO] [stderr]    Compiling ssh-cipher v0.2.0
[INFO] [stderr]    Compiling serde_with_macros v3.18.0
[INFO] [stderr]    Compiling argon2 v0.5.3
[INFO] [stderr]    Compiling bcrypt-pbkdf v0.10.0
[INFO] [stderr]    Compiling sha1 v0.11.0
[INFO] [stderr]    Compiling kem v0.3.0-pre.0
[INFO] [stderr]    Compiling sha1 v0.10.6
[INFO] [stderr]    Compiling getrandom v0.4.2
[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 dirs-sys v0.5.0
[INFO] [stderr]    Compiling vmm-sys-util v0.15.0
[INFO] [stderr]    Compiling clap v4.6.0
[INFO] [stderr]    Compiling backtrace v0.3.76
[INFO] [stderr]    Compiling generic-array v1.3.5
[INFO] [stderr]    Compiling serde_with v3.18.0
[INFO] [stderr]    Compiling internal-russh-forked-ssh-key v0.6.16+upstream-0.6.7
[INFO] [stderr]    Compiling uuid v1.21.0
[INFO] [stderr]    Compiling ml-kem v0.2.3
[INFO] [stderr]    Compiling vsock v0.5.3
[INFO] [stderr]    Compiling crossterm v0.29.0
[INFO] [stderr]    Compiling russh-cryptovec v0.58.0
[INFO] [stderr]    Compiling console v0.16.3
[INFO] [stderr]    Compiling num-bigint v0.4.6
[INFO] [stderr]    Compiling md-5 v0.10.6
[INFO] [stderr]    Compiling vm-memory v0.17.1
[INFO] [stderr]    Compiling sysinfo v0.35.2
[INFO] [stderr]    Compiling strum_macros v0.28.0
[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 serde_bytes v0.11.19
[INFO] [stderr]    Compiling delegate v0.13.5
[INFO] [stderr]    Compiling enum_dispatch v0.3.13
[INFO] [stderr]    Compiling tower-http v0.6.8
[INFO] [stderr]    Compiling h2 v0.4.13
[INFO] [stderr]    Compiling numtoa v0.2.4
[INFO] [stderr]    Compiling hex-literal v0.4.1
[INFO] [stderr]    Compiling unicode-segmentation v1.13.2
[INFO] [stderr]    Compiling md5 v0.7.0
[INFO] [stderr]    Compiling unit-prefix v0.5.2
[INFO] [stderr]    Compiling roff v1.1.1
[INFO] [stderr]    Compiling termion v4.0.6
[INFO] [stderr]    Compiling indicatif v0.18.4
[INFO] [stderr]    Compiling dir-lock v0.5.0
[INFO] [stderr]    Compiling vhost v0.16.0
[INFO] [stderr]    Compiling clap_mangen v0.3.0
[INFO] [stderr]    Compiling tokio-fd v0.3.0
[INFO] [stderr]    Compiling comfy-table v7.2.2
[INFO] [stderr]    Compiling tokio-vsock v0.7.2
[INFO] [stderr]    Compiling color-eyre v0.6.5
[INFO] [stderr]    Compiling rattler_digest v1.2.3
[INFO] [stderr]    Compiling directories v6.0.0
[INFO] [stderr]    Compiling clap_complete v4.6.0
[INFO] [stderr]    Compiling daemonize-me v2.0.2
[INFO] [stderr]    Compiling nanoid v0.4.0
[INFO] [stderr]    Compiling which v8.0.2
[INFO] [stderr]    Compiling sysinfo v0.38.4
[INFO] [stderr]    Compiling humansize v2.1.3
[INFO] [stderr]    Compiling strum v0.28.0
[INFO] [stderr]    Compiling shell-escape v0.1.5
[INFO] [stderr]    Compiling humantime v2.3.0
[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 49s
[INFO] running `Command { std: "docker" "inspect" "956f849f3cbb4f4a6c85d47dff41106eb8f98c67c806d2001baa216bdf924fac", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "956f849f3cbb4f4a6c85d47dff41106eb8f98c67c806d2001baa216bdf924fac", kill_on_drop: false }`
[INFO] [stdout] 956f849f3cbb4f4a6c85d47dff41106eb8f98c67c806d2001baa216bdf924fac
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-tc1/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" "+1.95.0" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] b097bc40256cfa190c6ffe90788e4bdad814b9a0660808ef6802f542166d7cad
[INFO] running `Command { std: "docker" "start" "-a" "b097bc40256cfa190c6ffe90788e4bdad814b9a0660808ef6802f542166d7cad", kill_on_drop: false }`
[INFO] [stderr]    Compiling futures-util v0.3.32
[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 rstest_macros v0.26.1
[INFO] [stderr]    Compiling predicates-core v1.0.10
[INFO] [stderr]    Compiling float-cmp v0.10.0
[INFO] [stderr]    Compiling termtree v0.5.1
[INFO] [stderr]    Compiling relative-path v1.9.3
[INFO] [stderr]    Compiling normalize-line-endings v0.3.0
[INFO] [stderr]    Compiling assert_cmd v2.2.0
[INFO] [stderr]    Compiling difflib v0.4.0
[INFO] [stderr]    Compiling anstream v0.6.21
[INFO] [stderr]    Compiling wait-timeout v0.2.1
[INFO] [stderr]    Compiling futures-timer v3.0.3
[INFO] [stderr]    Compiling diff v0.1.13
[INFO] [stderr]    Compiling yansi v1.0.1
[INFO] [stderr]    Compiling predicates-tree v1.0.13
[INFO] [stderr]    Compiling pretty_assertions v1.4.1
[INFO] [stderr]    Compiling toml_parser v1.1.0+spec-1.1.0
[INFO] [stderr]    Compiling toml_edit v0.25.8+spec-1.1.0
[INFO] [stderr]    Compiling proc-macro-crate v3.5.0
[INFO] [stderr]    Compiling regex v1.12.3
[INFO] [stderr]    Compiling futures-executor v0.3.32
[INFO] [stderr]    Compiling tower v0.5.3
[INFO] [stderr]    Compiling hyper-util v0.1.20
[INFO] [stderr]    Compiling matchers v0.2.0
[INFO] [stderr]    Compiling bstr v1.12.1
[INFO] [stderr]    Compiling futures v0.3.32
[INFO] [stderr]    Compiling tracing-subscriber v0.3.23
[INFO] [stderr]    Compiling tokio-vsock v0.7.2
[INFO] [stderr]    Compiling russh v0.58.1
[INFO] [stderr]    Compiling tower-http v0.6.8
[INFO] [stderr]    Compiling predicates v3.1.4
[INFO] [stderr]    Compiling hyper-rustls v0.27.7
[INFO] [stderr]    Compiling tracing-error v0.2.1
[INFO] [stderr]    Compiling color-spantrace v0.3.0
[INFO] [stderr]    Compiling reqwest v0.13.2
[INFO] [stderr]    Compiling color-eyre v0.6.5
[INFO] [stderr]    Compiling rstest v0.26.1
[INFO] [stderr]    Compiling vmexec v0.5.3 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 42.71s
[INFO] running `Command { std: "docker" "inspect" "b097bc40256cfa190c6ffe90788e4bdad814b9a0660808ef6802f542166d7cad", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "b097bc40256cfa190c6ffe90788e4bdad814b9a0660808ef6802f542166d7cad", kill_on_drop: false }`
[INFO] [stdout] b097bc40256cfa190c6ffe90788e4bdad814b9a0660808ef6802f542166d7cad
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-tc1/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" "+1.95.0" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] 4cfb84b147b3e7ccf537631cf36bf20a4a30445ad67191206ef80a2b1332c6fd
[INFO] running `Command { std: "docker" "start" "-a" "4cfb84b147b3e7ccf537631cf36bf20a4a30445ad67191206ef80a2b1332c6fd", kill_on_drop: false }`
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.55s
[INFO] [stdout] 
[INFO] [stdout] running 46 tests
[INFO] [stdout] test cli::tests::test_parse_bind_volume_invalid::case_4 ... ok
[INFO] [stderr]      Running unittests src/main.rs (/opt/rustwide/target/debug/deps/vmexec-07d84d156bb529d5)
[INFO] [stdout] test cli::tests::test_parse_memory_invalid::case_3 ... ok
[INFO] [stdout] test cli::tests::test_parse_bind_volume_invalid::case_1 ... 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_3 ... ok
[INFO] [stdout] test cli::tests::test_parse_memory_valid::case_1 ... ok
[INFO] [stdout] test cli::tests::test_parse_memory_invalid::case_5 ... ok
[INFO] [stdout] test cli::tests::test_parse_bind_volume_valid::case_2 ... ok
[INFO] [stdout] test cli::tests::test_parse_memory_valid::case_2 ... ok
[INFO] [stdout] test cli::tests::test_parse_memory_invalid::case_4 ... ok
[INFO] [stdout] test cli::tests::test_parse_env_var_invalid::case_1 ... ok
[INFO] [stdout] test cli::tests::test_parse_os_type_or_image_path::case_1 ... ok
[INFO] [stdout] test cli::tests::test_parse_memory_valid::case_3 ... ok
[INFO] [stdout] test cli::tests::test_parse_bind_volume_invalid::case_2 ... ok
[INFO] [stdout] test cli::tests::test_parse_os_type_or_image_path_invalid::case_1 ... 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_2 ... ok
[INFO] [stdout] test cli::tests::test_parse_pmem_valid::case_1 ... ok
[INFO] [stdout] test cli::tests::test_parse_bind_volume_valid::case_3 ... 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_publish_port_valid::case_1 ... 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_2 ... 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 cli::tests::test_parse_env_var_invalid::case_2 ... ok
[INFO] [stdout] test cli::tests::test_parse_pmem_valid::case_2 ... ok
[INFO] [stdout] test cli::tests::test_parse_bind_volume_valid::case_4 ... ok
[INFO] [stdout] test cli::tests::test_parse_publish_port_invalid::case_2 ... 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 cli::tests::test_parse_pmem_invalid::case_1 ... ok
[INFO] [stdout] test cli::tests::test_parse_publish_port_invalid::case_1 ... 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] 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_memory_invalid::case_1 ... ok
[INFO] [stdout] test cli::tests::test_parse_env_var_invalid::case_3 ... ok
[INFO] [stdout] test cli::tests::test_parse_memory_invalid::case_2 ... ok
[INFO] [stdout] test cli::tests::test_parse_bind_volume_valid::case_1 ... ok
[INFO] [stderr]      Running tests/basic.rs (/opt/rustwide/target/debug/deps/basic-e381ea938bb5ab37)
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 46 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.03s
[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 run_command ... FAILED
[INFO] [stdout] test exec_command_and_stop ... FAILED
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout] 
[INFO] [stdout] ---- run_command_detached stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'run_command_detached' (67) panicked at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/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::hb44dbd74b69e551a\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::h91c70f8fbfe5193a\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::h16dea00151d3ea21\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::he455d82973c82e04\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::h03b5743dbd59d44c\x1b[0m
[INFO] [stdout]       at \x1b[35m/rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/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::hcb97a5a7f46df1c5\x1b[0m
[INFO] [stdout]       at \x1b[35m/rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/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::hb44dbd74b69e551a\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::h91c70f8fbfe5193a\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::h16dea00151d3ea21\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::he455d82973c82e04\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::h03b5743dbd59d44c\x1b[0m
[INFO] [stdout]       at \x1b[35m/rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/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::hcb97a5a7f46df1c5\x1b[0m
[INFO] [stdout]       at \x1b[35m/rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/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:     0x5bab0948bfba - std[e28293b1aa0f68bd]::backtrace_rs::backtrace::libunwind::trace
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/std/src/../../backtrace/src/backtrace/libunwind.rs:117:9
[INFO] [stdout]    1:     0x5bab0948bfba - std[e28293b1aa0f68bd]::backtrace_rs::backtrace::trace_unsynchronized::<std[e28293b1aa0f68bd]::sys::backtrace::_print_fmt::{closure#1}>
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/std/src/../../backtrace/src/backtrace/mod.rs:66:14
[INFO] [stdout]    2:     0x5bab0948bfba - std[e28293b1aa0f68bd]::sys::backtrace::_print_fmt
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/std/src/sys/backtrace.rs:74:9
[INFO] [stdout]    3:     0x5bab0948bfba - <<std[e28293b1aa0f68bd]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[c1f1a4ba060b9bfa]::fmt::Display>::fmt
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/std/src/sys/backtrace.rs:44:26
[INFO] [stdout]    4:     0x5bab094a25ca - <core[c1f1a4ba060b9bfa]::fmt::rt::Argument>::fmt
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/core/src/fmt/rt.rs:152:76
[INFO] [stdout]    5:     0x5bab094a25ca - core[c1f1a4ba060b9bfa]::fmt::write
[INFO] [stdout]    6:     0x5bab09492582 - std[e28293b1aa0f68bd]::io::default_write_fmt::<alloc[fdfd2bd8633a6659]::vec::Vec<u8>>
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/std/src/io/mod.rs:639:11
[INFO] [stdout]    7:     0x5bab09492582 - <alloc[fdfd2bd8633a6659]::vec::Vec<u8> as std[e28293b1aa0f68bd]::io::Write>::write_fmt
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/std/src/io/mod.rs:1994:13
[INFO] [stdout]    8:     0x5bab0946ab0f - <std[e28293b1aa0f68bd]::sys::backtrace::BacktraceLock>::print
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/std/src/sys/backtrace.rs:47:9
[INFO] [stdout]    9:     0x5bab0946ab0f - std[e28293b1aa0f68bd]::panicking::default_hook::{closure#0}
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/std/src/panicking.rs:292:27
[INFO] [stdout]   10:     0x5bab094842c9 - std[e28293b1aa0f68bd]::panicking::default_hook
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/std/src/panicking.rs:316:9
[INFO] [stdout]   11:     0x5bab093d750c - <alloc[fdfd2bd8633a6659]::boxed::Box<dyn for<'a, 'b> core[c1f1a4ba060b9bfa]::ops::function::Fn<(&'a std[e28293b1aa0f68bd]::panic::PanicHookInfo<'b>,), Output = ()> + core[c1f1a4ba060b9bfa]::marker::Sync + core[c1f1a4ba060b9bfa]::marker::Send> as core[c1f1a4ba060b9bfa]::ops::function::Fn<(&std[e28293b1aa0f68bd]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/alloc/src/boxed.rs:2254:9
[INFO] [stdout]   12:     0x5bab093d750c - test[273d7611820c9051]::test_main_with_exit_callback::<test[273d7611820c9051]::test_main::{closure#0}>::{closure#0}
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/test/src/lib.rs:145:21
[INFO] [stdout]   13:     0x5bab09484482 - <alloc[fdfd2bd8633a6659]::boxed::Box<dyn for<'a, 'b> core[c1f1a4ba060b9bfa]::ops::function::Fn<(&'a std[e28293b1aa0f68bd]::panic::PanicHookInfo<'b>,), Output = ()> + core[c1f1a4ba060b9bfa]::marker::Sync + core[c1f1a4ba060b9bfa]::marker::Send> as core[c1f1a4ba060b9bfa]::ops::function::Fn<(&std[e28293b1aa0f68bd]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/alloc/src/boxed.rs:2254:9
[INFO] [stdout]   14:     0x5bab09484482 - std[e28293b1aa0f68bd]::panicking::panic_with_hook
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/std/src/panicking.rs:833:13
[INFO] [stdout]   15:     0x5bab0946abc8 - std[e28293b1aa0f68bd]::panicking::panic_handler::{closure#0}
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/std/src/panicking.rs:698:13
[INFO] [stdout]   16:     0x5bab09462569 - std[e28293b1aa0f68bd]::sys::backtrace::__rust_end_short_backtrace::<std[e28293b1aa0f68bd]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/std/src/sys/backtrace.rs:182:18
[INFO] [stdout]   17:     0x5bab0946b8dd - __rustc[b7974e8690430dd9]::rust_begin_unwind
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/std/src/panicking.rs:689:5
[INFO] [stdout]   18:     0x5bab094a2efc - core[c1f1a4ba060b9bfa]::panicking::panic_fmt
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/core/src/panicking.rs:80:14
[INFO] [stdout]   19:     0x5bab094216c8 - core::panicking::panic_display::h5ef86658f21bd4dc
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/core/src/panicking.rs:259:5
[INFO] [stdout]   20:     0x5bab0941d180 - assert_cmd::assert::AssertError::panic::h80c2cf7dc4a95bdf
[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:     0x5bab0941de17 - core::ops::function::FnOnce::call_once::hb72915421b5e3dee
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   22:     0x5bab094111d4 - core::result::Result<T,E>::unwrap_or_else::h8e3c23c74b1b8367
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/core/src/result.rs:1622:23
[INFO] [stdout]   23:     0x5bab0941db00 - assert_cmd::assert::Assert::success::h739e33cf2bd992c6
[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:     0x5bab093c9618 - basic::run_command_detached::h0a396d5918e0d9b7
[INFO] [stdout]                                at /opt/rustwide/workdir/tests/basic.rs:115:10
[INFO] [stdout]   25:     0x5bab093c9ab7 - basic::run_command_detached::{{closure}}::h2271262804197745
[INFO] [stdout]                                at /opt/rustwide/workdir/tests/basic.rs:105:30
[INFO] [stdout]   26:     0x5bab093c69b6 - core::ops::function::FnOnce::call_once::h10a1285c46d63ec0
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   27:     0x5bab093cb5eb - <fn() -> core[c1f1a4ba060b9bfa]::result::Result<(), alloc[fdfd2bd8633a6659]::string::String> as core[c1f1a4ba060b9bfa]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   28:     0x5bab093cb5eb - test[273d7611820c9051]::__rust_begin_short_backtrace::<core[c1f1a4ba060b9bfa]::result::Result<(), alloc[fdfd2bd8633a6659]::string::String>, fn() -> core[c1f1a4ba060b9bfa]::result::Result<(), alloc[fdfd2bd8633a6659]::string::String>>
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/test/src/lib.rs:663:18
[INFO] [stdout]   29:     0x5bab093d7fdb - test[273d7611820c9051]::run_test_in_process::{closure#0}
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/test/src/lib.rs:686:74
[INFO] [stdout]   30:     0x5bab093d7fdb - <core[c1f1a4ba060b9bfa]::panic::unwind_safe::AssertUnwindSafe<test[273d7611820c9051]::run_test_in_process::{closure#0}> as core[c1f1a4ba060b9bfa]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/core/src/panic/unwind_safe.rs:274:9
[INFO] [stdout]   31:     0x5bab093d7fdb - std[e28293b1aa0f68bd]::panicking::catch_unwind::do_call::<core[c1f1a4ba060b9bfa]::panic::unwind_safe::AssertUnwindSafe<test[273d7611820c9051]::run_test_in_process::{closure#0}>, core[c1f1a4ba060b9bfa]::result::Result<(), alloc[fdfd2bd8633a6659]::string::String>>
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/std/src/panicking.rs:581:40
[INFO] [stdout]   32:     0x5bab093d7fdb - std[e28293b1aa0f68bd]::panicking::catch_unwind::<core[c1f1a4ba060b9bfa]::result::Result<(), alloc[fdfd2bd8633a6659]::string::String>, core[c1f1a4ba060b9bfa]::panic::unwind_safe::AssertUnwindSafe<test[273d7611820c9051]::run_test_in_process::{closure#0}>>
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/std/src/panicking.rs:544:19
[INFO] [stdout]   33:     0x5bab093d7fdb - std[e28293b1aa0f68bd]::panic::catch_unwind::<core[c1f1a4ba060b9bfa]::panic::unwind_safe::AssertUnwindSafe<test[273d7611820c9051]::run_test_in_process::{closure#0}>, core[c1f1a4ba060b9bfa]::result::Result<(), alloc[fdfd2bd8633a6659]::string::String>>
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/std/src/panic.rs:359:14
[INFO] [stdout]   34:     0x5bab093d7fdb - test[273d7611820c9051]::run_test_in_process
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/test/src/lib.rs:686:27
[INFO] [stdout]   35:     0x5bab093d7fdb - test[273d7611820c9051]::run_test::{closure#0}
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/test/src/lib.rs:607:43
[INFO] [stdout]   36:     0x5bab093d36f4 - test[273d7611820c9051]::run_test::{closure#1}
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/test/src/lib.rs:637:41
[INFO] [stdout]   37:     0x5bab093d36f4 - std[e28293b1aa0f68bd]::sys::backtrace::__rust_begin_short_backtrace::<test[273d7611820c9051]::run_test::{closure#1}, ()>
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/std/src/sys/backtrace.rs:166:18
[INFO] [stdout]   38:     0x5bab093dabe2 - std[e28293b1aa0f68bd]::thread::lifecycle::spawn_unchecked::<test[273d7611820c9051]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/std/src/thread/lifecycle.rs:91:13
[INFO] [stdout]   39:     0x5bab093dabe2 - <core[c1f1a4ba060b9bfa]::panic::unwind_safe::AssertUnwindSafe<std[e28293b1aa0f68bd]::thread::lifecycle::spawn_unchecked<test[273d7611820c9051]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}> as core[c1f1a4ba060b9bfa]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/core/src/panic/unwind_safe.rs:274:9
[INFO] [stdout]   40:     0x5bab093dabe2 - std[e28293b1aa0f68bd]::panicking::catch_unwind::do_call::<core[c1f1a4ba060b9bfa]::panic::unwind_safe::AssertUnwindSafe<std[e28293b1aa0f68bd]::thread::lifecycle::spawn_unchecked<test[273d7611820c9051]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/std/src/panicking.rs:581:40
[INFO] [stdout]   41:     0x5bab093dabe2 - std[e28293b1aa0f68bd]::panicking::catch_unwind::<(), core[c1f1a4ba060b9bfa]::panic::unwind_safe::AssertUnwindSafe<std[e28293b1aa0f68bd]::thread::lifecycle::spawn_unchecked<test[273d7611820c9051]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>>
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/std/src/panicking.rs:544:19
[INFO] [stdout]   42:     0x5bab093dabe2 - std[e28293b1aa0f68bd]::panic::catch_unwind::<core[c1f1a4ba060b9bfa]::panic::unwind_safe::AssertUnwindSafe<std[e28293b1aa0f68bd]::thread::lifecycle::spawn_unchecked<test[273d7611820c9051]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/std/src/panic.rs:359:14
[INFO] [stdout]   43:     0x5bab093dabe2 - std[e28293b1aa0f68bd]::thread::lifecycle::spawn_unchecked::<test[273d7611820c9051]::run_test::{closure#1}, ()>::{closure#1}
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/std/src/thread/lifecycle.rs:89:26
[INFO] [stdout]   44:     0x5bab093dabe2 - <std[e28293b1aa0f68bd]::thread::lifecycle::spawn_unchecked<test[273d7611820c9051]::run_test::{closure#1}, ()>::{closure#1} as core[c1f1a4ba060b9bfa]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   45:     0x5bab0948b81f - <alloc[fdfd2bd8633a6659]::boxed::Box<dyn core[c1f1a4ba060b9bfa]::ops::function::FnOnce<(), Output = ()> + core[c1f1a4ba060b9bfa]::marker::Send> as core[c1f1a4ba060b9bfa]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/alloc/src/boxed.rs:2240:9
[INFO] [stdout]   46:     0x5bab0948b81f - <std[e28293b1aa0f68bd]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/std/src/sys/thread/unix.rs:118:17
[INFO] [stdout]   47:     0x70541f7d5aa4 - <unknown>
[INFO] [stdout]   48:     0x70541f862a64 - clone
[INFO] [stdout]   49:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- run_command stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'run_command' (65) panicked at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/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::hb44dbd74b69e551a\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::h91c70f8fbfe5193a\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::h16dea00151d3ea21\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::he455d82973c82e04\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::h03b5743dbd59d44c\x1b[0m
[INFO] [stdout]       at \x1b[35m/rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/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::hcb97a5a7f46df1c5\x1b[0m
[INFO] [stdout]       at \x1b[35m/rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/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::hb44dbd74b69e551a\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::h91c70f8fbfe5193a\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::h16dea00151d3ea21\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::he455d82973c82e04\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::h03b5743dbd59d44c\x1b[0m
[INFO] [stdout]       at \x1b[35m/rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/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::hcb97a5a7f46df1c5\x1b[0m
[INFO] [stdout]       at \x1b[35m/rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/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:     0x5bab0948bfba - std[e28293b1aa0f68bd]::backtrace_rs::backtrace::libunwind::trace
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/std/src/../../backtrace/src/backtrace/libunwind.rs:117:9
[INFO] [stdout]    1:     0x5bab0948bfba - std[e28293b1aa0f68bd]::backtrace_rs::backtrace::trace_unsynchronized::<std[e28293b1aa0f68bd]::sys::backtrace::_print_fmt::{closure#1}>
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/std/src/../../backtrace/src/backtrace/mod.rs:66:14
[INFO] [stdout]    2:     0x5bab0948bfba - std[e28293b1aa0f68bd]::sys::backtrace::_print_fmt
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/std/src/sys/backtrace.rs:74:9
[INFO] [stdout]    3:     0x5bab0948bfba - <<std[e28293b1aa0f68bd]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[c1f1a4ba060b9bfa]::fmt::Display>::fmt
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/std/src/sys/backtrace.rs:44:26
[INFO] [stdout]    4:     0x5bab094a25ca - <core[c1f1a4ba060b9bfa]::fmt::rt::Argument>::fmt
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/core/src/fmt/rt.rs:152:76
[INFO] [stdout]    5:     0x5bab094a25ca - core[c1f1a4ba060b9bfa]::fmt::write
[INFO] [stdout]    6:     0x5bab09492582 - std[e28293b1aa0f68bd]::io::default_write_fmt::<alloc[fdfd2bd8633a6659]::vec::Vec<u8>>
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/std/src/io/mod.rs:639:11
[INFO] [stdout]    7:     0x5bab09492582 - <alloc[fdfd2bd8633a6659]::vec::Vec<u8> as std[e28293b1aa0f68bd]::io::Write>::write_fmt
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/std/src/io/mod.rs:1994:13
[INFO] [stdout]    8:     0x5bab0946ab0f - <std[e28293b1aa0f68bd]::sys::backtrace::BacktraceLock>::print
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/std/src/sys/backtrace.rs:47:9
[INFO] [stdout]    9:     0x5bab0946ab0f - std[e28293b1aa0f68bd]::panicking::default_hook::{closure#0}
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/std/src/panicking.rs:292:27
[INFO] [stdout]   10:     0x5bab094842c9 - std[e28293b1aa0f68bd]::panicking::default_hook
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/std/src/panicking.rs:316:9
[INFO] [stdout]   11:     0x5bab093d750c - <alloc[fdfd2bd8633a6659]::boxed::Box<dyn for<'a, 'b> core[c1f1a4ba060b9bfa]::ops::function::Fn<(&'a std[e28293b1aa0f68bd]::panic::PanicHookInfo<'b>,), Output = ()> + core[c1f1a4ba060b9bfa]::marker::Sync + core[c1f1a4ba060b9bfa]::marker::Send> as core[c1f1a4ba060b9bfa]::ops::function::Fn<(&std[e28293b1aa0f68bd]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/alloc/src/boxed.rs:2254:9
[INFO] [stdout]   12:     0x5bab093d750c - test[273d7611820c9051]::test_main_with_exit_callback::<test[273d7611820c9051]::test_main::{closure#0}>::{closure#0}
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/test/src/lib.rs:145:21
[INFO] [stdout]   13:     0x5bab09484482 - <alloc[fdfd2bd8633a6659]::boxed::Box<dyn for<'a, 'b> core[c1f1a4ba060b9bfa]::ops::function::Fn<(&'a std[e28293b1aa0f68bd]::panic::PanicHookInfo<'b>,), Output = ()> + core[c1f1a4ba060b9bfa]::marker::Sync + core[c1f1a4ba060b9bfa]::marker::Send> as core[c1f1a4ba060b9bfa]::ops::function::Fn<(&std[e28293b1aa0f68bd]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/alloc/src/boxed.rs:2254:9
[INFO] [stdout]   14:     0x5bab09484482 - std[e28293b1aa0f68bd]::panicking::panic_with_hook
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/std/src/panicking.rs:833:13
[INFO] [stdout]   15:     0x5bab0946abc8 - std[e28293b1aa0f68bd]::panicking::panic_handler::{closure#0}
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/std/src/panicking.rs:698:13
[INFO] [stdout]   16:     0x5bab09462569 - std[e28293b1aa0f68bd]::sys::backtrace::__rust_end_short_backtrace::<std[e28293b1aa0f68bd]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/std/src/sys/backtrace.rs:182:18
[INFO] [stdout]   17:     0x5bab0946b8dd - __rustc[b7974e8690430dd9]::rust_begin_unwind
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/std/src/panicking.rs:689:5
[INFO] [stdout]   18:     0x5bab094a2efc - core[c1f1a4ba060b9bfa]::panicking::panic_fmt
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/core/src/panicking.rs:80:14
[INFO] [stdout]   19:     0x5bab094216c8 - core::panicking::panic_display::h5ef86658f21bd4dc
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/core/src/panicking.rs:259:5
[INFO] [stdout]   20:     0x5bab0941d180 - assert_cmd::assert::AssertError::panic::h80c2cf7dc4a95bdf
[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:     0x5bab0941de17 - core::ops::function::FnOnce::call_once::hb72915421b5e3dee
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   22:     0x5bab094111d4 - core::result::Result<T,E>::unwrap_or_else::h8e3c23c74b1b8367
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/core/src/result.rs:1622:23
[INFO] [stdout]   23:     0x5bab0941db00 - assert_cmd::assert::Assert::success::h739e33cf2bd992c6
[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:     0x5bab093c919d - basic::run_command::h60e6faf8ae0fa52e
[INFO] [stdout]                                at /opt/rustwide/workdir/tests/basic.rs:89:10
[INFO] [stdout]   25:     0x5bab093c9427 - basic::run_command::{{closure}}::h3d95c19a7044b445
[INFO] [stdout]                                at /opt/rustwide/workdir/tests/basic.rs:78:21
[INFO] [stdout]   26:     0x5bab093c6ac6 - core::ops::function::FnOnce::call_once::hfb4e8df8971bfe2f
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   27:     0x5bab093cb5eb - <fn() -> core[c1f1a4ba060b9bfa]::result::Result<(), alloc[fdfd2bd8633a6659]::string::String> as core[c1f1a4ba060b9bfa]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   28:     0x5bab093cb5eb - test[273d7611820c9051]::__rust_begin_short_backtrace::<core[c1f1a4ba060b9bfa]::result::Result<(), alloc[fdfd2bd8633a6659]::string::String>, fn() -> core[c1f1a4ba060b9bfa]::result::Result<(), alloc[fdfd2bd8633a6659]::string::String>>
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/test/src/lib.rs:663:18
[INFO] [stdout]   29:     0x5bab093d7fdb - test[273d7611820c9051]::run_test_in_process::{closure#0}
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/test/src/lib.rs:686:74
[INFO] [stdout]   30:     0x5bab093d7fdb - <core[c1f1a4ba060b9bfa]::panic::unwind_safe::AssertUnwindSafe<test[273d7611820c9051]::run_test_in_process::{closure#0}> as core[c1f1a4ba060b9bfa]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/core/src/panic/unwind_safe.rs:274:9
[INFO] [stdout]   31:     0x5bab093d7fdb - std[e28293b1aa0f68bd]::panicking::catch_unwind::do_call::<core[c1f1a4ba060b9bfa]::panic::unwind_safe::AssertUnwindSafe<test[273d7611820c9051]::run_test_in_process::{closure#0}>, core[c1f1a4ba060b9bfa]::result::Result<(), alloc[fdfd2bd8633a6659]::string::String>>
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/std/src/panicking.rs:581:40
[INFO] [stdout]   32:     0x5bab093d7fdb - std[e28293b1aa0f68bd]::panicking::catch_unwind::<core[c1f1a4ba060b9bfa]::result::Result<(), alloc[fdfd2bd8633a6659]::string::String>, core[c1f1a4ba060b9bfa]::panic::unwind_safe::AssertUnwindSafe<test[273d7611820c9051]::run_test_in_process::{closure#0}>>
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/std/src/panicking.rs:544:19
[INFO] [stdout]   33:     0x5bab093d7fdb - std[e28293b1aa0f68bd]::panic::catch_unwind::<core[c1f1a4ba060b9bfa]::panic::unwind_safe::AssertUnwindSafe<test[273d7611820c9051]::run_test_in_process::{closure#0}>, core[c1f1a4ba060b9bfa]::result::Result<(), alloc[fdfd2bd8633a6659]::string::String>>
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/std/src/panic.rs:359:14
[INFO] [stdout]   34:     0x5bab093d7fdb - test[273d7611820c9051]::run_test_in_process
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/test/src/lib.rs:686:27
[INFO] [stdout]   35:     0x5bab093d7fdb - test[273d7611820c9051]::run_test::{closure#0}
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/test/src/lib.rs:607:43
[INFO] [stdout]   36:     0x5bab093d36f4 - test[273d7611820c9051]::run_test::{closure#1}
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/test/src/lib.rs:637:41
[INFO] [stdout]   37:     0x5bab093d36f4 - std[e28293b1aa0f68bd]::sys::backtrace::__rust_begin_short_backtrace::<test[273d7611820c9051]::run_test::{closure#1}, ()>
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/std/src/sys/backtrace.rs:166:18
[INFO] [stdout]   38:     0x5bab093dabe2 - std[e28293b1aa0f68bd]::thread::lifecycle::spawn_unchecked::<test[273d7611820c9051]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/std/src/thread/lifecycle.rs:91:13
[INFO] [stdout]   39:     0x5bab093dabe2 - <core[c1f1a4ba060b9bfa]::panic::unwind_safe::AssertUnwindSafe<std[e28293b1aa0f68bd]::thread::lifecycle::spawn_unchecked<test[273d7611820c9051]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}> as core[c1f1a4ba060b9bfa]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/core/src/panic/unwind_safe.rs:274:9
[INFO] [stdout]   40:     0x5bab093dabe2 - std[e28293b1aa0f68bd]::panicking::catch_unwind::do_call::<core[c1f1a4ba060b9bfa]::panic::unwind_safe::AssertUnwindSafe<std[e28293b1aa0f68bd]::thread::lifecycle::spawn_unchecked<test[273d7611820c9051]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/std/src/panicking.rs:581:40
[INFO] [stdout]   41:     0x5bab093dabe2 - std[e28293b1aa0f68bd]::panicking::catch_unwind::<(), core[c1f1a4ba060b9bfa]::panic::unwind_safe::AssertUnwindSafe<std[e28293b1aa0f68bd]::thread::lifecycle::spawn_unchecked<test[273d7611820c9051]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>>
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/std/src/panicking.rs:544:19
[INFO] [stdout]   42:     0x5bab093dabe2 - std[e28293b1aa0f68bd]::panic::catch_unwind::<core[c1f1a4ba060b9bfa]::panic::unwind_safe::AssertUnwindSafe<std[e28293b1aa0f68bd]::thread::lifecycle::spawn_unchecked<test[273d7611820c9051]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/std/src/panic.rs:359:14
[INFO] [stdout]   43:     0x5bab093dabe2 - std[e28293b1aa0f68bd]::thread::lifecycle::spawn_unchecked::<test[273d7611820c9051]::run_test::{closure#1}, ()>::{closure#1}
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/std/src/thread/lifecycle.rs:89:26
[INFO] [stdout]   44:     0x5bab093dabe2 - <std[e28293b1aa0f68bd]::thread::lifecycle::spawn_unchecked<test[273d7611820c9051]::run_test::{closure#1}, ()>::{closure#1} as core[c1f1a4ba060b9bfa]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   45:     0x5bab0948b81f - <alloc[fdfd2bd8633a6659]::boxed::Box<dyn core[c1f1a4ba060b9bfa]::ops::function::FnOnce<(), Output = ()> + core[c1f1a4ba060b9bfa]::marker::Send> as core[c1f1a4ba060b9bfa]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/alloc/src/boxed.rs:2240:9
[INFO] [stdout]   46:     0x5bab0948b81f - <std[e28293b1aa0f68bd]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/std/src/sys/thread/unix.rs:118:17
[INFO] [stdout]   47:     0x70541f7d5aa4 - <unknown>
[INFO] [stdout]   48:     0x70541f862a64 - clone
[INFO] [stdout]   49:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- exec_command_and_stop stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'exec_command_and_stop' (63) panicked at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/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::hb44dbd74b69e551a\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::h91c70f8fbfe5193a\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::h16dea00151d3ea21\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::he455d82973c82e04\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::h03b5743dbd59d44c\x1b[0m
[INFO] [stdout]       at \x1b[35m/rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/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::hcb97a5a7f46df1c5\x1b[0m
[INFO] [stdout]       at \x1b[35m/rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/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::hb44dbd74b69e551a\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::h91c70f8fbfe5193a\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::h16dea00151d3ea21\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::he455d82973c82e04\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::h03b5743dbd59d44c\x1b[0m
[INFO] [stdout]       at \x1b[35m/rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/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::hcb97a5a7f46df1c5\x1b[0m
[INFO] [stdout]       at \x1b[35m/rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/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:     0x5bab0948bfba - std[e28293b1aa0f68bd]::backtrace_rs::backtrace::libunwind::trace
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/std/src/../../backtrace/src/backtrace/libunwind.rs:117:9
[INFO] [stdout]    1:     0x5bab0948bfba - std[e28293b1aa0f68bd]::backtrace_rs::backtrace::trace_unsynchronized::<std[e28293b1aa0f68bd]::sys::backtrace::_print_fmt::{closure#1}>
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/std/src/../../backtrace/src/backtrace/mod.rs:66:14
[INFO] [stdout]    2:     0x5bab0948bfba - std[e28293b1aa0f68bd]::sys::backtrace::_print_fmt
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/std/src/sys/backtrace.rs:74:9
[INFO] [stdout]    3:     0x5bab0948bfba - <<std[e28293b1aa0f68bd]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[c1f1a4ba060b9bfa]::fmt::Display>::fmt
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/std/src/sys/backtrace.rs:44:26
[INFO] [stdout]    4:     0x5bab094a25ca - <core[c1f1a4ba060b9bfa]::fmt::rt::Argument>::fmt
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/core/src/fmt/rt.rs:152:76
[INFO] [stdout]    5:     0x5bab094a25ca - core[c1f1a4ba060b9bfa]::fmt::write
[INFO] [stdout]    6:     0x5bab09492582 - std[e28293b1aa0f68bd]::io::default_write_fmt::<alloc[fdfd2bd8633a6659]::vec::Vec<u8>>
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/std/src/io/mod.rs:639:11
[INFO] [stdout]    7:     0x5bab09492582 - <alloc[fdfd2bd8633a6659]::vec::Vec<u8> as std[e28293b1aa0f68bd]::io::Write>::write_fmt
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/std/src/io/mod.rs:1994:13
[INFO] [stdout]    8:     0x5bab0946ab0f - <std[e28293b1aa0f68bd]::sys::backtrace::BacktraceLock>::print
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/std/src/sys/backtrace.rs:47:9
[INFO] [stdout]    9:     0x5bab0946ab0f - std[e28293b1aa0f68bd]::panicking::default_hook::{closure#0}
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/std/src/panicking.rs:292:27
[INFO] [stdout]   10:     0x5bab094842c9 - std[e28293b1aa0f68bd]::panicking::default_hook
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/std/src/panicking.rs:316:9
[INFO] [stdout]   11:     0x5bab093d750c - <alloc[fdfd2bd8633a6659]::boxed::Box<dyn for<'a, 'b> core[c1f1a4ba060b9bfa]::ops::function::Fn<(&'a std[e28293b1aa0f68bd]::panic::PanicHookInfo<'b>,), Output = ()> + core[c1f1a4ba060b9bfa]::marker::Sync + core[c1f1a4ba060b9bfa]::marker::Send> as core[c1f1a4ba060b9bfa]::ops::function::Fn<(&std[e28293b1aa0f68bd]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/alloc/src/boxed.rs:2254:9
[INFO] [stdout]   12:     0x5bab093d750c - test[273d7611820c9051]::test_main_with_exit_callback::<test[273d7611820c9051]::test_main::{closure#0}>::{closure#0}
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/test/src/lib.rs:145:21
[INFO] [stdout]   13:     0x5bab09484482 - <alloc[fdfd2bd8633a6659]::boxed::Box<dyn for<'a, 'b> core[c1f1a4ba060b9bfa]::ops::function::Fn<(&'a std[e28293b1aa0f68bd]::panic::PanicHookInfo<'b>,), Output = ()> + core[c1f1a4ba060b9bfa]::marker::Sync + core[c1f1a4ba060b9bfa]::marker::Send> as core[c1f1a4ba060b9bfa]::ops::function::Fn<(&std[e28293b1aa0f68bd]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/alloc/src/boxed.rs:2254:9
[INFO] [stdout]   14:     0x5bab09484482 - std[e28293b1aa0f68bd]::panicking::panic_with_hook
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/std/src/panicking.rs:833:13
[INFO] [stdout]   15:     0x5bab0946abc8 - std[e28293b1aa0f68bd]::panicking::panic_handler::{closure#0}
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/std/src/panicking.rs:698:13
[INFO] [stdout]   16:     0x5bab09462569 - std[e28293b1aa0f68bd]::sys::backtrace::__rust_end_short_backtrace::<std[e28293b1aa0f68bd]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/std/src/sys/backtrace.rs:182:18
[INFO] [stdout]   17:     0x5bab0946b8dd - __rustc[b7974e8690430dd9]::rust_begin_unwind
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/std/src/panicking.rs:689:5
[INFO] [stdout]   18:     0x5bab094a2efc - core[c1f1a4ba060b9bfa]::panicking::panic_fmt
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/core/src/panicking.rs:80:14
[INFO] [stdout]   19:     0x5bab094216c8 - core::panicking::panic_display::h5ef86658f21bd4dc
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/core/src/panicking.rs:259:5
[INFO] [stdout]   20:     0x5bab0941d180 - assert_cmd::assert::AssertError::panic::h80c2cf7dc4a95bdf
[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:     0x5bab0941de17 - core::ops::function::FnOnce::call_once::hb72915421b5e3dee
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   22:     0x5bab094111d4 - core::result::Result<T,E>::unwrap_or_else::h8e3c23c74b1b8367
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/core/src/result.rs:1622:23
[INFO] [stdout]   23:     0x5bab0941db00 - assert_cmd::assert::Assert::success::h739e33cf2bd992c6
[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:     0x5bab093c9ce0 - basic::exec_command_and_stop::h80d38b5f5aba7810
[INFO] [stdout]                                at /opt/rustwide/workdir/tests/basic.rs:29:10
[INFO] [stdout]   25:     0x5bab093ca867 - basic::exec_command_and_stop::{{closure}}::hc63ed185eab628c7
[INFO] [stdout]                                at /opt/rustwide/workdir/tests/basic.rs:19:31
[INFO] [stdout]   26:     0x5bab093c6a86 - core::ops::function::FnOnce::call_once::h840c2ad4e75cce5b
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   27:     0x5bab093cb5eb - <fn() -> core[c1f1a4ba060b9bfa]::result::Result<(), alloc[fdfd2bd8633a6659]::string::String> as core[c1f1a4ba060b9bfa]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   28:     0x5bab093cb5eb - test[273d7611820c9051]::__rust_begin_short_backtrace::<core[c1f1a4ba060b9bfa]::result::Result<(), alloc[fdfd2bd8633a6659]::string::String>, fn() -> core[c1f1a4ba060b9bfa]::result::Result<(), alloc[fdfd2bd8633a6659]::string::String>>
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/test/src/lib.rs:663:18
[INFO] [stdout]   29:     0x5bab093d7fdb - test[273d7611820c9051]::run_test_in_process::{closure#0}
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/test/src/lib.rs:686:74
[INFO] [stdout]   30:     0x5bab093d7fdb - <core[c1f1a4ba060b9bfa]::panic::unwind_safe::AssertUnwindSafe<test[273d7611820c9051]::run_test_in_process::{closure#0}> as core[c1f1a4ba060b9bfa]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/core/src/panic/unwind_safe.rs:274:9
[INFO] [stdout]   31:     0x5bab093d7fdb - std[e28293b1aa0f68bd]::panicking::catch_unwind::do_call::<core[c1f1a4ba060b9bfa]::panic::unwind_safe::AssertUnwindSafe<test[273d7611820c9051]::run_test_in_process::{closure#0}>, core[c1f1a4ba060b9bfa]::result::Result<(), alloc[fdfd2bd8633a6659]::string::String>>
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/std/src/panicking.rs:581:40
[INFO] [stdout]   32:     0x5bab093d7fdb - std[e28293b1aa0f68bd]::panicking::catch_unwind::<core[c1f1a4ba060b9bfa]::result::Result<(), alloc[fdfd2bd8633a6659]::string::String>, core[c1f1a4ba060b9bfa]::panic::unwind_safe::AssertUnwindSafe<test[273d7611820c9051]::run_test_in_process::{closure#0}>>
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/std/src/panicking.rs:544:19
[INFO] [stdout]   33:     0x5bab093d7fdb - std[e28293b1aa0f68bd]::panic::catch_unwind::<core[c1f1a4ba060b9bfa]::panic::unwind_safe::AssertUnwindSafe<test[273d7611820c9051]::run_test_in_process::{closure#0}>, core[c1f1a4ba060b9bfa]::result::Result<(), alloc[fdfd2bd8633a6659]::string::String>>
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/std/src/panic.rs:359:14
[INFO] [stdout]   34:     0x5bab093d7fdb - test[273d7611820c9051]::run_test_in_process
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/test/src/lib.rs:686:27
[INFO] [stdout]   35:     0x5bab093d7fdb - test[273d7611820c9051]::run_test::{closure#0}
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/test/src/lib.rs:607:43
[INFO] [stdout]   36:     0x5bab093d36f4 - test[273d7611820c9051]::run_test::{closure#1}
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/test/src/lib.rs:637:41
[INFO] [stdout]   37:     0x5bab093d36f4 - std[e28293b1aa0f68bd]::sys::backtrace::__rust_begin_short_backtrace::<test[273d7611820c9051]::run_test::{closure#1}, ()>
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/std/src/sys/backtrace.rs:166:18
[INFO] [stdout]   38:     0x5bab093dabe2 - std[e28293b1aa0f68bd]::thread::lifecycle::spawn_unchecked::<test[273d7611820c9051]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/std/src/thread/lifecycle.rs:91:13
[INFO] [stdout]   39:     0x5bab093dabe2 - <core[c1f1a4ba060b9bfa]::panic::unwind_safe::AssertUnwindSafe<std[e28293b1aa0f68bd]::thread::lifecycle::spawn_unchecked<test[273d7611820c9051]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}> as core[c1f1a4ba060b9bfa]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/core/src/panic/unwind_safe.rs:274:9
[INFO] [stdout]   40:     0x5bab093dabe2 - std[e28293b1aa0f68bd]::panicking::catch_unwind::do_call::<core[c1f1a4ba060b9bfa]::panic::unwind_safe::AssertUnwindSafe<std[e28293b1aa0f68bd]::thread::lifecycle::spawn_unchecked<test[273d7611820c9051]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/std/src/panicking.rs:581:40
[INFO] [stdout]   41:     0x5bab093dabe2 - std[e28293b1aa0f68bd]::panicking::catch_unwind::<(), core[c1f1a4ba060b9bfa]::panic::unwind_safe::AssertUnwindSafe<std[e28293b1aa0f68bd]::thread::lifecycle::spawn_unchecked<test[273d7611820c9051]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>>
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/std/src/panicking.rs:544:19
[INFO] [stdout]   42:     0x5bab093dabe2 - std[e28293b1aa0f68bd]::panic::catch_unwind::<core[c1f1a4ba060b9bfa]::panic::unwind_safe::AssertUnwindSafe<std[e28293b1aa0f68bd]::thread::lifecycle::spawn_unchecked<test[273d7611820c9051]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/std/src/panic.rs:359:14
[INFO] [stdout]   43:     0x5bab093dabe2 - std[e28293b1aa0f68bd]::thread::lifecycle::spawn_unchecked::<test[273d7611820c9051]::run_test::{closure#1}, ()>::{closure#1}
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/std/src/thread/lifecycle.rs:89:26
[INFO] [stdout]   44:     0x5bab093dabe2 - <std[e28293b1aa0f68bd]::thread::lifecycle::spawn_unchecked<test[273d7611820c9051]::run_test::{closure#1}, ()>::{closure#1} as core[c1f1a4ba060b9bfa]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   45:     0x5bab0948b81f - <alloc[fdfd2bd8633a6659]::boxed::Box<dyn core[c1f1a4ba060b9bfa]::ops::function::FnOnce<(), Output = ()> + core[c1f1a4ba060b9bfa]::marker::Send> as core[c1f1a4ba060b9bfa]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/alloc/src/boxed.rs:2240:9
[INFO] [stdout]   46:     0x5bab0948b81f - <std[e28293b1aa0f68bd]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/std/src/sys/thread/unix.rs:118:17
[INFO] [stdout]   47:     0x70541f7d5aa4 - <unknown>
[INFO] [stdout]   48:     0x70541f862a64 - 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.56s
[INFO] [stdout] 
[INFO] [stderr] error: test failed, to rerun pass `--test basic`
[INFO] running `Command { std: "docker" "inspect" "4cfb84b147b3e7ccf537631cf36bf20a4a30445ad67191206ef80a2b1332c6fd", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "4cfb84b147b3e7ccf537631cf36bf20a4a30445ad67191206ef80a2b1332c6fd", kill_on_drop: false }`
[INFO] [stdout] 4cfb84b147b3e7ccf537631cf36bf20a4a30445ad67191206ef80a2b1332c6fd
