[INFO] fetching crate sdhci-host 0.5.2...
[INFO] testing sdhci-host-0.5.2 against 1.100.0-beta.1 for beta-1.100-2
[INFO] extracting crate sdhci-host 0.5.2 into /workspace/builds/worker-0-tc2/source
[INFO] started tweaking crates.io crate sdhci-host 0.5.2
[INFO] finished tweaking crates.io crate sdhci-host 0.5.2
[INFO] tweaked toml for crates.io crate sdhci-host 0.5.2 written to /workspace/builds/worker-0-tc2/source/Cargo.toml
[INFO] validating manifest of crates.io crate sdhci-host 0.5.2 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 sdhci-host 0.5.2 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-0-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-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] ae669224bd4631e2f49c6df0e8ea1a9edee77c855ac8baf69dcf6b48c2dba3df
[INFO] running `Command { std: "docker" "start" "ae669224bd4631e2f49c6df0e8ea1a9edee77c855ac8baf69dcf6b48c2dba3df", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "ae669224bd4631e2f49c6df0e8ea1a9edee77c855ac8baf69dcf6b48c2dba3df", 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" "ae669224bd4631e2f49c6df0e8ea1a9edee77c855ac8baf69dcf6b48c2dba3df" "/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" "ae669224bd4631e2f49c6df0e8ea1a9edee77c855ac8baf69dcf6b48c2dba3df", 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" "ae669224bd4631e2f49c6df0e8ea1a9edee77c855ac8baf69dcf6b48c2dba3df" "/opt/rustwide/cargo-home/bin/cargo" "+1.100.0-beta.1" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stderr]    Compiling thiserror v2.0.20
[INFO] [stderr]    Compiling unicode-segmentation v1.13.3
[INFO] [stderr]    Compiling unicode-xid v0.2.6
[INFO] [stderr]    Compiling mbarrier v0.1.3
[INFO] [stderr]    Compiling log v0.4.34
[INFO] [stderr]    Compiling as-any v0.3.2
[INFO] [stderr]    Compiling embedded-hal v1.0.0
[INFO] [stderr]    Compiling bitflags v2.13.1
[INFO] [stderr]    Compiling tock-registers v0.10.1
[INFO] [stderr]    Compiling syn v3.0.5
[INFO] [stderr]    Compiling syn v2.0.119
[INFO] [stderr]    Compiling convert_case v0.10.0
[INFO] [stderr]    Compiling thiserror-impl v2.0.20
[INFO] [stderr]    Compiling async-trait v0.1.92
[INFO] [stderr]    Compiling derive_more-impl v2.1.1
[INFO] [stderr]    Compiling rdif-def v0.4.1
[INFO] [stderr]    Compiling rdif-base v0.9.1
[INFO] [stderr]    Compiling derive_more v2.1.1
[INFO] [stderr]    Compiling dma-api v0.10.2
[INFO] [stderr]    Compiling mmio-api v0.3.1
[INFO] [stderr]    Compiling sdmmc-host v0.1.8
[INFO] [stderr]    Compiling rdif-block v0.13.0
[INFO] [stderr]    Compiling sdmmc-protocol v0.5.3
[INFO] [stderr]    Compiling sdhci-host v0.5.2 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 13.22s
[INFO] running `Command { std: "docker" "inspect" "ae669224bd4631e2f49c6df0e8ea1a9edee77c855ac8baf69dcf6b48c2dba3df", 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" "ae669224bd4631e2f49c6df0e8ea1a9edee77c855ac8baf69dcf6b48c2dba3df" "/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 sdhci-host v0.5.2 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 1.24s
[INFO] running `Command { std: "docker" "inspect" "ae669224bd4631e2f49c6df0e8ea1a9edee77c855ac8baf69dcf6b48c2dba3df", 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" "ae669224bd4631e2f49c6df0e8ea1a9edee77c855ac8baf69dcf6b48c2dba3df" "/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.06s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/build/sdhci-host/1d5204b6a3abaf54/out/sdhci_host-1d5204b6a3abaf54)
[INFO] [stdout] 
[INFO] [stdout] running 50 tests
[INFO] [stdout] test command::tests::generic_multi_block_transfer_does_not_assume_auto_cmd12 ... ok
[INFO] [stdout] test dma::tests::aborted_owned_dma_is_returned_after_controller_quiesce ... ok
[INFO] [stdout] test dma::tests::rejects_unaligned_bus_address ... ok
[INFO] [stdout] test command::tests::issued_command_keeps_irq_generation_active_for_completion_cache ... ok
[INFO] [stdout] test command::tests::r1b_command_completes_when_dat0_is_already_released_at_command_irq ... ok
[INFO] [stdout] test command::tests::new_command_discards_cached_irq_status_from_previous_request ... ok
[INFO] [stdout] test dma::tests::rejects_transfer_past_32bit_dma_mask ... ok
[INFO] [stdout] test command::tests::task_side_does_not_harvest_unacknowledged_raw_command_status ... ok
[INFO] [stdout] test command::tests::r1b_busy_release_is_register_progress_after_command_irq ... ok
[INFO] [stdout] test dma::tests::block_request_slot_rejects_second_request_until_completed ... ok
[INFO] [stdout] test dma::tests::sdhci_dma_read_plan_rejects_non_block_sized_buffers ... ok
[INFO] [stdout] test dma::tests::sdhci_dma_read_plan_reports_block_count ... ok
[INFO] [stdout] test dma::tests::rejects_zero_length ... ok
[INFO] [stdout] test command::tests::command_completion_does_not_consume_a_concurrent_adma_error ... ok
[INFO] [stdout] test dma::tests::rejects_64bit_bus_address ... ok
[INFO] [stdout] test dma::tests::descriptor64_preserves_address_above_4gib ... ok
[INFO] [stdout] test command::tests::irq_cache_drops_events_from_previous_generation ... ok
[INFO] [stdout] test dma::tests::sdhci_dma_write_plan_rejects_non_block_sized_buffers ... ok
[INFO] [stdout] test tests::card_interrupt_and_error_share_one_lossless_snapshot ... ok
[INFO] [stdout] test dma::tests::single_descriptor_for_small_buffer ... ok
[INFO] [stdout] test dma::tests::splits_across_max_chunk ... ok
[INFO] [stdout] test host::tests::reset_all_calls_owned_platform_before_hook_before_software_reset ... ok
[INFO] [stdout] test dma::tests::splits_at_dwcmshc_128m_boundary ... ok
[INFO] [stdout] test tests::card_interrupt_and_transfer_completion_share_one_lossless_snapshot ... ok
[INFO] [stdout] test host::tests::interrupt_status_capture_keeps_signal_irq_masked ... ok
[INFO] [stdout] test rdif::tests::dma_config_advertises_adma_window ... ok
[INFO] [stdout] test tests::card_irq_rearm_closes_already_asserted_window ... ok
[INFO] [stdout] test tests::card_irq_top_half_masks_status_and_signal_without_acknowledging_level_status ... ok
[INFO] [stdout] test tests::clock_div_zero_quirk_uses_nonzero_divider_for_low_external_clock ... ok
[INFO] [stdout] test tests::data_transaction_rejects_missing_dma_capability ... ok
[INFO] [stdout] test tests::event_reports_command_completion_without_os_wakeup_policy ... ok
[INFO] [stdout] test tests::event_reports_data_completion_source_for_runtime_wakeup ... ok
[INFO] [stdout] test tests::event_reports_data_completion_without_os_wakeup_policy ... ok
[INFO] [stdout] test tests::event_reports_error_status_without_translating_to_os_action ... ok
[INFO] [stdout] test tests::host2_v180_rejects_partial_high_dat_lines_before_switch ... ok
[INFO] [stdout] test tests::irq_capability_trait_controls_hardware_signal_masks ... ok
[INFO] [stdout] test tests::status_enabled_completion_is_not_lost_when_only_card_signal_is_unmasked ... ok
[INFO] [stdout] test tests::host2_advance_after_complete_is_rejected ... ok
[INFO] [stdout] test tests::host2_bus_request_is_bound_to_originating_host ... ok
[INFO] [stdout] test tests::host2_clock_stable_timeout_uses_elapsed_wall_time ... ok
[INFO] [stdout] test tests::host2_data_submit_reports_busy_without_replacing_the_active_command ... ok
[INFO] [stdout] test tests::host2_external_clock_runs_host_stage_before_enable ... ok
[INFO] [stdout] test tests::host2_r1b_busy_release_advances_on_register_retry ... ok
[INFO] [stdout] test tests::masked_irq_status_is_acked_without_publishing_an_event ... ok
[INFO] [stdout] test tests::merged_command_and_data_irq_reports_queue_ready ... ok
[INFO] [stdout] test tests::owned_irq_endpoint_acks_and_caches_status ... ok
[INFO] [stdout] test tests::host2_reset_all_invalidates_stale_irq_state_before_restore ... ok
[INFO] [stdout] test tests::host2_v180_requires_real_timer ... ok
[INFO] [stdout] test dma::tests::small_transfer_uses_linux_style_nop_terminator ... ok
[INFO] [stdout] test tests::completion_rearm_captures_status_latched_while_signal_is_masked ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 50 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.04s
[INFO] [stdout] 
[INFO] [stderr]    Doc-tests sdhci_host
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stdout] test src/lib.rs - (line 24) - compile ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stdout] all doctests ran in 0.41s; merged doctests compilation took 0.40s
[INFO] running `Command { std: "docker" "inspect" "ae669224bd4631e2f49c6df0e8ea1a9edee77c855ac8baf69dcf6b48c2dba3df", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "ae669224bd4631e2f49c6df0e8ea1a9edee77c855ac8baf69dcf6b48c2dba3df", kill_on_drop: false }`
[INFO] [stdout] ae669224bd4631e2f49c6df0e8ea1a9edee77c855ac8baf69dcf6b48c2dba3df
