[INFO] fetching crate sz-orm-mqtt 9.0.0...
[INFO] testing sz-orm-mqtt-9.0.0 against 1.99.0-beta.8 for beta-1.100-2
[INFO] extracting crate sz-orm-mqtt 9.0.0 into /workspace/builds/worker-7-tc1/source
[INFO] started tweaking crates.io crate sz-orm-mqtt 9.0.0
[INFO] removed 0 missing tests
[INFO] finished tweaking crates.io crate sz-orm-mqtt 9.0.0
[INFO] tweaked toml for crates.io crate sz-orm-mqtt 9.0.0 written to /workspace/builds/worker-7-tc1/source/Cargo.toml
[INFO] validating manifest of crates.io crate sz-orm-mqtt 9.0.0 on toolchain 1.99.0-beta.8
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+1.99.0-beta.8" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate crates.io crate sz-orm-mqtt 9.0.0 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" "+1.99.0-beta.8" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr]     Updating crates.io index
[INFO] [stderr]  Downloading crates ...
[INFO] [stderr]   Downloaded rumqttc v0.25.1
[INFO] [stderr]   Downloaded aws-lc-rs v1.18.1
[INFO] [stderr]   Downloaded aws-lc-sys v0.45.0
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc1/target:/opt/rustwide/target:rw,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" "-m" "1610612736" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:3111399a4047eeb3a02b7a90e478d715f38a8c6669b5c4b49d30a17385265909" "sleep" "infinity", kill_on_drop: false }`
[INFO] [stdout] 6e3c469d07207c40600757943291323235c46142734ed829c3ee17e58e600a5d
[INFO] running `Command { std: "docker" "start" "6e3c469d07207c40600757943291323235c46142734ed829c3ee17e58e600a5d", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "6e3c469d07207c40600757943291323235c46142734ed829c3ee17e58e600a5d", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "exec" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-w" "/opt/rustwide/workdir" "--user" "0:0" "6e3c469d07207c40600757943291323235c46142734ed829c3ee17e58e600a5d" "/opt/rustwide/cargo-home/bin/cargo" "+1.99.0-beta.8" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "6e3c469d07207c40600757943291323235c46142734ed829c3ee17e58e600a5d", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "exec" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-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" "-w" "/opt/rustwide/workdir" "--user" "0:0" "6e3c469d07207c40600757943291323235c46142734ed829c3ee17e58e600a5d" "/opt/rustwide/cargo-home/bin/cargo" "+1.99.0-beta.8" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stderr]    Compiling tokio-macros v2.7.1
[INFO] [stderr]    Compiling tokio v1.53.1
[INFO] [stderr]    Compiling sz-orm-mqtt v9.0.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 8.95s
[INFO] running `Command { std: "docker" "inspect" "6e3c469d07207c40600757943291323235c46142734ed829c3ee17e58e600a5d", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "exec" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-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" "-w" "/opt/rustwide/workdir" "--user" "0:0" "6e3c469d07207c40600757943291323235c46142734ed829c3ee17e58e600a5d" "/opt/rustwide/cargo-home/bin/cargo" "+1.99.0-beta.8" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stderr]    Compiling tokio v1.53.1
[INFO] [stderr]    Compiling tokio-stream v0.1.19
[INFO] [stderr]    Compiling sz-orm-mqtt v9.0.0 (/opt/rustwide/workdir)
[INFO] [stderr]    Compiling tokio-test v0.4.5
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 11.72s
[INFO] running `Command { std: "docker" "inspect" "6e3c469d07207c40600757943291323235c46142734ed829c3ee17e58e600a5d", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "exec" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-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" "-w" "/opt/rustwide/workdir" "--user" "0:0" "6e3c469d07207c40600757943291323235c46142734ed829c3ee17e58e600a5d" "/opt/rustwide/cargo-home/bin/cargo" "+1.99.0-beta.8" "test" "--frozen", kill_on_drop: false }`
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.08s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/sz_orm_mqtt-c402e48608670329)
[INFO] [stdout] 
[INFO] [stdout] running 155 tests
[INFO] [stdout] test broker::tests::test_mqtt_topic_matches_method ... ok
[INFO] [stdout] test qos2::tests::test_packet_state_in_progress ... ok
[INFO] [stdout] test broker::tests::test_publish_not_connected_fails ... ok
[INFO] [stdout] test qos2::tests::test_qos2_packet_advance_full_cycle ... ok
[INFO] [stdout] test qos2::tests::test_qos2_packet_new ... ok
[INFO] [stdout] test broker::tests::test_publish_multiple_messages ... ok
[INFO] [stdout] test broker::tests::test_clear_messages ... ok
[INFO] [stdout] test broker::tests::test_subscribe_not_connected_fails ... ok
[INFO] [stdout] test broker::tests::test_retained_messages ... ok
[INFO] [stdout] test qos2::tests::test_sender_pubcomp_in_wrong_state_fails ... ok
[INFO] [stdout] test broker::tests::test_publish_stores_message ... ok
[INFO] [stdout] test broker::tests::test_subscribe_updates_qos_for_existing ... ok
[INFO] [stdout] test qos2::tests::test_qos2_packet_reset_increments_retries ... ok
[INFO] [stdout] test broker::tests::test_disconnect_clears_connection_state ... ok
[INFO] [stdout] test qos2::tests::test_sender_full_handshake ... ok
[INFO] [stdout] test qos2::tests::test_receiver_duplicate_publish_fails ... ok
[INFO] [stdout] test qos2::tests::test_receiver_pubrel_without_pubrec_fails ... ok
[INFO] [stdout] test broker::tests::test_messages_matching_wildcard ... ok
[INFO] [stdout] test qos2::tests::test_sender_tracked_ids_sorted ... ok
[INFO] [stdout] test qos2::tests::test_sender_pubrel_in_wrong_state_fails ... ok
[INFO] [stdout] test qos2::tests::test_simulator_full_handshake_success ... ok
[INFO] [stdout] test qos2::tests::test_sender_allocate_id_wraps_at_max ... ok
[INFO] [stdout] test qos2::tests::test_sender_register_duplicate_packet_id_fails ... ok
[INFO] [stdout] test qos2::tests::test_simulator_multiple_concurrent_handshakes ... ok
[INFO] [stdout] test qos2::tests::test_sender_allocate_id_increments ... ok
[INFO] [stdout] test qos2::tests::test_simulator_tracks_state_during_handshake ... ok
[INFO] [stdout] test qos::tests::test_at_most_once_factory ... ok
[INFO] [stdout] test qos::tests::test_exactly_once_factory ... ok
[INFO] [stdout] test qos::tests::test_from_level_boundaries ... ok
[INFO] [stdout] test retained::tests::test_all_sorted_by_topic ... ok
[INFO] [stdout] test retained::tests::test_clear ... ok
[INFO] [stdout] test qos2::tests::test_sender_pubrec_for_unknown_packet_fails ... ok
[INFO] [stdout] test retained::tests::test_contains ... ok
[INFO] [stdout] test broker::tests::test_unsubscribe_removes_subscription ... ok
[INFO] [stdout] test qos::tests::test_at_least_once_factory ... ok
[INFO] [stdout] test qos::tests::test_default_is_at_most_once ... ok
[INFO] [stdout] test qos2::tests::test_sender_pubrec_in_wrong_state_fails ... ok
[INFO] [stdout] test retained::tests::test_retained_message_text_payload_invalid_utf8 ... ok
[INFO] [stdout] test retained::tests::test_matching_root_hash ... ok
[INFO] [stdout] test retained::tests::test_remove_missing_returns_none ... ok
[INFO] [stdout] test retained::tests::test_retained_message_text_payload ... ok
[INFO] [stdout] test retained::tests::test_remove ... ok
[INFO] [stdout] test retained::tests::test_store_action_replaced_existing_with_topic ... ok
[INFO] [stdout] test retained::tests::test_store_empty_payload_removes_topic ... ok
[INFO] [stdout] test retained::tests::test_matching_plus_wildcard ... ok
[INFO] [stdout] test retained::tests::test_matching_exact_filter ... ok
[INFO] [stdout] test retained::tests::test_matching_no_results ... ok
[INFO] [stdout] test qos2::tests::test_sender_register_publish ... ok
[INFO] [stdout] test retained::tests::test_matching_hash_wildcard ... ok
[INFO] [stdout] test retained::tests::test_get_returns_none_for_missing ... ok
[INFO] [stdout] test shared::tests::test_parse_shared_filter_no_prefix ... ok
[INFO] [stdout] test shared::tests::test_parse_shared_filter_empty_group ... ok
[INFO] [stdout] test retained::tests::test_store_from_message_empty_payload_removes ... ok
[INFO] [stdout] test shared::tests::test_registry_groups_matching ... ok
[INFO] [stdout] test shared::tests::test_registry_groups_matching_none ... ok
[INFO] [stdout] test retained::tests::test_store_from_message_retain_false_ignored ... ok
[INFO] [stdout] test shared::tests::test_registry_list_groups_sorted ... ok
[INFO] [stdout] test retained::tests::test_store_from_message_retain_true ... ok
[INFO] [stdout] test shared::tests::test_registry_multiple_filters_same_group ... ok
[INFO] [stdout] test retained::tests::test_store_overwrites_previous ... ok
[INFO] [stdout] test shared::tests::test_registry_select_recipients_multiple_groups ... ok
[INFO] [stdout] test retained::tests::test_topics_sorted ... ok
[INFO] [stdout] test retained::tests::test_retained_message_to_mqtt_message_sets_retain ... ok
[INFO] [stdout] test shared::tests::test_registry_select_recipients_no_match ... ok
[INFO] [stdout] test shared::tests::test_is_shared_filter ... ok
[INFO] [stdout] test shared::tests::test_parse_shared_filter_valid ... ok
[INFO] [stdout] test shared::tests::test_registry_select_recipients_round_robin ... ok
[INFO] [stdout] test shared::tests::test_parse_shared_filter_empty_topic ... ok
[INFO] [stdout] test shared::tests::test_registry_subscribe_creates_group ... ok
[INFO] [stdout] test shared::tests::test_registry_unsubscribe_removes_empty_group ... ok
[INFO] [stdout] test shared::tests::test_select_next_empty_returns_none ... ok
[INFO] [stdout] test shared::tests::test_registry_subscribe_invalid_filter_fails ... ok
[INFO] [stdout] test shared::tests::test_registry_unsubscribe_removes_subscriber ... ok
[INFO] [stdout] test shared::tests::test_shared_subscription_remove_missing_returns_false ... ok
[INFO] [stdout] test shared::tests::test_registry_subscribe_updates_qos_for_existing ... ok
[INFO] [stdout] test shared::tests::test_registry_unsubscribe_all ... ok
[INFO] [stdout] test shared::tests::test_registry_unsubscribe_missing_returns_false ... ok
[INFO] [stdout] test shared::tests::test_shared_subscription_upsert_adds_new ... ok
[INFO] [stdout] test shared::tests::test_select_next_random_returns_valid_index ... ok
[INFO] [stdout] test shared::tests::test_select_next_round_robin_cycles ... ok
[INFO] [stdout] test shared::tests::test_shared_subscription_add_subscriber ... ok
[INFO] [stdout] test tests::test_mqtt_config_builder ... ok
[INFO] [stdout] test shared::tests::test_shared_subscription_matches ... ok
[INFO] [stdout] test shared::tests::test_shared_subscription_remove_subscriber ... ok
[INFO] [stdout] test tests::test_mqtt_config_add_topic ... ok
[INFO] [stdout] test shared::tests::test_shared_subscription_new ... ok
[INFO] [stdout] test shared::tests::test_shared_subscriber_new ... ok
[INFO] [stdout] test shared::tests::test_shared_subscription_add_duplicate_returns_false ... ok
[INFO] [stdout] test tests::test_mqtt_config_default ... ok
[INFO] [stdout] test shared::tests::test_shared_subscription_upsert_updates_qos ... ok
[INFO] [stdout] test tests::test_mqtt_message_new ... ok
[INFO] [stdout] test tests::test_mqtt_message_retain ... ok
[INFO] [stdout] test tests::test_mqtt_message_text ... ok
[INFO] [stdout] test shared::tests::test_with_strategy ... ok
[INFO] [stdout] test tests::test_mqtt_message_json ... ok
[INFO] [stdout] test broker::tests::test_subscribe_registers_subscription ... ok
[INFO] [stdout] test qos2::tests::test_receiver_full_handshake ... ok
[INFO] [stdout] test tests::test_mqtt_message_with_qos ... ok
[INFO] [stdout] test retained::tests::test_store_new_message ... ok
[INFO] [stdout] test shared::tests::test_registry_subscribe_adds_to_existing_group ... ok
[INFO] [stdout] test tests::test_mqtt_plugin_connect ... ok
[INFO] [stdout] test tests::test_mqtt_topic_from_str ... ok
[INFO] [stdout] test topics::tests::test_topic_filter_matches_method ... ok
[INFO] [stdout] test tests::test_qos_from_level ... ok
[INFO] [stdout] test tests::test_mqtt_plugin_topic_matches ... ok
[INFO] [stdout] test tests::test_mqtt_plugin_publish_not_connected ... ok
[INFO] [stdout] test tests::test_mqtt_plugin_subscribe ... ok
[INFO] [stdout] test topics::tests::test_topic_filter_new_invalid_hash_in_middle ... ok
[INFO] [stdout] test topics::tests::test_topic_filter_new_invalid_hash_with_suffix ... ok
[INFO] [stdout] test topics::tests::test_topic_filter_new_wildcard_plus ... ok
[INFO] [stdout] test topics::tests::test_topic_filter_new_invalid_plus_with_suffix ... ok
[INFO] [stdout] test tests::test_mqtt_topic_levels ... ok
[INFO] [stdout] test topics::tests::test_topic_filter_from_str ... ok
[INFO] [stdout] test topics::tests::test_topic_matches_empty ... ok
[INFO] [stdout] test tests::test_mqtt_plugin_disconnect ... ok
[INFO] [stdout] test topics::tests::test_topic_filter_new_empty ... ok
[INFO] [stdout] test tests::test_mqtt_plugin_publish ... ok
[INFO] [stdout] test topics::tests::test_topic_filter_new_wildcard_hash ... ok
[INFO] [stdout] test tests::test_mqtt_topic_new ... ok
[INFO] [stdout] test topics::tests::test_topic_matches_mixed_wildcards ... ok
[INFO] [stdout] test tests::test_mqtt_plugin_new ... ok
[INFO] [stdout] test topics::tests::test_topic_filter_new_valid ... ok
[INFO] [stdout] test topics::tests::test_topic_matches_multi_level_wildcard ... ok
[INFO] [stdout] test topics::tests::test_topic_matches_plus_at_end ... ok
[INFO] [stdout] test topics::tests::test_topic_matches_trailing_slash ... ok
[INFO] [stdout] test will::tests::test_drain_due_returns_only_expired ... ok
[INFO] [stdout] test tests::test_mqtt_plugin_unsubscribe ... ok
[INFO] [stdout] test topics::tests::test_topic_matches_exact ... ok
[INFO] [stdout] test tests::test_qos_levels ... ok
[INFO] [stdout] test will::tests::test_drain_due_clears_returned_entries ... ok
[INFO] [stdout] test will::tests::test_trigger_graceful_with_publish_flag ... ok
[INFO] [stdout] test will::tests::test_trigger_consumes_will_from_registry ... ok
[INFO] [stdout] test will::tests::test_trigger_graceful_default_skipped ... ok
[INFO] [stdout] test will::tests::test_cancel_pending_not_present_returns_false ... ok
[INFO] [stdout] test will::tests::test_will_config_default_no_delay ... ok
[INFO] [stdout] test will::tests::test_trigger_ungraceful_immediate_delivery ... ok
[INFO] [stdout] test topics::tests::test_topic_matches_root_level ... ok
[INFO] [stdout] test topics::tests::test_topic_matches_single_level_wildcard ... ok
[INFO] [stdout] test will::tests::test_will_config_publish_on_graceful ... ok
[INFO] [stdout] test will::tests::test_trigger_with_delay_schedules ... ok
[INFO] [stdout] test will::tests::test_cancel_pending ... ok
[INFO] [stdout] test will::tests::test_will_message_new_defaults ... ok
[INFO] [stdout] test will::tests::test_multiple_clients_independent_wills ... ok
[INFO] [stdout] test will::tests::test_registry_get ... ok
[INFO] [stdout] test will::tests::test_registry_register_and_has ... ok
[INFO] [stdout] test will::tests::test_registry_unregister ... ok
[INFO] [stdout] test will::tests::test_trigger_no_will_returns_skipped ... ok
[INFO] [stdout] test will::tests::test_will_config_with_delay ... ok
[INFO] [stdout] test will::tests::test_will_message_to_mqtt_message_sets_client ... ok
[INFO] [stdout] test tests::test_mqtt_topic_wildcard ... ok
[INFO] [stdout] test tests::test_mqtt_topic_with_qos ... ok
[INFO] [stdout] test will::tests::test_to_mqtt_messages_conversion ... ok
[INFO] [stdout] test will::tests::test_will_message_builder ... ok
[INFO] [stdout] test will::tests::test_will_message_to_mqtt_message_retain_flag ... ok
[INFO] [stderr]      Running tests/blackhat_poc.rs (/opt/rustwide/target/debug/deps/blackhat_poc-43643479c8dcba2d)
[INFO] [stdout] test qos2::tests::test_packet_state_is_complete ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 155 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.03s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 2 tests
[INFO] [stdout] test regress_wildcard_no_longer_matches_dollar_topics ... ok
[INFO] [stdout] test regress_invalid_topic_filter_no_longer_silent ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 2 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.04s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/error_test.rs (/opt/rustwide/target/debug/deps/error_test-6cac0818ad6b7650)
[INFO] [stdout] 
[INFO] [stdout] running 8 tests
[INFO] [stdout] test test_from_serde_json_error ... ok
[INFO] [stdout] test test_error_trait ... ok
[INFO] [stdout] test test_subscribe ... ok
[INFO] [stdout] test test_protocol ... ok
[INFO] [stdout] test test_topic ... ok
[INFO] [stdout] test test_from_io_error ... ok
[INFO] [stdout] test test_connection ... ok
[INFO] [stdout] test test_publish ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 8 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/stress.rs (/opt/rustwide/target/debug/deps/stress-5343fc6904f361ac)
[INFO] [stdout] 
[INFO] [stdout] running 10 tests
[INFO] [stdout] test stress_mqtt_not_connected_consistency ... ok
[INFO] [stdout] test stress_mqtt_disconnect_reconnect_cycle ... ok
[INFO] [stdout] test stress_mqtt_subscribe_same_topic_idempotent ... ok
[INFO] [stdout] test stress_mqtt_wildcard_matching ... ok
[INFO] [stdout] test stress_mqtt_topic_matches_function ... ok
[INFO] [stdout] test stress_mqtt_1000_subscribers ... ok
[INFO] [stdout] test stress_mqtt_retained_uniqueness ... ok
[INFO] [stdout] test stress_mqtt_large_payload ... ok
[INFO] [stdout] test stress_mqtt_100k_messages ... ok
[INFO] [stdout] test stress_mqtt_concurrent_publish ... ok
[INFO] [stderr]    Doc-tests sz_orm_mqtt
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 10 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.51s
[INFO] [stdout] 
[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] running `Command { std: "docker" "inspect" "6e3c469d07207c40600757943291323235c46142734ed829c3ee17e58e600a5d", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "6e3c469d07207c40600757943291323235c46142734ed829c3ee17e58e600a5d", kill_on_drop: false }`
[INFO] [stdout] 6e3c469d07207c40600757943291323235c46142734ed829c3ee17e58e600a5d
