[INFO] fetching crate hash_cons 0.2.0...
[INFO] testing hash_cons-0.2.0 against master#562dee4820c458d823175268e41601d4c060588a for pr-154210-1
[INFO] extracting crate hash_cons 0.2.0 into /workspace/builds/worker-4-tc1/source
[INFO] started tweaking crates.io crate hash_cons 0.2.0
[INFO] finished tweaking crates.io crate hash_cons 0.2.0
[INFO] tweaked toml for crates.io crate hash_cons 0.2.0 written to /workspace/builds/worker-4-tc1/source/Cargo.toml
[INFO] validating manifest of crates.io crate hash_cons 0.2.0 on toolchain 562dee4820c458d823175268e41601d4c060588a
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+562dee4820c458d823175268e41601d4c060588a" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+562dee4820c458d823175268e41601d4c060588a" "generate-lockfile" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr]     Updating crates.io index
[INFO] [stderr]      Locking 14 packages to latest compatible versions
[INFO] [stderr]       Adding rand v0.8.5 (available: v0.10.0)
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+562dee4820c458d823175268e41601d4c060588a" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr]  Downloading crates ...
[INFO] [stderr]   Downloaded zerocopy-derive v0.8.48
[INFO] [stderr]   Downloaded zerocopy v0.8.48
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-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" "+562dee4820c458d823175268e41601d4c060588a" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] dc5d64558e3d91b30321d03930955b0118252ac1e8a35ddcd150a07652fcd470
[INFO] running `Command { std: "docker" "start" "-a" "dc5d64558e3d91b30321d03930955b0118252ac1e8a35ddcd150a07652fcd470", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "dc5d64558e3d91b30321d03930955b0118252ac1e8a35ddcd150a07652fcd470", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "dc5d64558e3d91b30321d03930955b0118252ac1e8a35ddcd150a07652fcd470", kill_on_drop: false }`
[INFO] [stdout] dc5d64558e3d91b30321d03930955b0118252ac1e8a35ddcd150a07652fcd470
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-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" "+562dee4820c458d823175268e41601d4c060588a" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 8c75e4b10700f4be8f8cf50007103834a399cf95093ab10626fa9ccc7532fb64
[INFO] running `Command { std: "docker" "start" "-a" "8c75e4b10700f4be8f8cf50007103834a399cf95093ab10626fa9ccc7532fb64", kill_on_drop: false }`
[INFO] [stderr]    Compiling hash_cons v0.2.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 0.15s
[INFO] running `Command { std: "docker" "inspect" "8c75e4b10700f4be8f8cf50007103834a399cf95093ab10626fa9ccc7532fb64", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "8c75e4b10700f4be8f8cf50007103834a399cf95093ab10626fa9ccc7532fb64", kill_on_drop: false }`
[INFO] [stdout] 8c75e4b10700f4be8f8cf50007103834a399cf95093ab10626fa9ccc7532fb64
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-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" "+562dee4820c458d823175268e41601d4c060588a" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 4851f9b1f050b2365b6457c5d6b34bede57adffddef41d43da517e1284611bf1
[INFO] running `Command { std: "docker" "start" "-a" "4851f9b1f050b2365b6457c5d6b34bede57adffddef41d43da517e1284611bf1", kill_on_drop: false }`
[INFO] [stderr]    Compiling libc v0.2.184
[INFO] [stderr]    Compiling zerocopy v0.8.48
[INFO] [stderr]    Compiling getrandom v0.2.17
[INFO] [stderr]    Compiling rand_core v0.6.4
[INFO] [stderr]    Compiling ppv-lite86 v0.2.21
[INFO] [stderr]    Compiling rand_chacha v0.3.1
[INFO] [stderr]    Compiling rand v0.8.5
[INFO] [stderr]    Compiling hash_cons v0.2.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 7.48s
[INFO] running `Command { std: "docker" "inspect" "4851f9b1f050b2365b6457c5d6b34bede57adffddef41d43da517e1284611bf1", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "4851f9b1f050b2365b6457c5d6b34bede57adffddef41d43da517e1284611bf1", kill_on_drop: false }`
[INFO] [stdout] 4851f9b1f050b2365b6457c5d6b34bede57adffddef41d43da517e1284611bf1
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-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" "+562dee4820c458d823175268e41601d4c060588a" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] 373d5532fb28bb9ca51a88be56a43c786294203530f63cd0bd7144577738d9b0
[INFO] running `Command { std: "docker" "start" "-a" "373d5532fb28bb9ca51a88be56a43c786294203530f63cd0bd7144577738d9b0", kill_on_drop: false }`
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.03s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/hash_cons-c89fc713fd1e1097)
[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/single_threaded_tests.rs (/opt/rustwide/target/debug/deps/single_threaded_tests-cd0e0914903adec7)
[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/thread_safe_tests.rs (/opt/rustwide/target/debug/deps/thread_safe_tests-4b9fcbd22424dd47)
[INFO] [stdout] 
[INFO] [stdout] running 14 tests
[INFO] [stdout] test thread_safe_tests::single_tests::test_basic_const_hashconsing ... ok
[INFO] [stdout] test thread_safe_tests::single_tests::test_auto_cleanup_behavior ... ok
[INFO] [stdout] test thread_safe_tests::single_tests::test_complex_expression_hashconsing ... ok
[INFO] [stdout] test thread_safe_tests::multi_threaded_tests::test_multi_threaded_auto_cleanup_behavior ... ok
[INFO] [stdout] test thread_safe_tests::single_tests::test_hash_collision ... ok
[INFO] [stdout] test thread_safe_tests::single_tests::test_memory_usage_hash_consing ... ok
[INFO] [stdout] test thread_safe_tests::multi_threaded_tests::test_multi_threaded_hash_collision ... ok
[INFO] [stdout] test thread_safe_tests::multi_threaded_tests::test_multi_threaded_basic_const_hashconsing ... ok
[INFO] [stdout] test thread_safe_tests::multi_threaded_tests::test_multi_threaded_complex_expression_hashconsing ... ok
[INFO] [stdout] test thread_safe_tests::multi_threaded_tests::test_multi_threaded_memory_usage_hash_consing ... ok
[INFO] [stdout] test thread_safe_tests::multi_threaded_tests::test_multi_threaded_large_non_unique ... ok
[INFO] [stdout] test thread_safe_tests::single_tests::test_large_non_unique ... ok
[INFO] [stdout] test thread_safe_tests::multi_threaded_tests::test_multi_threaded_large_unique has been running for over 60 seconds
[INFO] [stdout] test thread_safe_tests::single_tests::test_large_unique has been running for over 60 seconds
[INFO] [stdout] test thread_safe_tests::multi_threaded_tests::test_multi_threaded_large_unique ... ok
[INFO] [stdout] test thread_safe_tests::single_tests::test_large_unique ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 14 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 76.58s
[INFO] [stdout] 
[INFO] [stderr]    Doc-tests hash_cons
[INFO] [stdout] 
[INFO] [stdout] running 17 tests
[INFO] [stdout] test src/thread_safe.rs - thread_safe::HcTable<T>::new (line 366) ... ok
[INFO] [stdout] test src/thread_safe.rs - thread_safe::Hc<T>::hash (line 103) ... ok
[INFO] [stdout] test src/thread_safe.rs - thread_safe::HcTable<T>::hashcons (line 386) ... ok
[INFO] [stdout] test src/thread_safe.rs - thread_safe::Hc<T>::fmt (line 190) ... ok
[INFO] [stdout] test src/thread_safe.rs - thread_safe::Hc<T>::as_ref (line 249) ... ok
[INFO] [stdout] test src/thread_safe.rs - thread_safe::Hc<T>::eq (line 72) ... ok
[INFO] [stdout] test src/thread_safe.rs - thread_safe::Hc<T>::cmp (line 315) ... ok
[INFO] [stdout] test src/thread_safe.rs - thread_safe::HcTable<T>::clone (line 503) ... ok
[INFO] [stdout] test src/thread_safe.rs - thread_safe::Hc (line 17) ... ok
[INFO] [stdout] test src/thread_safe.rs - thread_safe::Hc<T>::fmt (line 165) ... ok
[INFO] [stdout] test src/thread_safe.rs - thread_safe::Hc<T>::deref (line 217) ... ok
[INFO] [stdout] test src/thread_safe.rs - thread_safe::Hc<T>::partial_cmp (line 283) ... ok
[INFO] [stdout] test src/thread_safe.rs - thread_safe::Hc<T>::get (line 45) ... ok
[INFO] [stdout] test src/lib.rs - (line 48) ... ok
[INFO] [stdout] test src/thread_safe.rs - thread_safe::Hc<T>::clone (line 130) ... ok
[INFO] [stdout] test src/thread_safe.rs - thread_safe::HcTable<T>::len (line 479) ... ok
[INFO] [stdout] test src/thread_safe.rs - thread_safe::Inner<T>::drop (line 549) ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 17 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 1.45s
[INFO] [stdout] 
[INFO] running `Command { std: "docker" "inspect" "373d5532fb28bb9ca51a88be56a43c786294203530f63cd0bd7144577738d9b0", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "373d5532fb28bb9ca51a88be56a43c786294203530f63cd0bd7144577738d9b0", kill_on_drop: false }`
[INFO] [stdout] 373d5532fb28bb9ca51a88be56a43c786294203530f63cd0bd7144577738d9b0