[INFO] fetching crate rewal 0.1.0...
[INFO] testing rewal-0.1.0 against master#562dee4820c458d823175268e41601d4c060588a for pr-154210-2
[INFO] extracting crate rewal 0.1.0 into /workspace/builds/worker-4-tc1/source
[INFO] started tweaking crates.io crate rewal 0.1.0
[INFO] finished tweaking crates.io crate rewal 0.1.0
[INFO] tweaked toml for crates.io crate rewal 0.1.0 written to /workspace/builds/worker-4-tc1/source/Cargo.toml
[INFO] validating manifest of crates.io crate rewal 0.1.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 rewal 0.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" "+562dee4820c458d823175268e41601d4c060588a" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-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] 8287a848d6b468cff67af1e265696b464e9e99ecd82b95b1a53da566e7f47dc0
[INFO] running `Command { std: "docker" "start" "-a" "8287a848d6b468cff67af1e265696b464e9e99ecd82b95b1a53da566e7f47dc0", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "8287a848d6b468cff67af1e265696b464e9e99ecd82b95b1a53da566e7f47dc0", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "8287a848d6b468cff67af1e265696b464e9e99ecd82b95b1a53da566e7f47dc0", kill_on_drop: false }`
[INFO] [stdout] 8287a848d6b468cff67af1e265696b464e9e99ecd82b95b1a53da566e7f47dc0
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-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] 1a197a9246c1e36f82ffc1f5dde6ab6d302f97e325f370767f5ff2cabd5587cc
[INFO] running `Command { std: "docker" "start" "-a" "1a197a9246c1e36f82ffc1f5dde6ab6d302f97e325f370767f5ff2cabd5587cc", kill_on_drop: false }`
[INFO] [stderr]    Compiling rewal v0.1.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 1.16s
[INFO] running `Command { std: "docker" "inspect" "1a197a9246c1e36f82ffc1f5dde6ab6d302f97e325f370767f5ff2cabd5587cc", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "1a197a9246c1e36f82ffc1f5dde6ab6d302f97e325f370767f5ff2cabd5587cc", kill_on_drop: false }`
[INFO] [stdout] 1a197a9246c1e36f82ffc1f5dde6ab6d302f97e325f370767f5ff2cabd5587cc
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-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] f9952d1cdc0249976db934166abc1298237e02d472809168c5b9ee988798308b
[INFO] running `Command { std: "docker" "start" "-a" "f9952d1cdc0249976db934166abc1298237e02d472809168c5b9ee988798308b", kill_on_drop: false }`
[INFO] [stderr]    Compiling zerocopy v0.8.42
[INFO] [stderr]    Compiling serde v1.0.228
[INFO] [stderr]    Compiling libc v0.2.183
[INFO] [stderr]    Compiling ciborium-io v0.2.2
[INFO] [stderr]    Compiling clap_lex v1.0.0
[INFO] [stderr]    Compiling regex-syntax v0.8.10
[INFO] [stderr]    Compiling plotters-backend v0.3.7
[INFO] [stderr]    Compiling itoa v1.0.17
[INFO] [stderr]    Compiling syn v2.0.117
[INFO] [stderr]    Compiling cast v0.3.0
[INFO] [stderr]    Compiling bitflags v2.11.0
[INFO] [stderr]    Compiling oorandom v11.1.5
[INFO] [stderr]    Compiling anes v0.1.6
[INFO] [stderr]    Compiling criterion-plot v0.5.0
[INFO] [stderr]    Compiling plotters-svg v0.3.7
[INFO] [stderr]    Compiling plotters v0.3.7
[INFO] [stderr]    Compiling rustix v1.1.4
[INFO] [stderr]    Compiling clap_builder v4.5.60
[INFO] [stderr]    Compiling serde_json v1.0.149
[INFO] [stderr]    Compiling regex-automata v0.4.14
[INFO] [stderr]    Compiling tempfile v3.26.0
[INFO] [stderr]    Compiling is-terminal v0.4.17
[INFO] [stderr]    Compiling clap v4.5.60
[INFO] [stderr]    Compiling zerocopy-derive v0.8.42
[INFO] [stderr]    Compiling serde_derive v1.0.228
[INFO] [stderr]    Compiling regex v1.12.3
[INFO] [stderr]    Compiling tinytemplate v1.2.1
[INFO] [stderr]    Compiling half v2.7.1
[INFO] [stderr]    Compiling ciborium-ll v0.2.2
[INFO] [stderr]    Compiling ciborium v0.2.2
[INFO] [stderr]    Compiling criterion v0.5.1
[INFO] [stderr]    Compiling rewal v0.1.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 29.63s
[INFO] running `Command { std: "docker" "inspect" "f9952d1cdc0249976db934166abc1298237e02d472809168c5b9ee988798308b", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "f9952d1cdc0249976db934166abc1298237e02d472809168c5b9ee988798308b", kill_on_drop: false }`
[INFO] [stdout] f9952d1cdc0249976db934166abc1298237e02d472809168c5b9ee988798308b
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-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] 5ae0214bc20b0b97999080190818f1619d428283a517a3932cbfb6a7e3208b48
[INFO] running `Command { std: "docker" "start" "-a" "5ae0214bc20b0b97999080190818f1619d428283a517a3932cbfb6a7e3208b48", kill_on_drop: false }`
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.14s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/rewal-b117b137d2e706c7)
[INFO] [stdout] 
[INFO] [stdout] running 60 tests
[INFO] [stdout] test crc::tests::empty_input ... ok
[INFO] [stdout] test crc::tests::alignment_independence ... ok
[INFO] [stdout] test crc::tests::incremental_equals_oneshot ... ok
[INFO] [stdout] test crc::tests::known_vector_123456789 ... ok
[INFO] [stdout] test encoding::tests::crc_mismatch_detected ... ok
[INFO] [stdout] test encoding::tests::ewal_magic_bytes ... ok
[INFO] [stdout] test encoding::tests::encoder_reuse ... ok
[INFO] [stdout] test encoding::tests::frame_size_calculation ... ok
[INFO] [stdout] test encoding::tests::invalid_magic ... ok
[INFO] [stdout] test encoding::tests::multiple_frames_decode ... ok
[INFO] [stdout] test encoding::tests::round_trip_single_event ... ok
[INFO] [stdout] test encoding::tests::round_trip_batch ... ok
[INFO] [stdout] test encoding::tests::zero_copy_round_trip ... ok
[INFO] [stdout] test error::tests::clone_preserves_variant ... ok
[INFO] [stdout] test error::tests::equality_by_variant ... ok
[INFO] [stdout] test error::tests::display_messages ... ok
[INFO] [stdout] test error::tests::error_trait_source ... ok
[INFO] [stdout] test event::tests::batch_default ... ok
[INFO] [stdout] test error::tests::io_error_conversion ... ok
[INFO] [stdout] test event::tests::event_with_meta ... ok
[INFO] [stdout] test event::tests::event_new ... ok
[INFO] [stdout] test hooks::tests::hooks_fire ... ok
[INFO] [stdout] test hooks::tests::noop_hooks ... ok
[INFO] [stdout] test queue::tests::close_rejects_enqueue ... ok
[INFO] [stdout] test queue::tests::try_enqueue_full ... ok
[INFO] [stdout] test state::tests::display ... ok
[INFO] [stdout] test encoding::tests::truncated_frame ... ok
[INFO] [stdout] test state::tests::backward_transition_rejected ... ok
[INFO] [stdout] test state::tests::must_be_running ... ok
[INFO] [stdout] test state::tests::skip_transition_rejected ... ok
[INFO] [stdout] test queue::tests::drain_all ... ok
[INFO] [stdout] test mmap::tests::mmap_reader_empty ... ok
[INFO] [stdout] test crc::tests::various_lengths ... ok
[INFO] [stdout] test event::tests::batch_operations ... ok
[INFO] [stdout] test state::tests::valid_forward_transitions ... ok
[INFO] [stdout] test state::tests::initial_state ... ok
[INFO] [stdout] test queue::tests::enqueue_dequeue ... ok
[INFO] [stdout] test stats::tests::increments ... ok
[INFO] [stdout] test stats::tests::initial_values ... ok
[INFO] [stdout] test stats::tests::lsn_store_load ... ok
[INFO] [stdout] test replay::tests::iterator_empty_file ... ok
[INFO] [stdout] test mmap::tests::mmap_reader_file ... ok
[INFO] [stdout] test state::tests::wrong_from_state_rejected ... ok
[INFO] [stdout] test replay::tests::iterator_from_lsn ... ok
[INFO] [stdout] test replay::tests::iterator_all_events ... ok
[INFO] [stdout] test storage::tests::file_locking ... ok
[INFO] [stdout] test crc::tests::software_matches_dispatch ... ok
[INFO] [stdout] test storage::tests::file_storage_lifecycle ... ok
[INFO] [stdout] test wal::tests::iterator_read ... ok
[INFO] [stdout] test wal::tests::shutdown_idempotent ... ok
[INFO] [stdout] test wal::tests::open_append_replay ... ok
[INFO] [stdout] test wal::tests::close_immediate ... ok
[INFO] [stdout] test wal::tests::empty_batch_rejected ... ok
[INFO] [stdout] test wal::tests::recovery_after_reopen ... ok
[INFO] [stdout] test wal::tests::batch_append ... ok
[INFO] [stdout] test wal::tests::stats_tracking ... ok
[INFO] [stdout] test hooks::tests::panic_recovered ... ok
[INFO] [stdout] test wal::tests::concurrent_appends ... ok
[INFO] [stdout] test queue::tests::close_unblocks_dequeue ... ok
[INFO] [stdout] test queue::tests::blocking_enqueue_on_full ... ok
[INFO] [stderr]    Doc-tests rewal
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 60 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.07s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 2 tests
[INFO] [stdout] test src/wal.rs - wal::Wal::open (line 47) - compile ... ok
[INFO] [stdout] test src/lib.rs - (line 27) - compile ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 2 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.20s
[INFO] [stdout] 
[INFO] running `Command { std: "docker" "inspect" "5ae0214bc20b0b97999080190818f1619d428283a517a3932cbfb6a7e3208b48", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "5ae0214bc20b0b97999080190818f1619d428283a517a3932cbfb6a7e3208b48", kill_on_drop: false }`
[INFO] [stdout] 5ae0214bc20b0b97999080190818f1619d428283a517a3932cbfb6a7e3208b48