[INFO] fetching crate swbt-bumble-backend 0.1.1...
[INFO] testing swbt-bumble-backend-0.1.1 against 1.100.0-beta.1 for beta-1.100-2
[INFO] extracting crate swbt-bumble-backend 0.1.1 into /workspace/builds/worker-1-tc2/source
[INFO] started tweaking crates.io crate swbt-bumble-backend 0.1.1
[INFO] removed 0 missing tests
[INFO] finished tweaking crates.io crate swbt-bumble-backend 0.1.1
[INFO] tweaked toml for crates.io crate swbt-bumble-backend 0.1.1 written to /workspace/builds/worker-1-tc2/source/Cargo.toml
[INFO] validating manifest of crates.io crate swbt-bumble-backend 0.1.1 on toolchain 1.100.0-beta.1
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+1.100.0-beta.1" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate crates.io crate swbt-bumble-backend 0.1.1 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.100.0-beta.1" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-tc2/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] a89884014955c844c08b1c445496fd55034c2e39571953558199ac54b49e453f
[INFO] running `Command { std: "docker" "start" "a89884014955c844c08b1c445496fd55034c2e39571953558199ac54b49e453f", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "a89884014955c844c08b1c445496fd55034c2e39571953558199ac54b49e453f", 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" "a89884014955c844c08b1c445496fd55034c2e39571953558199ac54b49e453f" "/opt/rustwide/cargo-home/bin/cargo" "+1.100.0-beta.1" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "a89884014955c844c08b1c445496fd55034c2e39571953558199ac54b49e453f", 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" "a89884014955c844c08b1c445496fd55034c2e39571953558199ac54b49e453f" "/opt/rustwide/cargo-home/bin/cargo" "+1.100.0-beta.1" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stderr]    Compiling rusb v0.9.4
[INFO] [stderr]    Compiling cc v1.4.0
[INFO] [stderr]    Compiling libusb1-sys v0.7.0
[INFO] [stderr]    Compiling swbt-bumble-backend v0.1.1 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 6.49s
[INFO] running `Command { std: "docker" "inspect" "a89884014955c844c08b1c445496fd55034c2e39571953558199ac54b49e453f", 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" "a89884014955c844c08b1c445496fd55034c2e39571953558199ac54b49e453f" "/opt/rustwide/cargo-home/bin/cargo" "+1.100.0-beta.1" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stderr]    Compiling swbt-bumble-backend v0.1.1 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 4.24s
[INFO] running `Command { std: "docker" "inspect" "a89884014955c844c08b1c445496fd55034c2e39571953558199ac54b49e453f", 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" "a89884014955c844c08b1c445496fd55034c2e39571953558199ac54b49e453f" "/opt/rustwide/cargo-home/bin/cargo" "+1.100.0-beta.1" "test" "--frozen", kill_on_drop: false }`
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.02s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/build/swbt-bumble-backend/99976194de66a3af/out/swbt_bumble_backend-99976194de66a3af)
[INFO] [stdout] 
[INFO] [stdout] running 71 tests
[INFO] [stdout] test classic_host::tests::disconnect_clears_partial_acl_reassembly_for_reused_handle ... ok
[INFO] [stdout] test classic_host::tests::pending_connection_rejects_a_different_peer ... ok
[INFO] [stdout] test classic_host::tests::authentication_transitions_to_encrypted_session ... ok
[INFO] [stdout] test csr::tests::read_and_volatile_rewrite_match_the_pinned_csr_layout ... ok
[INFO] [stdout] test classic_host::tests::acl_credit_never_sends_more_than_controller_capacity ... ok
[INFO] [stdout] test classic_host::tests::incoming_pairing_emits_accept_io_and_confirmation_commands ... ok
[INFO] [stdout] test classic_host::tests::test_only_peer_opens_channel_and_exchanges_sdu ... ok
[INFO] [stdout] test classic_host::tests::completed_packet_count_cannot_exceed_in_flight_acl ... ok
[INFO] [stdout] test csr::tests::malformed_or_non_csr_responses_fail_without_echoing_payloads ... ok
[INFO] [stdout] test classic_host::tests::stored_and_new_link_keys_drive_reconnect_without_le_key_state ... ok
[INFO] [stdout] test hci::tests::command_and_event_frames_match_bumble_oracles ... ok
[INFO] [stdout] test identity::tests::already_active_closes_without_write_reset_or_reenumeration ... ok
[INFO] [stdout] test identity::tests::rewrite_waits_for_reenumeration_and_requires_matching_readback ... ok
[INFO] [stdout] test hci::tests::assembler_rejects_continuation_without_start ... ok
[INFO] [stdout] test identity::tests::warm_reset_transfer_failure_is_settled_by_reenumeration_readback ... ok
[INFO] [stdout] test csr::tests::response_matching_uses_type_sequence_and_varid ... ok
[INFO] [stdout] test classic_host::tests::missing_link_key_uses_negative_reply ... ok
[INFO] [stdout] test identity::tests::read_failure_before_write_remains_retryable_and_closes ... ok
[INFO] [stdout] test csr::tests::read_response_decodes_the_address_and_rejects_failed_status ... ok
[INFO] [stdout] test hci::tests::acl_fragments_reassemble_one_l2cap_pdu ... ok
[INFO] [stdout] test hci::tests::codec_rejects_sco_and_iso_packet_families ... ok
[INFO] [stdout] test identity::tests::reenumeration_and_readback_failures_require_recovery ... ok
[INFO] [stdout] test identity::tests::reset_disconnect_close_failure_is_settled_by_reenumeration_readback ... ok
[INFO] [stdout] test hci::tests::codec_rejects_declared_length_mismatch ... ok
[INFO] [stdout] test identity::tests::write_failure_requires_physical_recovery_and_still_closes ... ok
[INFO] [stdout] test hidp::tests::all_upstream_message_forms_are_byte_exact_and_round_trip ... ok
[INFO] [stdout] test hidp::tests::host_device_control_dispatch_matches_upstream ... ok
[INFO] [stdout] test l2cap::classic_tests::dynamic_psm_allocation_is_valid_and_deterministic ... ok
[INFO] [stdout] test l2cap::classic_tests::classic_channel_connect_configure_transfer_and_disconnect ... ok
[INFO] [stdout] test l2cap::ertm_tests::live_busy_and_logical_timeout_paths_resume_without_data_loss ... ok
[INFO] [stdout] test sdp::service_tests::client_reassembles_the_same_records_regardless_of_mtu ... ok
[INFO] [stdout] test l2cap::classic_tests::connection_to_unregistered_psm_is_refused ... ok
[INFO] [stdout] test l2cap::classic_tests::default_information_capabilities_do_not_advertise_le_signaling ... ok
[INFO] [stdout] test l2cap::ertm_tests::live_channel_recovers_a_dropped_window_via_reject ... ok
[INFO] [stdout] test sdp::service_tests::continuation_chunks_match_upstream_and_reassemble ... ok
[INFO] [stdout] test sdp::service_tests::service_attribute_and_search_round_trip ... ok
[INFO] [stdout] test l2cap::ertm_tests::mode_mismatch_and_invalid_specs_fail_cleanly ... ok
[INFO] [stdout] test l2cap::ertm_tests::optional_fcs_is_verified_before_ertm_processing ... ok
[INFO] [stdout] test l2cap::ertm_tests::upstream_mtu_matrix_transfers_bidirectionally_over_live_ertm_channels ... ok
[INFO] [stdout] test sdp::service_tests::service_attribute_and_search_responses_match_upstream ... ok
[INFO] [stdout] test sdp::service_tests::service_search_attribute_response_matches_upstream ... ok
[INFO] [stdout] test hidp::tests::malformed_messages_and_callback_errors_are_safe ... ok
[INFO] [stdout] test identity::tests::non_csr_controller_is_unsupported_before_any_write ... ok
[INFO] [stdout] test session::tests::bond_store_namespace_failure_is_a_typed_open_error ... ok
[INFO] [stdout] test session::tests::active_reconnect_opens_control_then_interrupt_channels ... ok
[INFO] [stdout] test session::tests::scripted_initialization_uses_exact_classic_command_sequence ... ok
[INFO] [stdout] test session::tests::event_queue_overflow_is_immediately_terminal ... ok
[INFO] [stdout] test tests::extraction_source_revision_is_a_full_git_object_id ... ok
[INFO] [stdout] test session::tests::missing_classic_capability_and_failed_command_are_typed_open_errors ... ok
[INFO] [stdout] test session::tests::reconnect_requires_one_bond_and_uses_stored_link_key ... ok
[INFO] [stdout] test session::tests::pair_sdp_continuation_hid_output_and_interrupt_input_share_one_session ... ok
[INFO] [stdout] test session::tests::rejected_connection_completion_does_not_poison_the_session ... ok
[INFO] [stdout] test session::tests::legacy_hci_version_uses_legacy_le_event_mask ... ok
[INFO] [stdout] test session::tests::explicit_interrupt_send_queues_behind_in_flight_acl_credit ... ok
[INFO] [stdout] test session::tests::pairing_latches_one_peer_and_converts_connection_events ... ok
[INFO] [stdout] test usb::tests::endpoint_selection_requires_bluetooth_interrupt_and_bulk_triplet ... ok
[INFO] [stdout] test usb::tests::selectors_cover_index_vid_pid_serial_occurrence_and_path ... ok
[INFO] [stdout] test values::tests::address_rejects_irregular_separators ... ok
[INFO] [stdout] test values::tests::short_and_expanded_uuid_compare_equal ... ok
[INFO] [stdout] test session::tests::disconnect_is_idempotent_and_immediately_rejects_more_interrupt_input ... ok
[INFO] [stdout] test usb::tests::scripted_transport_reads_event_and_acl_and_writes_command_and_acl ... ok
[INFO] [stdout] test values::tests::address_round_trips_controller_byte_order ... ok
[INFO] [stdout] test values::tests::classic_bond_rejects_non_hci_key_length ... ok
[INFO] [stdout] test session::tests::drain_interrupt_processes_completed_packets_until_host_queue_is_empty ... ok
[INFO] [stdout] test usb::tests::reader_surfaces_disconnect_once_then_ends ... ok
[INFO] [stdout] test session::tests::close_releases_hci_io_clears_pending_input_and_is_idempotent ... ok
[INFO] [stdout] test usb::tests::reader_close_requests_cancellation_and_joins_worker ... ok
[INFO] [stdout] test usb::tests::event_framer_preserves_partial_and_multiple_usb_transfers ... ok
[INFO] [stdout] test usb::tests::event_packets_are_not_valid_usb_output ... ok
[INFO] [stdout] test session::tests::rewritten_identity_mismatch_requires_recovery ... ok
[INFO] [stdout] test usb::tests::reader_notifies_packet_and_terminal_activity ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 71 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.03s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/public_api.rs (/opt/rustwide/target/debug/build/swbt-bumble-backend/4d1666bf05ff586f/out/public_api-4d1666bf05ff586f)
[INFO] [stdout] 
[INFO] [stdout] running 4 tests
[INFO] [stdout] test session_facing_types_are_send_and_do_not_require_bumble_protocol_types ... ok
[INFO] [stderr]    Doc-tests swbt_bumble_backend
[INFO] [stdout] test bond_store_and_activity_callback_are_owned_session_inputs ... ok
[INFO] [stdout] test invalid_controller_identity_configuration_returns_a_typed_error ... ok
[INFO] [stdout] test public_configuration_builds_complete_local_name_eir_without_protocol_types ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 4 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[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" "a89884014955c844c08b1c445496fd55034c2e39571953558199ac54b49e453f", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "a89884014955c844c08b1c445496fd55034c2e39571953558199ac54b49e453f", kill_on_drop: false }`
[INFO] [stdout] a89884014955c844c08b1c445496fd55034c2e39571953558199ac54b49e453f
