[INFO] cloning repository https://github.com/florentw/erosion [INFO] running `Command { std: "git" "-c" "credential.helper=" "-c" "credential.helper=/workspace/cargo-home/bin/git-credential-null" "clone" "--bare" "https://github.com/florentw/erosion" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fflorentw%2Ferosion", kill_on_drop: false }` [INFO] [stderr] Cloning into bare repository '/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fflorentw%2Ferosion'... [INFO] running `Command { std: "git" "rev-parse" "HEAD", kill_on_drop: false }` [INFO] [stdout] 350f1fb30b2bf671277850f9b72330d27248fddd [INFO] testing florentw/erosion against master#23949593103d03a82c7ebc2e84434ce6c6edc561 for pr-115235-1 [INFO] running `Command { std: "git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fflorentw%2Ferosion" "/workspace/builds/worker-7-tc1/source", kill_on_drop: false }` [INFO] [stderr] Cloning into '/workspace/builds/worker-7-tc1/source'... [INFO] [stderr] done. [INFO] validating manifest of git repo https://github.com/florentw/erosion on toolchain 23949593103d03a82c7ebc2e84434ce6c6edc561 [INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+23949593103d03a82c7ebc2e84434ce6c6edc561" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }` [INFO] started tweaking git repo https://github.com/florentw/erosion [INFO] finished tweaking git repo https://github.com/florentw/erosion [INFO] tweaked toml for git repo https://github.com/florentw/erosion written to /workspace/builds/worker-7-tc1/source/Cargo.toml [INFO] crate git repo https://github.com/florentw/erosion 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" "+23949593103d03a82c7ebc2e84434ce6c6edc561" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }` [INFO] [stderr] Blocking waiting for file lock on package cache [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-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:4f8da3c744d2da974e54ce020a79f4ad54313e914ebeaab8fc086f07e8d8b066" "/opt/rustwide/cargo-home/bin/cargo" "+23949593103d03a82c7ebc2e84434ce6c6edc561" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }` [INFO] [stdout] 39247394cdb72c1f8a3079d6d5b098ec73b6ba3ed4291edb2b989103881b1cc4 [INFO] running `Command { std: "docker" "start" "-a" "39247394cdb72c1f8a3079d6d5b098ec73b6ba3ed4291edb2b989103881b1cc4", kill_on_drop: false }` [INFO] running `Command { std: "docker" "inspect" "39247394cdb72c1f8a3079d6d5b098ec73b6ba3ed4291edb2b989103881b1cc4", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "39247394cdb72c1f8a3079d6d5b098ec73b6ba3ed4291edb2b989103881b1cc4", kill_on_drop: false }` [INFO] [stdout] 39247394cdb72c1f8a3079d6d5b098ec73b6ba3ed4291edb2b989103881b1cc4 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:4f8da3c744d2da974e54ce020a79f4ad54313e914ebeaab8fc086f07e8d8b066" "/opt/rustwide/cargo-home/bin/cargo" "+23949593103d03a82c7ebc2e84434ce6c6edc561" "build" "--frozen" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] 98d854fdd5dfd1f35b2c32b311dd891b6809d3b9156bb7bc63a695ad9a0b2a92 [INFO] running `Command { std: "docker" "start" "-a" "98d854fdd5dfd1f35b2c32b311dd891b6809d3b9156bb7bc63a695ad9a0b2a92", kill_on_drop: false }` [INFO] [stderr] Compiling erosion v0.1.0 (/opt/rustwide/workdir) [INFO] [stdout] warning: variable does not need to be mutable [INFO] [stdout] --> src/throughput/mod.rs:49:9 [INFO] [stdout] | [INFO] [stdout] 49 | let mut running = true; [INFO] [stdout] | ----^^^^^^^ [INFO] [stdout] | | [INFO] [stdout] | help: remove this `mut` [INFO] [stdout] | [INFO] [stdout] = note: `#[warn(unused_mut)]` on by default [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] warning: 1 warning emitted [INFO] [stdout] [INFO] [stdout] [INFO] [stderr] Finished dev [unoptimized + debuginfo] target(s) in 0.27s [INFO] running `Command { std: "docker" "inspect" "98d854fdd5dfd1f35b2c32b311dd891b6809d3b9156bb7bc63a695ad9a0b2a92", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "98d854fdd5dfd1f35b2c32b311dd891b6809d3b9156bb7bc63a695ad9a0b2a92", kill_on_drop: false }` [INFO] [stdout] 98d854fdd5dfd1f35b2c32b311dd891b6809d3b9156bb7bc63a695ad9a0b2a92 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:4f8da3c744d2da974e54ce020a79f4ad54313e914ebeaab8fc086f07e8d8b066" "/opt/rustwide/cargo-home/bin/cargo" "+23949593103d03a82c7ebc2e84434ce6c6edc561" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] 0e4f609b83dcbb9e2eefa46a0cf9636eae5367a738f3a854a9aed8a1aa15741d [INFO] running `Command { std: "docker" "start" "-a" "0e4f609b83dcbb9e2eefa46a0cf9636eae5367a738f3a854a9aed8a1aa15741d", kill_on_drop: false }` [INFO] [stderr] Compiling erosion v0.1.0 (/opt/rustwide/workdir) [INFO] [stdout] warning: variable does not need to be mutable [INFO] [stdout] --> src/throughput/mod.rs:49:9 [INFO] [stdout] | [INFO] [stdout] 49 | let mut running = true; [INFO] [stdout] | ----^^^^^^^ [INFO] [stdout] | | [INFO] [stdout] | help: remove this `mut` [INFO] [stdout] | [INFO] [stdout] = note: `#[warn(unused_mut)]` on by default [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] warning: 1 warning emitted [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] warning: variable does not need to be mutable [INFO] [stdout] --> src/throughput/mod.rs:49:9 [INFO] [stdout] | [INFO] [stdout] 49 | let mut running = true; [INFO] [stdout] | ----^^^^^^^ [INFO] [stdout] | | [INFO] [stdout] | help: remove this `mut` [INFO] [stdout] | [INFO] [stdout] = note: `#[warn(unused_mut)]` on by default [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] warning: 1 warning emitted [INFO] [stdout] [INFO] [stdout] [INFO] [stderr] Finished test [unoptimized + debuginfo] target(s) in 0.60s [INFO] running `Command { std: "docker" "inspect" "0e4f609b83dcbb9e2eefa46a0cf9636eae5367a738f3a854a9aed8a1aa15741d", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "0e4f609b83dcbb9e2eefa46a0cf9636eae5367a738f3a854a9aed8a1aa15741d", kill_on_drop: false }` [INFO] [stdout] 0e4f609b83dcbb9e2eefa46a0cf9636eae5367a738f3a854a9aed8a1aa15741d [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:4f8da3c744d2da974e54ce020a79f4ad54313e914ebeaab8fc086f07e8d8b066" "/opt/rustwide/cargo-home/bin/cargo" "+23949593103d03a82c7ebc2e84434ce6c6edc561" "test" "--frozen", kill_on_drop: false }` [INFO] [stdout] d07a9f1f464c6f0732248947a8ad8f01d956a6058075c016636aa92eee956af4 [INFO] running `Command { std: "docker" "start" "-a" "d07a9f1f464c6f0732248947a8ad8f01d956a6058075c016636aa92eee956af4", kill_on_drop: false }` [INFO] [stderr] warning: variable does not need to be mutable [INFO] [stderr] --> src/throughput/mod.rs:49:9 [INFO] [stderr] | [INFO] [stderr] 49 | let mut running = true; [INFO] [stderr] | ----^^^^^^^ [INFO] [stderr] | | [INFO] [stderr] | help: remove this `mut` [INFO] [stderr] | [INFO] [stderr] = note: `#[warn(unused_mut)]` on by default [INFO] [stderr] [INFO] [stderr] warning: `erosion` (lib) generated 1 warning (run `cargo fix --lib -p erosion` to apply 1 suggestion) [INFO] [stderr] warning: `erosion` (lib test) generated 1 warning (1 duplicate) [INFO] [stderr] Finished test [unoptimized + debuginfo] target(s) in 0.01s [INFO] [stderr] Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/erosion-d4618962ff9bfb94) [INFO] [stdout] [INFO] [stdout] running 10 tests [INFO] [stdout] test throughput::tests::achieved_throughput_returns_minus_one_when_elapsed_is_zero ... ok [INFO] [stdout] test throughput::tests::remaining_sleep_duration_returns_zero_when_start_is_in_the_future ... ok [INFO] [stdout] test throughput::tests::should_add_event_from_remainder_returns_false_when_index_is_outside_remainder_interval ... ok [INFO] [stdout] test throughput::tests::sleep_if_too_fast_returns_false_if_duration_zero ... ok [INFO] [stdout] test throughput::tests::should_add_event_from_remainder_returns_true_when_index_is_in_remainder_interval ... ok [INFO] [stdout] test throughput::tests::to_millis_converts_duration_to_integer ... ok [INFO] [stdout] test throughput::tests::achieved_throughput_returns_rounded_throughput ... ok [INFO] [stdout] test throughput::tests::sleep_if_too_fast_returns_true_if_duration_not_zero ... ok [INFO] [stdout] test throughput::tests::round_function_rounds_to_two_places ... ok [INFO] [stdout] test throughput::tests::should_add_event_from_remainder_returns_false_when_no_remainder ... ok [INFO] [stdout] [INFO] [stdout] test result: ok. 10 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s [INFO] [stdout] [INFO] [stderr] Running tests/mono_thread_inttest.rs (/opt/rustwide/target/debug/deps/mono_thread_inttest-72803145049daf3d) [INFO] [stdout] [INFO] [stdout] running 5 tests [INFO] [stdout] test throughput_generator_calls_event_function_thousands_times_a_sec ... ok [INFO] [stdout] test throughput_generator_calls_event_function_once_a_sec ... ok [INFO] [stdout] test throughput_generator_calls_event_function_hundred_times_a_sec ... FAILED [INFO] [stdout] test throughput_generator_calls_event_function_five_hundred_times_a_sec ... FAILED [INFO] [stdout] test throughput_generator_calls_event_function_three_hundred_times_a_sec ... FAILED [INFO] [stderr] error: test failed, to rerun pass `--test mono_thread_inttest` [INFO] [stdout] [INFO] [stdout] failures: [INFO] [stdout] [INFO] [stdout] ---- throughput_generator_calls_event_function_hundred_times_a_sec stdout ---- [INFO] [stdout] events_per_small_period: 100, events_remainder: 0, additional_event: 0, main_target_interval(s): 10, previous_sleep: 0ns [INFO] [stdout] avg_sleep: 949.238951ms, last_sleep:9.49238951s, to_sleep:949.238951ms [INFO] [stdout] events_per_small_period: 100, events_remainder: 0, additional_event: 0, main_target_interval(s): 10, previous_sleep: 9.49238951s [INFO] [stdout] avg_sleep: 967.50878ms, last_sleep:9.49238951s, to_sleep:985.77861ms [INFO] [stdout] events_per_small_period: 100, events_remainder: 0, additional_event: 0, main_target_interval(s): 10, previous_sleep: 9.49238951s [INFO] [stdout] avg_sleep: 965.788615ms, last_sleep:9.49238951s, to_sleep:982.33828ms [INFO] [stdout] events_per_small_period: 100, events_remainder: 0, additional_event: 0, main_target_interval(s): 10, previous_sleep: 9.49238951s [INFO] [stdout] avg_sleep: 965.777615ms, last_sleep:9.49238951s, to_sleep:982.31628ms [INFO] [stdout] events_per_small_period: 100, events_remainder: 0, additional_event: 0, main_target_interval(s): 10, previous_sleep: 9.49238951s [INFO] [stdout] avg_sleep: 965.694075ms, last_sleep:9.49238951s, to_sleep:982.1492ms [INFO] [stdout] events_per_small_period: 100, events_remainder: 0, additional_event: 0, main_target_interval(s): 10, previous_sleep: 9.49238951s [INFO] [stdout] avg_sleep: 965.74874ms, last_sleep:9.49238951s, to_sleep:982.25853ms [INFO] [stdout] events_per_small_period: 100, events_remainder: 0, additional_event: 0, main_target_interval(s): 10, previous_sleep: 9.49238951s [INFO] [stdout] avg_sleep: 962.536111ms, last_sleep:9.49238951s, to_sleep:975.833271ms [INFO] [stdout] events_per_small_period: 100, events_remainder: 0, additional_event: 0, main_target_interval(s): 10, previous_sleep: 9.49238951s [INFO] [stdout] avg_sleep: 963.6299ms, last_sleep:9.49238951s, to_sleep:978.02085ms [INFO] [stdout] events_per_small_period: 100, events_remainder: 0, additional_event: 0, main_target_interval(s): 10, previous_sleep: 9.49238951s [INFO] [stdout] avg_sleep: 964.843511ms, last_sleep:9.49238951s, to_sleep:980.448071ms [INFO] [stdout] events_per_small_period: 100, events_remainder: 0, additional_event: 0, main_target_interval(s): 10, previous_sleep: 9.49238951s [INFO] [stdout] avg_sleep: 965.626075ms, last_sleep:9.49238951s, to_sleep:982.0132ms [INFO] [stdout] Throughput: 100 event/s - Was too slow: true - Distance to target: 0 events/s - toSleep: 140.035362ms [INFO] [stdout] throughput_results: last sleep: 9.632424872s, distance: 0.0 [INFO] [stdout] thread 'throughput_generator_calls_event_function_hundred_times_a_sec' panicked at tests/mono_thread_inttest.rs:36:5: [INFO] [stdout] assertion failed: results.last_sleep > Duration::new(9, 700_000_000) [INFO] [stdout] stack backtrace: [INFO] [stdout] 0: 0x556c6424759c - std::backtrace_rs::backtrace::libunwind::trace::hc56bfd85f78f2ac1 [INFO] [stdout] at /rustc/23949593103d03a82c7ebc2e84434ce6c6edc561/library/std/src/../../backtrace/src/backtrace/libunwind.rs:93:5 [INFO] [stdout] 1: 0x556c6424759c - std::backtrace_rs::backtrace::trace_unsynchronized::h3a4cd0ee39c2ec51 [INFO] [stdout] at /rustc/23949593103d03a82c7ebc2e84434ce6c6edc561/library/std/src/../../backtrace/src/backtrace/mod.rs:66:5 [INFO] [stdout] 2: 0x556c6424759c - std::sys_common::backtrace::_print_fmt::h7ec874d1114e2ca4 [INFO] [stdout] at /rustc/23949593103d03a82c7ebc2e84434ce6c6edc561/library/std/src/sys_common/backtrace.rs:67:5 [INFO] [stdout] 3: 0x556c6424759c - ::fmt::h9409967845944e27 [INFO] [stdout] at /rustc/23949593103d03a82c7ebc2e84434ce6c6edc561/library/std/src/sys_common/backtrace.rs:44:22 [INFO] [stdout] 4: 0x556c642715bc - core::fmt::rt::Argument::fmt::h3548c9d6e288e4b2 [INFO] [stdout] at /rustc/23949593103d03a82c7ebc2e84434ce6c6edc561/library/core/src/fmt/rt.rs:138:9 [INFO] [stdout] 5: 0x556c642715bc - core::fmt::write::h31bd00fc0f85031b [INFO] [stdout] at /rustc/23949593103d03a82c7ebc2e84434ce6c6edc561/library/core/src/fmt/mod.rs:1094:21 [INFO] [stdout] 6: 0x556c6424461e - std::io::Write::write_fmt::hd97b56c28d49e8d5 [INFO] [stdout] at /rustc/23949593103d03a82c7ebc2e84434ce6c6edc561/library/std/src/io/mod.rs:1714:15 [INFO] [stdout] 7: 0x556c64247384 - std::sys_common::backtrace::_print::h8ab9a59ea8fc9a86 [INFO] [stdout] at /rustc/23949593103d03a82c7ebc2e84434ce6c6edc561/library/std/src/sys_common/backtrace.rs:47:5 [INFO] [stdout] 8: 0x556c64247384 - std::sys_common::backtrace::print::hfe7afa943e316a5b [INFO] [stdout] at /rustc/23949593103d03a82c7ebc2e84434ce6c6edc561/library/std/src/sys_common/backtrace.rs:34:9 [INFO] [stdout] 9: 0x556c6424934a - std::panicking::panic_hook_with_disk_dump::{{closure}}::hae52bf9a035c21dd [INFO] [stdout] at /rustc/23949593103d03a82c7ebc2e84434ce6c6edc561/library/std/src/panicking.rs:280:22 [INFO] [stdout] 10: 0x556c64248f0d - std::panicking::panic_hook_with_disk_dump::hb6c613677d0ff679 [INFO] [stdout] at /rustc/23949593103d03a82c7ebc2e84434ce6c6edc561/library/std/src/panicking.rs:311:9 [INFO] [stdout] 11: 0x556c6421741c - as core::ops::function::Fn>::call::h70da65d4ac9eaaa2 [INFO] [stdout] at /rustc/23949593103d03a82c7ebc2e84434ce6c6edc561/library/alloc/src/boxed.rs:2021:9 [INFO] [stdout] 12: 0x556c6421741c - test::test_main::{{closure}}::h436fe668754d7667 [INFO] [stdout] at /rustc/23949593103d03a82c7ebc2e84434ce6c6edc561/library/test/src/lib.rs:136:21 [INFO] [stdout] 13: 0x556c64249ad3 - as core::ops::function::Fn>::call::h98627c2cfbd34c2e [INFO] [stdout] at /rustc/23949593103d03a82c7ebc2e84434ce6c6edc561/library/alloc/src/boxed.rs:2021:9 [INFO] [stdout] 14: 0x556c64249ad3 - std::panicking::rust_panic_with_hook::hd82d7066cdf9e06f [INFO] [stdout] at /rustc/23949593103d03a82c7ebc2e84434ce6c6edc561/library/std/src/panicking.rs:757:13 [INFO] [stdout] 15: 0x556c64249806 - std::panicking::begin_panic_handler::{{closure}}::hf97c71166d409481 [INFO] [stdout] at /rustc/23949593103d03a82c7ebc2e84434ce6c6edc561/library/std/src/panicking.rs:623:13 [INFO] [stdout] 16: 0x556c64247ac6 - std::sys_common::backtrace::__rust_end_short_backtrace::haa22df65c16edaa5 [INFO] [stdout] at /rustc/23949593103d03a82c7ebc2e84434ce6c6edc561/library/std/src/sys_common/backtrace.rs:170:18 [INFO] [stdout] 17: 0x556c64249592 - rust_begin_unwind [INFO] [stdout] at /rustc/23949593103d03a82c7ebc2e84434ce6c6edc561/library/std/src/panicking.rs:619:5 [INFO] [stdout] 18: 0x556c641de555 - core::panicking::panic_fmt::h0e243d6d1945608c [INFO] [stdout] at /rustc/23949593103d03a82c7ebc2e84434ce6c6edc561/library/core/src/panicking.rs:72:14 [INFO] [stdout] 19: 0x556c641de5f3 - core::panicking::panic::h01bef10ebaa206d6 [INFO] [stdout] at /rustc/23949593103d03a82c7ebc2e84434ce6c6edc561/library/core/src/panicking.rs:127:5 [INFO] [stdout] 20: 0x556c641e377d - mono_thread_inttest::throughput_generator_calls_event_function_hundred_times_a_sec::h3305422aae3ce6cf [INFO] [stdout] at /opt/rustwide/workdir/tests/mono_thread_inttest.rs:36:5 [INFO] [stdout] 21: 0x556c641e36c7 - mono_thread_inttest::throughput_generator_calls_event_function_hundred_times_a_sec::{{closure}}::h8a9980999b936d19 [INFO] [stdout] at /opt/rustwide/workdir/tests/mono_thread_inttest.rs:32:68 [INFO] [stdout] 22: 0x556c641df576 - core::ops::function::FnOnce::call_once::h0baa36252429da72 [INFO] [stdout] at /rustc/23949593103d03a82c7ebc2e84434ce6c6edc561/library/core/src/ops/function.rs:250:5 [INFO] [stdout] 23: 0x556c6421c54f - core::ops::function::FnOnce::call_once::hf5a9c4e4ad819183 [INFO] [stdout] at /rustc/23949593103d03a82c7ebc2e84434ce6c6edc561/library/core/src/ops/function.rs:250:5 [INFO] [stdout] 24: 0x556c6421c54f - test::__rust_begin_short_backtrace::ha85af04d2b493cbd [INFO] [stdout] at /rustc/23949593103d03a82c7ebc2e84434ce6c6edc561/library/test/src/lib.rs:626:18 [INFO] [stdout] 25: 0x556c6421b53e - test::run_test_in_process::{{closure}}::h80bae6710d6987aa [INFO] [stdout] at /rustc/23949593103d03a82c7ebc2e84434ce6c6edc561/library/test/src/lib.rs:649:60 [INFO] [stdout] 26: 0x556c6421b53e - as core::ops::function::FnOnce<()>>::call_once::h1e8456d02dd6a27f [INFO] [stdout] at /rustc/23949593103d03a82c7ebc2e84434ce6c6edc561/library/core/src/panic/unwind_safe.rs:271:9 [INFO] [stdout] 27: 0x556c6421b53e - std::panicking::try::do_call::h70bdb29e697eb37f [INFO] [stdout] at /rustc/23949593103d03a82c7ebc2e84434ce6c6edc561/library/std/src/panicking.rs:526:40 [INFO] [stdout] 28: 0x556c6421b53e - std::panicking::try::hac0a5b5cab93a94f [INFO] [stdout] at /rustc/23949593103d03a82c7ebc2e84434ce6c6edc561/library/std/src/panicking.rs:490:19 [INFO] [stdout] 29: 0x556c6421b53e - std::panic::catch_unwind::h242aae5a8ee61348 [INFO] [stdout] at /rustc/23949593103d03a82c7ebc2e84434ce6c6edc561/library/std/src/panic.rs:142:14 [INFO] [stdout] 30: 0x556c6421b53e - test::run_test_in_process::hb95e3471d249e80a [INFO] [stdout] at /rustc/23949593103d03a82c7ebc2e84434ce6c6edc561/library/test/src/lib.rs:649:27 [INFO] [stdout] 31: 0x556c6421b53e - test::run_test::{{closure}}::he4945c759a6bb284 [INFO] [stdout] at /rustc/23949593103d03a82c7ebc2e84434ce6c6edc561/library/test/src/lib.rs:572:43 [INFO] [stdout] 32: 0x556c641e3df3 - test::run_test::{{closure}}::hef3ab237ad9dfdfa [INFO] [stdout] at /rustc/23949593103d03a82c7ebc2e84434ce6c6edc561/library/test/src/lib.rs:600:41 [INFO] [stdout] 33: 0x556c641e3df3 - std::sys_common::backtrace::__rust_begin_short_backtrace::hd6c3f5e9450d872a [INFO] [stdout] at /rustc/23949593103d03a82c7ebc2e84434ce6c6edc561/library/std/src/sys_common/backtrace.rs:154:18 [INFO] [stdout] 34: 0x556c641e8eeb - std::thread::Builder::spawn_unchecked_::{{closure}}::{{closure}}::h033a4dc4b24e2ae4 [INFO] [stdout] at /rustc/23949593103d03a82c7ebc2e84434ce6c6edc561/library/std/src/thread/mod.rs:529:17 [INFO] [stdout] 35: 0x556c641e8eeb - as core::ops::function::FnOnce<()>>::call_once::hf6ace37e8c04836b [INFO] [stdout] at /rustc/23949593103d03a82c7ebc2e84434ce6c6edc561/library/core/src/panic/unwind_safe.rs:271:9 [INFO] [stdout] 36: 0x556c641e8eeb - std::panicking::try::do_call::h873acc9efc68dde9 [INFO] [stdout] at /rustc/23949593103d03a82c7ebc2e84434ce6c6edc561/library/std/src/panicking.rs:526:40 [INFO] [stdout] 37: 0x556c641e8eeb - std::panicking::try::hf1c7b0cddd2a156c [INFO] [stdout] at /rustc/23949593103d03a82c7ebc2e84434ce6c6edc561/library/std/src/panicking.rs:490:19 [INFO] [stdout] 38: 0x556c641e8eeb - std::panic::catch_unwind::hd0800cc038b1a0e3 [INFO] [stdout] at /rustc/23949593103d03a82c7ebc2e84434ce6c6edc561/library/std/src/panic.rs:142:14 [INFO] [stdout] 39: 0x556c641e8eeb - std::thread::Builder::spawn_unchecked_::{{closure}}::hbd3f2581a283969b [INFO] [stdout] at /rustc/23949593103d03a82c7ebc2e84434ce6c6edc561/library/std/src/thread/mod.rs:528:30 [INFO] [stdout] 40: 0x556c641e8eeb - core::ops::function::FnOnce::call_once{{vtable.shim}}::h05a4474bc37631e7 [INFO] [stdout] at /rustc/23949593103d03a82c7ebc2e84434ce6c6edc561/library/core/src/ops/function.rs:250:5 [INFO] [stdout] 41: 0x556c6424e0e5 - as core::ops::function::FnOnce>::call_once::ha81bceedc5dd4605 [INFO] [stdout] at /rustc/23949593103d03a82c7ebc2e84434ce6c6edc561/library/alloc/src/boxed.rs:2007:9 [INFO] [stdout] 42: 0x556c6424e0e5 - as core::ops::function::FnOnce>::call_once::ha86fb738f82c9cc8 [INFO] [stdout] at /rustc/23949593103d03a82c7ebc2e84434ce6c6edc561/library/alloc/src/boxed.rs:2007:9 [INFO] [stdout] 43: 0x556c6424e0e5 - std::sys::unix::thread::Thread::new::thread_start::h2c80c44f64da2074 [INFO] [stdout] at /rustc/23949593103d03a82c7ebc2e84434ce6c6edc561/library/std/src/sys/unix/thread.rs:108:17 [INFO] [stdout] 44: 0x7fad55565b43 - [INFO] [stdout] 45: 0x7fad555f6bb4 - __clone [INFO] [stdout] 46: 0x0 - [INFO] [stdout] [INFO] [stdout] ---- throughput_generator_calls_event_function_five_hundred_times_a_sec stdout ---- [INFO] [stdout] events_per_small_period: 500, events_remainder: 0, additional_event: 0, main_target_interval(s): 10, previous_sleep: 0ns [INFO] [stdout] avg_sleep: 885.409092ms, last_sleep:8.85409092s, to_sleep:885.409092ms [INFO] [stdout] events_per_small_period: 500, events_remainder: 0, additional_event: 0, main_target_interval(s): 10, previous_sleep: 8.85409092s [INFO] [stdout] avg_sleep: 916.031101ms, last_sleep:8.85409092s, to_sleep:946.653111ms [INFO] [stdout] events_per_small_period: 500, events_remainder: 0, additional_event: 0, main_target_interval(s): 10, previous_sleep: 8.85409092s [INFO] [stdout] avg_sleep: 919.238356ms, last_sleep:8.85409092s, to_sleep:953.067621ms [INFO] [stdout] events_per_small_period: 500, events_remainder: 0, additional_event: 0, main_target_interval(s): 10, previous_sleep: 8.85409092s [INFO] [stdout] avg_sleep: 920.932696ms, last_sleep:8.85409092s, to_sleep:956.456301ms [INFO] [stdout] events_per_small_period: 500, events_remainder: 0, additional_event: 0, main_target_interval(s): 10, previous_sleep: 8.85409092s [INFO] [stdout] avg_sleep: 921.073576ms, last_sleep:8.85409092s, to_sleep:956.738061ms [INFO] [stdout] events_per_small_period: 500, events_remainder: 0, additional_event: 0, main_target_interval(s): 10, previous_sleep: 8.85409092s [INFO] [stdout] avg_sleep: 921.120896ms, last_sleep:8.85409092s, to_sleep:956.832701ms [INFO] [stdout] events_per_small_period: 500, events_remainder: 0, additional_event: 0, main_target_interval(s): 10, previous_sleep: 8.85409092s [INFO] [stdout] avg_sleep: 919.798766ms, last_sleep:8.85409092s, to_sleep:954.188441ms [INFO] [stdout] events_per_small_period: 500, events_remainder: 0, additional_event: 0, main_target_interval(s): 10, previous_sleep: 8.85409092s [INFO] [stdout] avg_sleep: 917.329351ms, last_sleep:8.85409092s, to_sleep:949.24961ms [INFO] [stdout] events_per_small_period: 500, events_remainder: 0, additional_event: 0, main_target_interval(s): 10, previous_sleep: 8.85409092s [INFO] [stdout] avg_sleep: 918.194066ms, last_sleep:8.85409092s, to_sleep:950.97904ms [INFO] [stdout] events_per_small_period: 500, events_remainder: 0, additional_event: 0, main_target_interval(s): 10, previous_sleep: 8.85409092s [INFO] [stdout] avg_sleep: 919.329716ms, last_sleep:8.85409092s, to_sleep:953.25034ms [INFO] [stdout] Throughput: 500 event/s - Was too slow: true - Distance to target: 0 events/s - toSleep: 303.384719ms [INFO] [stdout] throughput_results: last sleep: 9.157475639s, distance: 0.0 [INFO] [stdout] thread 'throughput_generator_calls_event_function_five_hundred_times_a_sec' panicked at tests/mono_thread_inttest.rs:52:5: [INFO] [stdout] assertion failed: results.last_sleep > Duration::new(9, 500_000_000) [INFO] [stdout] stack backtrace: [INFO] [stdout] 0: 0x556c6424759c - std::backtrace_rs::backtrace::libunwind::trace::hc56bfd85f78f2ac1 [INFO] [stdout] at /rustc/23949593103d03a82c7ebc2e84434ce6c6edc561/library/std/src/../../backtrace/src/backtrace/libunwind.rs:93:5 [INFO] [stdout] 1: 0x556c6424759c - std::backtrace_rs::backtrace::trace_unsynchronized::h3a4cd0ee39c2ec51 [INFO] [stdout] at /rustc/23949593103d03a82c7ebc2e84434ce6c6edc561/library/std/src/../../backtrace/src/backtrace/mod.rs:66:5 [INFO] [stdout] 2: 0x556c6424759c - std::sys_common::backtrace::_print_fmt::h7ec874d1114e2ca4 [INFO] [stdout] at /rustc/23949593103d03a82c7ebc2e84434ce6c6edc561/library/std/src/sys_common/backtrace.rs:67:5 [INFO] [stdout] 3: 0x556c6424759c - ::fmt::h9409967845944e27 [INFO] [stdout] at /rustc/23949593103d03a82c7ebc2e84434ce6c6edc561/library/std/src/sys_common/backtrace.rs:44:22 [INFO] [stdout] 4: 0x556c642715bc - core::fmt::rt::Argument::fmt::h3548c9d6e288e4b2 [INFO] [stdout] at /rustc/23949593103d03a82c7ebc2e84434ce6c6edc561/library/core/src/fmt/rt.rs:138:9 [INFO] [stdout] 5: 0x556c642715bc - core::fmt::write::h31bd00fc0f85031b [INFO] [stdout] at /rustc/23949593103d03a82c7ebc2e84434ce6c6edc561/library/core/src/fmt/mod.rs:1094:21 [INFO] [stdout] 6: 0x556c6424461e - std::io::Write::write_fmt::hd97b56c28d49e8d5 [INFO] [stdout] at /rustc/23949593103d03a82c7ebc2e84434ce6c6edc561/library/std/src/io/mod.rs:1714:15 [INFO] [stdout] 7: 0x556c64247384 - std::sys_common::backtrace::_print::h8ab9a59ea8fc9a86 [INFO] [stdout] at /rustc/23949593103d03a82c7ebc2e84434ce6c6edc561/library/std/src/sys_common/backtrace.rs:47:5 [INFO] [stdout] 8: 0x556c64247384 - std::sys_common::backtrace::print::hfe7afa943e316a5b [INFO] [stdout] at /rustc/23949593103d03a82c7ebc2e84434ce6c6edc561/library/std/src/sys_common/backtrace.rs:34:9 [INFO] [stdout] 9: 0x556c6424934a - std::panicking::panic_hook_with_disk_dump::{{closure}}::hae52bf9a035c21dd [INFO] [stdout] at /rustc/23949593103d03a82c7ebc2e84434ce6c6edc561/library/std/src/panicking.rs:280:22 [INFO] [stdout] 10: 0x556c64248f0d - std::panicking::panic_hook_with_disk_dump::hb6c613677d0ff679 [INFO] [stdout] at /rustc/23949593103d03a82c7ebc2e84434ce6c6edc561/library/std/src/panicking.rs:311:9 [INFO] [stdout] 11: 0x556c6421741c - as core::ops::function::Fn>::call::h70da65d4ac9eaaa2 [INFO] [stdout] at /rustc/23949593103d03a82c7ebc2e84434ce6c6edc561/library/alloc/src/boxed.rs:2021:9 [INFO] [stdout] 12: 0x556c6421741c - test::test_main::{{closure}}::h436fe668754d7667 [INFO] [stdout] at /rustc/23949593103d03a82c7ebc2e84434ce6c6edc561/library/test/src/lib.rs:136:21 [INFO] [stdout] 13: 0x556c64249ad3 - as core::ops::function::Fn>::call::h98627c2cfbd34c2e [INFO] [stdout] at /rustc/23949593103d03a82c7ebc2e84434ce6c6edc561/library/alloc/src/boxed.rs:2021:9 [INFO] [stdout] 14: 0x556c64249ad3 - std::panicking::rust_panic_with_hook::hd82d7066cdf9e06f [INFO] [stdout] at /rustc/23949593103d03a82c7ebc2e84434ce6c6edc561/library/std/src/panicking.rs:757:13 [INFO] [stdout] 15: 0x556c64249806 - std::panicking::begin_panic_handler::{{closure}}::hf97c71166d409481 [INFO] [stdout] at /rustc/23949593103d03a82c7ebc2e84434ce6c6edc561/library/std/src/panicking.rs:623:13 [INFO] [stdout] 16: 0x556c64247ac6 - std::sys_common::backtrace::__rust_end_short_backtrace::haa22df65c16edaa5 [INFO] [stdout] at /rustc/23949593103d03a82c7ebc2e84434ce6c6edc561/library/std/src/sys_common/backtrace.rs:170:18 [INFO] [stdout] 17: 0x556c64249592 - rust_begin_unwind [INFO] [stdout] at /rustc/23949593103d03a82c7ebc2e84434ce6c6edc561/library/std/src/panicking.rs:619:5 [INFO] [stdout] 18: 0x556c641de555 - core::panicking::panic_fmt::h0e243d6d1945608c [INFO] [stdout] at /rustc/23949593103d03a82c7ebc2e84434ce6c6edc561/library/core/src/panicking.rs:72:14 [INFO] [stdout] 19: 0x556c641de5f3 - core::panicking::panic::h01bef10ebaa206d6 [INFO] [stdout] at /rustc/23949593103d03a82c7ebc2e84434ce6c6edc561/library/core/src/panicking.rs:127:5 [INFO] [stdout] 20: 0x556c641e393d - mono_thread_inttest::throughput_generator_calls_event_function_five_hundred_times_a_sec::h683135dd2abc8dc1 [INFO] [stdout] at /opt/rustwide/workdir/tests/mono_thread_inttest.rs:52:5 [INFO] [stdout] 21: 0x556c641e3887 - mono_thread_inttest::throughput_generator_calls_event_function_five_hundred_times_a_sec::{{closure}}::h1ce0fc912e091805 [INFO] [stdout] at /opt/rustwide/workdir/tests/mono_thread_inttest.rs:48:73 [INFO] [stdout] 22: 0x556c641df6f6 - core::ops::function::FnOnce::call_once::he8ffb29981f05a84 [INFO] [stdout] at /rustc/23949593103d03a82c7ebc2e84434ce6c6edc561/library/core/src/ops/function.rs:250:5 [INFO] [stdout] 23: 0x556c6421c54f - core::ops::function::FnOnce::call_once::hf5a9c4e4ad819183 [INFO] [stdout] at /rustc/23949593103d03a82c7ebc2e84434ce6c6edc561/library/core/src/ops/function.rs:250:5 [INFO] [stdout] 24: 0x556c6421c54f - test::__rust_begin_short_backtrace::ha85af04d2b493cbd [INFO] [stdout] at /rustc/23949593103d03a82c7ebc2e84434ce6c6edc561/library/test/src/lib.rs:626:18 [INFO] [stdout] 25: 0x556c6421b53e - test::run_test_in_process::{{closure}}::h80bae6710d6987aa [INFO] [stdout] at /rustc/23949593103d03a82c7ebc2e84434ce6c6edc561/library/test/src/lib.rs:649:60 [INFO] [stdout] 26: 0x556c6421b53e - as core::ops::function::FnOnce<()>>::call_once::h1e8456d02dd6a27f [INFO] [stdout] at /rustc/23949593103d03a82c7ebc2e84434ce6c6edc561/library/core/src/panic/unwind_safe.rs:271:9 [INFO] [stdout] 27: 0x556c6421b53e - std::panicking::try::do_call::h70bdb29e697eb37f [INFO] [stdout] at /rustc/23949593103d03a82c7ebc2e84434ce6c6edc561/library/std/src/panicking.rs:526:40 [INFO] [stdout] 28: 0x556c6421b53e - std::panicking::try::hac0a5b5cab93a94f [INFO] [stdout] at /rustc/23949593103d03a82c7ebc2e84434ce6c6edc561/library/std/src/panicking.rs:490:19 [INFO] [stdout] 29: 0x556c6421b53e - std::panic::catch_unwind::h242aae5a8ee61348 [INFO] [stdout] at /rustc/23949593103d03a82c7ebc2e84434ce6c6edc561/library/std/src/panic.rs:142:14 [INFO] [stdout] 30: 0x556c6421b53e - test::run_test_in_process::hb95e3471d249e80a [INFO] [stdout] at /rustc/23949593103d03a82c7ebc2e84434ce6c6edc561/library/test/src/lib.rs:649:27 [INFO] [stdout] 31: 0x556c6421b53e - test::run_test::{{closure}}::he4945c759a6bb284 [INFO] [stdout] at /rustc/23949593103d03a82c7ebc2e84434ce6c6edc561/library/test/src/lib.rs:572:43 [INFO] [stdout] 32: 0x556c641e3df3 - test::run_test::{{closure}}::hef3ab237ad9dfdfa [INFO] [stdout] at /rustc/23949593103d03a82c7ebc2e84434ce6c6edc561/library/test/src/lib.rs:600:41 [INFO] [stdout] 33: 0x556c641e3df3 - std::sys_common::backtrace::__rust_begin_short_backtrace::hd6c3f5e9450d872a [INFO] [stdout] at /rustc/23949593103d03a82c7ebc2e84434ce6c6edc561/library/std/src/sys_common/backtrace.rs:154:18 [INFO] [stdout] 34: 0x556c641e8eeb - std::thread::Builder::spawn_unchecked_::{{closure}}::{{closure}}::h033a4dc4b24e2ae4 [INFO] [stdout] at /rustc/23949593103d03a82c7ebc2e84434ce6c6edc561/library/std/src/thread/mod.rs:529:17 [INFO] [stdout] 35: 0x556c641e8eeb - as core::ops::function::FnOnce<()>>::call_once::hf6ace37e8c04836b [INFO] [stdout] at /rustc/23949593103d03a82c7ebc2e84434ce6c6edc561/library/core/src/panic/unwind_safe.rs:271:9 [INFO] [stdout] 36: 0x556c641e8eeb - std::panicking::try::do_call::h873acc9efc68dde9 [INFO] [stdout] at /rustc/23949593103d03a82c7ebc2e84434ce6c6edc561/library/std/src/panicking.rs:526:40 [INFO] [stdout] 37: 0x556c641e8eeb - std::panicking::try::hf1c7b0cddd2a156c [INFO] [stdout] at /rustc/23949593103d03a82c7ebc2e84434ce6c6edc561/library/std/src/panicking.rs:490:19 [INFO] [stdout] 38: 0x556c641e8eeb - std::panic::catch_unwind::hd0800cc038b1a0e3 [INFO] [stdout] at /rustc/23949593103d03a82c7ebc2e84434ce6c6edc561/library/std/src/panic.rs:142:14 [INFO] [stdout] 39: 0x556c641e8eeb - std::thread::Builder::spawn_unchecked_::{{closure}}::hbd3f2581a283969b [INFO] [stdout] at /rustc/23949593103d03a82c7ebc2e84434ce6c6edc561/library/std/src/thread/mod.rs:528:30 [INFO] [stdout] 40: 0x556c641e8eeb - core::ops::function::FnOnce::call_once{{vtable.shim}}::h05a4474bc37631e7 [INFO] [stdout] at /rustc/23949593103d03a82c7ebc2e84434ce6c6edc561/library/core/src/ops/function.rs:250:5 [INFO] [stdout] 41: 0x556c6424e0e5 - as core::ops::function::FnOnce>::call_once::ha81bceedc5dd4605 [INFO] [stdout] at /rustc/23949593103d03a82c7ebc2e84434ce6c6edc561/library/alloc/src/boxed.rs:2007:9 [INFO] [stdout] 42: 0x556c6424e0e5 - as core::ops::function::FnOnce>::call_once::ha86fb738f82c9cc8 [INFO] [stdout] at /rustc/23949593103d03a82c7ebc2e84434ce6c6edc561/library/alloc/src/boxed.rs:2007:9 [INFO] [stdout] 43: 0x556c6424e0e5 - std::sys::unix::thread::Thread::new::thread_start::h2c80c44f64da2074 [INFO] [stdout] at /rustc/23949593103d03a82c7ebc2e84434ce6c6edc561/library/std/src/sys/unix/thread.rs:108:17 [INFO] [stdout] 44: 0x7fad55565b43 - [INFO] [stdout] 45: 0x7fad555f6bb4 - __clone [INFO] [stdout] 46: 0x0 - [INFO] [stdout] [INFO] [stdout] ---- throughput_generator_calls_event_function_three_hundred_times_a_sec stdout ---- [INFO] [stdout] events_per_small_period: 300, events_remainder: 0, additional_event: 0, main_target_interval(s): 10, previous_sleep: 0ns [INFO] [stdout] avg_sleep: 908.561132ms, last_sleep:9.08561132s, to_sleep:908.561132ms [INFO] [stdout] events_per_small_period: 300, events_remainder: 0, additional_event: 0, main_target_interval(s): 10, previous_sleep: 9.08561132s [INFO] [stdout] avg_sleep: 937.436376ms, last_sleep:9.08561132s, to_sleep:966.311621ms [INFO] [stdout] events_per_small_period: 300, events_remainder: 0, additional_event: 0, main_target_interval(s): 10, previous_sleep: 9.08561132s [INFO] [stdout] avg_sleep: 938.440216ms, last_sleep:9.08561132s, to_sleep:968.3193ms [INFO] [stdout] events_per_small_period: 300, events_remainder: 0, additional_event: 0, main_target_interval(s): 10, previous_sleep: 9.08561132s [INFO] [stdout] avg_sleep: 938.090951ms, last_sleep:9.08561132s, to_sleep:967.62077ms [INFO] [stdout] events_per_small_period: 300, events_remainder: 0, additional_event: 0, main_target_interval(s): 10, previous_sleep: 9.08561132s [INFO] [stdout] avg_sleep: 937.735921ms, last_sleep:9.08561132s, to_sleep:966.910711ms [INFO] [stdout] events_per_small_period: 300, events_remainder: 0, additional_event: 0, main_target_interval(s): 10, previous_sleep: 9.08561132s [INFO] [stdout] avg_sleep: 934.280121ms, last_sleep:9.08561132s, to_sleep:959.999111ms [INFO] [stdout] events_per_small_period: 300, events_remainder: 0, additional_event: 0, main_target_interval(s): 10, previous_sleep: 9.08561132s [INFO] [stdout] avg_sleep: 935.320106ms, last_sleep:9.08561132s, to_sleep:962.079081ms [INFO] [stdout] events_per_small_period: 300, events_remainder: 0, additional_event: 0, main_target_interval(s): 10, previous_sleep: 9.08561132s [INFO] [stdout] avg_sleep: 937.413921ms, last_sleep:9.08561132s, to_sleep:966.266711ms [INFO] [stdout] events_per_small_period: 300, events_remainder: 0, additional_event: 0, main_target_interval(s): 10, previous_sleep: 9.08561132s [INFO] [stdout] avg_sleep: 938.252821ms, last_sleep:9.08561132s, to_sleep:967.944511ms [INFO] [stdout] events_per_small_period: 300, events_remainder: 0, additional_event: 0, main_target_interval(s): 10, previous_sleep: 9.08561132s [INFO] [stdout] avg_sleep: 938.122221ms, last_sleep:9.08561132s, to_sleep:967.683311ms [INFO] [stdout] Throughput: 300 event/s - Was too slow: true - Distance to target: 0 events/s - toSleep: 257.06214ms [INFO] [stdout] throughput_results: last sleep: 9.34267346s, distance: 0.0 [INFO] [stdout] thread 'throughput_generator_calls_event_function_three_hundred_times_a_sec' panicked at tests/mono_thread_inttest.rs:44:5: [INFO] [stdout] assertion failed: results.last_sleep > Duration::new(9, 700_000_000) [INFO] [stdout] stack backtrace: [INFO] [stdout] 0: 0x556c6424759c - std::backtrace_rs::backtrace::libunwind::trace::hc56bfd85f78f2ac1 [INFO] [stdout] at /rustc/23949593103d03a82c7ebc2e84434ce6c6edc561/library/std/src/../../backtrace/src/backtrace/libunwind.rs:93:5 [INFO] [stdout] 1: 0x556c6424759c - std::backtrace_rs::backtrace::trace_unsynchronized::h3a4cd0ee39c2ec51 [INFO] [stdout] at /rustc/23949593103d03a82c7ebc2e84434ce6c6edc561/library/std/src/../../backtrace/src/backtrace/mod.rs:66:5 [INFO] [stdout] 2: 0x556c6424759c - std::sys_common::backtrace::_print_fmt::h7ec874d1114e2ca4 [INFO] [stdout] at /rustc/23949593103d03a82c7ebc2e84434ce6c6edc561/library/std/src/sys_common/backtrace.rs:67:5 [INFO] [stdout] 3: 0x556c6424759c - ::fmt::h9409967845944e27 [INFO] [stdout] at /rustc/23949593103d03a82c7ebc2e84434ce6c6edc561/library/std/src/sys_common/backtrace.rs:44:22 [INFO] [stdout] 4: 0x556c642715bc - core::fmt::rt::Argument::fmt::h3548c9d6e288e4b2 [INFO] [stdout] at /rustc/23949593103d03a82c7ebc2e84434ce6c6edc561/library/core/src/fmt/rt.rs:138:9 [INFO] [stdout] 5: 0x556c642715bc - core::fmt::write::h31bd00fc0f85031b [INFO] [stdout] at /rustc/23949593103d03a82c7ebc2e84434ce6c6edc561/library/core/src/fmt/mod.rs:1094:21 [INFO] [stdout] 6: 0x556c6424461e - std::io::Write::write_fmt::hd97b56c28d49e8d5 [INFO] [stdout] at /rustc/23949593103d03a82c7ebc2e84434ce6c6edc561/library/std/src/io/mod.rs:1714:15 [INFO] [stdout] 7: 0x556c64247384 - std::sys_common::backtrace::_print::h8ab9a59ea8fc9a86 [INFO] [stdout] at /rustc/23949593103d03a82c7ebc2e84434ce6c6edc561/library/std/src/sys_common/backtrace.rs:47:5 [INFO] [stdout] 8: 0x556c64247384 - std::sys_common::backtrace::print::hfe7afa943e316a5b [INFO] [stdout] at /rustc/23949593103d03a82c7ebc2e84434ce6c6edc561/library/std/src/sys_common/backtrace.rs:34:9 [INFO] [stdout] 9: 0x556c6424934a - std::panicking::panic_hook_with_disk_dump::{{closure}}::hae52bf9a035c21dd [INFO] [stdout] at /rustc/23949593103d03a82c7ebc2e84434ce6c6edc561/library/std/src/panicking.rs:280:22 [INFO] [stdout] 10: 0x556c64248f0d - std::panicking::panic_hook_with_disk_dump::hb6c613677d0ff679 [INFO] [stdout] at /rustc/23949593103d03a82c7ebc2e84434ce6c6edc561/library/std/src/panicking.rs:311:9 [INFO] [stdout] 11: 0x556c6421741c - as core::ops::function::Fn>::call::h70da65d4ac9eaaa2 [INFO] [stdout] at /rustc/23949593103d03a82c7ebc2e84434ce6c6edc561/library/alloc/src/boxed.rs:2021:9 [INFO] [stdout] 12: 0x556c6421741c - test::test_main::{{closure}}::h436fe668754d7667 [INFO] [stdout] at /rustc/23949593103d03a82c7ebc2e84434ce6c6edc561/library/test/src/lib.rs:136:21 [INFO] [stdout] 13: 0x556c64249ad3 - as core::ops::function::Fn>::call::h98627c2cfbd34c2e [INFO] [stdout] at /rustc/23949593103d03a82c7ebc2e84434ce6c6edc561/library/alloc/src/boxed.rs:2021:9 [INFO] [stdout] 14: 0x556c64249ad3 - std::panicking::rust_panic_with_hook::hd82d7066cdf9e06f [INFO] [stdout] at /rustc/23949593103d03a82c7ebc2e84434ce6c6edc561/library/std/src/panicking.rs:757:13 [INFO] [stdout] 15: 0x556c64249806 - std::panicking::begin_panic_handler::{{closure}}::hf97c71166d409481 [INFO] [stdout] at /rustc/23949593103d03a82c7ebc2e84434ce6c6edc561/library/std/src/panicking.rs:623:13 [INFO] [stdout] 16: 0x556c64247ac6 - std::sys_common::backtrace::__rust_end_short_backtrace::haa22df65c16edaa5 [INFO] [stdout] at /rustc/23949593103d03a82c7ebc2e84434ce6c6edc561/library/std/src/sys_common/backtrace.rs:170:18 [INFO] [stdout] 17: 0x556c64249592 - rust_begin_unwind [INFO] [stdout] at /rustc/23949593103d03a82c7ebc2e84434ce6c6edc561/library/std/src/panicking.rs:619:5 [INFO] [stdout] 18: 0x556c641de555 - core::panicking::panic_fmt::h0e243d6d1945608c [INFO] [stdout] at /rustc/23949593103d03a82c7ebc2e84434ce6c6edc561/library/core/src/panicking.rs:72:14 [INFO] [stdout] 19: 0x556c641de5f3 - core::panicking::panic::h01bef10ebaa206d6 [INFO] [stdout] at /rustc/23949593103d03a82c7ebc2e84434ce6c6edc561/library/core/src/panicking.rs:127:5 [INFO] [stdout] 20: 0x556c641e385d - mono_thread_inttest::throughput_generator_calls_event_function_three_hundred_times_a_sec::h080c86695308f02b [INFO] [stdout] at /opt/rustwide/workdir/tests/mono_thread_inttest.rs:44:5 [INFO] [stdout] 21: 0x556c641e37a7 - mono_thread_inttest::throughput_generator_calls_event_function_three_hundred_times_a_sec::{{closure}}::hbfc4dee20394135f [INFO] [stdout] at /opt/rustwide/workdir/tests/mono_thread_inttest.rs:40:74 [INFO] [stdout] 22: 0x556c641df5b6 - core::ops::function::FnOnce::call_once::h1bbd51f424a957d7 [INFO] [stdout] at /rustc/23949593103d03a82c7ebc2e84434ce6c6edc561/library/core/src/ops/function.rs:250:5 [INFO] [stdout] 23: 0x556c6421c54f - core::ops::function::FnOnce::call_once::hf5a9c4e4ad819183 [INFO] [stdout] at /rustc/23949593103d03a82c7ebc2e84434ce6c6edc561/library/core/src/ops/function.rs:250:5 [INFO] [stdout] 24: 0x556c6421c54f - test::__rust_begin_short_backtrace::ha85af04d2b493cbd [INFO] [stdout] at /rustc/23949593103d03a82c7ebc2e84434ce6c6edc561/library/test/src/lib.rs:626:18 [INFO] [stdout] 25: 0x556c6421b53e - test::run_test_in_process::{{closure}}::h80bae6710d6987aa [INFO] [stdout] at /rustc/23949593103d03a82c7ebc2e84434ce6c6edc561/library/test/src/lib.rs:649:60 [INFO] [stdout] 26: 0x556c6421b53e - as core::ops::function::FnOnce<()>>::call_once::h1e8456d02dd6a27f [INFO] [stdout] at /rustc/23949593103d03a82c7ebc2e84434ce6c6edc561/library/core/src/panic/unwind_safe.rs:271:9 [INFO] [stdout] 27: 0x556c6421b53e - std::panicking::try::do_call::h70bdb29e697eb37f [INFO] [stdout] at /rustc/23949593103d03a82c7ebc2e84434ce6c6edc561/library/std/src/panicking.rs:526:40 [INFO] [stdout] 28: 0x556c6421b53e - std::panicking::try::hac0a5b5cab93a94f [INFO] [stdout] at /rustc/23949593103d03a82c7ebc2e84434ce6c6edc561/library/std/src/panicking.rs:490:19 [INFO] [stdout] 29: 0x556c6421b53e - std::panic::catch_unwind::h242aae5a8ee61348 [INFO] [stdout] at /rustc/23949593103d03a82c7ebc2e84434ce6c6edc561/library/std/src/panic.rs:142:14 [INFO] [stdout] 30: 0x556c6421b53e - test::run_test_in_process::hb95e3471d249e80a [INFO] [stdout] at /rustc/23949593103d03a82c7ebc2e84434ce6c6edc561/library/test/src/lib.rs:649:27 [INFO] [stdout] 31: 0x556c6421b53e - test::run_test::{{closure}}::he4945c759a6bb284 [INFO] [stdout] at /rustc/23949593103d03a82c7ebc2e84434ce6c6edc561/library/test/src/lib.rs:572:43 [INFO] [stdout] 32: 0x556c641e3df3 - test::run_test::{{closure}}::hef3ab237ad9dfdfa [INFO] [stdout] at /rustc/23949593103d03a82c7ebc2e84434ce6c6edc561/library/test/src/lib.rs:600:41 [INFO] [stdout] 33: 0x556c641e3df3 - std::sys_common::backtrace::__rust_begin_short_backtrace::hd6c3f5e9450d872a [INFO] [stdout] at /rustc/23949593103d03a82c7ebc2e84434ce6c6edc561/library/std/src/sys_common/backtrace.rs:154:18 [INFO] [stdout] 34: 0x556c641e8eeb - std::thread::Builder::spawn_unchecked_::{{closure}}::{{closure}}::h033a4dc4b24e2ae4 [INFO] [stdout] at /rustc/23949593103d03a82c7ebc2e84434ce6c6edc561/library/std/src/thread/mod.rs:529:17 [INFO] [stdout] 35: 0x556c641e8eeb - as core::ops::function::FnOnce<()>>::call_once::hf6ace37e8c04836b [INFO] [stdout] at /rustc/23949593103d03a82c7ebc2e84434ce6c6edc561/library/core/src/panic/unwind_safe.rs:271:9 [INFO] [stdout] 36: 0x556c641e8eeb - std::panicking::try::do_call::h873acc9efc68dde9 [INFO] [stdout] at /rustc/23949593103d03a82c7ebc2e84434ce6c6edc561/library/std/src/panicking.rs:526:40 [INFO] [stdout] 37: 0x556c641e8eeb - std::panicking::try::hf1c7b0cddd2a156c [INFO] [stdout] at /rustc/23949593103d03a82c7ebc2e84434ce6c6edc561/library/std/src/panicking.rs:490:19 [INFO] [stdout] 38: 0x556c641e8eeb - std::panic::catch_unwind::hd0800cc038b1a0e3 [INFO] [stdout] at /rustc/23949593103d03a82c7ebc2e84434ce6c6edc561/library/std/src/panic.rs:142:14 [INFO] [stdout] 39: 0x556c641e8eeb - std::thread::Builder::spawn_unchecked_::{{closure}}::hbd3f2581a283969b [INFO] [stdout] at /rustc/23949593103d03a82c7ebc2e84434ce6c6edc561/library/std/src/thread/mod.rs:528:30 [INFO] [stdout] 40: 0x556c641e8eeb - core::ops::function::FnOnce::call_once{{vtable.shim}}::h05a4474bc37631e7 [INFO] [stdout] at /rustc/23949593103d03a82c7ebc2e84434ce6c6edc561/library/core/src/ops/function.rs:250:5 [INFO] [stdout] 41: 0x556c6424e0e5 - as core::ops::function::FnOnce>::call_once::ha81bceedc5dd4605 [INFO] [stdout] at /rustc/23949593103d03a82c7ebc2e84434ce6c6edc561/library/alloc/src/boxed.rs:2007:9 [INFO] [stdout] 42: 0x556c6424e0e5 - as core::ops::function::FnOnce>::call_once::ha86fb738f82c9cc8 [INFO] [stdout] at /rustc/23949593103d03a82c7ebc2e84434ce6c6edc561/library/alloc/src/boxed.rs:2007:9 [INFO] [stdout] 43: 0x556c6424e0e5 - std::sys::unix::thread::Thread::new::thread_start::h2c80c44f64da2074 [INFO] [stdout] at /rustc/23949593103d03a82c7ebc2e84434ce6c6edc561/library/std/src/sys/unix/thread.rs:108:17 [INFO] [stdout] 44: 0x7fad55565b43 - [INFO] [stdout] 45: 0x7fad555f6bb4 - __clone [INFO] [stdout] 46: 0x0 - [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] failures: [INFO] [stdout] throughput_generator_calls_event_function_five_hundred_times_a_sec [INFO] [stdout] throughput_generator_calls_event_function_hundred_times_a_sec [INFO] [stdout] throughput_generator_calls_event_function_three_hundred_times_a_sec [INFO] [stdout] [INFO] [stdout] test result: FAILED. 2 passed; 3 failed; 0 ignored; 0 measured; 0 filtered out; finished in 10.02s [INFO] [stdout] [INFO] running `Command { std: "docker" "inspect" "d07a9f1f464c6f0732248947a8ad8f01d956a6058075c016636aa92eee956af4", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "d07a9f1f464c6f0732248947a8ad8f01d956a6058075c016636aa92eee956af4", kill_on_drop: false }` [INFO] [stdout] d07a9f1f464c6f0732248947a8ad8f01d956a6058075c016636aa92eee956af4