[INFO] cloning repository https://github.com/RouHim/zpool-rw-meter
[INFO] running `Command { std: "git" "-c" "credential.helper=" "-c" "credential.helper=/workspace/cargo-home/bin/git-credential-null" "clone" "--bare" "https://github.com/RouHim/zpool-rw-meter" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2FRouHim%2Fzpool-rw-meter", kill_on_drop: false }`
[INFO] [stderr] Cloning into bare repository '/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2FRouHim%2Fzpool-rw-meter'...
[INFO] running `Command { std: "git" "rev-parse" "HEAD", kill_on_drop: false }`
[INFO] [stdout] 8bc62b56dcc4f0f8ed24fd7e3d0418c9c914413a
[INFO] testing RouHim/zpool-rw-meter against beta-2025-10-28 for beta-1.92-2
[INFO] running `Command { std: "git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2FRouHim%2Fzpool-rw-meter" "/workspace/builds/worker-1-tc2/source", kill_on_drop: false }`
[INFO] [stderr] Cloning into '/workspace/builds/worker-1-tc2/source'...
[INFO] [stderr] done.
[INFO] started tweaking git repo https://github.com/RouHim/zpool-rw-meter
[INFO] finished tweaking git repo https://github.com/RouHim/zpool-rw-meter
[INFO] tweaked toml for git repo https://github.com/RouHim/zpool-rw-meter written to /workspace/builds/worker-1-tc2/source/Cargo.toml
[INFO] validating manifest of git repo https://github.com/RouHim/zpool-rw-meter on toolchain beta-2025-10-28
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+beta-2025-10-28" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate git repo https://github.com/RouHim/zpool-rw-meter 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" "+beta-2025-10-28" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-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:4848fb76d95f26979359cc7e45710b1dbc8f3acb7aeedee7c460d7702230f228" "/opt/rustwide/cargo-home/bin/cargo" "+beta-2025-10-28" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] 25a3533bc7434cfe5bfd11faa3bcddf5f777aa97fa3382edd102112ebaaf08a4
[INFO] running `Command { std: "docker" "start" "-a" "25a3533bc7434cfe5bfd11faa3bcddf5f777aa97fa3382edd102112ebaaf08a4", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "25a3533bc7434cfe5bfd11faa3bcddf5f777aa97fa3382edd102112ebaaf08a4", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "25a3533bc7434cfe5bfd11faa3bcddf5f777aa97fa3382edd102112ebaaf08a4", kill_on_drop: false }`
[INFO] [stdout] 25a3533bc7434cfe5bfd11faa3bcddf5f777aa97fa3382edd102112ebaaf08a4
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-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=warn" "-e" "RUSTDOCFLAGS=--cap-lints=warn" "-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:4848fb76d95f26979359cc7e45710b1dbc8f3acb7aeedee7c460d7702230f228" "/opt/rustwide/cargo-home/bin/cargo" "+beta-2025-10-28" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] d53fb3b42d829f638897cc51cd12df7a129c001fef4835624b8f3f9b659f7169
[INFO] running `Command { std: "docker" "start" "-a" "d53fb3b42d829f638897cc51cd12df7a129c001fef4835624b8f3f9b659f7169", kill_on_drop: false }`
[INFO] [stderr]    Compiling libc v0.2.175
[INFO] [stderr]    Compiling rustix v0.38.44
[INFO] [stderr]    Compiling linux-raw-sys v0.4.15
[INFO] [stderr]    Compiling procfs v0.16.0
[INFO] [stderr]    Compiling unicode-width v0.2.1
[INFO] [stderr]    Compiling nix v0.29.0
[INFO] [stderr]    Compiling syn v2.0.106
[INFO] [stderr]    Compiling procfs-core v0.16.0
[INFO] [stderr]    Compiling mio v1.0.4
[INFO] [stderr]    Compiling signal-hook-registry v1.4.6
[INFO] [stderr]    Compiling console v0.15.11
[INFO] [stderr]    Compiling tokio-macros v2.5.0
[INFO] [stderr]    Compiling async-trait v0.1.89
[INFO] [stderr]    Compiling tokio v1.47.1
[INFO] [stderr]    Compiling zfs-cache-monitor-rs v0.1.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 15.52s
[INFO] running `Command { std: "docker" "inspect" "d53fb3b42d829f638897cc51cd12df7a129c001fef4835624b8f3f9b659f7169", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "d53fb3b42d829f638897cc51cd12df7a129c001fef4835624b8f3f9b659f7169", kill_on_drop: false }`
[INFO] [stdout] d53fb3b42d829f638897cc51cd12df7a129c001fef4835624b8f3f9b659f7169
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-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=warn" "-e" "RUSTDOCFLAGS=--cap-lints=warn" "-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:4848fb76d95f26979359cc7e45710b1dbc8f3acb7aeedee7c460d7702230f228" "/opt/rustwide/cargo-home/bin/cargo" "+beta-2025-10-28" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] ce6c312c989f6fae78b1718ac191b0014ae209ec08f4e3d437ea730509ca5eae
[INFO] running `Command { std: "docker" "start" "-a" "ce6c312c989f6fae78b1718ac191b0014ae209ec08f4e3d437ea730509ca5eae", kill_on_drop: false }`
[INFO] [stderr]    Compiling zfs-cache-monitor-rs v0.1.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 2.06s
[INFO] running `Command { std: "docker" "inspect" "ce6c312c989f6fae78b1718ac191b0014ae209ec08f4e3d437ea730509ca5eae", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "ce6c312c989f6fae78b1718ac191b0014ae209ec08f4e3d437ea730509ca5eae", kill_on_drop: false }`
[INFO] [stdout] ce6c312c989f6fae78b1718ac191b0014ae209ec08f4e3d437ea730509ca5eae
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-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=warn" "-e" "RUSTDOCFLAGS=--cap-lints=warn" "-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:4848fb76d95f26979359cc7e45710b1dbc8f3acb7aeedee7c460d7702230f228" "/opt/rustwide/cargo-home/bin/cargo" "+beta-2025-10-28" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] a9883815c1b12684c341941f33ef525a52edbd31581d49f3ee6b8ed816e847b9
[INFO] running `Command { std: "docker" "start" "-a" "a9883815c1b12684c341941f33ef525a52edbd31581d49f3ee6b8ed816e847b9", kill_on_drop: false }`
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.08s
[INFO] [stderr]      Running unittests src/main.rs (/opt/rustwide/target/debug/deps/zfs_cache_monitor_rs-3f14928c50b17904)
[INFO] [stdout] 
[INFO] [stdout] running 52 tests
[INFO] [stdout] test display::formatter::tests::test_format_bytes ... ok
[INFO] [stdout] test display::formatter::tests::test_format_bytes_ratio ... ok
[INFO] [stdout] test display::formatter::tests::test_format_ops_per_second ... ok
[INFO] [stdout] test display::progress::tests::test_progress_bar_empty ... ok
[INFO] [stdout] test display::progress::tests::test_progress_bar_full ... ok
[INFO] [stdout] test system::cache::tests::test_cache_default_ttl ... ok
[INFO] [stdout] test system::cache::tests::test_cache_insert_and_get ... ok
[INFO] [stdout] test system::cache::tests::test_cache_overwrite ... ok
[INFO] [stdout] test system::cache::tests::test_cache_unicode_keys ... ok
[INFO] [stdout] test system::cache::tests::test_cache_with_complex_types ... ok
[INFO] [stdout] test system::cache::tests::test_cache_zero_ttl ... ok
[INFO] [stdout] test zfs::rate_calculator::tests::test_decreasing_rate_calculation ... ok
[INFO] [stdout] test zfs::rate_calculator::tests::test_empty_key_handling ... ok
[INFO] [stdout] test zfs::rate_calculator::tests::test_calculate_rate_without_update ... ok
[INFO] [stdout] test zfs::rate_calculator::tests::test_different_keys ... ok
[INFO] [stdout] test zfs::rate_calculator::tests::test_first_measurement_returns_none ... ok
[INFO] [stdout] test display::formatter::tests::test_format_latency_ms ... ok
[INFO] [stdout] test zfs::rate_calculator::tests::test_update_overwrites_previous_values ... ok
[INFO] [stdout] test zfs::rate_calculator::tests::test_very_large_rates ... ok
[INFO] [stdout] test zfs::rate_calculator::tests::test_precision_with_small_deltas ... ok
[INFO] [stdout] test zfs::stats::tests::test_parse_arcstat_output_edge_cases ... ok
[INFO] [stdout] test zfs::rate_calculator::tests::test_unicode_keys ... ok
[INFO] [stdout] test zfs::rate_calculator::tests::test_zero_time_delta ... ok
[INFO] [stdout] test zfs::stats::tests::test_parse_arcstat_output_insufficient_parts ... ok
[INFO] [stdout] test zfs::stats::tests::test_parse_arcstat_output_invalid_hit_rate ... ok
[INFO] [stdout] test zfs::stats::tests::test_collect_slog_stats_cached ... ok
[INFO] [stdout] test zfs::stats::tests::test_parse_arcstat_output_valid ... ok
[INFO] [stdout] test zfs::stats::tests::test_collect_arc_stats_from_proc_success ... ok
[INFO] [stdout] test zfs::stats::tests::test_parse_arcstat_output_invalid_read_ops ... ok
[INFO] [stdout] test zfs::stats::tests::test_parse_arcstat_output_invalid_size ... ok
[INFO] [stdout] test zfs::stats::tests::test_parse_arcstat_output_invalid_target ... ok
[INFO] [stdout] test zfs::stats::tests::test_parse_bandwidth_bytes ... ok
[INFO] [stdout] test zfs::stats::tests::test_parse_bandwidth_edge_cases ... ok
[INFO] [stdout] test zfs::stats::tests::test_parse_bandwidth_empty_or_dash ... ok
[INFO] [stdout] test zfs::stats::tests::test_parse_bandwidth_invalid_unit ... ok
[INFO] [stdout] test zfs::stats::tests::test_parse_slog_device_from_status ... ok
[INFO] [stdout] test zfs::stats::tests::test_parse_bandwidth_invalid_number ... ok
[INFO] [stdout] test zfs::stats::tests::test_parse_slog_stats_from_iostat_invalid_data ... ok
[INFO] [stdout] test zfs::stats::tests::test_parse_slog_stats_from_iostat_invalid_bandwidth ... ok
[INFO] [stdout] test zfs::stats::tests::test_parse_bandwidth_with_units ... ok
[INFO] [stdout] test zfs::stats::tests::test_parse_slog_stats_from_iostat ... ok
[INFO] [stdout] test zfs::rate_calculator::tests::test_rate_calculation_with_fractional_seconds ... ok
[INFO] [stdout] test system::cache::tests::test_cache_very_short_ttl ... ok
[INFO] [stdout] test zfs::stats::tests::test_parse_slog_stats_from_iostat_no_device ... ok
[INFO] [stdout] test zfs::stats::tests::test_collect_arc_stats_from_arcstat_fallback ... ok
[INFO] [stdout] test zfs::rate_calculator::tests::test_zero_rate_calculation ... ok
[INFO] [stdout] test display::progress::tests::test_progress_bar_half ... ok
[INFO] [stdout] test system::cache::tests::test_cache_custom_ttl ... ok
[INFO] [stdout] test zfs::rate_calculator::tests::test_calculate_and_update ... ok
[INFO] [stdout] test system::cache::tests::test_cache_expiration ... ok
[INFO] [stdout] test zfs::rate_calculator::tests::test_rate_calculation ... ok
[INFO] [stdout] test display::formatter::tests::test_format_rate ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 52 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.20s
[INFO] [stdout] 
[INFO] running `Command { std: "docker" "inspect" "a9883815c1b12684c341941f33ef525a52edbd31581d49f3ee6b8ed816e847b9", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "a9883815c1b12684c341941f33ef525a52edbd31581d49f3ee6b8ed816e847b9", kill_on_drop: false }`
[INFO] [stdout] a9883815c1b12684c341941f33ef525a52edbd31581d49f3ee6b8ed816e847b9