[INFO] fetching crate stream_limiter 3.2.0... [INFO] testing stream_limiter-3.2.0 against try#b6ae10aa7319b7ebb6c7b3331dd71a9d6c9c5b4b for pr-145330-1 [INFO] extracting crate stream_limiter 3.2.0 into /workspace/builds/worker-5-tc2/source [INFO] started tweaking crates.io crate stream_limiter 3.2.0 [INFO] finished tweaking crates.io crate stream_limiter 3.2.0 [INFO] tweaked toml for crates.io crate stream_limiter 3.2.0 written to /workspace/builds/worker-5-tc2/source/Cargo.toml [INFO] validating manifest of crates.io crate stream_limiter 3.2.0 on toolchain b6ae10aa7319b7ebb6c7b3331dd71a9d6c9c5b4b [INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+b6ae10aa7319b7ebb6c7b3331dd71a9d6c9c5b4b" "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" "+b6ae10aa7319b7ebb6c7b3331dd71a9d6c9c5b4b" "generate-lockfile" "--manifest-path" "Cargo.toml", kill_on_drop: false }` [INFO] [stderr] Updating crates.io index [INFO] [stderr] Locking 23 packages to latest compatible versions [INFO] [stderr] Adding hex-literal v0.4.1 (available: v1.0.0) [INFO] [stderr] Adding rand v0.8.5 (available: v0.9.2) [INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+b6ae10aa7319b7ebb6c7b3331dd71a9d6c9c5b4b" "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:e90291280db7d1fac5b66fc6dad9f9662629e7365a55743daf9bdf73ebc4ea79" "/opt/rustwide/cargo-home/bin/cargo" "+b6ae10aa7319b7ebb6c7b3331dd71a9d6c9c5b4b" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }` [INFO] [stdout] b34d1f7fe7e0eba72c5815e5ac4fed418949bf95fa98ac9737e24d7f5e881e08 [INFO] running `Command { std: "docker" "start" "-a" "b34d1f7fe7e0eba72c5815e5ac4fed418949bf95fa98ac9737e24d7f5e881e08", kill_on_drop: false }` [INFO] running `Command { std: "docker" "inspect" "b34d1f7fe7e0eba72c5815e5ac4fed418949bf95fa98ac9737e24d7f5e881e08", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "b34d1f7fe7e0eba72c5815e5ac4fed418949bf95fa98ac9737e24d7f5e881e08", kill_on_drop: false }` [INFO] [stdout] b34d1f7fe7e0eba72c5815e5ac4fed418949bf95fa98ac9737e24d7f5e881e08 [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:e90291280db7d1fac5b66fc6dad9f9662629e7365a55743daf9bdf73ebc4ea79" "/opt/rustwide/cargo-home/bin/cargo" "+b6ae10aa7319b7ebb6c7b3331dd71a9d6c9c5b4b" "build" "--frozen" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] c6df22565b9635afa91e7f722c9778a10e9f9ab2ec869b34df8ca57322d9c96a [INFO] running `Command { std: "docker" "start" "-a" "c6df22565b9635afa91e7f722c9778a10e9f9ab2ec869b34df8ca57322d9c96a", kill_on_drop: false }` [INFO] [stderr] Compiling stream_limiter v3.2.0 (/opt/rustwide/workdir) [INFO] [stderr] Finished `dev` profile [unoptimized + debuginfo] target(s) in 0.19s [INFO] running `Command { std: "docker" "inspect" "c6df22565b9635afa91e7f722c9778a10e9f9ab2ec869b34df8ca57322d9c96a", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "c6df22565b9635afa91e7f722c9778a10e9f9ab2ec869b34df8ca57322d9c96a", kill_on_drop: false }` [INFO] [stdout] c6df22565b9635afa91e7f722c9778a10e9f9ab2ec869b34df8ca57322d9c96a [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:e90291280db7d1fac5b66fc6dad9f9662629e7365a55743daf9bdf73ebc4ea79" "/opt/rustwide/cargo-home/bin/cargo" "+b6ae10aa7319b7ebb6c7b3331dd71a9d6c9c5b4b" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] 111ad7a989e2db2cf1f96a2e25e403fbccf3df248a26c0ccccd74bb75952594d [INFO] running `Command { std: "docker" "start" "-a" "111ad7a989e2db2cf1f96a2e25e403fbccf3df248a26c0ccccd74bb75952594d", kill_on_drop: false }` [INFO] [stderr] Compiling rand_chacha v0.3.1 [INFO] [stderr] Compiling rand v0.8.5 [INFO] [stderr] Compiling stream_limiter v3.2.0 (/opt/rustwide/workdir) [INFO] [stderr] Finished `test` profile [unoptimized + debuginfo] target(s) in 2.50s [INFO] running `Command { std: "docker" "inspect" "111ad7a989e2db2cf1f96a2e25e403fbccf3df248a26c0ccccd74bb75952594d", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "111ad7a989e2db2cf1f96a2e25e403fbccf3df248a26c0ccccd74bb75952594d", kill_on_drop: false }` [INFO] [stdout] 111ad7a989e2db2cf1f96a2e25e403fbccf3df248a26c0ccccd74bb75952594d [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:e90291280db7d1fac5b66fc6dad9f9662629e7365a55743daf9bdf73ebc4ea79" "/opt/rustwide/cargo-home/bin/cargo" "+b6ae10aa7319b7ebb6c7b3331dd71a9d6c9c5b4b" "test" "--frozen", kill_on_drop: false }` [INFO] [stdout] 7d45a82ad7d4e49085255db2d833c07af3ef225218e7cd6b68d8c9e2141aa35e [INFO] running `Command { std: "docker" "start" "-a" "7d45a82ad7d4e49085255db2d833c07af3ef225218e7cd6b68d8c9e2141aa35e", kill_on_drop: false }` [INFO] [stderr] Finished `test` profile [unoptimized + debuginfo] target(s) in 0.03s [INFO] [stderr] Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/stream_limiter-ead6139743471db0) [INFO] [stdout] [INFO] [stdout] running 27 tests [INFO] [stdout] test tests::network::test_no_limit ... ok [INFO] [stdout] test tests::read::read_instant ... ok [INFO] [stdout] test tests::read::test_max_limit ... ok [INFO] [stdout] test tests::parametric::test_buffer ... FAILED [INFO] [stdout] test tests::read::read_timeout ... ok [INFO] [stdout] test tests::read::splitted_read ... ok [INFO] [stdout] test tests::parametric::test_tcp ... FAILED [INFO] [stdout] test tests::write::test_max_limit ... ok [INFO] [stdout] test tests::network::test_limit_read ... ok [INFO] [stdout] test tests::network::test_limit_both ... ok [INFO] [stdout] test tests::write::write_instant ... ok [INFO] [stdout] test tests::network::test_limit_write ... ok [INFO] [stdout] test tests::write::splitted_write ... ok [INFO] [stdout] test tests::read::one_byte_each_two_hundreds_fifty_millis ... ok [INFO] [stdout] test tests::write::one_byte_each_two_hundreds_fifty_millis ... ok [INFO] [stdout] test tests::write::write_timeout ... FAILED [INFO] [stdout] test tests::read::test_bucket_full ... ok [INFO] [stdout] test tests::read::read_the_whole_file ... ok [INFO] [stdout] test tests::read::two_byte_each_second ... ok [INFO] [stdout] test tests::write::write_bucket_full ... ok [INFO] [stdout] test tests::write::two_byte_each_second ... ok [INFO] [stdout] test tests::read::test_burst ... ok [INFO] [stdout] test tests::read::one_byte_each_second ... ok [INFO] [stdout] test tests::write::one_byte_each_second ... ok [INFO] [stdout] test tests::read::oneko_limit ... ok [INFO] [stdout] test tests::write::test_burst ... ok [INFO] [stdout] test tests::write::oneko_limit ... ok [INFO] [stdout] [INFO] [stdout] failures: [INFO] [stdout] [INFO] [stdout] ---- tests::parametric::test_buffer stdout ---- [INFO] [stdout] [INFO] [stdout] Test regression seed 15874969967965961473 [INFO] [stdout] BW Elapsed: 64.641114ms, datalen: 448135, opts: Some(LimiterOptions { window_length: 385846, window_time: 55ms, bucket_size: 1982298, min_operation_size: 1, timeout: None, tsleep: 142ns, wtime_ns: 55000000, stream_cap_limit: 385846, sleep_threshold: 385846 }) [INFO] [stdout] BR Elapsed: 104.931311ms, datalen: 448135, opts: Some(LimiterOptions { window_length: 448764, window_time: 105ms, bucket_size: 534454, min_operation_size: 1, timeout: None, tsleep: 233ns, wtime_ns: 105000000, stream_cap_limit: 448764, sleep_threshold: 448764 }) [INFO] [stdout] [INFO] [stdout] Test regression seed 8110699151753673162 [INFO] [stdout] BW Elapsed: 5.79666ms, datalen: 383187, opts: Some(LimiterOptions { window_length: 396567, window_time: 6ms, bucket_size: 83137, min_operation_size: 1, timeout: None, tsleep: 15ns, wtime_ns: 6000000, stream_cap_limit: 83137, sleep_threshold: 83137 }) [INFO] [stdout] BR Elapsed: 20.248708ms, datalen: 383187, opts: Some(LimiterOptions { window_length: 1691780, window_time: 89ms, bucket_size: 805200, min_operation_size: 1, timeout: None, tsleep: 52ns, wtime_ns: 89000000, stream_cap_limit: 805200, sleep_threshold: 805200 }) [INFO] [stdout] [INFO] [stdout] Test regression seed 3938382362045563233 [INFO] [stdout] BW Elapsed: 106.885521ms, datalen: 395917, opts: Some(LimiterOptions { window_length: 704271, window_time: 190ms, bucket_size: 1276961, min_operation_size: 1, timeout: None, tsleep: 269ns, wtime_ns: 190000000, stream_cap_limit: 704271, sleep_threshold: 704271 }) [INFO] [stdout] BR Elapsed: 4.8384ms, datalen: 395917, opts: Some(LimiterOptions { window_length: 416468, window_time: 5ms, bucket_size: 1113282, min_operation_size: 1, timeout: None, tsleep: 12ns, wtime_ns: 5000000, stream_cap_limit: 416468, sleep_threshold: 416468 }) [INFO] [stdout] [INFO] [stdout] Test regression seed 4127573218620133621 [INFO] [stdout] BW Elapsed: 8.73209ms, datalen: 501579, opts: Some(LimiterOptions { window_length: 526315, window_time: 9ms, bucket_size: 193893, min_operation_size: 1, timeout: None, tsleep: 17ns, wtime_ns: 9000000, stream_cap_limit: 193893, sleep_threshold: 193893 }) [INFO] [stdout] BR Elapsed: 38.975427ms, datalen: 501579, opts: Some(LimiterOptions { window_length: 1134627, window_time: 88ms, bucket_size: 700857, min_operation_size: 1, timeout: None, tsleep: 77ns, wtime_ns: 88000000, stream_cap_limit: 700857, sleep_threshold: 700857 }) [INFO] [stdout] [INFO] [stdout] Test regression seed 16703096643533052530 [INFO] [stdout] BW Elapsed: 14.947058ms, datalen: 49998, opts: Some(LimiterOptions { window_length: 50185, window_time: 9ms, bucket_size: 39629, min_operation_size: 1, timeout: None, tsleep: 179ns, wtime_ns: 9000000, stream_cap_limit: 39629, sleep_threshold: 39629 }) [INFO] [stdout] [INFO] [stdout] thread 'tests::parametric::test_buffer' (29) panicked at src/tests/parametric.rs:39:13: [INFO] [stdout] BW| Elapsed 14.947058ms > Window time 9ms (with 5% margin, got 66.08% diff) [INFO] [stdout] stack backtrace: [INFO] [stdout] 0: 0x5c2220672a12 - std::backtrace_rs::backtrace::libunwind::trace::hc4a5f428cfb78751 [INFO] [stdout] at /rustc/b6ae10aa7319b7ebb6c7b3331dd71a9d6c9c5b4b/library/std/src/../../backtrace/src/backtrace/libunwind.rs:117:9 [INFO] [stdout] 1: 0x5c2220672a12 - std::backtrace_rs::backtrace::trace_unsynchronized::h20e1095684b4c296 [INFO] [stdout] at /rustc/b6ae10aa7319b7ebb6c7b3331dd71a9d6c9c5b4b/library/std/src/../../backtrace/src/backtrace/mod.rs:66:14 [INFO] [stdout] 2: 0x5c2220672a12 - std::sys::backtrace::_print_fmt::h461f2e3a8f6b29e2 [INFO] [stdout] at /rustc/b6ae10aa7319b7ebb6c7b3331dd71a9d6c9c5b4b/library/std/src/sys/backtrace.rs:66:9 [INFO] [stdout] 3: 0x5c2220672a12 - ::fmt::h4ee3a75aa71a2c45 [INFO] [stdout] at /rustc/b6ae10aa7319b7ebb6c7b3331dd71a9d6c9c5b4b/library/std/src/sys/backtrace.rs:39:26 [INFO] [stdout] 4: 0x5c222069684f - core::fmt::rt::Argument::fmt::h6f1564705cd089af [INFO] [stdout] at /rustc/b6ae10aa7319b7ebb6c7b3331dd71a9d6c9c5b4b/library/core/src/fmt/rt.rs:173:76 [INFO] [stdout] 5: 0x5c222069684f - core::fmt::write::h21ca93b65a7c281a [INFO] [stdout] at /rustc/b6ae10aa7319b7ebb6c7b3331dd71a9d6c9c5b4b/library/core/src/fmt/mod.rs:1468:25 [INFO] [stdout] 6: 0x5c222065f823 - std::io::default_write_fmt::h6f3360f4711e9130 [INFO] [stdout] at /rustc/b6ae10aa7319b7ebb6c7b3331dd71a9d6c9c5b4b/library/std/src/io/mod.rs:639:11 [INFO] [stdout] 7: 0x5c222065f823 - std::io::Write::write_fmt::hf4539125c0e0bc30 [INFO] [stdout] at /rustc/b6ae10aa7319b7ebb6c7b3331dd71a9d6c9c5b4b/library/std/src/io/mod.rs:1954:13 [INFO] [stdout] 8: 0x5c2220673022 - std::sys::backtrace::BacktraceLock::print::h58d5d73f9e953cf1 [INFO] [stdout] at /rustc/b6ae10aa7319b7ebb6c7b3331dd71a9d6c9c5b4b/library/std/src/sys/backtrace.rs:42:9 [INFO] [stdout] 9: 0x5c222065de7c - std::panicking::default_hook::{{closure}}::h1457fbe47c9457d1 [INFO] [stdout] at /rustc/b6ae10aa7319b7ebb6c7b3331dd71a9d6c9c5b4b/library/std/src/panicking.rs:301:27 [INFO] [stdout] 10: 0x5c222065dcf4 - std::panicking::default_hook::hccb5e73b206c0830 [INFO] [stdout] at /rustc/b6ae10aa7319b7ebb6c7b3331dd71a9d6c9c5b4b/library/std/src/panicking.rs:325:9 [INFO] [stdout] 11: 0x5c22205fe00e - as core::ops::function::Fn>::call::h04940a86aa899793 [INFO] [stdout] at /rustc/b6ae10aa7319b7ebb6c7b3331dd71a9d6c9c5b4b/library/alloc/src/boxed.rs:1985:9 [INFO] [stdout] 12: 0x5c22205fe00e - test::test_main_with_exit_callback::{{closure}}::hca6cc295403b83e8 [INFO] [stdout] at /rustc/b6ae10aa7319b7ebb6c7b3331dd71a9d6c9c5b4b/library/test/src/lib.rs:145:21 [INFO] [stdout] 13: 0x5c222065ead3 - as core::ops::function::Fn>::call::h9d85285925beb538 [INFO] [stdout] at /rustc/b6ae10aa7319b7ebb6c7b3331dd71a9d6c9c5b4b/library/alloc/src/boxed.rs:1985:9 [INFO] [stdout] 14: 0x5c222065ead3 - std::panicking::panic_with_hook::h3190ecc6229cdd29 [INFO] [stdout] at /rustc/b6ae10aa7319b7ebb6c7b3331dd71a9d6c9c5b4b/library/std/src/panicking.rs:842:13 [INFO] [stdout] 15: 0x5c2220672e2a - std::panicking::panic_handler::{{closure}}::ha1f1b769bc2bb40c [INFO] [stdout] at /rustc/b6ae10aa7319b7ebb6c7b3331dd71a9d6c9c5b4b/library/std/src/panicking.rs:707:13 [INFO] [stdout] 16: 0x5c2220672d89 - std::sys::backtrace::__rust_end_short_backtrace::h5f9cf66f19c2a172 [INFO] [stdout] at /rustc/b6ae10aa7319b7ebb6c7b3331dd71a9d6c9c5b4b/library/std/src/sys/backtrace.rs:174:18 [INFO] [stdout] 17: 0x5c222065e77d - __rustc[a93bd50104b99ad4]::rust_begin_unwind [INFO] [stdout] at /rustc/b6ae10aa7319b7ebb6c7b3331dd71a9d6c9c5b4b/library/std/src/panicking.rs:698:5 [INFO] [stdout] 18: 0x5c222069fa80 - core::panicking::panic_fmt::hc70c3c83f13c1375 [INFO] [stdout] at /rustc/b6ae10aa7319b7ebb6c7b3331dd71a9d6c9c5b4b/library/core/src/panicking.rs:75:14 [INFO] [stdout] 19: 0x5c22205e1fd0 - stream_limiter::tests::parametric::assert_rate_limited::hb43c3bb766a27c41 [INFO] [stdout] at /opt/rustwide/workdir/src/tests/parametric.rs:39:13 [INFO] [stdout] 20: 0x5c22205e3b1a - stream_limiter::tests::parametric::test_buffer::paramtest_buffer::h06096ec78750a264 [INFO] [stdout] at /opt/rustwide/workdir/src/tests/parametric.rs:102:9 [INFO] [stdout] 21: 0x5c22205d9d03 - core::ops::function::Fn::call::h468c7c54b1470e2e [INFO] [stdout] at /rustc/b6ae10aa7319b7ebb6c7b3331dd71a9d6c9c5b4b/library/core/src/ops/function.rs:80:5 [INFO] [stdout] 22: 0x5c22205dede1 - stream_limiter::tests::utils::paramtests::start_parametric_test::h01c7b14f67056746 [INFO] [stdout] at /opt/rustwide/workdir/src/tests/utils/paramtests.rs:17:9 [INFO] [stdout] 23: 0x5c22205e4ec8 - stream_limiter::tests::parametric::test_buffer::hc583d0695ba84b35 [INFO] [stdout] at /opt/rustwide/workdir/src/tests/parametric.rs:117:5 [INFO] [stdout] 24: 0x5c22205e2397 - stream_limiter::tests::parametric::test_buffer::{{closure}}::h3759d9aebb5ade32 [INFO] [stdout] at /opt/rustwide/workdir/src/tests/parametric.rs:85:17 [INFO] [stdout] 25: 0x5c22205d9616 - core::ops::function::FnOnce::call_once::h82b0df34623cc0cd [INFO] [stdout] at /rustc/b6ae10aa7319b7ebb6c7b3331dd71a9d6c9c5b4b/library/core/src/ops/function.rs:253:5 [INFO] [stdout] 26: 0x5c22205ff08b - core::ops::function::FnOnce::call_once::h70d190c46966003b [INFO] [stdout] at /rustc/b6ae10aa7319b7ebb6c7b3331dd71a9d6c9c5b4b/library/core/src/ops/function.rs:253:5 [INFO] [stdout] 27: 0x5c22205ff08b - test::__rust_begin_short_backtrace::hb077ccaabb17d2ec [INFO] [stdout] at /rustc/b6ae10aa7319b7ebb6c7b3331dd71a9d6c9c5b4b/library/test/src/lib.rs:663:18 [INFO] [stdout] 28: 0x5c22205fccf5 - test::run_test_in_process::{{closure}}::hd73eeb633a1507bd [INFO] [stdout] at /rustc/b6ae10aa7319b7ebb6c7b3331dd71a9d6c9c5b4b/library/test/src/lib.rs:686:74 [INFO] [stdout] 29: 0x5c22205fccf5 - as core::ops::function::FnOnce<()>>::call_once::h033c82756fc9d192 [INFO] [stdout] at /rustc/b6ae10aa7319b7ebb6c7b3331dd71a9d6c9c5b4b/library/core/src/panic/unwind_safe.rs:272:9 [INFO] [stdout] 30: 0x5c22205fccf5 - std::panicking::catch_unwind::do_call::h734b44e243d34585 [INFO] [stdout] at /rustc/b6ae10aa7319b7ebb6c7b3331dd71a9d6c9c5b4b/library/std/src/panicking.rs:590:40 [INFO] [stdout] 31: 0x5c22205fccf5 - std::panicking::catch_unwind::h5e85417122517fb1 [INFO] [stdout] at /rustc/b6ae10aa7319b7ebb6c7b3331dd71a9d6c9c5b4b/library/std/src/panicking.rs:553:19 [INFO] [stdout] 32: 0x5c22205fccf5 - std::panic::catch_unwind::h0900e58a82a7d188 [INFO] [stdout] at /rustc/b6ae10aa7319b7ebb6c7b3331dd71a9d6c9c5b4b/library/std/src/panic.rs:359:14 [INFO] [stdout] 33: 0x5c22205fccf5 - test::run_test_in_process::h27721efd62ae77d0 [INFO] [stdout] at /rustc/b6ae10aa7319b7ebb6c7b3331dd71a9d6c9c5b4b/library/test/src/lib.rs:686:27 [INFO] [stdout] 34: 0x5c22205fccf5 - test::run_test::{{closure}}::h8b1264838cc597aa [INFO] [stdout] at /rustc/b6ae10aa7319b7ebb6c7b3331dd71a9d6c9c5b4b/library/test/src/lib.rs:607:43 [INFO] [stdout] 35: 0x5c2220623c04 - test::run_test::{{closure}}::h6ecb96835e2eae4f [INFO] [stdout] at /rustc/b6ae10aa7319b7ebb6c7b3331dd71a9d6c9c5b4b/library/test/src/lib.rs:637:41 [INFO] [stdout] 36: 0x5c2220623c04 - std::sys::backtrace::__rust_begin_short_backtrace::h098f38ee82a820a7 [INFO] [stdout] at /rustc/b6ae10aa7319b7ebb6c7b3331dd71a9d6c9c5b4b/library/std/src/sys/backtrace.rs:158:18 [INFO] [stdout] 37: 0x5c222060a81a - std::thread::Builder::spawn_unchecked_::{{closure}}::{{closure}}::h042592f748f5e369 [INFO] [stdout] at /rustc/b6ae10aa7319b7ebb6c7b3331dd71a9d6c9c5b4b/library/std/src/thread/mod.rs:559:17 [INFO] [stdout] 38: 0x5c222060a81a - as core::ops::function::FnOnce<()>>::call_once::h134fe7f3a2d9ec8d [INFO] [stdout] at /rustc/b6ae10aa7319b7ebb6c7b3331dd71a9d6c9c5b4b/library/core/src/panic/unwind_safe.rs:272:9 [INFO] [stdout] 39: 0x5c222060a81a - std::panicking::catch_unwind::do_call::heb87d604f60aee16 [INFO] [stdout] at /rustc/b6ae10aa7319b7ebb6c7b3331dd71a9d6c9c5b4b/library/std/src/panicking.rs:590:40 [INFO] [stdout] 40: 0x5c222060a81a - std::panicking::catch_unwind::h2203a0f553b89fd7 [INFO] [stdout] at /rustc/b6ae10aa7319b7ebb6c7b3331dd71a9d6c9c5b4b/library/std/src/panicking.rs:553:19 [INFO] [stdout] 41: 0x5c222060a81a - std::panic::catch_unwind::h4d383e0b0e25d838 [INFO] [stdout] at /rustc/b6ae10aa7319b7ebb6c7b3331dd71a9d6c9c5b4b/library/std/src/panic.rs:359:14 [INFO] [stdout] 42: 0x5c222060a81a - std::thread::Builder::spawn_unchecked_::{{closure}}::h76cbdd4635cfd58b [INFO] [stdout] at /rustc/b6ae10aa7319b7ebb6c7b3331dd71a9d6c9c5b4b/library/std/src/thread/mod.rs:557:30 [INFO] [stdout] 43: 0x5c222060a81a - core::ops::function::FnOnce::call_once{{vtable.shim}}::he72e066680f5e267 [INFO] [stdout] at /rustc/b6ae10aa7319b7ebb6c7b3331dd71a9d6c9c5b4b/library/core/src/ops/function.rs:253:5 [INFO] [stdout] 44: 0x5c222064894f - as core::ops::function::FnOnce>::call_once::h1b9c6fea2cbefc68 [INFO] [stdout] at /rustc/b6ae10aa7319b7ebb6c7b3331dd71a9d6c9c5b4b/library/alloc/src/boxed.rs:1971:9 [INFO] [stdout] 45: 0x5c222064894f - std::sys::pal::unix::thread::Thread::new::thread_start::h92caa992bc760789 [INFO] [stdout] at /rustc/b6ae10aa7319b7ebb6c7b3331dd71a9d6c9c5b4b/library/std/src/sys/pal/unix/thread.rs:107:17 [INFO] [stdout] 46: 0x77f29130baa4 - [INFO] [stdout] 47: 0x77f291398a34 - clone [INFO] [stdout] 48: 0x0 - [INFO] [stdout] [INFO] [stdout] ---- tests::parametric::test_tcp stdout ---- [INFO] [stdout] [INFO] [stdout] Test regression seed 14209078572640031037 [INFO] [stdout] TWC Elapsed: 1.27µs, datalen: 184176, opts: None [INFO] [stdout] TRL Elapsed: 2.62µs, datalen: 184176, opts: None [INFO] [stdout] TWL Elapsed: 168.455617ms, datalen: 184176, opts: Some(LimiterOptions { window_length: 198376, window_time: 181ms, bucket_size: 788709, min_operation_size: 65536, timeout: None, tsleep: 912ns, wtime_ns: 181000000, stream_cap_limit: 198376, sleep_threshold: 198376 }) [INFO] [stdout] TRC Elapsed: 176.015806ms, datalen: 184176, opts: Some(LimiterOptions { window_length: 198376, window_time: 181ms, bucket_size: 788709, min_operation_size: 65536, timeout: None, tsleep: 912ns, wtime_ns: 181000000, stream_cap_limit: 198376, sleep_threshold: 198376 }) [INFO] [stdout] [INFO] [stdout] Test regression seed 13904858326511268101 [INFO] [stdout] TWC Elapsed: 14.531669ms, datalen: 113052, opts: Some(LimiterOptions { window_length: 250217, window_time: 32ms, bucket_size: 374126, min_operation_size: 65536, timeout: None, tsleep: 127ns, wtime_ns: 32000000, stream_cap_limit: 250217, sleep_threshold: 250217 }) [INFO] [stdout] TRL Elapsed: 16.961548ms, datalen: 113052, opts: Some(LimiterOptions { window_length: 250217, window_time: 32ms, bucket_size: 374126, min_operation_size: 65536, timeout: None, tsleep: 127ns, wtime_ns: 32000000, stream_cap_limit: 250217, sleep_threshold: 250217 }) [INFO] [stdout] TWL Elapsed: 45.804327ms, datalen: 113052, opts: Some(LimiterOptions { window_length: 533021, window_time: 188ms, bucket_size: 549006, min_operation_size: 65536, timeout: None, tsleep: 352ns, wtime_ns: 188000000, stream_cap_limit: 533021, sleep_threshold: 533021 }) [INFO] [stdout] TRC Elapsed: 39.953986ms, datalen: 113052, opts: Some(LimiterOptions { window_length: 533021, window_time: 188ms, bucket_size: 549006, min_operation_size: 65536, timeout: None, tsleep: 352ns, wtime_ns: 188000000, stream_cap_limit: 533021, sleep_threshold: 533021 }) [INFO] [stdout] [INFO] [stdout] Test regression seed 15163516011027607440 [INFO] [stdout] TWC Elapsed: 81.798083ms, datalen: 231579, opts: Some(LimiterOptions { window_length: 444885, window_time: 157ms, bucket_size: 926860, min_operation_size: 65536, timeout: None, tsleep: 352ns, wtime_ns: 157000000, stream_cap_limit: 444885, sleep_threshold: 444885 }) [INFO] [stdout] TRL Elapsed: 81.801603ms, datalen: 231579, opts: Some(LimiterOptions { window_length: 444885, window_time: 157ms, bucket_size: 926860, min_operation_size: 65536, timeout: None, tsleep: 352ns, wtime_ns: 157000000, stream_cap_limit: 444885, sleep_threshold: 444885 }) [INFO] [stdout] TWL Elapsed: 4.945279ms, datalen: 231579, opts: Some(LimiterOptions { window_length: 567955, window_time: 12ms, bucket_size: 926556, min_operation_size: 65536, timeout: None, tsleep: 21ns, wtime_ns: 12000000, stream_cap_limit: 567955, sleep_threshold: 567955 }) [INFO] [stdout] TRC Elapsed: 5.116829ms, datalen: 231579, opts: Some(LimiterOptions { window_length: 567955, window_time: 12ms, bucket_size: 926556, min_operation_size: 65536, timeout: None, tsleep: 21ns, wtime_ns: 12000000, stream_cap_limit: 567955, sleep_threshold: 567955 }) [INFO] [stdout] [INFO] [stdout] Test regression seed 7013782957736503346 [INFO] [stdout] TWC Elapsed: 75.419124ms, datalen: 364318, opts: Some(LimiterOptions { window_length: 134995, window_time: 28ms, bucket_size: 645796, min_operation_size: 65536, timeout: None, tsleep: 207ns, wtime_ns: 28000000, stream_cap_limit: 134995, sleep_threshold: 134995 }) [INFO] [stdout] TRL Elapsed: 65.426175ms, datalen: 364318, opts: Some(LimiterOptions { window_length: 134995, window_time: 28ms, bucket_size: 645796, min_operation_size: 65536, timeout: None, tsleep: 207ns, wtime_ns: 28000000, stream_cap_limit: 134995, sleep_threshold: 134995 }) [INFO] [stdout] TWL Elapsed: 86.891484ms, datalen: 364318, opts: Some(LimiterOptions { window_length: 188597, window_time: 45ms, bucket_size: 969467, min_operation_size: 65536, timeout: None, tsleep: 238ns, wtime_ns: 45000000, stream_cap_limit: 188597, sleep_threshold: 188597 }) [INFO] [stdout] TRC Elapsed: 86.867314ms, datalen: 364318, opts: Some(LimiterOptions { window_length: 188597, window_time: 45ms, bucket_size: 969467, min_operation_size: 65536, timeout: None, tsleep: 238ns, wtime_ns: 45000000, stream_cap_limit: 188597, sleep_threshold: 188597 }) [INFO] [stdout] [INFO] [stdout] Test regression seed 11546705333993054670 [INFO] [stdout] TWC Elapsed: 163.056107ms, datalen: 5584, opts: Some(LimiterOptions { window_length: 6133, window_time: 179ms, bucket_size: 80487, min_operation_size: 65536, timeout: None, tsleep: 29.186µs, wtime_ns: 179000000, stream_cap_limit: 6133, sleep_threshold: 65536 }) [INFO] [stdout] TRL Elapsed: 163.053507ms, datalen: 5584, opts: Some(LimiterOptions { window_length: 6133, window_time: 179ms, bucket_size: 80487, min_operation_size: 65536, timeout: None, tsleep: 29.186µs, wtime_ns: 179000000, stream_cap_limit: 6133, sleep_threshold: 65536 }) [INFO] [stdout] TRC Elapsed: 53.840236ms, datalen: 5584, opts: Some(LimiterOptions { window_length: 15270, window_time: 147ms, bucket_size: 111761, min_operation_size: 65536, timeout: None, tsleep: 9.626µs, wtime_ns: 147000000, stream_cap_limit: 15270, sleep_threshold: 65536 }) [INFO] [stdout] TWL Elapsed: 53.832766ms, datalen: 5584, opts: Some(LimiterOptions { window_length: 15270, window_time: 147ms, bucket_size: 111761, min_operation_size: 65536, timeout: None, tsleep: 9.626µs, wtime_ns: 147000000, stream_cap_limit: 15270, sleep_threshold: 65536 }) [INFO] [stdout] [INFO] [stdout] Test regression seed 13065936645921438274 [INFO] [stdout] TWC Elapsed: 2.25µs, datalen: 184869, opts: None [INFO] [stdout] TRL Elapsed: 2.88µs, datalen: 184869, opts: None [INFO] [stdout] TRC Elapsed: 10.69631ms, datalen: 184869, opts: Some(LimiterOptions { window_length: 486433, window_time: 28ms, bucket_size: 574311, min_operation_size: 65536, timeout: None, tsleep: 57ns, wtime_ns: 28000000, stream_cap_limit: 486433, sleep_threshold: 486433 }) [INFO] [stdout] TWL Elapsed: 10.73063ms, datalen: 184869, opts: Some(LimiterOptions { window_length: 486433, window_time: 28ms, bucket_size: 574311, min_operation_size: 65536, timeout: None, tsleep: 57ns, wtime_ns: 28000000, stream_cap_limit: 486433, sleep_threshold: 486433 }) [INFO] [stdout] [INFO] [stdout] 1/100| Seed 17970984561478979373 [INFO] [stdout] TWC Elapsed: 43.000387ms, datalen: 213325, opts: Some(LimiterOptions { window_length: 878852, window_time: 148ms, bucket_size: 501601, min_operation_size: 65536, timeout: None, tsleep: 168ns, wtime_ns: 148000000, stream_cap_limit: 501601, sleep_threshold: 501601 }) [INFO] [stdout] TRL Elapsed: 36.005037ms, datalen: 213325, opts: Some(LimiterOptions { window_length: 878852, window_time: 148ms, bucket_size: 501601, min_operation_size: 65536, timeout: None, tsleep: 168ns, wtime_ns: 148000000, stream_cap_limit: 501601, sleep_threshold: 501601 }) [INFO] [stdout] TWL Elapsed: 38.241497ms, datalen: 213325, opts: Some(LimiterOptions { window_length: 230603, window_time: 38ms, bucket_size: 648161, min_operation_size: 65536, timeout: None, tsleep: 164ns, wtime_ns: 38000000, stream_cap_limit: 230603, sleep_threshold: 230603 }) [INFO] [stdout] TRC Elapsed: 38.369627ms, datalen: 213325, opts: Some(LimiterOptions { window_length: 230603, window_time: 38ms, bucket_size: 648161, min_operation_size: 65536, timeout: None, tsleep: 164ns, wtime_ns: 38000000, stream_cap_limit: 230603, sleep_threshold: 230603 }) [INFO] [stdout] [INFO] [stdout] 2/100| Seed 922309307349979317 [INFO] [stdout] TWC Elapsed: 74.526344ms, datalen: 56184, opts: Some(LimiterOptions { window_length: 133550, window_time: 177ms, bucket_size: 260847, min_operation_size: 65536, timeout: None, tsleep: 1.325µs, wtime_ns: 177000000, stream_cap_limit: 133550, sleep_threshold: 133550 }) [INFO] [stdout] TRL Elapsed: 76.958714ms, datalen: 56184, opts: Some(LimiterOptions { window_length: 133550, window_time: 177ms, bucket_size: 260847, min_operation_size: 65536, timeout: None, tsleep: 1.325µs, wtime_ns: 177000000, stream_cap_limit: 133550, sleep_threshold: 133550 }) [INFO] [stdout] TWL Elapsed: 17.410529ms, datalen: 56184, opts: Some(LimiterOptions { window_length: 59697, window_time: 14ms, bucket_size: 118822, min_operation_size: 65536, timeout: None, tsleep: 234ns, wtime_ns: 14000000, stream_cap_limit: 59697, sleep_threshold: 65536 }) [INFO] [stdout] [INFO] [stdout] thread 'tests::parametric::test_tcp' (30) panicked at src/tests/parametric.rs:39:13: [INFO] [stdout] TWL| Elapsed 17.410529ms > Window time 14ms (with 5% margin, got 24.36% diff) [INFO] [stdout] stack backtrace: [INFO] [stdout] 0: 0x5c2220672a12 - std::backtrace_rs::backtrace::libunwind::trace::hc4a5f428cfb78751 [INFO] [stdout] at /rustc/b6ae10aa7319b7ebb6c7b3331dd71a9d6c9c5b4b/library/std/src/../../backtrace/src/backtrace/libunwind.rs:117:9 [INFO] [stdout] 1: 0x5c2220672a12 - std::backtrace_rs::backtrace::trace_unsynchronized::h20e1095684b4c296 [INFO] [stdout] at /rustc/b6ae10aa7319b7ebb6c7b3331dd71a9d6c9c5b4b/library/std/src/../../backtrace/src/backtrace/mod.rs:66:14 [INFO] [stdout] 2: 0x5c2220672a12 - std::sys::backtrace::_print_fmt::h461f2e3a8f6b29e2 [INFO] [stdout] at /rustc/b6ae10aa7319b7ebb6c7b3331dd71a9d6c9c5b4b/library/std/src/sys/backtrace.rs:66:9 [INFO] [stdout] 3: 0x5c2220672a12 - ::fmt::h4ee3a75aa71a2c45 [INFO] [stdout] at /rustc/b6ae10aa7319b7ebb6c7b3331dd71a9d6c9c5b4b/library/std/src/sys/backtrace.rs:39:26 [INFO] [stdout] 4: 0x5c222069684f - core::fmt::rt::Argument::fmt::h6f1564705cd089af [INFO] [stdout] at /rustc/b6ae10aa7319b7ebb6c7b3331dd71a9d6c9c5b4b/library/core/src/fmt/rt.rs:173:76 [INFO] [stdout] 5: 0x5c222069684f - core::fmt::write::h21ca93b65a7c281a [INFO] [stdout] at /rustc/b6ae10aa7319b7ebb6c7b3331dd71a9d6c9c5b4b/library/core/src/fmt/mod.rs:1468:25 [INFO] [stdout] 6: 0x5c222065f823 - std::io::default_write_fmt::h6f3360f4711e9130 [INFO] [stdout] at /rustc/b6ae10aa7319b7ebb6c7b3331dd71a9d6c9c5b4b/library/std/src/io/mod.rs:639:11 [INFO] [stdout] 7: 0x5c222065f823 - std::io::Write::write_fmt::hf4539125c0e0bc30 [INFO] [stdout] at /rustc/b6ae10aa7319b7ebb6c7b3331dd71a9d6c9c5b4b/library/std/src/io/mod.rs:1954:13 [INFO] [stdout] 8: 0x5c2220673022 - std::sys::backtrace::BacktraceLock::print::h58d5d73f9e953cf1 [INFO] [stdout] at /rustc/b6ae10aa7319b7ebb6c7b3331dd71a9d6c9c5b4b/library/std/src/sys/backtrace.rs:42:9 [INFO] [stdout] 9: 0x5c222065de7c - std::panicking::default_hook::{{closure}}::h1457fbe47c9457d1 [INFO] [stdout] at /rustc/b6ae10aa7319b7ebb6c7b3331dd71a9d6c9c5b4b/library/std/src/panicking.rs:301:27 [INFO] [stdout] 10: 0x5c222065dcf4 - std::panicking::default_hook::hccb5e73b206c0830 [INFO] [stdout] at /rustc/b6ae10aa7319b7ebb6c7b3331dd71a9d6c9c5b4b/library/std/src/panicking.rs:325:9 [INFO] [stdout] 11: 0x5c22205fe00e - as core::ops::function::Fn>::call::h04940a86aa899793 [INFO] [stdout] at /rustc/b6ae10aa7319b7ebb6c7b3331dd71a9d6c9c5b4b/library/alloc/src/boxed.rs:1985:9 [INFO] [stdout] 12: 0x5c22205fe00e - test::test_main_with_exit_callback::{{closure}}::hca6cc295403b83e8 [INFO] [stdout] at /rustc/b6ae10aa7319b7ebb6c7b3331dd71a9d6c9c5b4b/library/test/src/lib.rs:145:21 [INFO] [stdout] 13: 0x5c222065ead3 - as core::ops::function::Fn>::call::h9d85285925beb538 [INFO] [stdout] at /rustc/b6ae10aa7319b7ebb6c7b3331dd71a9d6c9c5b4b/library/alloc/src/boxed.rs:1985:9 [INFO] [stdout] 14: 0x5c222065ead3 - std::panicking::panic_with_hook::h3190ecc6229cdd29 [INFO] [stdout] at /rustc/b6ae10aa7319b7ebb6c7b3331dd71a9d6c9c5b4b/library/std/src/panicking.rs:842:13 [INFO] [stdout] 15: 0x5c2220672e2a - std::panicking::panic_handler::{{closure}}::ha1f1b769bc2bb40c [INFO] [stdout] at /rustc/b6ae10aa7319b7ebb6c7b3331dd71a9d6c9c5b4b/library/std/src/panicking.rs:707:13 [INFO] [stdout] 16: 0x5c2220672d89 - std::sys::backtrace::__rust_end_short_backtrace::h5f9cf66f19c2a172 [INFO] [stdout] at /rustc/b6ae10aa7319b7ebb6c7b3331dd71a9d6c9c5b4b/library/std/src/sys/backtrace.rs:174:18 [INFO] [stdout] 17: 0x5c222065e77d - __rustc[a93bd50104b99ad4]::rust_begin_unwind [INFO] [stdout] at /rustc/b6ae10aa7319b7ebb6c7b3331dd71a9d6c9c5b4b/library/std/src/panicking.rs:698:5 [INFO] [stdout] 18: 0x5c222069fa80 - core::panicking::panic_fmt::hc70c3c83f13c1375 [INFO] [stdout] at /rustc/b6ae10aa7319b7ebb6c7b3331dd71a9d6c9c5b4b/library/core/src/panicking.rs:75:14 [INFO] [stdout] 19: 0x5c22205e1fd0 - stream_limiter::tests::parametric::assert_rate_limited::hb43c3bb766a27c41 [INFO] [stdout] at /opt/rustwide/workdir/src/tests/parametric.rs:39:13 [INFO] [stdout] 20: 0x5c22205e32b9 - stream_limiter::tests::parametric::test_tcp::paramtest_tcp::hb51cdff3560c5eba [INFO] [stdout] at /opt/rustwide/workdir/src/tests/parametric.rs:214:13 [INFO] [stdout] 21: 0x5c22205da3f3 - core::ops::function::Fn::call::h074a277e00fb67f6 [INFO] [stdout] at /rustc/b6ae10aa7319b7ebb6c7b3331dd71a9d6c9c5b4b/library/core/src/ops/function.rs:80:5 [INFO] [stdout] 22: 0x5c22205df2a7 - stream_limiter::tests::utils::paramtests::start_parametric_test::h6389f53e5291a175 [INFO] [stdout] at /opt/rustwide/workdir/src/tests/utils/paramtests.rs:27:9 [INFO] [stdout] 23: 0x5c22205e4258 - stream_limiter::tests::parametric::test_tcp::hdacd56397f30e5fb [INFO] [stdout] at /opt/rustwide/workdir/src/tests/parametric.rs:220:5 [INFO] [stdout] 24: 0x5c22205e19f7 - stream_limiter::tests::parametric::test_tcp::{{closure}}::h50d0c2dec90affa2 [INFO] [stdout] at /opt/rustwide/workdir/src/tests/parametric.rs:132:14 [INFO] [stdout] 25: 0x5c22205d9ec6 - core::ops::function::FnOnce::call_once::h4ae70e91795dad55 [INFO] [stdout] at /rustc/b6ae10aa7319b7ebb6c7b3331dd71a9d6c9c5b4b/library/core/src/ops/function.rs:253:5 [INFO] [stdout] 26: 0x5c22205ff08b - core::ops::function::FnOnce::call_once::h70d190c46966003b [INFO] [stdout] at /rustc/b6ae10aa7319b7ebb6c7b3331dd71a9d6c9c5b4b/library/core/src/ops/function.rs:253:5 [INFO] [stdout] 27: 0x5c22205ff08b - test::__rust_begin_short_backtrace::hb077ccaabb17d2ec [INFO] [stdout] at /rustc/b6ae10aa7319b7ebb6c7b3331dd71a9d6c9c5b4b/library/test/src/lib.rs:663:18 [INFO] [stdout] 28: 0x5c22205fccf5 - test::run_test_in_process::{{closure}}::hd73eeb633a1507bd [INFO] [stdout] at /rustc/b6ae10aa7319b7ebb6c7b3331dd71a9d6c9c5b4b/library/test/src/lib.rs:686:74 [INFO] [stdout] 29: 0x5c22205fccf5 - as core::ops::function::FnOnce<()>>::call_once::h033c82756fc9d192 [INFO] [stdout] at /rustc/b6ae10aa7319b7ebb6c7b3331dd71a9d6c9c5b4b/library/core/src/panic/unwind_safe.rs:272:9 [INFO] [stdout] 30: 0x5c22205fccf5 - std::panicking::catch_unwind::do_call::h734b44e243d34585 [INFO] [stdout] at /rustc/b6ae10aa7319b7ebb6c7b3331dd71a9d6c9c5b4b/library/std/src/panicking.rs:590:40 [INFO] [stdout] 31: 0x5c22205fccf5 - std::panicking::catch_unwind::h5e85417122517fb1 [INFO] [stdout] at /rustc/b6ae10aa7319b7ebb6c7b3331dd71a9d6c9c5b4b/library/std/src/panicking.rs:553:19 [INFO] [stdout] 32: 0x5c22205fccf5 - std::panic::catch_unwind::h0900e58a82a7d188 [INFO] [stdout] at /rustc/b6ae10aa7319b7ebb6c7b3331dd71a9d6c9c5b4b/library/std/src/panic.rs:359:14 [INFO] [stdout] 33: 0x5c22205fccf5 - test::run_test_in_process::h27721efd62ae77d0 [INFO] [stdout] at /rustc/b6ae10aa7319b7ebb6c7b3331dd71a9d6c9c5b4b/library/test/src/lib.rs:686:27 [INFO] [stdout] 34: 0x5c22205fccf5 - test::run_test::{{closure}}::h8b1264838cc597aa [INFO] [stdout] at /rustc/b6ae10aa7319b7ebb6c7b3331dd71a9d6c9c5b4b/library/test/src/lib.rs:607:43 [INFO] [stdout] 35: 0x5c2220623c04 - test::run_test::{{closure}}::h6ecb96835e2eae4f [INFO] [stdout] at /rustc/b6ae10aa7319b7ebb6c7b3331dd71a9d6c9c5b4b/library/test/src/lib.rs:637:41 [INFO] [stdout] 36: 0x5c2220623c04 - std::sys::backtrace::__rust_begin_short_backtrace::h098f38ee82a820a7 [INFO] [stdout] at /rustc/b6ae10aa7319b7ebb6c7b3331dd71a9d6c9c5b4b/library/std/src/sys/backtrace.rs:158:18 [INFO] [stdout] 37: 0x5c222060a81a - std::thread::Builder::spawn_unchecked_::{{closure}}::{{closure}}::h042592f748f5e369 [INFO] [stdout] at /rustc/b6ae10aa7319b7ebb6c7b3331dd71a9d6c9c5b4b/library/std/src/thread/mod.rs:559:17 [INFO] [stdout] 38: 0x5c222060a81a - as core::ops::function::FnOnce<()>>::call_once::h134fe7f3a2d9ec8d [INFO] [stdout] at /rustc/b6ae10aa7319b7ebb6c7b3331dd71a9d6c9c5b4b/library/core/src/panic/unwind_safe.rs:272:9 [INFO] [stdout] 39: 0x5c222060a81a - std::panicking::catch_unwind::do_call::heb87d604f60aee16 [INFO] [stdout] at /rustc/b6ae10aa7319b7ebb6c7b3331dd71a9d6c9c5b4b/library/std/src/panicking.rs:590:40 [INFO] [stdout] 40: 0x5c222060a81a - std::panicking::catch_unwind::h2203a0f553b89fd7 [INFO] [stdout] at /rustc/b6ae10aa7319b7ebb6c7b3331dd71a9d6c9c5b4b/library/std/src/panicking.rs:553:19 [INFO] [stdout] 41: 0x5c222060a81a - std::panic::catch_unwind::h4d383e0b0e25d838 [INFO] [stdout] at /rustc/b6ae10aa7319b7ebb6c7b3331dd71a9d6c9c5b4b/library/std/src/panic.rs:359:14 [INFO] [stdout] 42: 0x5c222060a81a - std::thread::Builder::spawn_unchecked_::{{closure}}::h76cbdd4635cfd58b [INFO] [stdout] at /rustc/b6ae10aa7319b7ebb6c7b3331dd71a9d6c9c5b4b/library/std/src/thread/mod.rs:557:30 [INFO] [stdout] 43: 0x5c222060a81a - core::ops::function::FnOnce::call_once{{vtable.shim}}::he72e066680f5e267 [INFO] [stdout] at /rustc/b6ae10aa7319b7ebb6c7b3331dd71a9d6c9c5b4b/library/core/src/ops/function.rs:253:5 [INFO] [stdout] 44: 0x5c222064894f - as core::ops::function::FnOnce>::call_once::h1b9c6fea2cbefc68 [INFO] [stdout] at /rustc/b6ae10aa7319b7ebb6c7b3331dd71a9d6c9c5b4b/library/alloc/src/boxed.rs:1971:9 [INFO] [stdout] 45: 0x5c222064894f - std::sys::pal::unix::thread::Thread::new::thread_start::h92caa992bc760789 [INFO] [stdout] at /rustc/b6ae10aa7319b7ebb6c7b3331dd71a9d6c9c5b4b/library/std/src/sys/pal/unix/thread.rs:107:17 [INFO] [stdout] 46: 0x77f29130baa4 - [INFO] [stdout] 47: 0x77f291398a34 - clone [INFO] [stdout] 48: 0x0 - [INFO] [stdout] TRC Elapsed: 13.240969ms, datalen: 56184, opts: Some(LimiterOptions { window_length: 59697, window_time: 14ms, bucket_size: 118822, min_operation_size: 65536, timeout: None, tsleep: 234ns, wtime_ns: 14000000, stream_cap_limit: 59697, sleep_threshold: 65536 }) [INFO] [stdout] [INFO] [stdout] ---- tests::write::write_timeout stdout ---- [INFO] [stdout] [INFO] [stdout] thread 'tests::write::write_timeout' (62) panicked at src/tests/write.rs:174:5: [INFO] [stdout] assertion `left == right` failed [INFO] [stdout] left: 1002 [INFO] [stdout] right: 1000 [INFO] [stdout] stack backtrace: [INFO] [stdout] 0: 0x5c2220672a12 - std::backtrace_rs::backtrace::libunwind::trace::hc4a5f428cfb78751 [INFO] [stdout] at /rustc/b6ae10aa7319b7ebb6c7b3331dd71a9d6c9c5b4b/library/std/src/../../backtrace/src/backtrace/libunwind.rs:117:9 [INFO] [stdout] 1: 0x5c2220672a12 - std::backtrace_rs::backtrace::trace_unsynchronized::h20e1095684b4c296 [INFO] [stdout] at /rustc/b6ae10aa7319b7ebb6c7b3331dd71a9d6c9c5b4b/library/std/src/../../backtrace/src/backtrace/mod.rs:66:14 [INFO] [stdout] 2: 0x5c2220672a12 - std::sys::backtrace::_print_fmt::h461f2e3a8f6b29e2 [INFO] [stdout] at /rustc/b6ae10aa7319b7ebb6c7b3331dd71a9d6c9c5b4b/library/std/src/sys/backtrace.rs:66:9 [INFO] [stdout] 3: 0x5c2220672a12 - ::fmt::h4ee3a75aa71a2c45 [INFO] [stdout] at /rustc/b6ae10aa7319b7ebb6c7b3331dd71a9d6c9c5b4b/library/std/src/sys/backtrace.rs:39:26 [INFO] [stdout] 4: 0x5c222069684f - core::fmt::rt::Argument::fmt::h6f1564705cd089af [INFO] [stdout] at /rustc/b6ae10aa7319b7ebb6c7b3331dd71a9d6c9c5b4b/library/core/src/fmt/rt.rs:173:76 [INFO] [stdout] 5: 0x5c222069684f - core::fmt::write::h21ca93b65a7c281a [INFO] [stdout] at /rustc/b6ae10aa7319b7ebb6c7b3331dd71a9d6c9c5b4b/library/core/src/fmt/mod.rs:1468:25 [INFO] [stdout] 6: 0x5c222065f823 - std::io::default_write_fmt::h6f3360f4711e9130 [INFO] [stdout] at /rustc/b6ae10aa7319b7ebb6c7b3331dd71a9d6c9c5b4b/library/std/src/io/mod.rs:639:11 [INFO] [stdout] 7: 0x5c222065f823 - std::io::Write::write_fmt::hf4539125c0e0bc30 [INFO] [stdout] at /rustc/b6ae10aa7319b7ebb6c7b3331dd71a9d6c9c5b4b/library/std/src/io/mod.rs:1954:13 [INFO] [stdout] 8: 0x5c2220673022 - std::sys::backtrace::BacktraceLock::print::h58d5d73f9e953cf1 [INFO] [stdout] at /rustc/b6ae10aa7319b7ebb6c7b3331dd71a9d6c9c5b4b/library/std/src/sys/backtrace.rs:42:9 [INFO] [stdout] 9: 0x5c222065de7c - std::panicking::default_hook::{{closure}}::h1457fbe47c9457d1 [INFO] [stdout] at /rustc/b6ae10aa7319b7ebb6c7b3331dd71a9d6c9c5b4b/library/std/src/panicking.rs:301:27 [INFO] [stdout] 10: 0x5c222065dcf4 - std::panicking::default_hook::hccb5e73b206c0830 [INFO] [stdout] at /rustc/b6ae10aa7319b7ebb6c7b3331dd71a9d6c9c5b4b/library/std/src/panicking.rs:325:9 [INFO] [stdout] 11: 0x5c22205fe00e - as core::ops::function::Fn>::call::h04940a86aa899793 [INFO] [stdout] at /rustc/b6ae10aa7319b7ebb6c7b3331dd71a9d6c9c5b4b/library/alloc/src/boxed.rs:1985:9 [INFO] [stdout] 12: 0x5c22205fe00e - test::test_main_with_exit_callback::{{closure}}::hca6cc295403b83e8 [INFO] [stdout] at /rustc/b6ae10aa7319b7ebb6c7b3331dd71a9d6c9c5b4b/library/test/src/lib.rs:145:21 [INFO] [stdout] 13: 0x5c222065ead3 - as core::ops::function::Fn>::call::h9d85285925beb538 [INFO] [stdout] at /rustc/b6ae10aa7319b7ebb6c7b3331dd71a9d6c9c5b4b/library/alloc/src/boxed.rs:1985:9 [INFO] [stdout] 14: 0x5c222065ead3 - std::panicking::panic_with_hook::h3190ecc6229cdd29 [INFO] [stdout] at /rustc/b6ae10aa7319b7ebb6c7b3331dd71a9d6c9c5b4b/library/std/src/panicking.rs:842:13 [INFO] [stdout] 15: 0x5c2220672e2a - std::panicking::panic_handler::{{closure}}::ha1f1b769bc2bb40c [INFO] [stdout] at /rustc/b6ae10aa7319b7ebb6c7b3331dd71a9d6c9c5b4b/library/std/src/panicking.rs:707:13 [INFO] [stdout] 16: 0x5c2220672d89 - std::sys::backtrace::__rust_end_short_backtrace::h5f9cf66f19c2a172 [INFO] [stdout] at /rustc/b6ae10aa7319b7ebb6c7b3331dd71a9d6c9c5b4b/library/std/src/sys/backtrace.rs:174:18 [INFO] [stdout] 17: 0x5c222065e77d - __rustc[a93bd50104b99ad4]::rust_begin_unwind [INFO] [stdout] at /rustc/b6ae10aa7319b7ebb6c7b3331dd71a9d6c9c5b4b/library/std/src/panicking.rs:698:5 [INFO] [stdout] 18: 0x5c222069fa80 - core::panicking::panic_fmt::hc70c3c83f13c1375 [INFO] [stdout] at /rustc/b6ae10aa7319b7ebb6c7b3331dd71a9d6c9c5b4b/library/core/src/panicking.rs:75:14 [INFO] [stdout] 19: 0x5c222069fb70 - core::panicking::assert_failed_inner::hb61150608d983f16 [INFO] [stdout] at /rustc/b6ae10aa7319b7ebb6c7b3331dd71a9d6c9c5b4b/library/core/src/panicking.rs:448:17 [INFO] [stdout] 20: 0x5c22205df62b - core::panicking::assert_failed::hb95e2f089b546fc8 [INFO] [stdout] at /rustc/b6ae10aa7319b7ebb6c7b3331dd71a9d6c9c5b4b/library/core/src/panicking.rs:403:5 [INFO] [stdout] 21: 0x5c22205ca992 - stream_limiter::tests::write::write_timeout::h8633dabd2dbc5cd0 [INFO] [stdout] at /opt/rustwide/workdir/src/tests/write.rs:174:5 [INFO] [stdout] 22: 0x5c22205cd6e7 - stream_limiter::tests::write::write_timeout::{{closure}}::haf11456bbbc106cb [INFO] [stdout] at /opt/rustwide/workdir/src/tests/write.rs:164:19 [INFO] [stdout] 23: 0x5c22205da066 - core::ops::function::FnOnce::call_once::h0304fe760a998b76 [INFO] [stdout] at /rustc/b6ae10aa7319b7ebb6c7b3331dd71a9d6c9c5b4b/library/core/src/ops/function.rs:253:5 [INFO] [stdout] 24: 0x5c22205ff08b - core::ops::function::FnOnce::call_once::h70d190c46966003b [INFO] [stdout] at /rustc/b6ae10aa7319b7ebb6c7b3331dd71a9d6c9c5b4b/library/core/src/ops/function.rs:253:5 [INFO] [stdout] 25: 0x5c22205ff08b - test::__rust_begin_short_backtrace::hb077ccaabb17d2ec [INFO] [stdout] at /rustc/b6ae10aa7319b7ebb6c7b3331dd71a9d6c9c5b4b/library/test/src/lib.rs:663:18 [INFO] [stdout] 26: 0x5c22205fccf5 - test::run_test_in_process::{{closure}}::hd73eeb633a1507bd [INFO] [stdout] at /rustc/b6ae10aa7319b7ebb6c7b3331dd71a9d6c9c5b4b/library/test/src/lib.rs:686:74 [INFO] [stdout] 27: 0x5c22205fccf5 - as core::ops::function::FnOnce<()>>::call_once::h033c82756fc9d192 [INFO] [stdout] at /rustc/b6ae10aa7319b7ebb6c7b3331dd71a9d6c9c5b4b/library/core/src/panic/unwind_safe.rs:272:9 [INFO] [stdout] 28: 0x5c22205fccf5 - std::panicking::catch_unwind::do_call::h734b44e243d34585 [INFO] [stdout] at /rustc/b6ae10aa7319b7ebb6c7b3331dd71a9d6c9c5b4b/library/std/src/panicking.rs:590:40 [INFO] [stdout] 29: 0x5c22205fccf5 - std::panicking::catch_unwind::h5e85417122517fb1 [INFO] [stdout] at /rustc/b6ae10aa7319b7ebb6c7b3331dd71a9d6c9c5b4b/library/std/src/panicking.rs:553:19 [INFO] [stdout] 30: 0x5c22205fccf5 - std::panic::catch_unwind::h0900e58a82a7d188 [INFO] [stdout] at /rustc/b6ae10aa7319b7ebb6c7b3331dd71a9d6c9c5b4b/library/std/src/panic.rs:359:14 [INFO] [stdout] 31: 0x5c22205fccf5 - test::run_test_in_process::h27721efd62ae77d0 [INFO] [stdout] at /rustc/b6ae10aa7319b7ebb6c7b3331dd71a9d6c9c5b4b/library/test/src/lib.rs:686:27 [INFO] [stdout] 32: 0x5c22205fccf5 - test::run_test::{{closure}}::h8b1264838cc597aa [INFO] [stdout] at /rustc/b6ae10aa7319b7ebb6c7b3331dd71a9d6c9c5b4b/library/test/src/lib.rs:607:43 [INFO] [stdout] 33: 0x5c2220623c04 - test::run_test::{{closure}}::h6ecb96835e2eae4f [INFO] [stdout] at /rustc/b6ae10aa7319b7ebb6c7b3331dd71a9d6c9c5b4b/library/test/src/lib.rs:637:41 [INFO] [stdout] 34: 0x5c2220623c04 - std::sys::backtrace::__rust_begin_short_backtrace::h098f38ee82a820a7 [INFO] [stdout] at /rustc/b6ae10aa7319b7ebb6c7b3331dd71a9d6c9c5b4b/library/std/src/sys/backtrace.rs:158:18 [INFO] [stdout] 35: 0x5c222060a81a - std::thread::Builder::spawn_unchecked_::{{closure}}::{{closure}}::h042592f748f5e369 [INFO] [stdout] at /rustc/b6ae10aa7319b7ebb6c7b3331dd71a9d6c9c5b4b/library/std/src/thread/mod.rs:559:17 [INFO] [stdout] 36: 0x5c222060a81a - as core::ops::function::FnOnce<()>>::call_once::h134fe7f3a2d9ec8d [INFO] [stdout] at /rustc/b6ae10aa7319b7ebb6c7b3331dd71a9d6c9c5b4b/library/core/src/panic/unwind_safe.rs:272:9 [INFO] [stdout] 37: 0x5c222060a81a - std::panicking::catch_unwind::do_call::heb87d604f60aee16 [INFO] [stdout] at /rustc/b6ae10aa7319b7ebb6c7b3331dd71a9d6c9c5b4b/library/std/src/panicking.rs:590:40 [INFO] [stdout] 38: 0x5c222060a81a - std::panicking::catch_unwind::h2203a0f553b89fd7 [INFO] [stdout] at /rustc/b6ae10aa7319b7ebb6c7b3331dd71a9d6c9c5b4b/library/std/src/panicking.rs:553:19 [INFO] [stdout] 39: 0x5c222060a81a - std::panic::catch_unwind::h4d383e0b0e25d838 [INFO] [stdout] at /rustc/b6ae10aa7319b7ebb6c7b3331dd71a9d6c9c5b4b/library/std/src/panic.rs:359:14 [INFO] [stdout] 40: 0x5c222060a81a - std::thread::Builder::spawn_unchecked_::{{closure}}::h76cbdd4635cfd58b [INFO] [stdout] at /rustc/b6ae10aa7319b7ebb6c7b3331dd71a9d6c9c5b4b/library/std/src/thread/mod.rs:557:30 [INFO] [stdout] 41: 0x5c222060a81a - core::ops::function::FnOnce::call_once{{vtable.shim}}::he72e066680f5e267 [INFO] [stdout] at /rustc/b6ae10aa7319b7ebb6c7b3331dd71a9d6c9c5b4b/library/core/src/ops/function.rs:253:5 [INFO] [stdout] 42: 0x5c222064894f - as core::ops::function::FnOnce>::call_once::h1b9c6fea2cbefc68 [INFO] [stdout] at /rustc/b6ae10aa7319b7ebb6c7b3331dd71a9d6c9c5b4b/library/alloc/src/boxed.rs:1971:9 [INFO] [stdout] 43: 0x5c222064894f - std::sys::pal::unix::thread::Thread::new::thread_start::h92caa992bc760789 [INFO] [stdout] at /rustc/b6ae10aa7319b7ebb6c7b3331dd71a9d6c9c5b4b/library/std/src/sys/pal/unix/thread.rs:107:17 [INFO] [stdout] 44: 0x77f29130baa4 - [INFO] [stdout] 45: 0x77f291398a34 - clone [INFO] [stdout] 46: 0x0 - [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] failures: [INFO] [stdout] tests::parametric::test_buffer [INFO] [stdout] tests::parametric::test_tcp [INFO] [stdout] tests::write::write_timeout [INFO] [stdout] [INFO] [stdout] test result: FAILED. 24 passed; 3 failed; 0 ignored; 0 measured; 0 filtered out; finished in 11.92s [INFO] [stdout] [INFO] [stderr] error: test failed, to rerun pass `--lib` [INFO] running `Command { std: "docker" "inspect" "7d45a82ad7d4e49085255db2d833c07af3ef225218e7cd6b68d8c9e2141aa35e", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "7d45a82ad7d4e49085255db2d833c07af3ef225218e7cd6b68d8c9e2141aa35e", kill_on_drop: false }` [INFO] [stdout] 7d45a82ad7d4e49085255db2d833c07af3ef225218e7cd6b68d8c9e2141aa35e