[INFO] cloning repository https://github.com/franzos/shelf
[INFO] running `Command { std: "git" "-c" "credential.helper=" "-c" "credential.helper=/workspace/cargo-home/bin/git-credential-null" "clone" "--bare" "https://github.com/franzos/shelf" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Ffranzos%2Fshelf", kill_on_drop: false }`
[INFO] [stderr] Cloning into bare repository '/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Ffranzos%2Fshelf'...
[INFO] running `Command { std: "git" "rev-parse" "HEAD", kill_on_drop: false }`
[INFO] [stdout] f0be74794ebff97c5a91ab85517768f216e15e62
[INFO] testing franzos/shelf against 1.98.0-beta.1 for beta-1.98-1
[INFO] running `Command { std: "git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Ffranzos%2Fshelf" "/workspace/builds/worker-0-tc2/source", kill_on_drop: false }`
[INFO] [stderr] Cloning into '/workspace/builds/worker-0-tc2/source'...
[INFO] [stderr] done.
[INFO] started tweaking git repo https://github.com/franzos/shelf
[INFO] finished tweaking git repo https://github.com/franzos/shelf
[INFO] tweaked toml for git repo https://github.com/franzos/shelf written to /workspace/builds/worker-0-tc2/source/Cargo.toml
[INFO] validating manifest of git repo https://github.com/franzos/shelf on toolchain 1.98.0-beta.1
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+1.98.0-beta.1" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate git repo https://github.com/franzos/shelf 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.98.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:3d5ced03c013a94a2f102a4510f48a6e9184255caf5fd8244f58017bde7f5210" "sleep" "infinity", kill_on_drop: false }`
[INFO] [stdout] a737c6d128ddaad429e76896dee7ddb789477da3548b6b20b694400f80211f24
[INFO] running `Command { std: "docker" "start" "a737c6d128ddaad429e76896dee7ddb789477da3548b6b20b694400f80211f24", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "a737c6d128ddaad429e76896dee7ddb789477da3548b6b20b694400f80211f24", 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" "a737c6d128ddaad429e76896dee7ddb789477da3548b6b20b694400f80211f24" "/opt/rustwide/cargo-home/bin/cargo" "+1.98.0-beta.1" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "a737c6d128ddaad429e76896dee7ddb789477da3548b6b20b694400f80211f24", 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" "a737c6d128ddaad429e76896dee7ddb789477da3548b6b20b694400f80211f24" "/opt/rustwide/cargo-home/bin/cargo" "+1.98.0-beta.1" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stderr]    Compiling serde_core v1.0.228
[INFO] [stderr]    Compiling crossbeam-utils v0.8.21
[INFO] [stderr]    Compiling libc v0.2.186
[INFO] [stderr]    Compiling typenum v1.20.0
[INFO] [stderr]    Compiling once_cell v1.21.4
[INFO] [stderr]    Compiling regex-syntax v0.8.10
[INFO] [stderr]    Compiling log v0.4.29
[INFO] [stderr]    Compiling foldhash v0.2.0
[INFO] [stderr]    Compiling aho-corasick v1.1.4
[INFO] [stderr]    Compiling libsqlite3-sys v0.37.0
[INFO] [stderr]    Compiling syn v2.0.117
[INFO] [stderr]    Compiling portable-atomic v1.13.1
[INFO] [stderr]    Compiling rayon-core v1.13.0
[INFO] [stderr]    Compiling anyhow v1.0.102
[INFO] [stderr]    Compiling unicode-width v0.2.2
[INFO] [stderr]    Compiling pin-project-lite v0.2.17
[INFO] [stderr]    Compiling const-oid v0.10.2
[INFO] [stderr]    Compiling hashbrown v0.16.1
[INFO] [stderr]    Compiling winnow v1.0.2
[INFO] [stderr]    Compiling rusqlite_migration v2.5.0
[INFO] [stderr]    Compiling tracing-core v0.1.36
[INFO] [stderr]    Compiling lazy_static v1.5.0
[INFO] [stderr]    Compiling sharded-slab v0.1.7
[INFO] [stderr]    Compiling bstr v1.12.1
[INFO] [stderr]    Compiling thread_local v1.1.9
[INFO] [stderr]    Compiling cpufeatures v0.3.0
[INFO] [stderr]    Compiling crossbeam-epoch v0.9.18
[INFO] [stderr]    Compiling tracing-log v0.2.0
[INFO] [stderr]    Compiling unit-prefix v0.5.2
[INFO] [stderr]    Compiling nu-ansi-term v0.50.3
[INFO] [stderr]    Compiling either v1.15.0
[INFO] [stderr]    Compiling crossbeam-deque v0.8.6
[INFO] [stderr]    Compiling toml_parser v1.1.2+spec-1.1.0
[INFO] [stderr]    Compiling same-file v1.0.6
[INFO] [stderr]    Compiling mutate_once v0.1.2
[INFO] [stderr]    Compiling toml_writer v1.1.1+spec-1.1.0
[INFO] [stderr]    Compiling hashlink v0.11.0
[INFO] [stderr]    Compiling walkdir v2.5.0
[INFO] [stderr]    Compiling kamadak-exif v0.6.1
[INFO] [stderr]    Compiling hybrid-array v0.4.12
[INFO] [stderr]    Compiling console v0.16.3
[INFO] [stderr]    Compiling filetime v0.2.28
[INFO] [stderr]    Compiling crypto-common v0.2.1
[INFO] [stderr]    Compiling block-buffer v0.12.0
[INFO] [stderr]    Compiling rayon v1.12.0
[INFO] [stderr]    Compiling regex-automata v0.4.14
[INFO] [stderr]    Compiling indicatif v0.18.4
[INFO] [stderr]    Compiling digest v0.11.3
[INFO] [stderr]    Compiling sha2 v0.11.0
[INFO] [stderr]    Compiling toml_datetime v1.1.1+spec-1.1.0
[INFO] [stderr]    Compiling serde_spanned v1.1.1
[INFO] [stderr]    Compiling toml v1.1.2+spec-1.1.0
[INFO] [stderr]    Compiling tracing-attributes v0.1.31
[INFO] [stderr]    Compiling serde_derive v1.0.228
[INFO] [stderr]    Compiling thiserror-impl v2.0.18
[INFO] [stderr]    Compiling clap_derive v4.6.1
[INFO] [stderr]    Compiling tracing v0.1.44
[INFO] [stderr]    Compiling matchers v0.2.0
[INFO] [stderr]    Compiling globset v0.4.18
[INFO] [stderr]    Compiling tracing-subscriber v0.3.23
[INFO] [stderr]    Compiling thiserror v2.0.18
[INFO] [stderr]    Compiling clap v4.6.1
[INFO] [stderr]    Compiling serde v1.0.228
[INFO] [stderr]    Compiling chrono v0.4.44
[INFO] [stderr]    Compiling rusqlite v0.39.0
[INFO] [stderr]    Compiling shelf v0.1.2 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 44.80s
[INFO] running `Command { std: "docker" "inspect" "a737c6d128ddaad429e76896dee7ddb789477da3548b6b20b694400f80211f24", 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" "a737c6d128ddaad429e76896dee7ddb789477da3548b6b20b694400f80211f24" "/opt/rustwide/cargo-home/bin/cargo" "+1.98.0-beta.1" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stderr]    Compiling memchr v2.8.0
[INFO] [stderr]    Compiling regex-syntax v0.8.10
[INFO] [stderr]    Compiling zerocopy v0.8.48
[INFO] [stderr]    Compiling num-traits v0.2.19
[INFO] [stderr]    Compiling serde v1.0.228
[INFO] [stderr]    Compiling zerocopy-derive v0.8.48
[INFO] [stderr]    Compiling either v1.15.0
[INFO] [stderr]    Compiling getrandom v0.4.2
[INFO] [stderr]    Compiling getrandom v0.3.4
[INFO] [stderr]    Compiling predicates-core v1.0.10
[INFO] [stderr]    Compiling rusqlite v0.39.0
[INFO] [stderr]    Compiling wait-timeout v0.2.1
[INFO] [stderr]    Compiling alloca v0.4.0
[INFO] [stderr]    Compiling normalize-line-endings v0.3.0
[INFO] [stderr]    Compiling plotters-backend v0.3.7
[INFO] [stderr]    Compiling ciborium-io v0.2.2
[INFO] [stderr]    Compiling termtree v0.5.1
[INFO] [stderr]    Compiling difflib v0.4.0
[INFO] [stderr]    Compiling rand_core v0.9.5
[INFO] [stderr]    Compiling doc-comment v0.3.4
[INFO] [stderr]    Compiling tempfile v3.27.0
[INFO] [stderr]    Compiling rayon v1.12.0
[INFO] [stderr]    Compiling itertools v0.13.0
[INFO] [stderr]    Compiling aho-corasick v1.1.4
[INFO] [stderr]    Compiling plotters-svg v0.3.7
[INFO] [stderr]    Compiling serde_json v1.0.149
[INFO] [stderr]    Compiling predicates-tree v1.0.13
[INFO] [stderr]    Compiling assert_cmd v2.2.2
[INFO] [stderr]    Compiling bit-vec v0.8.0
[INFO] [stderr]    Compiling fnv v1.0.7
[INFO] [stderr]    Compiling cast v0.3.0
[INFO] [stderr]    Compiling quick-error v1.2.3
[INFO] [stderr]    Compiling rand v0.9.4
[INFO] [stderr]    Compiling rand_xorshift v0.4.0
[INFO] [stderr]    Compiling bit-set v0.8.0
[INFO] [stderr]    Compiling page_size v0.6.0
[INFO] [stderr]    Compiling rusty-fork v0.3.1
[INFO] [stderr]    Compiling anes v0.1.6
[INFO] [stderr]    Compiling oorandom v11.1.5
[INFO] [stderr]    Compiling similar v2.7.0
[INFO] [stderr]    Compiling unarray v0.1.4
[INFO] [stderr]    Compiling rusqlite_migration v2.5.0
[INFO] [stderr]    Compiling float-cmp v0.10.0
[INFO] [stderr]    Compiling chrono v0.4.44
[INFO] [stderr]    Compiling plotters v0.3.7
[INFO] [stderr]    Compiling tinytemplate v1.2.1
[INFO] [stderr]    Compiling insta v1.47.2
[INFO] [stderr]    Compiling criterion-plot v0.8.2
[INFO] [stderr]    Compiling regex-automata v0.4.14
[INFO] [stderr]    Compiling bstr v1.12.1
[INFO] [stderr]    Compiling regex v1.12.3
[INFO] [stderr]    Compiling matchers v0.2.0
[INFO] [stderr]    Compiling tracing-subscriber v0.3.23
[INFO] [stderr]    Compiling predicates v3.1.4
[INFO] [stderr]    Compiling half v2.7.1
[INFO] [stderr]    Compiling ppv-lite86 v0.2.21
[INFO] [stderr]    Compiling globset v0.4.18
[INFO] [stderr]    Compiling ciborium-ll v0.2.2
[INFO] [stderr]    Compiling rand_chacha v0.9.0
[INFO] [stderr]    Compiling ciborium v0.2.2
[INFO] [stderr]    Compiling proptest v1.11.0
[INFO] [stderr]    Compiling ignore v0.4.25
[INFO] [stderr]    Compiling shelf v0.1.2 (/opt/rustwide/workdir)
[INFO] [stderr]    Compiling criterion v0.8.2
[INFO] [stderr]    Compiling globwalk v0.9.1
[INFO] [stderr]    Compiling assert_fs v1.1.3
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 37.75s
[INFO] running `Command { std: "docker" "inspect" "a737c6d128ddaad429e76896dee7ddb789477da3548b6b20b694400f80211f24", 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" "a737c6d128ddaad429e76896dee7ddb789477da3548b6b20b694400f80211f24" "/opt/rustwide/cargo-home/bin/cargo" "+1.98.0-beta.1" "test" "--frozen", kill_on_drop: false }`
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.34s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/shelf-90ff22023af65c74)
[INFO] [stdout] 
[INFO] [stdout] running 175 tests
[INFO] [stdout] test apply::tests::cross_device_detection_recognises_exdev ... ok
[INFO] [stdout] test apply::tests::op_mode_str_is_stable_kebab_case ... ok
[INFO] [stdout] test apply::tests::temp_sibling_lives_next_to_dst ... ok
[INFO] [stdout] test config::discovery::tests::discovers_missing_dir_is_empty ... ok
[INFO] [stdout] test apply::tests::temp_sibling_unique_across_calls ... ok
[INFO] [stdout] test config::discovery::tests::override_wins ... ok
[INFO] [stdout] test config::profile::tests::extension_in_two_kinds_is_validation_error ... ok
[INFO] [stdout] test config::profile::tests::bad_glob_is_validation_error ... ok
[INFO] [stdout] test config::profile::tests::invoices_profile_validates_clean ... ok
[INFO] [stdout] test config::profile::tests::missing_inputs_field_fails ... ok
[INFO] [stdout] test config::discovery::tests::discovers_toml_files_only ... ok
[INFO] [stdout] test config::profile::tests::duplicate_output_name_is_validation_error ... ok
[INFO] [stdout] test config::profile::tests::no_outputs_is_validation_error ... ok
[INFO] [stdout] test config::profile::tests::parses_invoices_profile ... ok
[INFO] [stdout] test config::profile::tests::parses_photos_profile ... ok
[INFO] [stdout] test hash::tests::different_bytes_hash_differently ... ok
[INFO] [stdout] test extension::tests::tif_collapses_to_tiff ... ok
[INFO] [stdout] test config::profile::tests::photos_profile_validates_clean ... ok
[INFO] [stdout] test apply::tests::replace_placement_rolls_back_when_insert_fails ... ok
[INFO] [stdout] test config::profile::tests::preserve_mtime_can_be_disabled_per_output ... ok
[INFO] [stdout] test config::profile::tests::unknown_enum_value_fails ... ok
[INFO] [stdout] test extension::tests::empty_map_just_lowercases ... ok
[INFO] [stdout] test extension::tests::jpeg_variants_collapse_to_jpg ... ok
[INFO] [stdout] test extension::tests::already_canonical_is_unchanged ... ok
[INFO] [stdout] test hash::tests::missing_file_yields_hash_error ... ok
[INFO] [stdout] test kind::tests::classify_scanned_uses_canonical_map_and_kinds ... ok
[INFO] [stdout] test kind::tests::cr3_resolves_to_raw ... ok
[INFO] [stdout] test hash::tests::identical_bytes_hash_identically ... ok
[INFO] [stdout] test kind::tests::jpg_resolves_to_photo ... ok
[INFO] [stdout] test hash::tests::empty_file_hashes_to_known_value ... ok
[INFO] [stdout] test kind::tests::mp4_resolves_to_video ... ok
[INFO] [stdout] test health::tests::jpeg_truncation_detects_missing_eoi ... ok
[INFO] [stdout] test hash::tests::known_vector ... ok
[INFO] [stdout] test health::tests::jpeg_truncation_flags_tiny_file ... ok
[INFO] [stdout] test health::tests::jpeg_truncation_ignores_non_jpeg ... ok
[INFO] [stdout] test health::tests::jpeg_truncation_passes_well_formed ... ok
[INFO] [stdout] test kind::tests::none_is_other ... ok
[INFO] [stdout] test kind::tests::unknown_ext_is_other ... ok
[INFO] [stdout] test health::tests::truncation_entry_dispatches_by_extension ... ok
[INFO] [stdout] test kind::tests::empty_kinds_is_other ... ok
[INFO] [stdout] test metadata::dispatch_tests::dispatch_falls_through_to_filename ... ok
[INFO] [stdout] test metadata::dispatch_tests::dispatch_falls_through_to_mtime ... ok
[INFO] [stdout] test hash::tests::streams_files_larger_than_buf ... ok
[INFO] [stdout] test extension::tests::no_extension_yields_none ... ok
[INFO] [stdout] test metadata::filename::tests::unmatched_returns_none ... ok
[INFO] [stdout] test metadata::dispatch_tests::dispatch_pdf_falls_back_to_filename_when_no_info ... ok
[INFO] [stdout] test metadata::dispatch_tests::dispatch_picks_exif_when_available ... ok
[INFO] [stdout] test metadata::dispatch_tests::dispatch_errors_when_no_source_produces_date ... ok
[INFO] [stdout] test metadata::dispatch_tests::dispatch_picks_pdf_creation_date_over_filename_and_mtime ... ok
[INFO] [stdout] test metadata::exif::tests::collapses_when_model_includes_make ... ok
[INFO] [stdout] test metadata::exif::tests::combines_make_and_model ... ok
[INFO] [stdout] test metadata::exif::tests::make_only ... ok
[INFO] [stdout] test metadata::exif::tests::neither ... ok
[INFO] [stdout] test metadata::pdf::tests::decode_string_handles_hex_string ... ok
[INFO] [stdout] test metadata::filename::tests::date_only_pattern_synthesizes_midnight ... ok
[INFO] [stdout] test metadata::mtime::tests::reads_mtime_into_naive_datetime ... ok
[INFO] [stdout] test metadata::pdf::tests::cache_returns_consistent_values_across_calls ... ok
[INFO] [stdout] test metadata::filename::tests::empty_patterns_returns_none ... ok
[INFO] [stdout] test metadata::filename::tests::matches_known_pattern ... ok
[INFO] [stdout] test metadata::dispatch_tests::dispatch_picks_quicktime_for_video_with_creationdate ... ok
[INFO] [stdout] test metadata::pdf::tests::decode_string_handles_utf16be_bom ... ok
[INFO] [stdout] test metadata::pdf::tests::parse_pdf_date_full ... ok
[INFO] [stdout] test metadata::pdf::tests::parse_pdf_date_rejects_garbage ... ok
[INFO] [stdout] test metadata::pdf::tests::try_date_on_non_pdf_returns_ok_none ... ok
[INFO] [stdout] test extension::tests::canonical_map_is_idempotent ... ok
[INFO] [stdout] test metadata::pdf::tests::parse_pdf_date_date_only ... ok
[INFO] [stdout] test metadata::pdf::tests::parse_pdf_date_with_offset ... ok
[INFO] [stdout] test metadata::pdf::tests::try_author_and_title_from_full_info ... ok
[INFO] [stdout] test metadata::pdf::tests::try_author_on_non_pdf_returns_ok_none ... ok
[INFO] [stdout] test metadata::quicktime::tests::mac_epoch_converts_to_utc_naive ... ok
[INFO] [stdout] test metadata::filename::tests::falls_through_to_later_pattern ... ok
[INFO] [stdout] test metadata::quicktime::tests::combine_make_model_iphone ... ok
[INFO] [stdout] test metadata::quicktime::tests::container_ok_rejects_empty_file ... ok
[INFO] [stdout] test metadata::quicktime::tests::container_ok_rejects_non_mp4 ... ok
[INFO] [stdout] test metadata::quicktime::tests::mac_epoch_zero_returns_none ... ok
[INFO] [stdout] test metadata::pdf::tests::try_date_on_pdf_without_info_returns_none ... ok
[INFO] [stdout] test metadata::pdf::tests::try_date_reads_creation_date_from_fixture ... ok
[INFO] [stdout] test metadata::quicktime::tests::container_ok_accepts_well_formed_mp4 ... ok
[INFO] [stdout] test metadata::quicktime::tests::cache_returns_same_metadata_for_repeated_calls ... ok
[INFO] [stdout] test metadata::quicktime::tests::container_ok_rejects_truncated_mp4 ... ok
[INFO] [stdout] test metadata::pdf::tests::try_vendor_prefers_author_falls_back_to_producer ... ok
[INFO] [stdout] test metadata::quicktime::tests::try_camera_on_non_mp4_returns_ok_none ... ok
[INFO] [stdout] test metadata::quicktime::tests::try_date_falls_back_to_mvhd_when_no_mdta ... ok
[INFO] [stdout] test metadata::quicktime::tests::try_date_on_non_mp4_returns_ok_none ... ok
[INFO] [stdout] test plan::tests::compose_dst_appends_extension ... ok
[INFO] [stdout] test metadata::tests::source_spec_rejects_unknown ... ok
[INFO] [stdout] test plan::tests::compose_dst_handles_leading_dot_in_ext ... ok
[INFO] [stdout] test metadata::tests::source_spec_parses_known_heads ... ok
[INFO] [stdout] test metadata::quicktime::tests::parses_apple_creationdate_without_colon_offset ... ok
[INFO] [stdout] test metadata::quicktime::tests::try_date_reads_creationdate_from_fixture ... ok
[INFO] [stdout] test metadata::quicktime::tests::try_camera_reads_iphone_make_model ... ok
[INFO] [stdout] test plan::tests::compose_dst_no_extension ... ok
[INFO] [stdout] test plan::tests::output_accepts_kinds_filter ... ok
[INFO] [stdout] test plan::tests::with_stem_suffix_dotfile_keeps_dot ... ok
[INFO] [stdout] test plan::tests::with_stem_suffix_keeps_extension ... ok
[INFO] [stdout] test plan::tests::with_stem_suffix_no_extension ... ok
[INFO] [stdout] test scan::tests::bad_glob_yields_error ... ok
[INFO] [stdout] test scan::tests::empty_include_matches_everything ... ok
[INFO] [stdout] test sequence::scope_key_tests::day_scope_is_yyyy_mm_dd ... ok
[INFO] [stdout] test sequence::scope_key_tests::folder_scope_falls_back_when_directory_missing ... ok
[INFO] [stdout] test sequence::scope_key_tests::folder_scope_uses_destination_directory ... ok
[INFO] [stdout] test scan::tests::include_matches_uppercase_extension ... ok
[INFO] [stdout] test scan::tests::exclude_is_case_insensitive_symmetrically ... ok
[INFO] [stdout] test sequence::scope_key_tests::global_scope_is_empty ... ok
[INFO] [stdout] test sequence::scope_key_tests::year_scope_is_yyyy ... ok
[INFO] [stdout] test sequence::scope_key_tests::month_scope_is_yyyy_mm ... ok
[INFO] [stdout] test state::runs::tests::decode_returns_empty_on_garbage ... ok
[INFO] [stdout] test state::runs::tests::encode_escapes_quotes_and_backslashes ... ok
[INFO] [stdout] test state::runs::tests::encode_decode_roundtrips_simple ... ok
[INFO] [stdout] test scan::tests::include_filters_to_matches ... ok
[INFO] [stdout] test state::tests::decode_hex32_rejects_wrong_length ... ok
[INFO] [stdout] test state::tests::decode_hex32_roundtrip ... ok
[INFO] [stdout] test state::tests::decode_hex32_rejects_non_hex ... ok
[INFO] [stdout] test state::schema::tests::migration_fingerprints_are_pinned ... ok
[INFO] [stdout] test state::tests::resolve_db_path_passes_absolute_through ... ok
[INFO] [stdout] test template::proptests::seq_width_pads_to_at_least_width ... ok
[INFO] [stdout] test scan::tests::exclude_subtracts_from_include ... ok
[INFO] [stdout] test template::tests::author_token_falls_back_when_metadata_missing ... ok
[INFO] [stdout] test template::tests::author_token_uses_metadata_when_present ... ok
[INFO] [stdout] test template::tests::camera_default_slugifies ... ok
[INFO] [stdout] test template::tests::camera_raw_keeps_original ... ok
[INFO] [stdout] test template::tests::end_to_end_library_render ... ok
[INFO] [stdout] test template::tests::ext_renders_when_present ... ok
[INFO] [stdout] test template::tests::hash_with_no_modifier_is_full ... ok
[INFO] [stdout] test template::tests::hash_with_width_truncates ... ok
[INFO] [stdout] test template::tests::missing_token_with_fallback_is_slugified_fallback ... ok
[INFO] [stdout] test template::tests::missing_token_with_fallback_raw_keeps_original ... ok
[INFO] [stdout] test template::tests::missing_token_without_fallback_is_unknown ... ok
[INFO] [stdout] test template::tests::parse_handles_escaped_braces ... ok
[INFO] [stdout] test template::tests::parse_rejects_empty_modifier ... ok
[INFO] [stdout] test template::tests::parse_rejects_empty_token ... ok
[INFO] [stdout] test template::tests::parse_rejects_malformed_seq_width ... ok
[INFO] [stdout] test template::tests::parse_rejects_raw_on_date_token ... ok
[INFO] [stdout] test template::tests::parse_rejects_stray_close ... ok
[INFO] [stdout] test template::tests::parse_rejects_unclosed_brace ... ok
[INFO] [stdout] test template::tests::parse_rejects_unknown_token ... ok
[INFO] [stdout] test template::tests::parse_rejects_width_on_camera ... ok
[INFO] [stdout] test template::tests::parses_hash_width ... ok
[INFO] [stdout] test template::tests::parses_literal_only ... ok
[INFO] [stdout] test template::tests::parses_mixed ... ok
[INFO] [stdout] test template::tests::parses_raw_modifier ... ok
[INFO] [stdout] test template::tests::parses_simple_field ... ok
[INFO] [stdout] test template::tests::renders_all_date_tokens ... ok
[INFO] [stdout] test template::tests::renders_library_directory_example ... ok
[INFO] [stdout] test template::tests::renders_library_filename_example ... ok
[INFO] [stdout] test template::tests::seq_no_modifier_no_value_is_zero_width_sentinel ... ok
[INFO] [stdout] test template::tests::seq_no_modifier_renders_bare_number ... ok
[INFO] [stdout] test template::tests::seq_with_assigned_value_pads ... ok
[INFO] [stdout] test template::tests::seq_without_value_renders_width_tagged_sentinel ... ok
[INFO] [stdout] test template::tests::slugify_documented_cases ... ok
[INFO] [stdout] test template::tests::slugify_strips_punctuation ... ok
[INFO] [stdout] test template::tests::substitute_seq_handles_multiple_sentinels_with_same_width ... ok
[INFO] [stdout] test template::proptests::substitute_seq_pads_to_width ... ok
[INFO] [stdout] test template::tests::substitute_seq_renders_through_template ... ok
[INFO] [stdout] test template::tests::substitute_seq_replaces_padded_sentinel ... ok
[INFO] [stdout] test template::tests::substitute_seq_passes_through_strings_without_sentinel ... ok
[INFO] [stdout] test template::tests::substitute_seq_replaces_unpadded_sentinel ... ok
[INFO] [stdout] test template::tests::title_and_vendor_render_from_metadata ... ok
[INFO] [stdout] test verify::tests::default_sample_floor_is_one ... ok
[INFO] [stdout] test verify::tests::default_sample_one_percent_rounded_up ... ok
[INFO] [stdout] test verify::tests::default_sample_zero_for_empty_library ... ok
[INFO] [stdout] test state::runs::tests::finish_revert_run_updates_target ... ok
[INFO] [stdout] test state::cache_tests::upsert_writes_author_and_title_columns ... ok
[INFO] [stdout] test state::tests::record_health_inserts_row ... ok
[INFO] [stdout] test state::runs::tests::list_runs_returns_newest_first ... ok
[INFO] [stdout] test state::cache_tests::cold_run_is_all_misses ... ok
[INFO] [stdout] test state::runs::tests::list_runs_filters_by_profile ... ok
[INFO] [stdout] test state::cache_tests::mutated_file_misses_others_hit ... ok
[INFO] [stdout] test state::runs::tests::open_finish_run_roundtrip ... ok
[INFO] [stdout] test state::tests::record_health_many_is_transactional ... ok
[INFO] [stdout] test state::cache_tests::cached_hash_matches_fresh_hash ... ok
[INFO] [stdout] test state::cache_tests::warm_run_is_all_hits ... ok
[INFO] [stdout] test state::cache_tests::upsert_file_is_idempotent_on_same_inputs ... ok
[INFO] [stdout] test metadata::quicktime::tests::parses_apple_creationdate_with_colon_offset ... ok
[INFO] [stdout] test state::runs::tests::list_runs_respects_limit ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 175 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.19s
[INFO] [stdout] 
[INFO] [stderr]      Running unittests src/main.rs (/opt/rustwide/target/debug/deps/shelf-162b2c3940d7ff1b)
[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] [stdout] 
[INFO] [stdout] running 17 tests
[INFO] [stderr]      Running tests/apply.rs (/opt/rustwide/target/debug/deps/apply-8528418bc9a062ea)
[INFO] [stdout] test dedupe_skip_emits_skipped_counter ... ok
[INFO] [stdout] test xdev_move_falls_back_to_copy_remove ... ignored
[INFO] [stdout] test copy_preserves_source_mtime_by_default ... ok
[INFO] [stdout] test move_renames_when_same_filesystem_and_removes_source ... ok
[INFO] [stdout] test hardlink_creates_link_with_same_inode ... ok
[INFO] [stdout] test plan_and_apply_survive_non_utf8_source_filename ... ok
[INFO] [stdout] test skipconflict_leaves_existing_file_untouched ... ok
[INFO] [stdout] test move_same_fs_preserves_mtime_for_free ... ok
[INFO] [stdout] test copy_with_preserve_mtime_false_does_not_carry_source_mtime ... ok
[INFO] [stdout] test replace_overwrites_existing_and_records_single_placement ... ok
[INFO] [stdout] test copy_places_file_and_leaves_source_in_place ... ok
[INFO] [stdout] test hardlink_inherits_source_mtime_through_inode ... ok
[INFO] [stdout] test symlink_points_at_absolute_source ... ok
[INFO] [stdout] test rename_places_second_file_at_suffixed_path ... ok
[INFO] [stdout] test replace_evicts_old_placement_row_for_same_dst ... ok
[INFO] [stdout] test copy_to_unwritable_dest_fails_cleanly_with_no_partial_artifacts ... FAILED
[INFO] [stdout] test multi_action_plan_partial_failure_commits_remainder_and_replays_cleanly ... FAILED
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout] 
[INFO] [stdout] ---- copy_to_unwritable_dest_fails_cleanly_with_no_partial_artifacts stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'copy_to_unwritable_dest_fails_cleanly_with_no_partial_artifacts' (2840) panicked at tests/apply.rs:617:5:
[INFO] [stdout] assertion `left == right` failed
[INFO] [stdout]   left: 1
[INFO] [stdout]  right: 0
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x5d00abad07c1 - std[73adb7dc35730857]::backtrace_rs::backtrace::libunwind::trace
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/../../backtrace/src/backtrace/libunwind.rs:117:9
[INFO] [stdout]    1:     0x5d00abad07c1 - std[73adb7dc35730857]::backtrace_rs::backtrace::trace_unsynchronized::<std[73adb7dc35730857]::sys::backtrace::_print_fmt::{closure#1}>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/../../backtrace/src/backtrace/mod.rs:66:14
[INFO] [stdout]    2:     0x5d00abad07c1 - std[73adb7dc35730857]::sys::backtrace::_print_fmt
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/sys/backtrace.rs:74:9
[INFO] [stdout]    3:     0x5d00abad07c1 - <<std[73adb7dc35730857]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[6883ba1bc0fe4ed1]::fmt::Display>::fmt
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/sys/backtrace.rs:44:26
[INFO] [stdout]    4:     0x5d00abae8eca - <core[6883ba1bc0fe4ed1]::fmt::rt::Argument>::fmt
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/core/src/fmt/rt.rs:152:76
[INFO] [stdout]    5:     0x5d00abae8eca - core[6883ba1bc0fe4ed1]::fmt::write
[INFO] [stdout]    6:     0x5d00abad54ac - std[73adb7dc35730857]::io::default_write_fmt::<alloc[55a36b64bcbf2c0d]::vec::Vec<u8>>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/io/mod.rs:626:11
[INFO] [stdout]    7:     0x5d00abad54ac - <alloc[55a36b64bcbf2c0d]::vec::Vec<u8> as std[73adb7dc35730857]::io::Write>::write_fmt
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/io/mod.rs:1730:13
[INFO] [stdout]    8:     0x5d00abaaa7b6 - <std[73adb7dc35730857]::sys::backtrace::BacktraceLock>::print
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/sys/backtrace.rs:47:9
[INFO] [stdout]    9:     0x5d00abaaa7b6 - std[73adb7dc35730857]::panicking::default_hook::{closure#0}
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panicking.rs:292:27
[INFO] [stdout]   10:     0x5d00abac5f09 - std[73adb7dc35730857]::panicking::default_hook
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panicking.rs:316:9
[INFO] [stdout]   11:     0x5d00ab364510 - <alloc[55a36b64bcbf2c0d]::boxed::Box<dyn for<'a, 'b> core[6883ba1bc0fe4ed1]::ops::function::Fn<(&'a std[73adb7dc35730857]::panic::PanicHookInfo<'b>,), Output = ()> + core[6883ba1bc0fe4ed1]::marker::Send + core[6883ba1bc0fe4ed1]::marker::Sync> as core[6883ba1bc0fe4ed1]::ops::function::Fn<(&std[73adb7dc35730857]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/alloc/src/boxed.rs:2333:9
[INFO] [stdout]   12:     0x5d00ab364510 - test[980ffaebd391d06d]::test_main_inner::<test[980ffaebd391d06d]::test_main_static::{closure#0}>::{closure#0}
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/test/src/lib.rs:155:21
[INFO] [stdout]   13:     0x5d00abac6232 - <alloc[55a36b64bcbf2c0d]::boxed::Box<dyn for<'a, 'b> core[6883ba1bc0fe4ed1]::ops::function::Fn<(&'a std[73adb7dc35730857]::panic::PanicHookInfo<'b>,), Output = ()> + core[6883ba1bc0fe4ed1]::marker::Send + core[6883ba1bc0fe4ed1]::marker::Sync> as core[6883ba1bc0fe4ed1]::ops::function::Fn<(&std[73adb7dc35730857]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/alloc/src/boxed.rs:2333:9
[INFO] [stdout]   14:     0x5d00abac6232 - std[73adb7dc35730857]::panicking::panic_with_hook
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panicking.rs:823:13
[INFO] [stdout]   15:     0x5d00abaaa862 - std[73adb7dc35730857]::panicking::panic_handler::{closure#0}
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panicking.rs:688:13
[INFO] [stdout]   16:     0x5d00abaa1d19 - std[73adb7dc35730857]::sys::backtrace::__rust_end_short_backtrace::<std[73adb7dc35730857]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/sys/backtrace.rs:182:18
[INFO] [stdout]   17:     0x5d00abaab7ed - __rustc[a7b7b02e776dd976]::rust_begin_unwind
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panicking.rs:679:5
[INFO] [stdout]   18:     0x5d00abae977c - core[6883ba1bc0fe4ed1]::panicking::panic_fmt
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/core/src/panicking.rs:80:14
[INFO] [stdout]   19:     0x5d00abae9603 - core[6883ba1bc0fe4ed1]::panicking::assert_failed_inner
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/core/src/panicking.rs:439:17
[INFO] [stdout]   20:     0x5d00abae3322 - core[6883ba1bc0fe4ed1]::panicking::assert_failed::<u64, u64>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/core/src/panicking.rs:394:5
[INFO] [stdout]   21:     0x5d00ab3085e1 - apply[17a091e029d3d343]::copy_to_unwritable_dest_fails_cleanly_with_no_partial_artifacts
[INFO] [stdout]                                at /opt/rustwide/workdir/tests/apply.rs:617:5
[INFO] [stdout]   22:     0x5d00ab2f5167 - apply[17a091e029d3d343]::copy_to_unwritable_dest_fails_cleanly_with_no_partial_artifacts::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/workdir/tests/apply.rs:583:69
[INFO] [stdout]   23:     0x5d00ab325ec6 - <apply[17a091e029d3d343]::copy_to_unwritable_dest_fails_cleanly_with_no_partial_artifacts::{closure#0} as core[6883ba1bc0fe4ed1]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   24:     0x5d00ab35784b - <fn() -> core[6883ba1bc0fe4ed1]::result::Result<(), alloc[55a36b64bcbf2c0d]::string::String> as core[6883ba1bc0fe4ed1]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   25:     0x5d00ab35784b - test[980ffaebd391d06d]::__rust_begin_short_backtrace::<core[6883ba1bc0fe4ed1]::result::Result<(), alloc[55a36b64bcbf2c0d]::string::String>, fn() -> core[6883ba1bc0fe4ed1]::result::Result<(), alloc[55a36b64bcbf2c0d]::string::String>>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/test/src/lib.rs:724:18
[INFO] [stdout]   26:     0x5d00ab364e95 - test[980ffaebd391d06d]::run_test_in_process::{closure#0}
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/test/src/lib.rs:747:74
[INFO] [stdout]   27:     0x5d00ab364e95 - <core[6883ba1bc0fe4ed1]::panic::unwind_safe::AssertUnwindSafe<test[980ffaebd391d06d]::run_test_in_process::{closure#0}> as core[6883ba1bc0fe4ed1]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   28:     0x5d00ab364e95 - std[73adb7dc35730857]::panicking::catch_unwind::do_call::<core[6883ba1bc0fe4ed1]::panic::unwind_safe::AssertUnwindSafe<test[980ffaebd391d06d]::run_test_in_process::{closure#0}>, core[6883ba1bc0fe4ed1]::result::Result<(), alloc[55a36b64bcbf2c0d]::string::String>>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panicking.rs:576:43
[INFO] [stdout]   29:     0x5d00ab364e95 - std[73adb7dc35730857]::panicking::catch_unwind::<core[6883ba1bc0fe4ed1]::result::Result<(), alloc[55a36b64bcbf2c0d]::string::String>, core[6883ba1bc0fe4ed1]::panic::unwind_safe::AssertUnwindSafe<test[980ffaebd391d06d]::run_test_in_process::{closure#0}>>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panicking.rs:544:19
[INFO] [stdout]   30:     0x5d00ab364e95 - std[73adb7dc35730857]::panic::catch_unwind::<core[6883ba1bc0fe4ed1]::panic::unwind_safe::AssertUnwindSafe<test[980ffaebd391d06d]::run_test_in_process::{closure#0}>, core[6883ba1bc0fe4ed1]::result::Result<(), alloc[55a36b64bcbf2c0d]::string::String>>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panic.rs:359:14
[INFO] [stdout]   31:     0x5d00ab364e95 - test[980ffaebd391d06d]::run_test_in_process
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/test/src/lib.rs:747:27
[INFO] [stdout]   32:     0x5d00ab364e95 - test[980ffaebd391d06d]::run_test::{closure#0}
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/test/src/lib.rs:668:43
[INFO] [stdout]   33:     0x5d00ab35f944 - test[980ffaebd391d06d]::run_test::{closure#1}
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/test/src/lib.rs:698:41
[INFO] [stdout]   34:     0x5d00ab35f944 - std[73adb7dc35730857]::sys::backtrace::__rust_begin_short_backtrace::<test[980ffaebd391d06d]::run_test::{closure#1}, ()>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/sys/backtrace.rs:166:18
[INFO] [stdout]   35:     0x5d00ab367fe2 - std[73adb7dc35730857]::thread::lifecycle::spawn_unchecked::<test[980ffaebd391d06d]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/thread/lifecycle.rs:70:13
[INFO] [stdout]   36:     0x5d00ab367fe2 - <core[6883ba1bc0fe4ed1]::panic::unwind_safe::AssertUnwindSafe<std[73adb7dc35730857]::thread::lifecycle::spawn_unchecked<test[980ffaebd391d06d]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}> as core[6883ba1bc0fe4ed1]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   37:     0x5d00ab367fe2 - std[73adb7dc35730857]::panicking::catch_unwind::do_call::<core[6883ba1bc0fe4ed1]::panic::unwind_safe::AssertUnwindSafe<std[73adb7dc35730857]::thread::lifecycle::spawn_unchecked<test[980ffaebd391d06d]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panicking.rs:576:43
[INFO] [stdout]   38:     0x5d00ab367fe2 - std[73adb7dc35730857]::panicking::catch_unwind::<(), core[6883ba1bc0fe4ed1]::panic::unwind_safe::AssertUnwindSafe<std[73adb7dc35730857]::thread::lifecycle::spawn_unchecked<test[980ffaebd391d06d]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panicking.rs:544:19
[INFO] [stdout]   39:     0x5d00ab367fe2 - std[73adb7dc35730857]::panic::catch_unwind::<core[6883ba1bc0fe4ed1]::panic::unwind_safe::AssertUnwindSafe<std[73adb7dc35730857]::thread::lifecycle::spawn_unchecked<test[980ffaebd391d06d]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panic.rs:359:14
[INFO] [stdout]   40:     0x5d00ab367fe2 - std[73adb7dc35730857]::thread::lifecycle::spawn_unchecked::<test[980ffaebd391d06d]::run_test::{closure#1}, ()>::{closure#1}
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/thread/lifecycle.rs:68:26
[INFO] [stdout]   41:     0x5d00ab367fe2 - <std[73adb7dc35730857]::thread::lifecycle::spawn_unchecked<test[980ffaebd391d06d]::run_test::{closure#1}, ()>::{closure#1} as core[6883ba1bc0fe4ed1]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   42:     0x5d00abacf6af - <alloc[55a36b64bcbf2c0d]::boxed::Box<dyn core[6883ba1bc0fe4ed1]::ops::function::FnOnce<(), Output = ()> + core[6883ba1bc0fe4ed1]::marker::Send> as core[6883ba1bc0fe4ed1]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/alloc/src/boxed.rs:2319:9
[INFO] [stdout]   43:     0x5d00abacf6af - <std[73adb7dc35730857]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/sys/thread/unix.rs:123:17
[INFO] [stdout]   44:     0x7ad81b322aa4 - <unknown>
[INFO] [stdout]   45:     0x7ad81b3afa64 - clone
[INFO] [stdout]   46:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- multi_action_plan_partial_failure_commits_remainder_and_replays_cleanly stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'multi_action_plan_partial_failure_commits_remainder_and_replays_cleanly' (2847) panicked at tests/apply.rs:830:5:
[INFO] [stdout] assertion `left == right` failed: actions surrounding the failure must commit
[INFO] [stdout]   left: 5
[INFO] [stdout]  right: 4
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x5d00abad07c1 - std[73adb7dc35730857]::backtrace_rs::backtrace::libunwind::trace
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/../../backtrace/src/backtrace/libunwind.rs:117:9
[INFO] [stdout]    1:     0x5d00abad07c1 - std[73adb7dc35730857]::backtrace_rs::backtrace::trace_unsynchronized::<std[73adb7dc35730857]::sys::backtrace::_print_fmt::{closure#1}>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/../../backtrace/src/backtrace/mod.rs:66:14
[INFO] [stdout]    2:     0x5d00abad07c1 - std[73adb7dc35730857]::sys::backtrace::_print_fmt
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/sys/backtrace.rs:74:9
[INFO] [stdout]    3:     0x5d00abad07c1 - <<std[73adb7dc35730857]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[6883ba1bc0fe4ed1]::fmt::Display>::fmt
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/sys/backtrace.rs:44:26
[INFO] [stdout]    4:     0x5d00abae8eca - <core[6883ba1bc0fe4ed1]::fmt::rt::Argument>::fmt
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/core/src/fmt/rt.rs:152:76
[INFO] [stdout]    5:     0x5d00abae8eca - core[6883ba1bc0fe4ed1]::fmt::write
[INFO] [stdout]    6:     0x5d00abad54ac - std[73adb7dc35730857]::io::default_write_fmt::<alloc[55a36b64bcbf2c0d]::vec::Vec<u8>>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/io/mod.rs:626:11
[INFO] [stdout]    7:     0x5d00abad54ac - <alloc[55a36b64bcbf2c0d]::vec::Vec<u8> as std[73adb7dc35730857]::io::Write>::write_fmt
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/io/mod.rs:1730:13
[INFO] [stdout]    8:     0x5d00abaaa7b6 - <std[73adb7dc35730857]::sys::backtrace::BacktraceLock>::print
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/sys/backtrace.rs:47:9
[INFO] [stdout]    9:     0x5d00abaaa7b6 - std[73adb7dc35730857]::panicking::default_hook::{closure#0}
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panicking.rs:292:27
[INFO] [stdout]   10:     0x5d00abac5f09 - std[73adb7dc35730857]::panicking::default_hook
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panicking.rs:316:9
[INFO] [stdout]   11:     0x5d00ab364510 - <alloc[55a36b64bcbf2c0d]::boxed::Box<dyn for<'a, 'b> core[6883ba1bc0fe4ed1]::ops::function::Fn<(&'a std[73adb7dc35730857]::panic::PanicHookInfo<'b>,), Output = ()> + core[6883ba1bc0fe4ed1]::marker::Send + core[6883ba1bc0fe4ed1]::marker::Sync> as core[6883ba1bc0fe4ed1]::ops::function::Fn<(&std[73adb7dc35730857]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/alloc/src/boxed.rs:2333:9
[INFO] [stdout]   12:     0x5d00ab364510 - test[980ffaebd391d06d]::test_main_inner::<test[980ffaebd391d06d]::test_main_static::{closure#0}>::{closure#0}
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/test/src/lib.rs:155:21
[INFO] [stdout]   13:     0x5d00abac6232 - <alloc[55a36b64bcbf2c0d]::boxed::Box<dyn for<'a, 'b> core[6883ba1bc0fe4ed1]::ops::function::Fn<(&'a std[73adb7dc35730857]::panic::PanicHookInfo<'b>,), Output = ()> + core[6883ba1bc0fe4ed1]::marker::Send + core[6883ba1bc0fe4ed1]::marker::Sync> as core[6883ba1bc0fe4ed1]::ops::function::Fn<(&std[73adb7dc35730857]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/alloc/src/boxed.rs:2333:9
[INFO] [stdout]   14:     0x5d00abac6232 - std[73adb7dc35730857]::panicking::panic_with_hook
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panicking.rs:823:13
[INFO] [stdout]   15:     0x5d00abaaa862 - std[73adb7dc35730857]::panicking::panic_handler::{closure#0}
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panicking.rs:688:13
[INFO] [stdout]   16:     0x5d00abaa1d19 - std[73adb7dc35730857]::sys::backtrace::__rust_end_short_backtrace::<std[73adb7dc35730857]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/sys/backtrace.rs:182:18
[INFO] [stdout]   17:     0x5d00abaab7ed - __rustc[a7b7b02e776dd976]::rust_begin_unwind
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panicking.rs:679:5
[INFO] [stdout]   18:     0x5d00abae977c - core[6883ba1bc0fe4ed1]::panicking::panic_fmt
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/core/src/panicking.rs:80:14
[INFO] [stdout]   19:     0x5d00abae9663 - core[6883ba1bc0fe4ed1]::panicking::assert_failed_inner
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/core/src/panicking.rs:434:23
[INFO] [stdout]   20:     0x5d00abae3322 - core[6883ba1bc0fe4ed1]::panicking::assert_failed::<u64, u64>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/core/src/panicking.rs:394:5
[INFO] [stdout]   21:     0x5d00ab309b26 - apply[17a091e029d3d343]::multi_action_plan_partial_failure_commits_remainder_and_replays_cleanly
[INFO] [stdout]                                at /opt/rustwide/workdir/tests/apply.rs:830:5
[INFO] [stdout]   22:     0x5d00ab2f5197 - apply[17a091e029d3d343]::multi_action_plan_partial_failure_commits_remainder_and_replays_cleanly::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/workdir/tests/apply.rs:785:77
[INFO] [stdout]   23:     0x5d00ab325f06 - <apply[17a091e029d3d343]::multi_action_plan_partial_failure_commits_remainder_and_replays_cleanly::{closure#0} as core[6883ba1bc0fe4ed1]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   24:     0x5d00ab35784b - <fn() -> core[6883ba1bc0fe4ed1]::result::Result<(), alloc[55a36b64bcbf2c0d]::string::String> as core[6883ba1bc0fe4ed1]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   25:     0x5d00ab35784b - test[980ffaebd391d06d]::__rust_begin_short_backtrace::<core[6883ba1bc0fe4ed1]::result::Result<(), alloc[55a36b64bcbf2c0d]::string::String>, fn() -> core[6883ba1bc0fe4ed1]::result::Result<(), alloc[55a36b64bcbf2c0d]::string::String>>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/test/src/lib.rs:724:18
[INFO] [stdout]   26:     0x5d00ab364e95 - test[980ffaebd391d06d]::run_test_in_process::{closure#0}
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/test/src/lib.rs:747:74
[INFO] [stdout]   27:     0x5d00ab364e95 - <core[6883ba1bc0fe4ed1]::panic::unwind_safe::AssertUnwindSafe<test[980ffaebd391d06d]::run_test_in_process::{closure#0}> as core[6883ba1bc0fe4ed1]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   28:     0x5d00ab364e95 - std[73adb7dc35730857]::panicking::catch_unwind::do_call::<core[6883ba1bc0fe4ed1]::panic::unwind_safe::AssertUnwindSafe<test[980ffaebd391d06d]::run_test_in_process::{closure#0}>, core[6883ba1bc0fe4ed1]::result::Result<(), alloc[55a36b64bcbf2c0d]::string::String>>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panicking.rs:576:43
[INFO] [stdout]   29:     0x5d00ab364e95 - std[73adb7dc35730857]::panicking::catch_unwind::<core[6883ba1bc0fe4ed1]::result::Result<(), alloc[55a36b64bcbf2c0d]::string::String>, core[6883ba1bc0fe4ed1]::panic::unwind_safe::AssertUnwindSafe<test[980ffaebd391d06d]::run_test_in_process::{closure#0}>>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panicking.rs:544:19
[INFO] [stdout]   30:     0x5d00ab364e95 - std[73adb7dc35730857]::panic::catch_unwind::<core[6883ba1bc0fe4ed1]::panic::unwind_safe::AssertUnwindSafe<test[980ffaebd391d06d]::run_test_in_process::{closure#0}>, core[6883ba1bc0fe4ed1]::result::Result<(), alloc[55a36b64bcbf2c0d]::string::String>>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panic.rs:359:14
[INFO] [stdout]   31:     0x5d00ab364e95 - test[980ffaebd391d06d]::run_test_in_process
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/test/src/lib.rs:747:27
[INFO] [stdout]   32:     0x5d00ab364e95 - test[980ffaebd391d06d]::run_test::{closure#0}
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/test/src/lib.rs:668:43
[INFO] [stdout]   33:     0x5d00ab35f944 - test[980ffaebd391d06d]::run_test::{closure#1}
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/test/src/lib.rs:698:41
[INFO] [stdout]   34:     0x5d00ab35f944 - std[73adb7dc35730857]::sys::backtrace::__rust_begin_short_backtrace::<test[980ffaebd391d06d]::run_test::{closure#1}, ()>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/sys/backtrace.rs:166:18
[INFO] [stdout]   35:     0x5d00ab367fe2 - std[73adb7dc35730857]::thread::lifecycle::spawn_unchecked::<test[980ffaebd391d06d]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/thread/lifecycle.rs:70:13
[INFO] [stdout]   36:     0x5d00ab367fe2 - <core[6883ba1bc0fe4ed1]::panic::unwind_safe::AssertUnwindSafe<std[73adb7dc35730857]::thread::lifecycle::spawn_unchecked<test[980ffaebd391d06d]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}> as core[6883ba1bc0fe4ed1]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   37:     0x5d00ab367fe2 - std[73adb7dc35730857]::panicking::catch_unwind::do_call::<core[6883ba1bc0fe4ed1]::panic::unwind_safe::AssertUnwindSafe<std[73adb7dc35730857]::thread::lifecycle::spawn_unchecked<test[980ffaebd391d06d]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panicking.rs:576:43
[INFO] [stdout]   38:     0x5d00ab367fe2 - std[73adb7dc35730857]::panicking::catch_unwind::<(), core[6883ba1bc0fe4ed1]::panic::unwind_safe::AssertUnwindSafe<std[73adb7dc35730857]::thread::lifecycle::spawn_unchecked<test[980ffaebd391d06d]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panicking.rs:544:19
[INFO] [stdout]   39:     0x5d00ab367fe2 - std[73adb7dc35730857]::panic::catch_unwind::<core[6883ba1bc0fe4ed1]::panic::unwind_safe::AssertUnwindSafe<std[73adb7dc35730857]::thread::lifecycle::spawn_unchecked<test[980ffaebd391d06d]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panic.rs:359:14
[INFO] [stdout]   40:     0x5d00ab367fe2 - std[73adb7dc35730857]::thread::lifecycle::spawn_unchecked::<test[980ffaebd391d06d]::run_test::{closure#1}, ()>::{closure#1}
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/thread/lifecycle.rs:68:26
[INFO] [stdout]   41:     0x5d00ab367fe2 - <std[73adb7dc35730857]::thread::lifecycle::spawn_unchecked<test[980ffaebd391d06d]::run_test::{closure#1}, ()>::{closure#1} as core[6883ba1bc0fe4ed1]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   42:     0x5d00abacf6af - <alloc[55a36b64bcbf2c0d]::boxed::Box<dyn core[6883ba1bc0fe4ed1]::ops::function::FnOnce<(), Output = ()> + core[6883ba1bc0fe4ed1]::marker::Send> as core[6883ba1bc0fe4ed1]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/alloc/src/boxed.rs:2319:9
[INFO] [stdout]   43:     0x5d00abacf6af - <std[73adb7dc35730857]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/sys/thread/unix.rs:123:17
[INFO] [stdout]   44:     0x7ad81b322aa4 - <unknown>
[INFO] [stdout]   45:     0x7ad81b3afa64 - clone
[INFO] [stdout]   46:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout]     copy_to_unwritable_dest_fails_cleanly_with_no_partial_artifacts
[INFO] [stdout]     multi_action_plan_partial_failure_commits_remainder_and_replays_cleanly
[INFO] [stdout] 
[INFO] [stdout] test result: FAILED. 14 passed; 2 failed; 1 ignored; 0 measured; 0 filtered out; finished in 0.12s
[INFO] [stdout] 
[INFO] [stderr] error: test failed, to rerun pass `--test apply`
[INFO] running `Command { std: "docker" "inspect" "a737c6d128ddaad429e76896dee7ddb789477da3548b6b20b694400f80211f24", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "a737c6d128ddaad429e76896dee7ddb789477da3548b6b20b694400f80211f24", kill_on_drop: false }`
[INFO] [stdout] a737c6d128ddaad429e76896dee7ddb789477da3548b6b20b694400f80211f24
