[INFO] fetching crate tokio_serial_pacing 0.1.1... [INFO] testing tokio_serial_pacing-0.1.1 against try#c066bf6804adc55193b71afdff6a56922f4a8689+target=x86_64-unknown-linux-musl for musl_upgrade_1_2_5_in_2025-retry-1 [INFO] extracting crate tokio_serial_pacing 0.1.1 into /workspace/builds/worker-5-tc2/source [INFO] started tweaking crates.io crate tokio_serial_pacing 0.1.1 [INFO] finished tweaking crates.io crate tokio_serial_pacing 0.1.1 [INFO] tweaked toml for crates.io crate tokio_serial_pacing 0.1.1 written to /workspace/builds/worker-5-tc2/source/Cargo.toml [INFO] validating manifest of crates.io crate tokio_serial_pacing 0.1.1 on toolchain c066bf6804adc55193b71afdff6a56922f4a8689 [INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+c066bf6804adc55193b71afdff6a56922f4a8689" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }` [INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+c066bf6804adc55193b71afdff6a56922f4a8689" "generate-lockfile" "--manifest-path" "Cargo.toml", kill_on_drop: false }` [INFO] [stderr] Updating crates.io index [INFO] [stderr] Locking 63 packages to latest compatible versions [INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+c066bf6804adc55193b71afdff6a56922f4a8689" "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:90999bfc7ae267e83380e433d8e61a7c072ca6729e92edbae886d3423b3a6f4c" "/opt/rustwide/cargo-home/bin/cargo" "+c066bf6804adc55193b71afdff6a56922f4a8689" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }` [INFO] [stdout] 70de36d301f60ca9ae83476a053df43d298e94928abbb1b6e893ec6d8a325102 [INFO] running `Command { std: "docker" "start" "-a" "70de36d301f60ca9ae83476a053df43d298e94928abbb1b6e893ec6d8a325102", kill_on_drop: false }` [INFO] running `Command { std: "docker" "inspect" "70de36d301f60ca9ae83476a053df43d298e94928abbb1b6e893ec6d8a325102", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "70de36d301f60ca9ae83476a053df43d298e94928abbb1b6e893ec6d8a325102", kill_on_drop: false }` [INFO] [stdout] 70de36d301f60ca9ae83476a053df43d298e94928abbb1b6e893ec6d8a325102 [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:90999bfc7ae267e83380e433d8e61a7c072ca6729e92edbae886d3423b3a6f4c" "/opt/rustwide/cargo-home/bin/cargo" "+c066bf6804adc55193b71afdff6a56922f4a8689" "build" "--frozen" "--message-format=json" "--target" "x86_64-unknown-linux-musl", kill_on_drop: false }` [INFO] [stdout] ceddaee9086104d030fa1fdd9a958996367172369b8313dc27f092f33d16a4e7 [INFO] running `Command { std: "docker" "start" "-a" "ceddaee9086104d030fa1fdd9a958996367172369b8313dc27f092f33d16a4e7", kill_on_drop: false }` [INFO] [stderr] Compiling libc v0.2.174 [INFO] [stderr] Compiling pin-project-lite v0.2.16 [INFO] [stderr] Compiling futures-core v0.3.31 [INFO] [stderr] Compiling cfg-if v1.0.1 [INFO] [stderr] Compiling futures-sink v0.3.31 [INFO] [stderr] Compiling cfg_aliases v0.2.1 [INFO] [stderr] Compiling bitflags v2.9.1 [INFO] [stderr] Compiling futures-task v0.3.31 [INFO] [stderr] Compiling slab v0.4.10 [INFO] [stderr] Compiling futures-io v0.3.31 [INFO] [stderr] Compiling bitflags v1.3.2 [INFO] [stderr] Compiling pin-utils v0.1.0 [INFO] [stderr] Compiling memchr v2.7.5 [INFO] [stderr] Compiling log v0.4.27 [INFO] [stderr] Compiling scopeguard v1.2.0 [INFO] [stderr] Compiling futures-macro v0.3.31 [INFO] [stderr] Compiling nix v0.29.0 [INFO] [stderr] Compiling futures-channel v0.3.31 [INFO] [stderr] Compiling futures-util v0.3.31 [INFO] [stderr] Compiling mio v1.0.4 [INFO] [stderr] Compiling nix v0.26.4 [INFO] [stderr] Compiling socket2 v0.5.10 [INFO] [stderr] Compiling tokio v1.45.1 [INFO] [stderr] Compiling serialport v4.7.2 [INFO] [stderr] Compiling mio-serial v5.0.6 [INFO] [stderr] Compiling futures-executor v0.3.31 [INFO] [stderr] Compiling futures v0.3.31 [INFO] [stderr] Compiling tokio-serial v5.4.5 [INFO] [stderr] Compiling tokio_serial_pacing v0.1.1 (/opt/rustwide/workdir) [INFO] [stderr] Finished `dev` profile [unoptimized + debuginfo] target(s) in 8.13s [INFO] running `Command { std: "docker" "inspect" "ceddaee9086104d030fa1fdd9a958996367172369b8313dc27f092f33d16a4e7", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "ceddaee9086104d030fa1fdd9a958996367172369b8313dc27f092f33d16a4e7", kill_on_drop: false }` [INFO] [stdout] ceddaee9086104d030fa1fdd9a958996367172369b8313dc27f092f33d16a4e7 [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:90999bfc7ae267e83380e433d8e61a7c072ca6729e92edbae886d3423b3a6f4c" "/opt/rustwide/cargo-home/bin/cargo" "+c066bf6804adc55193b71afdff6a56922f4a8689" "test" "--frozen" "--no-run" "--message-format=json" "--target" "x86_64-unknown-linux-musl", kill_on_drop: false }` [INFO] [stdout] b66e4fe4148b0db66d10a51688c42640fe53014ca62727647dd41a2d97e70e50 [INFO] running `Command { std: "docker" "start" "-a" "b66e4fe4148b0db66d10a51688c42640fe53014ca62727647dd41a2d97e70e50", kill_on_drop: false }` [INFO] [stderr] Compiling bytes v1.10.1 [INFO] [stderr] Compiling tokio-macros v2.5.0 [INFO] [stderr] Compiling tokio v1.45.1 [INFO] [stderr] Compiling tokio-serial v5.4.5 [INFO] [stderr] Compiling tokio_serial_pacing v0.1.1 (/opt/rustwide/workdir) [INFO] [stderr] Finished `test` profile [unoptimized + debuginfo] target(s) in 7.20s [INFO] running `Command { std: "docker" "inspect" "b66e4fe4148b0db66d10a51688c42640fe53014ca62727647dd41a2d97e70e50", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "b66e4fe4148b0db66d10a51688c42640fe53014ca62727647dd41a2d97e70e50", kill_on_drop: false }` [INFO] [stdout] b66e4fe4148b0db66d10a51688c42640fe53014ca62727647dd41a2d97e70e50 [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:90999bfc7ae267e83380e433d8e61a7c072ca6729e92edbae886d3423b3a6f4c" "/opt/rustwide/cargo-home/bin/cargo" "+c066bf6804adc55193b71afdff6a56922f4a8689" "test" "--frozen" "--target" "x86_64-unknown-linux-musl", kill_on_drop: false }` [INFO] [stdout] 3720671d03257ff3fe5c3f219777b6e55caf8a2b7bf99f4d149bafdce518a758 [INFO] running `Command { std: "docker" "start" "-a" "3720671d03257ff3fe5c3f219777b6e55caf8a2b7bf99f4d149bafdce518a758", kill_on_drop: false }` [INFO] [stderr] Finished `test` profile [unoptimized + debuginfo] target(s) in 0.08s [INFO] [stderr] Running unittests src/lib.rs (/opt/rustwide/target/x86_64-unknown-linux-musl/debug/deps/tokio_serial_pacing-31ced29fbc3e4eaf) [INFO] [stdout] [INFO] [stdout] running 4 tests [INFO] [stdout] test tests::ensure_sync_port_works ... ok [INFO] [stdout] test tests::check_wait_time ... ok [INFO] [stdout] test tests::check_write_pacing ... ok [INFO] [stdout] test tests::check_read_pacing ... FAILED [INFO] [stdout] [INFO] [stdout] failures: [INFO] [stdout] [INFO] [stdout] ---- tests::check_read_pacing stdout ---- [INFO] [stdout] TX=>RX: Writing large buf [INFO] [stdout] RX>=TX Reading large(?) buf [INFO] [stdout] TX=>RX Flushing [INFO] [stdout] TX<=RX Reading ack [INFO] [stdout] RX=>TX, Writing ack data [INFO] [stdout] Test cycle complete [INFO] [stdout] [INFO] [stdout] thread 'tests::check_read_pacing' panicked at src/lib.rs:152:9: [INFO] [stdout] It should not take a millisecond normally. [INFO] [stdout] stack backtrace: [INFO] [stdout] 0: 0x727732e36d62 - std::backtrace_rs::backtrace::libunwind::trace::hc0462517b060cf4b [INFO] [stdout] at /rustc/c066bf6804adc55193b71afdff6a56922f4a8689/library/std/src/../../backtrace/src/backtrace/libunwind.rs:117:9 [INFO] [stdout] 1: 0x727732e36d62 - std::backtrace_rs::backtrace::trace_unsynchronized::he3231a4df0b43f00 [INFO] [stdout] at /rustc/c066bf6804adc55193b71afdff6a56922f4a8689/library/std/src/../../backtrace/src/backtrace/mod.rs:66:14 [INFO] [stdout] 2: 0x727732e36d62 - std::sys::backtrace::_print_fmt::h7f744866c41d3a31 [INFO] [stdout] at /rustc/c066bf6804adc55193b71afdff6a56922f4a8689/library/std/src/sys/backtrace.rs:66:9 [INFO] [stdout] 3: 0x727732e36d62 - ::fmt::h1ef81e33f64f634e [INFO] [stdout] at /rustc/c066bf6804adc55193b71afdff6a56922f4a8689/library/std/src/sys/backtrace.rs:39:26 [INFO] [stdout] 4: 0x727732e730a3 - core::fmt::rt::Argument::fmt::h81a194710c4034a0 [INFO] [stdout] at /rustc/c066bf6804adc55193b71afdff6a56922f4a8689/library/core/src/fmt/rt.rs:173:76 [INFO] [stdout] 5: 0x727732e730a3 - core::fmt::write::h1ab4e554d51bab56 [INFO] [stdout] at /rustc/c066bf6804adc55193b71afdff6a56922f4a8689/library/core/src/fmt/mod.rs:1460:25 [INFO] [stdout] 6: 0x727732e340b3 - std::io::default_write_fmt::h1d8065796ed487b7 [INFO] [stdout] at /rustc/c066bf6804adc55193b71afdff6a56922f4a8689/library/std/src/io/mod.rs:639:11 [INFO] [stdout] 7: 0x727732e340b3 - std::io::Write::write_fmt::hb706b37400bb89a4 [INFO] [stdout] at /rustc/c066bf6804adc55193b71afdff6a56922f4a8689/library/std/src/io/mod.rs:1954:13 [INFO] [stdout] 8: 0x727732e36bb2 - std::sys::backtrace::BacktraceLock::print::h727d5db2588a6e85 [INFO] [stdout] at /rustc/c066bf6804adc55193b71afdff6a56922f4a8689/library/std/src/sys/backtrace.rs:42:9 [INFO] [stdout] 9: 0x727732e3833c - std::panicking::default_hook::{{closure}}::h6350a945c4503995 [INFO] [stdout] at /rustc/c066bf6804adc55193b71afdff6a56922f4a8689/library/std/src/panicking.rs:300:27 [INFO] [stdout] 10: 0x727732e38192 - std::panicking::default_hook::h4b8983249f85bd0b [INFO] [stdout] at /rustc/c066bf6804adc55193b71afdff6a56922f4a8689/library/std/src/panicking.rs:324:9 [INFO] [stdout] 11: 0x727732db0904 - as core::ops::function::Fn>::call::hede30da1739cef56 [INFO] [stdout] at /rustc/c066bf6804adc55193b71afdff6a56922f4a8689/library/alloc/src/boxed.rs:1980:9 [INFO] [stdout] 12: 0x727732db0904 - test::test_main_with_exit_callback::{{closure}}::h0965311d4539e3e1 [INFO] [stdout] at /rustc/c066bf6804adc55193b71afdff6a56922f4a8689/library/test/src/lib.rs:145:21 [INFO] [stdout] 13: 0x727732e38da3 - as core::ops::function::Fn>::call::hae484572816f635a [INFO] [stdout] at /rustc/c066bf6804adc55193b71afdff6a56922f4a8689/library/alloc/src/boxed.rs:1980:9 [INFO] [stdout] 14: 0x727732e38da3 - std::panicking::rust_panic_with_hook::hb38a28752bc10485 [INFO] [stdout] at /rustc/c066bf6804adc55193b71afdff6a56922f4a8689/library/std/src/panicking.rs:841:13 [INFO] [stdout] 15: 0x727732e38a56 - std::panicking::begin_panic_handler::{{closure}}::h006ddf6127456535 [INFO] [stdout] at /rustc/c066bf6804adc55193b71afdff6a56922f4a8689/library/std/src/panicking.rs:699:13 [INFO] [stdout] 16: 0x727732e37259 - std::sys::backtrace::__rust_end_short_backtrace::hf5f4a9954877c175 [INFO] [stdout] at /rustc/c066bf6804adc55193b71afdff6a56922f4a8689/library/std/src/sys/backtrace.rs:168:18 [INFO] [stdout] 17: 0x727732e3871d - __rustc[d925719a8a7e767]::rust_begin_unwind [INFO] [stdout] at /rustc/c066bf6804adc55193b71afdff6a56922f4a8689/library/std/src/panicking.rs:697:5 [INFO] [stdout] 18: 0x727732d39f50 - core::panicking::panic_fmt::h82f8bf7d1bff5441 [INFO] [stdout] at /rustc/c066bf6804adc55193b71afdff6a56922f4a8689/library/core/src/panicking.rs:75:14 [INFO] [stdout] 19: 0x727732d6db77 - tokio_serial_pacing::tests::check_read_pacing::{{closure}}::h6daa64460ce0e9ef [INFO] [stdout] at /opt/rustwide/workdir/src/lib.rs:152:9 [INFO] [stdout] 20: 0x727732d71c82 - as core::future::future::Future>::poll::h145cd144167cc9bc [INFO] [stdout] at /rustc/c066bf6804adc55193b71afdff6a56922f4a8689/library/core/src/future/future.rs:124:9 [INFO] [stdout] 21: 0x727732d71cbd - as core::future::future::Future>::poll::h26b38f2fc5d314e2 [INFO] [stdout] at /rustc/c066bf6804adc55193b71afdff6a56922f4a8689/library/core/src/future/future.rs:124:9 [INFO] [stdout] 22: 0x727732d6815f - tokio::runtime::scheduler::current_thread::CoreGuard::block_on::{{closure}}::{{closure}}::{{closure}}::h6080be3ff8c0304b [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.45.1/src/runtime/scheduler/current_thread/mod.rs:733:70 [INFO] [stdout] 23: 0x727732d67f05 - tokio::task::coop::with_budget::h869b11a26f1f1026 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.45.1/src/task/coop/mod.rs:167:5 [INFO] [stdout] 24: 0x727732d67f05 - tokio::task::coop::budget::h6cce9739295c5136 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.45.1/src/task/coop/mod.rs:133:5 [INFO] [stdout] 25: 0x727732d67f05 - tokio::runtime::scheduler::current_thread::CoreGuard::block_on::{{closure}}::{{closure}}::h7b610dd4f3ce44e8 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.45.1/src/runtime/scheduler/current_thread/mod.rs:733:25 [INFO] [stdout] 26: 0x727732d64ed0 - tokio::runtime::scheduler::current_thread::Context::enter::h2effe349c3a4d591 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.45.1/src/runtime/scheduler/current_thread/mod.rs:432:19 [INFO] [stdout] 27: 0x727732d66ac5 - tokio::runtime::scheduler::current_thread::CoreGuard::block_on::{{closure}}::h2fdddaa00b129e26 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.45.1/src/runtime/scheduler/current_thread/mod.rs:732:44 [INFO] [stdout] 28: 0x727732d667a4 - tokio::runtime::scheduler::current_thread::CoreGuard::enter::{{closure}}::h7a1cc9694f50076f [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.45.1/src/runtime/scheduler/current_thread/mod.rs:820:68 [INFO] [stdout] 29: 0x727732d5692b - tokio::runtime::context::scoped::Scoped::set::h9eef4d509a5ddd58 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.45.1/src/runtime/context/scoped.rs:40:9 [INFO] [stdout] 30: 0x727732d76289 - tokio::runtime::context::set_scheduler::{{closure}}::ha453de0cab9c4617 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.45.1/src/runtime/context.rs:176:38 [INFO] [stdout] 31: 0x727732d5a8b2 - std::thread::local::LocalKey::try_with::h6921da716dde453d [INFO] [stdout] at /rustc/c066bf6804adc55193b71afdff6a56922f4a8689/library/std/src/thread/local.rs:315:12 [INFO] [stdout] 32: 0x727732d5a06e - std::thread::local::LocalKey::with::ha89adf0ef6b94dc5 [INFO] [stdout] at /rustc/c066bf6804adc55193b71afdff6a56922f4a8689/library/std/src/thread/local.rs:279:20 [INFO] [stdout] 33: 0x727732d761bd - tokio::runtime::context::set_scheduler::h996b30339158a233 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.45.1/src/runtime/context.rs:176:17 [INFO] [stdout] 34: 0x727732d66530 - tokio::runtime::scheduler::current_thread::CoreGuard::enter::hfa7f6c75c48b5fb1 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.45.1/src/runtime/scheduler/current_thread/mod.rs:820:27 [INFO] [stdout] 35: 0x727732d66813 - tokio::runtime::scheduler::current_thread::CoreGuard::block_on::ha0718f07cc4ea380 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.45.1/src/runtime/scheduler/current_thread/mod.rs:720:24 [INFO] [stdout] 36: 0x727732d625ff - tokio::runtime::scheduler::current_thread::CurrentThread::block_on::{{closure}}::hc58e2bfcf5087bb2 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.45.1/src/runtime/scheduler/current_thread/mod.rs:200:33 [INFO] [stdout] 37: 0x727732d56bea - tokio::runtime::context::runtime::enter_runtime::h9ed4307ecab0dfeb [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.45.1/src/runtime/context/runtime.rs:65:16 [INFO] [stdout] 38: 0x727732d61ed1 - tokio::runtime::scheduler::current_thread::CurrentThread::block_on::hc4327323b6f3aba6 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.45.1/src/runtime/scheduler/current_thread/mod.rs:188:9 [INFO] [stdout] 39: 0x727732d77f0a - tokio::runtime::runtime::Runtime::block_on_inner::h6d94d9ea2d19f66a [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.45.1/src/runtime/runtime.rs:356:52 [INFO] [stdout] 40: 0x727732d780c7 - tokio::runtime::runtime::Runtime::block_on::h360cf0aa7427a034 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.45.1/src/runtime/runtime.rs:330:18 [INFO] [stdout] 41: 0x727732d6d5bc - tokio_serial_pacing::tests::check_read_pacing::he5166907fcb5a891 [INFO] [stdout] at /opt/rustwide/workdir/src/lib.rs:170:10 [INFO] [stdout] 42: 0x727732d6d467 - tokio_serial_pacing::tests::check_read_pacing::{{closure}}::hd1fba43f067e4d91 [INFO] [stdout] at /opt/rustwide/workdir/src/lib.rs:145:33 [INFO] [stdout] 43: 0x727732d4f976 - core::ops::function::FnOnce::call_once::hd76e3237322f27bb [INFO] [stdout] at /rustc/c066bf6804adc55193b71afdff6a56922f4a8689/library/core/src/ops/function.rs:250:5 [INFO] [stdout] 44: 0x727732db60ab - core::ops::function::FnOnce::call_once::h3d2c0f0c5993581d [INFO] [stdout] at /rustc/c066bf6804adc55193b71afdff6a56922f4a8689/library/core/src/ops/function.rs:250:5 [INFO] [stdout] 45: 0x727732db60ab - test::__rust_begin_short_backtrace::hf54991c0c0d36629 [INFO] [stdout] at /rustc/c066bf6804adc55193b71afdff6a56922f4a8689/library/test/src/lib.rs:648:18 [INFO] [stdout] 46: 0x727732db526e - test::run_test_in_process::{{closure}}::h0341f8cea17536bb [INFO] [stdout] at /rustc/c066bf6804adc55193b71afdff6a56922f4a8689/library/test/src/lib.rs:671:74 [INFO] [stdout] 47: 0x727732db526e - as core::ops::function::FnOnce<()>>::call_once::h1553eed696704d3d [INFO] [stdout] at /rustc/c066bf6804adc55193b71afdff6a56922f4a8689/library/core/src/panic/unwind_safe.rs:272:9 [INFO] [stdout] 48: 0x727732db526e - std::panicking::catch_unwind::do_call::h1f63de62998cca9c [INFO] [stdout] at /rustc/c066bf6804adc55193b71afdff6a56922f4a8689/library/std/src/panicking.rs:589:40 [INFO] [stdout] 49: 0x727732db526e - std::panicking::catch_unwind::h4302cf2989a72c19 [INFO] [stdout] at /rustc/c066bf6804adc55193b71afdff6a56922f4a8689/library/std/src/panicking.rs:552:19 [INFO] [stdout] 50: 0x727732db526e - std::panic::catch_unwind::h755c1d140d191b4d [INFO] [stdout] at /rustc/c066bf6804adc55193b71afdff6a56922f4a8689/library/std/src/panic.rs:359:14 [INFO] [stdout] 51: 0x727732db526e - test::run_test_in_process::h9532e6446792790f [INFO] [stdout] at /rustc/c066bf6804adc55193b71afdff6a56922f4a8689/library/test/src/lib.rs:671:27 [INFO] [stdout] 52: 0x727732db526e - test::run_test::{{closure}}::h4a686694e7a14850 [INFO] [stdout] at /rustc/c066bf6804adc55193b71afdff6a56922f4a8689/library/test/src/lib.rs:592:43 [INFO] [stdout] 53: 0x727732d7a0c4 - test::run_test::{{closure}}::hc71fa205a786e45a [INFO] [stdout] at /rustc/c066bf6804adc55193b71afdff6a56922f4a8689/library/test/src/lib.rs:622:41 [INFO] [stdout] 54: 0x727732d7a0c4 - std::sys::backtrace::__rust_begin_short_backtrace::h47b19d02f1433fd6 [INFO] [stdout] at /rustc/c066bf6804adc55193b71afdff6a56922f4a8689/library/std/src/sys/backtrace.rs:152:18 [INFO] [stdout] 55: 0x727732d7d80a - std::thread::Builder::spawn_unchecked_::{{closure}}::{{closure}}::h70d3ad48daaae9ca [INFO] [stdout] at /rustc/c066bf6804adc55193b71afdff6a56922f4a8689/library/std/src/thread/mod.rs:559:17 [INFO] [stdout] 56: 0x727732d7d80a - as core::ops::function::FnOnce<()>>::call_once::h05c136ea56e78f01 [INFO] [stdout] at /rustc/c066bf6804adc55193b71afdff6a56922f4a8689/library/core/src/panic/unwind_safe.rs:272:9 [INFO] [stdout] 57: 0x727732d7d80a - std::panicking::catch_unwind::do_call::h319e6bf1ae676552 [INFO] [stdout] at /rustc/c066bf6804adc55193b71afdff6a56922f4a8689/library/std/src/panicking.rs:589:40 [INFO] [stdout] 58: 0x727732d7d80a - std::panicking::catch_unwind::h87e2ae4fefda4094 [INFO] [stdout] at /rustc/c066bf6804adc55193b71afdff6a56922f4a8689/library/std/src/panicking.rs:552:19 [INFO] [stdout] 59: 0x727732d7d80a - std::panic::catch_unwind::hfb9059861e181e57 [INFO] [stdout] at /rustc/c066bf6804adc55193b71afdff6a56922f4a8689/library/std/src/panic.rs:359:14 [INFO] [stdout] 60: 0x727732d7d80a - std::thread::Builder::spawn_unchecked_::{{closure}}::h9acdf04a4f8f3746 [INFO] [stdout] at /rustc/c066bf6804adc55193b71afdff6a56922f4a8689/library/std/src/thread/mod.rs:557:30 [INFO] [stdout] 61: 0x727732d7d80a - core::ops::function::FnOnce::call_once{{vtable.shim}}::h04b8077dc2df262e [INFO] [stdout] at /rustc/c066bf6804adc55193b71afdff6a56922f4a8689/library/core/src/ops/function.rs:250:5 [INFO] [stdout] 62: 0x727732e3b847 - as core::ops::function::FnOnce>::call_once::hfdaf4814ac3c8671 [INFO] [stdout] at /rustc/c066bf6804adc55193b71afdff6a56922f4a8689/library/alloc/src/boxed.rs:1966:9 [INFO] [stdout] 63: 0x727732e3b847 - as core::ops::function::FnOnce>::call_once::h1e491c0829796b5d [INFO] [stdout] at /rustc/c066bf6804adc55193b71afdff6a56922f4a8689/library/alloc/src/boxed.rs:1966:9 [INFO] [stdout] 64: 0x727732e3b847 - std::sys::pal::unix::thread::Thread::new::thread_start::h72c96c274d9d1b61 [INFO] [stdout] at /rustc/c066bf6804adc55193b71afdff6a56922f4a8689/library/std/src/sys/pal/unix/thread.rs:97:17 [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] failures: [INFO] [stdout] tests::check_read_pacing [INFO] [stdout] [INFO] [stdout] test result: FAILED. 3 passed; 1 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.05s [INFO] [stdout] [INFO] [stderr] error: test failed, to rerun pass `--lib` [INFO] running `Command { std: "docker" "inspect" "3720671d03257ff3fe5c3f219777b6e55caf8a2b7bf99f4d149bafdce518a758", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "3720671d03257ff3fe5c3f219777b6e55caf8a2b7bf99f4d149bafdce518a758", kill_on_drop: false }` [INFO] [stdout] 3720671d03257ff3fe5c3f219777b6e55caf8a2b7bf99f4d149bafdce518a758