[INFO] fetching crate my-util 0.1.0... [INFO] testing my-util-0.1.0 against try#dec9417b8611e34e787a3e4c37686b5131f9e5c5 for pr-154210-1 [INFO] extracting crate my-util 0.1.0 into /workspace/builds/worker-5-tc2/source [INFO] started tweaking crates.io crate my-util 0.1.0 [INFO] finished tweaking crates.io crate my-util 0.1.0 [INFO] tweaked toml for crates.io crate my-util 0.1.0 written to /workspace/builds/worker-5-tc2/source/Cargo.toml [INFO] validating manifest of crates.io crate my-util 0.1.0 on toolchain dec9417b8611e34e787a3e4c37686b5131f9e5c5 [INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+dec9417b8611e34e787a3e4c37686b5131f9e5c5" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }` [INFO] crate crates.io crate my-util 0.1.0 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" "+dec9417b8611e34e787a3e4c37686b5131f9e5c5" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }` [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+dec9417b8611e34e787a3e4c37686b5131f9e5c5" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }` [INFO] [stdout] 586b36d83fc4fa509bfa80ba5a733710fb450294c433e535fbf0c89d0d1486c2 [INFO] running `Command { std: "docker" "start" "-a" "586b36d83fc4fa509bfa80ba5a733710fb450294c433e535fbf0c89d0d1486c2", kill_on_drop: false }` [INFO] running `Command { std: "docker" "inspect" "586b36d83fc4fa509bfa80ba5a733710fb450294c433e535fbf0c89d0d1486c2", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "586b36d83fc4fa509bfa80ba5a733710fb450294c433e535fbf0c89d0d1486c2", kill_on_drop: false }` [INFO] [stdout] 586b36d83fc4fa509bfa80ba5a733710fb450294c433e535fbf0c89d0d1486c2 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-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" "+dec9417b8611e34e787a3e4c37686b5131f9e5c5" "build" "--frozen" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] 1452d0622687b75e5445e36eaffff5e1d2a755728e499e32f489c4c882ac2a9c [INFO] running `Command { std: "docker" "start" "-a" "1452d0622687b75e5445e36eaffff5e1d2a755728e499e32f489c4c882ac2a9c", kill_on_drop: false }` [INFO] [stderr] Compiling proc-macro2 v1.0.83 [INFO] [stderr] Compiling unicode-ident v1.0.12 [INFO] [stderr] Compiling autocfg v1.3.0 [INFO] [stderr] Compiling regex-syntax v0.8.3 [INFO] [stderr] Compiling regex-syntax v0.6.29 [INFO] [stderr] Compiling pin-project-lite v0.2.14 [INFO] [stderr] Compiling iana-time-zone v0.1.60 [INFO] [stderr] Compiling overload v0.1.1 [INFO] [stderr] Compiling log v0.4.21 [INFO] [stderr] Compiling smallvec v1.13.2 [INFO] [stderr] Compiling cc v1.0.98 [INFO] [stderr] Compiling libc v0.2.155 [INFO] [stderr] Compiling adler v1.0.2 [INFO] [stderr] Compiling tracing-core v0.1.32 [INFO] [stderr] Compiling sharded-slab v0.1.7 [INFO] [stderr] Compiling thread_local v1.1.8 [INFO] [stderr] Compiling nu-ansi-term v0.46.0 [INFO] [stderr] Compiling gimli v0.28.1 [INFO] [stderr] Compiling eyre v0.6.12 [INFO] [stderr] Compiling memchr v2.7.2 [INFO] [stderr] Compiling miniz_oxide v0.7.3 [INFO] [stderr] Compiling num-traits v0.2.19 [INFO] [stderr] Compiling rustc-demangle v0.1.24 [INFO] [stderr] Compiling indenter v0.3.3 [INFO] [stderr] Compiling owo-colors v3.5.0 [INFO] [stderr] Compiling tracing-log v0.2.0 [INFO] [stderr] Compiling quote v1.0.36 [INFO] [stderr] Compiling object v0.32.2 [INFO] [stderr] Compiling syn v2.0.66 [INFO] [stderr] Compiling chrono v0.4.38 [INFO] [stderr] Compiling backtrace v0.3.71 [INFO] [stderr] Compiling regex-automata v0.4.6 [INFO] [stderr] Compiling addr2line v0.21.0 [INFO] [stderr] Compiling regex-automata v0.1.10 [INFO] [stderr] Compiling regex v1.10.4 [INFO] [stderr] Compiling matchers v0.1.0 [INFO] [stderr] Compiling tracing-attributes v0.1.27 [INFO] [stderr] Compiling tracing v0.1.40 [INFO] [stderr] Compiling tracing-subscriber v0.3.18 [INFO] [stderr] Compiling tracing-error v0.2.0 [INFO] [stderr] Compiling color-spantrace v0.2.1 [INFO] [stderr] Compiling color-eyre v0.6.3 [INFO] [stderr] Compiling my-util v0.1.0 (/opt/rustwide/workdir) [INFO] [stderr] Finished `dev` profile [unoptimized + debuginfo] target(s) in 19.37s [INFO] running `Command { std: "docker" "inspect" "1452d0622687b75e5445e36eaffff5e1d2a755728e499e32f489c4c882ac2a9c", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "1452d0622687b75e5445e36eaffff5e1d2a755728e499e32f489c4c882ac2a9c", kill_on_drop: false }` [INFO] [stdout] 1452d0622687b75e5445e36eaffff5e1d2a755728e499e32f489c4c882ac2a9c [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-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" "+dec9417b8611e34e787a3e4c37686b5131f9e5c5" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] 4e63e5b633052f3890ce3b703a94d75f61db28f3a99c1afbedc4512cadf9d549 [INFO] running `Command { std: "docker" "start" "-a" "4e63e5b633052f3890ce3b703a94d75f61db28f3a99c1afbedc4512cadf9d549", kill_on_drop: false }` [INFO] [stderr] Compiling my-util v0.1.0 (/opt/rustwide/workdir) [INFO] [stderr] Finished `test` profile [unoptimized + debuginfo] target(s) in 1.61s [INFO] running `Command { std: "docker" "inspect" "4e63e5b633052f3890ce3b703a94d75f61db28f3a99c1afbedc4512cadf9d549", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "4e63e5b633052f3890ce3b703a94d75f61db28f3a99c1afbedc4512cadf9d549", kill_on_drop: false }` [INFO] [stdout] 4e63e5b633052f3890ce3b703a94d75f61db28f3a99c1afbedc4512cadf9d549 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-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" "+dec9417b8611e34e787a3e4c37686b5131f9e5c5" "test" "--frozen", kill_on_drop: false }` [INFO] [stdout] 4d296994908c024c89ed7316ad228a50a1e3218e02bc40d244fe4870739df976 [INFO] running `Command { std: "docker" "start" "-a" "4d296994908c024c89ed7316ad228a50a1e3218e02bc40d244fe4870739df976", kill_on_drop: false }` [INFO] [stderr] Finished `test` profile [unoptimized + debuginfo] target(s) in 0.09s [INFO] [stderr] Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/myutil-716347a387dab7ce) [INFO] [stdout] [INFO] [stdout] running 8 tests [INFO] [stdout] 2026-04-10 10:06:53.001 +0000 TRACE myutil::log::tests: [trace]-1 log info [INFO] [stdout] at src/log.rs:205 on log::tests::display_full ThreadId(5) [INFO] [stdout] [INFO] [stdout] 2026-04-10 10:06:53.002 +0000 DEBUG myutil::log::tests: [debug]-2 log info [INFO] [stdout] at src/log.rs:206 on log::tests::display_full ThreadId(5) [INFO] [stdout] [INFO] [stdout] 2026-04-10 10:06:53.002 +0000  INFO myutil::log::tests: [info]-3 log info [INFO] [stdout] at src/log.rs:207 on log::tests::display_full ThreadId(5) [INFO] [stdout] [INFO] [stdout] 2026-04-10 10:06:53.002 +0000  WARN myutil::log::tests: [warn]-4 log info [INFO] [stdout] at src/log.rs:208 on log::tests::display_full ThreadId(5) [INFO] [stdout] [INFO] [stdout] 2026-04-10 10:06:53.002 +0000 ERROR myutil::log::tests: [error]-5 log info [INFO] [stdout] at src/log.rs:209 on log::tests::display_full ThreadId(5) [INFO] [stdout] [INFO] [stdout] test tests::it_works ... ok [INFO] [stdout] test log::tests::display_general ... FAILED [INFO] [stdout] test log::tests::display_custom ... FAILED [INFO] [stdout] test error::tests::error_no_hook_test ... FAILED [INFO] [stdout] test error::tests::error_hook_test ... FAILED [INFO] [stdout] test log::tests::display_original ... FAILED [INFO] [stdout] 2026-04-10 10:06:53.371 +0000 ERROR myutil::log::tests: [error]-5.1 my error 3 [INFO] [stdout] at src/log.rs:210 on log::tests::display_full ThreadId(5) [INFO] [stdout] [INFO] [stdout] 2026-04-10 10:06:53.372 +0000 ERROR myutil::log::tests: [error]-5.2 [INFO] [stdout] 0: my error 3 [INFO] [stdout] 1: my error 2 [INFO] [stdout] 2: error: my error 1 [INFO] [stdout] [INFO] [stdout] ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ BACKTRACE ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ [INFO] [stdout]  ⋮ 6 frames hidden ⋮  [INFO] [stdout] 7: myutil[85211c2db27e5d90]::log::tests::my_err::{closure#0} [INFO] [stdout] at /opt/rustwide/workdir/src/log.rs:198 [INFO] [stdout] 196 │ fn my_err() -> Report { [INFO] [stdout] 197 │ let err = || -> eyre::Result<()> { [INFO] [stdout]  198 >  Err(eyre::eyre!("error: my error 1")) [INFO] [stdout] 199 │ }().context("my error 2").context("my error 3").unwrap_err(); [INFO] [stdout] 200 │ [INFO] [stdout] 8: myutil[85211c2db27e5d90]::log::tests::my_err [INFO] [stdout] at /opt/rustwide/workdir/src/log.rs:197 [INFO] [stdout] 195 │ [INFO] [stdout] 196 │ fn my_err() -> Report { [INFO] [stdout]  197 >  let err = || -> eyre::Result<()> { [INFO] [stdout] 198 │ Err(eyre::eyre!("error: my error 1")) [INFO] [stdout] 199 │ }().context("my error 2").context("my error 3").unwrap_err(); [INFO] [stdout] 9: myutil[85211c2db27e5d90]::log::tests::display [INFO] [stdout] at /opt/rustwide/workdir/src/log.rs:211 [INFO] [stdout] 209 │ tracing::error!("[error]-5 log info"); [INFO] [stdout] 210 │ tracing::error!("[error]-5.1 {}", my_err()); [INFO] [stdout]  211 >  tracing::error!("[error]-5.2 {:?}", my_err()); [INFO] [stdout] 212 │ tracing::error!("[error]-5.3 {:#}", my_err()); [INFO] [stdout] 213 │ tracing::error!("[error]-5.4 {:#?}", my_err()); [INFO] [stdout] 10: myutil[85211c2db27e5d90]::log::tests::display_full [INFO] [stdout] at /opt/rustwide/workdir/src/log.rs:237 [INFO] [stdout] 235 │ fn display_full() { [INFO] [stdout] 236 │ init_log(LogMode::Full, tracing::Level::TRACE); [INFO] [stdout]  237 >  display(); [INFO] [stdout] 238 │ } [INFO] [stdout] 239 │ [INFO] [stdout] 11: myutil[85211c2db27e5d90]::log::tests::display_full::{closure#0} [INFO] [stdout] at /opt/rustwide/workdir/src/log.rs:235 [INFO] [stdout] 233 │ [INFO] [stdout] 234 │ #[test] [INFO] [stdout]  235 >  fn display_full() { [INFO] [stdout] 236 │ init_log(LogMode::Full, tracing::Level::TRACE); [INFO] [stdout] 237 │ display(); [INFO] [stdout]  ⋮ 22 frames hidden ⋮  [INFO] [stdout] at src/log.rs:211 on log::tests::display_full ThreadId(5) [INFO] [stdout] [INFO] [stdout] 2026-04-10 10:06:53.375 +0000 ERROR myutil::log::tests: [error]-5.3 my error 3: my error 2: error: my error 1 [INFO] [stdout] at src/log.rs:212 on log::tests::display_full ThreadId(5) [INFO] [stdout] [INFO] [stdout] test log::tests::display_simple ... FAILED [INFO] [stdout] 2026-04-10 10:06:53.376 +0000 ERROR myutil::log::tests: [error]-5.4 Error { [INFO] [stdout] msg: "my error 3", [INFO] [stdout] source: Error { [INFO] [stdout] msg: "my error 2", [INFO] [stdout] source: "error: my error 1", [INFO] [stdout] }, [INFO] [stdout] } [INFO] [stdout] at src/log.rs:213 on log::tests::display_full ThreadId(5) [INFO] [stdout] [INFO] [stdout] test log::tests::display_full ... ok [INFO] [stdout] [INFO] [stdout] failures: [INFO] [stdout] [INFO] [stdout] ---- log::tests::display_general stdout ---- [INFO] [stdout] The application panicked (crashed). [INFO] [stdout] Message: Could not set global default logger: SetGlobalDefaultError("a global default trace dispatcher has already been set") [INFO] [stdout] Location: src/log.rs:85 [INFO] [stdout] [INFO] [stdout] ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ BACKTRACE ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ [INFO] [stdout]  ⋮ 10 frames hidden ⋮  [INFO] [stdout] 11: myutil[85211c2db27e5d90]::log::init_log_general [INFO] [stdout] at /opt/rustwide/workdir/src/log.rs:85 [INFO] [stdout] 83 │ .compact() [INFO] [stderr] error: test failed, to rerun pass `--lib` [INFO] [stdout] 84 │ .finish(); [INFO] [stdout]  85 >  tracing::subscriber::set_global_default(subscriber).expect("Could not set global default logger"); [INFO] [stdout] 86 │ } [INFO] [stdout] 87 │ [INFO] [stdout] 12: myutil[85211c2db27e5d90]::log::init_log [INFO] [stdout] at /opt/rustwide/workdir/src/log.rs:22 [INFO] [stdout] 20 │ } [INFO] [stdout] 21 │ LogMode::Simple => init_log_simple(log_level), [INFO] [stdout]  22 >  LogMode::General => init_log_general(log_level), [INFO] [stdout] 23 │ LogMode::Full => init_log_full(log_level), [INFO] [stdout] 24 │ LogMode::Custom => init_log_custom(log_level), [INFO] [stdout] 13: myutil[85211c2db27e5d90]::log::tests::display_general [INFO] [stdout] at /opt/rustwide/workdir/src/log.rs:230 [INFO] [stdout] 228 │ #[test] [INFO] [stdout] 229 │ fn display_general() { [INFO] [stdout]  230 >  init_log(LogMode::General, tracing::Level::TRACE); [INFO] [stdout] 231 │ display(); [INFO] [stdout] 232 │ } [INFO] [stdout] 14: myutil[85211c2db27e5d90]::log::tests::display_general::{closure#0} [INFO] [stdout] at /opt/rustwide/workdir/src/log.rs:229 [INFO] [stdout] 227 │ [INFO] [stdout] 228 │ #[test] [INFO] [stdout]  229 >  fn display_general() { [INFO] [stdout] 230 │ init_log(LogMode::General, tracing::Level::TRACE); [INFO] [stdout] 231 │ display(); [INFO] [stdout]  ⋮ 22 frames hidden ⋮  [INFO] [stdout] [INFO] [stdout] ---- log::tests::display_custom stdout ---- [INFO] [stdout] The application panicked (crashed). [INFO] [stdout] Message: Could not set global default logger: SetGlobalDefaultError("a global default trace dispatcher has already been set") [INFO] [stdout] Location: src/log.rs:128 [INFO] [stdout] [INFO] [stdout] ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ BACKTRACE ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ [INFO] [stdout]  ⋮ 10 frames hidden ⋮  [INFO] [stdout] 11: myutil[85211c2db27e5d90]::log::init_log_custom [INFO] [stdout] at /opt/rustwide/workdir/src/log.rs:128 [INFO] [stdout] 126 │ .event_format(CustomFormatter) [INFO] [stdout] 127 │ .finish(); [INFO] [stdout]  128 >  tracing::subscriber::set_global_default(subscriber).expect("Could not set global default logger"); [INFO] [stdout] 129 │ } [INFO] [stdout] 130 │ [INFO] [stdout] 12: myutil[85211c2db27e5d90]::log::init_log [INFO] [stdout] at /opt/rustwide/workdir/src/log.rs:24 [INFO] [stdout] 22 │ LogMode::General => init_log_general(log_level), [INFO] [stdout] 23 │ LogMode::Full => init_log_full(log_level), [INFO] [stdout]  24 >  LogMode::Custom => init_log_custom(log_level), [INFO] [stdout] 25 │ } [INFO] [stdout] 26 │ [INFO] [stdout] 13: myutil[85211c2db27e5d90]::log::tests::display_custom [INFO] [stdout] at /opt/rustwide/workdir/src/log.rs:242 [INFO] [stdout] 240 │ #[test] [INFO] [stdout] 241 │ fn display_custom() { [INFO] [stdout]  242 >  init_log(LogMode::Custom, tracing::Level::TRACE); [INFO] [stdout] 243 │ display(); [INFO] [stdout] 244 │ } [INFO] [stdout] 14: myutil[85211c2db27e5d90]::log::tests::display_custom::{closure#0} [INFO] [stdout] at /opt/rustwide/workdir/src/log.rs:241 [INFO] [stdout] 239 │ [INFO] [stdout] 240 │ #[test] [INFO] [stdout]  241 >  fn display_custom() { [INFO] [stdout] 242 │ init_log(LogMode::Custom, tracing::Level::TRACE); [INFO] [stdout] 243 │ display(); [INFO] [stdout]  ⋮ 22 frames hidden ⋮  [INFO] [stdout] [INFO] [stdout] ---- error::tests::error_no_hook_test stdout ---- [INFO] [stdout] {err} >> my error 3 [INFO] [stdout] {err:?} >> [INFO] [stdout] 0: my error 3 [INFO] [stdout] 1: my error 2 [INFO] [stdout] 2: error: my error 1 [INFO] [stdout] [INFO] [stdout] ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ BACKTRACE ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ [INFO] [stdout]  ⋮ 6 frames hidden ⋮  [INFO] [stdout] 7: myutil[85211c2db27e5d90]::error::tests::my_err::{closure#0} [INFO] [stdout] at /opt/rustwide/workdir/src/error.rs:62 [INFO] [stdout] 60 │ fn my_err() -> Report { [INFO] [stdout] 61 │ let err = || -> Result<()> { [INFO] [stdout]  62 >  Err(eyre::eyre!("error: my error 1")) [INFO] [stdout] 63 │ }().context("my error 2").context("my error 3").unwrap_err(); [INFO] [stdout] 64 │ [INFO] [stdout] 8: myutil[85211c2db27e5d90]::error::tests::my_err [INFO] [stdout] at /opt/rustwide/workdir/src/error.rs:61 [INFO] [stdout] 59 │ [INFO] [stdout] 60 │ fn my_err() -> Report { [INFO] [stdout]  61 >  let err = || -> Result<()> { [INFO] [stdout] 62 │ Err(eyre::eyre!("error: my error 1")) [INFO] [stdout] 63 │ }().context("my error 2").context("my error 3").unwrap_err(); [INFO] [stdout] 9: myutil[85211c2db27e5d90]::error::tests::error_no_hook_test [INFO] [stdout] at /opt/rustwide/workdir/src/error.rs:70 [INFO] [stdout] 68 │ #[test] [INFO] [stdout] 69 │ fn error_no_hook_test() { [INFO] [stdout]  70 >  let err = my_err(); [INFO] [stdout] 71 │ print_error(&err); [INFO] [stdout] 72 │ panic!("panic: {err:?}"); [INFO] [stdout] 10: myutil[85211c2db27e5d90]::error::tests::error_no_hook_test::{closure#0} [INFO] [stdout] at /opt/rustwide/workdir/src/error.rs:69 [INFO] [stdout] 67 │ [INFO] [stdout] 68 │ #[test] [INFO] [stdout]  69 >  fn error_no_hook_test() { [INFO] [stdout] 70 │ let err = my_err(); [INFO] [stdout] 71 │ print_error(&err); [INFO] [stdout]  ⋮ 22 frames hidden ⋮  [INFO] [stdout] {err:#} >> my error 3: my error 2: error: my error 1 [INFO] [stdout] {err:#?} >> Error { [INFO] [stdout] msg: "my error 3", [INFO] [stdout] source: Error { [INFO] [stdout] msg: "my error 2", [INFO] [stdout] source: "error: my error 1", [INFO] [stdout] }, [INFO] [stdout] } [INFO] [stdout] The application panicked (crashed). [INFO] [stdout] Message: panic: [INFO] [stdout] 0: my error 3 [INFO] [stdout] 1: my error 2 [INFO] [stdout] 2: error: my error 1 [INFO] [stdout] [INFO] [stdout] ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ BACKTRACE ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ [INFO] [stdout]  ⋮ 6 frames hidden ⋮  [INFO] [stdout] 7: myutil[85211c2db27e5d90]::error::tests::my_err::{closure#0} [INFO] [stdout] at /opt/rustwide/workdir/src/error.rs:62 [INFO] [stdout] 60 │ fn my_err() -> Report { [INFO] [stdout] 61 │ let err = || -> Result<()> { [INFO] [stdout]  62 >  Err(eyre::eyre!("error: my error 1")) [INFO] [stdout] 63 │ }().context("my error 2").context("my error 3").unwrap_err(); [INFO] [stdout] 64 │ [INFO] [stdout] 8: myutil[85211c2db27e5d90]::error::tests::my_err [INFO] [stdout] at /opt/rustwide/workdir/src/error.rs:61 [INFO] [stdout] 59 │ [INFO] [stdout] 60 │ fn my_err() -> Report { [INFO] [stdout]  61 >  let err = || -> Result<()> { [INFO] [stdout] 62 │ Err(eyre::eyre!("error: my error 1")) [INFO] [stdout] 63 │ }().context("my error 2").context("my error 3").unwrap_err(); [INFO] [stdout] 9: myutil[85211c2db27e5d90]::error::tests::error_no_hook_test [INFO] [stdout] at /opt/rustwide/workdir/src/error.rs:70 [INFO] [stdout] 68 │ #[test] [INFO] [stdout] 69 │ fn error_no_hook_test() { [INFO] [stdout]  70 >  let err = my_err(); [INFO] [stdout] 71 │ print_error(&err); [INFO] [stdout] 72 │ panic!("panic: {err:?}"); [INFO] [stdout] 10: myutil[85211c2db27e5d90]::error::tests::error_no_hook_test::{closure#0} [INFO] [stdout] at /opt/rustwide/workdir/src/error.rs:69 [INFO] [stdout] 67 │ [INFO] [stdout] 68 │ #[test] [INFO] [stdout]  69 >  fn error_no_hook_test() { [INFO] [stdout] 70 │ let err = my_err(); [INFO] [stdout] 71 │ print_error(&err); [INFO] [stdout]  ⋮ 22 frames hidden ⋮  [INFO] [stdout] Location: src/error.rs:72 [INFO] [stdout] [INFO] [stdout] ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ BACKTRACE ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ [INFO] [stdout]  ⋮ 8 frames hidden ⋮  [INFO] [stdout] 9: myutil[85211c2db27e5d90]::error::tests::error_no_hook_test [INFO] [stdout] at /opt/rustwide/workdir/src/error.rs:72 [INFO] [stdout] 70 │ let err = my_err(); [INFO] [stdout] 71 │ print_error(&err); [INFO] [stdout]  72 >  panic!("panic: {err:?}"); [INFO] [stdout] 73 │ } [INFO] [stdout] 74 │ [INFO] [stdout] 10: myutil[85211c2db27e5d90]::error::tests::error_no_hook_test::{closure#0} [INFO] [stdout] at /opt/rustwide/workdir/src/error.rs:69 [INFO] [stdout] 67 │ [INFO] [stdout] 68 │ #[test] [INFO] [stdout]  69 >  fn error_no_hook_test() { [INFO] [stdout] 70 │ let err = my_err(); [INFO] [stdout] 71 │ print_error(&err); [INFO] [stdout]  ⋮ 22 frames hidden ⋮  [INFO] [stdout] [INFO] [stdout] ---- error::tests::error_hook_test stdout ---- [INFO] [stdout] {err} >> my error 3 [INFO] [stdout] {err:?} >> [INFO] [stdout] 0: my error 3 [INFO] [stdout] 1: my error 2 [INFO] [stdout] 2: error: my error 1 [INFO] [stdout] [INFO] [stdout] ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ BACKTRACE ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ [INFO] [stdout]  ⋮ 6 frames hidden ⋮  [INFO] [stdout] 7: myutil[85211c2db27e5d90]::error::tests::my_err::{closure#0} [INFO] [stdout] at /opt/rustwide/workdir/src/error.rs:62 [INFO] [stdout] 60 │ fn my_err() -> Report { [INFO] [stdout] 61 │ let err = || -> Result<()> { [INFO] [stdout]  62 >  Err(eyre::eyre!("error: my error 1")) [INFO] [stdout] 63 │ }().context("my error 2").context("my error 3").unwrap_err(); [INFO] [stdout] 64 │ [INFO] [stdout] 8: myutil[85211c2db27e5d90]::error::tests::my_err [INFO] [stdout] at /opt/rustwide/workdir/src/error.rs:61 [INFO] [stdout] 59 │ [INFO] [stdout] 60 │ fn my_err() -> Report { [INFO] [stdout]  61 >  let err = || -> Result<()> { [INFO] [stdout] 62 │ Err(eyre::eyre!("error: my error 1")) [INFO] [stdout] 63 │ }().context("my error 2").context("my error 3").unwrap_err(); [INFO] [stdout] 9: myutil[85211c2db27e5d90]::error::tests::error_hook_test [INFO] [stdout] at /opt/rustwide/workdir/src/error.rs:80 [INFO] [stdout] 78 │ init_error_hook(package_name); [INFO] [stdout] 79 │ [INFO] [stdout]  80 >  let err = my_err(); [INFO] [stdout] 81 │ print_error(&err); [INFO] [stdout] 82 │ panic!("panic: {err:?}"); [INFO] [stdout] 10: myutil[85211c2db27e5d90]::error::tests::error_hook_test::{closure#0} [INFO] [stdout] at /opt/rustwide/workdir/src/error.rs:76 [INFO] [stdout] 74 │ [INFO] [stdout] 75 │ #[test] [INFO] [stdout]  76 >  fn error_hook_test() { [INFO] [stdout] 77 │ let package_name = "myutil"; [INFO] [stdout] 78 │ init_error_hook(package_name); [INFO] [stdout]  ⋮ 22 frames hidden ⋮  [INFO] [stdout] {err:#} >> my error 3: my error 2: error: my error 1 [INFO] [stdout] {err:#?} >> Error { [INFO] [stdout] msg: "my error 3", [INFO] [stdout] source: Error { [INFO] [stdout] msg: "my error 2", [INFO] [stdout] source: "error: my error 1", [INFO] [stdout] }, [INFO] [stdout] } [INFO] [stdout] The application panicked (crashed). [INFO] [stdout] Message: panic: [INFO] [stdout] 0: my error 3 [INFO] [stdout] 1: my error 2 [INFO] [stdout] 2: error: my error 1 [INFO] [stdout] [INFO] [stdout] ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ BACKTRACE ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ [INFO] [stdout]  ⋮ 6 frames hidden ⋮  [INFO] [stdout] 7: myutil[85211c2db27e5d90]::error::tests::my_err::{closure#0} [INFO] [stdout] at /opt/rustwide/workdir/src/error.rs:62 [INFO] [stdout] 60 │ fn my_err() -> Report { [INFO] [stdout] 61 │ let err = || -> Result<()> { [INFO] [stdout]  62 >  Err(eyre::eyre!("error: my error 1")) [INFO] [stdout] 63 │ }().context("my error 2").context("my error 3").unwrap_err(); [INFO] [stdout] 64 │ [INFO] [stdout] 8: myutil[85211c2db27e5d90]::error::tests::my_err [INFO] [stdout] at /opt/rustwide/workdir/src/error.rs:61 [INFO] [stdout] 59 │ [INFO] [stdout] 60 │ fn my_err() -> Report { [INFO] [stdout]  61 >  let err = || -> Result<()> { [INFO] [stdout] 62 │ Err(eyre::eyre!("error: my error 1")) [INFO] [stdout] 63 │ }().context("my error 2").context("my error 3").unwrap_err(); [INFO] [stdout] 9: myutil[85211c2db27e5d90]::error::tests::error_hook_test [INFO] [stdout] at /opt/rustwide/workdir/src/error.rs:80 [INFO] [stdout] 78 │ init_error_hook(package_name); [INFO] [stdout] 79 │ [INFO] [stdout]  80 >  let err = my_err(); [INFO] [stdout] 81 │ print_error(&err); [INFO] [stdout] 82 │ panic!("panic: {err:?}"); [INFO] [stdout] 10: myutil[85211c2db27e5d90]::error::tests::error_hook_test::{closure#0} [INFO] [stdout] at /opt/rustwide/workdir/src/error.rs:76 [INFO] [stdout] 74 │ [INFO] [stdout] 75 │ #[test] [INFO] [stdout]  76 >  fn error_hook_test() { [INFO] [stdout] 77 │ let package_name = "myutil"; [INFO] [stdout] 78 │ init_error_hook(package_name); [INFO] [stdout]  ⋮ 22 frames hidden ⋮  [INFO] [stdout] Location: src/error.rs:82 [INFO] [stdout] [INFO] [stdout] ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ BACKTRACE ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ [INFO] [stdout]  ⋮ 8 frames hidden ⋮  [INFO] [stdout] 9: myutil[85211c2db27e5d90]::error::tests::error_hook_test [INFO] [stdout] at /opt/rustwide/workdir/src/error.rs:82 [INFO] [stdout] 80 │ let err = my_err(); [INFO] [stdout] 81 │ print_error(&err); [INFO] [stdout]  82 >  panic!("panic: {err:?}"); [INFO] [stdout] 83 │ } [INFO] [stdout] 84 │ } [INFO] [stdout] 10: myutil[85211c2db27e5d90]::error::tests::error_hook_test::{closure#0} [INFO] [stdout] at /opt/rustwide/workdir/src/error.rs:76 [INFO] [stdout] 74 │ [INFO] [stdout] 75 │ #[test] [INFO] [stdout]  76 >  fn error_hook_test() { [INFO] [stdout] 77 │ let package_name = "myutil"; [INFO] [stdout] 78 │ init_error_hook(package_name); [INFO] [stdout]  ⋮ 22 frames hidden ⋮  [INFO] [stdout] [INFO] [stdout] ---- log::tests::display_original stdout ---- [INFO] [stdout] The application panicked (crashed). [INFO] [stdout] Message: Unable to install global subscriber: SetGlobalDefaultError("a global default trace dispatcher has already been set") [INFO] [stdout] Location: /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tracing-subscriber-0.3.18/src/fmt/mod.rs:517 [INFO] [stdout] [INFO] [stdout] ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ BACKTRACE ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ [INFO] [stdout]  ⋮ 11 frames hidden ⋮  [INFO] [stdout] 12: myutil[85211c2db27e5d90]::log::init_log_original [INFO] [stdout] at /opt/rustwide/workdir/src/log.rs:48 [INFO] [stdout] 46 │ .compact() //紧凑模式 [INFO] [stdout] 47 │ // .pretty() //美观模式 [INFO] [stdout]  48 >  .init(); [INFO] [stdout] 49 │ } [INFO] [stdout] 50 │ [INFO] [stdout] 13: myutil[85211c2db27e5d90]::log::init_log [INFO] [stdout] at /opt/rustwide/workdir/src/log.rs:18 [INFO] [stdout] 16 │ match log_mode { [INFO] [stdout] 17 │ LogMode::Original => { [INFO] [stdout]  18 >  init_log_original(log_level); [INFO] [stdout] 19 │ return; [INFO] [stdout] 20 │ } [INFO] [stdout] 14: myutil[85211c2db27e5d90]::log::tests::display_original [INFO] [stdout] at /opt/rustwide/workdir/src/log.rs:218 [INFO] [stdout] 216 │ #[test] [INFO] [stdout] 217 │ fn display_original() { [INFO] [stdout]  218 >  init_log(LogMode::Original, tracing::Level::TRACE); [INFO] [stdout] 219 │ display(); [INFO] [stdout] 220 │ } [INFO] [stdout] 15: myutil[85211c2db27e5d90]::log::tests::display_original::{closure#0} [INFO] [stdout] at /opt/rustwide/workdir/src/log.rs:217 [INFO] [stdout] 215 │ [INFO] [stdout] 216 │ #[test] [INFO] [stdout]  217 >  fn display_original() { [INFO] [stdout] 218 │ init_log(LogMode::Original, tracing::Level::TRACE); [INFO] [stdout] 219 │ display(); [INFO] [stdout]  ⋮ 22 frames hidden ⋮  [INFO] [stdout] [INFO] [stdout] ---- log::tests::display_simple stdout ---- [INFO] [stdout] The application panicked (crashed). [INFO] [stdout] Message: Could not set global default logger: SetGlobalDefaultError("a global default trace dispatcher has already been set") [INFO] [stdout] Location: src/log.rs:58 [INFO] [stdout] [INFO] [stdout] ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ BACKTRACE ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ [INFO] [stdout]  ⋮ 10 frames hidden ⋮  [INFO] [stdout] 11: myutil[85211c2db27e5d90]::log::init_log_simple [INFO] [stdout] at /opt/rustwide/workdir/src/log.rs:58 [INFO] [stdout] 56 │ .compact() [INFO] [stdout] 57 │ .finish(); [INFO] [stdout]  58 >  tracing::subscriber::set_global_default(subscriber).expect("Could not set global default logger"); [INFO] [stdout] 59 │ } [INFO] [stdout] 60 │ [INFO] [stdout] 12: myutil[85211c2db27e5d90]::log::init_log [INFO] [stdout] at /opt/rustwide/workdir/src/log.rs:21 [INFO] [stdout] 19 │ return; [INFO] [stdout] 20 │ } [INFO] [stdout]  21 >  LogMode::Simple => init_log_simple(log_level), [INFO] [stdout] 22 │ LogMode::General => init_log_general(log_level), [INFO] [stdout] 23 │ LogMode::Full => init_log_full(log_level), [INFO] [stdout] 13: myutil[85211c2db27e5d90]::log::tests::display_simple [INFO] [stdout] at /opt/rustwide/workdir/src/log.rs:224 [INFO] [stdout] 222 │ #[test] [INFO] [stdout] 223 │ fn display_simple() { [INFO] [stdout]  224 >  init_log(LogMode::Simple, tracing::Level::TRACE); [INFO] [stdout] 225 │ display(); [INFO] [stdout] 226 │ } [INFO] [stdout] 14: myutil[85211c2db27e5d90]::log::tests::display_simple::{closure#0} [INFO] [stdout] at /opt/rustwide/workdir/src/log.rs:223 [INFO] [stdout] 221 │ [INFO] [stdout] 222 │ #[test] [INFO] [stdout]  223 >  fn display_simple() { [INFO] [stdout] 224 │ init_log(LogMode::Simple, tracing::Level::TRACE); [INFO] [stdout] 225 │ display(); [INFO] [stdout]  ⋮ 22 frames hidden ⋮  [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] failures: [INFO] [stdout] error::tests::error_hook_test [INFO] [stdout] error::tests::error_no_hook_test [INFO] [stdout] log::tests::display_custom [INFO] [stdout] log::tests::display_general [INFO] [stdout] log::tests::display_original [INFO] [stdout] log::tests::display_simple [INFO] [stdout] [INFO] [stdout] test result: FAILED. 2 passed; 6 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.38s [INFO] [stdout] [INFO] running `Command { std: "docker" "inspect" "4d296994908c024c89ed7316ad228a50a1e3218e02bc40d244fe4870739df976", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "4d296994908c024c89ed7316ad228a50a1e3218e02bc40d244fe4870739df976", kill_on_drop: false }` [INFO] [stdout] 4d296994908c024c89ed7316ad228a50a1e3218e02bc40d244fe4870739df976