[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#caccb4d0368bd918ef6668af8e13834d07040417 for pr-146098-1 [INFO] running `Command { std: "git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fmajksa-dev%2Frust-io" "/workspace/builds/worker-4-tc1/source", kill_on_drop: false }` [INFO] [stderr] Cloning into '/workspace/builds/worker-4-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-4-tc1/source/Cargo.toml [INFO] validating manifest of git repo https://github.com/majksa-dev/rust-io on toolchain caccb4d0368bd918ef6668af8e13834d07040417 [INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+caccb4d0368bd918ef6668af8e13834d07040417" "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" "+caccb4d0368bd918ef6668af8e13834d07040417" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }` [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 tokio-macros v2.3.0 [INFO] [stderr] Downloaded sendfile v0.3.0 [INFO] [stderr] Downloaded indenter v0.3.3 [INFO] [stderr] Downloaded owo-colors v3.5.0 [INFO] [stderr] Downloaded thread_local v1.1.8 [INFO] [stderr] Downloaded serde v1.0.204 [INFO] [stderr] Downloaded miniz_oxide v0.7.4 [INFO] [stderr] Downloaded valuable v0.1.0 [INFO] [stderr] Downloaded derive_more v0.99.18 [INFO] [stderr] Downloaded anyhow v1.0.86 [INFO] [stderr] Downloaded color-spantrace v0.2.1 [INFO] [stderr] Downloaded color-eyre v0.6.3 [INFO] [stderr] Downloaded cc v1.0.106 [INFO] [stderr] Downloaded eyre v0.6.12 [INFO] [stderr] Downloaded tracing-error v0.2.0 [INFO] [stderr] Downloaded serde-env v0.1.1 [INFO] [stderr] Downloaded tracing-serde v0.1.3 [INFO] [stderr] Downloaded tracing-panic v0.1.2 [INFO] [stderr] Downloaded serde_derive v1.0.204 [INFO] [stderr] Downloaded tokio v1.38.0 [INFO] [stderr] Downloaded tracing-subscriber v0.3.18 [INFO] [stderr] Downloaded serde_json v1.0.120 [INFO] [stderr] Downloaded syn v2.0.70 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-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" "+caccb4d0368bd918ef6668af8e13834d07040417" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }` [INFO] [stdout] 392bd9c8cc16a038168b910cae27e8c6fb02195a7d9122bfd7767133afeaf640 [INFO] running `Command { std: "docker" "start" "-a" "392bd9c8cc16a038168b910cae27e8c6fb02195a7d9122bfd7767133afeaf640", kill_on_drop: false }` [INFO] running `Command { std: "docker" "inspect" "392bd9c8cc16a038168b910cae27e8c6fb02195a7d9122bfd7767133afeaf640", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "392bd9c8cc16a038168b910cae27e8c6fb02195a7d9122bfd7767133afeaf640", kill_on_drop: false }` [INFO] [stdout] 392bd9c8cc16a038168b910cae27e8c6fb02195a7d9122bfd7767133afeaf640 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-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" "-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" "+caccb4d0368bd918ef6668af8e13834d07040417" "build" "--frozen" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] 5e61906d52b57e6beb85c8d03df47442ea1d7a0ce4af44af73fa4b80ebf24498 [INFO] running `Command { std: "docker" "start" "-a" "5e61906d52b57e6beb85c8d03df47442ea1d7a0ce4af44af73fa4b80ebf24498", kill_on_drop: false }` [INFO] [stderr] Compiling proc-macro2 v1.0.86 [INFO] [stderr] Compiling serde v1.0.204 [INFO] [stderr] Compiling log v0.4.22 [INFO] [stderr] Compiling serde_json v1.0.120 [INFO] [stderr] Compiling regex-syntax v0.8.4 [INFO] [stderr] Compiling regex-syntax v0.6.29 [INFO] [stderr] Compiling overload v0.1.1 [INFO] [stderr] Compiling lazy_static v1.5.0 [INFO] [stderr] Compiling tracing-core v0.1.32 [INFO] [stderr] Compiling thread_local v1.1.8 [INFO] [stderr] Compiling cc v1.0.106 [INFO] [stderr] Compiling memchr v2.7.4 [INFO] [stderr] Compiling anyhow v1.0.86 [INFO] [stderr] Compiling gimli v0.28.1 [INFO] [stderr] Compiling eyre v0.6.12 [INFO] [stderr] Compiling miniz_oxide v0.7.4 [INFO] [stderr] Compiling nu-ansi-term v0.46.0 [INFO] [stderr] Compiling sharded-slab v0.1.7 [INFO] [stderr] Compiling indenter v0.3.3 [INFO] [stderr] Compiling rustc-demangle v0.1.24 [INFO] [stderr] Compiling owo-colors v3.5.0 [INFO] [stderr] Compiling parking_lot v0.12.3 [INFO] [stderr] Compiling object v0.32.2 [INFO] [stderr] Compiling mio v0.8.11 [INFO] [stderr] Compiling futures-core v0.3.30 [INFO] [stderr] Compiling quote v1.0.36 [INFO] [stderr] Compiling dotenv v0.15.0 [INFO] [stderr] Compiling tracing-log v0.2.0 [INFO] [stderr] Compiling futures-util v0.3.30 [INFO] [stderr] Compiling sendfile v0.3.0 [INFO] [stderr] Compiling syn v2.0.70 [INFO] [stderr] Compiling backtrace v0.3.71 [INFO] [stderr] Compiling regex-automata v0.4.7 [INFO] [stderr] Compiling regex-automata v0.1.10 [INFO] [stderr] Compiling addr2line v0.21.0 [INFO] [stderr] Compiling matchers v0.1.0 [INFO] [stderr] Compiling regex v1.10.5 [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 15.59s [INFO] running `Command { std: "docker" "inspect" "5e61906d52b57e6beb85c8d03df47442ea1d7a0ce4af44af73fa4b80ebf24498", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "5e61906d52b57e6beb85c8d03df47442ea1d7a0ce4af44af73fa4b80ebf24498", kill_on_drop: false }` [INFO] [stdout] 5e61906d52b57e6beb85c8d03df47442ea1d7a0ce4af44af73fa4b80ebf24498 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-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" "-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" "+caccb4d0368bd918ef6668af8e13834d07040417" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] 6c85abc20d2fd8dd13c546dd5857aa62a0997fd107fb20367c7edede8119e41d [INFO] running `Command { std: "docker" "start" "-a" "6c85abc20d2fd8dd13c546dd5857aa62a0997fd107fb20367c7edede8119e41d", kill_on_drop: false }` [INFO] [stderr] Compiling io v0.3.0 (/opt/rustwide/workdir) [INFO] [stderr] Finished `test` profile [unoptimized + debuginfo] target(s) in 1.37s [INFO] running `Command { std: "docker" "inspect" "6c85abc20d2fd8dd13c546dd5857aa62a0997fd107fb20367c7edede8119e41d", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "6c85abc20d2fd8dd13c546dd5857aa62a0997fd107fb20367c7edede8119e41d", kill_on_drop: false }` [INFO] [stdout] 6c85abc20d2fd8dd13c546dd5857aa62a0997fd107fb20367c7edede8119e41d [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-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" "-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" "+caccb4d0368bd918ef6668af8e13834d07040417" "test" "--frozen", kill_on_drop: false }` [INFO] [stdout] 4e5dffd1053d404a5b86001b9199a7ed2986d589f62ed38c772697d9d03dd734 [INFO] running `Command { std: "docker" "start" "-a" "4e5dffd1053d404a5b86001b9199a7ed2986d589f62ed38c772697d9d03dd734", kill_on_drop: false }` [INFO] [stderr] Finished `test` profile [unoptimized + debuginfo] target(s) in 0.06s [INFO] [stderr] Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/io-3be9fad06fbc01ec) [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-febfb0c34d96bd56) [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-b05b7c2b9b1b687d) [INFO] [stdout] [INFO] [stdout] running 2 tests [INFO] [stdout] 2025-10-01T16:07:48.999032Z 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] test copy_file ... ok [INFO] [stdout] test copy_file_exact ... FAILED [INFO] [stdout] [INFO] [stdout] failures: [INFO] [stdout] [INFO] [stdout] ---- copy_file_exact 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::he3dcb7862578ad14 [INFO] [stdout] at /rustc/caccb4d0368bd918ef6668af8e13834d07040417/library/core/src/result.rs:1178 [INFO] [stdout] 11: tracing_subscriber::util::SubscriberInitExt::init::h1aafc76292744df4 [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::h71d71dbc6badab7f [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::hcdc8529d75b6e60f [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::he7d518ee376fb645 [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_exact::{{closure}}::h22bb033e5d70253d [INFO] [stdout] at /opt/rustwide/workdir/tests/copy_file.rs:43 [INFO] [stdout] 41 │ env::set_var("RUST_LOG", "debug"); [INFO] [stdout] 42 │ env::set_var("APP_ENV", "d"); [INFO] [stdout]  43 >  essentials::install(); [INFO] [stdout] 44 │ let listener = TcpListener::bind("127.0.0.1:0").await.unwrap(); [INFO] [stdout] 45 │ let addr = listener.local_addr().unwrap(); [INFO] [stdout] 16:  as core::future::future::Future>::poll::hb86e2ca3f6ec2a42 [INFO] [stdout] at /rustc/caccb4d0368bd918ef6668af8e13834d07040417/library/core/src/future/future.rs:133 [INFO] [stdout] 17:  as core::future::future::Future>::poll::h37e7035776729f2a [INFO] [stdout] at /rustc/caccb4d0368bd918ef6668af8e13834d07040417/library/core/src/future/future.rs:133 [INFO] [stdout] 18: tokio::runtime::scheduler::current_thread::CoreGuard::block_on::{{closure}}::{{closure}}::{{closure}}::hb665c0db56cad734 [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::h0ff266cb7badedbd [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::hf2be87616cac4775 [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] [stdout] 74 │ } [INFO] [stdout] 75 │ [INFO] [stdout] 21: tokio::runtime::scheduler::current_thread::CoreGuard::block_on::{{closure}}::{{closure}}::h1ccac89de23e9e4e [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::h86b2ac3cf7053cb5 [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}}::h615373724c3cc5c1 [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}}::h557580ffdc366243 [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::h694ed8f5219387f7 [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}}::ha2b85d7717f3681d [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::h26fcaa6a1df079fa [INFO] [stdout] at /rustc/caccb4d0368bd918ef6668af8e13834d07040417/library/std/src/thread/local.rs:315 [INFO] [stdout] 28: std::thread::local::LocalKey::with::h9e53c810929a2db8 [INFO] [stdout] at /rustc/caccb4d0368bd918ef6668af8e13834d07040417/library/std/src/thread/local.rs:279 [INFO] [stdout] 29: tokio::runtime::context::set_scheduler::h5c64ac60936ca93d [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::h21f92bc58d050805 [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::h705705b3b1ef3076 [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}}::h0cf1bd309bb06358 [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::ha7409de0bfe1f7ea [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::ha8e81c65ad03e325 [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::h210ff805df0e4bfb [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_exact::hc1e88867f72ae4e2 [INFO] [stdout] at /opt/rustwide/workdir/tests/copy_file.rs:68 [INFO] [stdout] 66 │ } [INFO] [stdout] 67 │ // 20 MB file [INFO] [stdout]  68 >  assert_eq!(len, 1024 * 1024); [INFO] [stdout] 69 │ } [INFO] [stdout] 37: copy_file::copy_file_exact::{{closure}}::h01302ca6a7f656f4 [INFO] [stdout] at /opt/rustwide/workdir/tests/copy_file.rs:40 [INFO] [stdout] 38 │ [INFO] [stdout] 39 │ #[tokio::test] [INFO] [stdout]  40 > async fn copy_file_exact() { [INFO] [stdout] 41 │ env::set_var("RUST_LOG", "debug"); [INFO] [stdout] 42 │ env::set_var("APP_ENV", "d"); [INFO] [stdout] 38: core::ops::function::FnOnce::call_once::h683890a90ba63fe0 [INFO] [stdout] at /rustc/caccb4d0368bd918ef6668af8e13834d07040417/library/core/src/ops/function.rs:250 [INFO] [stdout] 39: core::ops::function::FnOnce::call_once::h933a5d67211adff2 [INFO] [stdout] at /rustc/caccb4d0368bd918ef6668af8e13834d07040417/library/core/src/ops/function.rs:250 [INFO] [stdout] 40: test::__rust_begin_short_backtrace::h2157af19b0990ffb [INFO] [stdout] at /rustc/caccb4d0368bd918ef6668af8e13834d07040417/library/test/src/lib.rs:663 [INFO] [stdout] 41: test::run_test_in_process::{{closure}}::hc3b17e0f313c900a [INFO] [stdout] at /rustc/caccb4d0368bd918ef6668af8e13834d07040417/library/test/src/lib.rs:686 [INFO] [stdout] 42:  as core::ops::function::FnOnce<()>>::call_once::ha23e4b490259273c [INFO] [stdout] at /rustc/caccb4d0368bd918ef6668af8e13834d07040417/library/core/src/panic/unwind_safe.rs:274 [INFO] [stdout] 43: std::panicking::catch_unwind::do_call::h8dc9d5da83792f7a [INFO] [stdout] at /rustc/caccb4d0368bd918ef6668af8e13834d07040417/library/std/src/panicking.rs:590 [INFO] [stdout] 44: std::panicking::catch_unwind::h0d8461330de4a18b [INFO] [stdout] at /rustc/caccb4d0368bd918ef6668af8e13834d07040417/library/std/src/panicking.rs:553 [INFO] [stdout] 45: std::panic::catch_unwind::h0321b51397bf9811 [INFO] [stdout] at /rustc/caccb4d0368bd918ef6668af8e13834d07040417/library/std/src/panic.rs:359 [INFO] [stdout] 46: test::run_test_in_process::hcaaa440fdbe4a513 [INFO] [stdout] at /rustc/caccb4d0368bd918ef6668af8e13834d07040417/library/test/src/lib.rs:686 [INFO] [stdout] 47: test::run_test::{{closure}}::h6345da602b85d6c3 [INFO] [stdout] at /rustc/caccb4d0368bd918ef6668af8e13834d07040417/library/test/src/lib.rs:607 [INFO] [stdout] 48: test::run_test::{{closure}}::h90a3b75194d37b5d [INFO] [stdout] at /rustc/caccb4d0368bd918ef6668af8e13834d07040417/library/test/src/lib.rs:637 [INFO] [stdout] 49: std::sys::backtrace::__rust_begin_short_backtrace::hae41a17001f53ede [INFO] [stdout] at /rustc/caccb4d0368bd918ef6668af8e13834d07040417/library/std/src/sys/backtrace.rs:158 [INFO] [stdout] 50: std::thread::Builder::spawn_unchecked_::{{closure}}::{{closure}}::h6ea895368381e0b1 [INFO] [stdout] at /rustc/caccb4d0368bd918ef6668af8e13834d07040417/library/std/src/thread/mod.rs:559 [INFO] [stdout] 51:  as core::ops::function::FnOnce<()>>::call_once::h9c39013633bb9237 [INFO] [stdout] at /rustc/caccb4d0368bd918ef6668af8e13834d07040417/library/core/src/panic/unwind_safe.rs:274 [INFO] [stdout] 52: std::panicking::catch_unwind::do_call::hac7dba9ee4d99108 [INFO] [stdout] at /rustc/caccb4d0368bd918ef6668af8e13834d07040417/library/std/src/panicking.rs:590 [INFO] [stdout] 53: std::panicking::catch_unwind::h73015d00e4866ebc [INFO] [stdout] at /rustc/caccb4d0368bd918ef6668af8e13834d07040417/library/std/src/panicking.rs:553 [INFO] [stdout] 54: std::panic::catch_unwind::h6f7b38e188aa4f62 [INFO] [stdout] at /rustc/caccb4d0368bd918ef6668af8e13834d07040417/library/std/src/panic.rs:359 [INFO] [stdout] 55: std::thread::Builder::spawn_unchecked_::{{closure}}::h969a6a2dc1856df7 [INFO] [stdout] at /rustc/caccb4d0368bd918ef6668af8e13834d07040417/library/std/src/thread/mod.rs:557 [INFO] [stdout] 56: core::ops::function::FnOnce::call_once{{vtable.shim}}::h269ad1ff23e72122 [INFO] [stdout] at /rustc/caccb4d0368bd918ef6668af8e13834d07040417/library/core/src/ops/function.rs:250 [INFO] [stdout] 57:  as core::ops::function::FnOnce>::call_once::hdd09a470c130e806 [INFO] [stdout] at /rustc/caccb4d0368bd918ef6668af8e13834d07040417/library/alloc/src/boxed.rs:1973 [INFO] [stdout] 58: std::sys::thread::unix::Thread::new::thread_start::h14ea1ba6e320367c [INFO] [stdout] at /rustc/caccb4d0368bd918ef6668af8e13834d07040417/library/std/src/sys/thread/unix.rs:126 [INFO] [stdout] 59: 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_exact [INFO] [stdout] [INFO] [stdout] test result: FAILED. 1 passed; 1 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.24s [INFO] [stdout] [INFO] [stderr] error: test failed, to rerun pass `--test copy_file` [INFO] running `Command { std: "docker" "inspect" "4e5dffd1053d404a5b86001b9199a7ed2986d589f62ed38c772697d9d03dd734", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "4e5dffd1053d404a5b86001b9199a7ed2986d589f62ed38c772697d9d03dd734", kill_on_drop: false }` [INFO] [stdout] 4e5dffd1053d404a5b86001b9199a7ed2986d589f62ed38c772697d9d03dd734