[INFO] fetching crate trie 0.2.1...
[INFO] testing trie-0.2.1 against try#1b74e096b9bfb06f84a3007193dcd2f059cbdf6a for pr-96025
[INFO] extracting crate trie 0.2.1 into /workspace/builds/worker-12/source
[INFO] validating manifest of crates.io crate trie 0.2.1 on toolchain 1b74e096b9bfb06f84a3007193dcd2f059cbdf6a
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+1b74e096b9bfb06f84a3007193dcd2f059cbdf6a" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] started tweaking crates.io crate trie 0.2.1
[INFO] finished tweaking crates.io crate trie 0.2.1
[INFO] tweaked toml for crates.io crate trie 0.2.1 written to /workspace/builds/worker-12/source/Cargo.toml
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+1b74e096b9bfb06f84a3007193dcd2f059cbdf6a" "generate-lockfile" "--manifest-path" "Cargo.toml" "-Zno-index-update", kill_on_drop: false }`
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+1b74e096b9bfb06f84a3007193dcd2f059cbdf6a" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-12/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-12/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:b0c94ce3c1162fcb8e57cac5b65ec2f72eabb1eebea4fcc35e269e823f681646" "/opt/rustwide/cargo-home/bin/cargo" "+1b74e096b9bfb06f84a3007193dcd2f059cbdf6a" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] 19933ecade6bd9ec7ebfb733e1414fd649ebf46c9beba51eda7c890087d29639
[INFO] running `Command { std: "docker" "start" "-a" "19933ecade6bd9ec7ebfb733e1414fd649ebf46c9beba51eda7c890087d29639", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "19933ecade6bd9ec7ebfb733e1414fd649ebf46c9beba51eda7c890087d29639", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "19933ecade6bd9ec7ebfb733e1414fd649ebf46c9beba51eda7c890087d29639", kill_on_drop: false }`
[INFO] [stdout] 19933ecade6bd9ec7ebfb733e1414fd649ebf46c9beba51eda7c890087d29639
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-12/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-12/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" "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:b0c94ce3c1162fcb8e57cac5b65ec2f72eabb1eebea4fcc35e269e823f681646" "/opt/rustwide/cargo-home/bin/cargo" "+1b74e096b9bfb06f84a3007193dcd2f059cbdf6a" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] e7ca2bfbc58daf236b33c5c5aa33c8ffccbdcd822eb4ad0123b41f783275ab9d
[INFO] running `Command { std: "docker" "start" "-a" "e7ca2bfbc58daf236b33c5c5aa33c8ffccbdcd822eb4ad0123b41f783275ab9d", kill_on_drop: false }`
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] [stderr]    Compiling bit-vec v0.6.3
[INFO] [stderr]    Compiling vec_map v0.8.2
[INFO] [stderr]    Compiling bit-set v0.5.2
[INFO] [stderr]    Compiling ordered_iter v0.1.2
[INFO] [stderr]    Compiling trie v0.2.1 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 1.06s
[INFO] running `Command { std: "docker" "inspect" "e7ca2bfbc58daf236b33c5c5aa33c8ffccbdcd822eb4ad0123b41f783275ab9d", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "e7ca2bfbc58daf236b33c5c5aa33c8ffccbdcd822eb4ad0123b41f783275ab9d", kill_on_drop: false }`
[INFO] [stdout] e7ca2bfbc58daf236b33c5c5aa33c8ffccbdcd822eb4ad0123b41f783275ab9d
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-12/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-12/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" "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:b0c94ce3c1162fcb8e57cac5b65ec2f72eabb1eebea4fcc35e269e823f681646" "/opt/rustwide/cargo-home/bin/cargo" "+1b74e096b9bfb06f84a3007193dcd2f059cbdf6a" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 24417f2555c0941117556b62d176ad7de8bead23623a5ffa4ecb8078fadde820
[INFO] running `Command { std: "docker" "start" "-a" "24417f2555c0941117556b62d176ad7de8bead23623a5ffa4ecb8078fadde820", kill_on_drop: false }`
[INFO] [stderr]    Compiling libc v0.2.124
[INFO] [stderr]    Compiling rand v0.4.6
[INFO] [stderr]    Compiling trie v0.2.1 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 5.09s
[INFO] [stderr]   Executable unittests src/lib.rs (/opt/rustwide/target/debug/deps/trie-e93f54a7751fa58e)
[INFO] running `Command { std: "docker" "inspect" "24417f2555c0941117556b62d176ad7de8bead23623a5ffa4ecb8078fadde820", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "24417f2555c0941117556b62d176ad7de8bead23623a5ffa4ecb8078fadde820", kill_on_drop: false }`
[INFO] [stdout] 24417f2555c0941117556b62d176ad7de8bead23623a5ffa4ecb8078fadde820
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-12/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-12/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" "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:b0c94ce3c1162fcb8e57cac5b65ec2f72eabb1eebea4fcc35e269e823f681646" "/opt/rustwide/cargo-home/bin/cargo" "+1b74e096b9bfb06f84a3007193dcd2f059cbdf6a" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] 43389332927fef096dff4ef4ff34d5a923bd93c261d97bc656e08766d2fc568d
[INFO] running `Command { std: "docker" "start" "-a" "43389332927fef096dff4ef4ff34d5a923bd93c261d97bc656e08766d2fc568d", kill_on_drop: false }`
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 0.01s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/trie-e93f54a7751fa58e)
[INFO] [stdout] 
[INFO] [stdout] running 65 tests
[INFO] [stdout] test map::bench::bench_lower_bound ... ok
[INFO] [stdout] test map::bench::find_seq_100 ... ok
[INFO] [stdout] test map::bench::bench_get ... ok
[INFO] [stdout] test map::bench::bench_insert_small_low_bits ... ok
[INFO] [stdout] test map::bench::insert_seq_100 ... ok
[INFO] [stdout] test map::bench::bench_get_entry ... ok
[INFO] [stdout] test map::bench::find_rand_100 ... ok
[INFO] [stdout] test map::bench::bench_insert_small ... ok
[INFO] [stdout] test map::bench::insert_rand_100 ... ok
[INFO] [stdout] test map::test::test_each_reverse ... ok
[INFO] [stdout] test map::test::test_debug ... ok
[INFO] [stdout] test map::test::test_entry_get_mut ... ok
[INFO] [stdout] test map::test::test_clone ... ok
[INFO] [stdout] test map::bench::iter_20 ... ok
[INFO] [stdout] test map::test::test_entry_get ... ok
[INFO] [stdout] test map::test::test_entry_into_mut ... ok
[INFO] [stdout] test map::bench::iter_1000 ... ok
[INFO] [stdout] test map::test::test_entry_take ... ok
[INFO] [stdout] test map::test::test_eq ... ok
[INFO] [stdout] test map::test::test_find_mut ... ok
[INFO] [stdout] test map::bench::bench_upper_bound ... ok
[INFO] [stdout] test map::test::test_from_iter ... ok
[INFO] [stdout] test map::test::test_find_mut_missing ... ok
[INFO] [stdout] test map::test::test_hash ... ok
[INFO] [stdout] test map::test::test_index ... ok
[INFO] [stdout] test map::test::test_insert ... ok
[INFO] [stdout] test map::bench::bench_remove_entry ... ok
[INFO] [stdout] test map::test::test_bound ... ok
[INFO] [stdout] test map::test::test_keys ... ok
[INFO] [stdout] test map::bench::bench_remove ... ok
[INFO] [stdout] test map::test::test_lt ... ok
[INFO] [stdout] test map::test::test_mut_bound ... ok
[INFO] [stdout] test map::test::test_occupied_entry_set ... ok
[INFO] [stdout] test map::test::test_ord ... ok
[INFO] [stdout] test map::test::test_single_key ... ok
[INFO] [stdout] test map::test::test_remove ... ok
[INFO] [stdout] test map::test::test_vacant_entry_set ... ok
[INFO] [stdout] test map::test::test_values ... ok
[INFO] [stdout] test set::test::test_bit_and ... ok
[INFO] [stdout] test set::test::test_bit_or ... ok
[INFO] [stdout] test set::test::test_bit_xor ... ok
[INFO] [stdout] test set::test::test_clone ... ok
[INFO] [stdout] test set::test::test_debug ... ok
[INFO] [stdout] test set::test::test_from_iter ... ok
[INFO] [stdout] test set::test::test_difference ... ok
[INFO] [stdout] test set::test::test_intersection ... ok
[INFO] [stdout] test set::test::test_ord ... ok
[INFO] [stdout] test set::test::test_sub ... ok
[INFO] [stdout] test set::test::test_lt ... ok
[INFO] [stdout] test set::test::test_symmetric_difference ... ok
[INFO] [stdout] test set::test::test_sane_chunk ... ok
[INFO] [stdout] test set::test::test_union ... ok
[INFO] [stdout] test map::bench::insert_rand_10_000 ... ok
[INFO] [stdout] test map::bench::bench_insert_large ... ok
[INFO] [stdout] test map::bench::find_seq_10_000 ... ok
[INFO] [stdout] test map::bench::bench_insert_large_entry ... ok
[INFO] [stdout] test map::test::test_each_reverse_break ... ok
[INFO] [stdout] test map::test::test_iteration ... ok
[INFO] [stdout] test map::bench::bench_insert_large_low_bits ... ok
[INFO] [stdout] test map::bench::insert_seq_10_000 ... ok
[INFO] [stdout] test map::bench::find_rand_10_000 ... ok
[INFO] [stdout] test map::test::test_mut_iter ... ok
[INFO] [stdout] test map::test::test_index_nonexistent - should panic ... ok
[INFO] [stdout] test map::test::test_step ... ok
[INFO] [stdout] test map::bench::iter_100000 ... ok
[INFO] [stdout] 
[INFO] [stderr]    Doc-tests trie
[INFO] [stdout] test result: ok. 65 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.16s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 40 tests
[INFO] [stdout] test src/map.rs - map::Map<T>::is_empty (line 255) ... ok
[INFO] [stdout] test src/map.rs - map::Map<T>::clear (line 268) ... ok
[INFO] [stdout] test src/map.rs - map::Map<T>::insert (line 348) ... ok
[INFO] [stdout] test src/set.rs - set::Set::insert (line 354) ... ok
[INFO] [stdout] test src/set.rs - set::Set::is_superset (line 331) ... ok
[INFO] [stdout] test src/set.rs - set::Set::len (line 240) ... ok
[INFO] [stdout] test src/map.rs - map::Map<T>::lower_bound (line 486) ... ok
[INFO] [stdout] test src/map.rs - map::Map<T>::each_reverse (line 167) ... ok
[INFO] [stdout] test src/map.rs - map::Map<T>::new (line 154) ... ok
[INFO] [stdout] test src/set.rs - set::Set::union (line 220) ... ok
[INFO] [stdout] test src/map.rs - map::Map<T>::get_mut (line 329) ... ok
[INFO] [stdout] test src/set.rs - set::&'aSet::sub (line 467) ... ok
[INFO] [stdout] test src/set.rs - set::Set::is_disjoint (line 293) ... ok
[INFO] [stdout] test src/map.rs - map::Map<T>::get (line 284) ... ok
[INFO] [stdout] test src/set.rs - set::Set::new (line 67) ... ok
[INFO] [stdout] test src/map.rs - map::Map (line 58) ... ok
[INFO] [stdout] test src/map.rs - map::Map<T>::iter_mut (line 218) ... ok
[INFO] [stdout] test src/map.rs - map::Map<T>::len (line 242) ... ok
[INFO] [stdout] test src/map.rs - map::Map<T>::lower_bound_mut (line 526) ... ok
[INFO] [stdout] test src/set.rs - set::&'aSet::bitand (line 427) ... ok
[INFO] [stdout] test src/set.rs - set::Set::is_empty (line 253) ... ok
[INFO] [stdout] test src/set.rs - set::Set::remove (line 371) ... ok
[INFO] [stdout] test src/map.rs - map::Map<T>::contains_key (line 314) ... ok
[INFO] [stdout] test src/set.rs - set::&'aSet::bitxor (line 447) ... ok
[INFO] [stdout] test src/set.rs - set::Set::difference (line 152) ... ok
[INFO] [stdout] test src/set.rs - set::Set::iter (line 101) ... ok
[INFO] [stdout] test src/set.rs - set::Set::upper_bound (line 138) ... ok
[INFO] [stdout] test src/map.rs - map::Map<T>::remove (line 370) ... ok
[INFO] [stdout] test src/set.rs - set::Set (line 28) ... ok
[INFO] [stdout] test src/map.rs - map::Map<T>::upper_bound (line 502) ... ok
[INFO] [stdout] test src/map.rs - map::Map<T>::upper_bound_mut (line 550) ... ok
[INFO] [stdout] test src/map.rs - map::Map<T>::iter (line 197) ... ok
[INFO] [stdout] test src/set.rs - set::&'aSet::bitor (line 407) ... ok
[INFO] [stdout] test src/set.rs - set::Set::lower_bound (line 123) ... ok
[INFO] [stdout] test src/set.rs - set::Set::clear (line 265) ... ok
[INFO] [stdout] test src/set.rs - set::Set::intersection (line 200) ... ok
[INFO] [stdout] test src/set.rs - set::Set::is_subset (line 312) ... ok
[INFO] [stdout] test src/set.rs - set::Set::symmetric_difference (line 177) ... ok
[INFO] [stdout] test src/set.rs - set::Set::each_reverse (line 80) ... ok
[INFO] [stdout] test src/set.rs - set::Set::contains (line 278) ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 40 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 1.48s
[INFO] [stdout] 
[INFO] running `Command { std: "docker" "inspect" "43389332927fef096dff4ef4ff34d5a923bd93c261d97bc656e08766d2fc568d", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "43389332927fef096dff4ef4ff34d5a923bd93c261d97bc656e08766d2fc568d", kill_on_drop: false }`
[INFO] [stdout] 43389332927fef096dff4ef4ff34d5a923bd93c261d97bc656e08766d2fc568d