[INFO] fetching crate subetha-ffi 0.6.0...
[INFO] testing subetha-ffi-0.6.0 against 1.99.0-beta.8 for beta-1.100-2
[INFO] extracting crate subetha-ffi 0.6.0 into /workspace/builds/worker-5-tc1/source
[INFO] started tweaking crates.io crate subetha-ffi 0.6.0
[INFO] finished tweaking crates.io crate subetha-ffi 0.6.0
[INFO] tweaked toml for crates.io crate subetha-ffi 0.6.0 written to /workspace/builds/worker-5-tc1/source/Cargo.toml
[INFO] validating manifest of crates.io crate subetha-ffi 0.6.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 subetha-ffi 0.6.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 subetha-sidecar v0.6.0
[INFO] [stderr]   Downloaded subetha-pointers v0.6.0
[INFO] [stderr]   Downloaded subetha-core v0.6.0
[INFO] [stderr]   Downloaded toml v0.9.12+spec-1.1.0
[INFO] [stderr]   Downloaded cbindgen v0.29.4
[INFO] [stderr]   Downloaded subetha-cxc v0.6.0
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-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] e9320938aef8f32c2f523d72273253562de192afef7971ec8fc36c1d8f12f483
[INFO] running `Command { std: "docker" "start" "e9320938aef8f32c2f523d72273253562de192afef7971ec8fc36c1d8f12f483", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "e9320938aef8f32c2f523d72273253562de192afef7971ec8fc36c1d8f12f483", 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" "e9320938aef8f32c2f523d72273253562de192afef7971ec8fc36c1d8f12f483" "/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" "e9320938aef8f32c2f523d72273253562de192afef7971ec8fc36c1d8f12f483", 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" "e9320938aef8f32c2f523d72273253562de192afef7971ec8fc36c1d8f12f483" "/opt/rustwide/cargo-home/bin/cargo" "+1.99.0-beta.8" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stderr]    Compiling subetha-core v0.6.0
[INFO] [stderr]    Compiling smallvec v1.16.2
[INFO] [stderr]    Compiling subetha-ffi v0.6.0 (/opt/rustwide/workdir)
[INFO] [stderr]    Compiling io-uring v0.7.15
[INFO] [stderr]    Compiling socket2 v0.6.5
[INFO] [stderr]    Compiling subetha-pointers v0.6.0
[INFO] [stderr]    Compiling subetha-sidecar v0.6.0
[INFO] [stderr]    Compiling parking_lot_core v0.9.12
[INFO] [stderr]    Compiling parking_lot v0.12.5
[INFO] [stderr]    Compiling subetha-cxc v0.6.0
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 31.58s
[INFO] running `Command { std: "docker" "inspect" "e9320938aef8f32c2f523d72273253562de192afef7971ec8fc36c1d8f12f483", 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" "e9320938aef8f32c2f523d72273253562de192afef7971ec8fc36c1d8f12f483" "/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 serde_core v1.0.229
[INFO] [stderr]    Compiling serde v1.0.229
[INFO] [stderr]    Compiling zerocopy v0.8.59
[INFO] [stderr]    Compiling syn v2.0.119
[INFO] [stderr]    Compiling syn v3.0.6
[INFO] [stderr]    Compiling anstyle-parse v1.0.0
[INFO] [stderr]    Compiling colorchoice v1.0.5
[INFO] [stderr]    Compiling cc v1.5.1
[INFO] [stderr]    Compiling anstream v1.0.0
[INFO] [stderr]    Compiling clap_lex v1.1.1
[INFO] [stderr]    Compiling either v1.18.0
[INFO] [stderr]    Compiling winnow v1.0.4
[INFO] [stderr]    Compiling clap_builder v4.6.7
[INFO] [stderr]    Compiling rustix v1.1.5
[INFO] [stderr]    Compiling regex-automata v0.4.18
[INFO] [stderr]    Compiling toml_parser v1.1.3+spec-1.1.0
[INFO] [stderr]    Compiling itertools v0.13.0
[INFO] [stderr]    Compiling alloca v0.4.0
[INFO] [stderr]    Compiling getrandom v0.4.3
[INFO] [stderr]    Compiling io-uring v0.7.15
[INFO] [stderr]    Compiling serde_json v1.0.151
[INFO] [stderr]    Compiling serde_derive v1.0.229
[INFO] [stderr]    Compiling clap v4.6.7
[INFO] [stderr]    Compiling serde_spanned v1.1.1
[INFO] [stderr]    Compiling toml_datetime v0.7.5+spec-1.1.0
[INFO] [stderr]    Compiling winnow v0.7.15
[INFO] [stderr]    Compiling cbindgen v0.29.4
[INFO] [stderr]    Compiling criterion-plot v0.8.2
[INFO] [stderr]    Compiling tempfile v3.27.0
[INFO] [stderr]    Compiling subetha-cxc v0.6.0
[INFO] [stderr]    Compiling regex v1.13.1
[INFO] [stderr]    Compiling rayon v1.12.0
[INFO] [stderr]    Compiling zerocopy-derive v0.8.59
[INFO] [stderr]    Compiling plotters v0.3.7
[INFO] [stderr]    Compiling toml v0.9.12+spec-1.1.0
[INFO] [stderr]    Compiling page_size v0.6.0
[INFO] [stderr]    Compiling heck v0.5.0
[INFO] [stderr]    Compiling tinytemplate v1.2.1
[INFO] [stderr]    Compiling half v2.7.1
[INFO] [stderr]    Compiling ciborium-ll v0.2.2
[INFO] [stderr]    Compiling ciborium v0.2.2
[INFO] [stderr]    Compiling criterion v0.8.2
[INFO] [stderr]    Compiling subetha-ffi v0.6.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 1m 54s
[INFO] running `Command { std: "docker" "inspect" "e9320938aef8f32c2f523d72273253562de192afef7971ec8fc36c1d8f12f483", 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" "e9320938aef8f32c2f523d72273253562de192afef7971ec8fc36c1d8f12f483" "/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.22s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/subetha_ffi-2fcbde0c2f263bb8)
[INFO] [stdout] 
[INFO] [stdout] running 99 tests
[INFO] [stdout] test batch::tests::a_pair_batch_checks_both_arrays ... ok
[INFO] [stdout] test batch::tests::a_reporting_batch_fills_a_word_for_each_item_that_landed ... ok
[INFO] [stdout] test batch::tests::the_arguments_a_batch_refuses ... ok
[INFO] [stdout] test capacity::tests::the_options_a_capacity_ring_refuses_are_named ... ok
[INFO] [stdout] test broadcast::tests::the_exported_sizes_are_the_rings_own ... ok
[INFO] [stdout] test atomic::tests::the_orderings_map_and_the_impossible_ones_are_refused ... ok
[INFO] [stdout] test capacity_pubsub::tests::a_subscriber_crosses_the_chain_after_a_morph_and_gc_reclaims_behind_it ... ok
[INFO] [stdout] test capacity_broadcast::tests::a_consumer_follows_a_morph_and_reads_the_stale_backing_first ... ok
[INFO] [stdout] test capacity::tests::a_morph_keeps_every_item_in_flight_and_in_order ... ok
[INFO] [stdout] test broadcast::tests::every_consumer_sees_every_item_and_the_slowest_gates_the_producer ... ok
[INFO] [stdout] test arena::tests::the_object_reports_its_room_and_a_reference_round_trips ... ok
[INFO] [stdout] test btree::tests::the_object_reports_its_shape_and_refuses_a_key_of_no_size ... ok
[INFO] [stdout] test atomic::tests::the_object_reports_the_width_it_was_built_at ... ok
[INFO] [stdout] test cell::tests::the_object_carries_the_size_the_caller_declared_and_counts_its_writes ... ok
[INFO] [stdout] test deque::tests::the_owner_pushes_and_pops_lifo_and_a_thief_steals_fifo ... ok
[INFO] [stdout] test epoch::tests::a_wait_returns_at_once_when_every_thread_is_quiet ... ok
[INFO] [stdout] test epoch::tests::a_thread_publishes_the_slot_it_is_inside_and_clears_on_the_way_out ... ok
[INFO] [stdout] test epoch::tests::a_second_borrow_on_one_thread_is_refused_rather_than_unpublishing_the_first ... ok
[INFO] [stdout] test epoch_barrier::tests::a_snapshot_reports_the_epoch_and_its_peers ... ok
[INFO] [stdout] test epoch_barrier::tests::a_quorum_releases_without_the_straggler ... ok
[INFO] [stdout] test epoch::tests::a_wait_outlasts_a_call_on_its_own_slot_and_ignores_another ... ok
[INFO] [stdout] test epoch_barrier::tests::the_default_grace_matches_the_primitive ... ok
[INFO] [stdout] test epochs::tests::a_pin_holds_the_horizon_and_a_ticket_holds_the_published_epoch ... ok
[INFO] [stdout] test error::tests::detail_reports_the_size_it_needs_and_never_overruns ... ok
[INFO] [stdout] test condvar::tests::a_wait_ends_on_its_deadline_and_a_destroy_releases_an_unbounded_one ... ok
[INFO] [stdout] test condvar::tests::a_notify_advances_the_generation_and_releases_a_parked_caller ... ok
[INFO] [stdout] test error::tests::every_code_has_a_name_and_the_unknown_one_says_so ... ok
[INFO] [stdout] test fence_clock::tests::a_merge_orders_after_what_it_took_in_and_the_fence_follows_the_slowest ... ok
[INFO] [stdout] test frame_region::tests::the_object_reports_its_geometry_and_hands_blocks_round ... ok
[INFO] [stdout] test handle::tests::a_handle_is_refused_after_destroy_and_its_slot_is_reissued_with_a_new_generation ... ok
[INFO] [stdout] test epoch::tests::a_thread_that_ends_gives_its_word_back ... ok
[INFO] [stdout] test deque::tests::a_steal_parks_until_a_push_and_a_push_parks_until_a_steal ... ok
[INFO] [stdout] test hashmap::tests::the_object_reports_its_shape_and_refuses_bad_sizes ... ok
[INFO] [stdout] test heartbeat::tests::a_full_table_refuses_the_next_registration ... ok
[INFO] [stdout] test holders::tests::a_reap_leaves_a_live_process_alone ... ok
[INFO] [stdout] test holders::tests::slots_carry_a_payload_and_the_process_that_set_it ... ok
[INFO] [stdout] test holds::tests::a_bounded_table_stops_at_its_ceiling ... ok
[INFO] [stdout] test holds::tests::a_full_table_refuses_and_a_forged_token_names_nothing ... ok
[INFO] [stdout] test holds::tests::a_handle_shaped_value_is_refused_rather_than_naming_a_slot ... ok
[INFO] [stdout] test holds::tests::a_token_from_an_earlier_hold_of_the_same_slot_is_refused ... ok
[INFO] [stdout] test holds::tests::a_token_is_good_once_and_names_the_kind_it_was_taken_as ... ok
[INFO] [stdout] test holds::tests::an_unbounded_table_grows_past_a_block_and_keeps_every_token_good ... ok
[INFO] [stdout] test heartbeat::tests::slots_are_taken_and_given_back_and_a_quiet_one_shows_its_last_epoch ... ok
[INFO] [stdout] test handle::tests::zero_and_forged_handles_name_nothing ... ok
[INFO] [stdout] test leader::tests::the_lowest_process_id_leads_and_the_term_records_every_handover ... ok
[INFO] [stdout] test leader::tests::a_leader_that_stops_beating_is_replaced_once_the_epoch_moves ... ok
[INFO] [stdout] test handle::tests::destroy_waits_for_a_call_in_flight_and_that_call_sees_the_object_alive ... ok
[INFO] [stdout] test handle::tests::a_poisoned_handle_refuses_calls_until_it_is_destroyed ... ok
[INFO] [stdout] test epoch_barrier::tests::an_early_arriver_does_not_pass_until_the_late_one_arrives ... ok
[INFO] [stdout] test list::tests::the_object_reports_its_shape_and_walks_its_nodes ... ok
[INFO] [stdout] test epoch_barrier::tests::a_wait_alone_times_out_and_reports_it ... ok
[INFO] [stdout] test mpsc::tests::a_destroy_interrupt_releases_a_parked_producer ... ok
[INFO] [stdout] test mpmc::tests::each_consumer_drains_its_own_subset_and_parks_until_it_has_work ... ok
[INFO] [stdout] test ordered::tests::counter_stamps_get_the_reorder_strategy_and_come_out_in_stamp_order ... ok
[INFO] [stdout] test owner_lease::tests::the_epoch_has_to_be_ticked_for_a_stale_holder_to_be_displaced ... ok
[INFO] [stdout] test owner_lease::tests::the_object_reports_the_holder_and_the_payload_moves_with_the_lease ... ok
[INFO] [stdout] test ordered::tests::time_stamps_are_delivered_directly ... ok
[INFO] [stdout] test pubsub::tests::the_exported_size_is_the_rings_own ... ok
[INFO] [stdout] test region::tests::the_object_reports_its_shape_and_its_free_list ... ok
[INFO] [stdout] test ring::tests::a_frame_past_the_slot_round_trips_and_a_short_buffer_holds_it ... ok
[INFO] [stdout] test locale::tests::a_migration_carries_the_items_in_flight_and_the_stats_follow ... ok
[INFO] [stdout] test ring::tests::a_destroy_interrupt_releases_a_waiter_with_its_own_code ... ok
[INFO] [stdout] test ring::tests::a_notifier_is_signaled_by_a_push_and_quiet_after_a_drain ... ok
[INFO] [stdout] test mpsc::tests::producers_fan_in_and_the_consumer_parks_until_one_pushes ... ok
[INFO] [stdout] test pubsub::tests::a_subscriber_reads_in_order_waits_for_the_next_publish_and_is_told_what_it_lost ... ok
[INFO] [stdout] test runtime::tests::modes_resolve_and_the_rest_is_refused ... ok
[INFO] [stdout] test ring::tests::the_exported_sizes_are_the_rings_own ... ok
[INFO] [stdout] test runtime::tests::the_packed_version_agrees_with_its_string ... ok
[INFO] [stdout] test rwlock::tests::a_waiting_writer_registers_and_readers_are_refused_until_it_stops ... ok
[INFO] [stdout] test rwlock::tests::holds_exclude_each_other_and_a_released_hold_clears_the_state ... ok
[INFO] [stdout] test ring::tests::managed_mode_takes_a_default_interval_and_strict_needs_none ... ok
[INFO] [stdout] test semaphore::tests::a_release_past_the_ceiling_is_counted_and_the_count_stays_bounded ... ok
[INFO] [stdout] test holds::tests::concurrent_claims_never_hand_one_slot_to_two_holders ... ok
[INFO] [stdout] test shared_arc::tests::a_range_past_the_region_is_out_of_bounds ... ok
[INFO] [stdout] test shared_arc::tests::a_snapshot_reports_the_region_and_its_holders ... ok
[INFO] [stdout] test semaphore::tests::permits_run_out_and_a_released_permit_is_taken_again ... ok
[INFO] [stdout] test slab::tests::the_object_reports_its_shape_and_the_layout_it_opened_with ... ok
[INFO] [stdout] test shared_arc::tests::an_unknown_last_holder_policy_is_refused ... ok
[INFO] [stdout] test spsc::tests::a_destroy_interrupt_releases_a_waiter ... ok
[INFO] [stdout] test ring::tests::a_push_wakes_a_parked_pop_and_a_timeout_is_reported ... ok
[INFO] [stdout] test stack::tests::a_layout_is_checked_before_a_file_is_touched ... ok
[INFO] [stdout] test stack::tests::the_element_size_bounds_a_push_and_a_pop_buffer ... ok
[INFO] [stdout] test vec::tests::the_object_reports_its_shape_and_the_layout_it_opened_with ... ok
[INFO] [stdout] test vyukov::tests::a_stuck_slot_scan_finds_nothing_on_a_healthy_ring ... ok
[INFO] [stdout] test rwlock::tests::a_wait_ends_on_its_deadline_and_a_destroy_releases_an_unbounded_one ... ok
[INFO] [stdout] test semaphore::tests::a_wait_ends_on_its_deadline_and_a_destroy_releases_an_unbounded_one ... ok
[INFO] [stdout] test stack::tests::pushes_from_two_threads_all_arrive_and_a_pop_parks_until_one_does ... ok
[INFO] [stdout] test wait::tests::a_close_ends_the_wait_with_its_own_code ... ok
[INFO] [stdout] test waker::tests::a_park_token_is_good_once ... ok
[INFO] [stdout] test waker::tests::a_woken_park_is_released_by_its_wait ... ok
[INFO] [stdout] test waker::tests::the_park_table_holds_what_the_waker_holds ... ok
[INFO] [stdout] test waker::tests::waking_an_empty_waker_wakes_nobody ... ok
[INFO] [stdout] test vyukov::tests::pushes_from_two_threads_all_arrive_and_a_pop_parks_until_one_does ... ok
[INFO] [stdout] test spsc::tests::a_push_wakes_a_parked_pop_and_a_full_ring_parks_the_push ... ok
[INFO] [stdout] test wait::tests::a_wake_releases_the_wait_and_a_deadline_ends_it ... ok
[INFO] [stdout] test wait::tests::a_poll_ends_on_its_deadline_and_on_a_destroy ... ok
[INFO] [stdout] test runtime::tests::a_panic_is_caught_counted_and_kept ... ok
[INFO] [stdout] test handle::tests::the_table_grows_without_a_ceiling_and_every_handle_stays_distinct ... ok
[INFO] [stdout] test locale::tests::a_managed_ring_moved_directly_stays_where_it_was_moved ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 99 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 1.12s
[INFO] [stdout] 
[INFO] [stderr]    Doc-tests subetha_ffi
[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" "e9320938aef8f32c2f523d72273253562de192afef7971ec8fc36c1d8f12f483", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "e9320938aef8f32c2f523d72273253562de192afef7971ec8fc36c1d8f12f483", kill_on_drop: false }`
[INFO] [stdout] e9320938aef8f32c2f523d72273253562de192afef7971ec8fc36c1d8f12f483
