[INFO] fetching crate saku-storage 0.4.0...
[INFO] testing saku-storage-0.4.0 against master#562dee4820c458d823175268e41601d4c060588a for pr-154210-2
[INFO] extracting crate saku-storage 0.4.0 into /workspace/builds/worker-2-tc1/source
[INFO] started tweaking crates.io crate saku-storage 0.4.0
[INFO] finished tweaking crates.io crate saku-storage 0.4.0
[INFO] tweaked toml for crates.io crate saku-storage 0.4.0 written to /workspace/builds/worker-2-tc1/source/Cargo.toml
[INFO] validating manifest of crates.io crate saku-storage 0.4.0 on toolchain 562dee4820c458d823175268e41601d4c060588a
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+562dee4820c458d823175268e41601d4c060588a" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate crates.io crate saku-storage 0.4.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" "+562dee4820c458d823175268e41601d4c060588a" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr]  Downloading crates ...
[INFO] [stderr]   Downloaded tempfile v3.25.0
[INFO] [stderr]   Downloaded libc v0.2.181
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-tc1/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:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+562dee4820c458d823175268e41601d4c060588a" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] 0c4a7eb65f663a00439e3ba3f05c7850e05e1f8a1c56b97052d09b6fcb94a60a
[INFO] running `Command { std: "docker" "start" "-a" "0c4a7eb65f663a00439e3ba3f05c7850e05e1f8a1c56b97052d09b6fcb94a60a", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "0c4a7eb65f663a00439e3ba3f05c7850e05e1f8a1c56b97052d09b6fcb94a60a", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "0c4a7eb65f663a00439e3ba3f05c7850e05e1f8a1c56b97052d09b6fcb94a60a", kill_on_drop: false }`
[INFO] [stdout] 0c4a7eb65f663a00439e3ba3f05c7850e05e1f8a1c56b97052d09b6fcb94a60a
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-tc1/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:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+562dee4820c458d823175268e41601d4c060588a" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 69ba181c8c998901f0ba366e29466f590459d3e76691e5713edac9a3227a64e3
[INFO] running `Command { std: "docker" "start" "-a" "69ba181c8c998901f0ba366e29466f590459d3e76691e5713edac9a3227a64e3", kill_on_drop: false }`
[INFO] [stderr]    Compiling libc v0.2.181
[INFO] [stderr]    Compiling getrandom v0.3.4
[INFO] [stderr]    Compiling proc-macro2 v1.0.106
[INFO] [stderr]    Compiling serde_json v1.0.149
[INFO] [stderr]    Compiling jiff v0.2.20
[INFO] [stderr]    Compiling digest v0.10.7
[INFO] [stderr]    Compiling sha2 v0.10.9
[INFO] [stderr]    Compiling quote v1.0.44
[INFO] [stderr]    Compiling syn v2.0.115
[INFO] [stderr]    Compiling dirs-sys v0.5.0
[INFO] [stderr]    Compiling fs2 v0.4.3
[INFO] [stderr]    Compiling dirs v6.0.0
[INFO] [stderr]    Compiling uuid v1.20.0
[INFO] [stderr]    Compiling thiserror-impl v2.0.18
[INFO] [stderr]    Compiling serde_derive v1.0.228
[INFO] [stderr]    Compiling thiserror v2.0.18
[INFO] [stderr]    Compiling serde v1.0.228
[INFO] [stderr]    Compiling saku-storage v0.4.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 11.36s
[INFO] running `Command { std: "docker" "inspect" "69ba181c8c998901f0ba366e29466f590459d3e76691e5713edac9a3227a64e3", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "69ba181c8c998901f0ba366e29466f590459d3e76691e5713edac9a3227a64e3", kill_on_drop: false }`
[INFO] [stdout] 69ba181c8c998901f0ba366e29466f590459d3e76691e5713edac9a3227a64e3
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-tc1/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:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+562dee4820c458d823175268e41601d4c060588a" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 903186c5c42c989a01d1309672d8e3c03ce2001a7aab229220b7e03ed0733edd
[INFO] running `Command { std: "docker" "start" "-a" "903186c5c42c989a01d1309672d8e3c03ce2001a7aab229220b7e03ed0733edd", kill_on_drop: false }`
[INFO] [stderr]    Compiling rustix v1.1.3
[INFO] [stderr]    Compiling linux-raw-sys v0.11.0
[INFO] [stderr]    Compiling tempfile v3.25.0
[INFO] [stderr]    Compiling saku-storage v0.4.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 5.41s
[INFO] running `Command { std: "docker" "inspect" "903186c5c42c989a01d1309672d8e3c03ce2001a7aab229220b7e03ed0733edd", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "903186c5c42c989a01d1309672d8e3c03ce2001a7aab229220b7e03ed0733edd", kill_on_drop: false }`
[INFO] [stdout] 903186c5c42c989a01d1309672d8e3c03ce2001a7aab229220b7e03ed0733edd
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-tc1/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:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+562dee4820c458d823175268e41601d4c060588a" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] 2923a52681df257ede3aa0aad4e0b76697f3ef754e4aaddcf27b14e389c6c3e5
[INFO] running `Command { std: "docker" "start" "-a" "2923a52681df257ede3aa0aad4e0b76697f3ef754e4aaddcf27b14e389c6c3e5", 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/debug/deps/saku_storage-845c0e58853b5564)
[INFO] [stdout] 
[INFO] [stdout] running 53 tests
[INFO] [stdout] test device::tests::returns_same_id_on_subsequent_reads ... ok
[INFO] [stdout] test device::tests::creates_device_id_on_first_run ... ok
[INFO] [stdout] test dirty_tracker::tests::clear_dirty_removes_keys ... ok
[INFO] [stdout] test dirty_tracker::tests::load_missing_file_returns_empty ... ok
[INFO] [stdout] test dirty_tracker::tests::mark_dirty_is_idempotent ... ok
[INFO] [stdout] test dirty_tracker::tests::load_corrupt_file_returns_empty ... ok
[INFO] [stdout] test dirty_tracker::tests::clear_all_empties_set ... ok
[INFO] [stdout] test dirty_tracker::tests::sidecar_path_from_store_path ... ok
[INFO] [stdout] test entity::tests::natural_key_is_lowercase ... ok
[INFO] [stdout] test entity::tests::storage_key_combines_type_and_natural_key ... ok
[INFO] [stdout] test dirty_tracker::tests::mark_dirty_adds_key ... ok
[INFO] [stdout] test key_gen::tests::generate_task_key_different_timestamps_produce_different_keys ... ok
[INFO] [stdout] test dirty_tracker::tests::mark_dirty_many ... ok
[INFO] [stdout] test key_gen::tests::generate_task_key_is_deterministic ... ok
[INFO] [stdout] test key_gen::tests::generate_task_key_is_not_empty ... ok
[INFO] [stdout] test device::tests::saku_data_dir_returns_path ... ok
[INFO] [stdout] test dirty_tracker::tests::save_and_load_roundtrip ... ok
[INFO] [stdout] test io::atomic_writer::tests::writes_content_atomically ... ok
[INFO] [stdout] test io::backup::tests::backup_dir_is_sibling ... ok
[INFO] [stdout] test io::atomic_writer::tests::overwrites_existing_file ... ok
[INFO] [stdout] test io::backup::tests::no_backup_when_source_missing ... ok
[INFO] [stdout] test io::file_lock::tests::acquire_and_release ... ok
[INFO] [stdout] test key_gen::tests::base36_encode_small_values ... ok
[INFO] [stdout] test key_gen::tests::base36_encode_zero ... ok
[INFO] [stdout] test kv_store::tests::compare_modified_at_equal_timestamps ... ok
[INFO] [stdout] test key_gen::tests::generate_task_key_different_devices_produce_different_keys ... ok
[INFO] [stdout] test kv_store::tests::compare_modified_at_higher_wall_ms_wins ... ok
[INFO] [stdout] test kv_store::tests::compare_modified_at_missing_field_returns_equal ... ok
[INFO] [stdout] test kv_store::tests::gc_tombstones_keeps_all_when_young ... ok
[INFO] [stdout] test kv_store::tests::gc_tombstones_removes_old_tombstones ... ok
[INFO] [stdout] test kv_store::tests::lww_merge_kv_new_keys_from_both_sides ... ok
[INFO] [stdout] test kv_store::tests::lww_merge_kv_live_wins_over_tombstone_when_newer ... ok
[INFO] [stdout] test kv_store::tests::lww_merge_kv_picks_higher_version ... ok
[INFO] [stdout] test kv_store::tests::lww_merge_kv_same_key_higher_wins ... ok
[INFO] [stdout] test kv_store::tests::lww_merge_kv_same_key_local_wins_when_newer ... ok
[INFO] [stdout] test kv_store::tests::reconcile_renames_chain_gets_shortcut ... ok
[INFO] [stdout] test kv_store::tests::lww_merge_kv_tombstone_wins_over_live_when_newer ... ok
[INFO] [stdout] test kv_store::tests::reconcile_renames_single_rename_is_noop ... ok
[INFO] [stdout] test kv_store::tests::repair_references_fixes_dangling_single_ref ... ok
[INFO] [stdout] test timestamp::tests::equality ... ok
[INFO] [stdout] test kv_store::tests::repair_references_fixes_array_refs ... ok
[INFO] [stdout] test kv_store::tests::repair_references_follows_chain ... ok
[INFO] [stdout] test timestamp::tests::ordering_lamport_breaks_wall_ms_tie ... ok
[INFO] [stdout] test timestamp::tests::from_jiff_produces_correct_wall_ms ... ok
[INFO] [stdout] test kv_store::tests::repair_references_no_renames_is_noop ... ok
[INFO] [stdout] test timestamp::tests::ordering_wall_ms_takes_priority ... ok
[INFO] [stdout] test timestamp::tests::ordering_device_id_breaks_lamport_tie ... ok
[INFO] [stdout] test timestamp::tests::default_is_zero ... ok
[INFO] [stdout] test timestamp::tests::serde_roundtrip ... ok
[INFO] [stdout] test key_gen::tests::base36_encode_large_value ... ok
[INFO] [stdout] test dirty_tracker::tests::new_tracker_is_empty ... ok
[INFO] [stdout] test dirty_tracker::tests::set_cookie_updates_value ... ok
[INFO] [stdout] test io::backup::tests::creates_backup_and_cleans_up ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 53 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.08s
[INFO] [stdout] 
[INFO] [stderr]    Doc-tests saku_storage
[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] running `Command { std: "docker" "inspect" "2923a52681df257ede3aa0aad4e0b76697f3ef754e4aaddcf27b14e389c6c3e5", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "2923a52681df257ede3aa0aad4e0b76697f3ef754e4aaddcf27b14e389c6c3e5", kill_on_drop: false }`
[INFO] [stdout] 2923a52681df257ede3aa0aad4e0b76697f3ef754e4aaddcf27b14e389c6c3e5