[INFO] fetching crate messaging_thread_pool 4.1.0... [INFO] testing messaging_thread_pool-4.1.0 against beta-2025-10-28 for beta-1.92-2 [INFO] extracting crate messaging_thread_pool 4.1.0 into /workspace/builds/worker-2-tc2/source [INFO] started tweaking crates.io crate messaging_thread_pool 4.1.0 [INFO] finished tweaking crates.io crate messaging_thread_pool 4.1.0 [INFO] tweaked toml for crates.io crate messaging_thread_pool 4.1.0 written to /workspace/builds/worker-2-tc2/source/Cargo.toml [INFO] validating manifest of crates.io crate messaging_thread_pool 4.1.0 on toolchain beta-2025-10-28 [INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+beta-2025-10-28" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }` [INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+beta-2025-10-28" "generate-lockfile" "--manifest-path" "Cargo.toml", kill_on_drop: false }` [INFO] [stderr] Blocking waiting for file lock on package cache [INFO] [stderr] Updating crates.io index [INFO] [stderr] Blocking waiting for file lock on package cache [INFO] [stderr] Locking 102 packages to latest compatible versions [INFO] [stderr] Adding criterion v0.5.1 (available: v0.7.0) [INFO] [stderr] Adding rand v0.8.5 (available: v0.9.2) [INFO] [stderr] Adding rand_xoshiro v0.6.0 (available: v0.7.0) [INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+beta-2025-10-28" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }` [INFO] [stderr] Blocking waiting for file lock on package cache [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:4848fb76d95f26979359cc7e45710b1dbc8f3acb7aeedee7c460d7702230f228" "/opt/rustwide/cargo-home/bin/cargo" "+beta-2025-10-28" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }` [INFO] [stdout] 2f6dc0f65cc054ee72c5cd1d0f98ee4210b9232e93204a1eb2853c32ce333a83 [INFO] running `Command { std: "docker" "start" "-a" "2f6dc0f65cc054ee72c5cd1d0f98ee4210b9232e93204a1eb2853c32ce333a83", kill_on_drop: false }` [INFO] running `Command { std: "docker" "inspect" "2f6dc0f65cc054ee72c5cd1d0f98ee4210b9232e93204a1eb2853c32ce333a83", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "2f6dc0f65cc054ee72c5cd1d0f98ee4210b9232e93204a1eb2853c32ce333a83", kill_on_drop: false }` [INFO] [stdout] 2f6dc0f65cc054ee72c5cd1d0f98ee4210b9232e93204a1eb2853c32ce333a83 [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:4848fb76d95f26979359cc7e45710b1dbc8f3acb7aeedee7c460d7702230f228" "/opt/rustwide/cargo-home/bin/cargo" "+beta-2025-10-28" "build" "--frozen" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] 1ef1cd1add5645306e3104fc31166ceb287557d6becd43ccd49e95bd6631a6bf [INFO] running `Command { std: "docker" "start" "-a" "1ef1cd1add5645306e3104fc31166ceb287557d6becd43ccd49e95bd6631a6bf", kill_on_drop: false }` [INFO] [stderr] Compiling serde v1.0.228 [INFO] [stderr] Compiling regex-automata v0.4.13 [INFO] [stderr] Compiling thread_local v1.1.9 [INFO] [stderr] Compiling tracing-log v0.2.0 [INFO] [stderr] Compiling syn v2.0.108 [INFO] [stderr] Compiling getrandom v0.2.16 [INFO] [stderr] Compiling serde_json v1.0.145 [INFO] [stderr] Compiling time v0.3.44 [INFO] [stderr] Compiling rand_core v0.6.4 [INFO] [stderr] Compiling rand_chacha v0.3.1 [INFO] [stderr] Compiling rand_xoshiro v0.6.0 [INFO] [stderr] Compiling rand v0.8.5 [INFO] [stderr] Compiling tracing-serde v0.2.0 [INFO] [stderr] Compiling matchers v0.2.0 [INFO] [stderr] Compiling tracing-attributes v0.1.30 [INFO] [stderr] Compiling thiserror-impl v1.0.69 [INFO] [stderr] Compiling tracing v0.1.41 [INFO] [stderr] Compiling thiserror v1.0.69 [INFO] [stderr] Compiling tracing-subscriber v0.3.20 [INFO] [stderr] Compiling tracing-appender v0.2.3 [INFO] [stderr] Compiling messaging_thread_pool v4.1.0 (/opt/rustwide/workdir) [INFO] [stderr] Finished `dev` profile [unoptimized + debuginfo] target(s) in 14.51s [INFO] running `Command { std: "docker" "inspect" "1ef1cd1add5645306e3104fc31166ceb287557d6becd43ccd49e95bd6631a6bf", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "1ef1cd1add5645306e3104fc31166ceb287557d6becd43ccd49e95bd6631a6bf", kill_on_drop: false }` [INFO] [stdout] 1ef1cd1add5645306e3104fc31166ceb287557d6becd43ccd49e95bd6631a6bf [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:4848fb76d95f26979359cc7e45710b1dbc8f3acb7aeedee7c460d7702230f228" "/opt/rustwide/cargo-home/bin/cargo" "+beta-2025-10-28" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] 0419b2ce13ef6c444e21c417810661ebd296db548ffa425ff7d40a76e212191d [INFO] running `Command { std: "docker" "start" "-a" "0419b2ce13ef6c444e21c417810661ebd296db548ffa425ff7d40a76e212191d", kill_on_drop: false }` [INFO] [stderr] Compiling regex-automata v0.4.13 [INFO] [stderr] Compiling getrandom v0.2.16 [INFO] [stderr] Compiling libmimalloc-sys v0.1.44 [INFO] [stderr] Compiling zerocopy-derive v0.8.27 [INFO] [stderr] Compiling serde_derive v1.0.228 [INFO] [stderr] Compiling clap_builder v4.5.51 [INFO] [stderr] Compiling const_format_proc_macros v0.2.34 [INFO] [stderr] Compiling rand_core v0.6.4 [INFO] [stderr] Compiling rand_xoshiro v0.6.0 [INFO] [stderr] Compiling const_format v0.2.35 [INFO] [stderr] Compiling mimalloc v0.1.48 [INFO] [stderr] Compiling zerocopy v0.8.27 [INFO] [stderr] Compiling clap v4.5.51 [INFO] [stderr] Compiling matchers v0.2.0 [INFO] [stderr] Compiling regex v1.12.2 [INFO] [stderr] Compiling serde v1.0.228 [INFO] [stderr] Compiling tracing-serde v0.2.0 [INFO] [stderr] Compiling tinytemplate v1.2.1 [INFO] [stderr] Compiling tracing-subscriber v0.3.20 [INFO] [stderr] Compiling half v2.7.1 [INFO] [stderr] Compiling ppv-lite86 v0.2.21 [INFO] [stderr] Compiling ciborium-ll v0.2.2 [INFO] [stderr] Compiling rand_chacha v0.3.1 [INFO] [stderr] Compiling ciborium v0.2.2 [INFO] [stderr] Compiling rand v0.8.5 [INFO] [stderr] Compiling criterion v0.5.1 [INFO] [stderr] Compiling tracing-appender v0.2.3 [INFO] [stderr] Compiling messaging_thread_pool v4.1.0 (/opt/rustwide/workdir) [INFO] [stderr] Finished `test` profile [unoptimized + debuginfo] target(s) in 20.18s [INFO] running `Command { std: "docker" "inspect" "0419b2ce13ef6c444e21c417810661ebd296db548ffa425ff7d40a76e212191d", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "0419b2ce13ef6c444e21c417810661ebd296db548ffa425ff7d40a76e212191d", kill_on_drop: false }` [INFO] [stdout] 0419b2ce13ef6c444e21c417810661ebd296db548ffa425ff7d40a76e212191d [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:4848fb76d95f26979359cc7e45710b1dbc8f3acb7aeedee7c460d7702230f228" "/opt/rustwide/cargo-home/bin/cargo" "+beta-2025-10-28" "test" "--frozen", kill_on_drop: false }` [INFO] [stdout] 512f7572647a0717de50d22daa236d89003c4beb639669d8a4ef023d66ede804 [INFO] running `Command { std: "docker" "start" "-a" "512f7572647a0717de50d22daa236d89003c4beb639669d8a4ef023d66ede804", kill_on_drop: false }` [INFO] [stderr] Finished `test` profile [unoptimized + debuginfo] target(s) in 0.17s [INFO] [stderr] Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/messaging_thread_pool-617cf587df529972) [INFO] [stdout] [INFO] [stdout] running 77 tests [INFO] [stdout] 2025-11-05T02:47:28.239012Z  INFO messaging_thread_pool::id_based_blocking::tests: this should be logged to the console (1) [INFO] [stdout] test id_provider::id_provider_static::tests::get_first_id_gets_starting_id ... ignored, cannot test in test runner as it contains static variable [INFO] [stdout] test id_provider::id_provider_static::tests::getting_successive_id_providers_provides_successive_ids ... ignored, cannot test in test runner as it contains static variable [INFO] [stdout] test id_provider::id_provider_static::tests::peek_successive_id_providers_provides_successive_ids ... ignored, cannot test in test runner as it contains static variable [INFO] [stdout] test id_provider::id_provider_static::tests::visual_test ... ignored, cannot test in test runner as it contains static variable [INFO] [stdout] test id_provider::id_provider_mutex::tests::gets_ids_as_expected ... ok [INFO] [stdout] test id_provider::id_provider_mutex::tests::peek_id_as_expected ... ok [INFO] [stdout] test pool_thread::message_loop::tests::echo_message_responds_as_expected ... ok [INFO] [stdout] test pool_thread::message_loop::tests::id_1_receives_abort_message_exits_loop ... ok [INFO] [stdout] test pool_thread::message_loop::tests::id_1_receives_shutdown_message_exits_loop ... ok [INFO] [stdout] test pool_thread::message_loop::tests::id_2_receives_abort_message_exits_loop ... ok [INFO] [stdout] test pool_thread::message_loop::tests::id_2_receives_shutdown_message_exits_loop ... ok [INFO] [stdout] test new::tests::new_called_with_thread_pool_size_2_two_threads_created ... ok [INFO] [stdout] test pool_thread::message_loop::tests::init_id_101_102_thread_shutdown_clears_the_elements_returns_expected_shutdown_threads ... ok [INFO] [stdout] test new::tests::new_called_with_thread_pool_size_1_one_thread_created ... ok [INFO] [stdout] test pool_thread::message_loop::tests::init_id_1_2_thread_shutdown_clears_the_elements_returns_expected_shutdown_threads ... ok [INFO] [stdout] test pool_thread::shutdown_child_pool::tests::thread_pool_contains_single_element_2 ... ok [INFO] [stdout] test request_response::id_targeted::tests::request_response_contains_request_id_0_returns_0 ... ok [INFO] [stdout] test request_response::id_targeted::tests::request_response_contains_request_id_1_returns_1 ... ok [INFO] [stdout] test request_response::tests::request_response_contains_request_request_returns_request ... ok [INFO] [stdout] test pool_thread::message_loop::tests::send_remove_element_with_id_12_expected_element_removed_from_map_set ... ok [INFO] [stdout] test pool_thread::message_loop::tests::send_init_id_2_expected_element_added_to_map_set ... ok [INFO] [stdout] test pool_thread::message_loop::tests::send_remove_element_with_id_2_expected_element_removed_from_map_set ... ok [INFO] [stdout] test pool_thread::shutdown_child_pool::tests::thread_pool_contains_single_element_1 ... ok [INFO] [stdout] test pool_thread::shutdown_child_pool::tests::thread_pool_contains_no_elements_shutdown_returns_empty_vec ... ok [INFO] [stdout] test pool_thread::message_loop::tests::send_init_id_1_expected_element_added_to_map ... ok [INFO] [stdout] test samples::randoms::randoms_api::tests::returns_expected_ids_for_all_api_variants ... ok [INFO] [stdout] test receive::tests::single_init_request_on_a_single_thread_received_single_response_received ... ok [INFO] [stdout] test drop::tests::one_thread_drop_clean_shutdown_as_expected ... ok [INFO] [stdout] test receive::tests::three_init_requests_two_thread_received_three_responses_received ... ok [INFO] [stdout] test pool_thread::message_loop::tests::send_init_id_2_twice_returns_response_indicating_second_request_was_ignored ... ok [INFO] [stdout] test send::tests::pool_with_one_threads_send_two_echo_requests_both_processed_by_thread_0 ... ok [INFO] [stdout] test pool_thread::message_loop::tests::init_id_101_send_get_state_message_to_element_retrieved_expected_response ... ok [INFO] [stdout] test send::tests::pool_with_single_thread_sends_echo_request_echo_request_processed_by_thread_0 ... ok [INFO] [stdout] test send::tests::pool_with_two_threads_sends_echo_requests_echo_requests_processed_by_thread_0_and_thread_1 ... ok [INFO] [stdout] test sender_and_receiver::sender_and_receiver_mock::tests::empty_requests_and_responses_does_not_panic ... ok [INFO] [stdout] test sender_and_receiver::sender_and_receiver_mock::tests::one_response_only_returns_expected_response ... ok [INFO] [stdout] test sender_and_receiver::sender_and_receiver_mock::tests::zero_responses_returns_empty_iterator ... ok [INFO] [stdout] test sender_and_receiver::sender_and_receiver_raw_mock::tests::check_mock_send_and_sync ... ok [INFO] [stdout] test sender_and_receiver::sender_and_receiver_mock::tests::check_mock_send_and_sync ... ok [INFO] [stdout] test sender_and_receiver::sender_and_receiver_mock::tests::one_response_empty_requests_returns_empty_iterator ... ok [INFO] [stdout] test sender_and_receiver::sender_and_receiver_raw_mock::tests::empty_requests_and_responses_does_not_panic ... ok [INFO] [stdout] test sender_and_receiver::sender_and_receiver_mock::tests::two_responses_only_one_returned_is_complete_returns_false ... ok [INFO] [stdout] test sender_and_receiver::sender_and_receiver_mock::tests::two_responses_returned_over_multiple_requests ... ok [INFO] [stdout] test sender_and_receiver::sender_and_receiver_raw_mock::tests::one_response_empty_requests_returns_empty_iterator ... ok [INFO] [stdout] test sender_and_receiver::sender_and_receiver_raw_mock::tests::one_response_only_returns_expected_response ... ok [INFO] [stdout] test sender_and_receiver::sender_and_receiver_raw_mock::tests::two_responses_only_one_returned_is_complete_returns_false ... ok [INFO] [stdout] test send_and_receive::tests::single_thread_single_init_receives_expected_response ... ok [INFO] [stdout] test sender_and_receiver::tests::send_and_receive_one_functions_as_expected ... ok [INFO] [stdout] test sender_and_receiver::sender_and_receiver_raw_mock::tests::zero_responses_returns_empty_iterator ... ok [INFO] [stdout] test sender_and_receiver::sender_and_receiver_raw_mock::tests::two_responses_returned_over_multiple_heterogeneous_requests ... ok [INFO] [stdout] test sender_and_receiver::sender_and_receiver_raw_mock::tests::two_responses_returned_over_multiple_requests ... ok [INFO] [stdout] test send_and_receive::tests::two_threads_three_echoes_receives_expected_response ... ok [INFO] [stdout] test shutdown::tests::single_thread_clean_shutdown_as_expected ... ok [INFO] [stdout] test thread_endpoint::send::tests::pass_echo_message_through_echo_message_received_at_other_end_of_channel ... ok [INFO] [stdout] test shutdown::tests::two_threads_clean_shutdown_as_expected ... ok [INFO] [stdout] test tests::thread_pool_size_1_thread_count_1 ... ok [INFO] [stdout] test tests::thread_pool_size_2_thread_count_2 ... ok [INFO] [stdout] test thread_request_response::remove_pool_item_request::tests::request_id_1_id_returns_1 ... ok [INFO] [stdout] test thread_request_response::remove_pool_item_request::tests::request_id_2_id_returns_2 ... ok [INFO] [stdout] test thread_request_response::thread_abort_request::tests::request_id_1_id_returns_1 ... ok [INFO] [stdout] test thread_request_response::thread_abort_request::tests::request_id_2_id_returns_2 ... ok [INFO] [stdout] test thread_request_response::thread_echo_request::tests::request_id_1_id_returns_1 ... ok [INFO] [stdout] test thread_request_response::thread_echo_request::tests::request_id_2_id_returns_2 ... ok [INFO] [stdout] test thread_request_response::thread_shutdown_request::tests::request_id_1_id_returns_1 ... ok [INFO] [stdout] test thread_request_response::thread_shutdown_request::tests::request_id_2_id_returns_2 ... ok [INFO] [stdout] test shutdown::tests::two_threads_each_containing_a_sample_element_shutdown_simulates_child_thread_shutdown ... ok [INFO] [stdout] test id_based_blocking::id_based_writer::tests::sanity_check ... FAILED [INFO] [stdout] test request_response::id_targeted::tests::request_response_contains_response_request_panics - should panic ... ok [INFO] [stdout] test request_response::tests::request_response_contains_response_request_panics - should panic ... ok [INFO] [stdout] test sender_and_receiver::sender_and_receiver_mock::tests::one_expected_request_actual_requests_2_panics - should panic ... ok [INFO] [stdout] test id_based_blocking::tests::sanity_check ... FAILED [INFO] [stdout] test sender_and_receiver::sender_and_receiver_mock::tests::unmatched_requests_and_responses - should panic ... ok [INFO] [stdout] test sender_and_receiver::sender_and_receiver_mock::tests::one_expected_request_differs_from_one_actual_request - should panic ... ok [INFO] [stdout] test sender_and_receiver::sender_and_receiver_raw_mock::tests::one_expected_request_differs_from_one_actual_request - should panic ... ok [INFO] [stdout] test sender_and_receiver::sender_and_receiver_raw_mock::tests::one_expected_request_actual_requests_2_panics - should panic ... ok [INFO] [stdout] test sender_and_receiver::sender_and_receiver_raw_mock::tests::unmatched_requests_and_responses - should panic ... ok [INFO] [stdout] test shutdown::tests::single_thread_errors_trying_to_send_request_shutdown_does_not_panic has been running for over 60 seconds [ERROR] error running command: no output for 300 seconds [INFO] running `Command { std: "docker" "inspect" "512f7572647a0717de50d22daa236d89003c4beb639669d8a4ef023d66ede804", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "512f7572647a0717de50d22daa236d89003c4beb639669d8a4ef023d66ede804", kill_on_drop: false }` [INFO] [stdout] 512f7572647a0717de50d22daa236d89003c4beb639669d8a4ef023d66ede804