[INFO] fetching crate rumqttc-dev-patched 0.25.1-ack-notify... [INFO] testing rumqttc-dev-patched-0.25.1-ack-notify against try#dec9417b8611e34e787a3e4c37686b5131f9e5c5 for pr-154210-2 [INFO] extracting crate rumqttc-dev-patched 0.25.1-ack-notify into /workspace/builds/worker-5-tc2/source [INFO] started tweaking crates.io crate rumqttc-dev-patched 0.25.1-ack-notify [INFO] removed 0 missing examples [INFO] removed 0 missing tests [INFO] finished tweaking crates.io crate rumqttc-dev-patched 0.25.1-ack-notify [INFO] tweaked toml for crates.io crate rumqttc-dev-patched 0.25.1-ack-notify written to /workspace/builds/worker-5-tc2/source/Cargo.toml [INFO] validating manifest of crates.io crate rumqttc-dev-patched 0.25.1-ack-notify on toolchain dec9417b8611e34e787a3e4c37686b5131f9e5c5 [INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+dec9417b8611e34e787a3e4c37686b5131f9e5c5" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }` [INFO] crate crates.io crate rumqttc-dev-patched 0.25.1-ack-notify 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" "+dec9417b8611e34e787a3e4c37686b5131f9e5c5" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }` [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+dec9417b8611e34e787a3e4c37686b5131f9e5c5" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }` [INFO] [stdout] 2cb7f0bebb04638a439c3faa703ada1e85d921fd6ef2a47fc172da7e51597116 [INFO] running `Command { std: "docker" "start" "-a" "2cb7f0bebb04638a439c3faa703ada1e85d921fd6ef2a47fc172da7e51597116", kill_on_drop: false }` [INFO] running `Command { std: "docker" "inspect" "2cb7f0bebb04638a439c3faa703ada1e85d921fd6ef2a47fc172da7e51597116", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "2cb7f0bebb04638a439c3faa703ada1e85d921fd6ef2a47fc172da7e51597116", kill_on_drop: false }` [INFO] [stdout] 2cb7f0bebb04638a439c3faa703ada1e85d921fd6ef2a47fc172da7e51597116 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+dec9417b8611e34e787a3e4c37686b5131f9e5c5" "build" "--frozen" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] dc49d30917a2a54bc03f01569482d36efa7febb11142cc2d00917630853a601b [INFO] running `Command { std: "docker" "start" "-a" "dc49d30917a2a54bc03f01569482d36efa7febb11142cc2d00917630853a601b", kill_on_drop: false }` [INFO] [stderr] Compiling find-msvc-tools v0.1.5 [INFO] [stderr] Compiling aws-lc-rs v1.15.0 [INFO] [stderr] Compiling bytes v1.11.0 [INFO] [stderr] Compiling futures-core v0.3.31 [INFO] [stderr] Compiling rustls v0.23.35 [INFO] [stderr] Compiling rustls-pki-types v1.13.0 [INFO] [stderr] Compiling futures-sink v0.3.31 [INFO] [stderr] Compiling log v0.4.28 [INFO] [stderr] Compiling openssl-probe v0.1.6 [INFO] [stderr] Compiling spin v0.9.8 [INFO] [stderr] Compiling fixedbitset v0.5.7 [INFO] [stderr] Compiling syn v2.0.110 [INFO] [stderr] Compiling futures-util v0.3.31 [INFO] [stderr] Compiling flume v0.11.1 [INFO] [stderr] Compiling cc v1.2.47 [INFO] [stderr] Compiling rustls-pemfile v2.2.0 [INFO] [stderr] Compiling rustls-native-certs v0.8.2 [INFO] [stderr] Compiling cmake v0.1.54 [INFO] [stderr] Compiling aws-lc-sys v0.33.0 [INFO] [stderr] Compiling ring v0.17.14 [INFO] [stderr] Compiling tokio-macros v2.6.0 [INFO] [stderr] Compiling thiserror-impl v2.0.17 [INFO] [stderr] Compiling tokio v1.48.0 [INFO] [stderr] Compiling thiserror v2.0.17 [INFO] [stderr] Compiling rustls-webpki v0.102.8 [INFO] [stderr] Compiling tokio-stream v0.1.17 [INFO] [stderr] Compiling tokio-util v0.7.17 [INFO] [stderr] Compiling rustls-webpki v0.103.8 [INFO] [stderr] Compiling tokio-rustls v0.26.4 [INFO] [stderr] Compiling rumqttc-dev-patched v0.25.1-ack-notify (/opt/rustwide/workdir) [INFO] [stderr] Finished `dev` profile [unoptimized + debuginfo] target(s) in 35.08s [INFO] running `Command { std: "docker" "inspect" "dc49d30917a2a54bc03f01569482d36efa7febb11142cc2d00917630853a601b", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "dc49d30917a2a54bc03f01569482d36efa7febb11142cc2d00917630853a601b", kill_on_drop: false }` [INFO] [stdout] dc49d30917a2a54bc03f01569482d36efa7febb11142cc2d00917630853a601b [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+dec9417b8611e34e787a3e4c37686b5131f9e5c5" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] 7a6fcb776e0ce4b3896957940043b6fc988816906884d43d066413555a29eab4 [INFO] running `Command { std: "docker" "start" "-a" "7a6fcb776e0ce4b3896957940043b6fc988816906884d43d066413555a29eab4", kill_on_drop: false }` [INFO] [stderr] Compiling memchr v2.7.6 [INFO] [stderr] Compiling termcolor v1.4.1 [INFO] [stderr] Compiling addr2line v0.25.1 [INFO] [stderr] Compiling serde_derive v1.0.228 [INFO] [stderr] Compiling is-terminal v0.4.17 [INFO] [stderr] Compiling humantime v2.3.0 [INFO] [stderr] Compiling rustc-demangle v0.1.26 [INFO] [stderr] Compiling rustls v0.23.35 [INFO] [stderr] Compiling diff v0.1.13 [INFO] [stderr] Compiling pretty_assertions v1.4.1 [INFO] [stderr] Compiling aho-corasick v1.1.4 [INFO] [stderr] Compiling object v0.37.3 [INFO] [stderr] Compiling regex-automata v0.4.13 [INFO] [stderr] Compiling serde v1.0.228 [INFO] [stderr] Compiling bincode v1.3.3 [INFO] [stderr] Compiling tokio-rustls v0.26.4 [INFO] [stderr] Compiling regex v1.12.2 [INFO] [stderr] Compiling backtrace v0.3.76 [INFO] [stderr] Compiling rumqttc-dev-patched v0.25.1-ack-notify (/opt/rustwide/workdir) [INFO] [stderr] Compiling env_logger v0.10.2 [INFO] [stderr] Compiling color-backtrace v0.6.1 [INFO] [stderr] Compiling pretty_env_logger v0.5.0 [INFO] [stderr] Finished `test` profile [unoptimized + debuginfo] target(s) in 31.26s [INFO] running `Command { std: "docker" "inspect" "7a6fcb776e0ce4b3896957940043b6fc988816906884d43d066413555a29eab4", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "7a6fcb776e0ce4b3896957940043b6fc988816906884d43d066413555a29eab4", kill_on_drop: false }` [INFO] [stdout] 7a6fcb776e0ce4b3896957940043b6fc988816906884d43d066413555a29eab4 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+dec9417b8611e34e787a3e4c37686b5131f9e5c5" "test" "--frozen", kill_on_drop: false }` [INFO] [stdout] 1ae0cc529d5f2845a613350fef264b845c53fd850f77ab0f1399cce51144adeb [INFO] running `Command { std: "docker" "start" "-a" "1ae0cc529d5f2845a613350fef264b845c53fd850f77ab0f1399cce51144adeb", kill_on_drop: false }` [INFO] [stderr] Finished `test` profile [unoptimized + debuginfo] target(s) in 0.21s [INFO] [stderr] Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/rumqttc_dev_patched-86029d639bf27b95) [INFO] [stdout] [INFO] [stdout] running 73 tests [INFO] [stdout] test mqttbytes::topic::test::dollar_subscriptions_doesnt_match_dollar_topic ... ok [INFO] [stdout] test client::test::calling_iter_twice_on_connection_shouldnt_panic ... ok [INFO] [stdout] test client::test::should_be_able_to_build_test_client_from_channel ... ok [INFO] [stdout] test mqttbytes::topic::test::wildcards_are_detected_correctly ... ok [INFO] [stdout] test mqttbytes::v4::codec::tests::outgoing_max_packet_size_check ... ok [INFO] [stdout] test mqttbytes::topic::test::zero_len_subscriptions_are_not_allowed ... ok [INFO] [stdout] test mqttbytes::topic::test::topics_are_validated_correctly ... ok [INFO] [stdout] test mqttbytes::topic::test::filters_are_validated_correctly ... ok [INFO] [stdout] test mqttbytes::topic::test::topics_match_with_filters_as_expected ... ok [INFO] [stdout] test mqttbytes::v4::connack::test::connack_parsing_works ... ok [INFO] [stdout] test mqttbytes::v4::connect::test::connect_encoding_works ... ok [INFO] [stdout] test mqttbytes::v4::connect::test::connect_parsing_works ... ok [INFO] [stdout] test mqttbytes::v4::publish::test::qos0_publish_encoding_works ... ok [INFO] [stdout] test mqttbytes::v4::publish::test::qos0_publish_parsing_works ... ok [INFO] [stdout] test mqttbytes::v4::publish::test::qos1_publish_parsing_works ... ok [INFO] [stdout] test mqttbytes::v4::puback::test::puback_encoding_works ... ok [INFO] [stdout] test mqttbytes::v4::suback::test::suback_parsing_works ... ok [INFO] [stdout] test mqttbytes::v4::subscribe::test::subscribe_parsing_works ... ok [INFO] [stdout] test mqttbytes::v4::subscribe::test::subscribe_encoding_works ... ok [INFO] [stdout] test state::test::clean_is_calculating_pending_correctly ... ok [INFO] [stdout] test state::test::incoming_puback_with_pkid_greater_than_max_inflight_should_be_handled_gracefully ... ok [INFO] [stdout] test state::test::incoming_puback_should_remove_correct_publish_from_queue ... ok [INFO] [stdout] test mqttbytes::v4::publish::test::qos1_publish_encoding_works ... ok [INFO] [stdout] test state::test::incoming_pubcomp_should_release_correct_pkid_from_release_queue ... ok [INFO] [stdout] test state::test::incoming_publish_should_be_acked ... ok [INFO] [stdout] test state::test::incoming_publish_should_be_added_to_queue_correctly ... ok [INFO] [stdout] test state::test::incoming_publish_should_not_be_acked_with_manual_acks ... ok [INFO] [stdout] test state::test::incoming_pubrec_should_release_publish_from_queue_and_add_relid_to_rel_queue ... ok [INFO] [stdout] test state::test::incoming_pubrec_should_send_release_to_network_and_nothing_to_user ... ok [INFO] [stdout] test state::test::incoming_pubrel_should_send_comp_to_network_and_nothing_to_user ... ok [INFO] [stdout] test state::test::incoming_qos2_publish_should_send_rec_to_network_and_publish_to_user ... ok [INFO] [stdout] test state::test::next_pkid_increments_as_expected ... ok [INFO] [stdout] test state::test::outgoing_ping_handle_should_succeed_if_pingresp_is_received ... ok [INFO] [stdout] test state::test::outgoing_ping_handle_should_throw_errors_for_no_pingresp ... ok [INFO] [stdout] test test::accept_empty_client_id ... ok [INFO] [stdout] test test::set_clean_session_when_client_id_present ... ok [INFO] [stdout] test state::test::outgoing_publish_should_set_pkid_and_add_publish_to_queue ... ok [INFO] [stdout] test v5::mqttbytes::v5::codec::tests::outgoing_max_packet_size_check ... ok [INFO] [stdout] test v5::mqttbytes::v5::connack::test::length_calculation ... ok [INFO] [stdout] test v5::mqttbytes::v5::connect::test::length_calculation ... ok [INFO] [stdout] test v5::mqttbytes::v5::disconnect::test::disconnect1_encoding_works ... ok [INFO] [stdout] test v5::mqttbytes::v5::disconnect::test::disconnect1_parsing_works ... ok [INFO] [stdout] test v5::mqttbytes::v5::disconnect::test::disconnect2_parsing_works ... ok [INFO] [stdout] test v5::client::test::should_be_able_to_build_test_client_from_channel ... ok [INFO] [stdout] test v5::mqttbytes::v5::puback::test::length_calculation ... ok [INFO] [stdout] test v5::mqttbytes::v5::pubcomp::test::length_calculation ... ok [INFO] [stdout] test v5::mqttbytes::v5::publish::test::length_calculation ... ok [INFO] [stdout] test v5::mqttbytes::v5::pubrec::test::length_calculation ... ok [INFO] [stdout] test v5::mqttbytes::v5::pubrel::test::length_calculation ... ok [INFO] [stdout] test v5::mqttbytes::v5::suback::test::length_calculation ... ok [INFO] [stdout] test v5::mqttbytes::v5::subscribe::test::length_calculation ... ok [INFO] [stdout] test v5::mqttbytes::v5::unsuback::test::length_calculation ... ok [INFO] [stdout] test v5::mqttbytes::v5::unsubscribe::test::length_calculation ... ok [INFO] [stdout] test v5::mqttbytes::v5::disconnect::test::disconnect2_encoding_works ... ok [INFO] [stdout] test mqttbytes::v4::connack::test::connack_encoding_works ... ok [INFO] [stdout] test v5::mqttbytes::v5::disconnect::test::length_calculation ... ok [INFO] [stdout] test v5::state::test::outgoing_publish_with_max_inflight_is_ok ... ok [INFO] [stdout] test v5::test::allow_empty_client_id ... ok [INFO] [stdout] test v5::client::test::calling_iter_twice_on_connection_shouldnt_panic ... ok [INFO] [stdout] test v5::state::test::outgoing_publish_should_set_pkid_and_add_publish_to_queue ... ok [INFO] [stdout] test v5::state::test::incoming_pubrec_should_release_publish_from_queue_and_add_relid_to_rel_queue ... ok [INFO] [stdout] test v5::state::test::incoming_pubrec_should_send_release_to_network_and_nothing_to_user ... ok [INFO] [stdout] test v5::state::test::incoming_qos2_publish_should_send_rec_to_network_and_publish_to_user ... ok [INFO] [stdout] test v5::state::test::incoming_pubcomp_should_release_correct_pkid_from_release_queue ... ok [INFO] [stdout] test v5::state::test::incoming_puback_should_remove_correct_publish_from_queue ... ok [INFO] [stdout] test v5::state::test::incoming_publish_should_not_be_acked_with_manual_acks ... ok [INFO] [stdout] test v5::state::test::incoming_pubrel_should_send_comp_to_network_and_nothing_to_user ... ok [INFO] [stdout] test v5::state::test::incoming_puback_with_pkid_greater_than_max_inflight_should_be_handled_gracefully ... ok [INFO] [stdout] test v5::state::test::incoming_publish_should_be_acked ... ok [INFO] [stdout] test v5::state::test::next_pkid_increments_as_expected ... ok [INFO] [stdout] test v5::state::test::outgoing_ping_handle_should_throw_errors_for_no_pingresp ... ok [INFO] [stdout] test v5::state::test::incoming_publish_should_be_added_to_queue_correctly ... ok [INFO] [stdout] test v5::state::test::outgoing_ping_handle_should_succeed_if_pingresp_is_received ... ok [INFO] [stdout] [INFO] [stdout] test result: ok. 73 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.12s [INFO] [stdout] [INFO] [stderr] Running tests/broker.rs (/opt/rustwide/target/debug/deps/broker-b8341234d92a3714) [INFO] [stderr] Running tests/reliability.rs (/opt/rustwide/target/debug/deps/reliability-2fbe136e13a68ee1) [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] [stdout] [INFO] [stdout] running 20 tests [INFO] [stdout] test packet_id_collisions_are_detected_and_flow_control_is_applied ... ignored [INFO] [stdout] test resolve_on_qos0_before_write_to_tcp_buffer ... ok [INFO] [stdout] test resolve_on_unsub_ack_from_broker ... FAILED [INFO] [stdout] test test_valid_keep_alive_values ... ok [INFO] [stdout] test test_zero_keep_alive_values ... ok [INFO] [stdout] test test_invalid_keep_alive_value - should panic ... ok [INFO] [stdout] test next_poll_after_connect_failure_reconnects ... ok [INFO] [stdout] test resolve_on_sub_ack_from_broker ... ok [INFO] [stdout] test resolve_on_qos1_ack_from_broker ... ok [INFO] [stdout] test idle_connection_triggers_pings_on_time ... ok [INFO] [stdout] test resolve_on_qos2_ack_from_broker ... ok [INFO] [stdout] test state_is_being_cleaned_properly_and_pending_request_calculated_properly ... ok [INFO] [stdout] test connection_should_timeout_on_time ... ok [INFO] [stdout] test requests_are_recovered_after_inflight_queue_size_falls_below_max ... ok [INFO] [stdout] test reconnection_resumes_from_the_previous_state ... ok [INFO] [stdout] test reconnection_resends_unacked_packets_from_the_previous_connection_first ... ok [INFO] [stdout] test detects_halfopen_connections_in_the_second_ping_request ... ok [INFO] [stdout] test requests_are_blocked_after_max_inflight_queue_size ... ok [INFO] [stdout] test some_outgoing_and_no_incoming_should_trigger_pings_on_time ... ok [INFO] [stdout] test some_incoming_and_no_outgoing_should_trigger_pings_on_time ... ok [INFO] [stdout] [INFO] [stdout] failures: [INFO] [stdout] [INFO] [stdout] ---- resolve_on_unsub_ack_from_broker stdout ---- [INFO] [stdout] [INFO] [stdout] thread 'resolve_on_unsub_ack_from_broker' (104) panicked at tests/broker.rs:56:55: [INFO] [stdout] called `Result::unwrap()` on an `Err` value: Os { code: 98, kind: AddrInUse, message: "Address already in use" } [INFO] [stdout] stack backtrace: [INFO] [stdout] 0: 0x5c97b56da30a - std[e72de78501789eb0]::backtrace_rs::backtrace::libunwind::trace [INFO] [stdout] at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/std/src/../../backtrace/src/backtrace/libunwind.rs:117:9 [INFO] [stdout] 1: 0x5c97b56da30a - std[e72de78501789eb0]::backtrace_rs::backtrace::trace_unsynchronized:: [INFO] [stdout] at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/std/src/../../backtrace/src/backtrace/mod.rs:66:14 [INFO] [stdout] 2: 0x5c97b56da30a - std[e72de78501789eb0]::sys::backtrace::_print_fmt [INFO] [stdout] at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/std/src/sys/backtrace.rs:74:9 [INFO] [stdout] 3: 0x5c97b56da30a - <::print::DisplayBacktrace as core[6aaeda2c7ad07a48]::fmt::Display>::fmt [INFO] [stdout] at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/std/src/sys/backtrace.rs:44:26 [INFO] [stdout] 4: 0x5c97b56efdca - ::fmt [INFO] [stdout] at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/core/src/fmt/rt.rs:152:76 [INFO] [stdout] 5: 0x5c97b56efdca - core[6aaeda2c7ad07a48]::fmt::write [INFO] [stdout] 6: 0x5c97b56df0c2 - std[e72de78501789eb0]::io::default_write_fmt::> [INFO] [stdout] at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/std/src/io/mod.rs:639:11 [INFO] [stdout] 7: 0x5c97b56df0c2 - as std[e72de78501789eb0]::io::Write>::write_fmt [INFO] [stdout] at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/std/src/io/mod.rs:1994:13 [INFO] [stdout] 8: 0x5c97b56b86af - ::print [INFO] [stdout] at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/std/src/sys/backtrace.rs:47:9 [INFO] [stdout] 9: 0x5c97b56b86af - std[e72de78501789eb0]::panicking::default_hook::{closure#0} [INFO] [stdout] at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/std/src/panicking.rs:292:27 [INFO] [stdout] 10: 0x5c97b56d2139 - std[e72de78501789eb0]::panicking::default_hook [INFO] [stdout] at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/std/src/panicking.rs:316:9 [INFO] [stdout] 11: 0x5c97b51f798c - core[6aaeda2c7ad07a48]::ops::function::Fn<(&'a std[e72de78501789eb0]::panic::PanicHookInfo<'b>,), Output = ()> + core[6aaeda2c7ad07a48]::marker::Sync + core[6aaeda2c7ad07a48]::marker::Send> as core[6aaeda2c7ad07a48]::ops::function::Fn<(&std[e72de78501789eb0]::panic::PanicHookInfo,)>>::call [INFO] [stdout] at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/alloc/src/boxed.rs:2254:9 [INFO] [stdout] 12: 0x5c97b51f798c - test[4f12b380f1fb68c5]::test_main_with_exit_callback::::{closure#0} [INFO] [stdout] at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/test/src/lib.rs:145:21 [INFO] [stdout] 13: 0x5c97b56d23b2 - core[6aaeda2c7ad07a48]::ops::function::Fn<(&'a std[e72de78501789eb0]::panic::PanicHookInfo<'b>,), Output = ()> + core[6aaeda2c7ad07a48]::marker::Sync + core[6aaeda2c7ad07a48]::marker::Send> as core[6aaeda2c7ad07a48]::ops::function::Fn<(&std[e72de78501789eb0]::panic::PanicHookInfo,)>>::call [INFO] [stdout] at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/alloc/src/boxed.rs:2254:9 [INFO] [stdout] 14: 0x5c97b56d23b2 - std[e72de78501789eb0]::panicking::panic_with_hook [INFO] [stdout] at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/std/src/panicking.rs:833:13 [INFO] [stdout] 15: 0x5c97b56b8768 - std[e72de78501789eb0]::panicking::panic_handler::{closure#0} [INFO] [stdout] at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/std/src/panicking.rs:698:13 [INFO] [stdout] 16: 0x5c97b56b0059 - std[e72de78501789eb0]::sys::backtrace::__rust_end_short_backtrace:: [INFO] [stdout] at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/std/src/sys/backtrace.rs:182:18 [INFO] [stdout] 17: 0x5c97b56b963d - __rustc[78a4cf188af856fc]::rust_begin_unwind [INFO] [stdout] at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/std/src/panicking.rs:689:5 [INFO] [stdout] 18: 0x5c97b56f05bc - core[6aaeda2c7ad07a48]::panicking::panic_fmt [INFO] [stdout] at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/core/src/panicking.rs:80:14 [INFO] [stdout] 19: 0x5c97b56f0302 - core[6aaeda2c7ad07a48]::result::unwrap_failed [INFO] [stdout] at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/core/src/result.rs:1867:5 [INFO] [stdout] 20: 0x5c97b51cc3f2 - >::unwrap [INFO] [stdout] at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/core/src/result.rs:1233:23 [INFO] [stdout] 21: 0x5c97b51cc3f2 - ::new::{closure#0} [INFO] [stdout] at /opt/rustwide/workdir/tests/broker.rs:56:55 [INFO] [stdout] 22: 0x5c97b5175247 - reliability[ea433707265253e2]::resolve_on_unsub_ack_from_broker::{closure#0} [INFO] [stdout] at /opt/rustwide/workdir/tests/reliability.rs:870:50 [INFO] [stdout] 23: 0x5c97b51c0a82 - > as core[6aaeda2c7ad07a48]::future::future::Future>::poll [INFO] [stdout] at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/core/src/future/future.rs:133:9 [INFO] [stdout] 24: 0x5c97b51c0afd - >> as core[6aaeda2c7ad07a48]::future::future::Future>::poll [INFO] [stdout] at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/core/src/future/future.rs:133:9 [INFO] [stdout] 25: 0x5c97b518edfd - ::block_on::>>>::{closure#0}::{closure#0}::{closure#0} [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.48.0/src/runtime/scheduler/current_thread/mod.rs:742:70 [INFO] [stdout] 26: 0x5c97b518ecfb - tokio[1d08f3551f384f2d]::task::coop::with_budget::, ::block_on>>>::{closure#0}::{closure#0}::{closure#0}> [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.48.0/src/task/coop/mod.rs:167:5 [INFO] [stdout] 27: 0x5c97b518ecfb - tokio[1d08f3551f384f2d]::task::coop::budget::, ::block_on>>>::{closure#0}::{closure#0}::{closure#0}> [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.48.0/src/task/coop/mod.rs:133:5 [INFO] [stdout] 28: 0x5c97b518ecfb - ::block_on::>>>::{closure#0}::{closure#0} [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.48.0/src/runtime/scheduler/current_thread/mod.rs:742:25 [INFO] [stdout] 29: 0x5c97b5183260 - ::enter::, ::block_on>>>::{closure#0}::{closure#0}> [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.48.0/src/runtime/scheduler/current_thread/mod.rs:432:19 [INFO] [stdout] 30: 0x5c97b518e08d - ::block_on::>>>::{closure#0} [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.48.0/src/runtime/scheduler/current_thread/mod.rs:741:44 [INFO] [stdout] 31: 0x5c97b518d374 - ::enter::<::block_on>>>::{closure#0}, core[6aaeda2c7ad07a48]::option::Option<()>>::{closure#0} [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.48.0/src/runtime/scheduler/current_thread/mod.rs:829:68 [INFO] [stdout] 32: 0x5c97b51c127b - >::set::<::enter<::block_on>>>::{closure#0}, core[6aaeda2c7ad07a48]::option::Option<()>>::{closure#0}, (alloc[28d2dab30c1dc666]::boxed::Box, core[6aaeda2c7ad07a48]::option::Option<()>)> [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.48.0/src/runtime/context/scoped.rs:40:9 [INFO] [stdout] 33: 0x5c97b51b54d9 - tokio[1d08f3551f384f2d]::runtime::context::set_scheduler::<(alloc[28d2dab30c1dc666]::boxed::Box, core[6aaeda2c7ad07a48]::option::Option<()>), ::enter<::block_on>>>::{closure#0}, core[6aaeda2c7ad07a48]::option::Option<()>>::{closure#0}>::{closure#0} [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.48.0/src/runtime/context.rs:176:38 [INFO] [stdout] 34: 0x5c97b51c16f2 - >::try_with::, core[6aaeda2c7ad07a48]::option::Option<()>), ::enter<::block_on>>>::{closure#0}, core[6aaeda2c7ad07a48]::option::Option<()>>::{closure#0}>::{closure#0}, (alloc[28d2dab30c1dc666]::boxed::Box, core[6aaeda2c7ad07a48]::option::Option<()>)> [INFO] [stdout] at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/std/src/thread/local.rs:462:12 [INFO] [stdout] 35: 0x5c97b51c137e - >::with::, core[6aaeda2c7ad07a48]::option::Option<()>), ::enter<::block_on>>>::{closure#0}, core[6aaeda2c7ad07a48]::option::Option<()>>::{closure#0}>::{closure#0}, (alloc[28d2dab30c1dc666]::boxed::Box, core[6aaeda2c7ad07a48]::option::Option<()>)> [INFO] [stdout] at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/std/src/thread/local.rs:426:20 [INFO] [stdout] 36: 0x5c97b51b086d - tokio[1d08f3551f384f2d]::runtime::context::set_scheduler::<(alloc[28d2dab30c1dc666]::boxed::Box, core[6aaeda2c7ad07a48]::option::Option<()>), ::enter<::block_on>>>::{closure#0}, core[6aaeda2c7ad07a48]::option::Option<()>>::{closure#0}> [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.48.0/src/runtime/context.rs:176:17 [INFO] [stdout] 37: 0x5c97b518c6a0 - ::enter::<::block_on>>>::{closure#0}, core[6aaeda2c7ad07a48]::option::Option<()>> [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.48.0/src/runtime/scheduler/current_thread/mod.rs:829:27 [INFO] [stdout] 38: 0x5c97b518c997 - ::block_on::>>> [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.48.0/src/runtime/scheduler/current_thread/mod.rs:729:24 [INFO] [stdout] 39: 0x5c97b518cf86 - ::block_on::>>::{closure#0} [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.48.0/src/runtime/scheduler/current_thread/mod.rs:200:33 [INFO] [stdout] 40: 0x5c97b51b5045 - tokio[1d08f3551f384f2d]::runtime::context::runtime::enter_runtime::<::block_on>>::{closure#0}, ()> [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.48.0/src/runtime/context/runtime.rs:65:16 [INFO] [stdout] 41: 0x5c97b5182d91 - ::block_on::>> [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.48.0/src/runtime/scheduler/current_thread/mod.rs:188:9 [INFO] [stdout] 42: 0x5c97b51d651a - ::block_on_inner::>> [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.48.0/src/runtime/runtime.rs:368:52 [INFO] [stdout] 43: 0x5c97b51d66df - ::block_on::>> [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.48.0/src/runtime/runtime.rs:342:18 [INFO] [stdout] 44: 0x5c97b5181453 - reliability[ea433707265253e2]::resolve_on_unsub_ack_from_broker [INFO] [stdout] at /opt/rustwide/workdir/tests/reliability.rs:901:6 [INFO] [stdout] 45: 0x5c97b5170307 - reliability[ea433707265253e2]::resolve_on_unsub_ack_from_broker::{closure#0} [INFO] [stdout] at /opt/rustwide/workdir/tests/reliability.rs:851:44 [INFO] [stdout] 46: 0x5c97b515cb66 - >::call_once [INFO] [stdout] at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/core/src/ops/function.rs:250:5 [INFO] [stdout] 47: 0x5c97b51eba6b - core[6aaeda2c7ad07a48]::result::Result<(), alloc[28d2dab30c1dc666]::string::String> as core[6aaeda2c7ad07a48]::ops::function::FnOnce<()>>::call_once [INFO] [stdout] at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/core/src/ops/function.rs:250:5 [INFO] [stdout] 48: 0x5c97b51eba6b - test[4f12b380f1fb68c5]::__rust_begin_short_backtrace::, fn() -> core[6aaeda2c7ad07a48]::result::Result<(), alloc[28d2dab30c1dc666]::string::String>> [INFO] [stdout] at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/test/src/lib.rs:663:18 [INFO] [stdout] 49: 0x5c97b51f845b - test[4f12b380f1fb68c5]::run_test_in_process::{closure#0} [INFO] [stdout] at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/test/src/lib.rs:686:74 [INFO] [stdout] 50: 0x5c97b51f845b - as core[6aaeda2c7ad07a48]::ops::function::FnOnce<()>>::call_once [INFO] [stdout] at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/core/src/panic/unwind_safe.rs:275:9 [INFO] [stdout] 51: 0x5c97b51f845b - std[e72de78501789eb0]::panicking::catch_unwind::do_call::, core[6aaeda2c7ad07a48]::result::Result<(), alloc[28d2dab30c1dc666]::string::String>> [INFO] [stdout] at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/std/src/panicking.rs:581:40 [INFO] [stdout] 52: 0x5c97b51f845b - std[e72de78501789eb0]::panicking::catch_unwind::, core[6aaeda2c7ad07a48]::panic::unwind_safe::AssertUnwindSafe> [INFO] [stdout] at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/std/src/panicking.rs:544:19 [INFO] [stdout] 53: 0x5c97b51f845b - std[e72de78501789eb0]::panic::catch_unwind::, core[6aaeda2c7ad07a48]::result::Result<(), alloc[28d2dab30c1dc666]::string::String>> [INFO] [stdout] at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/std/src/panic.rs:359:14 [INFO] [stdout] 54: 0x5c97b51f845b - test[4f12b380f1fb68c5]::run_test_in_process [INFO] [stdout] at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/test/src/lib.rs:686:27 [INFO] [stdout] 55: 0x5c97b51f845b - test[4f12b380f1fb68c5]::run_test::{closure#0} [INFO] [stdout] at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/test/src/lib.rs:607:43 [INFO] [stdout] 56: 0x5c97b51f3b74 - test[4f12b380f1fb68c5]::run_test::{closure#1} [INFO] [stdout] at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/test/src/lib.rs:637:41 [INFO] [stdout] 57: 0x5c97b51f3b74 - std[e72de78501789eb0]::sys::backtrace::__rust_begin_short_backtrace:: [INFO] [stdout] at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/std/src/sys/backtrace.rs:166:18 [INFO] [stdout] 58: 0x5c97b51fb062 - std[e72de78501789eb0]::thread::lifecycle::spawn_unchecked::::{closure#1}::{closure#0} [INFO] [stdout] at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/std/src/thread/lifecycle.rs:91:13 [INFO] [stdout] 59: 0x5c97b51fb062 - ::{closure#1}::{closure#0}> as core[6aaeda2c7ad07a48]::ops::function::FnOnce<()>>::call_once [INFO] [stdout] at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/core/src/panic/unwind_safe.rs:275:9 [INFO] [stdout] 60: 0x5c97b51fb062 - std[e72de78501789eb0]::panicking::catch_unwind::do_call::::{closure#1}::{closure#0}>, ()> [INFO] [stdout] at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/std/src/panicking.rs:581:40 [INFO] [stdout] 61: 0x5c97b51fb062 - std[e72de78501789eb0]::panicking::catch_unwind::<(), core[6aaeda2c7ad07a48]::panic::unwind_safe::AssertUnwindSafe::{closure#1}::{closure#0}>> [INFO] [stdout] at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/std/src/panicking.rs:544:19 [INFO] [stdout] 62: 0x5c97b51fb062 - std[e72de78501789eb0]::panic::catch_unwind::::{closure#1}::{closure#0}>, ()> [INFO] [stdout] at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/std/src/panic.rs:359:14 [INFO] [stdout] 63: 0x5c97b51fb062 - std[e72de78501789eb0]::thread::lifecycle::spawn_unchecked::::{closure#1} [INFO] [stdout] at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/std/src/thread/lifecycle.rs:89:26 [INFO] [stdout] 64: 0x5c97b51fb062 - ::{closure#1} as core[6aaeda2c7ad07a48]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0} [INFO] [stdout] at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/core/src/ops/function.rs:250:5 [INFO] [stdout] 65: 0x5c97b56d9bdf - + core[6aaeda2c7ad07a48]::marker::Send> as core[6aaeda2c7ad07a48]::ops::function::FnOnce<()>>::call_once [INFO] [stdout] at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/alloc/src/boxed.rs:2240:9 [INFO] [stdout] 66: 0x5c97b56d9bdf - ::new::thread_start [INFO] [stdout] at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/std/src/sys/thread/unix.rs:118:17 [INFO] [stdout] 67: 0x74baeee75aa4 - [INFO] [stdout] 68: 0x74baeef02a64 - clone [INFO] [stdout] 69: 0x0 - [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] failures: [INFO] [stdout] resolve_on_unsub_ack_from_broker [INFO] [stdout] [INFO] [stdout] test result: FAILED. 18 passed; 1 failed; 1 ignored; 0 measured; 0 filtered out; finished in 15.03s [INFO] [stdout] [INFO] [stderr] error: test failed, to rerun pass `--test reliability` [INFO] running `Command { std: "docker" "inspect" "1ae0cc529d5f2845a613350fef264b845c53fd850f77ab0f1399cce51144adeb", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "1ae0cc529d5f2845a613350fef264b845c53fd850f77ab0f1399cce51144adeb", kill_on_drop: false }` [INFO] [stdout] 1ae0cc529d5f2845a613350fef264b845c53fd850f77ab0f1399cce51144adeb