[INFO] fetching crate image-flatify 6.1.0...
[INFO] testing image-flatify-6.1.0 against 1.99.0-beta.8+cargoflags=--release for beta-release-1.100-2
[INFO] extracting crate image-flatify 6.1.0 into /workspace/builds/worker-6-tc1/source
[INFO] started tweaking crates.io crate image-flatify 6.1.0
[INFO] removed 0 missing tests
[INFO] finished tweaking crates.io crate image-flatify 6.1.0
[INFO] tweaked toml for crates.io crate image-flatify 6.1.0 written to /workspace/builds/worker-6-tc1/source/Cargo.toml
[INFO] validating manifest of crates.io crate image-flatify 6.1.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 image-flatify 6.1.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-6-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-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] e53de8e48f43fa8888754ea863d25c18a358ec6e791b7ba8aabdb96e3b90635b
[INFO] running `Command { std: "docker" "start" "e53de8e48f43fa8888754ea863d25c18a358ec6e791b7ba8aabdb96e3b90635b", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "e53de8e48f43fa8888754ea863d25c18a358ec6e791b7ba8aabdb96e3b90635b", 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" "e53de8e48f43fa8888754ea863d25c18a358ec6e791b7ba8aabdb96e3b90635b" "/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" "e53de8e48f43fa8888754ea863d25c18a358ec6e791b7ba8aabdb96e3b90635b", 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" "e53de8e48f43fa8888754ea863d25c18a358ec6e791b7ba8aabdb96e3b90635b" "/opt/rustwide/cargo-home/bin/cargo" "+1.99.0-beta.8" "build" "--frozen" "--message-format=json" "--release", kill_on_drop: false }`
[INFO] [stderr]    Compiling libc v0.2.190
[INFO] [stderr]    Compiling const-oid v0.10.2
[INFO] [stderr]    Compiling hybrid-array v0.4.14
[INFO] [stderr]    Compiling aho-corasick v1.1.5
[INFO] [stderr]    Compiling clap_lex v1.1.1
[INFO] [stderr]    Compiling chacha20 v0.10.2
[INFO] [stderr]    Compiling syn v3.0.6
[INFO] [stderr]    Compiling chrono v0.4.45
[INFO] [stderr]    Compiling clap_builder v4.6.7
[INFO] [stderr]    Compiling crypto-common v0.2.2
[INFO] [stderr]    Compiling block-buffer v0.12.1
[INFO] [stderr]    Compiling regex-automata v0.4.18
[INFO] [stderr]    Compiling digest v0.11.3
[INFO] [stderr]    Compiling md-5 v0.11.0
[INFO] [stderr]    Compiling getrandom v0.4.3
[INFO] [stderr]    Compiling which v8.0.6
[INFO] [stderr]    Compiling rand v0.10.3
[INFO] [stderr]    Compiling clap_derive v4.6.7
[INFO] [stderr]    Compiling regex v1.13.1
[INFO] [stderr]    Compiling clap v4.6.7
[INFO] [stderr]    Compiling image-flatify v6.1.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `release` profile [optimized] target(s) in 29.23s
[INFO] running `Command { std: "docker" "inspect" "e53de8e48f43fa8888754ea863d25c18a358ec6e791b7ba8aabdb96e3b90635b", 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" "e53de8e48f43fa8888754ea863d25c18a358ec6e791b7ba8aabdb96e3b90635b" "/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 predicates-core v1.0.10
[INFO] [stderr]    Compiling regex-automata v0.4.18
[INFO] [stderr]    Compiling termtree v0.5.1
[INFO] [stderr]    Compiling normalize-line-endings v0.3.0
[INFO] [stderr]    Compiling assert_cmd v2.2.2
[INFO] [stderr]    Compiling difflib v0.4.0
[INFO] [stderr]    Compiling wait-timeout v0.2.1
[INFO] [stderr]    Compiling float-cmp v0.10.0
[INFO] [stderr]    Compiling tempfile v3.27.0
[INFO] [stderr]    Compiling predicates-tree v1.0.13
[INFO] [stderr]    Compiling regex v1.13.1
[INFO] [stderr]    Compiling bstr v1.13.1
[INFO] [stderr]    Compiling predicates v3.1.4
[INFO] [stderr]    Compiling image-flatify v6.1.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `release` profile [optimized] target(s) in 23.46s
[INFO] running `Command { std: "docker" "inspect" "e53de8e48f43fa8888754ea863d25c18a358ec6e791b7ba8aabdb96e3b90635b", 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" "e53de8e48f43fa8888754ea863d25c18a358ec6e791b7ba8aabdb96e3b90635b" "/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.09s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/release/deps/image_flatify-eb708229da18e29b)
[INFO] [stdout] 
[INFO] [stdout] running 57 tests
[INFO] [stdout] test date::tests::test_exiftool_timezone_only_result_empty ... ok
[INFO] [stdout] test cleaner::tests::test_clean_directory_read_dir_error ... ok
[INFO] [stdout] test cleaner::tests::test_clean_directory_keeps_non_empty ... ok
[INFO] [stdout] test cleaner::tests::test_clean_directory_removes_empty ... ok
[INFO] [stdout] test cleaner::tests::test_clean_directory_dry_run_preserves ... ok
[INFO] [stdout] test date::tests::test_get_date_string_exiftool_fallback ... ok
[INFO] [stdout] test date::tests::test_exiftool_valid_date ... ok
[INFO] [stdout] test date::tests::test_exiftool_command_fails ... ok
[INFO] [stdout] test date::tests::test_get_date_string_final_none_when_no_metadata ... ok
[INFO] [stdout] test date::tests::test_exiftool_with_timezone ... ok
[INFO] [stdout] test date::tests::test_get_date_string_gm_fallback ... ok
[INFO] [stdout] test date::tests::test_format_date_dashed ... ok
[INFO] [stdout] test cleaner::tests::test_clean_directories_missing_directory ... ok
[INFO] [stdout] test date::tests::test_format_date_dashed_edge_cases ... ok
[INFO] [stdout] test date::tests::test_get_date_string_non_existing ... ok
[INFO] [stdout] test date::tests::test_exiftool_empty_output ... ok
[INFO] [stdout] test date::tests::test_get_date_string_all_tools_fail_uses_fallback ... ok
[INFO] [stdout] test finder::tests::test_get_images_fixtures ... ok
[INFO] [stdout] test media::tests::test_is_media ... ok
[INFO] [stdout] test date::tests::test_gm_empty_output ... ok
[INFO] [stdout] test naming::tests::test_build_extension_lowercase ... ok
[INFO] [stdout] test date::tests::test_get_date_string_mediainfo_invalid_format_skips ... ok
[INFO] [stdout] test date::tests::test_mediainfo_command_fails ... ok
[INFO] [stdout] test naming::tests::test_build_extension_none ... ok
[INFO] [stdout] test naming::tests::test_build_extension_uppercase ... ok
[INFO] [stdout] test naming::tests::test_build_hash_input ... ok
[INFO] [stdout] test date::tests::test_get_date_string_exiftool_not_starting_with_number ... ok
[INFO] [stdout] test date::tests::test_gm_command_fails ... ok
[INFO] [stdout] test naming::tests::test_build_name_part_empty_stem ... ok
[INFO] [stdout] test naming::tests::test_build_name_part_with_append_date ... ok
[INFO] [stdout] test naming::tests::test_build_name_part_with_prefix ... ok
[INFO] [stdout] test naming::tests::test_generate_md5_hash_abc ... ok
[INFO] [stdout] test naming::tests::test_generate_md5_hash_empty ... ok
[INFO] [stdout] test naming::tests::test_generate_md5_hash_known_value ... ok
[INFO] [stdout] test finder::tests::test_get_images_non_existing ... ok
[INFO] [stdout] test naming::tests::test_build_hash_input_empty_dest ... ok
[INFO] [stdout] test date::tests::test_mediainfo_file_date_skipped ... ok
[INFO] [stdout] test date::tests::test_gm_valid_date ... ok
[INFO] [stdout] test naming::tests::test_join_with_dest_dir_non_empty ... ok
[INFO] [stdout] test date::tests::test_get_date_string_gm_not_starting_with_number ... ok
[INFO] [stdout] test naming::tests::test_join_with_dest_dir_empty ... ok
[INFO] [stdout] test date::tests::test_get_date_string_mediainfo_priority ... ok
[INFO] [stdout] test naming::tests::test_extract_iso_date ... ok
[INFO] [stdout] test naming::tests::test_resolve_collision_no_conflict ... ok
[INFO] [stdout] test naming::tests::test_resolve_collision_multiple_conflicts ... ok
[INFO] [stdout] test date::tests::test_mediainfo_no_date_lines ... ok
[INFO] [stdout] test date::tests::test_mediainfo_valid_date_found ... ok
[INFO] [stdout] test naming::tests::test_resolve_collision_single_conflict ... ok
[INFO] [stdout] test naming::tests::test_build_extension_lowercase_already ... ok
[INFO] [stdout] test naming::tests::test_build_name_part_without_append_date ... ok
[INFO] [stdout] test naming::tests::test_get_target_path_lowercase_suffix ... ok
[INFO] [stdout] test date::tests::test_get_date_string_fixture ... ok
[INFO] [stdout] test date::tests::test_get_date_string_with_valid_fixture ... ok
[INFO] [stdout] test naming::tests::test_get_target_path_collision_counter ... ok
[INFO] [stdout] test naming::tests::test_get_target_path_basic ... ok
[INFO] [stdout] test naming::tests::test_get_target_path_append_hash ... ok
[INFO] [stdout] test date::tests::test_file_metadata_fallback ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 57 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.16s
[INFO] [stdout] 
[INFO] [stderr]      Running unittests src/main.rs (/opt/rustwide/target/release/deps/image_flatify-3adac876f9381f0a)
[INFO] [stdout] 
[INFO] [stdout] running 2 tests
[INFO] [stdout] test tests::test_check_external_tools_warns_when_unavailable ... ok
[INFO] [stdout] test tests::test_check_external_tools_accepts_available_tools ... 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/cli_tests.rs (/opt/rustwide/target/release/deps/cli_tests-45db911285efdf0d)
[INFO] [stdout] 
[INFO] [stdout] running 8 tests
[INFO] [stdout] test test_cli_non_existing_directory_fails ... ok
[INFO] [stdout] test test_cli_version ... FAILED
[INFO] [stdout] test test_cli_requires_directory ... ok
[INFO] [stdout] test test_cli_invalid_option ... ok
[INFO] [stdout] test test_cli_help ... ok
[INFO] [stdout] test test_cli_dry_run_verbose ... ok
[INFO] [stdout] test test_cli_append_hash ... ok
[INFO] [stdout] test test_cli_prefix ... ok
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout] 
[INFO] [stdout] ---- test_cli_version stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'test_cli_version' (1064) panicked at tests/cli_tests.rs:10:10:
[INFO] [stdout] Unexpected stdout, failed var.contains(image-flatify 6.0.0)
[INFO] [stdout] ├── var: image-flatify 6.1.0
[INFO] [stdout] └── var as str: image-flatify 6.1.0
[INFO] [stdout] 
[INFO] [stdout] command=`"/opt/rustwide/target/release/image-flatify" "-V"`
[INFO] [stdout] code=0
[INFO] [stdout] stdout="image-flatify 6.1.0\n"
[INFO] [stdout] stderr=""
[INFO] [stdout] 
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x5c9d8f36f0c1 - <<std[70759c8f55707aa4]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[df12db4294e9bfd3]::fmt::Display>::fmt
[INFO] [stdout]    1:     0x5c9d8f3867da - core[df12db4294e9bfd3]::fmt::write
[INFO] [stdout]    2:     0x5c9d8f3749dc - <alloc[2182bb758b4b3781]::vec::Vec<u8> as core[df12db4294e9bfd3]::io::write::Write>::write_fmt
[INFO] [stdout]    3:     0x5c9d8f34acb6 - std[70759c8f55707aa4]::panicking::default_hook::{closure#0}
[INFO] [stdout]    4:     0x5c9d8f3676f9 - std[70759c8f55707aa4]::panicking::default_hook
[INFO] [stdout]    5:     0x5c9d8f22aed0 - test[9d35eded1c95d3be]::test_main_inner::<test[9d35eded1c95d3be]::test_main_static::{closure#0}>::{closure#0}
[INFO] [stdout]    6:     0x5c9d8f3678b2 - std[70759c8f55707aa4]::panicking::panic_with_hook
[INFO] [stdout]    7:     0x5c9d8f34ad62 - std[70759c8f55707aa4]::panicking::panic_handler::{closure#0}
[INFO] [stdout]    8:     0x5c9d8f343ae9 - std[70759c8f55707aa4]::sys::backtrace::__rust_end_short_backtrace::<std[70759c8f55707aa4]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]    9:     0x5c9d8f34b95d - __rustc[8fa7c3cbc660c2b3]::rust_begin_unwind
[INFO] [stdout]   10:     0x5c9d8f386fbc - core[df12db4294e9bfd3]::panicking::panic_fmt
[INFO] [stdout]   11:     0x5c9d8f21abb0 - <assert_cmd[f5a98fd115ac29a8]::assert::AssertError>::panic::<assert_cmd[f5a98fd115ac29a8]::assert::Assert>
[INFO] [stdout]   12:     0x5c9d8f21cf43 - <cli_tests[8a1aee8e835101ff]::test_cli_version::{closure#0} as core[df12db4294e9bfd3]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   13:     0x5c9d8f21e1bb - 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]   14:     0x5c9d8f22b825 - test[9d35eded1c95d3be]::run_test::{closure#0}
[INFO] [stdout]   15:     0x5c9d8f2250e4 - std[70759c8f55707aa4]::sys::backtrace::__rust_begin_short_backtrace::<test[9d35eded1c95d3be]::run_test::{closure#1}, ()>
[INFO] [stdout]   16:     0x5c9d8f22e982 - <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]   17:     0x5c9d8f36e919 - <std[70759c8f55707aa4]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   18:     0x7f257098ddfa - <unknown>
[INFO] [stdout]   19:     0x7f2570a213d4 - clone
[INFO] [stdout]   20:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout]     test_cli_version
[INFO] [stdout] 
[INFO] [stdout] test result: FAILED. 7 passed; 1 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.07s
[INFO] [stdout] 
[INFO] [stderr] error: test failed, to rerun pass `--test cli_tests`
[INFO] running `Command { std: "docker" "inspect" "e53de8e48f43fa8888754ea863d25c18a358ec6e791b7ba8aabdb96e3b90635b", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "e53de8e48f43fa8888754ea863d25c18a358ec6e791b7ba8aabdb96e3b90635b", kill_on_drop: false }`
[INFO] [stdout] e53de8e48f43fa8888754ea863d25c18a358ec6e791b7ba8aabdb96e3b90635b
