[INFO] cloning repository https://github.com/snltd/zfs-tools-rs
[INFO] running `Command { std: "git" "-c" "credential.helper=" "-c" "credential.helper=/workspace/cargo-home/bin/git-credential-null" "clone" "--bare" "https://github.com/snltd/zfs-tools-rs" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fsnltd%2Fzfs-tools-rs", kill_on_drop: false }`
[INFO] [stderr] Cloning into bare repository '/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fsnltd%2Fzfs-tools-rs'...
[INFO] running `Command { std: "git" "rev-parse" "HEAD", kill_on_drop: false }`
[INFO] [stdout] fd766b85b13927ebf1c060f1a5d81a8a0dc03385
[INFO] testing snltd/zfs-tools-rs against try#c066bf6804adc55193b71afdff6a56922f4a8689+target=x86_64-unknown-linux-musl for musl_upgrade_1_2_5_in_2025-retry-1
[INFO] running `Command { std: "git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fsnltd%2Fzfs-tools-rs" "/workspace/builds/worker-2-tc2/source", kill_on_drop: false }`
[INFO] [stderr] Cloning into '/workspace/builds/worker-2-tc2/source'...
[INFO] [stderr] done.
[INFO] started tweaking git repo https://github.com/snltd/zfs-tools-rs
[INFO] finished tweaking git repo https://github.com/snltd/zfs-tools-rs
[INFO] tweaked toml for git repo https://github.com/snltd/zfs-tools-rs written to /workspace/builds/worker-2-tc2/source/Cargo.toml
[INFO] validating manifest of git repo https://github.com/snltd/zfs-tools-rs on toolchain c066bf6804adc55193b71afdff6a56922f4a8689
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+c066bf6804adc55193b71afdff6a56922f4a8689" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate git repo https://github.com/snltd/zfs-tools-rs 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" "+c066bf6804adc55193b71afdff6a56922f4a8689" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] [stderr]  Downloading crates ...
[INFO] [stderr]   Downloaded byte-unit v5.1.4
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-tc2/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:90999bfc7ae267e83380e433d8e61a7c072ca6729e92edbae886d3423b3a6f4c" "/opt/rustwide/cargo-home/bin/cargo" "+c066bf6804adc55193b71afdff6a56922f4a8689" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] 5c35cd4f16dd97185ae155757b355d5b0396f1fe4764a0b3a1f02469a8813a3f
[INFO] running `Command { std: "docker" "start" "-a" "5c35cd4f16dd97185ae155757b355d5b0396f1fe4764a0b3a1f02469a8813a3f", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "5c35cd4f16dd97185ae155757b355d5b0396f1fe4764a0b3a1f02469a8813a3f", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "5c35cd4f16dd97185ae155757b355d5b0396f1fe4764a0b3a1f02469a8813a3f", kill_on_drop: false }`
[INFO] [stdout] 5c35cd4f16dd97185ae155757b355d5b0396f1fe4764a0b3a1f02469a8813a3f
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-tc2/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:90999bfc7ae267e83380e433d8e61a7c072ca6729e92edbae886d3423b3a6f4c" "/opt/rustwide/cargo-home/bin/cargo" "+c066bf6804adc55193b71afdff6a56922f4a8689" "build" "--frozen" "--message-format=json" "--target" "x86_64-unknown-linux-musl", kill_on_drop: false }`
[INFO] [stdout] 62b00995aefa785c6cac6a0b33257c4182b48f9bf118522c1d6b20e96f706fc5
[INFO] running `Command { std: "docker" "start" "-a" "62b00995aefa785c6cac6a0b33257c4182b48f9bf118522c1d6b20e96f706fc5", kill_on_drop: false }`
[INFO] [stderr]    Compiling proc-macro2 v1.0.89
[INFO] [stderr]    Compiling unicode-ident v1.0.13
[INFO] [stderr]    Compiling anyhow v1.0.94
[INFO] [stderr]    Compiling utf8parse v0.2.2
[INFO] [stderr]    Compiling is_terminal_polyfill v1.70.1
[INFO] [stderr]    Compiling anstyle-query v1.1.2
[INFO] [stderr]    Compiling colorchoice v1.0.3
[INFO] [stderr]    Compiling anstyle v1.0.9
[INFO] [stderr]    Compiling heck v0.5.0
[INFO] [stderr]    Compiling clap_lex v0.7.2
[INFO] [stderr]    Compiling libc v0.2.161
[INFO] [stderr]    Compiling strsim v0.11.1
[INFO] [stderr]    Compiling autocfg v1.4.0
[INFO] [stderr]    Compiling memchr v2.7.4
[INFO] [stderr]    Compiling powerfmt v0.2.0
[INFO] [stderr]    Compiling time-core v0.1.2
[INFO] [stderr]    Compiling anstyle-parse v0.2.6
[INFO] [stderr]    Compiling regex-syntax v0.8.5
[INFO] [stderr]    Compiling num-conv v0.1.0
[INFO] [stderr]    Compiling itoa v1.0.11
[INFO] [stderr]    Compiling rust_decimal v1.36.0
[INFO] [stderr]    Compiling num_threads v0.1.7
[INFO] [stderr]    Compiling deranged v0.3.11
[INFO] [stderr]    Compiling time-macros v0.2.18
[INFO] [stderr]    Compiling anstream v0.6.17
[INFO] [stderr]    Compiling arrayvec v0.7.6
[INFO] [stderr]    Compiling cfg-if v1.0.0
[INFO] [stderr]    Compiling utf8-width v0.1.7
[INFO] [stderr]    Compiling lazy_static v1.5.0
[INFO] [stderr]    Compiling glob v0.3.1
[INFO] [stderr]    Compiling colored v2.1.0
[INFO] [stderr]    Compiling common v2.0.0 (/opt/rustwide/workdir/common)
[INFO] [stderr]    Compiling aho-corasick v1.1.3
[INFO] [stderr]    Compiling num-traits v0.2.19
[INFO] [stderr]    Compiling clap_builder v4.5.20
[INFO] [stderr]    Compiling filetime v0.2.25
[INFO] [stderr]    Compiling quote v1.0.37
[INFO] [stderr]    Compiling time v0.3.36
[INFO] [stderr]    Compiling syn v2.0.85
[INFO] [stderr]    Compiling regex-automata v0.4.8
[INFO] [stderr]    Compiling byte-unit v5.1.4
[INFO] [stderr]    Compiling zfs-real-usage v2.0.1 (/opt/rustwide/workdir/zfs-real-usage)
[INFO] [stderr]    Compiling clap_derive v4.5.18
[INFO] [stderr]    Compiling regex v1.11.1
[INFO] [stderr]    Compiling zfs-rogue-snaps v2.0.1 (/opt/rustwide/workdir/zfs-rogue-snaps)
[INFO] [stderr]    Compiling clap v4.5.20
[INFO] [stderr]    Compiling zfs-remove-snaps v2.0.1 (/opt/rustwide/workdir/zfs-remove-snaps)
[INFO] [stderr]    Compiling zr v2.0.1 (/opt/rustwide/workdir/zr)
[INFO] [stderr]    Compiling zfs-touch-from-snap v2.0.2 (/opt/rustwide/workdir/zfs-touch-from-snap)
[INFO] [stderr]    Compiling zfs-snap v2.0.1 (/opt/rustwide/workdir/zfs-snap)
[INFO] [stderr]    Compiling zp v2.0.0 (/opt/rustwide/workdir/zp)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 26.70s
[INFO] running `Command { std: "docker" "inspect" "62b00995aefa785c6cac6a0b33257c4182b48f9bf118522c1d6b20e96f706fc5", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "62b00995aefa785c6cac6a0b33257c4182b48f9bf118522c1d6b20e96f706fc5", kill_on_drop: false }`
[INFO] [stdout] 62b00995aefa785c6cac6a0b33257c4182b48f9bf118522c1d6b20e96f706fc5
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-tc2/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:90999bfc7ae267e83380e433d8e61a7c072ca6729e92edbae886d3423b3a6f4c" "/opt/rustwide/cargo-home/bin/cargo" "+c066bf6804adc55193b71afdff6a56922f4a8689" "test" "--frozen" "--no-run" "--message-format=json" "--target" "x86_64-unknown-linux-musl", kill_on_drop: false }`
[INFO] [stdout] 92f5fb0f5985db7f61d92931f49bf4171e00e82999314f75b6c553727a01717c
[INFO] running `Command { std: "docker" "start" "-a" "92f5fb0f5985db7f61d92931f49bf4171e00e82999314f75b6c553727a01717c", kill_on_drop: false }`
[INFO] [stderr]    Compiling rustix v0.38.39
[INFO] [stderr]    Compiling linux-raw-sys v0.4.14
[INFO] [stderr]    Compiling bitflags v2.6.0
[INFO] [stderr]    Compiling fastrand v2.1.1
[INFO] [stderr]    Compiling once_cell v1.20.2
[INFO] [stderr]    Compiling zfs-rogue-snaps v2.0.1 (/opt/rustwide/workdir/zfs-rogue-snaps)
[INFO] [stderr]    Compiling zfs-real-usage v2.0.1 (/opt/rustwide/workdir/zfs-real-usage)
[INFO] [stderr]    Compiling zfs-touch-from-snap v2.0.2 (/opt/rustwide/workdir/zfs-touch-from-snap)
[INFO] [stderr]    Compiling zp v2.0.0 (/opt/rustwide/workdir/zp)
[INFO] [stderr]    Compiling zfs-remove-snaps v2.0.1 (/opt/rustwide/workdir/zfs-remove-snaps)
[INFO] [stderr]    Compiling zfs-snap v2.0.1 (/opt/rustwide/workdir/zfs-snap)
[INFO] [stderr]    Compiling tempfile v3.13.0
[INFO] [stderr]    Compiling zr v2.0.1 (/opt/rustwide/workdir/zr)
[INFO] [stderr]    Compiling common v2.0.0 (/opt/rustwide/workdir/common)
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 8.46s
[INFO] running `Command { std: "docker" "inspect" "92f5fb0f5985db7f61d92931f49bf4171e00e82999314f75b6c553727a01717c", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "92f5fb0f5985db7f61d92931f49bf4171e00e82999314f75b6c553727a01717c", kill_on_drop: false }`
[INFO] [stdout] 92f5fb0f5985db7f61d92931f49bf4171e00e82999314f75b6c553727a01717c
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-tc2/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:90999bfc7ae267e83380e433d8e61a7c072ca6729e92edbae886d3423b3a6f4c" "/opt/rustwide/cargo-home/bin/cargo" "+c066bf6804adc55193b71afdff6a56922f4a8689" "test" "--frozen" "--target" "x86_64-unknown-linux-musl", kill_on_drop: false }`
[INFO] [stdout] 390a2a7fe589cd5887a54de07d5b6ac8092dc5b3317df2dea3d2e0bbe5d9af55
[INFO] running `Command { std: "docker" "start" "-a" "390a2a7fe589cd5887a54de07d5b6ac8092dc5b3317df2dea3d2e0bbe5d9af55", kill_on_drop: false }`
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.13s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/x86_64-unknown-linux-musl/debug/deps/common-53f6696b45636d6e)
[INFO] [stdout] 
[INFO] [stdout] running 16 tests
[INFO] [stdout] test file_copier::tests::test_copy_file_action_verbose ... ok
[INFO] [stdout] test zfs_file::test::test_files_to_datasets ... ok
[INFO] [stdout] test zfs_info::test::test_zfs_mounts ... ok
[INFO] [stdout] test file_copier::tests::test_copy_file_with_noop ... ok
[INFO] [stdout] test rules::tests::test_contains_match ... ok
[INFO] [stdout] test rules::tests::test_empty_rules ... ok
[INFO] [stdout] test rules::tests::test_exact_match ... ok
[INFO] [stdout] test rules::tests::test_multiple_rules ... ok
[INFO] [stdout] test rules::tests::test_prefix_match ... ok
[INFO] [stdout] test rules::tests::test_suffix_match ... ok
[INFO] [stdout] test file_copier::tests::test_copy_file_with_noclobber ... ok
[INFO] [stdout] test zfs_file::test::test_file_to_dataset ... ok
[INFO] [stdout] test file_copier::tests::test_copy_directory_with_files ... ok
[INFO] [stdout] test file_copier::tests::test_copy_file_without_noclobber ... ok
[INFO] [stdout] test zfs_info::test::test_dataset_list_recursive ... ok
[INFO] [stderr]      Running unittests src/main.rs (/opt/rustwide/target/x86_64-unknown-linux-musl/debug/deps/zfs_real_usage-8456f83dc7001b86)
[INFO] [stdout] test command_helpers::test::test_output_as_lines ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 16 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.02s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stdout] test test::test_parse_line ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]      Running unittests src/main.rs (/opt/rustwide/target/x86_64-unknown-linux-musl/debug/deps/zfs_remove_snaps-5b054366f8e1240f)
[INFO] [stdout] 
[INFO] [stdout] running 2 tests
[INFO] [stdout] test test::test_filter_by_snap_name ... ok
[INFO] [stdout] test test::test_filter_by_fs_name ... 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 unittests src/main.rs (/opt/rustwide/target/x86_64-unknown-linux-musl/debug/deps/zfs_rogue_snaps-97197a78f0acd07e)
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stdout] test test::test_find_rogue_snapshots ... ok
[INFO] [stderr]      Running unittests src/main.rs (/opt/rustwide/target/x86_64-unknown-linux-musl/debug/deps/zfs_snap-d2a1536f6440db3d)
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 2 tests
[INFO] [stdout] test test::test_omit_filesystems ... ok
[INFO] [stdout] test test::test_snapname ... ok
[INFO] [stdout] 
[INFO] [stderr]      Running unittests src/main.rs (/opt/rustwide/target/x86_64-unknown-linux-musl/debug/deps/zfs_touch_from_snap-4a125f672d708184)
[INFO] [stdout] test result: ok. 2 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 2 tests
[INFO] [stderr]      Running unittests src/main.rs (/opt/rustwide/target/x86_64-unknown-linux-musl/debug/deps/zp-b7df623e21005f02)
[INFO] [stdout] test test::test_default_snapname ... ok
[INFO] [stdout] test test::test_timestamps_for ... 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] [stdout] 
[INFO] [stdout] running 2 tests
[INFO] [stdout] test test::test_in_snapshot ... ok
[INFO] [stdout] test test::test_target_file ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 2 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s
[INFO] [stdout] 
[INFO] [stderr]      Running unittests src/main.rs (/opt/rustwide/target/x86_64-unknown-linux-musl/debug/deps/zr-59e430c96063909a)
[INFO] [stdout] 
[INFO] [stdout] running 6 tests
[INFO] [stdout] test test::test_candidates ... ok
[INFO] [stdout] test test::test_path_relative_to_fs_root ... ok
[INFO] [stdout] test user_interaction::test::test_basic_line ... ok
[INFO] [stdout] test user_interaction::test::test_parse_choice ... ok
[INFO] [stdout] test test::test_restore_action_no_candidates ... ok
[INFO] [stdout] test test::test_restore_action_auto_mode ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 6 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.02s
[INFO] [stdout] 
[INFO] running `Command { std: "docker" "inspect" "390a2a7fe589cd5887a54de07d5b6ac8092dc5b3317df2dea3d2e0bbe5d9af55", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "390a2a7fe589cd5887a54de07d5b6ac8092dc5b3317df2dea3d2e0bbe5d9af55", kill_on_drop: false }`
[INFO] [stdout] 390a2a7fe589cd5887a54de07d5b6ac8092dc5b3317df2dea3d2e0bbe5d9af55