[INFO] fetching crate rt-app-rs 0.2.0...
[INFO] testing rt-app-rs-0.2.0 against master#562dee4820c458d823175268e41601d4c060588a for pr-154210-2
[INFO] extracting crate rt-app-rs 0.2.0 into /workspace/builds/worker-7-tc1/source
[INFO] started tweaking crates.io crate rt-app-rs 0.2.0
[INFO] removed 0 missing tests
[INFO] finished tweaking crates.io crate rt-app-rs 0.2.0
[INFO] tweaked toml for crates.io crate rt-app-rs 0.2.0 written to /workspace/builds/worker-7-tc1/source/Cargo.toml
[INFO] validating manifest of crates.io crate rt-app-rs 0.2.0 on toolchain 562dee4820c458d823175268e41601d4c060588a
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+562dee4820c458d823175268e41601d4c060588a" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate crates.io crate rt-app-rs 0.2.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" "+562dee4820c458d823175268e41601d4c060588a" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc1/source:/opt/rustwide/workdir:ro,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" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+562dee4820c458d823175268e41601d4c060588a" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] 48d87d6522c9dc02e177eb91445754b167f2c509497bc6753b7288e42dbc927d
[INFO] running `Command { std: "docker" "start" "-a" "48d87d6522c9dc02e177eb91445754b167f2c509497bc6753b7288e42dbc927d", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "48d87d6522c9dc02e177eb91445754b167f2c509497bc6753b7288e42dbc927d", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "48d87d6522c9dc02e177eb91445754b167f2c509497bc6753b7288e42dbc927d", kill_on_drop: false }`
[INFO] [stdout] 48d87d6522c9dc02e177eb91445754b167f2c509497bc6753b7288e42dbc927d
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc1/source:/opt/rustwide/workdir:ro,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" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+562dee4820c458d823175268e41601d4c060588a" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] ef024c201482f2c715006b9e0359b3446839d4bd3f5692aafa65b960c004ad5c
[INFO] running `Command { std: "docker" "start" "-a" "ef024c201482f2c715006b9e0359b3446839d4bd3f5692aafa65b960c004ad5c", kill_on_drop: false }`
[INFO] [stderr]    Compiling libc v0.2.182
[INFO] [stderr]    Compiling regex-syntax v0.8.9
[INFO] [stderr]    Compiling aho-corasick v1.1.4
[INFO] [stderr]    Compiling nix v0.29.0
[INFO] [stderr]    Compiling serde_json v1.0.149
[INFO] [stderr]    Compiling quote v1.0.44
[INFO] [stderr]    Compiling jiff v0.2.20
[INFO] [stderr]    Compiling colored v3.1.1
[INFO] [stderr]    Compiling syn v2.0.117
[INFO] [stderr]    Compiling regex-automata v0.4.14
[INFO] [stderr]    Compiling errno v0.3.14
[INFO] [stderr]    Compiling signal-hook-registry v1.4.8
[INFO] [stderr]    Compiling signal-hook v0.3.18
[INFO] [stderr]    Compiling clap_derive v4.5.55
[INFO] [stderr]    Compiling serde_derive v1.0.228
[INFO] [stderr]    Compiling thiserror-impl v2.0.18
[INFO] [stderr]    Compiling thiserror v2.0.18
[INFO] [stderr]    Compiling regex v1.12.3
[INFO] [stderr]    Compiling clap v4.5.60
[INFO] [stderr]    Compiling env_filter v1.0.0
[INFO] [stderr]    Compiling env_logger v0.11.9
[INFO] [stderr]    Compiling serde v1.0.228
[INFO] [stderr]    Compiling rt-app-rs v0.2.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 22.91s
[INFO] running `Command { std: "docker" "inspect" "ef024c201482f2c715006b9e0359b3446839d4bd3f5692aafa65b960c004ad5c", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "ef024c201482f2c715006b9e0359b3446839d4bd3f5692aafa65b960c004ad5c", kill_on_drop: false }`
[INFO] [stdout] ef024c201482f2c715006b9e0359b3446839d4bd3f5692aafa65b960c004ad5c
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc1/source:/opt/rustwide/workdir:ro,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" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+562dee4820c458d823175268e41601d4c060588a" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 8b5d575be946d79779340369f2fedcf0603bebd11eb4854ae3198bda970723c6
[INFO] running `Command { std: "docker" "start" "-a" "8b5d575be946d79779340369f2fedcf0603bebd11eb4854ae3198bda970723c6", kill_on_drop: false }`
[INFO] [stderr]    Compiling getrandom v0.4.1
[INFO] [stderr]    Compiling rustix v1.1.3
[INFO] [stderr]    Compiling nix v0.29.0
[INFO] [stderr]    Compiling linux-raw-sys v0.11.0
[INFO] [stderr]    Compiling rt-app-rs v0.2.0 (/opt/rustwide/workdir)
[INFO] [stderr]    Compiling tempfile v3.25.0
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 9.96s
[INFO] running `Command { std: "docker" "inspect" "8b5d575be946d79779340369f2fedcf0603bebd11eb4854ae3198bda970723c6", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "8b5d575be946d79779340369f2fedcf0603bebd11eb4854ae3198bda970723c6", kill_on_drop: false }`
[INFO] [stdout] 8b5d575be946d79779340369f2fedcf0603bebd11eb4854ae3198bda970723c6
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc1/source:/opt/rustwide/workdir:ro,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" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+562dee4820c458d823175268e41601d4c060588a" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] f276decc382810e66d63e1e1ab920fd538e3172f5fe886019424b7de07f27f8b
[INFO] running `Command { std: "docker" "start" "-a" "f276decc382810e66d63e1e1ab920fd538e3172f5fe886019424b7de07f27f8b", kill_on_drop: false }`
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.12s
[INFO] [stdout] 
[INFO] [stdout] running 264 tests
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/rt_app_rs-e00974071ffbc718)
[INFO] [stdout] test affinity::tests::cpuset_data_from_cpus ... ok
[INFO] [stdout] test args::tests::colorize_json_handles_comments ... ok
[INFO] [stdout] test affinity::tests::cpuset_str_empty ... ok
[INFO] [stdout] test affinity::tests::cpuset_data_display ... ok
[INFO] [stdout] test affinity::tests::set_thread_affinity_uses_phase_over_task ... ok
[INFO] [stdout] test args::tests::custom_log_level_long ... ok
[INFO] [stdout] test affinity::tests::cpuset_data_equality ... ok
[INFO] [stdout] test args::tests::colorize_json_handles_block_comments ... ok
[INFO] [stdout] test args::tests::colorize_json_preserves_content ... ok
[INFO] [stdout] test affinity::tests::cpuset_str_high_numbers ... ok
[INFO] [stdout] test args::tests::exit_code_into_process_exit_code ... ok
[INFO] [stdout] test affinity::tests::cpuset_str_single ... ok
[INFO] [stdout] test args::tests::exit_code_values ... ok
[INFO] [stdout] test args::tests::log_level_ordering ... ok
[INFO] [stdout] test args::tests::log_level_parse ... ok
[INFO] [stdout] test args::tests::log_level_display ... ok
[INFO] [stdout] test args::tests::log_level_parse_invalid ... ok
[INFO] [stdout] test args::tests::positional_config_file ... ok
[INFO] [stdout] test config::global::tests::calibration_precise ... ok
[INFO] [stdout] test config::global::tests::calibration_string ... ok
[INFO] [stdout] test args::tests::missing_config_arg_errors ... ok
[INFO] [stdout] test args::tests::print_template_with_config ... ok
[INFO] [stdout] test args::tests::default_log_level ... ok
[INFO] [stdout] test args::tests::template_contains_expected_sections ... ok
[INFO] [stdout] test args::tests::version_string_format ... ok
[INFO] [stdout] test args::tests::print_template_without_config ... ok
[INFO] [stdout] test args::tests::custom_log_level_short ... ok
[INFO] [stdout] test affinity::tests::set_thread_affinity_falls_back_to_task ... ok
[INFO] [stdout] test config::global::tests::global_duration_negative ... ok
[INFO] [stdout] test config::global::tests::ftrace_boolean_deprecated ... ok
[INFO] [stdout] test config::global::tests::invalid_policy ... ok
[INFO] [stdout] test config::global::tests::ftrace_none_string ... ok
[INFO] [stdout] test config::global::tests::log_size_disable ... ok
[INFO] [stdout] test args::tests::positional_config_stdin ... ok
[INFO] [stdout] test config::global::tests::global_duration_positive ... ok
[INFO] [stdout] test config::global::tests::log_size_file ... ok
[INFO] [stdout] test config::global::tests::parse_global_full ... ok
[INFO] [stdout] test affinity::tests::cpuset_str_multiple ... ok
[INFO] [stdout] test config::global::tests::default_global_config ... ok
[INFO] [stdout] test config::global::tests::ftrace_string ... ok
[INFO] [stdout] test config::global::tests::calibration_integer ... ok
[INFO] [stdout] test config::resources::tests::auto_create_resource ... ok
[INFO] [stdout] test config::resources::tests::invalid_resource_type ... ok
[INFO] [stdout] test config::global::tests::log_size_auto_fallback_to_file ... ok
[INFO] [stdout] test config::global::tests::log_size_integer ... ok
[INFO] [stdout] test config::resources::tests::resource_data_for_types ... ok
[INFO] [stdout] test config::resources::tests::parse_explicit_resources ... ok
[INFO] [stdout] test config::resources::tests::same_name_different_type ... ok
[INFO] [stdout] test config::resources::tests::default_type_is_mutex ... ok
[INFO] [stdout] test config::tasks::tests::auto_create_resources ... ok
[INFO] [stdout] test config::global::tests::parse_global_none_gives_defaults ... ok
[INFO] [stdout] test config::tasks::tests::classify_run_events ... ok
[INFO] [stdout] test config::resources::tests::empty_resources ... ok
[INFO] [stdout] test config::tasks::tests::classify_lock_events ... ok
[INFO] [stdout] test config::tasks::tests::classify_non_event ... ok
[INFO] [stdout] test config::tasks::tests::parse_fork_event ... ok
[INFO] [stdout] test config::tasks::tests::parse_deadline_params ... ok
[INFO] [stdout] test config::tasks::tests::parse_instance_count ... ok
[INFO] [stdout] test config::tasks::tests::parse_barrier_event ... ok
[INFO] [stdout] test config::tasks::tests::parse_scheduling_params ... ok
[INFO] [stdout] test config::tasks::tests::delay_parsing ... ok
[INFO] [stdout] test config::tasks::tests::parse_cpus_and_numa ... ok
[INFO] [stdout] test config::tasks::tests::parse_suspend_resume ... ok
[INFO] [stdout] test config::tasks::tests::parse_sync_event ... ok
[INFO] [stdout] test config::tasks::tests::parse_wait_event ... ok
[INFO] [stdout] test config::tasks::tests::parse_task_with_phases ... ok
[INFO] [stdout] test config::tasks::tests::parse_yield_event ... ok
[INFO] [stdout] test config::tasks::tests::parse_timer_modes ... ok
[INFO] [stdout] test config::tasks::tests::parse_lock_unlock_events ... ok
[INFO] [stdout] test config::tasks::tests::parse_signal_event ... ok
[INFO] [stdout] test config::tasks::tests::no_events_error ... ok
[INFO] [stdout] test config::tasks::tests::is_event_key_works ... ok
[INFO] [stdout] test config::tasks::tests::parse_simple_task ... ok
[INFO] [stdout] test config::tasks::tests::single_phase_loop_explicit ... ok
[INFO] [stdout] test config::tasks::tests::single_phase_loop_infinite ... ok
[INFO] [stdout] test config::tasks::tests::phase_taskgroup ... ok
[INFO] [stdout] test config::tasks::tests::util_clamp_validation ... ok
[INFO] [stdout] test config::tasks::tests::taskgroup_parsing ... ok
[INFO] [stdout] test config::tasks::tests::parse_mem_iorun ... ok
[INFO] [stdout] test config::tests::dedup_escaped_quotes_in_keys ... ok
[INFO] [stdout] test config::tests::dedup_keys_with_existing_numeric_suffix ... ok
[INFO] [stdout] test config::tests::dedup_multiple_different_keys_duplicated ... ok
[INFO] [stdout] test config::tests::dedup_nested_independent_levels ... ok
[INFO] [stdout] test config::tests::dedup_preserves_no_dup_input ... ok
[INFO] [stdout] test config::tests::dedup_no_false_positives_in_string_values ... ok
[INFO] [stdout] test config::tests::dedup_deeply_nested ... ok
[INFO] [stdout] test config::tests::dedup_simple_duplicate_keys ... ok
[INFO] [stdout] test config::tests::dedup_multiple_nesting_levels ... ok
[INFO] [stdout] test config::tests::dedup_empty_object ... ok
[INFO] [stdout] test config::tests::parse_example4_suspend_resume ... ok
[INFO] [stdout] test config::tests::parse_example3_phases ... ok
[INFO] [stdout] test config::tests::parse_config_full_global ... ok
[INFO] [stdout] test config::tests::parse_example6_mem_io ... ok
[INFO] [stdout] test config::tests::parse_example2 ... ok
[INFO] [stdout] test config::tests::parse_example10_taskgroup ... ok
[INFO] [stdout] test config::tests::parse_example11_phase_taskgroups ... ok
[INFO] [stdout] test config::tests::parse_example1 ... ok
[INFO] [stdout] test config::tests::parse_config_with_resources ... ok
[INFO] [stdout] test config::tests::parse_example7_barriers ... ok
[INFO] [stdout] test config::tests::dedup_triple_duplicate ... ok
[INFO] [stdout] test config::tests::preprocess_combined ... ok
[INFO] [stdout] test config::tests::strip_comments_preserves_strings ... ok
[INFO] [stdout] test config::tests::preprocess_deduplicates_after_comment_and_comma_strip ... ok
[INFO] [stdout] test config::tests::strip_line_comments ... ok
[INFO] [stdout] test config::tests::parse_missing_tasks_error ... ok
[INFO] [stdout] test config::tests::strip_mixed_comments ... ok
[INFO] [stdout] test config::tests::strip_block_comments ... ok
[INFO] [stdout] test config::tests::strip_trailing_commas_object ... ok
[INFO] [stdout] test config::tests::strip_line_comments_preserves_strings ... ok
[INFO] [stdout] test config::tests::parse_example8_cpus ... ok
[INFO] [stdout] test config::tests::strip_multiline_comments ... ok
[INFO] [stdout] test config::tests::strip_trailing_commas_array ... ok
[INFO] [stdout] test config::tests::strip_line_comments_end_of_line ... ok
[INFO] [stdout] test conversions::tests::global_config_infinite_duration ... ok
[INFO] [stdout] test conversions::tests::global_config_to_app_options ... ok
[INFO] [stdout] test conversions::tests::global_config_ns_per_loop_calibration ... ok
[INFO] [stdout] test conversions::tests::format_cpu_list_works ... ok
[INFO] [stdout] test config::tests::parse_minimal_config ... ok
[INFO] [stdout] test conversions::tests::log_capacity_file ... ok
[INFO] [stdout] test conversions::tests::phase_config_to_phase_data ... ok
[INFO] [stdout] test config::tests::strip_trailing_commas_preserves_strings ... ok
[INFO] [stdout] test conversions::tests::sched_config_to_sched_data ... ok
[INFO] [stdout] test engine::calibration::tests::ldexp_matches_c_pattern ... ok
[INFO] [stdout] test config::tests::parse_example9_fork ... ok
[INFO] [stdout] test engine::calibration::tests::convergence_check ... ok
[INFO] [stdout] test conversions::tests::log_capacity_buffer ... ok
[INFO] [stdout] test conversions::tests::log_capacity_disabled ... ok
[INFO] [stdout] test engine::calibration::tests::convergence_zero_avg_not_converged ... ok
[INFO] [stdout] test engine::calibration::tests::ldexp_basic ... ok
[INFO] [stdout] test engine::calibration::tests::loadwait_zero_ns_per_loop ... ok
[INFO] [stdout] test engine::calibration::tests::ns_per_loop_exec_to_loops_zero ... ok
[INFO] [stdout] test engine::calibration::tests::ns_per_loop_ordering ... ok
[INFO] [stdout] test conversions::tests::event_config_to_event_data ... ok
[INFO] [stdout] test engine::events::tests::event_dispatch_sleep ... ok
[INFO] [stdout] test engine::calibration::tests::cpu_burn_mode_variants ... ok
[INFO] [stdout] test engine::calibration::tests::loadwait_small_duration ... ok
[INFO] [stdout] test engine::calibration::tests::ns_per_loop_exec_to_loops ... ok
[INFO] [stdout] test engine::calibration::tests::spin_wait_ns_zero_is_noop ... ok
[INFO] [stdout] test engine::calibration::tests::waste_cpu_cycles_runs_without_panic ... ok
[INFO] [stdout] test engine::events::tests::barrier_state_construction ... ok
[INFO] [stdout] test engine::events::tests::event_dispatch_dry_run_skips_work ... ok
[INFO] [stdout] test engine::events::tests::event_dispatch_run_precise_mode ... ok
[INFO] [stdout] test engine::events::tests::event_dispatch_run_updates_perf ... ok
[INFO] [stdout] test engine::events::tests::event_dispatch_runtime_precise_mode ... ok
[INFO] [stdout] test engine::calibration::tests::spin_wait_ns_short_duration ... ok
[INFO] [stdout] test engine::events::tests::ioload_writes_less_than_buf ... ok
[INFO] [stdout] test engine::events::tests::lock_delta_values ... ok
[INFO] [stdout] test engine::events::tests::memload_zeroes_bytes ... ok
[INFO] [stdout] test engine::events::tests::resource_handle_variants ... ok
[INFO] [stdout] test engine::events::tests::timer_state_default ... ok
[INFO] [stdout] test engine::scheduling::tests::build_cfs_attr_fields ... ok
[INFO] [stdout] test engine::scheduling::tests::build_deadline_attr_fields ... ok
[INFO] [stdout] test engine::events::tests::memload_zero_count ... ok
[INFO] [stdout] test engine::events::tests::event_dispatch_yield ... ok
[INFO] [stdout] test engine::scheduling::tests::build_uclamp_attr_none ... ok
[INFO] [stdout] test engine::scheduling::tests::deadline_sched_data_construction ... ok
[INFO] [stdout] test engine::events::tests::run_phase_stops_early_when_not_running ... ok
[INFO] [stdout] test engine::scheduling::tests::build_uclamp_attr_both ... ok
[INFO] [stdout] test engine::events::tests::ioload_zero_count ... ok
[INFO] [stdout] test engine::events::tests::memload_larger_than_buf ... ok
[INFO] [stdout] test engine::events::tests::ioload_writes_exact_count ... ok
[INFO] [stdout] test engine::scheduling::tests::build_uclamp_attr_min_only ... ok
[INFO] [stdout] test engine::tests::advance_phase_loop_single_phase ... ok
[INFO] [stdout] test engine::tests::make_thread_unique_name_forked ... ok
[INFO] [stdout] test engine::tests::set_thread_name_works ... ok
[INFO] [stdout] test engine::tests::build_timing_point_values ... ok
[INFO] [stdout] test engine::timing::tests::timing_buffer_flush ... ok
[INFO] [stdout] test engine::tests::make_thread_unique_name_normal ... ok
[INFO] [stdout] test engine::tests::set_thread_name_long_truncates ... ok
[INFO] [stdout] test engine::timing::tests::timing_buffer_no_wrap_order ... ok
[INFO] [stdout] test engine::scheduling::tests::sched_priority_negative_clamped ... ok
[INFO] [stdout] test engine::timing::tests::timing_buffer_push_and_len ... ok
[INFO] [stdout] test engine::timing::tests::timing_buffer_none_for_zero ... ok
[INFO] [stdout] test engine::timing::tests::timing_buffer_wrap_around ... ok
[INFO] [stdout] test engine::timing::tests::write_log_header_format ... ok
[INFO] [stdout] test engine::timing::tests::thread_gnuplot_creates_file ... ok
[INFO] [stdout] test engine::timing::tests::main_gnuplot_creates_files ... ok
[INFO] [stdout] test engine::scheduling::tests::sched_priority_rt_policies ... ok
[INFO] [stdout] test engine::tests::engine_state_construction ... ok
[INFO] [stdout] test engine::scheduling::tests::build_rt_attr_fields ... ok
[INFO] [stdout] test engine::scheduling::tests::sched_priority_non_rt_is_zero ... ok
[INFO] [stdout] test engine::scheduling::tests::set_thread_param_resolves_same_policy ... ok
[INFO] [stdout] test gnuplot::tests::aggregate_file_path_construction ... ok
[INFO] [stdout] test engine::tests::advance_phase_loop_multi_phase ... ok
[INFO] [stdout] test gnuplot::tests::main_gnuplot_creates_both_files ... ok
[INFO] [stdout] test gnuplot::tests::main_gnuplot_run_plot_contents ... ok
[INFO] [stdout] test syscalls::tests::sched_attr_default_has_correct_size ... ok
[INFO] [stdout] test engine::calibration::tests::spin_wait_ns_accuracy ... ok
[INFO] [stdout] test syscalls::tests::sched_attr_size_matches_kernel ... ok
[INFO] [stdout] test syscalls::tests::sched_deadline_constant ... ok
[INFO] [stdout] test syscalls::tests::sched_flags_bitwise_operations ... ok
[INFO] [stdout] test syscalls::tests::sched_flags_composites ... ok
[INFO] [stdout] test syscalls::tests::sched_flags_individual_bits ... ok
[INFO] [stdout] test syscalls::tests::sched_getattr_current_thread ... ok
[INFO] [stdout] test taskgroups::tests::alloc_and_find_taskgroup ... ok
[INFO] [stdout] test syscalls::tests::sched_flags_empty_and_all ... ok
[INFO] [stdout] test gnuplot::tests::thread_file_path_construction ... ok
[INFO] [stdout] test taskgroups::tests::alloc_respects_max_limit ... ok
[INFO] [stdout] test gnuplot::tests::thread_gnuplot_plot_line_references ... ok
[INFO] [stdout] test gnuplot::tests::thread_gnuplot_has_trailer ... ok
[INFO] [stdout] test taskgroups::tests::check_cpu_controller_disabled ... ok
[INFO] [stdout] test gnuplot::tests::thread_gnuplot_creates_plot_file ... ok
[INFO] [stdout] test gnuplot::tests::main_gnuplot_period_plot_contents ... ok
[INFO] [stdout] test taskgroups::tests::check_cpu_controller_present_and_enabled ... ok
[INFO] [stdout] test taskgroups::tests::cumulative_components ... ok
[INFO] [stdout] test taskgroups::tests::check_cpu_controller_missing ... ok
[INFO] [stdout] test taskgroups::tests::add_and_remove_cgroups_round_trip ... ok
[INFO] [stdout] test gnuplot::tests::main_gnuplot_three_threads ... ok
[INFO] [stdout] test gnuplot::tests::setup_thread_logging_creates_file ... ok
[INFO] [stdout] test taskgroups::tests::find_mount_point_cgroup_v1 ... ok
[INFO] [stdout] test gnuplot::tests::main_gnuplot_single_thread_no_trailing_comma ... ok
[INFO] [stdout] test taskgroups::tests::find_mount_point_not_found ... ok
[INFO] [stdout] test gnuplot::tests::thread_gnuplot_contains_expected_commands ... ok
[INFO] [stdout] test gnuplot::tests::setup_thread_logging_writable ... ok
[INFO] [stdout] test taskgroups::tests::cgroup_rmdir_none_offset_is_noop ... ok
[INFO] [stdout] test taskgroups::tests::find_mount_point_rejects_cpuset_only ... ok
[INFO] [stdout] test taskgroups::tests::find_nonexistent_returns_none ... ok
[INFO] [stdout] test taskgroups::tests::cgroup_mkdir_offset_tracks_first_created ... ok
[INFO] [stdout] test types::tests::ftrace_level_values_match_c ... ok
[INFO] [stdout] test types::tests::index_serde_roundtrip ... ok
[INFO] [stdout] test types::tests::log_data_default ... ok
[INFO] [stdout] test types::tests::index_display ... ok
[INFO] [stdout] test taskgroups::tests::taskgroup_name_normalises_slashes ... ok
[INFO] [stdout] test taskgroups::tests::taskgroup_name_rejects_empty_and_root ... ok
[INFO] [stdout] test types::tests::exit_code_values ... ok
[INFO] [stdout] test types::tests::ftrace_level_combine ... ok
[INFO] [stdout] test types::tests::parse_enum_error_display ... ok
[INFO] [stdout] test types::tests::resource_type_from_str_invalid ... ok
[INFO] [stdout] test types::tests::sched_flags_combinations ... ok
[INFO] [stdout] test types::tests::resource_type_serde_json ... ok
[INFO] [stdout] test types::tests::sched_flags_values_match_c ... ok
[INFO] [stdout] test types::tests::sched_attr_new_has_correct_size ... ok
[INFO] [stdout] test types::tests::scheduling_policy_from_str_invalid ... ok
[INFO] [stdout] test types::tests::resource_type_display_roundtrip ... ok
[INFO] [stdout] test taskgroups::tests::cgroup_rmdir_cleans_up_created_dirs ... ok
[INFO] [stdout] test types::tests::scheduling_policy_kernel_ids ... ok
[INFO] [stdout] test types::tests::scheduling_policy_serde_json ... ok
[INFO] [stdout] test utils::tests::ftrace_parse_multiple ... ok
[INFO] [stdout] test utils::tests::duration_timespec_roundtrip ... ok
[INFO] [stdout] test utils::tests::ftrace_parse_case_insensitive ... ok
[INFO] [stdout] test utils::tests::ftrace_parse_none_resets ... ok
[INFO] [stdout] test utils::tests::ftrace_parse_empty ... ok
[INFO] [stdout] test utils::tests::ftrace_parse_single ... ok
[INFO] [stdout] test taskgroups::tests::cgroup_mkdir_constructs_correct_paths ... ok
[INFO] [stdout] test utils::tests::ftrace_parse_unknown_returns_error ... ok
[INFO] [stdout] test utils::tests::ftrace_parse_whitespace_tolerance ... ok
[INFO] [stdout] test types::tests::timing_point_default ... ok
[INFO] [stdout] test types::tests::scheduling_policy_display_roundtrip ... ok
[INFO] [stdout] test taskgroups::tests::cgroup_mkdir_all_exist_returns_none ... ok
[INFO] [stdout] test utils::tests::ftrace_write_fmt_to_vec ... ok
[INFO] [stdout] test utils::tests::gettid_returns_positive ... ok
[INFO] [stdout] test utils::tests::timespec_diff_nsec_basic ... ok
[INFO] [stdout] test utils::tests::msec_to_timespec_basic ... ok
[INFO] [stdout] test utils::tests::log_timing_format ... ok
[INFO] [stdout] test utils::tests::timespec_diff_nsec_negative ... ok
[INFO] [stdout] test utils::tests::timespec_to_duration_negative_returns_none ... ok
[INFO] [stdout] test utils::tests::timespec_diff_nsec_borrow ... ok
[INFO] [stdout] test utils::tests::timespec_to_nsec_basic ... ok
[INFO] [stdout] test utils::tests::timespec_to_usec_basic ... ok
[INFO] [stdout] test utils::tests::ftrace_write_to_vec ... ok
[INFO] [stdout] test utils::tests::timespec_to_usec_rounding ... ok
[INFO] [stdout] test utils::tests::usec_to_timespec_basic ... ok
[INFO] [stdout] test utils::tests::usec_to_timespec_zero ... ok
[INFO] [stdout] test engine::calibration::tests::loadwait_large_duration_splits ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 264 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 38.14s
[INFO] [stdout] 
[INFO] [stderr]      Running unittests src/main.rs (/opt/rustwide/target/debug/deps/rtapp-e65bdbe0907d74f9)
[INFO] [stdout] 
[INFO] [stdout] running 2 tests
[INFO] [stdout] test tests::app_options_from_config ... ok
[INFO] [stdout] test tests::parse_minimal_config_succeeds ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 2 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/compat_format.rs (/opt/rustwide/target/debug/deps/compat_format-f2ed114f113a833a)
[INFO] [stdout] 
[INFO] [stdout] running 36 tests
[INFO] [stdout] test config_parsing::example1_structure_matches_c ... ok
[INFO] [stdout] test config_parsing::example3_phases_match_c ... ok
[INFO] [stdout] test config_parsing::example7_barriers_match_c ... ok
[INFO] [stdout] test config_parsing::example9_fork_match_c ... ok
[INFO] [stdout] test cross_format::gnuplot_column_refs_within_log_range ... ok
[INFO] [stdout] test cross_format::header_and_data_column_count_match ... ok
[INFO] [stdout] test ftrace_compat::write_produces_exact_bytes ... ok
[INFO] [stdout] test ftrace_compat::write_fmt_stats_format ... ok
[INFO] [stdout] test ftrace_compat::level_bits_match_c_defines ... ok
[INFO] [stdout] test ftrace_compat::write_fmt_event_format ... ok
[INFO] [stdout] test config_parsing::local_tutorial_count_matches_c_orig ... ok
[INFO] [stdout] test gnuplot_compat::thread_script_references_correct_log ... ok
[INFO] [stdout] test gnuplot_compat::thread_script_eps_output ... ok
[INFO] [stdout] test gnuplot_compat::thread_script_valid_syntax ... ok
[INFO] [stdout] test gnuplot_compat::thread_script_y2_axis_for_load ... ok
[INFO] [stdout] test log_format::column_widths_match_c_format ... ok
[INFO] [stdout] test log_format::ends_with_single_newline ... ok
[INFO] [stdout] test log_format::field_order_matches_c ... ok
[INFO] [stdout] test log_format::produces_exactly_11_columns ... ok
[INFO] [stdout] test log_format::large_values_not_truncated ... ok
[INFO] [stdout] test log_format::negative_slack_preserves_sign ... ok
[INFO] [stdout] test log_header::ends_with_newline ... ok
[INFO] [stdout] test gnuplot_compat::main_scripts_no_trailing_comma_single ... ok
[INFO] [stdout] test gnuplot_compat::main_scripts_legends_include_policy ... ok
[INFO] [stdout] test log_header::has_11_columns ... ok
[INFO] [stdout] test ftrace_compat::write_fmt_attrs_format ... ok
[INFO] [stdout] test log_header::starts_with_comment_marker ... ok
[INFO] [stdout] test gnuplot_compat::main_scripts_use_usec_x_axis ... ok
[INFO] [stdout] test log_format::zero_values_all_zero ... ok
[INFO] [stdout] test gnuplot_compat::main_scripts_valid_syntax ... ok
[INFO] [stdout] test ftrace_compat::write_fmt_loop_format ... ok
[INFO] [stdout] test config_parsing::workgen_only_configs_produce_parse_error ... ok
[INFO] [stdout] test log_header::contains_all_column_names ... ok
[INFO] [stdout] test gnuplot_compat::thread_script_correct_columns ... ok
[INFO] [stdout] test config_parsing::parse_all_tutorial_examples ... ok
[INFO] [stdout] test config_parsing::parse_standard_top_level_examples ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 36 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.03s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/integration_tests.rs (/opt/rustwide/target/debug/deps/integration_tests-9f2997680585fd57)
[INFO] [stdout] 
[INFO] [stdout] running 60 tests
[INFO] [stdout] test all_fixture_files_exist ... ok
[INFO] [stdout] test cross_module_complex_workload_config ... ok
[INFO] [stdout] test cross_module_affinity_with_config ... ok
[INFO] [stdout] test cross_module_calibration_variants ... ok
[INFO] [stdout] test cross_module_affinity_phase_override ... ok
[INFO] [stdout] test cross_module_deadline_params_from_config ... ok
[INFO] [stdout] test cross_module_config_to_types ... ok
[INFO] [stdout] test cross_module_ftrace_levels_from_config ... ok
[INFO] [stdout] test cross_module_parse_and_verify_event_ordering ... ok
[INFO] [stdout] test cross_module_resource_auto_creation ... ok
[INFO] [stdout] test cross_module_global_defaults_to_types ... ok
[INFO] [stdout] test cross_module_log_size_variants ... ok
[INFO] [stdout] test cross_module_multiple_resource_types_in_single_task ... ok
[INFO] [stdout] test cross_module_calibration_precise ... ok
[INFO] [stdout] test edge_case_comments_and_trailing_commas ... ok
[INFO] [stdout] test edge_case_invalid_json_not_an_object ... ok
[INFO] [stdout] test edge_case_invalid_policy_in_task ... ok
[INFO] [stdout] test edge_case_missing_optional_global ... ok
[INFO] [stdout] test cross_module_resource_data_construction ... ok
[INFO] [stdout] test edge_case_missing_optional_resources ... ok
[INFO] [stdout] test cross_module_scheduling_params_from_config ... ok
[INFO] [stdout] test edge_case_negative_duration ... ok
[INFO] [stdout] test cross_module_uclamp_params ... ok
[INFO] [stdout] test edge_case_all_scheduling_policies ... ok
[INFO] [stdout] test edge_case_empty_phases_object ... ok
[INFO] [stdout] test edge_case_many_phases ... ok
[INFO] [stdout] test edge_case_invalid_policy_in_global ... ok
[INFO] [stdout] test edge_case_invalid_json_missing_brace ... ok
[INFO] [stdout] test edge_case_invalid_json_empty_string ... ok
[INFO] [stdout] test edge_case_invalid_json_plain_text ... ok
[INFO] [stdout] test edge_case_missing_tasks_section ... ok
[INFO] [stdout] test edge_case_many_tasks ... ok
[INFO] [stdout] test edge_case_nonexistent_file ... ok
[INFO] [stdout] test edge_case_null_global ... ok
[INFO] [stdout] test edge_case_very_large_run_value ... ok
[INFO] [stdout] test parse_custom_slice ... ok
[INFO] [stdout] test edge_case_zero_duration_run ... ok
[INFO] [stdout] test parse_spreading_tasks ... ok
[INFO] [stdout] test parse_mp3_long ... ok
[INFO] [stdout] test parse_tutorial_example10 ... ok
[INFO] [stdout] test parse_browser_long ... ok
[INFO] [stdout] test parse_tutorial_example1 ... ok
[INFO] [stdout] test edge_case_zero_duration_sleep ... ok
[INFO] [stdout] test parse_tutorial_example2 ... ok
[INFO] [stdout] test parse_tutorial_example11 ... ok
[INFO] [stdout] test parse_template ... ok
[INFO] [stdout] test edge_case_null_resources ... ok
[INFO] [stdout] test edge_case_very_large_duration ... ok
[INFO] [stdout] test edge_case_string_containing_comment_syntax ... ok
[INFO] [stdout] test parse_browser_short ... ok
[INFO] [stdout] test parse_tutorial_example6 ... ok
[INFO] [stdout] test parse_tutorial_example3 ... ok
[INFO] [stdout] test parse_tutorial_example8 ... ok
[INFO] [stdout] test parse_tutorial_example5 ... ok
[INFO] [stdout] test parse_tutorial_example9 ... ok
[INFO] [stdout] test parse_video_long_is_invalid_json ... ok
[INFO] [stdout] test parse_tutorial_example7 ... ok
[INFO] [stdout] test parse_video_short_is_invalid_json ... ok
[INFO] [stdout] test parse_mp3_short ... ok
[INFO] [stdout] test parse_tutorial_example4 ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 60 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.02s
[INFO] [stdout] 
[INFO] [stderr]    Doc-tests rt_app_rs
[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" "f276decc382810e66d63e1e1ab920fd538e3172f5fe886019424b7de07f27f8b", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "f276decc382810e66d63e1e1ab920fd538e3172f5fe886019424b7de07f27f8b", kill_on_drop: false }`
[INFO] [stdout] f276decc382810e66d63e1e1ab920fd538e3172f5fe886019424b7de07f27f8b