[INFO] fetching crate mset 0.0.4...
[INFO] testing mset-0.0.4 against master#404c8471aba60c2d837fa728e7c729a0f52d5830 for pr-87041-1
[INFO] extracting crate mset 0.0.4 into /workspace/builds/worker-31/source
[INFO] validating manifest of crates.io crate mset 0.0.4 on toolchain 404c8471aba60c2d837fa728e7c729a0f52d5830
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+404c8471aba60c2d837fa728e7c729a0f52d5830" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] started tweaking crates.io crate mset 0.0.4
[INFO] finished tweaking crates.io crate mset 0.0.4
[INFO] tweaked toml for crates.io crate mset 0.0.4 written to /workspace/builds/worker-31/source/Cargo.toml
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+404c8471aba60c2d837fa728e7c729a0f52d5830" "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" "+404c8471aba60c2d837fa728e7c729a0f52d5830" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-31/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-31/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:a429bb59b7a3b4e7270a4b4c243bb115fc561d8f62ec34ef63a0a36cabf65dfd" "/opt/rustwide/cargo-home/bin/cargo" "+404c8471aba60c2d837fa728e7c729a0f52d5830" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] cfd2dd77cb842ad4643a7e844d5617fa430587fdd66e6925f578ec83edee8f49
[INFO] running `Command { std: "docker" "start" "-a" "cfd2dd77cb842ad4643a7e844d5617fa430587fdd66e6925f578ec83edee8f49", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "cfd2dd77cb842ad4643a7e844d5617fa430587fdd66e6925f578ec83edee8f49", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "cfd2dd77cb842ad4643a7e844d5617fa430587fdd66e6925f578ec83edee8f49", kill_on_drop: false }`
[INFO] [stdout] cfd2dd77cb842ad4643a7e844d5617fa430587fdd66e6925f578ec83edee8f49
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-31/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-31/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:a429bb59b7a3b4e7270a4b4c243bb115fc561d8f62ec34ef63a0a36cabf65dfd" "/opt/rustwide/cargo-home/bin/cargo" "+404c8471aba60c2d837fa728e7c729a0f52d5830" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 7cf2f4d0a9f24e5095ee99b1d488105141b521b25625c850e346b1618d5cc80f
[INFO] running `Command { std: "docker" "start" "-a" "7cf2f4d0a9f24e5095ee99b1d488105141b521b25625c850e346b1618d5cc80f", kill_on_drop: false }`
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] [stderr]    Compiling mset v0.0.4 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.61s
[INFO] running `Command { std: "docker" "inspect" "7cf2f4d0a9f24e5095ee99b1d488105141b521b25625c850e346b1618d5cc80f", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "7cf2f4d0a9f24e5095ee99b1d488105141b521b25625c850e346b1618d5cc80f", kill_on_drop: false }`
[INFO] [stdout] 7cf2f4d0a9f24e5095ee99b1d488105141b521b25625c850e346b1618d5cc80f
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-31/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-31/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:a429bb59b7a3b4e7270a4b4c243bb115fc561d8f62ec34ef63a0a36cabf65dfd" "/opt/rustwide/cargo-home/bin/cargo" "+404c8471aba60c2d837fa728e7c729a0f52d5830" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] cffb23129ab1a64348ce1ba0de434169676523f7ce26b5f287636055c97ea1fb
[INFO] running `Command { std: "docker" "start" "-a" "cffb23129ab1a64348ce1ba0de434169676523f7ce26b5f287636055c97ea1fb", kill_on_drop: false }`
[INFO] [stderr]    Compiling memchr v2.4.1
[INFO] [stderr]    Compiling tinyvec_macros v0.1.0
[INFO] [stderr]    Compiling version_check v0.9.3
[INFO] [stderr]    Compiling proc-macro2 v0.4.30
[INFO] [stderr]    Compiling serde v1.0.131
[INFO] [stderr]    Compiling unicode-xid v0.1.0
[INFO] [stderr]    Compiling unicode-bidi v0.3.7
[INFO] [stderr]    Compiling matches v0.1.9
[INFO] [stderr]    Compiling syn v0.15.44
[INFO] [stderr]    Compiling pulldown-cmark v0.4.1
[INFO] [stderr]    Compiling either v1.6.1
[INFO] [stderr]    Compiling regex-syntax v0.6.25
[INFO] [stderr]    Compiling percent-encoding v1.0.1
[INFO] [stderr]    Compiling semver-parser v0.9.0
[INFO] [stderr]    Compiling tinyvec v1.5.1
[INFO] [stderr]    Compiling itertools v0.8.2
[INFO] [stderr]    Compiling unicase v2.6.0
[INFO] [stderr]    Compiling quote v0.6.13
[INFO] [stderr]    Compiling aho-corasick v0.7.18
[INFO] [stderr]    Compiling unicode-normalization v0.1.19
[INFO] [stderr]    Compiling idna v0.1.5
[INFO] [stderr]    Compiling regex v1.5.4
[INFO] [stderr]    Compiling url v1.7.2
[INFO] [stderr]    Compiling toml v0.5.8
[INFO] [stderr]    Compiling version-sync v0.8.1
[INFO] [stderr]    Compiling mset v0.0.4 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 8.95s
[INFO] running `Command { std: "docker" "inspect" "cffb23129ab1a64348ce1ba0de434169676523f7ce26b5f287636055c97ea1fb", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "cffb23129ab1a64348ce1ba0de434169676523f7ce26b5f287636055c97ea1fb", kill_on_drop: false }`
[INFO] [stdout] cffb23129ab1a64348ce1ba0de434169676523f7ce26b5f287636055c97ea1fb
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-31/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-31/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:a429bb59b7a3b4e7270a4b4c243bb115fc561d8f62ec34ef63a0a36cabf65dfd" "/opt/rustwide/cargo-home/bin/cargo" "+404c8471aba60c2d837fa728e7c729a0f52d5830" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] 41d28bfacaef0edfceaff472ce6e81d02034aca1b7aad33889e3a08fa51bfede
[INFO] running `Command { std: "docker" "start" "-a" "41d28bfacaef0edfceaff472ce6e81d02034aca1b7aad33889e3a08fa51bfede", kill_on_drop: false }`
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 0.13s
[INFO] [stderr]      Running unittests (/opt/rustwide/target/debug/deps/mset-858eb464472c531c)
[INFO] [stdout] 
[INFO] [stdout] running 20 tests
[INFO] [stdout] test test_mset::test_difference ... ok
[INFO] [stdout] test test_mset::test_disjoint ... ok
[INFO] [stdout] test test_mset::test_eq ... ok
[INFO] [stdout] test test_mset::test_insert_and_retrieve_elements ... ok
[INFO] [stdout] test test_mset::test_iterator_over_element_counts ... ok
[INFO] [stdout] test test_mset::test_iterator_over_elements ... ok
[INFO] [stdout] test test_mset::test_extend_ref ... ok
[INFO] [stdout] test test_mset::test_move_iter ... ok
[INFO] [stdout] test test_mset::test_retrieving_mset_values ... ok
[INFO] [stdout] test test_mset::test_intersection ... ok
[INFO] [stdout] test test_mset::test_show_nontrivial ... ok
[INFO] [stdout] test test_mset::test_retain ... ok
[INFO] [stdout] test test_mset::test_drain_nontrivial ... ok
[INFO] [stdout] test test_mset::test_subset_and_superset ... ok
[INFO] [stdout] test test_mset::test_show_trivial ... ok
[INFO] [stdout] test test_mset::test_mset_clear ... ok
[INFO] [stdout] test test_mset::test_drain_trivial ... ok
[INFO] [stdout] test test_mset::test_symmetric_difference ... ok
[INFO] [stdout] test test_mset::test_union ... ok
[INFO] [stdout] test test_mset::test_zero_capacity ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 20 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.02s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/version-numbers.rs (/opt/rustwide/target/debug/deps/version_numbers-8cbd396f1335740d)
[INFO] [stdout] 
[INFO] [stdout] running 2 tests
[INFO] [stdout] test test_readme_deps ... ok
[INFO] [stderr]    Doc-tests mset
[INFO] [stdout] test test_html_root_url ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 2 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.21s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 40 tests
[INFO] [stdout] test src/lib.rs - MultiSet<T,S>::take (line 660) ... ok
[INFO] [stdout] test src/lib.rs - MultiSet<T,S>::element_counts (line 197) ... ok
[INFO] [stdout] test src/lib.rs - MultiSet<T,S>::contains (line 838) ... ok
[INFO] [stdout] test src/lib.rs - MultiSet<T,S>::hasher (line 348) ... ok
[INFO] [stdout] test src/lib.rs - &'_MultiSet<T,S>::bitand (line 980) ... ok
[INFO] [stdout] test src/lib.rs - MultiSet<T,RandomState>::new (line 143) ... ok
[INFO] [stdout] test src/lib.rs - MultiSet<T,S>::iter (line 410) ... ok
[INFO] [stdout] test src/lib.rs - &'_MultiSet<T,S>::bitxor (line 1013) ... ok
[INFO] [stdout] test src/lib.rs - MultiSet<T,S>::clear (line 281) ... ok
[INFO] [stdout] test src/lib.rs - MultiSet<T,S>::intersection (line 785) ... ok
[INFO] [stdout] test src/lib.rs - MultiSet<T,S>::remove_times (line 617) ... ok
[INFO] [stdout] test src/lib.rs - MultiSet<T,S>::capacity (line 182) ... ok
[INFO] [stdout] test src/lib.rs - MultiSet<T,S>::get (line 866) ... ok
[INFO] [stdout] test src/lib.rs - MultiSet<T,S>::iter_with_multiplicity (line 432) ... ok
[INFO] [stdout] test src/lib.rs - MultiSet (line 55) ... ok
[INFO] [stdout] test src/lib.rs - MultiSet<T,S>::difference (line 725) ... ok
[INFO] [stdout] test src/lib.rs - MultiSet<T,S>::reserve (line 372) ... ok
[INFO] [stdout] test src/lib.rs - MultiSet<T,S>::is_empty (line 263) ... ok
[INFO] [stdout] test src/lib.rs - MultiSet (line 114) ... ok
[INFO] [stdout] test src/lib.rs - MultiSet<T,S>::with_capacity_and_hasher (line 328) ... ok
[INFO] [stdout] test src/lib.rs - MultiSet<T,S>::symmetric_difference (line 757) ... ok
[INFO] [stdout] test src/lib.rs - MultiSet<T,RandomState>::with_capacity (line 164) ... ok
[INFO] [stdout] test src/lib.rs - MultiSet<T,S>::insert (line 535) ... ok
[INFO] [stdout] test src/lib.rs - MultiSet<T,S>::shrink_to_fit (line 388) ... ok
[INFO] [stdout] test src/lib.rs - MultiSet<T,S>::drain (line 701) ... ok
[INFO] [stdout] test src/lib.rs - &'_MultiSet<T,S>::sub (line 1043) ... ok
[INFO] [stdout] test src/lib.rs - MultiSet (line 90) ... ok
[INFO] [stdout] test src/lib.rs - MultiSet<T> (line 910) ... ok
[INFO] [stdout] test src/lib.rs - MultiSet<T,S>::is_superset (line 505) ... ok
[INFO] [stdout] test src/lib.rs - MultiSet<T,S>::insert_times (line 557) ... ok
[INFO] [stdout] test src/lib.rs - MultiSet<T,S>::len (line 243) ... ok
[INFO] [stdout] test src/lib.rs - MultiSet<T,S>::is_subset (line 481) ... ok
[INFO] [stdout] test src/lib.rs - MultiSet<T,S>::is_disjoint (line 455) ... ok
[INFO] [stdout] test src/lib.rs - MultiSet<T,S>::get_element_multiplicity (line 888) ... ok
[INFO] [stdout] test src/lib.rs - MultiSet<T,S>::union (line 813) ... ok
[INFO] [stdout] test src/lib.rs - MultiSet<T,S>::elements (line 219) ... ok
[INFO] [stdout] test src/lib.rs - MultiSet<T,S>::remove (line 587) ... ok
[INFO] [stdout] test src/lib.rs - &'_MultiSet<T,S>::bitor (line 948) ... ok
[INFO] [stdout] test src/lib.rs - MultiSet<T,S>::retain (line 681) ... ok
[INFO] [stdout] test src/lib.rs - MultiSet<T,S>::with_hasher (line 306) ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 40 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 1.39s
[INFO] [stdout] 
[INFO] running `Command { std: "docker" "inspect" "41d28bfacaef0edfceaff472ce6e81d02034aca1b7aad33889e3a08fa51bfede", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "41d28bfacaef0edfceaff472ce6e81d02034aca1b7aad33889e3a08fa51bfede", kill_on_drop: false }`
[INFO] [stdout] 41d28bfacaef0edfceaff472ce6e81d02034aca1b7aad33889e3a08fa51bfede