[INFO] cloning repository https://github.com/mccullocht/mt_postings
[INFO] running `Command { std: "git" "-c" "credential.helper=" "-c" "credential.helper=/workspace/cargo-home/bin/git-credential-null" "clone" "--bare" "https://github.com/mccullocht/mt_postings" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fmccullocht%2Fmt_postings", kill_on_drop: false }`
[INFO] [stderr] Cloning into bare repository '/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fmccullocht%2Fmt_postings'...
[INFO] running `Command { std: "git" "rev-parse" "HEAD", kill_on_drop: false }`
[INFO] [stdout] 31c60f36a590237e91ad8a5a0b993afa200b48bf
[INFO] testing mccullocht/mt_postings against master#cced03bfd61a304243a34504618ecec86c17063f for pr-154065-2
[INFO] running `Command { std: "git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fmccullocht%2Fmt_postings" "/workspace/builds/worker-3-tc1/source", kill_on_drop: false }`
[INFO] [stderr] Cloning into '/workspace/builds/worker-3-tc1/source'...
[INFO] [stderr] done.
[INFO] started tweaking git repo https://github.com/mccullocht/mt_postings
[INFO] finished tweaking git repo https://github.com/mccullocht/mt_postings
[INFO] tweaked toml for git repo https://github.com/mccullocht/mt_postings written to /workspace/builds/worker-3-tc1/source/Cargo.toml
[INFO] validating manifest of git repo https://github.com/mccullocht/mt_postings on toolchain cced03bfd61a304243a34504618ecec86c17063f
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+cced03bfd61a304243a34504618ecec86c17063f" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate git repo https://github.com/mccullocht/mt_postings 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" "+cced03bfd61a304243a34504618ecec86c17063f" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr]     Updating crates.io index
[INFO] [stderr]  Downloading crates ...
[INFO] [stderr]   Downloaded sfa v1.0.0
[INFO] [stderr]   Downloaded varint-rs v2.2.0
[INFO] [stderr]   Downloaded lsm-tree v3.1.4
[INFO] [stderr]   Downloaded quick_cache v0.6.21
[INFO] [stderr]   Downloaded sketches-ddsketch v0.2.2
[INFO] [stderr]   Downloaded tikv-jemallocator v0.6.1
[INFO] [stderr]   Downloaded interval-heap v0.0.5
[INFO] [stderr]   Downloaded compare v0.0.6
[INFO] [stderr]   Downloaded ownedbytes v0.7.0
[INFO] [stderr]   Downloaded murmurhash32 v0.3.1
[INFO] [stderr]   Downloaded tantivy-bitpacker v0.6.0
[INFO] [stderr]   Downloaded measure_time v0.8.3
[INFO] [stderr]   Downloaded census v0.4.2
[INFO] [stderr]   Downloaded fastdivide v0.4.2
[INFO] [stderr]   Downloaded tantivy-tokenizer-api v0.3.0
[INFO] [stderr]   Downloaded fjall v3.1.4
[INFO] [stderr]   Downloaded levenshtein_automata v0.2.1
[INFO] [stderr]   Downloaded fs4 v0.8.4
[INFO] [stderr]   Downloaded bitpacking v0.9.3
[INFO] [stderr]   Downloaded tantivy-common v0.7.0
[INFO] [stderr]   Downloaded tantivy-query-grammar v0.22.0
[INFO] [stderr]   Downloaded tantivy-stacker v0.3.0
[INFO] [stderr]   Downloaded tantivy-sstable v0.3.0
[INFO] [stderr]   Downloaded crossbeam-skiplist v0.1.3
[INFO] [stderr]   Downloaded byteview v0.10.1
[INFO] [stderr]   Downloaded htmlescape v0.3.1
[INFO] [stderr]   Downloaded flume v0.12.0
[INFO] [stderr]   Downloaded tantivy-columnar v0.3.0
[INFO] [stderr]   Downloaded tikv-jemalloc-sys v0.6.1+5.3.0-1-ge13ca993e8ccb9ba9847cc330696e02839f328f7
[INFO] [stderr]   Downloaded tantivy v0.22.1
[INFO] [stderr]   Downloaded tantivy-fst v0.5.0
[INFO] [stderr]   Downloaded rust-stemmers v1.2.0
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-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" "+cced03bfd61a304243a34504618ecec86c17063f" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] f60db2743676ad3457f8b77470fbfdf111ee00d8e26f36d1ddbec93f0cd8ceff
[INFO] running `Command { std: "docker" "start" "-a" "f60db2743676ad3457f8b77470fbfdf111ee00d8e26f36d1ddbec93f0cd8ceff", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "f60db2743676ad3457f8b77470fbfdf111ee00d8e26f36d1ddbec93f0cd8ceff", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "f60db2743676ad3457f8b77470fbfdf111ee00d8e26f36d1ddbec93f0cd8ceff", kill_on_drop: false }`
[INFO] [stdout] f60db2743676ad3457f8b77470fbfdf111ee00d8e26f36d1ddbec93f0cd8ceff
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-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" "+cced03bfd61a304243a34504618ecec86c17063f" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 6c72b436afa77c7e9c0447ad45dde1d7c72789e6f40b49c1353ca638c5b54021
[INFO] running `Command { std: "docker" "start" "-a" "6c72b436afa77c7e9c0447ad45dde1d7c72789e6f40b49c1353ca638c5b54021", kill_on_drop: false }`
[INFO] [stderr]    Compiling libc v0.2.186
[INFO] [stderr]    Compiling getrandom v0.4.2
[INFO] [stderr]    Compiling rustix v1.1.4
[INFO] [stderr]    Compiling linux-raw-sys v0.12.1
[INFO] [stderr]    Compiling bitflags v2.11.1
[INFO] [stderr]    Compiling log v0.4.29
[INFO] [stderr]    Compiling hashbrown v0.16.1
[INFO] [stderr]    Compiling compare v0.0.6
[INFO] [stderr]    Compiling smallvec v1.15.1
[INFO] [stderr]    Compiling twox-hash v2.1.2
[INFO] [stderr]    Compiling xxhash-rust v0.8.15
[INFO] [stderr]    Compiling fastrand v2.4.1
[INFO] [stderr]    Compiling spin v0.9.8
[INFO] [stderr]    Compiling self_cell v1.2.2
[INFO] [stderr]    Compiling syn v2.0.117
[INFO] [stderr]    Compiling crossbeam-skiplist v0.1.3
[INFO] [stderr]    Compiling byteview v0.10.1
[INFO] [stderr]    Compiling varint-rs v2.2.0
[INFO] [stderr]    Compiling lz4_flex v0.13.1
[INFO] [stderr]    Compiling rustc-hash v2.1.2
[INFO] [stderr]    Compiling hashbrown v0.14.5
[INFO] [stderr]    Compiling interval-heap v0.0.5
[INFO] [stderr]    Compiling flume v0.12.0
[INFO] [stderr]    Compiling leb128 v0.2.6
[INFO] [stderr]    Compiling sfa v1.0.0
[INFO] [stderr]    Compiling quick_cache v0.6.21
[INFO] [stderr]    Compiling parking_lot_core v0.9.12
[INFO] [stderr]    Compiling dashmap v6.1.0
[INFO] [stderr]    Compiling tempfile v3.27.0
[INFO] [stderr]    Compiling enum_dispatch v0.3.13
[INFO] [stderr]    Compiling lsm-tree v3.1.4
[INFO] [stderr]    Compiling fjall v3.1.4
[INFO] [stderr]    Compiling mt_postings v0.1.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 16.45s
[INFO] running `Command { std: "docker" "inspect" "6c72b436afa77c7e9c0447ad45dde1d7c72789e6f40b49c1353ca638c5b54021", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "6c72b436afa77c7e9c0447ad45dde1d7c72789e6f40b49c1353ca638c5b54021", kill_on_drop: false }`
[INFO] [stdout] 6c72b436afa77c7e9c0447ad45dde1d7c72789e6f40b49c1353ca638c5b54021
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-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" "+cced03bfd61a304243a34504618ecec86c17063f" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 84763f4b79be8bfb88fb616191d32806b7afb9019f92906e8041b94dc973997f
[INFO] running `Command { std: "docker" "start" "-a" "84763f4b79be8bfb88fb616191d32806b7afb9019f92906e8041b94dc973997f", kill_on_drop: false }`
[INFO] [stderr]    Compiling mt_postings v0.1.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 1.65s
[INFO] running `Command { std: "docker" "inspect" "84763f4b79be8bfb88fb616191d32806b7afb9019f92906e8041b94dc973997f", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "84763f4b79be8bfb88fb616191d32806b7afb9019f92906e8041b94dc973997f", kill_on_drop: false }`
[INFO] [stdout] 84763f4b79be8bfb88fb616191d32806b7afb9019f92906e8041b94dc973997f
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-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" "+cced03bfd61a304243a34504618ecec86c17063f" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] bdc398dca8e5b3bbe1208d3583c0afd21c6508c8d0172b6db453c0d4b7959e64
[INFO] running `Command { std: "docker" "start" "-a" "bdc398dca8e5b3bbe1208d3583c0afd21c6508c8d0172b6db453c0d4b7959e64", 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/mt_postings-0ecb2cb213894222)
[INFO] [stdout] 
[INFO] [stdout] running 27 tests
[INFO] [stdout] test tests::positions_with_tf_always_empty ... ok
[INFO] [stdout] test tests::positions_doc_always_empty ... ok
[INFO] [stdout] test tests::doc_multi_hit_next ... ok
[INFO] [stdout] test tests::positions_after_advance_to ... ok
[INFO] [stdout] test tests::positions_with_pos_multi_hit ... ok
[INFO] [stdout] test tests::positions_append_idempotent ... ok
[INFO] [stdout] test tests::advance_to_from_unpositioned ... ok
[INFO] [stdout] test tests::doc_advance_to_gap ... ok
[INFO] [stdout] test tests::doc_missing_token_returns_none ... ok
[INFO] [stdout] test tests::doc_single_hit ... ok
[INFO] [stdout] test tests::positions_with_pos_single_hit ... ok
[INFO] [stdout] test tests::tf_with_pos_single_hit ... ok
[INFO] [stdout] test tests::with_pos_multi_hit_next ... ok
[INFO] [stdout] test tests::posting_features ... ok
[INFO] [stdout] test tests::tf_with_tf_multi_hit ... ok
[INFO] [stdout] test tests::tf_with_tf_single_hit ... ok
[INFO] [stdout] test tests::tf_doc_always_one ... ok
[INFO] [stdout] test tests::tf_with_pos_multi_hit ... ok
[INFO] [stdout] test tests::with_tf_single_hit ... ok
[INFO] [stdout] test tests::with_pos_single_hit ... ok
[INFO] [stdout] test tests::with_pos_advance_to_gap ... ok
[INFO] [stdout] test tests::with_tf_multi_hit_next ... ok
[INFO] [stdout] test tests::with_tf_advance_to ... ok
[INFO] [stdout] test tests::multi_block_next ... ok
[INFO] [stdout] test tests::multi_block_advance_to_second_block ... ok
[INFO] [stdout] test tests::positions_across_block_boundary ... ok
[INFO] [stdout] test tests::doc_advance_to_exact has been running for over 60 seconds
[ERROR] error running command: no output for 300 seconds
[INFO] running `Command { std: "docker" "inspect" "bdc398dca8e5b3bbe1208d3583c0afd21c6508c8d0172b6db453c0d4b7959e64", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "bdc398dca8e5b3bbe1208d3583c0afd21c6508c8d0172b6db453c0d4b7959e64", kill_on_drop: false }`
[INFO] [stdout] bdc398dca8e5b3bbe1208d3583c0afd21c6508c8d0172b6db453c0d4b7959e64