[INFO] fetching crate nano-wal 0.5.0...
[INFO] testing nano-wal-0.5.0 against try#db823df02fd0c2cf67b43025ac3fef3f2d743245 for pr-150681
[INFO] extracting crate nano-wal 0.5.0 into /workspace/builds/worker-2-tc2/source
[INFO] started tweaking crates.io crate nano-wal 0.5.0
[INFO] removed 0 missing examples
[INFO] removed 0 missing tests
[INFO] finished tweaking crates.io crate nano-wal 0.5.0
[INFO] tweaked toml for crates.io crate nano-wal 0.5.0 written to /workspace/builds/worker-2-tc2/source/Cargo.toml
[INFO] validating manifest of crates.io crate nano-wal 0.5.0 on toolchain db823df02fd0c2cf67b43025ac3fef3f2d743245
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+db823df02fd0c2cf67b43025ac3fef3f2d743245" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate crates.io crate nano-wal 0.5.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" "+db823df02fd0c2cf67b43025ac3fef3f2d743245" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[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:845e597a41426bbf2703be69acdb67d10b6de511142d05cba7bbe119c898b2c7" "/opt/rustwide/cargo-home/bin/cargo" "+db823df02fd0c2cf67b43025ac3fef3f2d743245" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] 9051d5bd3d0221588be9f6891b46efa6658fb82be470981f5a37cab58332674e
[INFO] running `Command { std: "docker" "start" "-a" "9051d5bd3d0221588be9f6891b46efa6658fb82be470981f5a37cab58332674e", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "9051d5bd3d0221588be9f6891b46efa6658fb82be470981f5a37cab58332674e", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "9051d5bd3d0221588be9f6891b46efa6658fb82be470981f5a37cab58332674e", kill_on_drop: false }`
[INFO] [stdout] 9051d5bd3d0221588be9f6891b46efa6658fb82be470981f5a37cab58332674e
[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:845e597a41426bbf2703be69acdb67d10b6de511142d05cba7bbe119c898b2c7" "/opt/rustwide/cargo-home/bin/cargo" "+db823df02fd0c2cf67b43025ac3fef3f2d743245" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 669867de8b1ef5246810d2bf51b50ec2bf21d87e950454bfa147036e13b08e68
[INFO] running `Command { std: "docker" "start" "-a" "669867de8b1ef5246810d2bf51b50ec2bf21d87e950454bfa147036e13b08e68", kill_on_drop: false }`
[INFO] [stderr]    Compiling serde v1.0.219
[INFO] [stderr]    Compiling chrono v0.4.41
[INFO] [stderr]    Compiling nano-wal v0.5.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 5.31s
[INFO] running `Command { std: "docker" "inspect" "669867de8b1ef5246810d2bf51b50ec2bf21d87e950454bfa147036e13b08e68", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "669867de8b1ef5246810d2bf51b50ec2bf21d87e950454bfa147036e13b08e68", kill_on_drop: false }`
[INFO] [stdout] 669867de8b1ef5246810d2bf51b50ec2bf21d87e950454bfa147036e13b08e68
[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:845e597a41426bbf2703be69acdb67d10b6de511142d05cba7bbe119c898b2c7" "/opt/rustwide/cargo-home/bin/cargo" "+db823df02fd0c2cf67b43025ac3fef3f2d743245" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] fb6ec04c8b4023937bc6f726b307338ef3c0c07d131bc0bc284f137549e75127
[INFO] running `Command { std: "docker" "start" "-a" "fb6ec04c8b4023937bc6f726b307338ef3c0c07d131bc0bc284f137549e75127", kill_on_drop: false }`
[INFO] [stderr]    Compiling proc-macro2 v1.0.101
[INFO] [stderr]    Compiling getrandom v0.3.3
[INFO] [stderr]    Compiling rustix v1.0.8
[INFO] [stderr]    Compiling is-terminal v0.4.16
[INFO] [stderr]    Compiling uuid v1.18.1
[INFO] [stderr]    Compiling quote v1.0.40
[INFO] [stderr]    Compiling syn v2.0.106
[INFO] [stderr]    Compiling tempfile v3.21.0
[INFO] [stderr]    Compiling serde_derive v1.0.219
[INFO] [stderr]    Compiling serde v1.0.219
[INFO] [stderr]    Compiling serde_json v1.0.143
[INFO] [stderr]    Compiling chrono v0.4.41
[INFO] [stderr]    Compiling ciborium v0.2.2
[INFO] [stderr]    Compiling tinytemplate v1.2.1
[INFO] [stderr]    Compiling criterion v0.5.1
[INFO] [stderr]    Compiling nano-wal v0.5.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 19.06s
[INFO] running `Command { std: "docker" "inspect" "fb6ec04c8b4023937bc6f726b307338ef3c0c07d131bc0bc284f137549e75127", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "fb6ec04c8b4023937bc6f726b307338ef3c0c07d131bc0bc284f137549e75127", kill_on_drop: false }`
[INFO] [stdout] fb6ec04c8b4023937bc6f726b307338ef3c0c07d131bc0bc284f137549e75127
[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:845e597a41426bbf2703be69acdb67d10b6de511142d05cba7bbe119c898b2c7" "/opt/rustwide/cargo-home/bin/cargo" "+db823df02fd0c2cf67b43025ac3fef3f2d743245" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] dd6c87529a54bf707fa8bcc760e1ad501896b7df76080e8c6162628bf061cb84
[INFO] running `Command { std: "docker" "start" "-a" "dd6c87529a54bf707fa8bcc760e1ad501896b7df76080e8c6162628bf061cb84", kill_on_drop: false }`
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.11s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/nano_wal-1d87f87aaade9417)
[INFO] [stdout] 
[INFO] [stderr]      Running tests/advanced_tests.rs (/opt/rustwide/target/debug/deps/advanced_tests-b2cd4316217cbfc5)
[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 9 tests
[INFO] [stdout] test test_error_handling_invalid_config ... ok
[INFO] [stdout] test test_wal_options_builder_methods ... ok
[INFO] [stdout] test test_segment_rotation_time_based ... ok
[INFO] [stdout] test test_empty_and_large_content ... ok
[INFO] [stdout] test test_segment_id_progression ... ok
[INFO] [stdout] test test_special_characters_in_keys ... ok
[INFO] [stdout] test test_large_number_of_entries ... ok
[INFO] [stdout] test test_concurrent_like_operations ... ok
[INFO] [stdout] test test_compaction ... ok
[INFO] [stderr]      Running tests/basic_tests.rs (/opt/rustwide/target/debug/deps/basic_tests-cf5a6107b945453b)
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 9 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 3.11s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 11 tests
[INFO] [stdout] test test_append_and_log ... ok
[INFO] [stdout] test test_enumerate_keys ... ok
[INFO] [stdout] test test_entry_count ... ok
[INFO] [stdout] test test_empty_wal_operations ... ok
[INFO] [stdout] test test_new_and_shutdown ... ok
[INFO] [stdout] test test_append_and_enumerate ... ok
[INFO] [stdout] test test_sync ... ok
[INFO] [stdout] test test_multiple_records_same_key ... ok
[INFO] [stdout] test test_log_file_extension ... ok
[INFO] [stdout] test test_header_functionality ... ok
[INFO] [stdout] test test_key_in_filename ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 11 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.11s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/crash_test.rs (/opt/rustwide/target/debug/deps/crash_test-fd57fb34c7ed34a5)
[INFO] [stdout] 
[INFO] [stdout] running 5 tests
[INFO] [stdout] test test_extreme_crash_simulation ... ok
[INFO] [stdout] test test_crash_with_forced_termination ... ok
[INFO] [stdout] test test_crash_durability ... ok
[INFO] [stdout] test test_original_crash_requirements ... ok
[INFO] [stdout] test test_crash_durability_multiple_runs ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 5 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 17.63s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/integration_tests.rs (/opt/rustwide/target/debug/deps/integration_tests-6488e36e67842d04)
[INFO] [stdout] 
[INFO] [stdout] running 7 tests
[INFO] [stdout] test test_log_file_naming_with_meaningful_keys ... ok
[INFO] [stdout] test test_real_world_event_sourcing_scenario ... ok
[INFO] [stdout] test test_database_wal_simulation ... ok
[INFO] [stdout] test test_cache_warming_scenario ... ok
[INFO] [stdout] test test_high_frequency_trading_logs ... ok
[INFO] [stdout] test test_message_queue_persistence ... ok
[INFO] [stdout] test test_audit_log_with_timestamps ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 7 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.07s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/persistence_tests.rs (/opt/rustwide/target/debug/deps/persistence_tests-d49eb10a5d38e5df)
[INFO] [stdout] 
[INFO] [stdout] running 4 tests
[INFO] [stdout] test test_crash_recovery_with_partial_writes ... ok
[INFO] [stdout] test test_multiple_restart_cycles ... ok
[INFO] [stdout] test test_persistence_across_restarts ... ok
[INFO] [stdout] test test_simulated_process_kill_persistence ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 4 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.11s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/random_access_tests.rs (/opt/rustwide/target/debug/deps/random_access_tests-f81f149db601d196)
[INFO] [stdout] 
[INFO] [stdout] running 13 tests
[INFO] [stdout] test test_read_entry_at_nonexistent_segment ... ok
[INFO] [stdout] test test_entry_ref_serialization_compatibility ... ok
[INFO] [stdout] test test_log_entry_returns_entry_ref ... ok
[INFO] [stdout] test test_read_entry_at_empty_content ... ok
[INFO] [stdout] test test_append_returns_entry_ref ... ok
[INFO] [stdout] test test_read_entry_at_after_restart ... ok
[INFO] [stdout] test test_read_entry_at_valid_reference ... ok
[INFO] [stdout] test test_read_entry_at_invalid_signature ... ok
[INFO] [stdout] test test_read_entry_at_large_content ... ok
[INFO] [stdout] test test_entry_ref_across_segment_rotation ... ok
[INFO] [stdout] test test_read_entry_at_with_different_key_types ... ok
[INFO] [stdout] test test_read_entry_at_multiple_entries ... ok
[INFO] [stdout] test test_random_access_performance_characteristics ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 13 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.19s
[INFO] [stdout] 
[INFO] [stderr]    Doc-tests nano_wal
[INFO] [stdout] 
[INFO] [stdout] running 15 tests
[INFO] [stdout] test src/lib.rs - (line 16) - compile ... ok
[INFO] [stdout] test src/lib.rs - Wal::new (line 273) - compile ... ok
[INFO] [stdout] test src/lib.rs - Wal::enumerate_records (line 630) - compile ... ok
[INFO] [stdout] test src/lib.rs - Wal::active_segment_count (line 886) - compile ... ok
[INFO] [stdout] test src/lib.rs - Wal::append_entry (line 429) - compile ... ok
[INFO] [stdout] test src/lib.rs - Wal::enumerate_keys (line 567) - compile ... ok
[INFO] [stdout] test src/lib.rs - Wal::compact (line 819) - compile ... ok
[INFO] [stdout] test src/lib.rs - Wal::shutdown (line 904) - compile ... ok
[INFO] [stdout] test src/lib.rs - Wal::sync (line 869) - compile ... ok
[INFO] [stdout] test src/lib.rs - Wal::log_entry (line 543) - compile ... ok
[INFO] [stdout] test src/lib.rs - Wal::append_batch (line 508) - compile ... ok
[INFO] [stdout] test src/lib.rs - Wal::read_entry_at (line 752) - compile ... ok
[INFO] [stdout] test src/lib.rs - WalOptions::with_retention (line 170) ... ok
[INFO] [stdout] test src/lib.rs - WalOptions::with_segments_per_retention_period (line 187) ... ok
[INFO] [stdout] test src/lib.rs - WalOptions (line 140) ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 15 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.36s
[INFO] [stdout] 
[INFO] running `Command { std: "docker" "inspect" "dd6c87529a54bf707fa8bcc760e1ad501896b7df76080e8c6162628bf061cb84", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "dd6c87529a54bf707fa8bcc760e1ad501896b7df76080e8c6162628bf061cb84", kill_on_drop: false }`
[INFO] [stdout] dd6c87529a54bf707fa8bcc760e1ad501896b7df76080e8c6162628bf061cb84