[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/350f1fb30b2bf671277850f9b72330d27248fddd against beta-2024-09-05 for beta-1.82-1 [INFO] running `Command { std: "git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fflorentw%2Ferosion" "/workspace/builds/worker-2-tc2/source", kill_on_drop: false }` [INFO] [stderr] Cloning into '/workspace/builds/worker-2-tc2/source'... [INFO] [stderr] done. [INFO] validating manifest of git repo https://github.com/florentw/erosion on toolchain beta-2024-09-05 [INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+beta-2024-09-05" "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-2-tc2/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" "+beta-2024-09-05" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }` [INFO] [stderr] warning: no edition set: defaulting to the 2015 edition while the latest is 2021 [INFO] [stderr] Blocking waiting for file lock on package cache [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-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:96c0aa5895ecf336b730ef27bcdde6e21027415df19888aad3cf1448e5d65d90" "/opt/rustwide/cargo-home/bin/cargo" "+beta-2024-09-05" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }` [INFO] [stdout] 3fd7582baed80f6d3a8eb7417862332c7b85a8e4c5f411482f283d50bfe5d042 [INFO] running `Command { std: "docker" "start" "-a" "3fd7582baed80f6d3a8eb7417862332c7b85a8e4c5f411482f283d50bfe5d042", kill_on_drop: false }` [INFO] running `Command { std: "docker" "inspect" "3fd7582baed80f6d3a8eb7417862332c7b85a8e4c5f411482f283d50bfe5d042", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "3fd7582baed80f6d3a8eb7417862332c7b85a8e4c5f411482f283d50bfe5d042", kill_on_drop: false }` [INFO] [stdout] 3fd7582baed80f6d3a8eb7417862332c7b85a8e4c5f411482f283d50bfe5d042 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-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=warn" "-e" "RUSTDOCFLAGS=--cap-lints=warn" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:96c0aa5895ecf336b730ef27bcdde6e21027415df19888aad3cf1448e5d65d90" "/opt/rustwide/cargo-home/bin/cargo" "+beta-2024-09-05" "build" "--frozen" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] 42758ef97652ddacbbb3d932849c4c039ebd3547465d31432e9a597845eff832 [INFO] running `Command { std: "docker" "start" "-a" "42758ef97652ddacbbb3d932849c4c039ebd3547465d31432e9a597845eff832", kill_on_drop: false }` [INFO] [stderr] warning: no edition set: defaulting to the 2015 edition while the latest is 2021 [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` profile [unoptimized + debuginfo] target(s) in 0.05s [INFO] running `Command { std: "docker" "inspect" "42758ef97652ddacbbb3d932849c4c039ebd3547465d31432e9a597845eff832", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "42758ef97652ddacbbb3d932849c4c039ebd3547465d31432e9a597845eff832", kill_on_drop: false }` [INFO] [stdout] 42758ef97652ddacbbb3d932849c4c039ebd3547465d31432e9a597845eff832 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-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=warn" "-e" "RUSTDOCFLAGS=--cap-lints=warn" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:96c0aa5895ecf336b730ef27bcdde6e21027415df19888aad3cf1448e5d65d90" "/opt/rustwide/cargo-home/bin/cargo" "+beta-2024-09-05" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] a881ff3747de7567094b7aafe156f9a0f44bef0062130c4493fd6087c4b44f26 [INFO] running `Command { std: "docker" "start" "-a" "a881ff3747de7567094b7aafe156f9a0f44bef0062130c4493fd6087c4b44f26", kill_on_drop: false }` [INFO] [stderr] warning: no edition set: defaulting to the 2015 edition while the latest is 2021 [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` profile [unoptimized + debuginfo] target(s) in 0.45s [INFO] running `Command { std: "docker" "inspect" "a881ff3747de7567094b7aafe156f9a0f44bef0062130c4493fd6087c4b44f26", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "a881ff3747de7567094b7aafe156f9a0f44bef0062130c4493fd6087c4b44f26", kill_on_drop: false }` [INFO] [stdout] a881ff3747de7567094b7aafe156f9a0f44bef0062130c4493fd6087c4b44f26 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-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=warn" "-e" "RUSTDOCFLAGS=--cap-lints=warn" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:96c0aa5895ecf336b730ef27bcdde6e21027415df19888aad3cf1448e5d65d90" "/opt/rustwide/cargo-home/bin/cargo" "+beta-2024-09-05" "test" "--frozen", kill_on_drop: false }` [INFO] [stdout] 01535720fe4e285c56d74f278d67a1f4a46b221ba4b1a6a7a4973e31f35f429d [INFO] running `Command { std: "docker" "start" "-a" "01535720fe4e285c56d74f278d67a1f4a46b221ba4b1a6a7a4973e31f35f429d", kill_on_drop: false }` [INFO] [stderr] warning: no edition set: defaulting to the 2015 edition while the latest is 2021 [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` profile [unoptimized + debuginfo] target(s) in 0.01s [INFO] [stderr] Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/erosion-9f6898ff0d99e28f) [INFO] [stdout] [INFO] [stdout] running 10 tests [INFO] [stdout] test throughput::tests::achieved_throughput_returns_rounded_throughput ... ok [INFO] [stdout] test throughput::tests::achieved_throughput_returns_minus_one_when_elapsed_is_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_true_when_index_is_in_remainder_interval ... ok [INFO] [stdout] test throughput::tests::remaining_sleep_duration_returns_zero_when_start_is_in_the_future ... ok [INFO] [stdout] test throughput::tests::sleep_if_too_fast_returns_true_if_duration_not_zero ... 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_false_when_no_remainder ... ok [INFO] [stderr] Running tests/mono_thread_inttest.rs (/opt/rustwide/target/debug/deps/mono_thread_inttest-8d712782c518b0d3) [INFO] [stdout] test throughput::tests::to_millis_converts_duration_to_integer ... ok [INFO] [stdout] [INFO] [stdout] test result: ok. 10 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.04s [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] running 5 tests [INFO] [stdout] test throughput_generator_calls_event_function_once_a_sec ... ok [INFO] [stdout] test throughput_generator_calls_event_function_thousands_times_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] [stderr] error: test failed, to rerun pass `--test mono_thread_inttest` [INFO] [stdout] test throughput_generator_calls_event_function_three_hundred_times_a_sec ... FAILED [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: 781.684078ms, last_sleep:7.81684078s, to_sleep:781.684078ms [INFO] [stdout] events_per_small_period: 100, events_remainder: 0, additional_event: 0, main_target_interval(s): 10, previous_sleep: 7.81684078s [INFO] [stdout] avg_sleep: 881.132031ms, last_sleep:7.81684078s, to_sleep:980.579984ms [INFO] [stdout] events_per_small_period: 100, events_remainder: 0, additional_event: 0, main_target_interval(s): 10, previous_sleep: 7.81684078s [INFO] [stdout] avg_sleep: 882.130676ms, last_sleep:7.81684078s, to_sleep:982.577274ms [INFO] [stdout] events_per_small_period: 100, events_remainder: 0, additional_event: 0, main_target_interval(s): 10, previous_sleep: 7.81684078s [INFO] [stdout] avg_sleep: 882.33833ms, last_sleep:7.81684078s, to_sleep:982.992583ms [INFO] [stdout] events_per_small_period: 100, events_remainder: 0, additional_event: 0, main_target_interval(s): 10, previous_sleep: 7.81684078s [INFO] [stdout] avg_sleep: 882.37134ms, last_sleep:7.81684078s, to_sleep:983.058602ms [INFO] [stdout] events_per_small_period: 100, events_remainder: 0, additional_event: 0, main_target_interval(s): 10, previous_sleep: 7.81684078s [INFO] [stdout] avg_sleep: 881.67906ms, last_sleep:7.81684078s, to_sleep:981.674043ms [INFO] [stdout] events_per_small_period: 100, events_remainder: 0, additional_event: 0, main_target_interval(s): 10, previous_sleep: 7.81684078s [INFO] [stdout] avg_sleep: 877.731876ms, last_sleep:7.81684078s, to_sleep:973.779674ms [INFO] [stdout] events_per_small_period: 100, events_remainder: 0, additional_event: 0, main_target_interval(s): 10, previous_sleep: 7.81684078s [INFO] [stdout] avg_sleep: 882.137021ms, last_sleep:7.81684078s, to_sleep:982.589964ms [INFO] [stdout] events_per_small_period: 100, events_remainder: 0, additional_event: 0, main_target_interval(s): 10, previous_sleep: 7.81684078s [INFO] [stdout] avg_sleep: 882.116855ms, last_sleep:7.81684078s, to_sleep:982.549633ms [INFO] [stdout] events_per_small_period: 100, events_remainder: 0, additional_event: 0, main_target_interval(s): 10, previous_sleep: 7.81684078s [INFO] [stdout] avg_sleep: 882.151995ms, last_sleep:7.81684078s, to_sleep:982.619913ms [INFO] [stdout] Throughput: 100 event/s - Was too slow: true - Distance to target: 0 events/s - toSleep: 897.63304ms [INFO] [stdout] throughput_results: last sleep: 8.71447382s, 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: 0x62efb73005ea - std::backtrace_rs::backtrace::libunwind::trace::h9ce576133d3ff57c [INFO] [stdout] at /rustc/c7c49f44a7bb561dd9317e14908a1e50fa478ce5/library/std/src/../../backtrace/src/backtrace/libunwind.rs:116:5 [INFO] [stdout] 1: 0x62efb73005ea - std::backtrace_rs::backtrace::trace_unsynchronized::h77316b33bca73147 [INFO] [stdout] at /rustc/c7c49f44a7bb561dd9317e14908a1e50fa478ce5/library/std/src/../../backtrace/src/backtrace/mod.rs:66:5 [INFO] [stdout] 2: 0x62efb73005ea - std::sys::backtrace::_print_fmt::haa182cb1ad45bc9f [INFO] [stdout] at /rustc/c7c49f44a7bb561dd9317e14908a1e50fa478ce5/library/std/src/sys/backtrace.rs:66:9 [INFO] [stdout] 3: 0x62efb73005ea - ::fmt::ha95eeb186e57c055 [INFO] [stdout] at /rustc/c7c49f44a7bb561dd9317e14908a1e50fa478ce5/library/std/src/sys/backtrace.rs:39:26 [INFO] [stdout] 4: 0x62efb73267cb - core::fmt::rt::Argument::fmt::h71240792b23d8aa6 [INFO] [stdout] at /rustc/c7c49f44a7bb561dd9317e14908a1e50fa478ce5/library/core/src/fmt/rt.rs:177:76 [INFO] [stdout] 5: 0x62efb73267cb - core::fmt::write::h1c4d59c42453601a [INFO] [stdout] at /rustc/c7c49f44a7bb561dd9317e14908a1e50fa478ce5/library/core/src/fmt/mod.rs:1178:21 [INFO] [stdout] 6: 0x62efb72fd7d3 - std::io::Write::write_fmt::h08b6f2062a09bc68 [INFO] [stdout] at /rustc/c7c49f44a7bb561dd9317e14908a1e50fa478ce5/library/std/src/io/mod.rs:1823:15 [INFO] [stdout] 7: 0x62efb7301df2 - std::sys::backtrace::BacktraceLock::print::hb66718c44668eb56 [INFO] [stdout] at /rustc/c7c49f44a7bb561dd9317e14908a1e50fa478ce5/library/std/src/sys/backtrace.rs:42:9 [INFO] [stdout] 8: 0x62efb7301df2 - std::panicking::default_hook::{{closure}}::h1a6c150385aaf90f [INFO] [stdout] at /rustc/c7c49f44a7bb561dd9317e14908a1e50fa478ce5/library/std/src/panicking.rs:266:22 [INFO] [stdout] 9: 0x62efb7301933 - std::panicking::default_hook::h5e8a75844aa4ea98 [INFO] [stdout] at /rustc/c7c49f44a7bb561dd9317e14908a1e50fa478ce5/library/std/src/panicking.rs:290:9 [INFO] [stdout] 10: 0x62efb72ccd99 - as core::ops::function::Fn>::call::h6ee94b0b846f4546 [INFO] [stdout] at /rustc/c7c49f44a7bb561dd9317e14908a1e50fa478ce5/library/alloc/src/boxed.rs:2245:9 [INFO] [stdout] 11: 0x62efb72ccd99 - test::test_main::{{closure}}::hccc1c379f17b5ca1 [INFO] [stdout] at /rustc/c7c49f44a7bb561dd9317e14908a1e50fa478ce5/library/test/src/lib.rs:132:21 [INFO] [stdout] 12: 0x62efb73026b2 - as core::ops::function::Fn>::call::h8b15e23d52a5df89 [INFO] [stdout] at /rustc/c7c49f44a7bb561dd9317e14908a1e50fa478ce5/library/alloc/src/boxed.rs:2245:9 [INFO] [stdout] 13: 0x62efb73026b2 - std::panicking::rust_panic_with_hook::hea686c8750159a85 [INFO] [stdout] at /rustc/c7c49f44a7bb561dd9317e14908a1e50fa478ce5/library/std/src/panicking.rs:805:13 [INFO] [stdout] 14: 0x62efb7302333 - std::panicking::begin_panic_handler::{{closure}}::hfd69333fda29d50a [INFO] [stdout] at /rustc/c7c49f44a7bb561dd9317e14908a1e50fa478ce5/library/std/src/panicking.rs:664:13 [INFO] [stdout] 15: 0x62efb7300ac9 - std::sys::backtrace::__rust_end_short_backtrace::hb7c23c01c3c70e35 [INFO] [stdout] at /rustc/c7c49f44a7bb561dd9317e14908a1e50fa478ce5/library/std/src/sys/backtrace.rs:170:18 [INFO] [stdout] 16: 0x62efb7301ff4 - rust_begin_unwind [INFO] [stdout] at /rustc/c7c49f44a7bb561dd9317e14908a1e50fa478ce5/library/std/src/panicking.rs:662:5 [INFO] [stdout] 17: 0x62efb728eaa3 - core::panicking::panic_fmt::h43de95d595ebabc0 [INFO] [stdout] at /rustc/c7c49f44a7bb561dd9317e14908a1e50fa478ce5/library/core/src/panicking.rs:74:14 [INFO] [stdout] 18: 0x62efb728eb2c - core::panicking::panic::h7d15abfb2c709e2c [INFO] [stdout] at /rustc/c7c49f44a7bb561dd9317e14908a1e50fa478ce5/library/core/src/panicking.rs:148:5 [INFO] [stdout] 19: 0x62efb729490b - mono_thread_inttest::throughput_generator_calls_event_function_hundred_times_a_sec::h1f305173951ff6c9 [INFO] [stdout] at /opt/rustwide/workdir/tests/mono_thread_inttest.rs:36:5 [INFO] [stdout] 20: 0x62efb7294857 - mono_thread_inttest::throughput_generator_calls_event_function_hundred_times_a_sec::{{closure}}::hc6073a7e11abf408 [INFO] [stdout] at /opt/rustwide/workdir/tests/mono_thread_inttest.rs:32:67 [INFO] [stdout] 21: 0x62efb728fff6 - core::ops::function::FnOnce::call_once::ha9e64ce919279dbe [INFO] [stdout] at /rustc/c7c49f44a7bb561dd9317e14908a1e50fa478ce5/library/core/src/ops/function.rs:250:5 [INFO] [stdout] 22: 0x62efb72d170b - core::ops::function::FnOnce::call_once::h9b5e01539a2850c9 [INFO] [stdout] at /rustc/c7c49f44a7bb561dd9317e14908a1e50fa478ce5/library/core/src/ops/function.rs:250:5 [INFO] [stdout] 23: 0x62efb72d170b - test::__rust_begin_short_backtrace::hfa35fd69df7e11a6 [INFO] [stdout] at /rustc/c7c49f44a7bb561dd9317e14908a1e50fa478ce5/library/test/src/lib.rs:620:18 [INFO] [stdout] 24: 0x62efb72d0f88 - test::run_test_in_process::{{closure}}::he6acc04e3f33efba [INFO] [stdout] at /rustc/c7c49f44a7bb561dd9317e14908a1e50fa478ce5/library/test/src/lib.rs:643:60 [INFO] [stdout] 25: 0x62efb72d0f88 - as core::ops::function::FnOnce<()>>::call_once::h9ed0882d7a8705d4 [INFO] [stdout] at /rustc/c7c49f44a7bb561dd9317e14908a1e50fa478ce5/library/core/src/panic/unwind_safe.rs:272:9 [INFO] [stdout] 26: 0x62efb72d0f88 - std::panicking::try::do_call::hb4e1060b1206bf1d [INFO] [stdout] at /rustc/c7c49f44a7bb561dd9317e14908a1e50fa478ce5/library/std/src/panicking.rs:554:40 [INFO] [stdout] 27: 0x62efb72d0f88 - std::panicking::try::he7301d205c111379 [INFO] [stdout] at /rustc/c7c49f44a7bb561dd9317e14908a1e50fa478ce5/library/std/src/panicking.rs:518:19 [INFO] [stdout] 28: 0x62efb72d0f88 - std::panic::catch_unwind::h6c993e01d18171db [INFO] [stdout] at /rustc/c7c49f44a7bb561dd9317e14908a1e50fa478ce5/library/std/src/panic.rs:345:14 [INFO] [stdout] 29: 0x62efb72d0f88 - test::run_test_in_process::h39faa778a589c8b3 [INFO] [stdout] at /rustc/c7c49f44a7bb561dd9317e14908a1e50fa478ce5/library/test/src/lib.rs:643:27 [INFO] [stdout] 30: 0x62efb72d0f88 - test::run_test::{{closure}}::h59e32a442f6c7a1c [INFO] [stdout] at /rustc/c7c49f44a7bb561dd9317e14908a1e50fa478ce5/library/test/src/lib.rs:564:43 [INFO] [stdout] 31: 0x62efb7296354 - test::run_test::{{closure}}::h6615d2e57f7516c2 [INFO] [stdout] at /rustc/c7c49f44a7bb561dd9317e14908a1e50fa478ce5/library/test/src/lib.rs:594:41 [INFO] [stdout] 32: 0x62efb7296354 - std::sys::backtrace::__rust_begin_short_backtrace::h761da13b6d71bc61 [INFO] [stdout] at /rustc/c7c49f44a7bb561dd9317e14908a1e50fa478ce5/library/std/src/sys/backtrace.rs:154:18 [INFO] [stdout] 33: 0x62efb7299a02 - std::thread::Builder::spawn_unchecked_::{{closure}}::{{closure}}::haa82f2052ad87664 [INFO] [stdout] at /rustc/c7c49f44a7bb561dd9317e14908a1e50fa478ce5/library/std/src/thread/mod.rs:522:17 [INFO] [stdout] 34: 0x62efb7299a02 - as core::ops::function::FnOnce<()>>::call_once::h709672ecbcc3758b [INFO] [stdout] at /rustc/c7c49f44a7bb561dd9317e14908a1e50fa478ce5/library/core/src/panic/unwind_safe.rs:272:9 [INFO] [stdout] 35: 0x62efb7299a02 - std::panicking::try::do_call::hb005a67f6fd9e082 [INFO] [stdout] at /rustc/c7c49f44a7bb561dd9317e14908a1e50fa478ce5/library/std/src/panicking.rs:554:40 [INFO] [stdout] 36: 0x62efb7299a02 - std::panicking::try::hec02b24e35e6ab47 [INFO] [stdout] at /rustc/c7c49f44a7bb561dd9317e14908a1e50fa478ce5/library/std/src/panicking.rs:518:19 [INFO] [stdout] 37: 0x62efb7299a02 - std::panic::catch_unwind::h13617efa0948dabc [INFO] [stdout] at /rustc/c7c49f44a7bb561dd9317e14908a1e50fa478ce5/library/std/src/panic.rs:345:14 [INFO] [stdout] 38: 0x62efb7299a02 - std::thread::Builder::spawn_unchecked_::{{closure}}::hb4a56c6b32a8e045 [INFO] [stdout] at /rustc/c7c49f44a7bb561dd9317e14908a1e50fa478ce5/library/std/src/thread/mod.rs:521:30 [INFO] [stdout] 39: 0x62efb7299a02 - core::ops::function::FnOnce::call_once{{vtable.shim}}::h26d01bfaffaa945b [INFO] [stdout] at /rustc/c7c49f44a7bb561dd9317e14908a1e50fa478ce5/library/core/src/ops/function.rs:250:5 [INFO] [stdout] 40: 0x62efb73066cb - as core::ops::function::FnOnce>::call_once::hbe8e1ebf83e6794e [INFO] [stdout] at /rustc/c7c49f44a7bb561dd9317e14908a1e50fa478ce5/library/alloc/src/boxed.rs:2231:9 [INFO] [stdout] 41: 0x62efb73066cb - as core::ops::function::FnOnce>::call_once::h7d6ff770e6bfeff7 [INFO] [stdout] at /rustc/c7c49f44a7bb561dd9317e14908a1e50fa478ce5/library/alloc/src/boxed.rs:2231:9 [INFO] [stdout] 42: 0x62efb73066cb - std::sys::pal::unix::thread::Thread::new::thread_start::h68792b09799a7d87 [INFO] [stdout] at /rustc/c7c49f44a7bb561dd9317e14908a1e50fa478ce5/library/std/src/sys/pal/unix/thread.rs:105:17 [INFO] [stdout] 43: 0x7682b0862ac3 - [INFO] [stdout] 44: 0x7682b08f3a04 - clone [INFO] [stdout] 45: 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: 761.972361ms, last_sleep:7.61972361s, to_sleep:761.972361ms [INFO] [stdout] events_per_small_period: 500, events_remainder: 0, additional_event: 0, main_target_interval(s): 10, previous_sleep: 7.61972361s [INFO] [stdout] avg_sleep: 861.259364ms, last_sleep:7.61972361s, to_sleep:960.546367ms [INFO] [stdout] events_per_small_period: 500, events_remainder: 0, additional_event: 0, main_target_interval(s): 10, previous_sleep: 7.61972361s [INFO] [stdout] avg_sleep: 861.243454ms, last_sleep:7.61972361s, to_sleep:960.514547ms [INFO] [stdout] events_per_small_period: 500, events_remainder: 0, additional_event: 0, main_target_interval(s): 10, previous_sleep: 7.61972361s [INFO] [stdout] avg_sleep: 860.927219ms, last_sleep:7.61972361s, to_sleep:959.882077ms [INFO] [stdout] events_per_small_period: 500, events_remainder: 0, additional_event: 0, main_target_interval(s): 10, previous_sleep: 7.61972361s [INFO] [stdout] avg_sleep: 860.846949ms, last_sleep:7.61972361s, to_sleep:959.721537ms [INFO] [stdout] events_per_small_period: 500, events_remainder: 0, additional_event: 0, main_target_interval(s): 10, previous_sleep: 7.61972361s [INFO] [stdout] avg_sleep: 861.657659ms, last_sleep:7.61972361s, to_sleep:961.342957ms [INFO] [stdout] events_per_small_period: 500, events_remainder: 0, additional_event: 0, main_target_interval(s): 10, previous_sleep: 7.61972361s [INFO] [stdout] avg_sleep: 857.293504ms, last_sleep:7.61972361s, to_sleep:952.614648ms [INFO] [stdout] events_per_small_period: 500, events_remainder: 0, additional_event: 0, main_target_interval(s): 10, previous_sleep: 7.61972361s [INFO] [stdout] avg_sleep: 861.449334ms, last_sleep:7.61972361s, to_sleep:960.926307ms [INFO] [stdout] events_per_small_period: 500, events_remainder: 0, additional_event: 0, main_target_interval(s): 10, previous_sleep: 7.61972361s [INFO] [stdout] avg_sleep: 861.230188ms, last_sleep:7.61972361s, to_sleep:960.488016ms [INFO] [stdout] events_per_small_period: 500, events_remainder: 0, additional_event: 0, main_target_interval(s): 10, previous_sleep: 7.61972361s [INFO] [stdout] avg_sleep: 859.231144ms, last_sleep:7.61972361s, to_sleep:956.489927ms [INFO] [stdout] Throughput: 500 event/s - Was too slow: true - Distance to target: 0 events/s - toSleep: 886.364712ms [INFO] [stdout] throughput_results: last sleep: 8.506088322s, 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: 0x62efb73005ea - std::backtrace_rs::backtrace::libunwind::trace::h9ce576133d3ff57c [INFO] [stdout] at /rustc/c7c49f44a7bb561dd9317e14908a1e50fa478ce5/library/std/src/../../backtrace/src/backtrace/libunwind.rs:116:5 [INFO] [stdout] 1: 0x62efb73005ea - std::backtrace_rs::backtrace::trace_unsynchronized::h77316b33bca73147 [INFO] [stdout] at /rustc/c7c49f44a7bb561dd9317e14908a1e50fa478ce5/library/std/src/../../backtrace/src/backtrace/mod.rs:66:5 [INFO] [stdout] 2: 0x62efb73005ea - std::sys::backtrace::_print_fmt::haa182cb1ad45bc9f [INFO] [stdout] at /rustc/c7c49f44a7bb561dd9317e14908a1e50fa478ce5/library/std/src/sys/backtrace.rs:66:9 [INFO] [stdout] 3: 0x62efb73005ea - ::fmt::ha95eeb186e57c055 [INFO] [stdout] at /rustc/c7c49f44a7bb561dd9317e14908a1e50fa478ce5/library/std/src/sys/backtrace.rs:39:26 [INFO] [stdout] 4: 0x62efb73267cb - core::fmt::rt::Argument::fmt::h71240792b23d8aa6 [INFO] [stdout] at /rustc/c7c49f44a7bb561dd9317e14908a1e50fa478ce5/library/core/src/fmt/rt.rs:177:76 [INFO] [stdout] 5: 0x62efb73267cb - core::fmt::write::h1c4d59c42453601a [INFO] [stdout] at /rustc/c7c49f44a7bb561dd9317e14908a1e50fa478ce5/library/core/src/fmt/mod.rs:1178:21 [INFO] [stdout] 6: 0x62efb72fd7d3 - std::io::Write::write_fmt::h08b6f2062a09bc68 [INFO] [stdout] at /rustc/c7c49f44a7bb561dd9317e14908a1e50fa478ce5/library/std/src/io/mod.rs:1823:15 [INFO] [stdout] 7: 0x62efb7301df2 - std::sys::backtrace::BacktraceLock::print::hb66718c44668eb56 [INFO] [stdout] at /rustc/c7c49f44a7bb561dd9317e14908a1e50fa478ce5/library/std/src/sys/backtrace.rs:42:9 [INFO] [stdout] 8: 0x62efb7301df2 - std::panicking::default_hook::{{closure}}::h1a6c150385aaf90f [INFO] [stdout] at /rustc/c7c49f44a7bb561dd9317e14908a1e50fa478ce5/library/std/src/panicking.rs:266:22 [INFO] [stdout] 9: 0x62efb7301933 - std::panicking::default_hook::h5e8a75844aa4ea98 [INFO] [stdout] at /rustc/c7c49f44a7bb561dd9317e14908a1e50fa478ce5/library/std/src/panicking.rs:290:9 [INFO] [stdout] 10: 0x62efb72ccd99 - as core::ops::function::Fn>::call::h6ee94b0b846f4546 [INFO] [stdout] at /rustc/c7c49f44a7bb561dd9317e14908a1e50fa478ce5/library/alloc/src/boxed.rs:2245:9 [INFO] [stdout] 11: 0x62efb72ccd99 - test::test_main::{{closure}}::hccc1c379f17b5ca1 [INFO] [stdout] at /rustc/c7c49f44a7bb561dd9317e14908a1e50fa478ce5/library/test/src/lib.rs:132:21 [INFO] [stdout] 12: 0x62efb73026b2 - as core::ops::function::Fn>::call::h8b15e23d52a5df89 [INFO] [stdout] at /rustc/c7c49f44a7bb561dd9317e14908a1e50fa478ce5/library/alloc/src/boxed.rs:2245:9 [INFO] [stdout] 13: 0x62efb73026b2 - std::panicking::rust_panic_with_hook::hea686c8750159a85 [INFO] [stdout] at /rustc/c7c49f44a7bb561dd9317e14908a1e50fa478ce5/library/std/src/panicking.rs:805:13 [INFO] [stdout] 14: 0x62efb7302333 - std::panicking::begin_panic_handler::{{closure}}::hfd69333fda29d50a [INFO] [stdout] at /rustc/c7c49f44a7bb561dd9317e14908a1e50fa478ce5/library/std/src/panicking.rs:664:13 [INFO] [stdout] 15: 0x62efb7300ac9 - std::sys::backtrace::__rust_end_short_backtrace::hb7c23c01c3c70e35 [INFO] [stdout] at /rustc/c7c49f44a7bb561dd9317e14908a1e50fa478ce5/library/std/src/sys/backtrace.rs:170:18 [INFO] [stdout] 16: 0x62efb7301ff4 - rust_begin_unwind [INFO] [stdout] at /rustc/c7c49f44a7bb561dd9317e14908a1e50fa478ce5/library/std/src/panicking.rs:662:5 [INFO] [stdout] 17: 0x62efb728eaa3 - core::panicking::panic_fmt::h43de95d595ebabc0 [INFO] [stdout] at /rustc/c7c49f44a7bb561dd9317e14908a1e50fa478ce5/library/core/src/panicking.rs:74:14 [INFO] [stdout] 18: 0x62efb728eb2c - core::panicking::panic::h7d15abfb2c709e2c [INFO] [stdout] at /rustc/c7c49f44a7bb561dd9317e14908a1e50fa478ce5/library/core/src/panicking.rs:148:5 [INFO] [stdout] 19: 0x62efb7294aab - mono_thread_inttest::throughput_generator_calls_event_function_five_hundred_times_a_sec::haa93dfeb62236f2b [INFO] [stdout] at /opt/rustwide/workdir/tests/mono_thread_inttest.rs:52:5 [INFO] [stdout] 20: 0x62efb72949f7 - mono_thread_inttest::throughput_generator_calls_event_function_five_hundred_times_a_sec::{{closure}}::h96e95fcafb5f4264 [INFO] [stdout] at /opt/rustwide/workdir/tests/mono_thread_inttest.rs:48:72 [INFO] [stdout] 21: 0x62efb728ff76 - core::ops::function::FnOnce::call_once::h44fa1bae80d0e183 [INFO] [stdout] at /rustc/c7c49f44a7bb561dd9317e14908a1e50fa478ce5/library/core/src/ops/function.rs:250:5 [INFO] [stdout] 22: 0x62efb72d170b - core::ops::function::FnOnce::call_once::h9b5e01539a2850c9 [INFO] [stdout] at /rustc/c7c49f44a7bb561dd9317e14908a1e50fa478ce5/library/core/src/ops/function.rs:250:5 [INFO] [stdout] 23: 0x62efb72d170b - test::__rust_begin_short_backtrace::hfa35fd69df7e11a6 [INFO] [stdout] at /rustc/c7c49f44a7bb561dd9317e14908a1e50fa478ce5/library/test/src/lib.rs:620:18 [INFO] [stdout] 24: 0x62efb72d0f88 - test::run_test_in_process::{{closure}}::he6acc04e3f33efba [INFO] [stdout] at /rustc/c7c49f44a7bb561dd9317e14908a1e50fa478ce5/library/test/src/lib.rs:643:60 [INFO] [stdout] 25: 0x62efb72d0f88 - as core::ops::function::FnOnce<()>>::call_once::h9ed0882d7a8705d4 [INFO] [stdout] at /rustc/c7c49f44a7bb561dd9317e14908a1e50fa478ce5/library/core/src/panic/unwind_safe.rs:272:9 [INFO] [stdout] 26: 0x62efb72d0f88 - std::panicking::try::do_call::hb4e1060b1206bf1d [INFO] [stdout] at /rustc/c7c49f44a7bb561dd9317e14908a1e50fa478ce5/library/std/src/panicking.rs:554:40 [INFO] [stdout] 27: 0x62efb72d0f88 - std::panicking::try::he7301d205c111379 [INFO] [stdout] at /rustc/c7c49f44a7bb561dd9317e14908a1e50fa478ce5/library/std/src/panicking.rs:518:19 [INFO] [stdout] 28: 0x62efb72d0f88 - std::panic::catch_unwind::h6c993e01d18171db [INFO] [stdout] at /rustc/c7c49f44a7bb561dd9317e14908a1e50fa478ce5/library/std/src/panic.rs:345:14 [INFO] [stdout] 29: 0x62efb72d0f88 - test::run_test_in_process::h39faa778a589c8b3 [INFO] [stdout] at /rustc/c7c49f44a7bb561dd9317e14908a1e50fa478ce5/library/test/src/lib.rs:643:27 [INFO] [stdout] 30: 0x62efb72d0f88 - test::run_test::{{closure}}::h59e32a442f6c7a1c [INFO] [stdout] at /rustc/c7c49f44a7bb561dd9317e14908a1e50fa478ce5/library/test/src/lib.rs:564:43 [INFO] [stdout] 31: 0x62efb7296354 - test::run_test::{{closure}}::h6615d2e57f7516c2 [INFO] [stdout] at /rustc/c7c49f44a7bb561dd9317e14908a1e50fa478ce5/library/test/src/lib.rs:594:41 [INFO] [stdout] 32: 0x62efb7296354 - std::sys::backtrace::__rust_begin_short_backtrace::h761da13b6d71bc61 [INFO] [stdout] at /rustc/c7c49f44a7bb561dd9317e14908a1e50fa478ce5/library/std/src/sys/backtrace.rs:154:18 [INFO] [stdout] 33: 0x62efb7299a02 - std::thread::Builder::spawn_unchecked_::{{closure}}::{{closure}}::haa82f2052ad87664 [INFO] [stdout] at /rustc/c7c49f44a7bb561dd9317e14908a1e50fa478ce5/library/std/src/thread/mod.rs:522:17 [INFO] [stdout] 34: 0x62efb7299a02 - as core::ops::function::FnOnce<()>>::call_once::h709672ecbcc3758b [INFO] [stdout] at /rustc/c7c49f44a7bb561dd9317e14908a1e50fa478ce5/library/core/src/panic/unwind_safe.rs:272:9 [INFO] [stdout] 35: 0x62efb7299a02 - std::panicking::try::do_call::hb005a67f6fd9e082 [INFO] [stdout] at /rustc/c7c49f44a7bb561dd9317e14908a1e50fa478ce5/library/std/src/panicking.rs:554:40 [INFO] [stdout] 36: 0x62efb7299a02 - std::panicking::try::hec02b24e35e6ab47 [INFO] [stdout] at /rustc/c7c49f44a7bb561dd9317e14908a1e50fa478ce5/library/std/src/panicking.rs:518:19 [INFO] [stdout] 37: 0x62efb7299a02 - std::panic::catch_unwind::h13617efa0948dabc [INFO] [stdout] at /rustc/c7c49f44a7bb561dd9317e14908a1e50fa478ce5/library/std/src/panic.rs:345:14 [INFO] [stdout] 38: 0x62efb7299a02 - std::thread::Builder::spawn_unchecked_::{{closure}}::hb4a56c6b32a8e045 [INFO] [stdout] at /rustc/c7c49f44a7bb561dd9317e14908a1e50fa478ce5/library/std/src/thread/mod.rs:521:30 [INFO] [stdout] 39: 0x62efb7299a02 - core::ops::function::FnOnce::call_once{{vtable.shim}}::h26d01bfaffaa945b [INFO] [stdout] at /rustc/c7c49f44a7bb561dd9317e14908a1e50fa478ce5/library/core/src/ops/function.rs:250:5 [INFO] [stdout] 40: 0x62efb73066cb - as core::ops::function::FnOnce>::call_once::hbe8e1ebf83e6794e [INFO] [stdout] at /rustc/c7c49f44a7bb561dd9317e14908a1e50fa478ce5/library/alloc/src/boxed.rs:2231:9 [INFO] [stdout] 41: 0x62efb73066cb - as core::ops::function::FnOnce>::call_once::h7d6ff770e6bfeff7 [INFO] [stdout] at /rustc/c7c49f44a7bb561dd9317e14908a1e50fa478ce5/library/alloc/src/boxed.rs:2231:9 [INFO] [stdout] 42: 0x62efb73066cb - std::sys::pal::unix::thread::Thread::new::thread_start::h68792b09799a7d87 [INFO] [stdout] at /rustc/c7c49f44a7bb561dd9317e14908a1e50fa478ce5/library/std/src/sys/pal/unix/thread.rs:105:17 [INFO] [stdout] 43: 0x7682b0862ac3 - [INFO] [stdout] 44: 0x7682b08f3a04 - clone [INFO] [stdout] 45: 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: 772.401459ms, last_sleep:7.72401459s, to_sleep:772.401459ms [INFO] [stdout] events_per_small_period: 300, events_remainder: 0, additional_event: 0, main_target_interval(s): 10, previous_sleep: 7.72401459s [INFO] [stdout] avg_sleep: 870.751117ms, last_sleep:7.72401459s, to_sleep:969.100775ms [INFO] [stdout] events_per_small_period: 300, events_remainder: 0, additional_event: 0, main_target_interval(s): 10, previous_sleep: 7.72401459s [INFO] [stdout] avg_sleep: 869.448652ms, last_sleep:7.72401459s, to_sleep:966.495845ms [INFO] [stdout] events_per_small_period: 300, events_remainder: 0, additional_event: 0, main_target_interval(s): 10, previous_sleep: 7.72401459s [INFO] [stdout] avg_sleep: 871.138237ms, last_sleep:7.72401459s, to_sleep:969.875015ms [INFO] [stdout] events_per_small_period: 300, events_remainder: 0, additional_event: 0, main_target_interval(s): 10, previous_sleep: 7.72401459s [INFO] [stdout] avg_sleep: 869.313677ms, last_sleep:7.72401459s, to_sleep:966.225895ms [INFO] [stdout] events_per_small_period: 300, events_remainder: 0, additional_event: 0, main_target_interval(s): 10, previous_sleep: 7.72401459s [INFO] [stdout] avg_sleep: 870.979447ms, last_sleep:7.72401459s, to_sleep:969.557435ms [INFO] [stdout] events_per_small_period: 300, events_remainder: 0, additional_event: 0, main_target_interval(s): 10, previous_sleep: 7.72401459s [INFO] [stdout] avg_sleep: 871.267252ms, last_sleep:7.72401459s, to_sleep:970.133046ms [INFO] [stdout] events_per_small_period: 300, events_remainder: 0, additional_event: 0, main_target_interval(s): 10, previous_sleep: 7.72401459s [INFO] [stdout] avg_sleep: 871.292162ms, last_sleep:7.72401459s, to_sleep:970.182865ms [INFO] [stdout] events_per_small_period: 300, events_remainder: 0, additional_event: 0, main_target_interval(s): 10, previous_sleep: 7.72401459s [INFO] [stdout] avg_sleep: 870.396557ms, last_sleep:7.72401459s, to_sleep:968.391656ms [INFO] [stdout] events_per_small_period: 300, events_remainder: 0, additional_event: 0, main_target_interval(s): 10, previous_sleep: 7.72401459s [INFO] [stdout] avg_sleep: 871.050227ms, last_sleep:7.72401459s, to_sleep:969.698995ms [INFO] [stdout] Throughput: 300 event/s - Was too slow: true - Distance to target: 0 events/s - toSleep: 883.105272ms [INFO] [stdout] throughput_results: last sleep: 8.607119862s, 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: 0x62efb73005ea - std::backtrace_rs::backtrace::libunwind::trace::h9ce576133d3ff57c [INFO] [stdout] at /rustc/c7c49f44a7bb561dd9317e14908a1e50fa478ce5/library/std/src/../../backtrace/src/backtrace/libunwind.rs:116:5 [INFO] [stdout] 1: 0x62efb73005ea - std::backtrace_rs::backtrace::trace_unsynchronized::h77316b33bca73147 [INFO] [stdout] at /rustc/c7c49f44a7bb561dd9317e14908a1e50fa478ce5/library/std/src/../../backtrace/src/backtrace/mod.rs:66:5 [INFO] [stdout] 2: 0x62efb73005ea - std::sys::backtrace::_print_fmt::haa182cb1ad45bc9f [INFO] [stdout] at /rustc/c7c49f44a7bb561dd9317e14908a1e50fa478ce5/library/std/src/sys/backtrace.rs:66:9 [INFO] [stdout] 3: 0x62efb73005ea - ::fmt::ha95eeb186e57c055 [INFO] [stdout] at /rustc/c7c49f44a7bb561dd9317e14908a1e50fa478ce5/library/std/src/sys/backtrace.rs:39:26 [INFO] [stdout] 4: 0x62efb73267cb - core::fmt::rt::Argument::fmt::h71240792b23d8aa6 [INFO] [stdout] at /rustc/c7c49f44a7bb561dd9317e14908a1e50fa478ce5/library/core/src/fmt/rt.rs:177:76 [INFO] [stdout] 5: 0x62efb73267cb - core::fmt::write::h1c4d59c42453601a [INFO] [stdout] at /rustc/c7c49f44a7bb561dd9317e14908a1e50fa478ce5/library/core/src/fmt/mod.rs:1178:21 [INFO] [stdout] 6: 0x62efb72fd7d3 - std::io::Write::write_fmt::h08b6f2062a09bc68 [INFO] [stdout] at /rustc/c7c49f44a7bb561dd9317e14908a1e50fa478ce5/library/std/src/io/mod.rs:1823:15 [INFO] [stdout] 7: 0x62efb7301df2 - std::sys::backtrace::BacktraceLock::print::hb66718c44668eb56 [INFO] [stdout] at /rustc/c7c49f44a7bb561dd9317e14908a1e50fa478ce5/library/std/src/sys/backtrace.rs:42:9 [INFO] [stdout] 8: 0x62efb7301df2 - std::panicking::default_hook::{{closure}}::h1a6c150385aaf90f [INFO] [stdout] at /rustc/c7c49f44a7bb561dd9317e14908a1e50fa478ce5/library/std/src/panicking.rs:266:22 [INFO] [stdout] 9: 0x62efb7301933 - std::panicking::default_hook::h5e8a75844aa4ea98 [INFO] [stdout] at /rustc/c7c49f44a7bb561dd9317e14908a1e50fa478ce5/library/std/src/panicking.rs:290:9 [INFO] [stdout] 10: 0x62efb72ccd99 - as core::ops::function::Fn>::call::h6ee94b0b846f4546 [INFO] [stdout] at /rustc/c7c49f44a7bb561dd9317e14908a1e50fa478ce5/library/alloc/src/boxed.rs:2245:9 [INFO] [stdout] 11: 0x62efb72ccd99 - test::test_main::{{closure}}::hccc1c379f17b5ca1 [INFO] [stdout] at /rustc/c7c49f44a7bb561dd9317e14908a1e50fa478ce5/library/test/src/lib.rs:132:21 [INFO] [stdout] 12: 0x62efb73026b2 - as core::ops::function::Fn>::call::h8b15e23d52a5df89 [INFO] [stdout] at /rustc/c7c49f44a7bb561dd9317e14908a1e50fa478ce5/library/alloc/src/boxed.rs:2245:9 [INFO] [stdout] 13: 0x62efb73026b2 - std::panicking::rust_panic_with_hook::hea686c8750159a85 [INFO] [stdout] at /rustc/c7c49f44a7bb561dd9317e14908a1e50fa478ce5/library/std/src/panicking.rs:805:13 [INFO] [stdout] 14: 0x62efb7302333 - std::panicking::begin_panic_handler::{{closure}}::hfd69333fda29d50a [INFO] [stdout] at /rustc/c7c49f44a7bb561dd9317e14908a1e50fa478ce5/library/std/src/panicking.rs:664:13 [INFO] [stdout] 15: 0x62efb7300ac9 - std::sys::backtrace::__rust_end_short_backtrace::hb7c23c01c3c70e35 [INFO] [stdout] at /rustc/c7c49f44a7bb561dd9317e14908a1e50fa478ce5/library/std/src/sys/backtrace.rs:170:18 [INFO] [stdout] 16: 0x62efb7301ff4 - rust_begin_unwind [INFO] [stdout] at /rustc/c7c49f44a7bb561dd9317e14908a1e50fa478ce5/library/std/src/panicking.rs:662:5 [INFO] [stdout] 17: 0x62efb728eaa3 - core::panicking::panic_fmt::h43de95d595ebabc0 [INFO] [stdout] at /rustc/c7c49f44a7bb561dd9317e14908a1e50fa478ce5/library/core/src/panicking.rs:74:14 [INFO] [stdout] 18: 0x62efb728eb2c - core::panicking::panic::h7d15abfb2c709e2c [INFO] [stdout] at /rustc/c7c49f44a7bb561dd9317e14908a1e50fa478ce5/library/core/src/panicking.rs:148:5 [INFO] [stdout] 19: 0x62efb72949db - mono_thread_inttest::throughput_generator_calls_event_function_three_hundred_times_a_sec::h0a1e592baa520d5e [INFO] [stdout] at /opt/rustwide/workdir/tests/mono_thread_inttest.rs:44:5 [INFO] [stdout] 20: 0x62efb7294927 - mono_thread_inttest::throughput_generator_calls_event_function_three_hundred_times_a_sec::{{closure}}::hacd96c53da3d6aaf [INFO] [stdout] at /opt/rustwide/workdir/tests/mono_thread_inttest.rs:40:73 [INFO] [stdout] 21: 0x62efb7290076 - core::ops::function::FnOnce::call_once::he7c2b8b4ce4c2cea [INFO] [stdout] at /rustc/c7c49f44a7bb561dd9317e14908a1e50fa478ce5/library/core/src/ops/function.rs:250:5 [INFO] [stdout] 22: 0x62efb72d170b - core::ops::function::FnOnce::call_once::h9b5e01539a2850c9 [INFO] [stdout] at /rustc/c7c49f44a7bb561dd9317e14908a1e50fa478ce5/library/core/src/ops/function.rs:250:5 [INFO] [stdout] 23: 0x62efb72d170b - test::__rust_begin_short_backtrace::hfa35fd69df7e11a6 [INFO] [stdout] at /rustc/c7c49f44a7bb561dd9317e14908a1e50fa478ce5/library/test/src/lib.rs:620:18 [INFO] [stdout] 24: 0x62efb72d0f88 - test::run_test_in_process::{{closure}}::he6acc04e3f33efba [INFO] [stdout] at /rustc/c7c49f44a7bb561dd9317e14908a1e50fa478ce5/library/test/src/lib.rs:643:60 [INFO] [stdout] 25: 0x62efb72d0f88 - as core::ops::function::FnOnce<()>>::call_once::h9ed0882d7a8705d4 [INFO] [stdout] at /rustc/c7c49f44a7bb561dd9317e14908a1e50fa478ce5/library/core/src/panic/unwind_safe.rs:272:9 [INFO] [stdout] 26: 0x62efb72d0f88 - std::panicking::try::do_call::hb4e1060b1206bf1d [INFO] [stdout] at /rustc/c7c49f44a7bb561dd9317e14908a1e50fa478ce5/library/std/src/panicking.rs:554:40 [INFO] [stdout] 27: 0x62efb72d0f88 - std::panicking::try::he7301d205c111379 [INFO] [stdout] at /rustc/c7c49f44a7bb561dd9317e14908a1e50fa478ce5/library/std/src/panicking.rs:518:19 [INFO] [stdout] 28: 0x62efb72d0f88 - std::panic::catch_unwind::h6c993e01d18171db [INFO] [stdout] at /rustc/c7c49f44a7bb561dd9317e14908a1e50fa478ce5/library/std/src/panic.rs:345:14 [INFO] [stdout] 29: 0x62efb72d0f88 - test::run_test_in_process::h39faa778a589c8b3 [INFO] [stdout] at /rustc/c7c49f44a7bb561dd9317e14908a1e50fa478ce5/library/test/src/lib.rs:643:27 [INFO] [stdout] 30: 0x62efb72d0f88 - test::run_test::{{closure}}::h59e32a442f6c7a1c [INFO] [stdout] at /rustc/c7c49f44a7bb561dd9317e14908a1e50fa478ce5/library/test/src/lib.rs:564:43 [INFO] [stdout] 31: 0x62efb7296354 - test::run_test::{{closure}}::h6615d2e57f7516c2 [INFO] [stdout] at /rustc/c7c49f44a7bb561dd9317e14908a1e50fa478ce5/library/test/src/lib.rs:594:41 [INFO] [stdout] 32: 0x62efb7296354 - std::sys::backtrace::__rust_begin_short_backtrace::h761da13b6d71bc61 [INFO] [stdout] at /rustc/c7c49f44a7bb561dd9317e14908a1e50fa478ce5/library/std/src/sys/backtrace.rs:154:18 [INFO] [stdout] 33: 0x62efb7299a02 - std::thread::Builder::spawn_unchecked_::{{closure}}::{{closure}}::haa82f2052ad87664 [INFO] [stdout] at /rustc/c7c49f44a7bb561dd9317e14908a1e50fa478ce5/library/std/src/thread/mod.rs:522:17 [INFO] [stdout] 34: 0x62efb7299a02 - as core::ops::function::FnOnce<()>>::call_once::h709672ecbcc3758b [INFO] [stdout] at /rustc/c7c49f44a7bb561dd9317e14908a1e50fa478ce5/library/core/src/panic/unwind_safe.rs:272:9 [INFO] [stdout] 35: 0x62efb7299a02 - std::panicking::try::do_call::hb005a67f6fd9e082 [INFO] [stdout] at /rustc/c7c49f44a7bb561dd9317e14908a1e50fa478ce5/library/std/src/panicking.rs:554:40 [INFO] [stdout] 36: 0x62efb7299a02 - std::panicking::try::hec02b24e35e6ab47 [INFO] [stdout] at /rustc/c7c49f44a7bb561dd9317e14908a1e50fa478ce5/library/std/src/panicking.rs:518:19 [INFO] [stdout] 37: 0x62efb7299a02 - std::panic::catch_unwind::h13617efa0948dabc [INFO] [stdout] at /rustc/c7c49f44a7bb561dd9317e14908a1e50fa478ce5/library/std/src/panic.rs:345:14 [INFO] [stdout] 38: 0x62efb7299a02 - std::thread::Builder::spawn_unchecked_::{{closure}}::hb4a56c6b32a8e045 [INFO] [stdout] at /rustc/c7c49f44a7bb561dd9317e14908a1e50fa478ce5/library/std/src/thread/mod.rs:521:30 [INFO] [stdout] 39: 0x62efb7299a02 - core::ops::function::FnOnce::call_once{{vtable.shim}}::h26d01bfaffaa945b [INFO] [stdout] at /rustc/c7c49f44a7bb561dd9317e14908a1e50fa478ce5/library/core/src/ops/function.rs:250:5 [INFO] [stdout] 40: 0x62efb73066cb - as core::ops::function::FnOnce>::call_once::hbe8e1ebf83e6794e [INFO] [stdout] at /rustc/c7c49f44a7bb561dd9317e14908a1e50fa478ce5/library/alloc/src/boxed.rs:2231:9 [INFO] [stdout] 41: 0x62efb73066cb - as core::ops::function::FnOnce>::call_once::h7d6ff770e6bfeff7 [INFO] [stdout] at /rustc/c7c49f44a7bb561dd9317e14908a1e50fa478ce5/library/alloc/src/boxed.rs:2231:9 [INFO] [stdout] 42: 0x62efb73066cb - std::sys::pal::unix::thread::Thread::new::thread_start::h68792b09799a7d87 [INFO] [stdout] at /rustc/c7c49f44a7bb561dd9317e14908a1e50fa478ce5/library/std/src/sys/pal/unix/thread.rs:105:17 [INFO] [stdout] 43: 0x7682b0862ac3 - [INFO] [stdout] 44: 0x7682b08f3a04 - clone [INFO] [stdout] 45: 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.07s [INFO] [stdout] [INFO] running `Command { std: "docker" "inspect" "01535720fe4e285c56d74f278d67a1f4a46b221ba4b1a6a7a4973e31f35f429d", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "01535720fe4e285c56d74f278d67a1f4a46b221ba4b1a6a7a4973e31f35f429d", kill_on_drop: false }` [INFO] [stdout] 01535720fe4e285c56d74f278d67a1f4a46b221ba4b1a6a7a4973e31f35f429d