[INFO] fetching crate mac-headroom 0.8.0...
[INFO] testing mac-headroom-0.8.0 against 1.99.0-beta.8+cargoflags=--release for beta-release-1.100-2
[INFO] extracting crate mac-headroom 0.8.0 into /workspace/builds/worker-3-tc1/source
[INFO] started tweaking crates.io crate mac-headroom 0.8.0
[INFO] finished tweaking crates.io crate mac-headroom 0.8.0
[INFO] tweaked toml for crates.io crate mac-headroom 0.8.0 written to /workspace/builds/worker-3-tc1/source/Cargo.toml
[INFO] validating manifest of crates.io crate mac-headroom 0.8.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 mac-headroom 0.8.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] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-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:77db811e55d90add9212f6832c23229e3de5f1c1f9905e8cb07319ea15df73ac" "sleep" "infinity", kill_on_drop: false }`
[INFO] [stdout] 79f62e89642f64367b59758f8f72f45295419e9e3262d5aeff26a8511aa1fa3b
[INFO] running `Command { std: "docker" "start" "79f62e89642f64367b59758f8f72f45295419e9e3262d5aeff26a8511aa1fa3b", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "79f62e89642f64367b59758f8f72f45295419e9e3262d5aeff26a8511aa1fa3b", 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" "79f62e89642f64367b59758f8f72f45295419e9e3262d5aeff26a8511aa1fa3b" "/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" "79f62e89642f64367b59758f8f72f45295419e9e3262d5aeff26a8511aa1fa3b", 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" "79f62e89642f64367b59758f8f72f45295419e9e3262d5aeff26a8511aa1fa3b" "/opt/rustwide/cargo-home/bin/cargo" "+1.99.0-beta.8" "build" "--frozen" "--message-format=json" "--release", kill_on_drop: false }`
[INFO] [stderr]    Compiling syn v3.0.5
[INFO] [stderr]    Compiling clap_lex v1.1.1
[INFO] [stderr]    Compiling winnow v1.0.4
[INFO] [stderr]    Compiling toml_datetime v0.7.5+spec-1.1.0
[INFO] [stderr]    Compiling serde_spanned v1.1.1
[INFO] [stderr]    Compiling winnow v0.7.15
[INFO] [stderr]    Compiling toml_writer v1.1.2+spec-1.1.0
[INFO] [stderr]    Compiling same-file v1.0.6
[INFO] [stderr]    Compiling serde_json v1.0.151
[INFO] [stderr]    Compiling clap_builder v4.6.7
[INFO] [stderr]    Compiling walkdir v2.5.0
[INFO] [stderr]    Compiling glob v0.3.4
[INFO] [stderr]    Compiling toml_parser v1.1.3+spec-1.1.0
[INFO] [stderr]    Compiling toml v0.9.12+spec-1.1.0
[INFO] [stderr]    Compiling clap_derive v4.6.7
[INFO] [stderr]    Compiling serde_derive v1.0.229
[INFO] [stderr]    Compiling clap v4.6.7
[INFO] [stderr]    Compiling serde v1.0.229
[INFO] [stderr]    Compiling mac-headroom v0.8.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `release` profile [optimized] target(s) in 1m 01s
[INFO] running `Command { std: "docker" "inspect" "79f62e89642f64367b59758f8f72f45295419e9e3262d5aeff26a8511aa1fa3b", 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" "79f62e89642f64367b59758f8f72f45295419e9e3262d5aeff26a8511aa1fa3b" "/opt/rustwide/cargo-home/bin/cargo" "+1.99.0-beta.8" "test" "--frozen" "--no-run" "--message-format=json" "--release", kill_on_drop: false }`
[INFO] [stderr]    Compiling mac-headroom v0.8.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `release` profile [optimized] target(s) in 14.44s
[INFO] running `Command { std: "docker" "inspect" "79f62e89642f64367b59758f8f72f45295419e9e3262d5aeff26a8511aa1fa3b", 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" "79f62e89642f64367b59758f8f72f45295419e9e3262d5aeff26a8511aa1fa3b" "/opt/rustwide/cargo-home/bin/cargo" "+1.99.0-beta.8" "test" "--frozen" "--release", kill_on_drop: false }`
[INFO] [stderr]     Finished `release` profile [optimized] target(s) in 0.08s
[INFO] [stderr]      Running unittests src/main.rs (/opt/rustwide/target/release/deps/mac_headroom-631c43e8fc1b65e7)
[INFO] [stdout] 
[INFO] [stdout] running 62 tests
[INFO] [stdout] test apps::tests::only_a_verdict_of_unused_gets_a_command ... ok
[INFO] [stdout] test apps::tests::the_trash_command_survives_quotes_in_the_name ... ok
[INFO] [stdout] test apps::tests::an_app_with_no_record_needs_to_be_large_to_be_worth_a_row ... ok
[INFO] [stdout] test cleaners::tests::name_before_version_cuts_at_the_version ... ok
[INFO] [stdout] test cleaners::tests::builtins_validate ... ok
[INFO] [stdout] test cleaners::tests::group_by_needs_keep_newest ... ok
[INFO] [stdout] test apps::tests::months_are_coarse_and_never_under_the_cutoff ... ok
[INFO] [stdout] test apps::tests::reads_the_date_mdls_prints ... ok
[INFO] [stdout] test cleaners::tests::the_summary_counts_what_went_not_what_matched ... ok
[INFO] [stdout] test config::tests::example_parses_and_is_empty ... ok
[INFO] [stdout] test config::tests::example_entries_validate_when_enabled ... ok
[INFO] [stdout] test cleaners::tests::picks_the_line_that_explains_a_failure ... ok
[INFO] [stdout] test cleaners::tests::rejects_broad_or_odd_paths ... ok
[INFO] [stdout] test config::tests::missing_scan_root_is_a_note_not_a_problem ... ok
[INFO] [stdout] test config::tests::rejects_clash_and_duplicate ... ok
[INFO] [stdout] test diag::tests::a_reading_is_due_only_once_the_gap_has_passed ... ok
[INFO] [stdout] test diag::tests::a_young_record_compares_against_its_first_reading ... ok
[INFO] [stdout] test diag::tests::baseline_is_chosen_by_age_not_position ... ok
[INFO] [stdout] test diag::tests::history_skips_lines_that_do_not_parse ... ok
[INFO] [stdout] test diag::tests::parses_diskutil_bytes ... ok
[INFO] [stdout] test diag::tests::trend_has_no_full_date_when_free_space_grew_or_barely_moves ... ok
[INFO] [stdout] test diag::tests::trend_keeps_a_week_and_says_how_free_space_moved ... ok
[INFO] [stdout] test diag::tests::trend_says_nothing_until_the_record_spans_a_day ... ok
[INFO] [stdout] test findings::tests::a_folder_is_named_from_its_repository ... ok
[INFO] [stdout] test findings::tests::a_repository_too_far_up_is_not_looked_for ... ok
[INFO] [stdout] test findings::tests::a_working_copy_is_named_from_the_repository_that_owns_it ... ok
[INFO] [stdout] test cleaners::tests::a_file_a_running_program_has_open_is_kept ... FAILED
[INFO] [stdout] test findings::tests::a_lock_file_is_found_at_the_top_of_the_repository_and_no_higher ... ok
[INFO] [stdout] test findings::tests::docker_advice_names_what_is_unused_and_leaves_volumes_out_of_it ... ok
[INFO] [stdout] test findings::tests::a_dependency_folder_is_safe_only_with_a_lock_file ... ok
[INFO] [stdout] test findings::tests::a_git_folder_or_file_marks_a_repository ... ok
[INFO] [stdout] test findings::tests::docker_advice_says_so_when_there_is_nothing_to_prune ... ok
[INFO] [stdout] test findings::tests::docker_sizes_are_decimal_and_carry_their_unit ... ok
[INFO] [stdout] test findings::tests::every_action_says_why_it_is_safe ... ok
[INFO] [stdout] test findings::tests::every_action_names_an_opt_in_built_in ... ok
[INFO] [stdout] test growth::tests::a_folder_that_became_readable_is_not_new ... ok
[INFO] [stdout] test growth::tests::a_folder_that_became_unreadable_is_not_gone ... ok
[INFO] [stdout] test findings::tests::outside_a_repository_generic_parents_are_walked_past ... ok
[INFO] [stdout] test growth::tests::scan_accumulates_into_ancestors ... ok
[INFO] [stdout] test growth::tests::scans_without_the_record_compare_the_old_way ... ok
[INFO] [stdout] test cleaners::tests::keep_newest_and_older_than ... ok
[INFO] [stdout] test findings::tests::an_action_that_ran_stops_being_listed_at_its_old_size ... ok
[INFO] [stdout] test report::tests::new_lines_carry_measurement_and_reason ... ok
[INFO] [stdout] test report::tests::old_command_lines_are_unmeasured_not_zero ... ok
[INFO] [stdout] test findings::tests::a_build_folder_is_recognised_by_more_than_its_name ... ok
[INFO] [stdout] test schedule::tests::fallbacks_go_after_the_users_own_path ... ok
[INFO] [stdout] test schedule::tests::newest_node_is_chosen_by_number_not_name ... ok
[INFO] [stdout] test schedule::tests::plist_round_trips ... ok
[INFO] [stdout] test schedule::tests::weekday_names ... ok
[INFO] [stdout] test findings::tests::only_the_largest_folders_are_listed_and_only_safe_ones_get_a_command ... ok
[INFO] [stdout] test util::tests::human_carries_instead_of_printing_a_thousand ... ok
[INFO] [stdout] test util::tests::human_units_are_decimal_like_finder ... ok
[INFO] [stdout] test cleaners::tests::group_by_keeps_the_newest_of_each_thing_not_of_the_folder ... ok
[INFO] [stdout] test util::tests::signed_deltas ... ok
[INFO] [stdout] test util::tests::protected_folders_are_only_skipped_when_asked ... ok
[INFO] [stdout] test util::tests::url_encoding_keeps_only_unreserved_characters ... ok
[INFO] [stdout] test schedule::tests::prefers_a_stable_name_over_a_versioned_one ... ok
[INFO] [stdout] test orphans::tests::refuses_when_no_column_could_hold_a_path ... ok
[INFO] [stdout] test orphans::tests::refuses_when_the_database_says_nothing ... ok
[INFO] [stdout] test orphans::tests::keeps_referenced_and_protected_files ... ok
[INFO] [stdout] test findings::tests::a_worktree_is_safe_only_when_git_says_nothing_would_be_lost ... ok
[INFO] [stdout] test util::tests::a_command_that_never_answers_is_given_up_on ... ok
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout] 
[INFO] [stdout] ---- cleaners::tests::a_file_a_running_program_has_open_is_kept stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'cleaners::tests::a_file_a_running_program_has_open_is_kept' (519) panicked at src/cleaners.rs:1084:9:
[INFO] [stdout] assertion `left == right` failed
[INFO] [stdout]   left: ["idle", "in-use"]
[INFO] [stdout]  right: ["in-use"]
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x6004a7cb0421 - <<std[70759c8f55707aa4]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[df12db4294e9bfd3]::fmt::Display>::fmt
[INFO] [stdout]    1:     0x6004a7cc723a - core[df12db4294e9bfd3]::fmt::write
[INFO] [stdout]    2:     0x6004a7cb4c6c - <alloc[2182bb758b4b3781]::vec::Vec<u8> as core[df12db4294e9bfd3]::io::write::Write>::write_fmt
[INFO] [stdout]    3:     0x6004a7c88a06 - std[70759c8f55707aa4]::panicking::default_hook::{closure#0}
[INFO] [stdout]    4:     0x6004a7ca6bd9 - std[70759c8f55707aa4]::panicking::default_hook
[INFO] [stdout]    5:     0x6004a7c40030 - test[9d35eded1c95d3be]::test_main_inner::<test[9d35eded1c95d3be]::test_main_static::{closure#0}>::{closure#0}
[INFO] [stdout]    6:     0x6004a7ca6d92 - std[70759c8f55707aa4]::panicking::panic_with_hook
[INFO] [stdout]    7:     0x6004a7c88ab2 - std[70759c8f55707aa4]::panicking::panic_handler::{closure#0}
[INFO] [stdout]    8:     0x6004a7c81489 - std[70759c8f55707aa4]::sys::backtrace::__rust_end_short_backtrace::<std[70759c8f55707aa4]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]    9:     0x6004a7c8979d - __rustc[8fa7c3cbc660c2b3]::rust_begin_unwind
[INFO] [stdout]   10:     0x6004a7cc79ac - core[df12db4294e9bfd3]::panicking::panic_fmt
[INFO] [stdout]   11:     0x6004a7cc78c3 - core[df12db4294e9bfd3]::panicking::assert_failed_inner
[INFO] [stdout]   12:     0x6004a7c00641 - core[df12db4294e9bfd3]::panicking::assert_failed::<alloc[2182bb758b4b3781]::vec::Vec<alloc[2182bb758b4b3781]::string::String>, alloc[2182bb758b4b3781]::vec::Vec<&str>>
[INFO] [stdout]   13:     0x6004a7bb813c - mac_headroom[9b22af75f822b9cb]::cleaners::tests::a_file_a_running_program_has_open_is_kept
[INFO] [stdout]   14:     0x6004a7bdb949 - <mac_headroom[9b22af75f822b9cb]::cleaners::tests::a_file_a_running_program_has_open_is_kept::{closure#0} as core[df12db4294e9bfd3]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   15:     0x6004a7c3331b - test[9d35eded1c95d3be]::__rust_begin_short_backtrace::<core[df12db4294e9bfd3]::result::Result<(), alloc[2182bb758b4b3781]::string::String>, fn() -> core[df12db4294e9bfd3]::result::Result<(), alloc[2182bb758b4b3781]::string::String>>
[INFO] [stdout]   16:     0x6004a7c40985 - test[9d35eded1c95d3be]::run_test::{closure#0}
[INFO] [stdout]   17:     0x6004a7c3a244 - std[70759c8f55707aa4]::sys::backtrace::__rust_begin_short_backtrace::<test[9d35eded1c95d3be]::run_test::{closure#1}, ()>
[INFO] [stdout]   18:     0x6004a7c43ae2 - <std[70759c8f55707aa4]::thread::lifecycle::spawn_unchecked<test[9d35eded1c95d3be]::run_test::{closure#1}, ()>::{closure#1} as core[df12db4294e9bfd3]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]   19:     0x6004a7caf6d9 - <std[70759c8f55707aa4]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   20:     0x72bb0fb7fdfa - <unknown>
[INFO] [stdout]   21:     0x72bb0fc133d4 - clone
[INFO] [stdout]   22:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout]     cleaners::tests::a_file_a_running_program_has_open_is_kept
[INFO] [stdout] 
[INFO] [stdout] test result: FAILED. 61 passed; 1 failed; 0 ignored; 0 measured; 0 filtered out; finished in 1.18s
[INFO] [stdout] 
[INFO] [stderr] error: test failed, to rerun pass `--bin mac-headroom`
[INFO] running `Command { std: "docker" "inspect" "79f62e89642f64367b59758f8f72f45295419e9e3262d5aeff26a8511aa1fa3b", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "79f62e89642f64367b59758f8f72f45295419e9e3262d5aeff26a8511aa1fa3b", kill_on_drop: false }`
[INFO] [stdout] 79f62e89642f64367b59758f8f72f45295419e9e3262d5aeff26a8511aa1fa3b
