[INFO] cloning repository https://github.com/speroleague/middleman
[INFO] running `Command { std: "git" "-c" "credential.helper=" "-c" "credential.helper=/workspace/cargo-home/bin/git-credential-null" "clone" "--bare" "https://github.com/speroleague/middleman" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fsperoleague%2Fmiddleman", kill_on_drop: false }`
[INFO] [stderr] Cloning into bare repository '/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fsperoleague%2Fmiddleman'...
[INFO] running `Command { std: "git" "rev-parse" "HEAD", kill_on_drop: false }`
[INFO] [stdout] bf0c1baef61502d4e168a91c38633cdb9d2aaed4
[INFO] testing speroleague/middleman against 1.100.0-beta.1 for beta-1.100-2
[INFO] running `Command { std: "git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fsperoleague%2Fmiddleman" "/workspace/builds/worker-3-tc2/source", kill_on_drop: false }`
[INFO] [stderr] Cloning into '/workspace/builds/worker-3-tc2/source'...
[INFO] [stderr] done.
[INFO] started tweaking git repo https://github.com/speroleague/middleman
[INFO] finished tweaking git repo https://github.com/speroleague/middleman
[INFO] tweaked toml for git repo https://github.com/speroleague/middleman written to /workspace/builds/worker-3-tc2/source/Cargo.toml
[INFO] validating manifest of git repo https://github.com/speroleague/middleman on toolchain 1.100.0-beta.1
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+1.100.0-beta.1" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate git repo https://github.com/speroleague/middleman 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" "+1.100.0-beta.1" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc2/target:/opt/rustwide/target:rw,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" "-m" "1610612736" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:3111399a4047eeb3a02b7a90e478d715f38a8c6669b5c4b49d30a17385265909" "sleep" "infinity", kill_on_drop: false }`
[INFO] [stdout] bf9d5805f600b6d91ab9bd525d04d30ec8fcbbcb11294f2f378e62e75aaf4019
[INFO] running `Command { std: "docker" "start" "bf9d5805f600b6d91ab9bd525d04d30ec8fcbbcb11294f2f378e62e75aaf4019", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "bf9d5805f600b6d91ab9bd525d04d30ec8fcbbcb11294f2f378e62e75aaf4019", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "exec" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-w" "/opt/rustwide/workdir" "--user" "0:0" "bf9d5805f600b6d91ab9bd525d04d30ec8fcbbcb11294f2f378e62e75aaf4019" "/opt/rustwide/cargo-home/bin/cargo" "+1.100.0-beta.1" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "bf9d5805f600b6d91ab9bd525d04d30ec8fcbbcb11294f2f378e62e75aaf4019", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "exec" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-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" "-w" "/opt/rustwide/workdir" "--user" "0:0" "bf9d5805f600b6d91ab9bd525d04d30ec8fcbbcb11294f2f378e62e75aaf4019" "/opt/rustwide/cargo-home/bin/cargo" "+1.100.0-beta.1" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stderr]    Compiling serde_core v1.0.229
[INFO] [stderr]    Compiling find-msvc-tools v0.1.12
[INFO] [stderr]    Compiling serde v1.0.229
[INFO] [stderr]    Compiling syn v3.0.5
[INFO] [stderr]    Compiling aho-corasick v1.1.5
[INFO] [stderr]    Compiling getrandom v0.4.3
[INFO] [stderr]    Compiling arrayvec v0.7.8
[INFO] [stderr]    Compiling constant_time_eq v0.4.2
[INFO] [stderr]    Compiling cc v1.4.5
[INFO] [stderr]    Compiling rand v0.10.2
[INFO] [stderr]    Compiling log v0.4.34
[INFO] [stderr]    Compiling tracing v0.1.44
[INFO] [stderr]    Compiling hashbrown v0.17.1
[INFO] [stderr]    Compiling bstr v1.13.0
[INFO] [stderr]    Compiling rustix v1.1.4
[INFO] [stderr]    Compiling crossbeam-deque v0.8.7
[INFO] [stderr]    Compiling hashlink v0.12.2
[INFO] [stderr]    Compiling fs2 v0.4.3
[INFO] [stderr]    Compiling tracing-log v0.2.0
[INFO] [stderr]    Compiling regex-automata v0.4.18
[INFO] [stderr]    Compiling blake3 v1.8.7
[INFO] [stderr]    Compiling libsqlite3-sys v0.38.2
[INFO] [stderr]    Compiling tempfile v3.27.0
[INFO] [stderr]    Compiling toml_datetime v1.1.1+spec-1.1.0
[INFO] [stderr]    Compiling serde_spanned v1.1.1
[INFO] [stderr]    Compiling deranged v0.5.8
[INFO] [stderr]    Compiling serde_json v1.0.151
[INFO] [stderr]    Compiling toml v1.1.5+spec-1.1.0
[INFO] [stderr]    Compiling serde_derive v1.0.229
[INFO] [stderr]    Compiling thiserror-impl v2.0.20
[INFO] [stderr]    Compiling clap_derive v4.6.4
[INFO] [stderr]    Compiling time v0.3.55
[INFO] [stderr]    Compiling thiserror v2.0.20
[INFO] [stderr]    Compiling globset v0.4.19
[INFO] [stderr]    Compiling regex v1.13.1
[INFO] [stderr]    Compiling matchers v0.2.0
[INFO] [stderr]    Compiling clap v4.6.6
[INFO] [stderr]    Compiling tracing-subscriber v0.3.23
[INFO] [stderr]    Compiling ignore v0.4.31
[INFO] [stderr]    Compiling ulid v3.0.0
[INFO] [stderr]    Compiling middleman-core v0.1.0 (/opt/rustwide/workdir/crates/core)
[INFO] [stderr]    Compiling middleman-packet v0.1.0 (/opt/rustwide/workdir/crates/packet)
[INFO] [stderr]    Compiling middleman-indexer v0.1.0 (/opt/rustwide/workdir/crates/indexer)
[INFO] [stderr]    Compiling middleman-adapters v0.1.0 (/opt/rustwide/workdir/crates/adapters)
[INFO] [stderr]    Compiling rusqlite v0.40.2
[INFO] [stderr]    Compiling middleman-store v0.1.0 (/opt/rustwide/workdir/crates/store)
[INFO] [stderr]    Compiling middleman-mcp v0.1.0 (/opt/rustwide/workdir/crates/mcp)
[INFO] [stderr]    Compiling middleman-cli v0.1.0 (/opt/rustwide/workdir/crates/cli)
[INFO] [stderr] warning: unused dependency `middleman-core`
[INFO] [stderr]   --> crates/adapters/Cargo.toml:12:1
[INFO] [stderr]    |
[INFO] [stderr] 12 | middleman-core = { workspace = true }
[INFO] [stderr]    | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
[INFO] [stderr]    |
[INFO] [stderr]    = note: `cargo::unused_dependencies` is set to `warn` by default
[INFO] [stderr] help: consider removing the dependency on `middleman-core`
[INFO] [stderr] warning: `middleman-adapters` (manifest) generated 1 warning
[INFO] [stderr] warning: unused dependency `tracing-subscriber`
[INFO] [stderr]   --> crates/cli/Cargo.toml:26:1
[INFO] [stderr]    |
[INFO] [stderr] 26 | tracing-subscriber = { workspace = true }
[INFO] [stderr]    | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
[INFO] [stderr]    |
[INFO] [stderr]    = note: `cargo::unused_dependencies` is set to `warn` by default
[INFO] [stderr] help: consider removing the dependency on `tracing-subscriber`
[INFO] [stderr] warning: `middleman-cli` (manifest) generated 1 warning
[INFO] [stderr] warning: unused dependency `regex`
[INFO] [stderr]   --> crates/indexer/Cargo.toml:14:1
[INFO] [stderr]    |
[INFO] [stderr] 14 | regex = { workspace = true }
[INFO] [stderr]    | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^
[INFO] [stderr]    |
[INFO] [stderr]    = note: `cargo::unused_dependencies` is set to `warn` by default
[INFO] [stderr] help: consider removing the dependency on `regex`
[INFO] [stderr] warning: unused dependency `tracing`
[INFO] [stderr]   --> crates/indexer/Cargo.toml:20:1
[INFO] [stderr]    |
[INFO] [stderr] 20 | tracing = { workspace = true }
[INFO] [stderr]    | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
[INFO] [stderr]    |
[INFO] [stderr] help: consider removing the dependency on `tracing`
[INFO] [stderr] warning: `middleman-indexer` (manifest) generated 2 warnings
[INFO] [stderr] warning: unused dependency `middleman-indexer`
[INFO] [stderr]   --> crates/mcp/Cargo.toml:17:1
[INFO] [stderr]    |
[INFO] [stderr] 17 | middleman-indexer = { workspace = true }
[INFO] [stderr]    | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
[INFO] [stderr]    |
[INFO] [stderr]    = note: `cargo::unused_dependencies` is set to `warn` by default
[INFO] [stderr] help: consider removing the dependency on `middleman-indexer`
[INFO] [stderr] warning: unused dependency `middleman-packet`
[INFO] [stderr]   --> crates/mcp/Cargo.toml:18:1
[INFO] [stderr]    |
[INFO] [stderr] 18 | middleman-packet = { workspace = true }
[INFO] [stderr]    | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
[INFO] [stderr]    |
[INFO] [stderr] help: consider removing the dependency on `middleman-packet`
[INFO] [stderr] warning: unused dependency `middleman-store`
[INFO] [stderr]   --> crates/mcp/Cargo.toml:19:1
[INFO] [stderr]    |
[INFO] [stderr] 19 | middleman-store = { workspace = true }
[INFO] [stderr]    | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
[INFO] [stderr]    |
[INFO] [stderr] help: consider removing the dependency on `middleman-store`
[INFO] [stderr] warning: `middleman-mcp` (manifest) generated 3 warnings
[INFO] [stderr] warning: unused dependency `tracing`
[INFO] [stderr]   --> crates/store/Cargo.toml:20:1
[INFO] [stderr]    |
[INFO] [stderr] 20 | tracing = { workspace = true }
[INFO] [stderr]    | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
[INFO] [stderr]    |
[INFO] [stderr]    = note: `cargo::unused_dependencies` is set to `warn` by default
[INFO] [stderr] help: consider removing the dependency on `tracing`
[INFO] [stderr] warning: `middleman-store` (manifest) generated 1 warning
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 1m 17s
[INFO] running `Command { std: "docker" "inspect" "bf9d5805f600b6d91ab9bd525d04d30ec8fcbbcb11294f2f378e62e75aaf4019", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "exec" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-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" "-w" "/opt/rustwide/workdir" "--user" "0:0" "bf9d5805f600b6d91ab9bd525d04d30ec8fcbbcb11294f2f378e62e75aaf4019" "/opt/rustwide/cargo-home/bin/cargo" "+1.100.0-beta.1" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stderr]    Compiling wait-timeout v0.2.1
[INFO] [stderr]    Compiling getrandom v0.3.4
[INFO] [stderr]    Compiling num-traits v0.2.19
[INFO] [stderr]    Compiling ppv-lite86 v0.2.21
[INFO] [stderr]    Compiling middleman-mcp v0.1.0 (/opt/rustwide/workdir/crates/mcp)
[INFO] [stderr]    Compiling middleman-cli v0.1.0 (/opt/rustwide/workdir/crates/cli)
[INFO] [stderr]    Compiling rand_core v0.9.5
[INFO] [stderr]    Compiling rusty-fork v0.3.1
[INFO] [stderr]    Compiling rand v0.9.5
[INFO] [stderr]    Compiling rand_chacha v0.9.0
[INFO] [stderr]    Compiling rand_xorshift v0.4.0
[INFO] [stderr]    Compiling middleman-store v0.1.0 (/opt/rustwide/workdir/crates/store)
[INFO] [stderr]    Compiling middleman-packet v0.1.0 (/opt/rustwide/workdir/crates/packet)
[INFO] [stderr]    Compiling middleman-adapters v0.1.0 (/opt/rustwide/workdir/crates/adapters)
[INFO] [stderr]    Compiling proptest v1.11.0
[INFO] [stderr]    Compiling middleman-core v0.1.0 (/opt/rustwide/workdir/crates/core)
[INFO] [stderr]    Compiling middleman-indexer v0.1.0 (/opt/rustwide/workdir/crates/indexer)
[INFO] [stderr] warning: unused dependency `middleman-core`
[INFO] [stderr]   --> crates/adapters/Cargo.toml:12:1
[INFO] [stderr]    |
[INFO] [stderr] 12 | middleman-core = { workspace = true }
[INFO] [stderr]    | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
[INFO] [stderr]    |
[INFO] [stderr]    = note: `cargo::unused_dependencies` is set to `warn` by default
[INFO] [stderr] help: consider removing the dependency on `middleman-core`
[INFO] [stderr] warning: `middleman-adapters` (manifest) generated 1 warning
[INFO] [stderr] warning: unused dependency `regex`
[INFO] [stderr]   --> crates/indexer/Cargo.toml:14:1
[INFO] [stderr]    |
[INFO] [stderr] 14 | regex = { workspace = true }
[INFO] [stderr]    | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^
[INFO] [stderr]    |
[INFO] [stderr]    = note: `cargo::unused_dependencies` is set to `warn` by default
[INFO] [stderr] help: consider removing the dependency on `regex`
[INFO] [stderr] warning: unused dependency `tracing`
[INFO] [stderr]   --> crates/indexer/Cargo.toml:20:1
[INFO] [stderr]    |
[INFO] [stderr] 20 | tracing = { workspace = true }
[INFO] [stderr]    | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
[INFO] [stderr]    |
[INFO] [stderr] help: consider removing the dependency on `tracing`
[INFO] [stderr] warning: `middleman-indexer` (manifest) generated 2 warnings
[INFO] [stderr] warning: unused dependency `middleman-indexer`
[INFO] [stderr]   --> crates/mcp/Cargo.toml:17:1
[INFO] [stderr]    |
[INFO] [stderr] 17 | middleman-indexer = { workspace = true }
[INFO] [stderr]    | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
[INFO] [stderr]    |
[INFO] [stderr]    = note: `cargo::unused_dependencies` is set to `warn` by default
[INFO] [stderr] help: consider removing the dependency on `middleman-indexer`
[INFO] [stderr] warning: unused dependency `middleman-packet`
[INFO] [stderr]   --> crates/mcp/Cargo.toml:18:1
[INFO] [stderr]    |
[INFO] [stderr] 18 | middleman-packet = { workspace = true }
[INFO] [stderr]    | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
[INFO] [stderr]    |
[INFO] [stderr] help: consider removing the dependency on `middleman-packet`
[INFO] [stderr] warning: unused dependency `middleman-store`
[INFO] [stderr]   --> crates/mcp/Cargo.toml:19:1
[INFO] [stderr]    |
[INFO] [stderr] 19 | middleman-store = { workspace = true }
[INFO] [stderr]    | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
[INFO] [stderr]    |
[INFO] [stderr] help: consider removing the dependency on `middleman-store`
[INFO] [stderr] warning: `middleman-mcp` (manifest) generated 3 warnings
[INFO] [stderr] warning: unused dependency `tracing`
[INFO] [stderr]   --> crates/store/Cargo.toml:20:1
[INFO] [stderr]    |
[INFO] [stderr] 20 | tracing = { workspace = true }
[INFO] [stderr]    | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
[INFO] [stderr]    |
[INFO] [stderr]    = note: `cargo::unused_dependencies` is set to `warn` by default
[INFO] [stderr] help: consider removing the dependency on `tracing`
[INFO] [stderr] warning: `middleman-store` (manifest) generated 1 warning
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 32.98s
[INFO] running `Command { std: "docker" "inspect" "bf9d5805f600b6d91ab9bd525d04d30ec8fcbbcb11294f2f378e62e75aaf4019", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "exec" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-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" "-w" "/opt/rustwide/workdir" "--user" "0:0" "bf9d5805f600b6d91ab9bd525d04d30ec8fcbbcb11294f2f378e62e75aaf4019" "/opt/rustwide/cargo-home/bin/cargo" "+1.100.0-beta.1" "test" "--frozen", kill_on_drop: false }`
[INFO] [stderr] warning: unused dependency `middleman-core`
[INFO] [stderr]   --> crates/adapters/Cargo.toml:12:1
[INFO] [stderr]    |
[INFO] [stderr] 12 | middleman-core = { workspace = true }
[INFO] [stderr]    | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
[INFO] [stderr]    |
[INFO] [stderr]    = note: `cargo::unused_dependencies` is set to `warn` by default
[INFO] [stderr] help: consider removing the dependency on `middleman-core`
[INFO] [stderr] warning: `middleman-adapters` (manifest) generated 1 warning
[INFO] [stderr] warning: unused dependency `regex`
[INFO] [stderr]   --> crates/indexer/Cargo.toml:14:1
[INFO] [stderr]    |
[INFO] [stderr] 14 | regex = { workspace = true }
[INFO] [stderr]    | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^
[INFO] [stderr]    |
[INFO] [stderr]    = note: `cargo::unused_dependencies` is set to `warn` by default
[INFO] [stderr] help: consider removing the dependency on `regex`
[INFO] [stderr] warning: unused dependency `tracing`
[INFO] [stderr]   --> crates/indexer/Cargo.toml:20:1
[INFO] [stderr]    |
[INFO] [stderr] 20 | tracing = { workspace = true }
[INFO] [stderr]    | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
[INFO] [stderr]    |
[INFO] [stderr] help: consider removing the dependency on `tracing`
[INFO] [stderr] warning: `middleman-indexer` (manifest) generated 2 warnings
[INFO] [stderr] warning: unused dependency `middleman-indexer`
[INFO] [stderr]   --> crates/mcp/Cargo.toml:17:1
[INFO] [stderr]    |
[INFO] [stderr] 17 | middleman-indexer = { workspace = true }
[INFO] [stderr]    | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
[INFO] [stderr]    |
[INFO] [stderr]    = note: `cargo::unused_dependencies` is set to `warn` by default
[INFO] [stderr] help: consider removing the dependency on `middleman-indexer`
[INFO] [stderr] warning: unused dependency `middleman-packet`
[INFO] [stderr]   --> crates/mcp/Cargo.toml:18:1
[INFO] [stderr]    |
[INFO] [stderr] 18 | middleman-packet = { workspace = true }
[INFO] [stderr]    | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
[INFO] [stderr]    |
[INFO] [stderr] help: consider removing the dependency on `middleman-packet`
[INFO] [stderr] warning: unused dependency `middleman-store`
[INFO] [stderr]   --> crates/mcp/Cargo.toml:19:1
[INFO] [stderr]    |
[INFO] [stderr] 19 | middleman-store = { workspace = true }
[INFO] [stderr]    | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
[INFO] [stderr]    |
[INFO] [stderr] help: consider removing the dependency on `middleman-store`
[INFO] [stderr] warning: `middleman-mcp` (manifest) generated 3 warnings
[INFO] [stderr] warning: unused dependency `tracing`
[INFO] [stderr]   --> crates/store/Cargo.toml:20:1
[INFO] [stderr]    |
[INFO] [stderr] 20 | tracing = { workspace = true }
[INFO] [stderr]    | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
[INFO] [stderr]    |
[INFO] [stderr]    = note: `cargo::unused_dependencies` is set to `warn` by default
[INFO] [stderr] help: consider removing the dependency on `tracing`
[INFO] [stderr] warning: `middleman-store` (manifest) generated 1 warning
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.27s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/build/middleman-adapters/396b591457190e74/out/middleman_adapters-396b591457190e74)
[INFO] [stderr]      Running unittests src/main.rs (/opt/rustwide/target/debug/build/middleman-cli/cecb545677733bde/out/middleman-cecb545677733bde)
[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] [stdout] 
[INFO] [stdout] running 3 tests
[INFO] [stdout] test susumu::tests::ignores_susumu_marker_names_when_they_are_directories ... ok
[INFO] [stdout] test susumu::tests::detects_each_documented_susumu_marker_without_reading_artifacts ... ok
[INFO] [stdout] test susumu::tests::parses_the_versioned_native_digest_contract_without_requiring_extra_fields ... ok
[INFO] [stdout] 
[INFO] [stderr]      Running tests/guides.rs (/opt/rustwide/target/debug/build/middleman-cli/6ec12db631ccdd9a/out/guides-6ec12db631ccdd9a)
[INFO] [stdout] test result: ok. 3 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 5 tests
[INFO] [stdout] test invalid_markers_and_unsafe_destination_leave_originals_untouched ... ok
[INFO] [stdout] test linked_docs_directory_cannot_redirect_writes ... ok
[INFO] [stdout] test preview_and_write_preserve_user_text_and_do_not_append_events ... ok
[INFO] [stdout] test context_write_creates_missing_docs_directory ... ok
[INFO] [stdout] test context_map_is_bounded_and_does_not_index_itself_on_regeneration ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 5 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.12s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/index.rs (/opt/rustwide/target/debug/build/middleman-cli/04b549ed5a037bab/out/index-04b549ed5a037bab)
[INFO] [stdout] 
[INFO] [stdout] running 4 tests
[INFO] [stdout] test incompatible_index_flags_leave_state_unchanged ... ok
[INFO] [stdout] test status_detects_susumu_without_making_its_optional_digest_a_failure ... ok
[INFO] [stdout] test status_separates_reviewed_memory_from_the_derived_index ... ok
[INFO] [stdout] test index_persists_source_free_cache_and_refreshes_incrementally ... ok
[INFO] [stderr]      Running tests/learning.rs (/opt/rustwide/target/debug/build/middleman-cli/2a0e1e9a4377c918/out/learning-2a0e1e9a4377c918)
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 4 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.19s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 2 tests
[INFO] [stdout] test optional_ai_proposals_require_opt_in_and_never_append_events ... ok
[INFO] [stdout] test local_outcomes_generate_reviewable_tuning_and_maintenance_suggestions ... ok
[INFO] [stderr]      Running tests/phase_two.rs (/opt/rustwide/target/debug/build/middleman-cli/24419a8e8c1b3d5d/out/phase_two-24419a8e8c1b3d5d)
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 2 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.22s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stderr]      Running tests/proposals.rs (/opt/rustwide/target/debug/build/middleman-cli/c731cd430c2c8d3f/out/proposals-c731cd430c2c8d3f)
[INFO] [stdout] test separate_sessions_continue_related_work_from_packet_and_reviewed_state ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.53s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 4 tests
[INFO] [stdout] test invalid_drafts_return_a_report_without_appending_events ... ok
[INFO] [stdout] test pending_proposals_can_be_rejected_once_with_a_reason ... ok
[INFO] [stdout] test structured_claims_are_validated_then_appended_as_one_pending_proposal ... ok
[INFO] [stdout] test git_evidence_is_attached_without_executing_repository_source ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 4 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.30s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/retrieval.rs (/opt/rustwide/target/debug/build/middleman-cli/d4d291ae2af56920/out/retrieval-d4d291ae2af56920)
[INFO] [stdout] 
[INFO] [stdout] running 4 tests
[INFO] [stdout] test prepare_routes_fixture_and_explains_across_sessions_without_storing_prompt ... ok
[INFO] [stdout] test errors_are_json_and_failed_preparation_does_not_append ... ok
[INFO] [stdout] test other_language_fixtures_render_without_executing_source ... ok
[INFO] [stdout] test search_expand_and_deletion_use_current_files_without_query_events ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 4 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.44s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/tasks.rs (/opt/rustwide/target/debug/build/middleman-cli/ce07c6759fbf8f7f/out/tasks-ce07c6759fbf8f7f)
[INFO] [stdout] 
[INFO] [stdout] running 4 tests
[INFO] [stdout] test lifecycle_persists_observations_without_prompt_or_source ... ok
[INFO] [stdout] test unchanged_dirty_baseline_and_unreported_validation_are_not_claimed_as_changes ... ok
[INFO] [stdout] test duplicate_finish_and_conflicting_validation_do_not_append ... ok
[INFO] [stdout] test from_git_records_heads_without_running_hooks ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 4 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.27s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/transfer.rs (/opt/rustwide/target/debug/build/middleman-cli/0e42991fd8df78b6/out/transfer-0e42991fd8df78b6)
[INFO] [stdout] 
[INFO] [stdout] running 3 tests
[INFO] [stdout] test oversized_input_is_rejected_without_creating_state ... ok
[INFO] [stdout] test invalid_input_and_existing_backup_preserve_destination ... ok
[INFO] [stdout] test transfers_preserve_log_and_rebuild_projection ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 3 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.31s
[INFO] [stdout] 
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/build/middleman-core/cbc7286774710c5d/out/middleman_core-cbc7286774710c5d)
[INFO] [stdout] 
[INFO] [stdout] running 18 tests
[INFO] [stdout] test config::tests::unknown_fields_are_rejected ... ok
[INFO] [stdout] test entity::tests::accepts_matching_pairs_and_empty_evidence ... ok
[INFO] [stdout] test event::tests::a_chain_of_tasks_verifies ... ok
[INFO] [stdout] test config::tests::explicit_values_are_respected_and_round_trip ... ok
[INFO] [stdout] test entity::tests::rejects_kind_payload_mismatch ... ok
[INFO] [stdout] test event::tests::proposed_event_carries_its_proposal_id ... ok
[INFO] [stdout] test entity::tests::task_round_trips_through_serde ... ok
[INFO] [stdout] test ids::tests::accepts_prefixed_ulids_and_rejects_malformed_values ... ok
[INFO] [stdout] test ids::tests::hash_round_trips_through_serde_and_hex ... ok
[INFO] [stdout] test learning::tests::outcomes_are_bounded_and_only_adjust_retrieved_nodes ... ok
[INFO] [stdout] test learning::tests::report_explains_missing_docs_routing_and_stale_decisions ... ok
[INFO] [stdout] test projection::tests::tampered_log_is_rejected_before_projection ... ok
[INFO] [stdout] test projection::tests::completing_unknown_task_is_rejected ... ok
[INFO] [stdout] test projection::tests::empty_log_projects_to_default_state ... ok
[INFO] [stdout] test projection::tests::full_log_projects_every_event_kind ... ok
[INFO] [stdout] test config::tests::an_empty_file_is_the_default_config ... ok
[INFO] [stdout] test event::tests::sequence_gaps_and_bad_first_events_are_rejected ... ok
[INFO] [stdout] test event::tests::tampered_payload_breaks_the_chain ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 18 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.02s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stderr]      Running tests/derived_ids.rs (/opt/rustwide/target/debug/build/middleman-core/0b5795e6e155e046/out/derived_ids-0b5795e6e155e046)
[INFO] [stdout] running 3 tests
[INFO] [stdout] test invalid_namespaces_keys_and_escapes_are_rejected ... ok
[INFO] [stdout] test existing_entity_ulids_remain_compatible ... ok
[INFO] [stdout] test derived_ids_round_trip_and_encode_components_without_collisions ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 3 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/projection_determinism.rs (/opt/rustwide/target/debug/build/middleman-core/745da65bf2af9586/out/projection_determinism-745da65bf2af9586)
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stderr]      Running tests/proposals.rs (/opt/rustwide/target/debug/build/middleman-core/2271eafc24301dc4/out/proposals-2271eafc24301dc4)
[INFO] [stdout] test projection_is_deterministic_and_serde_stable ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.49s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 5 tests
[INFO] [stdout] test inactive_and_unknown_references_are_never_accepted ... ok
[INFO] [stdout] test valid_draft_is_accepted_with_active_scope_and_evidence ... ok
[INFO] [stdout] test exact_active_fact_is_a_duplicate_and_task_scope_mismatch_is_a_warning ... ok
[INFO] [stdout] test validation_rejects_shape_scope_evidence_and_duplicate_drafts ... ok
[INFO] [stdout] test validator_is_pure_and_report_is_serde_stable ... ok
[INFO] [stderr]      Running tests/routing.rs (/opt/rustwide/target/debug/build/middleman-core/a961e3e7b7faabc6/out/routing-a961e3e7b7faabc6)
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 5 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 8 tests
[INFO] [stdout] test explicit_expansion_keeps_target_first_and_excludes_ineligible_nodes ... ok
[INFO] [stdout] test classification_has_boundaries_and_explicit_override ... ok
[INFO] [stdout] test ignored_inactive_and_stale_nodes_do_not_seed_neighbors ... ok
[INFO] [stdout] test neighbors_are_one_hop_and_duplicate_evidence_does_not_multiply_scores ... ok
[INFO] [stdout] test weights_match_specification ... ok
[INFO] [stdout] test symbols_contracts_and_test_links_have_distinct_reasons ... ok
[INFO] [stdout] test paths_are_exact_case_sensitive_and_portable ... ok
[INFO] [stdout] test routing_history_and_limits_are_explicit ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 8 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/tasks.rs (/opt/rustwide/target/debug/build/middleman-core/1e15e49d983bbc60/out/tasks-1e15e49d983bbc60)
[INFO] [stdout] 
[INFO] [stdout] running 3 tests
[INFO] [stdout] test legacy_records_without_snapshot_fields_still_deserialize ... ok
[INFO] [stdout] test snapshots_distinguish_endpoint_changes_and_unknown_baselines ... ok
[INFO] [stdout] test terminal_and_duplicate_observation_transitions_are_rejected ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 3 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/build/middleman-indexer/49a8ceda973324ef/out/middleman_indexer-49a8ceda973324ef)
[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/git_observations.rs (/opt/rustwide/target/debug/build/middleman-indexer/261f744109cce045/out/git_observations-261f744109cce045)
[INFO] [stdout] 
[INFO] [stdout] running 6 tests
[INFO] [stdout] test process_child ... ok
[INFO] [stdout] test nul_parsers_handle_renames_newlines_and_reject_truncation ... ok
[INFO] [stdout] test empty_repositories_and_invalid_inputs_have_explicit_results ... ok
[INFO] [stdout] test repository_filters_and_fsmonitor_are_not_executed ... ok
[INFO] [stdout] test process_limits_terminate_sleeping_and_noisy_children ... ok
[INFO] [stdout] test histories_and_worktree_changes_are_repeatable_and_allowlisted ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 6 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.19s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/graph_refresh.rs (/opt/rustwide/target/debug/build/middleman-indexer/7e16590579c4c9b3/out/graph_refresh-7e16590579c4c9b3)
[INFO] [stdout] 
[INFO] [stdout] running 14 tests
[INFO] [stdout] test cochange_evidence_is_separate_and_has_a_hard_expansion_limit ... ok
[INFO] [stdout] test edits_refresh_only_direct_neighbors_and_preserve_symbol_identity ... ok
[INFO] [stdout] test document_links_and_test_dependencies_do_not_become_durable_claims ... ok
[INFO] [stdout] test invalid_snapshots_and_tight_budgets_leave_prior_state_unchanged ... ok
[INFO] [stdout] test exact_node_budget_and_new_git_history_do_not_require_reparsing ... ok
[INFO] [stdout] test configuration_changes_invalidate_facts_and_cannot_bypass_limits ... ok
[INFO] [stdout] test additions_deletions_and_ambiguity_match_full_rebuilds ... ok
[INFO] [stdout] test no_op_refresh_reuses_all_facts_and_fragments ... ok
[INFO] [stdout] test renames_remove_prior_entities_and_links_and_add_new_identities ... ok
[INFO] [stdout] test unsafe_document_references_are_diagnostics_and_never_dangling_edges ... ok
[INFO] [stdout] test custom_rust_libraries_and_manifest_alias_changes_refresh_callers ... ok
[INFO] [stdout] test framework_fixtures_preserve_language_specific_symbols_and_imports ... ok
[INFO] [stdout] test fixture_graphs_have_stable_ids_evidence_and_no_dangling_edges ... ok
[INFO] [stdout] test arbitrary_edit_sequences_match_full_rebuilds ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 14 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.65s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/language_observations.rs (/opt/rustwide/target/debug/build/middleman-indexer/d4ff56af1912fc5a/out/language_observations-d4ff56af1912fc5a)
[INFO] [stdout] 
[INFO] [stdout] running 6 tests
[INFO] [stdout] test method_receivers_grouped_imports_and_restricted_visibility_are_preserved ... ok
[INFO] [stdout] test supported_languages_extract_small_public_surfaces ... ok
[INFO] [stdout] test comments_and_multiline_strings_do_not_become_declarations ... ok
[INFO] [stdout] test exposing_type_constructors_does_not_export_private_elm_functions ... ok
[INFO] [stdout] test parser_limits_and_signature_redaction_are_explicit ... ok
[INFO] [stdout] test fixture_declarations_imports_and_test_hints_are_stable ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 6 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/scanning.rs (/opt/rustwide/target/debug/build/middleman-indexer/e72e1c1215eb64dd/out/scanning-e72e1c1215eb64dd)
[INFO] [stdout] 
[INFO] [stdout] running 8 tests
[INFO] [stdout] test bounded_reads_skip_binary_large_and_long_files_and_total_limits_fail_closed ... ok
[INFO] [stdout] test document_parser_ignores_fenced_examples_and_enforces_bounds ... ok
[INFO] [stdout] test links_and_linked_ignore_files_are_never_followed ... ok
[INFO] [stdout] test changed_content_changes_its_hash_without_disturbing_other_files ... ok
[INFO] [stdout] test ignore_files_are_bounded_and_broker_denials_beat_nested_git_allow_rules ... ok
[INFO] [stdout] test layered_ignore_rules_work_without_git_metadata_and_cannot_override_safety ... ok
[INFO] [stdout] test depth_and_invalid_rules_are_explicit ... ok
[INFO] [stdout] test fixtures_are_sorted_repeatable_and_have_real_indexing_signals ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 8 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.06s
[INFO] [stdout] 
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/build/middleman-mcp/76d9c99030da4510/out/middleman_mcp-76d9c99030da4510)
[INFO] [stderr]      Running unittests src/main.rs (/opt/rustwide/target/debug/build/middleman-mcp/3ea43d266ba70c68/out/middleman_mcp-3ea43d266ba70c68)
[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] [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/protocol.rs (/opt/rustwide/target/debug/build/middleman-mcp/6d1c5f90d77831d1/out/protocol-6d1c5f90d77831d1)
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stdout] test stdio_initialization_and_discovery_are_line_delimited_json_rpc ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s
[INFO] [stdout] 
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/build/middleman-packet/c30a992a9d78e170/out/middleman_packet-c30a992a9d78e170)
[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/guides.rs (/opt/rustwide/target/debug/build/middleman-packet/2c21c67558ee7087/out/guides-2c21c67558ee7087)
[INFO] [stdout] 
[INFO] [stdout] running 3 tests
[INFO] [stdout] test generated_integration_uses_available_commands_and_empty_map_is_explicit ... ok
[INFO] [stdout] test merging_preserves_surrounding_bytes_and_newline_style ... ok
[INFO] [stdout] test malformed_and_duplicate_markers_are_rejected ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 3 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 7 tests
[INFO] [stderr]      Running tests/packets.rs (/opt/rustwide/target/debug/build/middleman-packet/aa5656aae4551e86/out/packets-aa5656aae4551e86)
[INFO] [stdout] test expansion_is_bounded_and_stale_ranking_cannot_resurrect_entities ... ok
[INFO] [stdout] test exact_budget_fits_and_one_less_removes_whole_items ... ok
[INFO] [stdout] test markdown_quotes_untrusted_structure_and_estimators_are_explicit ... ok
[INFO] [stdout] test selecting_only_weak_matches_does_not_inherit_high_confidence ... ok
[INFO] [stdout] test task_to_packet_has_stable_ids_reasons_and_commands_in_all_formats ... ok
[INFO] [stdout] test malformed_versions_fields_and_duplicate_ids_are_rejected ... ok
[INFO] [stdout] test cir_round_trips_unicode_and_delimiters_without_injected_statements ... ok
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/build/middleman-store/c9a3b209b7a41ccf/out/middleman_store-c9a3b209b7a41ccf)
[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] [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/store_cli.rs (/opt/rustwide/target/debug/build/middleman-store/483835a11f1717b1/out/store_cli-483835a11f1717b1)
[INFO] [stderr]      Running tests/store_tests.rs (/opt/rustwide/target/debug/build/middleman-store/68a79f7d66093596/out/store_tests-68a79f7d66093596)
[INFO] [stdout] 
[INFO] [stdout] running 12 tests
[INFO] [stdout] test an_uncommitted_write_leaves_no_trace ... ok
[INFO] [stdout] test batch_failure_rolls_back_every_event_and_projection ... ok
[INFO] [stdout] test append_times_out_when_the_writer_lock_stays_held ... FAILED
[INFO] [stdout] test append_waits_for_the_writer_lock_then_succeeds ... FAILED
[INFO] [stdout] test appended_events_survive_reopen_and_match_core_fold ... ok
[INFO] [stdout] test a_corrupted_log_is_refused_at_open ... ok
[INFO] [stdout] test derived_entity_ids_survive_event_replay_and_sqlite_reopen ... ok
[INFO] [stdout] test open_creates_wal_database_and_schema ... ok
[INFO] [stdout] test stale_appends_are_rejected_without_touching_the_log ... ok
[INFO] [stdout] test read_only_open_does_not_rebuild_or_create_state ... ok
[INFO] [stdout] test restore_repairs_corrupt_log_and_rejects_invalid_archive ... ok
[INFO] [stdout] test append_retries_sqlite_busy_until_the_other_writer_commits ... ok
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout] 
[INFO] [stdout] ---- append_times_out_when_the_writer_lock_stays_held stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'append_times_out_when_the_writer_lock_stays_held' (2053) panicked at crates/store/tests/store_tests.rs:231:10:
[INFO] [stdout] store_cli helper exists
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x5dfcddccf781 - std[617be2ca0a6fd877]::backtrace_rs::backtrace::libunwind::trace
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/../../backtrace/src/backtrace/libunwind.rs:117:9
[INFO] [stdout]    1:     0x5dfcddccf781 - std[617be2ca0a6fd877]::backtrace_rs::backtrace::trace_unsynchronized::<std[617be2ca0a6fd877]::sys::backtrace::_print_fmt::{closure#1}>
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/../../backtrace/src/backtrace/mod.rs:66:14
[INFO] [stdout]    2:     0x5dfcddccf781 - std[617be2ca0a6fd877]::sys::backtrace::_print_fmt
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/sys/backtrace.rs:74:9
[INFO] [stdout]    3:     0x5dfcddccf781 - <<std[617be2ca0a6fd877]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[a373ad73a504971]::fmt::Display>::fmt
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/sys/backtrace.rs:44:26
[INFO] [stdout]    4:     0x5dfcddce52fa - <core[a373ad73a504971]::fmt::rt::Argument>::fmt
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/core/src/fmt/rt.rs:152:76
[INFO] [stdout]    5:     0x5dfcddce52fa - core[a373ad73a504971]::fmt::write
[INFO] [stdout]    6:     0x5dfcddcd3c6c - core[a373ad73a504971]::io::write::default_write_fmt::<alloc[2e1b2a31b91974c5]::vec::Vec<u8>>
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/core/src/io/write.rs:402:11
[INFO] [stdout]    7:     0x5dfcddcd3c6c - <alloc[2e1b2a31b91974c5]::vec::Vec<u8> as core[a373ad73a504971]::io::write::Write>::write_fmt
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/core/src/io/write.rs:335:13
[INFO] [stdout]    8:     0x5dfcddca9bb6 - <std[617be2ca0a6fd877]::sys::backtrace::BacktraceLock>::print
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/sys/backtrace.rs:47:9
[INFO] [stdout]    9:     0x5dfcddca9bb6 - std[617be2ca0a6fd877]::panicking::default_hook::{closure#0}
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/panicking.rs:289:27
[INFO] [stdout]   10:     0x5dfcddcc7429 - std[617be2ca0a6fd877]::panicking::default_hook
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/panicking.rs:313:9
[INFO] [stdout]   11:     0x5dfcdd8ce840 - <alloc[2e1b2a31b91974c5]::boxed::Box<dyn for<'a, 'b> core[a373ad73a504971]::ops::function::Fn<(&'a std[617be2ca0a6fd877]::panic::PanicHookInfo<'b>,), Output = ()> + core[a373ad73a504971]::marker::Sync + core[a373ad73a504971]::marker::Send> as core[a373ad73a504971]::ops::function::Fn<(&std[617be2ca0a6fd877]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/alloc/src/boxed.rs:2328:9
[INFO] [stdout]   12:     0x5dfcdd8ce840 - test[aada8e7e76ba119b]::test_main_inner::{closure#0}
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/test/src/lib.rs:137:21
[INFO] [stdout]   13:     0x5dfcddcc75e2 - <alloc[2e1b2a31b91974c5]::boxed::Box<dyn for<'a, 'b> core[a373ad73a504971]::ops::function::Fn<(&'a std[617be2ca0a6fd877]::panic::PanicHookInfo<'b>,), Output = ()> + core[a373ad73a504971]::marker::Sync + core[a373ad73a504971]::marker::Send> as core[a373ad73a504971]::ops::function::Fn<(&std[617be2ca0a6fd877]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/alloc/src/boxed.rs:2328:9
[INFO] [stdout]   14:     0x5dfcddcc75e2 - std[617be2ca0a6fd877]::panicking::panic_with_hook
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/panicking.rs:819:13
[INFO] [stdout]   15:     0x5dfcddca9c62 - std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/panicking.rs:685:13
[INFO] [stdout]   16:     0x5dfcddca27b9 - std[617be2ca0a6fd877]::sys::backtrace::__rust_end_short_backtrace::<std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/sys/backtrace.rs:182:18
[INFO] [stdout]   17:     0x5dfcddcaa86d - __rustc[ea4f61d10b84890b]::rust_begin_unwind
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/panicking.rs:676:5
[INFO] [stdout]   18:     0x5dfcddce5b1c - core[a373ad73a504971]::panicking::panic_fmt
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/core/src/panicking.rs:80:14
[INFO] [stdout]   19:     0x5dfcddce57f4 - core[a373ad73a504971]::panicking::panic_display::<&str>
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/core/src/panicking.rs:259:5
[INFO] [stdout]   20:     0x5dfcddce57f4 - core[a373ad73a504971]::option::expect_failed
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/core/src/option.rs:2262:5
[INFO] [stdout]   21:     0x5dfcdd87f0f2 - <core[a373ad73a504971]::option::Option<std[617be2ca0a6fd877]::path::PathBuf>>::expect
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/core/src/option.rs:971:21
[INFO] [stdout]   22:     0x5dfcdd88fa8e - store_tests[c00ce1723d958ed6]::spawn_lock_holder
[INFO] [stdout]                                at /opt/rustwide/workdir/crates/store/tests/store_tests.rs:231:10
[INFO] [stdout]   23:     0x5dfcdd8929eb - store_tests[c00ce1723d958ed6]::append_times_out_when_the_writer_lock_stays_held
[INFO] [stdout]                                at /opt/rustwide/workdir/crates/store/tests/store_tests.rs:446:22
[INFO] [stdout]   24:     0x5dfcdd88e767 - store_tests[c00ce1723d958ed6]::append_times_out_when_the_writer_lock_stays_held::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/workdir/crates/store/tests/store_tests.rs:438:54
[INFO] [stdout]   25:     0x5dfcdd8a7246 - <store_tests[c00ce1723d958ed6]::append_times_out_when_the_writer_lock_stays_held::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   26:     0x5dfcdd8c1c8b - <fn() -> core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String> as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   27:     0x5dfcdd8c1c8b - test[aada8e7e76ba119b]::__rust_begin_short_backtrace::<core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>, fn() -> core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>>
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/test/src/lib.rs:701:18
[INFO] [stdout]   28:     0x5dfcdd8cee65 - test[aada8e7e76ba119b]::run_test_in_process::{closure#0}
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/test/src/lib.rs:724:74
[INFO] [stdout]   29:     0x5dfcdd8cee65 - <core[a373ad73a504971]::panic::unwind_safe::AssertUnwindSafe<test[aada8e7e76ba119b]::run_test_in_process::{closure#0}> as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   30:     0x5dfcdd8cee65 - std[617be2ca0a6fd877]::panicking::catch_unwind::do_call::<core[a373ad73a504971]::panic::unwind_safe::AssertUnwindSafe<test[aada8e7e76ba119b]::run_test_in_process::{closure#0}>, core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>>
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/panicking.rs:573:43
[INFO] [stdout]   31:     0x5dfcdd8cee65 - std[617be2ca0a6fd877]::panicking::catch_unwind::<core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>, core[a373ad73a504971]::panic::unwind_safe::AssertUnwindSafe<test[aada8e7e76ba119b]::run_test_in_process::{closure#0}>>
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/panicking.rs:541:19
[INFO] [stdout]   32:     0x5dfcdd8cee65 - std[617be2ca0a6fd877]::panic::catch_unwind::<core[a373ad73a504971]::panic::unwind_safe::AssertUnwindSafe<test[aada8e7e76ba119b]::run_test_in_process::{closure#0}>, core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>>
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/panic.rs:359:14
[INFO] [stdout]   33:     0x5dfcdd8cee65 - test[aada8e7e76ba119b]::run_test_in_process
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/test/src/lib.rs:724:27
[INFO] [stdout]   34:     0x5dfcdd8cee65 - test[aada8e7e76ba119b]::run_test::{closure#0}
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/test/src/lib.rs:645:43
[INFO] [stdout]   35:     0x5dfcdd8c8754 - test[aada8e7e76ba119b]::run_test::{closure#1}
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/test/src/lib.rs:675:41
[INFO] [stdout]   36:     0x5dfcdd8c8754 - std[617be2ca0a6fd877]::sys::backtrace::__rust_begin_short_backtrace::<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/sys/backtrace.rs:166:18
[INFO] [stdout]   37:     0x5dfcdd8d1ef2 - std[617be2ca0a6fd877]::thread::lifecycle::spawn_unchecked::<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/thread/lifecycle.rs:70:13
[INFO] [stdout]   38:     0x5dfcdd8d1ef2 - <core[a373ad73a504971]::panic::unwind_safe::AssertUnwindSafe<std[617be2ca0a6fd877]::thread::lifecycle::spawn_unchecked<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}> as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   39:     0x5dfcdd8d1ef2 - std[617be2ca0a6fd877]::panicking::catch_unwind::do_call::<core[a373ad73a504971]::panic::unwind_safe::AssertUnwindSafe<std[617be2ca0a6fd877]::thread::lifecycle::spawn_unchecked<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/panicking.rs:573:43
[INFO] [stdout]   40:     0x5dfcdd8d1ef2 - std[617be2ca0a6fd877]::panicking::catch_unwind::<(), core[a373ad73a504971]::panic::unwind_safe::AssertUnwindSafe<std[617be2ca0a6fd877]::thread::lifecycle::spawn_unchecked<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>>
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/panicking.rs:541:19
[INFO] [stdout]   41:     0x5dfcdd8d1ef2 - std[617be2ca0a6fd877]::panic::catch_unwind::<core[a373ad73a504971]::panic::unwind_safe::AssertUnwindSafe<std[617be2ca0a6fd877]::thread::lifecycle::spawn_unchecked<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/panic.rs:359:14
[INFO] [stdout]   42:     0x5dfcdd8d1ef2 - std[617be2ca0a6fd877]::thread::lifecycle::spawn_unchecked::<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>::{closure#1}
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/thread/lifecycle.rs:68:26
[INFO] [stdout]   43:     0x5dfcdd8d1ef2 - <std[617be2ca0a6fd877]::thread::lifecycle::spawn_unchecked<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>::{closure#1} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   44:     0x5dfcddccefdf - <alloc[2e1b2a31b91974c5]::boxed::Box<dyn core[a373ad73a504971]::ops::function::FnOnce<(), Output = ()> + core[a373ad73a504971]::marker::Send> as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/alloc/src/boxed.rs:2314:9
[INFO] [stdout]   45:     0x5dfcddccefdf - <std[617be2ca0a6fd877]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/sys/thread/unix.rs:127:17
[INFO] [stdout]   46:     0x7945e2697dfa - <unknown>
[INFO] [stdout]   47:     0x7945e272b3d4 - clone
[INFO] [stdout]   48:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- append_waits_for_the_writer_lock_then_succeeds stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'append_waits_for_the_writer_lock_then_succeeds' (2054) panicked at crates/store/tests/store_tests.rs:231:10:
[INFO] [stdout] store_cli helper exists
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x5dfcddccf781 - std[617be2ca0a6fd877]::backtrace_rs::backtrace::libunwind::trace
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/../../backtrace/src/backtrace/libunwind.rs:117:9
[INFO] [stdout]    1:     0x5dfcddccf781 - std[617be2ca0a6fd877]::backtrace_rs::backtrace::trace_unsynchronized::<std[617be2ca0a6fd877]::sys::backtrace::_print_fmt::{closure#1}>
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/../../backtrace/src/backtrace/mod.rs:66:14
[INFO] [stdout]    2:     0x5dfcddccf781 - std[617be2ca0a6fd877]::sys::backtrace::_print_fmt
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/sys/backtrace.rs:74:9
[INFO] [stdout]    3:     0x5dfcddccf781 - <<std[617be2ca0a6fd877]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[a373ad73a504971]::fmt::Display>::fmt
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/sys/backtrace.rs:44:26
[INFO] [stdout]    4:     0x5dfcddce52fa - <core[a373ad73a504971]::fmt::rt::Argument>::fmt
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/core/src/fmt/rt.rs:152:76
[INFO] [stdout]    5:     0x5dfcddce52fa - core[a373ad73a504971]::fmt::write
[INFO] [stdout]    6:     0x5dfcddcd3c6c - core[a373ad73a504971]::io::write::default_write_fmt::<alloc[2e1b2a31b91974c5]::vec::Vec<u8>>
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/core/src/io/write.rs:402:11
[INFO] [stdout]    7:     0x5dfcddcd3c6c - <alloc[2e1b2a31b91974c5]::vec::Vec<u8> as core[a373ad73a504971]::io::write::Write>::write_fmt
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/core/src/io/write.rs:335:13
[INFO] [stdout]    8:     0x5dfcddca9bb6 - <std[617be2ca0a6fd877]::sys::backtrace::BacktraceLock>::print
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/sys/backtrace.rs:47:9
[INFO] [stdout]    9:     0x5dfcddca9bb6 - std[617be2ca0a6fd877]::panicking::default_hook::{closure#0}
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/panicking.rs:289:27
[INFO] [stdout]   10:     0x5dfcddcc7429 - std[617be2ca0a6fd877]::panicking::default_hook
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/panicking.rs:313:9
[INFO] [stdout]   11:     0x5dfcdd8ce840 - <alloc[2e1b2a31b91974c5]::boxed::Box<dyn for<'a, 'b> core[a373ad73a504971]::ops::function::Fn<(&'a std[617be2ca0a6fd877]::panic::PanicHookInfo<'b>,), Output = ()> + core[a373ad73a504971]::marker::Sync + core[a373ad73a504971]::marker::Send> as core[a373ad73a504971]::ops::function::Fn<(&std[617be2ca0a6fd877]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/alloc/src/boxed.rs:2328:9
[INFO] [stdout]   12:     0x5dfcdd8ce840 - test[aada8e7e76ba119b]::test_main_inner::{closure#0}
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/test/src/lib.rs:137:21
[INFO] [stdout]   13:     0x5dfcddcc75e2 - <alloc[2e1b2a31b91974c5]::boxed::Box<dyn for<'a, 'b> core[a373ad73a504971]::ops::function::Fn<(&'a std[617be2ca0a6fd877]::panic::PanicHookInfo<'b>,), Output = ()> + core[a373ad73a504971]::marker::Sync + core[a373ad73a504971]::marker::Send> as core[a373ad73a504971]::ops::function::Fn<(&std[617be2ca0a6fd877]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/alloc/src/boxed.rs:2328:9
[INFO] [stdout]   14:     0x5dfcddcc75e2 - std[617be2ca0a6fd877]::panicking::panic_with_hook
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/panicking.rs:819:13
[INFO] [stdout]   15:     0x5dfcddca9c62 - std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/panicking.rs:685:13
[INFO] [stdout]   16:     0x5dfcddca27b9 - std[617be2ca0a6fd877]::sys::backtrace::__rust_end_short_backtrace::<std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/sys/backtrace.rs:182:18
[INFO] [stdout]   17:     0x5dfcddcaa86d - __rustc[ea4f61d10b84890b]::rust_begin_unwind
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/panicking.rs:676:5
[INFO] [stdout]   18:     0x5dfcddce5b1c - core[a373ad73a504971]::panicking::panic_fmt
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/core/src/panicking.rs:80:14
[INFO] [stdout]   19:     0x5dfcddce57f4 - core[a373ad73a504971]::panicking::panic_display::<&str>
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/core/src/panicking.rs:259:5
[INFO] [stdout]   20:     0x5dfcddce57f4 - core[a373ad73a504971]::option::expect_failed
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/core/src/option.rs:2262:5
[INFO] [stdout]   21:     0x5dfcdd87f0f2 - <core[a373ad73a504971]::option::Option<std[617be2ca0a6fd877]::path::PathBuf>>::expect
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/core/src/option.rs:971:21
[INFO] [stdout]   22:     0x5dfcdd88fa8e - store_tests[c00ce1723d958ed6]::spawn_lock_holder
[INFO] [stdout]                                at /opt/rustwide/workdir/crates/store/tests/store_tests.rs:231:10
[INFO] [stdout]   23:     0x5dfcdd89133b - store_tests[c00ce1723d958ed6]::append_waits_for_the_writer_lock_then_succeeds
[INFO] [stdout]                                at /opt/rustwide/workdir/crates/store/tests/store_tests.rs:411:22
[INFO] [stdout]   24:     0x5dfcdd88e707 - store_tests[c00ce1723d958ed6]::append_waits_for_the_writer_lock_then_succeeds::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/workdir/crates/store/tests/store_tests.rs:403:52
[INFO] [stdout]   25:     0x5dfcdd8a7206 - <store_tests[c00ce1723d958ed6]::append_waits_for_the_writer_lock_then_succeeds::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   26:     0x5dfcdd8c1c8b - <fn() -> core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String> as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   27:     0x5dfcdd8c1c8b - test[aada8e7e76ba119b]::__rust_begin_short_backtrace::<core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>, fn() -> core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>>
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/test/src/lib.rs:701:18
[INFO] [stdout]   28:     0x5dfcdd8cee65 - test[aada8e7e76ba119b]::run_test_in_process::{closure#0}
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/test/src/lib.rs:724:74
[INFO] [stdout]   29:     0x5dfcdd8cee65 - <core[a373ad73a504971]::panic::unwind_safe::AssertUnwindSafe<test[aada8e7e76ba119b]::run_test_in_process::{closure#0}> as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   30:     0x5dfcdd8cee65 - std[617be2ca0a6fd877]::panicking::catch_unwind::do_call::<core[a373ad73a504971]::panic::unwind_safe::AssertUnwindSafe<test[aada8e7e76ba119b]::run_test_in_process::{closure#0}>, core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>>
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/panicking.rs:573:43
[INFO] [stdout]   31:     0x5dfcdd8cee65 - std[617be2ca0a6fd877]::panicking::catch_unwind::<core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>, core[a373ad73a504971]::panic::unwind_safe::AssertUnwindSafe<test[aada8e7e76ba119b]::run_test_in_process::{closure#0}>>
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/panicking.rs:541:19
[INFO] [stdout]   32:     0x5dfcdd8cee65 - std[617be2ca0a6fd877]::panic::catch_unwind::<core[a373ad73a504971]::panic::unwind_safe::AssertUnwindSafe<test[aada8e7e76ba119b]::run_test_in_process::{closure#0}>, core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>>
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/panic.rs:359:14
[INFO] [stdout]   33:     0x5dfcdd8cee65 - test[aada8e7e76ba119b]::run_test_in_process
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/test/src/lib.rs:724:27
[INFO] [stdout]   34:     0x5dfcdd8cee65 - test[aada8e7e76ba119b]::run_test::{closure#0}
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/test/src/lib.rs:645:43
[INFO] [stdout]   35:     0x5dfcdd8c8754 - test[aada8e7e76ba119b]::run_test::{closure#1}
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/test/src/lib.rs:675:41
[INFO] [stdout]   36:     0x5dfcdd8c8754 - std[617be2ca0a6fd877]::sys::backtrace::__rust_begin_short_backtrace::<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/sys/backtrace.rs:166:18
[INFO] [stdout]   37:     0x5dfcdd8d1ef2 - std[617be2ca0a6fd877]::thread::lifecycle::spawn_unchecked::<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/thread/lifecycle.rs:70:13
[INFO] [stdout]   38:     0x5dfcdd8d1ef2 - <core[a373ad73a504971]::panic::unwind_safe::AssertUnwindSafe<std[617be2ca0a6fd877]::thread::lifecycle::spawn_unchecked<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}> as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   39:     0x5dfcdd8d1ef2 - std[617be2ca0a6fd877]::panicking::catch_unwind::do_call::<core[a373ad73a504971]::panic::unwind_safe::AssertUnwindSafe<std[617be2ca0a6fd877]::thread::lifecycle::spawn_unchecked<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/panicking.rs:573:43
[INFO] [stdout]   40:     0x5dfcdd8d1ef2 - std[617be2ca0a6fd877]::panicking::catch_unwind::<(), core[a373ad73a504971]::panic::unwind_safe::AssertUnwindSafe<std[617be2ca0a6fd877]::thread::lifecycle::spawn_unchecked<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>>
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/panicking.rs:541:19
[INFO] [stdout]   41:     0x5dfcdd8d1ef2 - std[617be2ca0a6fd877]::panic::catch_unwind::<core[a373ad73a504971]::panic::unwind_safe::AssertUnwindSafe<std[617be2ca0a6fd877]::thread::lifecycle::spawn_unchecked<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/panic.rs:359:14
[INFO] [stdout]   42:     0x5dfcdd8d1ef2 - std[617be2ca0a6fd877]::thread::lifecycle::spawn_unchecked::<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>::{closure#1}
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/thread/lifecycle.rs:68:26
[INFO] [stdout]   43:     0x5dfcdd8d1ef2 - <std[617be2ca0a6fd877]::thread::lifecycle::spawn_unchecked<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>::{closure#1} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   44:     0x5dfcddccefdf - <alloc[2e1b2a31b91974c5]::boxed::Box<dyn core[a373ad73a504971]::ops::function::FnOnce<(), Output = ()> + core[a373ad73a504971]::marker::Send> as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/alloc/src/boxed.rs:2314:9
[INFO] [stdout]   45:     0x5dfcddccefdf - <std[617be2ca0a6fd877]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/sys/thread/unix.rs:127:17
[INFO] [stdout]   46:     0x7945e2697dfa - <unknown>
[INFO] [stdout]   47:     0x7945e272b3d4 - clone
[INFO] [stdout]   48:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout]     append_times_out_when_the_writer_lock_stays_held
[INFO] [stdout]     append_waits_for_the_writer_lock_then_succeeds
[INFO] [stdout] 
[INFO] [stdout] test result: FAILED. 10 passed; 2 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.73s
[INFO] [stdout] 
[INFO] [stderr] error: test failed, to rerun pass `-p middleman-store --test store_tests`
[INFO] running `Command { std: "docker" "inspect" "bf9d5805f600b6d91ab9bd525d04d30ec8fcbbcb11294f2f378e62e75aaf4019", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "bf9d5805f600b6d91ab9bd525d04d30ec8fcbbcb11294f2f378e62e75aaf4019", kill_on_drop: false }`
[INFO] [stdout] bf9d5805f600b6d91ab9bd525d04d30ec8fcbbcb11294f2f378e62e75aaf4019
