[INFO] cloning repository https://github.com/TESTMECS/async [INFO] running `Command { std: "git" "-c" "credential.helper=" "-c" "credential.helper=/workspace/cargo-home/bin/git-credential-null" "clone" "--bare" "https://github.com/TESTMECS/async" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2FTESTMECS%2Fasync", kill_on_drop: false }` [INFO] [stderr] Cloning into bare repository '/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2FTESTMECS%2Fasync'... [INFO] running `Command { std: "git" "rev-parse" "HEAD", kill_on_drop: false }` [INFO] [stdout] 6ccd2f449a24a80904e205da3d429c6a5818eee4 [INFO] testing TESTMECS/async against master#cdb45c87e2cd43495379f7e867e3cc15dcee9f93 for pr-145838-1 [INFO] running `Command { std: "git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2FTESTMECS%2Fasync" "/workspace/builds/worker-1-tc1/source", kill_on_drop: false }` [INFO] [stderr] Cloning into '/workspace/builds/worker-1-tc1/source'... [INFO] [stderr] done. [INFO] started tweaking git repo https://github.com/TESTMECS/async [INFO] finished tweaking git repo https://github.com/TESTMECS/async [INFO] tweaked toml for git repo https://github.com/TESTMECS/async written to /workspace/builds/worker-1-tc1/source/Cargo.toml [INFO] validating manifest of git repo https://github.com/TESTMECS/async on toolchain cdb45c87e2cd43495379f7e867e3cc15dcee9f93 [INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+cdb45c87e2cd43495379f7e867e3cc15dcee9f93" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }` [INFO] crate git repo https://github.com/TESTMECS/async 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" "+cdb45c87e2cd43495379f7e867e3cc15dcee9f93" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }` [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-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:7ad1b28ee6f5f7f699f6cf7015098d6ccdd96d6f2d78dd06228f5b4c9faf309c" "/opt/rustwide/cargo-home/bin/cargo" "+cdb45c87e2cd43495379f7e867e3cc15dcee9f93" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }` [INFO] [stdout] 479c96b56163b8c790ced647261113a3b1b3042640da6b1904ffd957982a487b [INFO] running `Command { std: "docker" "start" "-a" "479c96b56163b8c790ced647261113a3b1b3042640da6b1904ffd957982a487b", kill_on_drop: false }` [INFO] running `Command { std: "docker" "inspect" "479c96b56163b8c790ced647261113a3b1b3042640da6b1904ffd957982a487b", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "479c96b56163b8c790ced647261113a3b1b3042640da6b1904ffd957982a487b", kill_on_drop: false }` [INFO] [stdout] 479c96b56163b8c790ced647261113a3b1b3042640da6b1904ffd957982a487b [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-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:7ad1b28ee6f5f7f699f6cf7015098d6ccdd96d6f2d78dd06228f5b4c9faf309c" "/opt/rustwide/cargo-home/bin/cargo" "+cdb45c87e2cd43495379f7e867e3cc15dcee9f93" "build" "--frozen" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] 692101e3820693d182f1028b5587bb0b3ba761a760d3cb0cf3cc86d44a844dd3 [INFO] running `Command { std: "docker" "start" "-a" "692101e3820693d182f1028b5587bb0b3ba761a760d3cb0cf3cc86d44a844dd3", kill_on_drop: false }` [INFO] [stderr] Compiling std_async v0.1.0 (/opt/rustwide/workdir) [INFO] [stdout] warning: function `send_data` is never used [INFO] [stdout] --> src/client/client.rs:10:10 [INFO] [stdout] | [INFO] [stdout] 10 | async fn send_data(field1: u32, field2: u16, field3: String) -> io::Result { [INFO] [stdout] | ^^^^^^^^^ [INFO] [stdout] | [INFO] [stdout] = note: `#[warn(dead_code)]` (part of `#[warn(unused)]`) on by default [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] warning: function `main` is never used [INFO] [stdout] --> src/client/client.rs:30:4 [INFO] [stdout] | [INFO] [stdout] 30 | fn main() -> io::Result<()> { [INFO] [stdout] | ^^^^ [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] warning: static `FLAGS` is never used [INFO] [stdout] --> src/server/server.rs:13:8 [INFO] [stdout] | [INFO] [stdout] 13 | static FLAGS: [AtomicBool; 3] = [ [INFO] [stdout] | ^^^^^ [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] warning: function `handle_client` is never used [INFO] [stdout] --> src/server/server.rs:44:10 [INFO] [stdout] | [INFO] [stdout] 44 | async fn handle_client(mut stream: TcpStream) -> std::io::Result<()> { [INFO] [stdout] | ^^^^^^^^^^^^^ [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] warning: function `main` is never used [INFO] [stdout] --> src/server/server.rs:81:4 [INFO] [stdout] | [INFO] [stdout] 81 | fn main() -> io::Result<()> { [INFO] [stdout] | ^^^^ [INFO] [stdout] [INFO] [stdout] [INFO] [stderr] Finished `dev` profile [unoptimized + debuginfo] target(s) in 0.55s [INFO] running `Command { std: "docker" "inspect" "692101e3820693d182f1028b5587bb0b3ba761a760d3cb0cf3cc86d44a844dd3", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "692101e3820693d182f1028b5587bb0b3ba761a760d3cb0cf3cc86d44a844dd3", kill_on_drop: false }` [INFO] [stdout] 692101e3820693d182f1028b5587bb0b3ba761a760d3cb0cf3cc86d44a844dd3 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-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:7ad1b28ee6f5f7f699f6cf7015098d6ccdd96d6f2d78dd06228f5b4c9faf309c" "/opt/rustwide/cargo-home/bin/cargo" "+cdb45c87e2cd43495379f7e867e3cc15dcee9f93" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] b8b96a5baa99efc9416f651432971f7675b51c4397fcddccea22a1f0e9bec302 [INFO] running `Command { std: "docker" "start" "-a" "b8b96a5baa99efc9416f651432971f7675b51c4397fcddccea22a1f0e9bec302", kill_on_drop: false }` [INFO] [stdout] warning: function `send_data` is never used [INFO] [stdout] --> src/client/client.rs:10:10 [INFO] [stdout] | [INFO] [stdout] 10 | async fn send_data(field1: u32, field2: u16, field3: String) -> io::Result { [INFO] [stdout] | ^^^^^^^^^ [INFO] [stdout] | [INFO] [stdout] = note: `#[warn(dead_code)]` (part of `#[warn(unused)]`) on by default [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] warning: function `main` is never used [INFO] [stdout] --> src/client/client.rs:30:4 [INFO] [stdout] | [INFO] [stdout] 30 | fn main() -> io::Result<()> { [INFO] [stdout] | ^^^^ [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] warning: static `FLAGS` is never used [INFO] [stdout] --> src/server/server.rs:13:8 [INFO] [stdout] | [INFO] [stdout] 13 | static FLAGS: [AtomicBool; 3] = [ [INFO] [stdout] | ^^^^^ [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] warning: function `handle_client` is never used [INFO] [stdout] --> src/server/server.rs:44:10 [INFO] [stdout] | [INFO] [stdout] 44 | async fn handle_client(mut stream: TcpStream) -> std::io::Result<()> { [INFO] [stdout] | ^^^^^^^^^^^^^ [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] warning: function `main` is never used [INFO] [stdout] --> src/server/server.rs:81:4 [INFO] [stdout] | [INFO] [stdout] 81 | fn main() -> io::Result<()> { [INFO] [stdout] | ^^^^ [INFO] [stdout] [INFO] [stdout] [INFO] [stderr] Compiling std_async v0.1.0 (/opt/rustwide/workdir) [INFO] [stdout] warning: function `send_data` is never used [INFO] [stdout] --> src/client/client.rs:10:10 [INFO] [stdout] | [INFO] [stdout] 10 | async fn send_data(field1: u32, field2: u16, field3: String) -> io::Result { [INFO] [stdout] | ^^^^^^^^^ [INFO] [stdout] | [INFO] [stdout] = note: `#[warn(dead_code)]` (part of `#[warn(unused)]`) on by default [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] warning: function `main` is never used [INFO] [stdout] --> src/client/client.rs:30:4 [INFO] [stdout] | [INFO] [stdout] 30 | fn main() -> io::Result<()> { [INFO] [stdout] | ^^^^ [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] warning: static `FLAGS` is never used [INFO] [stdout] --> src/server/server.rs:13:8 [INFO] [stdout] | [INFO] [stdout] 13 | static FLAGS: [AtomicBool; 3] = [ [INFO] [stdout] | ^^^^^ [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] warning: function `handle_client` is never used [INFO] [stdout] --> src/server/server.rs:44:10 [INFO] [stdout] | [INFO] [stdout] 44 | async fn handle_client(mut stream: TcpStream) -> std::io::Result<()> { [INFO] [stdout] | ^^^^^^^^^^^^^ [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] warning: function `main` is never used [INFO] [stdout] --> src/server/server.rs:81:4 [INFO] [stdout] | [INFO] [stdout] 81 | fn main() -> io::Result<()> { [INFO] [stdout] | ^^^^ [INFO] [stdout] [INFO] [stdout] [INFO] [stderr] Finished `test` profile [unoptimized + debuginfo] target(s) in 1.88s [INFO] running `Command { std: "docker" "inspect" "b8b96a5baa99efc9416f651432971f7675b51c4397fcddccea22a1f0e9bec302", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "b8b96a5baa99efc9416f651432971f7675b51c4397fcddccea22a1f0e9bec302", kill_on_drop: false }` [INFO] [stdout] b8b96a5baa99efc9416f651432971f7675b51c4397fcddccea22a1f0e9bec302 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-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:7ad1b28ee6f5f7f699f6cf7015098d6ccdd96d6f2d78dd06228f5b4c9faf309c" "/opt/rustwide/cargo-home/bin/cargo" "+cdb45c87e2cd43495379f7e867e3cc15dcee9f93" "test" "--frozen", kill_on_drop: false }` [INFO] [stdout] 4cd89db0dfd5ab1c90eefdd6f750e7f9a4d507ace1cf7857343d3f6e3dff7331 [INFO] running `Command { std: "docker" "start" "-a" "4cd89db0dfd5ab1c90eefdd6f750e7f9a4d507ace1cf7857343d3f6e3dff7331", kill_on_drop: false }` [INFO] [stdout] [INFO] [stdout] running 29 tests [INFO] [stdout] test data::data_layer_tests::tests::test_data_deserialization_invalid_utf8 ... ok [INFO] [stdout] test data::data_layer_tests::tests::test_data_deserialization_truncated ... ok [INFO] [stdout] test data::data_layer_tests::tests::test_data_large_string ... ok [INFO] [stderr] warning: function `send_data` is never used [INFO] [stderr] --> src/client/client.rs:10:10 [INFO] [stderr] | [INFO] [stderr] 10 | async fn send_data(field1: u32, field2: u16, field3: String) -> io::Result { [INFO] [stderr] | ^^^^^^^^^ [INFO] [stderr] | [INFO] [stderr] = note: `#[warn(dead_code)]` (part of `#[warn(unused)]`) on by default [INFO] [stderr] [INFO] [stderr] warning: function `main` is never used [INFO] [stderr] --> src/client/client.rs:30:4 [INFO] [stderr] | [INFO] [stderr] 30 | fn main() -> io::Result<()> { [INFO] [stderr] | ^^^^ [INFO] [stdout] test data::data_layer_tests::tests::test_empty_string_serialization ... ok [INFO] [stderr] [INFO] [stderr] warning: static `FLAGS` is never used [INFO] [stderr] --> src/server/server.rs:13:8 [INFO] [stderr] | [INFO] [stderr] 13 | static FLAGS: [AtomicBool; 3] = [ [INFO] [stderr] | ^^^^^ [INFO] [stderr] [INFO] [stderr] warning: function `handle_client` is never used [INFO] [stderr] --> src/server/server.rs:44:10 [INFO] [stderr] | [INFO] [stderr] 44 | async fn handle_client(mut stream: TcpStream) -> std::io::Result<()> { [INFO] [stderr] | ^^^^^^^^^^^^^ [INFO] [stderr] [INFO] [stderr] warning: function `main` is never used [INFO] [stderr] --> src/server/server.rs:81:4 [INFO] [stderr] | [INFO] [stderr] 81 | fn main() -> io::Result<()> { [INFO] [stderr] | ^^^^ [INFO] [stderr] [INFO] [stderr] warning: `std_async` (lib) generated 5 warnings [INFO] [stderr] warning: `std_async` (lib test) generated 5 warnings (5 duplicates) [INFO] [stderr] Finished `test` profile [unoptimized + debuginfo] target(s) in 0.03s [INFO] [stderr] Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/std_async-79e2fbb69ccb37fe) [INFO] [stdout] test data::data_layer_tests::tests::test_data_deserialization ... ok [INFO] [stdout] test runtime::executor_tests::tests::test_executor_spawn_multiple_futures ... ok [INFO] [stdout] test runtime::executor_tests::tests::test_executor_spawn_simple_future ... ok [INFO] [stdout] test runtime::executor_tests::tests::test_waker_creation ... ok [INFO] [stdout] test runtime::reciever_tests::tests::test_tcp_receiver_creation ... ok [INFO] [stdout] test runtime::sender_tests::tests::test_tcp_sender_creation ... ok [INFO] [stdout] test data::data_layer_tests::tests::test_data_serialization ... ok [INFO] [stdout] test runtime::sleep_tests::tests::test_multiple_sleep_durations ... ok [INFO] [stdout] test runtime::sleep_tests::tests::test_sleep_pending ... ok [INFO] [stdout] test runtime::waker_tests::tests::test_create_raw_waker ... ok [INFO] [stdout] test runtime::waker_tests::tests::test_multiple_waker_operations ... ok [INFO] [stdout] test runtime::sleep_tests::tests::test_sleep_immediate_ready ... ok [INFO] [stdout] test runtime::waker_tests::tests::test_raw_waker_creation ... ok [INFO] [stdout] test runtime::waker_tests::tests::test_waker_clone ... ok [INFO] [stdout] test runtime::waker_tests::tests::test_waker_wake ... ok [INFO] [stdout] test runtime::executor_tests::tests::test_executor_creation ... ok [INFO] [stdout] test runtime::sleep_tests::tests::test_sleep_creation ... ok [INFO] [stdout] test data::data_layer_tests::tests::test_data_round_trip ... ok [INFO] [stdout] test runtime::reciever_tests::tests::test_tcp_receiver_large_buffer ... ok [INFO] [stdout] test runtime::reciever_tests::tests::test_tcp_receiver_with_existing_buffer ... ok [INFO] [stdout] test runtime::sender_tests::tests::test_tcp_sender_large_buffer ... ok [INFO] [stdout] test runtime::sender_tests::tests::test_tcp_sender_with_empty_buffer ... ok [INFO] [stdout] test runtime::executor_tests::tests::test_executor_handles_pending_futures ... ok [INFO] [stdout] test runtime::executor_tests::tests::test_executor_with_sleep_future ... ok [INFO] [stdout] test runtime::sleep_tests::tests::test_sleep_becomes_ready ... ok [INFO] [stdout] [INFO] [stdout] test result: ok. 29 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.07s [INFO] [stdout] [INFO] [stderr] Running unittests src/main.rs (/opt/rustwide/target/debug/deps/std_async-c70618cf08b5762a) [INFO] [stdout] [INFO] [stdout] running 0 tests [INFO] [stdout] [INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s [INFO] [stdout] [INFO] [stderr] Running tests/benchmark_tests.rs (/opt/rustwide/target/debug/deps/benchmark_tests-a2408aca3f88bf5a) [INFO] [stdout] [INFO] [stdout] running 4 tests [INFO] [stdout] test bench_executor_many_tasks ... ok [INFO] [stdout] test bench_sleep_precision ... ok [INFO] [stdout] test bench_concurrent_sleeps ... FAILED [INFO] [stdout] test bench_memory_usage ... FAILED [INFO] [stdout] [INFO] [stdout] failures: [INFO] [stdout] [INFO] [stdout] ---- bench_concurrent_sleeps stdout ---- [INFO] [stdout] 100 concurrent sleeps completed in: 243.413162ms [INFO] [stdout] [INFO] [stdout] thread 'bench_concurrent_sleeps' (62) panicked at tests/benchmark_tests.rs:149:9: [INFO] [stdout] Task 0 took 121.339811ms, expected around 50ms [INFO] [stdout] stack backtrace: [INFO] [stdout] 0: 0x6201f9966a42 - std::backtrace_rs::backtrace::libunwind::trace::h7cddb8376417e7cc [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/../../backtrace/src/backtrace/libunwind.rs:117:9 [INFO] [stdout] 1: 0x6201f9966a42 - std::backtrace_rs::backtrace::trace_unsynchronized::hb4e41acf4b349ff1 [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/../../backtrace/src/backtrace/mod.rs:66:14 [INFO] [stdout] 2: 0x6201f9966a42 - std::sys::backtrace::_print_fmt::h1222b80910ba6eb5 [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/sys/backtrace.rs:66:9 [INFO] [stdout] 3: 0x6201f9966a42 - ::fmt::h5bb8a979ba5db788 [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/sys/backtrace.rs:39:26 [INFO] [stdout] 4: 0x6201f9976caf - core::fmt::rt::Argument::fmt::h2ee2c138a50a7796 [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/core/src/fmt/rt.rs:173:76 [INFO] [stdout] 5: 0x6201f9976caf - core::fmt::write::h1e0dbf07fe3990bd [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/core/src/fmt/mod.rs:1468:25 [INFO] [stdout] 6: 0x6201f99342e3 - std::io::default_write_fmt::h7d7ad5ed6a883d81 [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/io/mod.rs:639:11 [INFO] [stdout] 7: 0x6201f99342e3 - std::io::Write::write_fmt::hc477d9325b345ece [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/io/mod.rs:1954:13 [INFO] [stdout] 8: 0x6201f99400a2 - std::sys::backtrace::BacktraceLock::print::h788d486777205086 [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/sys/backtrace.rs:42:9 [INFO] [stdout] 9: 0x6201f9944bbf - std::panicking::default_hook::{{closure}}::ha7bdfeb5949fc0fa [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/panicking.rs:301:27 [INFO] [stdout] 10: 0x6201f9944a51 - std::panicking::default_hook::h6dad75ec721846f4 [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/panicking.rs:325:9 [INFO] [stdout] 11: 0x6201f9900b4e - as core::ops::function::Fn>::call::h33799d04b0b96146 [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/alloc/src/boxed.rs:1999:9 [INFO] [stdout] 12: 0x6201f9900b4e - test::test_main_with_exit_callback::{{closure}}::h880e31c829a2eb5d [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/test/src/lib.rs:145:21 [INFO] [stdout] 13: 0x6201f994525e - as core::ops::function::Fn>::call::h5e1b8c652ea49180 [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/alloc/src/boxed.rs:1999:9 [INFO] [stdout] 14: 0x6201f994525e - std::panicking::panic_with_hook::hba00e869ada17676 [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/panicking.rs:842:13 [INFO] [stdout] 15: 0x6201f994502a - std::panicking::panic_handler::{{closure}}::h3d21149c258e5ceb [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/panicking.rs:707:13 [INFO] [stdout] 16: 0x6201f99401d9 - std::sys::backtrace::__rust_end_short_backtrace::h4f5d9b9dfb3e6ec1 [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/sys/backtrace.rs:174:18 [INFO] [stdout] 17: 0x6201f992854d - __rustc[9a7a9f9af7564de1]::rust_begin_unwind [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/panicking.rs:698:5 [INFO] [stdout] 18: 0x6201f997e720 - core::panicking::panic_fmt::h78e817a90331d98b [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/core/src/panicking.rs:75:14 [INFO] [stdout] 19: 0x6201f98d9a05 - benchmark_tests::bench_concurrent_sleeps::ha9fa905f631cc886 [INFO] [stdout] at /opt/rustwide/workdir/tests/benchmark_tests.rs:149:9 [INFO] [stdout] 20: 0x6201f98d9e57 - benchmark_tests::bench_concurrent_sleeps::{{closure}}::hc87673411a93e700 [INFO] [stdout] at /opt/rustwide/workdir/tests/benchmark_tests.rs:98:29 [INFO] [stdout] 21: 0x6201f98d3e36 - core::ops::function::FnOnce::call_once::h714efdcc95294aec [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/core/src/ops/function.rs:253:5 [INFO] [stdout] 22: 0x6201f990094b - core::ops::function::FnOnce::call_once::h7f4b4fba903e39d5 [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/core/src/ops/function.rs:253:5 [INFO] [stdout] 23: 0x6201f990094b - test::__rust_begin_short_backtrace::h9277cb6a2ccfc000 [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/test/src/lib.rs:663:18 [INFO] [stdout] 24: 0x6201f99163f5 - test::run_test_in_process::{{closure}}::h9aea5ca90d1f4423 [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/test/src/lib.rs:686:74 [INFO] [stdout] 25: 0x6201f99163f5 - as core::ops::function::FnOnce<()>>::call_once::h364f5fe6cc8afa85 [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/core/src/panic/unwind_safe.rs:272:9 [INFO] [stdout] 26: 0x6201f99163f5 - std::panicking::catch_unwind::do_call::h2b2bec3317fe53ec [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/panicking.rs:590:40 [INFO] [stdout] 27: 0x6201f99163f5 - std::panicking::catch_unwind::hc3763734156da4af [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/panicking.rs:553:19 [INFO] [stdout] 28: 0x6201f99163f5 - std::panic::catch_unwind::h28038391e867eabc [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/panic.rs:359:14 [INFO] [stdout] 29: 0x6201f99163f5 - test::run_test_in_process::had0273166695a036 [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/test/src/lib.rs:686:27 [INFO] [stdout] 30: 0x6201f99163f5 - test::run_test::{{closure}}::h743d09d4bb476605 [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/test/src/lib.rs:607:43 [INFO] [stdout] 31: 0x6201f98ece14 - test::run_test::{{closure}}::he950b8f9118d37e2 [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/test/src/lib.rs:637:41 [INFO] [stdout] 32: 0x6201f98ece14 - std::sys::backtrace::__rust_begin_short_backtrace::hbfaffa6539f6abb7 [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/sys/backtrace.rs:158:18 [INFO] [stdout] 33: 0x6201f98f06fa - std::thread::Builder::spawn_unchecked_::{{closure}}::{{closure}}::hcce3c2c65b9c3b20 [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/thread/mod.rs:559:17 [INFO] [stdout] 34: 0x6201f98f06fa - as core::ops::function::FnOnce<()>>::call_once::hb66b30b6d37985a5 [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/core/src/panic/unwind_safe.rs:272:9 [INFO] [stdout] 35: 0x6201f98f06fa - std::panicking::catch_unwind::do_call::h992bbe2c32dc1d79 [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/panicking.rs:590:40 [INFO] [stdout] 36: 0x6201f98f06fa - std::panicking::catch_unwind::h412991d5237de610 [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/panicking.rs:553:19 [INFO] [stdout] 37: 0x6201f98f06fa - std::panic::catch_unwind::ha82b139b3eb5840a [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/panic.rs:359:14 [INFO] [stdout] 38: 0x6201f98f06fa - std::thread::Builder::spawn_unchecked_::{{closure}}::ha823b36f5114938e [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/thread/mod.rs:557:30 [INFO] [stdout] 39: 0x6201f98f06fa - core::ops::function::FnOnce::call_once{{vtable.shim}}::hf120e7b1db22ac07 [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/core/src/ops/function.rs:253:5 [INFO] [stdout] 40: 0x6201f993a45f - as core::ops::function::FnOnce>::call_once::h3e049222c99298ac [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/alloc/src/boxed.rs:1985:9 [INFO] [stdout] 41: 0x6201f993a45f - std::sys::pal::unix::thread::Thread::new::thread_start::h942e336943ad5963 [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/sys/pal/unix/thread.rs:118:17 [INFO] [stdout] 42: 0x75487b82daa4 - [INFO] [stdout] 43: 0x75487b8baa34 - clone [INFO] [stdout] 44: 0x0 - [INFO] [stdout] [INFO] [stdout] ---- bench_memory_usage stdout ---- [INFO] [stdout] Created 5000 tasks, queue length: 5000 [INFO] [stdout] Executed 5000 tasks in: 4.308632153s [INFO] [stdout] Final queue length: 0 [INFO] [stdout] [INFO] [stdout] thread 'bench_memory_usage' (64) panicked at tests/benchmark_tests.rs:205:5: [INFO] [stdout] assertion failed: execution_time < Duration::from_secs(2) [INFO] [stdout] stack backtrace: [INFO] [stdout] 0: 0x6201f9966a42 - std::backtrace_rs::backtrace::libunwind::trace::h7cddb8376417e7cc [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/../../backtrace/src/backtrace/libunwind.rs:117:9 [INFO] [stdout] 1: 0x6201f9966a42 - std::backtrace_rs::backtrace::trace_unsynchronized::hb4e41acf4b349ff1 [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/../../backtrace/src/backtrace/mod.rs:66:14 [INFO] [stdout] 2: 0x6201f9966a42 - std::sys::backtrace::_print_fmt::h1222b80910ba6eb5 [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/sys/backtrace.rs:66:9 [INFO] [stdout] 3: 0x6201f9966a42 - ::fmt::h5bb8a979ba5db788 [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/sys/backtrace.rs:39:26 [INFO] [stdout] 4: 0x6201f9976caf - core::fmt::rt::Argument::fmt::h2ee2c138a50a7796 [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/core/src/fmt/rt.rs:173:76 [INFO] [stdout] 5: 0x6201f9976caf - core::fmt::write::h1e0dbf07fe3990bd [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/core/src/fmt/mod.rs:1468:25 [INFO] [stdout] 6: 0x6201f99342e3 - std::io::default_write_fmt::h7d7ad5ed6a883d81 [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/io/mod.rs:639:11 [INFO] [stdout] 7: 0x6201f99342e3 - std::io::Write::write_fmt::hc477d9325b345ece [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/io/mod.rs:1954:13 [INFO] [stdout] 8: 0x6201f99400a2 - std::sys::backtrace::BacktraceLock::print::h788d486777205086 [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/sys/backtrace.rs:42:9 [INFO] [stdout] 9: 0x6201f9944bbf - std::panicking::default_hook::{{closure}}::ha7bdfeb5949fc0fa [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/panicking.rs:301:27 [INFO] [stdout] 10: 0x6201f9944a51 - std::panicking::default_hook::h6dad75ec721846f4 [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/panicking.rs:325:9 [INFO] [stdout] 11: 0x6201f9900b4e - as core::ops::function::Fn>::call::h33799d04b0b96146 [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/alloc/src/boxed.rs:1999:9 [INFO] [stdout] 12: 0x6201f9900b4e - test::test_main_with_exit_callback::{{closure}}::h880e31c829a2eb5d [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/test/src/lib.rs:145:21 [INFO] [stdout] 13: 0x6201f994525e - as core::ops::function::Fn>::call::h5e1b8c652ea49180 [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/alloc/src/boxed.rs:1999:9 [INFO] [stdout] 14: 0x6201f994525e - std::panicking::panic_with_hook::hba00e869ada17676 [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/panicking.rs:842:13 [INFO] [stdout] 15: 0x6201f9944ff6 - std::panicking::panic_handler::{{closure}}::h3d21149c258e5ceb [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/panicking.rs:700:13 [INFO] [stdout] 16: 0x6201f99401d9 - std::sys::backtrace::__rust_end_short_backtrace::h4f5d9b9dfb3e6ec1 [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/sys/backtrace.rs:174:18 [INFO] [stdout] 17: 0x6201f992854d - __rustc[9a7a9f9af7564de1]::rust_begin_unwind [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/panicking.rs:698:5 [INFO] [stdout] 18: 0x6201f997e720 - core::panicking::panic_fmt::h78e817a90331d98b [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/core/src/panicking.rs:75:14 [INFO] [stdout] 19: 0x6201f997e6fc - core::panicking::panic::h33d2d00634ffed6f [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/core/src/panicking.rs:145:5 [INFO] [stdout] 20: 0x6201f98d859e - benchmark_tests::bench_memory_usage::ha624d81f520c70c0 [INFO] [stdout] at /opt/rustwide/workdir/tests/benchmark_tests.rs:205:5 [INFO] [stdout] 21: 0x6201f98d86a7 - benchmark_tests::bench_memory_usage::{{closure}}::h175dc06dc5dcefcf [INFO] [stdout] at /opt/rustwide/workdir/tests/benchmark_tests.rs:156:24 [INFO] [stdout] 22: 0x6201f98d3df6 - core::ops::function::FnOnce::call_once::h2ad7d7e7d0f58546 [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/core/src/ops/function.rs:253:5 [INFO] [stdout] 23: 0x6201f990094b - core::ops::function::FnOnce::call_once::h7f4b4fba903e39d5 [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/core/src/ops/function.rs:253:5 [INFO] [stdout] 24: 0x6201f990094b - test::__rust_begin_short_backtrace::h9277cb6a2ccfc000 [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/test/src/lib.rs:663:18 [INFO] [stdout] 25: 0x6201f99163f5 - test::run_test_in_process::{{closure}}::h9aea5ca90d1f4423 [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/test/src/lib.rs:686:74 [INFO] [stdout] 26: 0x6201f99163f5 - as core::ops::function::FnOnce<()>>::call_once::h364f5fe6cc8afa85 [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/core/src/panic/unwind_safe.rs:272:9 [INFO] [stdout] 27: 0x6201f99163f5 - std::panicking::catch_unwind::do_call::h2b2bec3317fe53ec [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/panicking.rs:590:40 [INFO] [stdout] 28: 0x6201f99163f5 - std::panicking::catch_unwind::hc3763734156da4af [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/panicking.rs:553:19 [INFO] [stdout] 29: 0x6201f99163f5 - std::panic::catch_unwind::h28038391e867eabc [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/panic.rs:359:14 [INFO] [stdout] 30: 0x6201f99163f5 - test::run_test_in_process::had0273166695a036 [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/test/src/lib.rs:686:27 [INFO] [stdout] 31: 0x6201f99163f5 - test::run_test::{{closure}}::h743d09d4bb476605 [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/test/src/lib.rs:607:43 [INFO] [stdout] 32: 0x6201f98ece14 - test::run_test::{{closure}}::he950b8f9118d37e2 [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/test/src/lib.rs:637:41 [INFO] [stdout] 33: 0x6201f98ece14 - std::sys::backtrace::__rust_begin_short_backtrace::hbfaffa6539f6abb7 [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/sys/backtrace.rs:158:18 [INFO] [stdout] 34: 0x6201f98f06fa - std::thread::Builder::spawn_unchecked_::{{closure}}::{{closure}}::hcce3c2c65b9c3b20 [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/thread/mod.rs:559:17 [INFO] [stdout] 35: 0x6201f98f06fa - as core::ops::function::FnOnce<()>>::call_once::hb66b30b6d37985a5 [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/core/src/panic/unwind_safe.rs:272:9 [INFO] [stdout] 36: 0x6201f98f06fa - std::panicking::catch_unwind::do_call::h992bbe2c32dc1d79 [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/panicking.rs:590:40 [INFO] [stdout] 37: 0x6201f98f06fa - std::panicking::catch_unwind::h412991d5237de610 [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/panicking.rs:553:19 [INFO] [stdout] 38: 0x6201f98f06fa - std::panic::catch_unwind::ha82b139b3eb5840a [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/panic.rs:359:14 [INFO] [stdout] 39: 0x6201f98f06fa - std::thread::Builder::spawn_unchecked_::{{closure}}::ha823b36f5114938e [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/thread/mod.rs:557:30 [INFO] [stdout] 40: 0x6201f98f06fa - core::ops::function::FnOnce::call_once{{vtable.shim}}::hf120e7b1db22ac07 [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/core/src/ops/function.rs:253:5 [INFO] [stdout] 41: 0x6201f993a45f - as core::ops::function::FnOnce>::call_once::h3e049222c99298ac [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/alloc/src/boxed.rs:1985:9 [INFO] [stdout] 42: 0x6201f993a45f - std::sys::pal::unix::thread::Thread::new::thread_start::h942e336943ad5963 [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/sys/pal/unix/thread.rs:118:17 [INFO] [stdout] 43: 0x75487b82daa4 - [INFO] [stdout] 44: 0x75487b8baa34 - clone [INFO] [stdout] 45: 0x0 - [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] failures: [INFO] [stdout] bench_concurrent_sleeps [INFO] [stdout] bench_memory_usage [INFO] [stdout] [INFO] [stdout] test result: FAILED. 2 passed; 2 failed; 0 ignored; 0 measured; 0 filtered out; finished in 4.35s [INFO] [stdout] [INFO] [stderr] error: test failed, to rerun pass `--test benchmark_tests` [INFO] running `Command { std: "docker" "inspect" "4cd89db0dfd5ab1c90eefdd6f750e7f9a4d507ace1cf7857343d3f6e3dff7331", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "4cd89db0dfd5ab1c90eefdd6f750e7f9a4d507ace1cf7857343d3f6e3dff7331", kill_on_drop: false }` [INFO] [stdout] 4cd89db0dfd5ab1c90eefdd6f750e7f9a4d507ace1cf7857343d3f6e3dff7331