[INFO] fetching crate hisi-rtos 0.1.0-alpha.25...
[INFO] testing hisi-rtos-0.1.0-alpha.25 against 1.99.0-beta.8 for beta-1.100-2
[INFO] extracting crate hisi-rtos 0.1.0-alpha.25 into /workspace/builds/worker-0-tc1/source
[INFO] removed /workspace/builds/worker-0-tc1/source/rust-toolchain.toml
[INFO] started tweaking crates.io crate hisi-rtos 0.1.0-alpha.25
[INFO] removed 0 missing tests
[INFO] finished tweaking crates.io crate hisi-rtos 0.1.0-alpha.25
[INFO] tweaked toml for crates.io crate hisi-rtos 0.1.0-alpha.25 written to /workspace/builds/worker-0-tc1/source/Cargo.toml
[INFO] validating manifest of crates.io crate hisi-rtos 0.1.0-alpha.25 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 hisi-rtos 0.1.0-alpha.25 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]     Blocking waiting for file lock on package cache
[INFO] [stderr]     Updating crates.io index
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] [stderr]  Downloading crates ...
[INFO] [stderr]   Downloaded target-triple v1.0.1
[INFO] [stderr]   Downloaded trybuild v1.0.120
[INFO] [stderr]   Downloaded hisi-rf-rtos-driver v0.1.0-alpha.20
[INFO] [stderr]   Downloaded vcell v0.1.3
[INFO] [stderr]   Downloaded embassy-time-driver v0.2.2
[INFO] [stderr]   Downloaded indexmap v2.14.1
[INFO] [stderr]   Downloaded nb v1.1.0
[INFO] [stderr]   Downloaded embedded-hal-nb v1.0.0
[INFO] [stderr]   Downloaded hisi-alloc v0.1.0-alpha.3
[INFO] [stderr]   Downloaded embassy-executor-timer-queue v0.1.0
[INFO] [stderr]   Downloaded embassy-time-queue-utils v0.3.2
[INFO] [stderr]   Downloaded hash32 v0.3.1
[INFO] [stderr]   Downloaded riscv-pac v0.2.0
[INFO] [stderr]   Downloaded riscv-macros v0.2.0
[INFO] [stderr]   Downloaded instability v0.3.13
[INFO] [stderr]   Downloaded embedded-dma v0.2.0
[INFO] [stderr]   Downloaded linked_list_allocator v0.10.6
[INFO] [stderr]   Downloaded embedded-hal v1.0.0
[INFO] [stderr]   Downloaded riscv v0.13.0
[INFO] [stderr]   Downloaded heapless v0.9.3
[INFO] [stderr]   Downloaded ws63-pac v0.4.5
[INFO] [stderr]   Downloaded hisi-hal v0.7.0-alpha.9
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-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] a452dd869d999237201ec882a47b43c9a160c4f3cc0da0f08b7aaf497749f1bd
[INFO] running `Command { std: "docker" "start" "a452dd869d999237201ec882a47b43c9a160c4f3cc0da0f08b7aaf497749f1bd", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "a452dd869d999237201ec882a47b43c9a160c4f3cc0da0f08b7aaf497749f1bd", 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" "a452dd869d999237201ec882a47b43c9a160c4f3cc0da0f08b7aaf497749f1bd" "/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" "a452dd869d999237201ec882a47b43c9a160c4f3cc0da0f08b7aaf497749f1bd", 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" "a452dd869d999237201ec882a47b43c9a160c4f3cc0da0f08b7aaf497749f1bd" "/opt/rustwide/cargo-home/bin/cargo" "+1.99.0-beta.8" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stderr]    Compiling critical-section v1.2.0
[INFO] [stderr]    Compiling linked_list_allocator v0.10.6
[INFO] [stderr]    Compiling hisi-rf-rtos-driver v0.1.0-alpha.20
[INFO] [stderr]    Compiling hisi-alloc v0.1.0-alpha.3
[INFO] [stderr]    Compiling hisi-rtos v0.1.0-alpha.25 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 1.04s
[INFO] running `Command { std: "docker" "inspect" "a452dd869d999237201ec882a47b43c9a160c4f3cc0da0f08b7aaf497749f1bd", 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" "a452dd869d999237201ec882a47b43c9a160c4f3cc0da0f08b7aaf497749f1bd" "/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 proc-macro2 v1.0.107
[INFO] [stderr]    Compiling quote v1.0.47
[INFO] [stderr]    Compiling zmij v1.0.23
[INFO] [stderr]    Compiling serde v1.0.229
[INFO] [stderr]    Compiling serde_json v1.0.151
[INFO] [stderr]    Compiling target-triple v1.0.1
[INFO] [stderr]    Compiling winnow v1.0.4
[INFO] [stderr]    Compiling critical-section v1.2.0
[INFO] [stderr]    Compiling memchr v2.8.3
[INFO] [stderr]    Compiling toml_writer v1.1.2+spec-1.1.0
[INFO] [stderr]    Compiling glob v0.3.4
[INFO] [stderr]    Compiling hisi-rf-rtos-driver v0.1.0-alpha.20
[INFO] [stderr]    Compiling hisi-alloc v0.1.0-alpha.3
[INFO] [stderr]    Compiling hisi-rtos v0.1.0-alpha.25 (/opt/rustwide/workdir)
[INFO] [stderr]    Compiling toml_parser v1.1.3+spec-1.1.0
[INFO] [stderr]    Compiling syn v3.0.4
[INFO] [stderr]    Compiling serde_spanned v1.1.1
[INFO] [stderr]    Compiling toml_datetime v1.1.1+spec-1.1.0
[INFO] [stderr]    Compiling toml v1.1.4+spec-1.1.0
[INFO] [stderr]    Compiling serde_derive v1.0.229
[INFO] [stderr]    Compiling trybuild v1.0.120
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 12.09s
[INFO] running `Command { std: "docker" "inspect" "a452dd869d999237201ec882a47b43c9a160c4f3cc0da0f08b7aaf497749f1bd", 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" "a452dd869d999237201ec882a47b43c9a160c4f3cc0da0f08b7aaf497749f1bd" "/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.17s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/hisi_rtos-0b84a586d7e5920c)
[INFO] [stdout] 
[INFO] [stdout] running 86 tests
[INFO] [stdout] test runtime::conformance::runtime_v1_executes_shared_conformance_scenarios ... ok
[INFO] [stdout] test runtime::tests::base_priority_change_preserves_and_then_restores_inheritance ... ok
[INFO] [stdout] test runtime::tests::all_run_policies_use_effective_priority_then_fifo ... ok
[INFO] [stdout] test runtime::tests::budget_exhaustion_removes_task_until_replenishment ... ok
[INFO] [stdout] test runtime::resource::tests::handle_kind_is_part_of_the_identity ... ok
[INFO] [stdout] test runtime::tests::caller_selected_capacity_bounds_dynamic_slots_and_diagnostics ... ok
[INFO] [stdout] test runtime::tests::cancelling_semaphore_handoff_returns_exactly_one_count ... ok
[INFO] [stdout] test runtime::tests::chained_mutex_inheritance_propagates_effective_priority ... ok
[INFO] [stdout] test runtime::tests::completed_switch_recovery_keeps_idle_out_of_ready_queues ... ok
[INFO] [stdout] test runtime::tests::completed_switch_recovery_does_not_duplicate_an_already_requeued_target ... ok
[INFO] [stdout] test runtime::tests::cancelling_mutex_wait_restores_owner_priority ... ok
[INFO] [stdout] test runtime::tests::completed_irq_switch_restores_the_detached_thread_target ... ok
[INFO] [stdout] test runtime::tests::equal_priority_mutex_waiters_handoff_in_fifo_order ... ok
[INFO] [stdout] test runtime::tests::duplicate_mutex_waiters_keep_owner_inherited_until_both_leave ... ok
[INFO] [stdout] test runtime::tests::coexistence_resource_plan_reserves_six_exact_groups_atomically ... ok
[INFO] [stdout] test runtime::tests::cooperative_task_is_not_preempted_by_irq_but_can_yield ... ok
[INFO] [stdout] test runtime::tests::cooperative_yield_hands_off_before_requeueing_higher_priority_task ... ok
[INFO] [stdout] test runtime::tests::heterogeneous_resource_plan_reserves_children_in_plan_order ... ok
[INFO] [stdout] test runtime::tests::exited_stacks_are_retired_for_later_reclamation ... ok
[INFO] [stdout] test runtime::tests::dynamic_allocation_reserves_main_and_idle_slots ... ok
[INFO] [stdout] test runtime::tests::expired_time_slice_round_robins_equal_priority_tasks ... ok
[INFO] [stdout] test runtime::tests::earliest_deadline_ignores_forever_waiters ... ok
[INFO] [stdout] test runtime::tests::heterogeneous_slot_failure_leaves_no_partial_reservation ... ok
[INFO] [stdout] test runtime::driver::policy_tests::ported_budget_maps_without_post_spawn_mutation ... ok
[INFO] [stdout] test runtime::tests::cancelling_mutex_handoff_releases_unconsumed_ownership ... ok
[INFO] [stdout] test runtime::tests::cancelling_queued_semaphore_wait_makes_task_ready_without_minting_a_count ... ok
[INFO] [stdout] test runtime::resource::tests::duplicate_and_stale_handles_fail_before_pointer_resolution ... ok
[INFO] [stdout] test runtime::tests::forever_semaphore_wait_is_not_treated_as_an_expired_deadline ... ok
[INFO] [stdout] test runtime::driver::policy_tests::portless_rejects_forced_execution_policies ... ok
[INFO] [stdout] test runtime::tests::heterogeneous_stack_failure_rolls_back_every_prior_group ... ok
[INFO] [stdout] test runtime::tests::idle_is_selected_only_when_the_ready_queues_are_empty ... ok
[INFO] [stdout] test runtime::tests::ported_switch_ticket_owns_target_before_leaving_scheduler_lock ... ok
[INFO] [stdout] test runtime::tests::mutex_handoff_transfers_remaining_inheritance_to_new_owner ... ok
[INFO] [stdout] test runtime::tests::ported_thread_switch_requires_mie_but_irq_epilogue_does_not ... ok
[INFO] [stdout] test runtime::tests::preemptive_ready_queue_uses_priority_then_fifo ... ok
[INFO] [stdout] test runtime::tests::ready_ownership_audit_detects_detached_duplicate_and_wrong_bucket ... ok
[INFO] [stdout] test runtime::tests::ready_queue_prefers_lower_priority_number_and_keeps_fifo ... ok
[INFO] [stdout] test runtime::tests::priority_change_keeps_pending_target_detached ... ok
[INFO] [stdout] test runtime::tests::releasing_a_reservation_returns_only_unconsumed_slots ... ok
[INFO] [stdout] test runtime::tests::reservations_protect_promised_slots_from_ordinary_spawns ... ok
[INFO] [stdout] test runtime::tests::resumed_switch_away_source_preserves_ready_target ... ok
[INFO] [stdout] test runtime::tests::ready_task_can_move_between_priority_queues ... ok
[INFO] [stdout] test runtime::tests::scheduler_lock_preserves_expired_time_slice_until_unlock ... ok
[INFO] [stdout] test runtime::tests::mutex_switch_away_preserves_wait_ownership ... ok
[INFO] [stdout] test runtime::tests::scheduler_lock_is_nested_and_rejects_unbalanced_unlock ... ok
[INFO] [stdout] test runtime::tests::outermost_scheduler_unlock_releases_pending_higher_priority_task ... ok
[INFO] [stdout] test runtime::tests::scheduler_lock_defers_but_cannot_cancel_budget_throttle ... ok
[INFO] [stdout] test runtime::tests::owned_or_waited_mutex_cannot_be_destroyed ... ok
[INFO] [stdout] test runtime::tests::idle_yield_hands_off_without_entering_the_ready_queue ... ok
[INFO] [stdout] test runtime::tests::ready_ownership_audit_accepts_queued_and_pending_ready_tasks ... ok
[INFO] [stdout] test runtime::tests::scheduler_lock_limit_is_a_timer_deadline_and_fail_stop_violation ... ok
[INFO] [stdout] test runtime::tests::policy_change_releases_a_throttled_task ... ok
[INFO] [stdout] test runtime::tests::policy_change_keeps_pending_target_detached ... ok
[INFO] [stdout] test runtime::tests::pending_thread_switch_is_not_mistaken_for_a_completed_irq_switch ... ok
[INFO] [stdout] test runtime::tests::scheduler_lock_rejects_switching_or_blocking_entry_points ... ok
[INFO] [stdout] test runtime::tests::irq_epilogue_preempts_only_after_outermost_interrupt_exit ... ok
[INFO] [stdout] test runtime::tests::semaphore_waiters_are_priority_fifo_and_reorder_on_priority_change ... ok
[INFO] [stdout] test runtime::tests::semaphore_with_waiters_cannot_be_destroyed ... ok
[INFO] [stdout] test runtime::tests::stale_timer_programming_ticket_requires_retry ... ok
[INFO] [stdout] test runtime::tests::switch_intent_is_committed_and_consumed_exactly_once ... ok
[INFO] [stdout] test runtime::tests::task_exit_switch_away_commits_after_source_is_freed ... ok
[INFO] [stdout] test runtime::tests::mutex_cycle_is_rejected_without_mutating_wait_graph ... ok
[INFO] [stdout] test runtime::tests::task_metrics_account_dispatch_cpu_and_ready_latency ... ok
[INFO] [stdout] test runtime::tests::shared_timer_uses_earliest_rtos_slice_or_embassy_deadline ... ok
[INFO] [stdout] test runtime::tests::task_metrics_attribute_outermost_irq_span_to_interrupted_task ... ok
[INFO] [stdout] test runtime::tests::switch_intent_rejects_reused_task_identity ... ok
[INFO] [stdout] test runtime::tests::switch_intent_source_identity_failure_restores_target ... ok
[INFO] [stdout] test runtime::tests::task_stack_preallocation_rolls_back_every_partial_allocation ... ok
[INFO] [stdout] test runtime::tests::task_identity_generation_rejects_stale_slot_handle ... ok
[INFO] [stdout] test runtime::tests::stale_switch_intent_restores_its_detached_target ... ok
[INFO] [stdout] test runtime::tests::timed_out_mutex_waiter_restores_owner_priority ... ok
[INFO] [stdout] test runtime::tests::timed_semaphore_wait_wakes_only_after_its_deadline ... ok
[INFO] [stdout] test runtime::tests::task_metrics_measure_outermost_scheduler_lock_interval ... ok
[INFO] [stdout] test runtime::tests::task_resource_reservation_consumes_and_releases_preallocated_stacks ... ok
[INFO] [stdout] test runtime::tests::task_diagnostics_report_dynamic_stack_allocation_size ... ok
[INFO] [stdout] test runtime::tests::sleep_switch_away_commits_detached_target_ownership ... ok
[INFO] [stdout] test runtime::tests::unrelated_deadline_rearm_does_not_postpone_time_slice ... ok
[INFO] [stdout] test scheduling::tests::exhausted_budget_throttles_until_phase_aligned_replenishment ... ok
[INFO] [stdout] test scheduling::tests::scheduler_lock_cannot_erase_an_exhaustion_by_crossing_periods ... ok
[INFO] [stdout] test scheduling::tests::switch_out_preserves_unused_budget ... ok
[INFO] [stdout] test storage::tests::unsupported_capacity_fails_before_claiming_the_arena ... ok
[INFO] [stdout] test runtime::tests::semaphore_switch_away_preserves_wait_ownership ... ok
[INFO] [stdout] test scheduling::tests::rejects_capacity_larger_than_period ... ok
[INFO] [stdout] test runtime::tests::timer_wakeup_preempts_idle_without_queueing_idle ... ok
[INFO] [stdout] test storage::tests::caller_owned_arena_is_installed_once_and_reports_usage ... ok
[INFO] [stdout] test scheduling::tests::long_inactivity_advances_phase_without_looping_each_period ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 86 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/ui.rs (/opt/rustwide/target/debug/deps/ui-5bdfdf3cd9555d45)
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stderr]    Compiling critical-section v1.2.0
[INFO] [stderr]    Compiling linked_list_allocator v0.10.6
[INFO] [stderr]    Compiling hisi-alloc v0.1.0-alpha.3
[INFO] [stderr]    Compiling hisi-rf-rtos-driver v0.1.0-alpha.20
[INFO] [stderr]    Compiling hisi-rtos v0.1.0-alpha.25 (/opt/rustwide/workdir)
[INFO] [stderr]    Compiling hisi-rtos-tests v0.0.0 (/opt/rustwide/target/tests/trybuild/hisi-rtos)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 2.27s
[INFO] [stderr] 
[INFO] [stderr] 
[INFO] [stderr] test tests/ui/cooperative_policy.rs [should fail to compile] ... ok
[INFO] [stderr] test tests/ui/ported_policy.rs [should pass] ... ok
[INFO] [stdout] test runtime_mode_capabilities_are_checked_at_compile_time ... ok
[INFO] [stderr] 
[INFO] [stdout] 
[INFO] [stderr] 
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 5.31s
[INFO] [stdout] 
[INFO] [stderr]    Doc-tests hisi_rtos
[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" "a452dd869d999237201ec882a47b43c9a160c4f3cc0da0f08b7aaf497749f1bd", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "a452dd869d999237201ec882a47b43c9a160c4f3cc0da0f08b7aaf497749f1bd", kill_on_drop: false }`
[INFO] [stdout] a452dd869d999237201ec882a47b43c9a160c4f3cc0da0f08b7aaf497749f1bd
