[INFO] fetching crate pullgraph 0.1.2...
[INFO] testing pullgraph-0.1.2 against 1.99.0-beta.8 for beta-1.100-2
[INFO] extracting crate pullgraph 0.1.2 into /workspace/builds/worker-3-tc1/source
[INFO] started tweaking crates.io crate pullgraph 0.1.2
[INFO] removed 0 missing tests
[INFO] finished tweaking crates.io crate pullgraph 0.1.2
[INFO] tweaked toml for crates.io crate pullgraph 0.1.2 written to /workspace/builds/worker-3-tc1/source/Cargo.toml
[INFO] validating manifest of crates.io crate pullgraph 0.1.2 on toolchain 1.99.0-beta.8
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+1.99.0-beta.8" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate crates.io crate pullgraph 0.1.2 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.99.0-beta.8" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc1/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] cabd5c8a41a41ee4c23c1596ec124abdd88df3133e9658c8c7a23fe6b7866883
[INFO] running `Command { std: "docker" "start" "cabd5c8a41a41ee4c23c1596ec124abdd88df3133e9658c8c7a23fe6b7866883", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "cabd5c8a41a41ee4c23c1596ec124abdd88df3133e9658c8c7a23fe6b7866883", 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" "cabd5c8a41a41ee4c23c1596ec124abdd88df3133e9658c8c7a23fe6b7866883" "/opt/rustwide/cargo-home/bin/cargo" "+1.99.0-beta.8" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "cabd5c8a41a41ee4c23c1596ec124abdd88df3133e9658c8c7a23fe6b7866883", 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" "cabd5c8a41a41ee4c23c1596ec124abdd88df3133e9658c8c7a23fe6b7866883" "/opt/rustwide/cargo-home/bin/cargo" "+1.99.0-beta.8" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stderr]    Compiling pullgraph v0.1.2 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 0.33s
[INFO] running `Command { std: "docker" "inspect" "cabd5c8a41a41ee4c23c1596ec124abdd88df3133e9658c8c7a23fe6b7866883", 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" "cabd5c8a41a41ee4c23c1596ec124abdd88df3133e9658c8c7a23fe6b7866883" "/opt/rustwide/cargo-home/bin/cargo" "+1.99.0-beta.8" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stderr]    Compiling pullgraph v0.1.2 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 2.45s
[INFO] running `Command { std: "docker" "inspect" "cabd5c8a41a41ee4c23c1596ec124abdd88df3133e9658c8c7a23fe6b7866883", 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" "cabd5c8a41a41ee4c23c1596ec124abdd88df3133e9658c8c7a23fe6b7866883" "/opt/rustwide/cargo-home/bin/cargo" "+1.99.0-beta.8" "test" "--frozen", kill_on_drop: false }`
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.01s
[INFO] [stdout] 
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/pullgraph-a45c53daa70d5ab6)
[INFO] [stdout] running 34 tests
[INFO] [stderr]      Running tests/context.rs (/opt/rustwide/target/debug/deps/context-5b1f908399e45e3c)
[INFO] [stdout] test context::tests::get_returns_none_for_unproduced_type ... ok
[INFO] [stdout] test context::tests::produce_returns_none_for_undemanded_type ... ok
[INFO] [stdout] test context::tests::produce_returns_true_for_cached_value ... ok
[INFO] [stdout] test context::tests::produce_returns_false_when_provider_removed ... ok
[INFO] [stdout] test context::tests::take_provider_returns_none_after_removal ... ok
[INFO] [stdout] test error::tests::error_trait_implemented ... ok
[INFO] [stdout] test error::tests::debug_is_derived ... ok
[INFO] [stdout] test error::tests::display_already_registered ... ok
[INFO] [stdout] test error::tests::display_dependency_cycle ... ok
[INFO] [stdout] test graph::tests::cycle_found_direct ... ok
[INFO] [stdout] test graph::tests::cycle_found_transitive ... ok
[INFO] [stdout] test graph::tests::diamond_no_false_positive ... ok
[INFO] [stdout] test graph::tests::no_cycle_empty_graph ... ok
[INFO] [stdout] test error::tests::display_no_provider ... ok
[INFO] [stdout] test graph::tests::no_cycle_long_chain ... ok
[INFO] [stdout] test graph::tests::no_cycle_one_way ... ok
[INFO] [stdout] test graph::tests::no_path_to_target ... ok
[INFO] [stdout] test graph::tests::visited_node_prevents_infinite_loop ... ok
[INFO] [stdout] test graph::tests::visited_node_skipped_on_revisit ... ok
[INFO] [stdout] test provider::tests::context_fn_produces_value ... ok
[INFO] [stdout] test provider::tests::dep_handle_ref_delegates ... ok
[INFO] [stdout] test provider::tests::provider_handle_type_id ... ok
[INFO] [stdout] test provider::tests::provider_trait_activate_deactivate ... ok
[INFO] [stdout] test provider::tests::zero_arg_closure_provider ... ok
[INFO] [stdout] test pullgraph::tests::default_creates_pullgraph ... ok
[INFO] [stdout] test pullgraph::tests::derive_self_cycle_detected ... ok
[INFO] [stdout] test pullgraph::tests::derive_transitive_cycle_detected ... ok
[INFO] [stdout] test pullgraph::tests::provide_with_deps_self_cycle_detected ... ok
[INFO] [stdout] test pullgraph::tests::provide_with_deps_transitive_cycle_detected ... ok
[INFO] [stdout] test graph::tests::visited_node_skipped ... ok
[INFO] [stdout] test pullgraph::tests::clone_shares_state ... ok
[INFO] [stdout] test context::tests::produce_dependency_chain ... ok
[INFO] [stdout] test context::tests::self_recursive_production_panics - should panic ... ok
[INFO] [stdout] test context::tests::null_provider_panics - should panic ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 34 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.02s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 5 tests
[INFO] [stdout] test new_context_allows_reproduction ... ok
[INFO] [stdout] test get_without_produce ... ok
[INFO] [stdout] test dependency_production_in_context ... ok
[INFO] [stdout] test context_idempotency ... ok
[INFO] [stdout] test produce_none_when_undemanded ... ok
[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] [stderr]      Running tests/demand.rs (/opt/rustwide/target/debug/deps/demand-76b33f6852b74737)
[INFO] [stderr]      Running tests/derive.rs (/opt/rustwide/target/debug/deps/derive-64dbcf6040b32895)
[INFO] [stderr]      Running tests/graph.rs (/opt/rustwide/target/debug/deps/graph-86d1ea4c3e961f04)
[INFO] [stderr]      Running tests/lifecycle.rs (/opt/rustwide/target/debug/deps/lifecycle-93ebc572722d9426)
[INFO] [stderr]      Running tests/providers.rs (/opt/rustwide/target/debug/deps/providers-eeb8cbf144c06300)
[INFO] [stderr]      Running tests/shared.rs (/opt/rustwide/target/debug/deps/shared-534c94af88baeed2)
[INFO] [stdout] 
[INFO] [stdout] running 6 tests
[INFO] [stdout] test demand_clone_creates_independent_reference ... ok
[INFO] [stdout] test demand_creates_for_registered_type ... ok
[INFO] [stdout] test demand_decrements_on_drop ... ok
[INFO] [stdout] test demand_increments_count ... ok
[INFO] [stdout] test demand_type_id_matches ... ok
[INFO] [stdout] test want_error_for_unregistered_type ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 6 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 derive_chain_produces_correctly ... ok
[INFO] [stdout] test derive_only_produces_demanded ... ok
[INFO] [stdout] test diamond_dependency ... 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 3 tests
[INFO] [stdout] test activation_propagation ... ok
[INFO] [stdout] test cycle_detection ... ok
[INFO] [stdout] test deactivation_unwinding ... 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 3 tests
[INFO] [stdout] test activate_called_on_first_demand ... ok
[INFO] [stdout] test deactivate_called_on_last_drop ... ok
[INFO] [stdout] test produce_count_tracked ... 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 4 tests
[INFO] [stdout] test provide_registers_provider ... ok
[INFO] [stdout] test derive_registers_provider ... ok
[INFO] [stdout] test provide_duplicate_returns_error ... ok
[INFO] [stdout] test provide_with_deps_registers ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 4 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 2 tests
[INFO] [stdout] test shared_derived_production ... ok
[INFO] [stdout] test multiple_demanders_share_production ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 2 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]    Doc-tests pullgraph
[INFO] [stdout] 
[INFO] [stdout] running 2 tests
[INFO] [stdout] test src/lib.rs - (line 10) ... ignored
[INFO] [stdout] test src/provider.rs - provider::Provider (line 14) ... ignored
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 2 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stdout] all doctests ran in 0.28s; merged doctests compilation took 0.27s
[INFO] running `Command { std: "docker" "inspect" "cabd5c8a41a41ee4c23c1596ec124abdd88df3133e9658c8c7a23fe6b7866883", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "cabd5c8a41a41ee4c23c1596ec124abdd88df3133e9658c8c7a23fe6b7866883", kill_on_drop: false }`
[INFO] [stdout] cabd5c8a41a41ee4c23c1596ec124abdd88df3133e9658c8c7a23fe6b7866883
