[INFO] cloning repository https://github.com/tontinton/cachey
[INFO] running `Command { std: "git" "-c" "credential.helper=" "-c" "credential.helper=/workspace/cargo-home/bin/git-credential-null" "clone" "--bare" "https://github.com/tontinton/cachey" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Ftontinton%2Fcachey", kill_on_drop: false }`
[INFO] [stderr] Cloning into bare repository '/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Ftontinton%2Fcachey'...
[INFO] running `Command { std: "git" "rev-parse" "HEAD", kill_on_drop: false }`
[INFO] [stdout] 5dda18016e8d975e4c2d6e60c0ad803a96df2cde
[INFO] testing tontinton/cachey against beta-2026-01-20 for beta-1.94-1
[INFO] running `Command { std: "git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Ftontinton%2Fcachey" "/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/tontinton/cachey
[INFO] finished tweaking git repo https://github.com/tontinton/cachey
[INFO] tweaked toml for git repo https://github.com/tontinton/cachey written to /workspace/builds/worker-2-tc2/source/Cargo.toml
[INFO] validating manifest of git repo https://github.com/tontinton/cachey on toolchain beta-2026-01-20
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+beta-2026-01-20" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate git repo https://github.com/tontinton/cachey 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-2026-01-20" "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:98afbf2d16093dec2546ff8915fddc74e65664aa03fc460b3712b1c2c54a33e4" "/opt/rustwide/cargo-home/bin/cargo" "+beta-2026-01-20" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] 02607dd449d76801755f827901a0ff75f12893b52259b6a95c6627758f58a48c
[INFO] running `Command { std: "docker" "start" "-a" "02607dd449d76801755f827901a0ff75f12893b52259b6a95c6627758f58a48c", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "02607dd449d76801755f827901a0ff75f12893b52259b6a95c6627758f58a48c", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "02607dd449d76801755f827901a0ff75f12893b52259b6a95c6627758f58a48c", kill_on_drop: false }`
[INFO] [stdout] 02607dd449d76801755f827901a0ff75f12893b52259b6a95c6627758f58a48c
[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=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:98afbf2d16093dec2546ff8915fddc74e65664aa03fc460b3712b1c2c54a33e4" "/opt/rustwide/cargo-home/bin/cargo" "+beta-2026-01-20" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] a9cb7d2f3d271d9cc67b744f3985d9cefa331fd6c7c21cc84601f20d378e93d9
[INFO] running `Command { std: "docker" "start" "-a" "a9cb7d2f3d271d9cc67b744f3985d9cefa331fd6c7c21cc84601f20d378e93d9", kill_on_drop: false }`
[INFO] [stderr]    Compiling proc-macro2 v1.0.104
[INFO] [stderr]    Compiling libc v0.2.178
[INFO] [stderr]    Compiling crossbeam-utils v0.8.21
[INFO] [stderr]    Compiling paste v1.0.15
[INFO] [stderr]    Compiling slab v0.4.11
[INFO] [stderr]    Compiling io-uring v0.7.11
[INFO] [stderr]    Compiling tracing-core v0.1.36
[INFO] [stderr]    Compiling bitflags v2.10.0
[INFO] [stderr]    Compiling arrayvec v0.7.6
[INFO] [stderr]    Compiling compio-driver v0.7.1
[INFO] [stderr]    Compiling parking_lot_core v0.9.12
[INFO] [stderr]    Compiling serde_core v1.0.228
[INFO] [stderr]    Compiling smallvec v1.15.1
[INFO] [stderr]    Compiling lock_api v0.4.14
[INFO] [stderr]    Compiling winnow v0.7.14
[INFO] [stderr]    Compiling scoped-tls v1.0.1
[INFO] [stderr]    Compiling hashbrown v0.16.1
[INFO] [stderr]    Compiling getrandom v0.3.4
[INFO] [stderr]    Compiling serde v1.0.228
[INFO] [stderr]    Compiling zmij v1.0.7
[INFO] [stderr]    Compiling toml_datetime v0.7.5+spec-1.1.0
[INFO] [stderr]    Compiling ahash v0.8.12
[INFO] [stderr]    Compiling tikv-jemalloc-sys v0.6.1+5.3.0-1-ge13ca993e8ccb9ba9847cc330696e02839f328f7
[INFO] [stderr]    Compiling compio-fs v0.7.0
[INFO] [stderr]    Compiling serde_json v1.0.148
[INFO] [stderr]    Compiling protobuf v3.7.2
[INFO] [stderr]    Compiling regex-syntax v0.8.8
[INFO] [stderr]    Compiling nix v0.30.1
[INFO] [stderr]    Compiling log v0.4.29
[INFO] [stderr]    Compiling prometheus v0.14.0
[INFO] [stderr]    Compiling either v1.15.0
[INFO] [stderr]    Compiling itoa v1.0.17
[INFO] [stderr]    Compiling quote v1.0.42
[INFO] [stderr]    Compiling spin v0.9.8
[INFO] [stderr]    Compiling thread_local v1.1.9
[INFO] [stderr]    Compiling indenter v0.3.4
[INFO] [stderr]    Compiling futures-sink v0.3.31
[INFO] [stderr]    Compiling indexmap v2.12.1
[INFO] [stderr]    Compiling tracing-log v0.2.0
[INFO] [stderr]    Compiling nu-ansi-term v0.50.3
[INFO] [stderr]    Compiling crossbeam-channel v0.5.15
[INFO] [stderr]    Compiling crossbeam-queue v0.3.12
[INFO] [stderr]    Compiling concurrent-queue v2.5.0
[INFO] [stderr]    Compiling syn v2.0.112
[INFO] [stderr]    Compiling eyre v0.6.12
[INFO] [stderr]    Compiling tokio v1.49.0
[INFO] [stderr]    Compiling humantime v2.3.0
[INFO] [stderr]    Compiling bytesize v2.3.1
[INFO] [stderr]    Compiling event-listener v5.4.1
[INFO] [stderr]    Compiling event-listener-strategy v0.5.4
[INFO] [stderr]    Compiling async-broadcast v0.7.2
[INFO] [stderr]    Compiling regex-automata v0.4.13
[INFO] [stderr]    Compiling toml_parser v1.0.6+spec-1.1.0
[INFO] [stderr]    Compiling compio-buf v0.5.1
[INFO] [stderr]    Compiling socket2 v0.5.10
[INFO] [stderr]    Compiling getrandom v0.2.16
[INFO] [stderr]    Compiling os_pipe v1.2.3
[INFO] [stderr]    Compiling num_cpus v1.17.0
[INFO] [stderr]    Compiling nanorand v0.7.0
[INFO] [stderr]    Compiling toml_edit v0.23.10+spec-1.0.0
[INFO] [stderr]    Compiling parking_lot v0.12.5
[INFO] [stderr]    Compiling dashmap v6.1.0
[INFO] [stderr]    Compiling flume v0.11.1
[INFO] [stderr]    Compiling quick_cache v0.6.18
[INFO] [stderr]    Compiling matchers v0.2.0
[INFO] [stderr]    Compiling proc-macro-crate v3.4.0
[INFO] [stderr]    Compiling tracing-serde v0.2.0
[INFO] [stderr]    Compiling futures-macro v0.3.31
[INFO] [stderr]    Compiling tracing-attributes v0.1.31
[INFO] [stderr]    Compiling thiserror-impl v1.0.69
[INFO] [stderr]    Compiling thiserror-impl v2.0.17
[INFO] [stderr]    Compiling strum_macros v0.27.2
[INFO] [stderr]    Compiling pin-project-internal v1.1.10
[INFO] [stderr]    Compiling compio-macros v0.1.2
[INFO] [stderr]    Compiling async-trait v0.1.89
[INFO] [stderr]    Compiling rsmp-derive v0.1.0 (/opt/rustwide/workdir/rsmp-derive)
[INFO] [stderr]    Compiling clap_derive v4.5.49
[INFO] [stderr]    Compiling futures-util v0.3.31
[INFO] [stderr]    Compiling pin-project v1.1.10
[INFO] [stderr]    Compiling thiserror v1.0.69
[INFO] [stderr]    Compiling protobuf-support v3.7.2
[INFO] [stderr]    Compiling tracing v0.1.44
[INFO] [stderr]    Compiling compio-log v0.1.0
[INFO] [stderr]    Compiling tracing-subscriber v0.3.22
[INFO] [stderr]    Compiling thiserror v2.0.17
[INFO] [stderr]    Compiling clap v4.5.53
[INFO] [stderr]    Compiling strum v0.27.2
[INFO] [stderr]    Compiling compio-io v0.6.0
[INFO] [stderr]    Compiling rsmp v0.1.0 (/opt/rustwide/workdir/rsmp)
[INFO] [stderr]    Compiling compio-runtime v0.7.0
[INFO] [stderr]    Compiling compio-net v0.7.0
[INFO] [stderr]    Compiling compio-signal v0.5.0
[INFO] [stderr]    Compiling compio v0.14.0
[INFO] [stderr]    Compiling tikv-jemallocator v0.6.1
[INFO] [stderr]    Compiling cachey v0.1.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 1m 34s
[INFO] running `Command { std: "docker" "inspect" "a9cb7d2f3d271d9cc67b744f3985d9cefa331fd6c7c21cc84601f20d378e93d9", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "a9cb7d2f3d271d9cc67b744f3985d9cefa331fd6c7c21cc84601f20d378e93d9", kill_on_drop: false }`
[INFO] [stdout] a9cb7d2f3d271d9cc67b744f3985d9cefa331fd6c7c21cc84601f20d378e93d9
[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=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:98afbf2d16093dec2546ff8915fddc74e65664aa03fc460b3712b1c2c54a33e4" "/opt/rustwide/cargo-home/bin/cargo" "+beta-2026-01-20" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] f87ed96fa3c0f37b67943493e7fabe05be4a64f312557ef29fd0e729f163b2a8
[INFO] running `Command { std: "docker" "start" "-a" "f87ed96fa3c0f37b67943493e7fabe05be4a64f312557ef29fd0e729f163b2a8", kill_on_drop: false }`
[INFO] [stderr]    Compiling io-uring v0.7.11
[INFO] [stderr]    Compiling tokio-macros v2.6.0
[INFO] [stderr]    Compiling mio v1.1.1
[INFO] [stderr]    Compiling socket2 v0.6.1
[INFO] [stderr]    Compiling bytes v1.11.0
[INFO] [stderr]    Compiling flume v0.11.1
[INFO] [stderr]    Compiling nix v0.30.1
[INFO] [stderr]    Compiling tempfile v3.24.0
[INFO] [stderr]    Compiling compio-driver v0.7.1
[INFO] [stderr]    Compiling tokio v1.49.0
[INFO] [stderr]    Compiling compio-runtime v0.7.0
[INFO] [stderr]    Compiling compio-net v0.7.0
[INFO] [stderr]    Compiling compio-signal v0.5.0
[INFO] [stderr]    Compiling compio-fs v0.7.0
[INFO] [stderr]    Compiling compio v0.14.0
[INFO] [stderr]    Compiling cachey v0.1.0 (/opt/rustwide/workdir)
[INFO] [stderr]    Compiling tokio-util v0.7.17
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 18.24s
[INFO] running `Command { std: "docker" "inspect" "f87ed96fa3c0f37b67943493e7fabe05be4a64f312557ef29fd0e729f163b2a8", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "f87ed96fa3c0f37b67943493e7fabe05be4a64f312557ef29fd0e729f163b2a8", kill_on_drop: false }`
[INFO] [stdout] f87ed96fa3c0f37b67943493e7fabe05be4a64f312557ef29fd0e729f163b2a8
[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=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:98afbf2d16093dec2546ff8915fddc74e65664aa03fc460b3712b1c2c54a33e4" "/opt/rustwide/cargo-home/bin/cargo" "+beta-2026-01-20" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] b76d9f7df17d54a6a507adcc556ce6ea55aa47abc5bc917cb315881caa849d60
[INFO] running `Command { std: "docker" "start" "-a" "b76d9f7df17d54a6a507adcc556ce6ea55aa47abc5bc917cb315881caa849d60", kill_on_drop: false }`
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.27s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/cachey-3e679f45695d405a)
[INFO] [stdout] 
[INFO] [stdout] running 19 tests
[INFO] [stdout] test cache::tests::memory_cache::same_file_different_ranges_coexist ... ok
[INFO] [stdout] test cache::tests::memory_cache::weight_reflects_data_size ... ok
[INFO] [stdout] test memory_semaphore::tests::bytes_to_permits_rounds_up ... ok
[INFO] [stdout] test memory_semaphore::tests::acquire_release_restores_permits ... ok
[INFO] [stdout] test cache::tests::memory_cache::eviction_by_weight ... ok
[INFO] [stdout] test server::tests::capture_ranges_no_overlap_produces_empty ... ok
[INFO] [stdout] test cache::tests::memory_cache::tracks_evictions_count_and_bytes ... ok
[INFO] [stdout] test server::tests::read_exact_with_reused_buffer_respects_remaining ... ok
[INFO] [stdout] test server::tests::stream_file_truncates_at_eof ... ok
[INFO] [stdout] test cache::tests::disk_cache::cleanup_pending_reflects_queue_length ... ok
[INFO] [stdout] test cache::tests::disk_cache::eviction_sends_to_cleanup_channel ... ok
[INFO] [stdout] test cache::tests::disk_cache::tracks_evictions_count_and_bytes ... ok
[INFO] [stdout] test server::tests::capture_ranges_single_chunk ... ok
[INFO] [stdout] test memory_semaphore::tests::concurrent_acquire_release_maintains_invariants ... ok
[INFO] [stdout] test cache::tests::disk_cache::tracks_hits_and_misses ... ok
[INFO] [stdout] test server::tests::stream_file_returns_exact_requested_range ... ok
[INFO] [stdout] test server::tests::capture_ranges_across_chunks ... ok
[INFO] [stdout] test cache::tests::memory_cache::exact_match_required ... ok
[INFO] [stdout] test memory_semaphore::tests::acquire_blocks_when_insufficient_permits ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 19 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.11s
[INFO] [stdout] 
[INFO] [stderr]      Running unittests src/main.rs (/opt/rustwide/target/debug/deps/cachey-b0dfc724eafa9f94)
[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] [stderr]      Running tests/put_and_get.rs (/opt/rustwide/target/debug/deps/put_and_get-b3fda8de28f2e377)
[INFO] [stdout] 
[INFO] [stdout] running 13 tests
[INFO] [stdout] test put_and_get_compio ... FAILED
[INFO] [stdout] test connection_reuse_after_put has been running for over 60 seconds
[INFO] [stdout] test get_falls_back_to_disk_on_memory_miss has been running for over 60 seconds
[INFO] [stdout] test get_hits_memory_cache_before_disk has been running for over 60 seconds
[INFO] [stdout] test get_partial_file_from_disk has been running for over 60 seconds
[INFO] [stdout] test get_range_past_eof_returns_partial_data has been running for over 60 seconds
[INFO] [stdout] test health_endpoint_returns_ok has been running for over 60 seconds
[INFO] [stdout] test put_and_get_tokio has been running for over 60 seconds
[INFO] [stdout] test put_and_get_verifies_disk_cache_metrics has been running for over 60 seconds
[INFO] [stdout] test put_existing_file_large_body_returns_already_exists has been running for over 60 seconds
[INFO] [stdout] test put_existing_file_skips_write has been running for over 60 seconds
[INFO] [stdout] test put_with_cache_ranges_populates_memory_cache has been running for over 60 seconds
[INFO] [stdout] test sequential_requests_complete_under_memory_pressure has been running for over 60 seconds
[ERROR] error running command: no output for 300 seconds
[INFO] running `Command { std: "docker" "inspect" "b76d9f7df17d54a6a507adcc556ce6ea55aa47abc5bc917cb315881caa849d60", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "b76d9f7df17d54a6a507adcc556ce6ea55aa47abc5bc917cb315881caa849d60", kill_on_drop: false }`
[INFO] [stdout] b76d9f7df17d54a6a507adcc556ce6ea55aa47abc5bc917cb315881caa849d60
