[INFO] cloning repository https://github.com/majksa-dev/rust-io [INFO] running `Command { std: "git" "-c" "credential.helper=" "-c" "credential.helper=/workspace/cargo-home/bin/git-credential-null" "clone" "--bare" "https://github.com/majksa-dev/rust-io" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fmajksa-dev%2Frust-io", kill_on_drop: false }` [INFO] [stderr] Cloning into bare repository '/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fmajksa-dev%2Frust-io'... [INFO] running `Command { std: "git" "rev-parse" "HEAD", kill_on_drop: false }` [INFO] [stdout] 9d3222b268ddeb7826004baeb72464fc589d10d2 [INFO] testing majksa-dev/rust-io against master#d98a5da813da67eb189387b8ccfb73cf481275d8+rustflags=-Copt-level=3 for pr-138759-retry [INFO] running `Command { std: "git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fmajksa-dev%2Frust-io" "/workspace/builds/worker-0-tc1/source", kill_on_drop: false }` [INFO] [stderr] Cloning into '/workspace/builds/worker-0-tc1/source'... [INFO] [stderr] done. [INFO] started tweaking git repo https://github.com/majksa-dev/rust-io [INFO] finished tweaking git repo https://github.com/majksa-dev/rust-io [INFO] tweaked toml for git repo https://github.com/majksa-dev/rust-io written to /workspace/builds/worker-0-tc1/source/Cargo.toml [INFO] validating manifest of git repo https://github.com/majksa-dev/rust-io on toolchain d98a5da813da67eb189387b8ccfb73cf481275d8 [INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+d98a5da813da67eb189387b8ccfb73cf481275d8" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }` [INFO] crate git repo https://github.com/majksa-dev/rust-io 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" "+d98a5da813da67eb189387b8ccfb73cf481275d8" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }` [INFO] [stderr] Blocking waiting for file lock on package cache [INFO] [stderr] Updating git repository `https://github.com/majksa-dev/rust-essentials` [INFO] [stderr] Updating crates.io index [INFO] [stderr] Downloading crates ... [INFO] [stderr] Downloaded tracing-error v0.2.0 [INFO] [stderr] Downloaded serde-env v0.1.1 [INFO] [stderr] Downloaded anyhow v1.0.86 [INFO] [stderr] Downloaded serde_derive v1.0.204 [INFO] [stderr] Downloaded serde v1.0.204 [INFO] [stderr] Downloaded cc v1.0.106 [INFO] [stderr] Downloaded serde_json v1.0.120 [INFO] [stderr] Downloaded tracing-panic v0.1.2 [INFO] [stderr] Downloaded color-spantrace v0.2.1 [INFO] [stderr] Downloaded syn v2.0.70 [INFO] [stderr] Downloaded color-eyre v0.6.3 [INFO] [stderr] Downloaded sendfile v0.3.0 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-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:e90291280db7d1fac5b66fc6dad9f9662629e7365a55743daf9bdf73ebc4ea79" "/opt/rustwide/cargo-home/bin/cargo" "+d98a5da813da67eb189387b8ccfb73cf481275d8" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }` [INFO] [stdout] 299ab07d5e2dc1542155af7e0802ca0f5bf012bef941c850c47aac2296f684a3 [INFO] running `Command { std: "docker" "start" "-a" "299ab07d5e2dc1542155af7e0802ca0f5bf012bef941c850c47aac2296f684a3", kill_on_drop: false }` [INFO] running `Command { std: "docker" "inspect" "299ab07d5e2dc1542155af7e0802ca0f5bf012bef941c850c47aac2296f684a3", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "299ab07d5e2dc1542155af7e0802ca0f5bf012bef941c850c47aac2296f684a3", kill_on_drop: false }` [INFO] [stdout] 299ab07d5e2dc1542155af7e0802ca0f5bf012bef941c850c47aac2296f684a3 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-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=forbid -Copt-level=3" "-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:e90291280db7d1fac5b66fc6dad9f9662629e7365a55743daf9bdf73ebc4ea79" "/opt/rustwide/cargo-home/bin/cargo" "+d98a5da813da67eb189387b8ccfb73cf481275d8" "build" "--frozen" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] 617d3b7c8ec13aff82b10c72f28e2251ed7d40cc453f98dfc91efacc1aee4e10 [INFO] running `Command { std: "docker" "start" "-a" "617d3b7c8ec13aff82b10c72f28e2251ed7d40cc453f98dfc91efacc1aee4e10", kill_on_drop: false }` [INFO] [stderr] Compiling syn v2.0.70 [INFO] [stderr] Compiling serde v1.0.204 [INFO] [stderr] Compiling serde_json v1.0.120 [INFO] [stderr] Compiling regex-automata v0.1.10 [INFO] [stderr] Compiling thread_local v1.1.8 [INFO] [stderr] Compiling cc v1.0.106 [INFO] [stderr] Compiling adler v1.0.2 [INFO] [stderr] Compiling memchr v2.7.4 [INFO] [stderr] Compiling gimli v0.28.1 [INFO] [stderr] Compiling tracing-log v0.2.0 [INFO] [stderr] Compiling regex v1.10.5 [INFO] [stderr] Compiling anyhow v1.0.86 [INFO] [stderr] Compiling getrandom v0.2.15 [INFO] [stderr] Compiling num-traits v0.2.19 [INFO] [stderr] Compiling rustc-demangle v0.1.24 [INFO] [stderr] Compiling owo-colors v3.5.0 [INFO] [stderr] Compiling miniz_oxide v0.7.4 [INFO] [stderr] Compiling eyre v0.6.12 [INFO] [stderr] Compiling parking_lot_core v0.9.10 [INFO] [stderr] Compiling rand_core v0.6.4 [INFO] [stderr] Compiling parking_lot v0.12.3 [INFO] [stderr] Compiling object v0.32.2 [INFO] [stderr] Compiling rand_chacha v0.3.1 [INFO] [stderr] Compiling signal-hook-registry v1.4.2 [INFO] [stderr] Compiling convert_case v0.4.0 [INFO] [stderr] Compiling matchers v0.1.0 [INFO] [stderr] Compiling futures-core v0.3.30 [INFO] [stderr] Compiling futures-util v0.3.30 [INFO] [stderr] Compiling rand v0.8.5 [INFO] [stderr] Compiling sendfile v0.3.0 [INFO] [stderr] Compiling addr2line v0.21.0 [INFO] [stderr] Compiling backtrace v0.3.71 [INFO] [stderr] Compiling serde_derive v1.0.204 [INFO] [stderr] Compiling tracing-attributes v0.1.27 [INFO] [stderr] Compiling tokio-macros v2.3.0 [INFO] [stderr] Compiling derive_more v0.99.18 [INFO] [stderr] Compiling tokio v1.38.0 [INFO] [stderr] Compiling tracing v0.1.40 [INFO] [stderr] Compiling tracing-serde v0.1.3 [INFO] [stderr] Compiling serde-env v0.1.1 [INFO] [stderr] Compiling chrono v0.4.38 [INFO] [stderr] Compiling tracing-subscriber v0.3.18 [INFO] [stderr] Compiling tracing-error v0.2.0 [INFO] [stderr] Compiling tracing-panic v0.1.2 [INFO] [stderr] Compiling color-spantrace v0.2.1 [INFO] [stderr] Compiling color-eyre v0.6.3 [INFO] [stderr] Compiling essentials v0.3.6 (https://github.com/majksa-dev/rust-essentials?tag=0.3.6#c1a0e2f6) [INFO] [stderr] Compiling io v0.3.0 (/opt/rustwide/workdir) [INFO] [stderr] Finished `dev` profile [unoptimized + debuginfo] target(s) in 2m 02s [INFO] running `Command { std: "docker" "inspect" "617d3b7c8ec13aff82b10c72f28e2251ed7d40cc453f98dfc91efacc1aee4e10", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "617d3b7c8ec13aff82b10c72f28e2251ed7d40cc453f98dfc91efacc1aee4e10", kill_on_drop: false }` [INFO] [stdout] 617d3b7c8ec13aff82b10c72f28e2251ed7d40cc453f98dfc91efacc1aee4e10 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-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=forbid -Copt-level=3" "-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:e90291280db7d1fac5b66fc6dad9f9662629e7365a55743daf9bdf73ebc4ea79" "/opt/rustwide/cargo-home/bin/cargo" "+d98a5da813da67eb189387b8ccfb73cf481275d8" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] 5227740be013968105bfd6fe2dbdceee56c2a2af7912378bc6994fb25de2e151 [INFO] running `Command { std: "docker" "start" "-a" "5227740be013968105bfd6fe2dbdceee56c2a2af7912378bc6994fb25de2e151", kill_on_drop: false }` [INFO] [stderr] Compiling io v0.3.0 (/opt/rustwide/workdir) [INFO] [stderr] Finished `test` profile [unoptimized + debuginfo] target(s) in 12.90s [INFO] running `Command { std: "docker" "inspect" "5227740be013968105bfd6fe2dbdceee56c2a2af7912378bc6994fb25de2e151", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "5227740be013968105bfd6fe2dbdceee56c2a2af7912378bc6994fb25de2e151", kill_on_drop: false }` [INFO] [stdout] 5227740be013968105bfd6fe2dbdceee56c2a2af7912378bc6994fb25de2e151 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-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=forbid -Copt-level=3" "-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:e90291280db7d1fac5b66fc6dad9f9662629e7365a55743daf9bdf73ebc4ea79" "/opt/rustwide/cargo-home/bin/cargo" "+d98a5da813da67eb189387b8ccfb73cf481275d8" "test" "--frozen", kill_on_drop: false }` [INFO] [stdout] 390485c2f42ed8b9ae1383ffedba93bfed22dcf27c54db161ecba05d9804f2b7 [INFO] running `Command { std: "docker" "start" "-a" "390485c2f42ed8b9ae1383ffedba93bfed22dcf27c54db161ecba05d9804f2b7", kill_on_drop: false }` [INFO] [stderr] Finished `test` profile [unoptimized + debuginfo] target(s) in 0.13s [INFO] [stderr] Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/io-d81d56f3594a973b) [INFO] [stdout] [INFO] [stdout] running 0 tests [INFO] [stdout] [INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s [INFO] [stdout] [INFO] [stderr] Running unittests src/main.rs (/opt/rustwide/target/debug/deps/io-23fb526f138ff005) [INFO] [stdout] [INFO] [stdout] running 0 tests [INFO] [stdout] [INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s [INFO] [stdout] [INFO] [stderr] Running tests/copy_file.rs (/opt/rustwide/target/debug/deps/copy_file-d9c4be91efbd1cb2) [INFO] [stdout] [INFO] [stdout] running 2 tests [INFO] [stdout] 2025-10-16T10:19:56.524141Z DEBUG io::copy::file::linux: copying file to tcp stream using sendfile [INFO] [stdout] at src/copy/file/linux.rs:77 [INFO] [stdout] [INFO] [stdout] 2025-10-16T10:19:56.528785Z DEBUG serde_env::de: deserialize struct: name: Context fields: ["app_env"] from { [INFO] [stdout] "app": { [INFO] [stdout] "env": d, [INFO] [stdout] }, [INFO] [stdout] "cargo": [ [INFO] [stdout] "/opt/rustwide/rustup-home/toolchains/d98a5da813da67eb189387b8ccfb73cf481275d8/bin/cargo", [INFO] [stdout] { [INFO] [stdout] "home": /opt/rustwide/cargo-home, [INFO] [stdout] "incremental": 0, [INFO] [stdout] "manifest": { [INFO] [stdout] "dir": /opt/rustwide/workdir, [INFO] [stdout] "path": /opt/rustwide/workdir/Cargo.toml, [INFO] [stdout] }, [INFO] [stdout] "pkg": { [INFO] [stdout] "name": io, [INFO] [stdout] "readme": README.md, [INFO] [stdout] "version": [ [INFO] [stdout] "0.3.0", [INFO] [stdout] { [INFO] [stdout] "major": 0, [INFO] [stdout] "minor": 3, [INFO] [stdout] "patch": 0, [INFO] [stdout] }, [INFO] [stdout] ], [INFO] [stdout] }, [INFO] [stdout] "target": { [INFO] [stdout] "dir": /opt/rustwide/target, [INFO] [stdout] }, [INFO] [stdout] }, [INFO] [stdout] ], [INFO] [stdout] "home": /root, [INFO] [stdout] "hostname": 390485c2f42e, [INFO] [stdout] "ld": { [INFO] [stdout] "library": { [INFO] [stdout] "path": /opt/rustwide/target/debug/deps:/opt/rustwide/target/debug:/opt/rustwide/rustup-home/toolchains/d98a5da813da67eb189387b8ccfb73cf481275d8/lib/rustlib/x86_64-unknown-linux-gnu/lib:/opt/rustwide/rustup-home/toolchains/d98a5da813da67eb189387b8ccfb73cf481275d8/lib, [INFO] [stdout] }, [INFO] [stdout] }, [INFO] [stdout] "path": /opt/rustwide/cargo-home/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin, [INFO] [stdout] "rust": { [INFO] [stdout] "backtrace": full, [INFO] [stdout] "log": debug, [INFO] [stdout] "recursion": { [INFO] [stdout] "count": 1, [INFO] [stdout] }, [INFO] [stdout] }, [INFO] [stdout] "rustdocflags": --cap-lints=forbid, [INFO] [stdout] "rustflags": --cap-lints=forbid -Copt-level=3, [INFO] [stdout] "rustup": { [INFO] [stdout] "home": /opt/rustwide/rustup-home, [INFO] [stdout] "toolchain": d98a5da813da67eb189387b8ccfb73cf481275d8, [INFO] [stdout] }, [INFO] [stdout] "source": { [INFO] [stdout] "dir": /opt/rustwide/workdir, [INFO] [stdout] }, [INFO] [stdout] "ssl": { [INFO] [stdout] "cert": { [INFO] [stdout] "dir": /usr/lib/ssl/certs, [INFO] [stdout] "file": /usr/lib/ssl/cert.pem, [INFO] [stdout] }, [INFO] [stdout] }, [INFO] [stdout] } [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/serde-env-0.1.1/src/de.rs:271 [INFO] [stdout] [INFO] [stdout] 2025-10-16T10:19:56.528846Z DEBUG serde_env::de: flatten keys: ["app_env"] [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/serde-env-0.1.1/src/de.rs:277 [INFO] [stdout] [INFO] [stdout] 2025-10-16T10:19:56.528852Z DEBUG serde_env::de: access keys ["app_env"] from map [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/serde-env-0.1.1/src/de.rs:344 [INFO] [stdout] [INFO] [stdout] 2025-10-16T10:19:56.528857Z DEBUG serde_env::de: deserialize string: "d" [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/serde-env-0.1.1/src/de.rs:170 [INFO] [stdout] [INFO] [stdout] test copy_file_exact ... ok [INFO] [stdout] test copy_file ... FAILED [INFO] [stdout] [INFO] [stdout] failures: [INFO] [stdout] [INFO] [stdout] ---- copy_file stdout ---- [INFO] [stdout] The application panicked (crashed). [INFO] [stdout] Message: failed to set global default 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/util.rs:91 [INFO] [stdout] [INFO] [stdout] ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ BACKTRACE ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ [INFO] [stdout]  ⋮ 9 frames hidden ⋮  [INFO] [stdout] 10: core::result::Result::expect::h2992e6044bd3c4a6 [INFO] [stdout] at /rustc/d98a5da813da67eb189387b8ccfb73cf481275d8/library/core/src/result.rs:1119 [INFO] [stdout] 11: tracing_subscriber::util::SubscriberInitExt::init::hc95499c6f678a05d [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tracing-subscriber-0.3.18/src/util.rs:91 [INFO] [stdout] 89 │ fn init(self) { [INFO] [stdout] 90 │ self.try_init() [INFO] [stdout]  91 >  .expect("failed to set global default subscriber") [INFO] [stdout] 92 │ } [INFO] [stdout] 93 │ } [INFO] [stdout] 12: essentials::log::install::h0e8f0e744f73425f [INFO] [stdout] at /opt/rustwide/cargo-home/git/checkouts/rust-essentials-b3676f35407a82c9/c1a0e2f/src/log.rs:23 [INFO] [stdout] 21 │ .from_env_lossy(), [INFO] [stdout] 22 │ ) [INFO] [stdout]  23 >  .init(); [INFO] [stdout] 24 │ } else { [INFO] [stdout] 25 │ tracing_subscriber::registry() [INFO] [stdout] 13: essentials::builder::Builder::install::hbf2dbf4a9a0293eb [INFO] [stdout] at /opt/rustwide/cargo-home/git/checkouts/rust-essentials-b3676f35407a82c9/c1a0e2f/src/builder.rs:43 [INFO] [stdout] 41 │ let context = self.context.unwrap_or_else(Context::load); [INFO] [stdout] 42 │ #[cfg(feature = "log")] [INFO] [stdout]  43 >  log::install(&context, self.log); [INFO] [stdout] 44 │ } [INFO] [stdout] 45 │ } [INFO] [stdout] 14: essentials::install::h8c3a6e994bdb8edc [INFO] [stdout] at /opt/rustwide/cargo-home/git/checkouts/rust-essentials-b3676f35407a82c9/c1a0e2f/src/lib.rs:16 [INFO] [stdout] 14 │ [INFO] [stdout] 15 │ pub fn install() { [INFO] [stdout]  16 >  Builder::::default().install(); [INFO] [stdout] 17 │ } [INFO] [stdout] 18 │ [INFO] [stdout] 15: copy_file::copy_file::{{closure}}::h725a7557d06f5d6b [INFO] [stdout] at /opt/rustwide/workdir/tests/copy_file.rs:11 [INFO] [stdout] 9 │ env::set_var("RUST_LOG", "debug"); [INFO] [stdout] 10 │ env::set_var("APP_ENV", "d"); [INFO] [stdout]  11 >  essentials::install(); [INFO] [stdout] 12 │ let listener = TcpListener::bind("127.0.0.1:0").await.unwrap(); [INFO] [stdout] 13 │ let addr = listener.local_addr().unwrap(); [INFO] [stdout] 16:  as core::future::future::Future>::poll::h4b8441450e87fcff [INFO] [stdout] at /rustc/d98a5da813da67eb189387b8ccfb73cf481275d8/library/core/src/future/future.rs:133 [INFO] [stdout] 17:  as core::future::future::Future>::poll::h95a9749ae2e201f4 [INFO] [stdout] at /rustc/d98a5da813da67eb189387b8ccfb73cf481275d8/library/core/src/future/future.rs:133 [INFO] [stdout] 18: tokio::runtime::scheduler::current_thread::CoreGuard::block_on::{{closure}}::{{closure}}::{{closure}}::h339c1f1d7f86a9cb [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.38.0/src/runtime/scheduler/current_thread/mod.rs:659 [INFO] [stdout] 657 │ if handle.reset_woken() { [INFO] [stdout] 658 │ let (c, res) = context.enter(core, || { [INFO] [stdout]  659 >  crate::runtime::coop::budget(|| future.as_mut().poll(&mut cx)) [INFO] [stdout] 660 │ }); [INFO] [stdout] 661 │ [INFO] [stdout] 19: tokio::runtime::coop::with_budget::h792439fd79226329 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.38.0/src/runtime/coop.rs:107 [INFO] [stdout] 105 │ // The function is called regardless even if the budget is not successfully [INFO] [stdout] 106 │ // set due to the thread-local being destroyed. [INFO] [stdout]  107 >  f() [INFO] [stdout] 108 │ } [INFO] [stdout] 109 │ [INFO] [stdout] 20: tokio::runtime::coop::budget::h2918b24fa10671c7 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.38.0/src/runtime/coop.rs:73 [INFO] [stdout] 71 │ #[inline(always)] [INFO] [stdout] 72 │ pub(crate) fn budget(f: impl FnOnce() -> R) -> R { [INFO] [stdout]  73 >  with_budget(Budget::initial(), f) [INFO] [stderr] error: test failed, to rerun pass `--test copy_file` [INFO] [stdout] 74 │ } [INFO] [stdout] 75 │ [INFO] [stdout] 21: tokio::runtime::scheduler::current_thread::CoreGuard::block_on::{{closure}}::{{closure}}::h18447109ed7c39ea [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.38.0/src/runtime/scheduler/current_thread/mod.rs:659 [INFO] [stdout] 657 │ if handle.reset_woken() { [INFO] [stdout] 658 │ let (c, res) = context.enter(core, || { [INFO] [stdout]  659 >  crate::runtime::coop::budget(|| future.as_mut().poll(&mut cx)) [INFO] [stdout] 660 │ }); [INFO] [stdout] 661 │ [INFO] [stdout] 22: tokio::runtime::scheduler::current_thread::Context::enter::h5ea719b0638ed0a1 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.38.0/src/runtime/scheduler/current_thread/mod.rs:404 [INFO] [stdout] 402 │ [INFO] [stdout] 403 │ // Execute the closure while tracking the execution budget [INFO] [stdout]  404 >  let ret = f(); [INFO] [stdout] 405 │ [INFO] [stdout] 406 │ // Take the scheduler core back [INFO] [stdout] 23: tokio::runtime::scheduler::current_thread::CoreGuard::block_on::{{closure}}::h5c4c6a848ff24023 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.38.0/src/runtime/scheduler/current_thread/mod.rs:658 [INFO] [stdout] 656 │ [INFO] [stdout] 657 │ if handle.reset_woken() { [INFO] [stdout]  658 >  let (c, res) = context.enter(core, || { [INFO] [stdout] 659 │ crate::runtime::coop::budget(|| future.as_mut().poll(&mut cx)) [INFO] [stdout] 660 │ }); [INFO] [stdout] 24: tokio::runtime::scheduler::current_thread::CoreGuard::enter::{{closure}}::h40c9125f5e2e9115 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.38.0/src/runtime/scheduler/current_thread/mod.rs:737 [INFO] [stdout] 735 │ [INFO] [stdout] 736 │ // Call the closure and place `core` back [INFO] [stdout]  737 >  let (core, ret) = context::set_scheduler(&self.context, || f(core, context)); [INFO] [stdout] 738 │ [INFO] [stdout] 739 │ *context.core.borrow_mut() = Some(core); [INFO] [stdout] 25: tokio::runtime::context::scoped::Scoped::set::h79fec044677c9976 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.38.0/src/runtime/context/scoped.rs:40 [INFO] [stdout] 38 │ }; [INFO] [stdout] 39 │ [INFO] [stdout]  40 >  f() [INFO] [stdout] 41 │ } [INFO] [stdout] 42 │ [INFO] [stdout] 26: tokio::runtime::context::set_scheduler::{{closure}}::ha77c76ca5c1f1c9c [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.38.0/src/runtime/context.rs:180 [INFO] [stdout] 178 │ [INFO] [stdout] 179 │ pub(super) fn set_scheduler(v: &scheduler::Context, f: impl FnOnce() -> R) -> R { [INFO] [stdout]  180 >  CONTEXT.with(|c| c.scheduler.set(v, f)) [INFO] [stdout] 181 │ } [INFO] [stdout] 182 │ [INFO] [stdout] 27: std::thread::local::LocalKey::try_with::h22dc01ebd0b1a4e4 [INFO] [stdout] at /rustc/d98a5da813da67eb189387b8ccfb73cf481275d8/library/std/src/thread/local.rs:315 [INFO] [stdout] 28: std::thread::local::LocalKey::with::hcff21d1e77451538 [INFO] [stdout] at /rustc/d98a5da813da67eb189387b8ccfb73cf481275d8/library/std/src/thread/local.rs:279 [INFO] [stdout] 29: tokio::runtime::context::set_scheduler::hcc7918ad6befe367 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.38.0/src/runtime/context.rs:180 [INFO] [stdout] 178 │ [INFO] [stdout] 179 │ pub(super) fn set_scheduler(v: &scheduler::Context, f: impl FnOnce() -> R) -> R { [INFO] [stdout]  180 >  CONTEXT.with(|c| c.scheduler.set(v, f)) [INFO] [stdout] 181 │ } [INFO] [stdout] 182 │ [INFO] [stdout] 30: tokio::runtime::scheduler::current_thread::CoreGuard::enter::h2763082fa2b17989 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.38.0/src/runtime/scheduler/current_thread/mod.rs:737 [INFO] [stdout] 735 │ [INFO] [stdout] 736 │ // Call the closure and place `core` back [INFO] [stdout]  737 >  let (core, ret) = context::set_scheduler(&self.context, || f(core, context)); [INFO] [stdout] 738 │ [INFO] [stdout] 739 │ *context.core.borrow_mut() = Some(core); [INFO] [stdout] 31: tokio::runtime::scheduler::current_thread::CoreGuard::block_on::hb49729b52fb60ba6 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.38.0/src/runtime/scheduler/current_thread/mod.rs:646 [INFO] [stdout] 644 │ #[track_caller] [INFO] [stdout] 645 │ fn block_on(self, future: F) -> F::Output { [INFO] [stdout]  646 >  let ret = self.enter(|mut core, context| { [INFO] [stdout] 647 │ let waker = Handle::waker_ref(&context.handle); [INFO] [stdout] 648 │ let mut cx = std::task::Context::from_waker(&waker); [INFO] [stdout] 32: tokio::runtime::scheduler::current_thread::CurrentThread::block_on::{{closure}}::hf87dacdadce026ea [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.38.0/src/runtime/scheduler/current_thread/mod.rs:175 [INFO] [stdout] 173 │ loop { [INFO] [stdout] 174 │ if let Some(core) = self.take_core(handle) { [INFO] [stdout]  175 >  return core.block_on(future); [INFO] [stdout] 176 │ } else { [INFO] [stdout] 177 │ let notified = self.notify.notified(); [INFO] [stdout] 33: tokio::runtime::context::runtime::enter_runtime::h7936d2bf3aab658a [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.38.0/src/runtime/context/runtime.rs:65 [INFO] [stdout] 63 │ [INFO] [stdout] 64 │ if let Some(mut guard) = maybe_guard { [INFO] [stdout]  65 >  return f(&mut guard.blocking); [INFO] [stdout] 66 │ } [INFO] [stdout] 67 │ [INFO] [stdout] 34: tokio::runtime::scheduler::current_thread::CurrentThread::block_on::h62d07dd2c92b3fc8 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.38.0/src/runtime/scheduler/current_thread/mod.rs:167 [INFO] [stdout] 165 │ pin!(future); [INFO] [stdout] 166 │ [INFO] [stdout]  167 >  crate::runtime::context::enter_runtime(handle, false, |blocking| { [INFO] [stdout] 168 │ let handle = handle.as_current_thread(); [INFO] [stdout] 169 │ [INFO] [stdout] 35: tokio::runtime::runtime::Runtime::block_on::h653402846208cdba [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.38.0/src/runtime/runtime.rs:347 [INFO] [stdout] 345 │ [INFO] [stdout] 346 │ match &self.scheduler { [INFO] [stdout]  347 >  Scheduler::CurrentThread(exec) => exec.block_on(&self.handle.inner, future), [INFO] [stdout] 348 │ #[cfg(feature = "rt-multi-thread")] [INFO] [stdout] 349 │ Scheduler::MultiThread(exec) => exec.block_on(&self.handle.inner, future), [INFO] [stdout] 36: copy_file::copy_file::h41190c459b450407 [INFO] [stdout] at /opt/rustwide/workdir/tests/copy_file.rs:36 [INFO] [stdout] 34 │ } [INFO] [stdout] 35 │ // 20 MB file [INFO] [stdout]  36 >  assert_eq!(len, 20 * 1024 * 1024); [INFO] [stdout] 37 │ } [INFO] [stdout] 38 │ [INFO] [stdout] 37: copy_file::copy_file::{{closure}}::h9afd2acdc262774c [INFO] [stdout] at /opt/rustwide/workdir/tests/copy_file.rs:8 [INFO] [stdout] 6 │ [INFO] [stdout] 7 │ #[tokio::test] [INFO] [stdout]  8 > async fn copy_file() { [INFO] [stdout] 9 │ env::set_var("RUST_LOG", "debug"); [INFO] [stdout] 10 │ env::set_var("APP_ENV", "d"); [INFO] [stdout] 38: core::ops::function::FnOnce::call_once::h064764516c5ab10c [INFO] [stdout] at /rustc/d98a5da813da67eb189387b8ccfb73cf481275d8/library/core/src/ops/function.rs:250 [INFO] [stdout] 39: core::ops::function::FnOnce::call_once::h2869fb5b0a2b0bdc [INFO] [stdout] at /rustc/d98a5da813da67eb189387b8ccfb73cf481275d8/library/core/src/ops/function.rs:250 [INFO] [stdout] 40: test::__rust_begin_short_backtrace::h7dd7142bd62fa711 [INFO] [stdout] at /rustc/d98a5da813da67eb189387b8ccfb73cf481275d8/library/test/src/lib.rs:648 [INFO] [stdout] 41: test::run_test_in_process::{{closure}}::h43a753f038d36b3f [INFO] [stdout] at /rustc/d98a5da813da67eb189387b8ccfb73cf481275d8/library/test/src/lib.rs:671 [INFO] [stdout] 42:  as core::ops::function::FnOnce<()>>::call_once::h0ca95dd3e12d1e16 [INFO] [stdout] at /rustc/d98a5da813da67eb189387b8ccfb73cf481275d8/library/core/src/panic/unwind_safe.rs:272 [INFO] [stdout] 43: std::panicking::catch_unwind::do_call::he308587d70ac34ba [INFO] [stdout] at /rustc/d98a5da813da67eb189387b8ccfb73cf481275d8/library/std/src/panicking.rs:589 [INFO] [stdout] 44: std::panicking::catch_unwind::h30dcba31973e8fb0 [INFO] [stdout] at /rustc/d98a5da813da67eb189387b8ccfb73cf481275d8/library/std/src/panicking.rs:552 [INFO] [stdout] 45: std::panic::catch_unwind::hfb68364e5621fbee [INFO] [stdout] at /rustc/d98a5da813da67eb189387b8ccfb73cf481275d8/library/std/src/panic.rs:359 [INFO] [stdout] 46: test::run_test_in_process::hcdcc2977903b998a [INFO] [stdout] at /rustc/d98a5da813da67eb189387b8ccfb73cf481275d8/library/test/src/lib.rs:671 [INFO] [stdout] 47: test::run_test::{{closure}}::h544a6550958c5d14 [INFO] [stdout] at /rustc/d98a5da813da67eb189387b8ccfb73cf481275d8/library/test/src/lib.rs:592 [INFO] [stdout] 48: test::run_test::{{closure}}::hb172e48ebe2b92c7 [INFO] [stdout] at /rustc/d98a5da813da67eb189387b8ccfb73cf481275d8/library/test/src/lib.rs:622 [INFO] [stdout] 49: std::sys::backtrace::__rust_begin_short_backtrace::h7e79d8706638bea0 [INFO] [stdout] at /rustc/d98a5da813da67eb189387b8ccfb73cf481275d8/library/std/src/sys/backtrace.rs:152 [INFO] [stdout] 50: std::thread::Builder::spawn_unchecked_::{{closure}}::{{closure}}::hc4a275f1e71b8ab0 [INFO] [stdout] at /rustc/d98a5da813da67eb189387b8ccfb73cf481275d8/library/std/src/thread/mod.rs:559 [INFO] [stdout] 51:  as core::ops::function::FnOnce<()>>::call_once::h01ed0d242df78cfd [INFO] [stdout] at /rustc/d98a5da813da67eb189387b8ccfb73cf481275d8/library/core/src/panic/unwind_safe.rs:272 [INFO] [stdout] 52: std::panicking::catch_unwind::do_call::h052f373fb905fee0 [INFO] [stdout] at /rustc/d98a5da813da67eb189387b8ccfb73cf481275d8/library/std/src/panicking.rs:589 [INFO] [stdout] 53: std::panicking::catch_unwind::hb425d20c8ffb09c8 [INFO] [stdout] at /rustc/d98a5da813da67eb189387b8ccfb73cf481275d8/library/std/src/panicking.rs:552 [INFO] [stdout] 54: std::panic::catch_unwind::ha0d5dfbf18fdeda5 [INFO] [stdout] at /rustc/d98a5da813da67eb189387b8ccfb73cf481275d8/library/std/src/panic.rs:359 [INFO] [stdout] 55: std::thread::Builder::spawn_unchecked_::{{closure}}::hbe79182bd37949c7 [INFO] [stdout] at /rustc/d98a5da813da67eb189387b8ccfb73cf481275d8/library/std/src/thread/mod.rs:557 [INFO] [stdout] 56: core::ops::function::FnOnce::call_once{{vtable.shim}}::hc096c4a06972fde5 [INFO] [stdout] at /rustc/d98a5da813da67eb189387b8ccfb73cf481275d8/library/core/src/ops/function.rs:250 [INFO] [stdout] 57:  as core::ops::function::FnOnce>::call_once::h47377e27fb938a26 [INFO] [stdout] at /rustc/d98a5da813da67eb189387b8ccfb73cf481275d8/library/alloc/src/boxed.rs:1971 [INFO] [stdout] 58:  as core::ops::function::FnOnce>::call_once::h72f1fe5d095abf57 [INFO] [stdout] at /rustc/d98a5da813da67eb189387b8ccfb73cf481275d8/library/alloc/src/boxed.rs:1971 [INFO] [stdout] 59: std::sys::pal::unix::thread::Thread::new::thread_start::h0a4d8e1b9c0d38cf [INFO] [stdout] at /rustc/d98a5da813da67eb189387b8ccfb73cf481275d8/library/std/src/sys/pal/unix/thread.rs:97 [INFO] [stdout] 60: clone [INFO] [stdout] at : [INFO] [stdout] [INFO] [stdout] Run with COLORBT_SHOW_HIDDEN=1 environment variable to disable frame filtering. [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] failures: [INFO] [stdout] copy_file [INFO] [stdout] [INFO] [stdout] test result: FAILED. 1 passed; 1 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.11s [INFO] [stdout] [INFO] running `Command { std: "docker" "inspect" "390485c2f42ed8b9ae1383ffedba93bfed22dcf27c54db161ecba05d9804f2b7", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "390485c2f42ed8b9ae1383ffedba93bfed22dcf27c54db161ecba05d9804f2b7", kill_on_drop: false }` [INFO] [stdout] 390485c2f42ed8b9ae1383ffedba93bfed22dcf27c54db161ecba05d9804f2b7