[INFO] cloning repository https://github.com/Spindel/tokio-serial-pacing [INFO] running `Command { std: "git" "-c" "credential.helper=" "-c" "credential.helper=/workspace/cargo-home/bin/git-credential-null" "clone" "--bare" "https://github.com/Spindel/tokio-serial-pacing" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2FSpindel%2Ftokio-serial-pacing", kill_on_drop: false }` [INFO] [stderr] Cloning into bare repository '/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2FSpindel%2Ftokio-serial-pacing'... [INFO] running `Command { std: "git" "rev-parse" "HEAD", kill_on_drop: false }` [INFO] [stdout] 00534273096501df7e6c89b3e5b42421222d2c20 [INFO] testing Spindel/tokio-serial-pacing against 1.84.0 for beta-1.85-1 [INFO] running `Command { std: "git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2FSpindel%2Ftokio-serial-pacing" "/workspace/builds/worker-0-tc1/source", kill_on_drop: false }` [INFO] [stderr] Cloning into '/workspace/builds/worker-0-tc1/source'... [INFO] [stderr] done. [INFO] validating manifest of git repo https://github.com/Spindel/tokio-serial-pacing on toolchain 1.84.0 [INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+1.84.0" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }` [INFO] started tweaking git repo https://github.com/Spindel/tokio-serial-pacing [INFO] finished tweaking git repo https://github.com/Spindel/tokio-serial-pacing [INFO] tweaked toml for git repo https://github.com/Spindel/tokio-serial-pacing written to /workspace/builds/worker-0-tc1/source/Cargo.toml [INFO] crate git repo https://github.com/Spindel/tokio-serial-pacing already has a lockfile, it will not be regenerated [INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+1.84.0" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }` [INFO] [stderr] Updating crates.io index [INFO] [stderr] Downloading crates ... [INFO] [stderr] Downloaded mio-serial v5.0.5 [INFO] [stderr] Downloaded tokio-serial v5.4.4 [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:86ea7c7af713d31e8cfdb68a6d0db50b5cf7cbeecde3d112f9f257f747318d36" "/opt/rustwide/cargo-home/bin/cargo" "+1.84.0" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }` [INFO] [stdout] 613d101b5deeeb4e4c2a4a4114ce02a2be1016f4abd776bd7940c9e36fa2889c [INFO] running `Command { std: "docker" "start" "-a" "613d101b5deeeb4e4c2a4a4114ce02a2be1016f4abd776bd7940c9e36fa2889c", kill_on_drop: false }` [INFO] running `Command { std: "docker" "inspect" "613d101b5deeeb4e4c2a4a4114ce02a2be1016f4abd776bd7940c9e36fa2889c", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "613d101b5deeeb4e4c2a4a4114ce02a2be1016f4abd776bd7940c9e36fa2889c", kill_on_drop: false }` [INFO] [stdout] 613d101b5deeeb4e4c2a4a4114ce02a2be1016f4abd776bd7940c9e36fa2889c [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=warn" "-e" "RUSTDOCFLAGS=--cap-lints=warn" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:86ea7c7af713d31e8cfdb68a6d0db50b5cf7cbeecde3d112f9f257f747318d36" "/opt/rustwide/cargo-home/bin/cargo" "+1.84.0" "build" "--frozen" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] 8ae19f8cbbb7e9bb1ad648fd146702822aa461640a9f293518f0a22cedab4f3e [INFO] running `Command { std: "docker" "start" "-a" "8ae19f8cbbb7e9bb1ad648fd146702822aa461640a9f293518f0a22cedab4f3e", kill_on_drop: false }` [INFO] [stderr] Compiling proc-macro2 v1.0.79 [INFO] [stderr] Compiling autocfg v1.2.0 [INFO] [stderr] Compiling unicode-ident v1.0.12 [INFO] [stderr] Compiling libc v0.2.153 [INFO] [stderr] Compiling pin-utils v0.1.0 [INFO] [stderr] Compiling thiserror v1.0.58 [INFO] [stderr] Compiling futures-sink v0.3.30 [INFO] [stderr] Compiling pin-project-lite v0.2.13 [INFO] [stderr] Compiling futures-core v0.3.30 [INFO] [stderr] Compiling futures-io v0.3.30 [INFO] [stderr] Compiling futures-task v0.3.30 [INFO] [stderr] Compiling memchr v2.7.1 [INFO] [stderr] Compiling log v0.4.21 [INFO] [stderr] Compiling scopeguard v1.2.0 [INFO] [stderr] Compiling bitflags v2.5.0 [INFO] [stderr] Compiling futures-channel v0.3.30 [INFO] [stderr] Compiling memoffset v0.7.1 [INFO] [stderr] Compiling slab v0.4.9 [INFO] [stderr] Compiling quote v1.0.35 [INFO] [stderr] Compiling syn v2.0.55 [INFO] [stderr] Compiling nix v0.26.4 [INFO] [stderr] Compiling mio v0.8.11 [INFO] [stderr] Compiling socket2 v0.5.6 [INFO] [stderr] Compiling tokio v1.36.0 [INFO] [stderr] Compiling thiserror-impl v1.0.58 [INFO] [stderr] Compiling futures-macro v0.3.30 [INFO] [stderr] Compiling futures-util v0.3.30 [INFO] [stderr] Compiling unescaper v0.1.4 [INFO] [stderr] Compiling serialport v4.3.0 [INFO] [stderr] Compiling mio-serial v5.0.5 [INFO] [stderr] Compiling futures-executor v0.3.30 [INFO] [stderr] Compiling futures v0.3.30 [INFO] [stderr] Compiling tokio-serial v5.4.4 [INFO] [stderr] Compiling tokio_serial_pacing v0.1.1 (/opt/rustwide/workdir) [INFO] [stderr] Finished `dev` profile [unoptimized + debuginfo] target(s) in 11.15s [INFO] running `Command { std: "docker" "inspect" "8ae19f8cbbb7e9bb1ad648fd146702822aa461640a9f293518f0a22cedab4f3e", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "8ae19f8cbbb7e9bb1ad648fd146702822aa461640a9f293518f0a22cedab4f3e", kill_on_drop: false }` [INFO] [stdout] 8ae19f8cbbb7e9bb1ad648fd146702822aa461640a9f293518f0a22cedab4f3e [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=warn" "-e" "RUSTDOCFLAGS=--cap-lints=warn" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:86ea7c7af713d31e8cfdb68a6d0db50b5cf7cbeecde3d112f9f257f747318d36" "/opt/rustwide/cargo-home/bin/cargo" "+1.84.0" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] 93476d87655e1123dc38cf1faf731b8d813f6e443b14f50bfffc224e69cd3b01 [INFO] running `Command { std: "docker" "start" "-a" "93476d87655e1123dc38cf1faf731b8d813f6e443b14f50bfffc224e69cd3b01", kill_on_drop: false }` [INFO] [stderr] Compiling tokio-macros v2.2.0 [INFO] [stderr] Compiling bytes v1.6.0 [INFO] [stderr] Compiling tokio v1.36.0 [INFO] [stderr] Compiling tokio-serial v5.4.4 [INFO] [stderr] Compiling tokio_serial_pacing v0.1.1 (/opt/rustwide/workdir) [INFO] [stderr] Finished `test` profile [unoptimized + debuginfo] target(s) in 7.08s [INFO] running `Command { std: "docker" "inspect" "93476d87655e1123dc38cf1faf731b8d813f6e443b14f50bfffc224e69cd3b01", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "93476d87655e1123dc38cf1faf731b8d813f6e443b14f50bfffc224e69cd3b01", kill_on_drop: false }` [INFO] [stdout] 93476d87655e1123dc38cf1faf731b8d813f6e443b14f50bfffc224e69cd3b01 [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=warn" "-e" "RUSTDOCFLAGS=--cap-lints=warn" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:86ea7c7af713d31e8cfdb68a6d0db50b5cf7cbeecde3d112f9f257f747318d36" "/opt/rustwide/cargo-home/bin/cargo" "+1.84.0" "test" "--frozen", kill_on_drop: false }` [INFO] [stdout] 922e33d44a2dc28ed43d7b38e6e8f7f9126b133e2827eb4cb59cdc249db9b2bf [INFO] running `Command { std: "docker" "start" "-a" "922e33d44a2dc28ed43d7b38e6e8f7f9126b133e2827eb4cb59cdc249db9b2bf", kill_on_drop: false }` [INFO] [stderr] Finished `test` profile [unoptimized + debuginfo] target(s) in 0.05s [INFO] [stderr] Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/tokio_serial_pacing-cd2b04d0370d377c) [INFO] [stdout] [INFO] [stdout] running 4 tests [INFO] [stdout] test tests::check_wait_time ... ok [INFO] [stdout] test tests::ensure_sync_port_works ... ok [INFO] [stdout] test tests::check_read_pacing ... ok [INFO] [stdout] test tests::check_write_pacing ... FAILED [INFO] [stdout] [INFO] [stdout] failures: [INFO] [stdout] [INFO] [stdout] ---- tests::check_write_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] thread 'tests::check_write_pacing' panicked at src/lib.rs:122:9: [INFO] [stdout] It should not take a millisecond normally. [INFO] [stdout] stack backtrace: [INFO] [stdout] 0: 0x6232da8bfd6a - std::backtrace_rs::backtrace::libunwind::trace::hfe0e1def394da018 [INFO] [stdout] at /rustc/9fc6b43126469e3858e2fe86cafb4f0fd5068869/library/std/src/../../backtrace/src/backtrace/libunwind.rs:116:5 [INFO] [stdout] 1: 0x6232da8bfd6a - std::backtrace_rs::backtrace::trace_unsynchronized::h96fbb6d117554616 [INFO] [stdout] at /rustc/9fc6b43126469e3858e2fe86cafb4f0fd5068869/library/std/src/../../backtrace/src/backtrace/mod.rs:66:5 [INFO] [stdout] 2: 0x6232da8bfd6a - std::sys::backtrace::_print_fmt::he1169c6f8956325b [INFO] [stdout] at /rustc/9fc6b43126469e3858e2fe86cafb4f0fd5068869/library/std/src/sys/backtrace.rs:66:9 [INFO] [stdout] 3: 0x6232da8bfd6a - ::fmt::ha4a311b32f6b4ad8 [INFO] [stdout] at /rustc/9fc6b43126469e3858e2fe86cafb4f0fd5068869/library/std/src/sys/backtrace.rs:39:26 [INFO] [stdout] 4: 0x6232da8e60f3 - core::fmt::rt::Argument::fmt::h8ccd2627d2418ed3 [INFO] [stdout] at /rustc/9fc6b43126469e3858e2fe86cafb4f0fd5068869/library/core/src/fmt/rt.rs:177:76 [INFO] [stdout] 5: 0x6232da8e60f3 - core::fmt::write::h1866771663f62b81 [INFO] [stdout] at /rustc/9fc6b43126469e3858e2fe86cafb4f0fd5068869/library/core/src/fmt/mod.rs:1189:21 [INFO] [stdout] 6: 0x6232da8bcdd3 - std::io::Write::write_fmt::h2df399c9fb8c6039 [INFO] [stdout] at /rustc/9fc6b43126469e3858e2fe86cafb4f0fd5068869/library/std/src/io/mod.rs:1884:15 [INFO] [stdout] 7: 0x6232da8bfbb2 - std::sys::backtrace::BacktraceLock::print::hddd3a9918ce29aa7 [INFO] [stdout] at /rustc/9fc6b43126469e3858e2fe86cafb4f0fd5068869/library/std/src/sys/backtrace.rs:42:9 [INFO] [stdout] 8: 0x6232da8c11ed - std::panicking::default_hook::{{closure}}::h791f75256b902d7d [INFO] [stdout] at /rustc/9fc6b43126469e3858e2fe86cafb4f0fd5068869/library/std/src/panicking.rs:268:22 [INFO] [stdout] 9: 0x6232da8c0ff7 - std::panicking::default_hook::h82cc572fcb0d8cd7 [INFO] [stdout] at /rustc/9fc6b43126469e3858e2fe86cafb4f0fd5068869/library/std/src/panicking.rs:292:9 [INFO] [stdout] 10: 0x6232da82d3e7 - as core::ops::function::Fn>::call::h47f4385e5c05a6bc [INFO] [stdout] at /rustc/9fc6b43126469e3858e2fe86cafb4f0fd5068869/library/alloc/src/boxed.rs:1986:9 [INFO] [stdout] 11: 0x6232da82d3e7 - test::test_main::{{closure}}::h592b6187ee9bbe51 [INFO] [stdout] at /rustc/9fc6b43126469e3858e2fe86cafb4f0fd5068869/library/test/src/lib.rs:134:21 [INFO] [stdout] 12: 0x6232da8c1898 - as core::ops::function::Fn>::call::h80408f032954f187 [INFO] [stdout] at /rustc/9fc6b43126469e3858e2fe86cafb4f0fd5068869/library/alloc/src/boxed.rs:1986:9 [INFO] [stdout] 13: 0x6232da8c1898 - std::panicking::rust_panic_with_hook::he21644cc2707f2c4 [INFO] [stdout] at /rustc/9fc6b43126469e3858e2fe86cafb4f0fd5068869/library/std/src/panicking.rs:809:13 [INFO] [stdout] 14: 0x6232da8c1626 - std::panicking::begin_panic_handler::{{closure}}::h42f7c414fed3cad9 [INFO] [stdout] at /rustc/9fc6b43126469e3858e2fe86cafb4f0fd5068869/library/std/src/panicking.rs:667:13 [INFO] [stdout] 15: 0x6232da8c0249 - std::sys::backtrace::__rust_end_short_backtrace::ha26cf5766b4e8c65 [INFO] [stdout] at /rustc/9fc6b43126469e3858e2fe86cafb4f0fd5068869/library/std/src/sys/backtrace.rs:170:18 [INFO] [stdout] 16: 0x6232da8c12ec - rust_begin_unwind [INFO] [stdout] at /rustc/9fc6b43126469e3858e2fe86cafb4f0fd5068869/library/std/src/panicking.rs:665:5 [INFO] [stdout] 17: 0x6232da7bdb40 - core::panicking::panic_fmt::h74866b78e934b1c0 [INFO] [stdout] at /rustc/9fc6b43126469e3858e2fe86cafb4f0fd5068869/library/core/src/panicking.rs:76:14 [INFO] [stdout] 18: 0x6232da7f1dbb - tokio_serial_pacing::tests::check_write_pacing::{{closure}}::h8c39e2684fee05eb [INFO] [stdout] at /opt/rustwide/workdir/src/lib.rs:122:9 [INFO] [stdout] 19: 0x6232da7d75e2 - as core::future::future::Future>::poll::h10c38467d0920208 [INFO] [stdout] at /rustc/9fc6b43126469e3858e2fe86cafb4f0fd5068869/library/core/src/future/future.rs:124:9 [INFO] [stdout] 20: 0x6232da7d77fd - as core::future::future::Future>::poll::hf712298a8ee651fe [INFO] [stdout] at /rustc/9fc6b43126469e3858e2fe86cafb4f0fd5068869/library/core/src/future/future.rs:124:9 [INFO] [stdout] 21: 0x6232da7ec44f - tokio::runtime::scheduler::current_thread::CoreGuard::block_on::{{closure}}::{{closure}}::{{closure}}::h7dbb191c35bdebae [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-6f17d22bba15001f/tokio-1.36.0/src/runtime/scheduler/current_thread/mod.rs:659:57 [INFO] [stdout] 22: 0x6232da7ec385 - tokio::runtime::coop::with_budget::h3af30eafb58c0fc2 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-6f17d22bba15001f/tokio-1.36.0/src/runtime/coop.rs:107:5 [INFO] [stdout] 23: 0x6232da7ec385 - tokio::runtime::coop::budget::h50435cf2e541c2f3 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-6f17d22bba15001f/tokio-1.36.0/src/runtime/coop.rs:73:5 [INFO] [stdout] 24: 0x6232da7ec385 - tokio::runtime::scheduler::current_thread::CoreGuard::block_on::{{closure}}::{{closure}}::h5a03110b89103513 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-6f17d22bba15001f/tokio-1.36.0/src/runtime/scheduler/current_thread/mod.rs:659:25 [INFO] [stdout] 25: 0x6232da7eae70 - tokio::runtime::scheduler::current_thread::Context::enter::h83dff87edead2103 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-6f17d22bba15001f/tokio-1.36.0/src/runtime/scheduler/current_thread/mod.rs:404:19 [INFO] [stdout] 26: 0x6232da7ebc0b - tokio::runtime::scheduler::current_thread::CoreGuard::block_on::{{closure}}::h0d7786573609f2b5 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-6f17d22bba15001f/tokio-1.36.0/src/runtime/scheduler/current_thread/mod.rs:658:36 [INFO] [stdout] 27: 0x6232da7eb984 - tokio::runtime::scheduler::current_thread::CoreGuard::enter::{{closure}}::hc30156a861c65ea3 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-6f17d22bba15001f/tokio-1.36.0/src/runtime/scheduler/current_thread/mod.rs:737:68 [INFO] [stdout] 28: 0x6232da7d52cb - tokio::runtime::context::scoped::Scoped::set::h0f55b13fb0071546 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-6f17d22bba15001f/tokio-1.36.0/src/runtime/context/scoped.rs:40:9 [INFO] [stdout] 29: 0x6232da7d5149 - tokio::runtime::context::set_scheduler::{{closure}}::h67a63e61fd7c00c9 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-6f17d22bba15001f/tokio-1.36.0/src/runtime/context.rs:176:26 [INFO] [stdout] 30: 0x6232da7bffbd - std::thread::local::LocalKey::try_with::ha288f932a3d606c3 [INFO] [stdout] at /rustc/9fc6b43126469e3858e2fe86cafb4f0fd5068869/library/std/src/thread/local.rs:283:12 [INFO] [stdout] 31: 0x6232da7beeca - std::thread::local::LocalKey::with::h2c83ca3cfec9b7f7 [INFO] [stdout] at /rustc/9fc6b43126469e3858e2fe86cafb4f0fd5068869/library/std/src/thread/local.rs:260:9 [INFO] [stdout] 32: 0x6232da7d50fd - tokio::runtime::context::set_scheduler::h7f4f9951e49bcf67 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-6f17d22bba15001f/tokio-1.36.0/src/runtime/context.rs:176:9 [INFO] [stdout] 33: 0x6232da7eb730 - tokio::runtime::scheduler::current_thread::CoreGuard::enter::hb151708e1574ec53 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-6f17d22bba15001f/tokio-1.36.0/src/runtime/scheduler/current_thread/mod.rs:737:27 [INFO] [stdout] 34: 0x6232da7eb9a3 - tokio::runtime::scheduler::current_thread::CoreGuard::block_on::hb63896d32e26f7e5 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-6f17d22bba15001f/tokio-1.36.0/src/runtime/scheduler/current_thread/mod.rs:646:19 [INFO] [stdout] 35: 0x6232da7e8abd - tokio::runtime::scheduler::current_thread::CurrentThread::block_on::{{closure}}::ha680fcddf91eea4c [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-6f17d22bba15001f/tokio-1.36.0/src/runtime/scheduler/current_thread/mod.rs:175:28 [INFO] [stdout] 36: 0x6232da7d6c37 - tokio::runtime::context::runtime::enter_runtime::h8d7f85314dae3f90 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-6f17d22bba15001f/tokio-1.36.0/src/runtime/context/runtime.rs:65:16 [INFO] [stdout] 37: 0x6232da7e89e1 - tokio::runtime::scheduler::current_thread::CurrentThread::block_on::hc53142f3e2ee550f [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-6f17d22bba15001f/tokio-1.36.0/src/runtime/scheduler/current_thread/mod.rs:167:9 [INFO] [stdout] 38: 0x6232da7d6fc2 - tokio::runtime::runtime::Runtime::block_on::h1ed314a087ccf834 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-6f17d22bba15001f/tokio-1.36.0/src/runtime/runtime.rs:348:47 [INFO] [stdout] 39: 0x6232da7f17ec - tokio_serial_pacing::tests::check_write_pacing::h7d219eb72871dec9 [INFO] [stdout] at /opt/rustwide/workdir/src/lib.rs:138:9 [INFO] [stdout] 40: 0x6232da7f1697 - tokio_serial_pacing::tests::check_write_pacing::{{closure}}::hccf119bcbba8ef1a [INFO] [stdout] at /opt/rustwide/workdir/src/lib.rs:115:34 [INFO] [stdout] 41: 0x6232da7daea6 - core::ops::function::FnOnce::call_once::h53fef4d658bd2cd5 [INFO] [stdout] at /rustc/9fc6b43126469e3858e2fe86cafb4f0fd5068869/library/core/src/ops/function.rs:250:5 [INFO] [stdout] 42: 0x6232da831f9b - core::ops::function::FnOnce::call_once::h2f54be8ccda9ff77 [INFO] [stdout] at /rustc/9fc6b43126469e3858e2fe86cafb4f0fd5068869/library/core/src/ops/function.rs:250:5 [INFO] [stdout] 43: 0x6232da831f9b - test::__rust_begin_short_backtrace::h505f4667df5bf10c [INFO] [stdout] at /rustc/9fc6b43126469e3858e2fe86cafb4f0fd5068869/library/test/src/lib.rs:632:18 [INFO] [stdout] 44: 0x6232da8318f8 - test::run_test_in_process::{{closure}}::h5ed3028e6069c117 [INFO] [stdout] at /rustc/9fc6b43126469e3858e2fe86cafb4f0fd5068869/library/test/src/lib.rs:655:60 [INFO] [stdout] 45: 0x6232da8318f8 - as core::ops::function::FnOnce<()>>::call_once::hbf705be6aea7c287 [INFO] [stdout] at /rustc/9fc6b43126469e3858e2fe86cafb4f0fd5068869/library/core/src/panic/unwind_safe.rs:272:9 [INFO] [stdout] 46: 0x6232da8318f8 - std::panicking::try::do_call::h555223a41631f02b [INFO] [stdout] at /rustc/9fc6b43126469e3858e2fe86cafb4f0fd5068869/library/std/src/panicking.rs:557:40 [INFO] [stdout] 47: 0x6232da8318f8 - std::panicking::try::he81e496d432108c4 [INFO] [stdout] at /rustc/9fc6b43126469e3858e2fe86cafb4f0fd5068869/library/std/src/panicking.rs:520:19 [INFO] [stdout] 48: 0x6232da8318f8 - std::panic::catch_unwind::hb2ff356e76867b52 [INFO] [stdout] at /rustc/9fc6b43126469e3858e2fe86cafb4f0fd5068869/library/std/src/panic.rs:358:14 [INFO] [stdout] 49: 0x6232da8318f8 - test::run_test_in_process::h665bcd2e01d69f5b [INFO] [stdout] at /rustc/9fc6b43126469e3858e2fe86cafb4f0fd5068869/library/test/src/lib.rs:655:27 [INFO] [stdout] 50: 0x6232da8318f8 - test::run_test::{{closure}}::ha58fbc9c79c6786d [INFO] [stdout] at /rustc/9fc6b43126469e3858e2fe86cafb4f0fd5068869/library/test/src/lib.rs:576:43 [INFO] [stdout] 51: 0x6232da7f539a - test::run_test::{{closure}}::h958602d5fcb1d647 [INFO] [stdout] at /rustc/9fc6b43126469e3858e2fe86cafb4f0fd5068869/library/test/src/lib.rs:606:41 [INFO] [stdout] 52: 0x6232da7f539a - std::sys::backtrace::__rust_begin_short_backtrace::h7ca2fb16f64faa0f [INFO] [stdout] at /rustc/9fc6b43126469e3858e2fe86cafb4f0fd5068869/library/std/src/sys/backtrace.rs:154:18 [INFO] [stdout] 53: 0x6232da7f8c54 - std::thread::Builder::spawn_unchecked_::{{closure}}::{{closure}}::hebe77fbe87ddae61 [INFO] [stdout] at /rustc/9fc6b43126469e3858e2fe86cafb4f0fd5068869/library/std/src/thread/mod.rs:561:17 [INFO] [stdout] 54: 0x6232da7f8c54 - as core::ops::function::FnOnce<()>>::call_once::h5e26d6a69f85d1d6 [INFO] [stdout] at /rustc/9fc6b43126469e3858e2fe86cafb4f0fd5068869/library/core/src/panic/unwind_safe.rs:272:9 [INFO] [stdout] 55: 0x6232da7f8c54 - std::panicking::try::do_call::h9787fac579f00884 [INFO] [stdout] at /rustc/9fc6b43126469e3858e2fe86cafb4f0fd5068869/library/std/src/panicking.rs:557:40 [INFO] [stdout] 56: 0x6232da7f8c54 - std::panicking::try::h788012b555fbd7c5 [INFO] [stdout] at /rustc/9fc6b43126469e3858e2fe86cafb4f0fd5068869/library/std/src/panicking.rs:520:19 [INFO] [stdout] 57: 0x6232da7f8c54 - std::panic::catch_unwind::hd194f26f99a98fb5 [INFO] [stdout] at /rustc/9fc6b43126469e3858e2fe86cafb4f0fd5068869/library/std/src/panic.rs:358:14 [INFO] [stdout] 58: 0x6232da7f8c54 - std::thread::Builder::spawn_unchecked_::{{closure}}::h31c0dcba34ed449c [INFO] [stdout] at /rustc/9fc6b43126469e3858e2fe86cafb4f0fd5068869/library/std/src/thread/mod.rs:559:30 [INFO] [stdout] 59: 0x6232da7f8c54 - core::ops::function::FnOnce::call_once{{vtable.shim}}::h60302d9c688101a6 [INFO] [stdout] at /rustc/9fc6b43126469e3858e2fe86cafb4f0fd5068869/library/core/src/ops/function.rs:250:5 [INFO] [stdout] 60: 0x6232da8c593b - as core::ops::function::FnOnce>::call_once::h9578f6ea1d4e1c4b [INFO] [stdout] at /rustc/9fc6b43126469e3858e2fe86cafb4f0fd5068869/library/alloc/src/boxed.rs:1972:9 [INFO] [stdout] 61: 0x6232da8c593b - as core::ops::function::FnOnce>::call_once::hf4a2f438d8019348 [INFO] [stdout] at /rustc/9fc6b43126469e3858e2fe86cafb4f0fd5068869/library/alloc/src/boxed.rs:1972:9 [INFO] [stdout] 62: 0x6232da8c593b - std::sys::pal::unix::thread::Thread::new::thread_start::h14f1eb868ff90fc9 [INFO] [stdout] at /rustc/9fc6b43126469e3858e2fe86cafb4f0fd5068869/library/std/src/sys/pal/unix/thread.rs:105:17 [INFO] [stdout] 63: 0x7c373e6dfac3 - [INFO] [stdout] 64: 0x7c373e770a04 - clone [INFO] [stdout] 65: 0x0 - [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] failures: [INFO] [stdout] tests::check_write_pacing [INFO] [stdout] [INFO] [stdout] test result: FAILED. 3 passed; 1 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.03s [INFO] [stdout] [INFO] [stderr] error: test failed, to rerun pass `--lib` [INFO] running `Command { std: "docker" "inspect" "922e33d44a2dc28ed43d7b38e6e8f7f9126b133e2827eb4cb59cdc249db9b2bf", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "922e33d44a2dc28ed43d7b38e6e8f7f9126b133e2827eb4cb59cdc249db9b2bf", kill_on_drop: false }` [INFO] [stdout] 922e33d44a2dc28ed43d7b38e6e8f7f9126b133e2827eb4cb59cdc249db9b2bf