[INFO] fetching crate emergent 1.5.2...
[INFO] testing emergent-1.5.2 against master#a77da2d454e6caa227a85b16410b95f93495e7e0 for pr-91031
[INFO] extracting crate emergent 1.5.2 into /workspace/builds/worker-37/source
[INFO] validating manifest of crates.io crate emergent 1.5.2 on toolchain a77da2d454e6caa227a85b16410b95f93495e7e0
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+a77da2d454e6caa227a85b16410b95f93495e7e0" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] started tweaking crates.io crate emergent 1.5.2
[INFO] finished tweaking crates.io crate emergent 1.5.2
[INFO] tweaked toml for crates.io crate emergent 1.5.2 written to /workspace/builds/worker-37/source/Cargo.toml
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+a77da2d454e6caa227a85b16410b95f93495e7e0" "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" "+a77da2d454e6caa227a85b16410b95f93495e7e0" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-37/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-37/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:5736fa189c1c60b01babf4b8b698fe57b6ecc41933a7ff2e0b8d7a221459412b" "/opt/rustwide/cargo-home/bin/cargo" "+a77da2d454e6caa227a85b16410b95f93495e7e0" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] 36f5572fce0ed4b0c0db49e80946a09e25df70d316a9d3cea20409280d5b6144
[INFO] running `Command { std: "docker" "start" "-a" "36f5572fce0ed4b0c0db49e80946a09e25df70d316a9d3cea20409280d5b6144", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "36f5572fce0ed4b0c0db49e80946a09e25df70d316a9d3cea20409280d5b6144", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "36f5572fce0ed4b0c0db49e80946a09e25df70d316a9d3cea20409280d5b6144", kill_on_drop: false }`
[INFO] [stdout] 36f5572fce0ed4b0c0db49e80946a09e25df70d316a9d3cea20409280d5b6144
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-37/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-37/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:5736fa189c1c60b01babf4b8b698fe57b6ecc41933a7ff2e0b8d7a221459412b" "/opt/rustwide/cargo-home/bin/cargo" "+a77da2d454e6caa227a85b16410b95f93495e7e0" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 1110bcc2a933d59e0b886b254dd8abf6b1c3c6c261edb4bf27aec366c50fbe8d
[INFO] running `Command { std: "docker" "start" "-a" "1110bcc2a933d59e0b886b254dd8abf6b1c3c6c261edb4bf27aec366c50fbe8d", kill_on_drop: false }`
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] [stderr]    Compiling emergent v1.5.2 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 1.30s
[INFO] running `Command { std: "docker" "inspect" "1110bcc2a933d59e0b886b254dd8abf6b1c3c6c261edb4bf27aec366c50fbe8d", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "1110bcc2a933d59e0b886b254dd8abf6b1c3c6c261edb4bf27aec366c50fbe8d", kill_on_drop: false }`
[INFO] [stdout] 1110bcc2a933d59e0b886b254dd8abf6b1c3c6c261edb4bf27aec366c50fbe8d
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-37/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-37/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:5736fa189c1c60b01babf4b8b698fe57b6ecc41933a7ff2e0b8d7a221459412b" "/opt/rustwide/cargo-home/bin/cargo" "+a77da2d454e6caa227a85b16410b95f93495e7e0" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] c7ff12d2efd501db65486cdbc8af087e531598f6417faa3cfb05b917a60912c2
[INFO] running `Command { std: "docker" "start" "-a" "c7ff12d2efd501db65486cdbc8af087e531598f6417faa3cfb05b917a60912c2", kill_on_drop: false }`
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] [stderr]    Compiling emergent v1.5.2 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 2.78s
[INFO] running `Command { std: "docker" "inspect" "c7ff12d2efd501db65486cdbc8af087e531598f6417faa3cfb05b917a60912c2", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "c7ff12d2efd501db65486cdbc8af087e531598f6417faa3cfb05b917a60912c2", kill_on_drop: false }`
[INFO] [stdout] c7ff12d2efd501db65486cdbc8af087e531598f6417faa3cfb05b917a60912c2
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-37/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-37/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:5736fa189c1c60b01babf4b8b698fe57b6ecc41933a7ff2e0b8d7a221459412b" "/opt/rustwide/cargo-home/bin/cargo" "+a77da2d454e6caa227a85b16410b95f93495e7e0" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] 558da431488fb348739ed2df14b6601203c5264721388d229e00d069a31161fa
[INFO] running `Command { std: "docker" "start" "-a" "558da431488fb348739ed2df14b6601203c5264721388d229e00d069a31161fa", kill_on_drop: false }`
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 0.43s
[INFO] [stderr]      Running unittests (/opt/rustwide/target/debug/deps/emergent-9e2d29eafc739a65)
[INFO] [stdout] 
[INFO] [stdout] running 8 tests
[INFO] [stdout] test tests::test_behavior_tree ... ok
[INFO] [stdout] test tests::test_lod ... ok
[INFO] [stdout] test tests::test_machinery ... ok
[INFO] [stdout] test tests::test_parallelizer ... ok
[INFO] [stdout] test tests::test_reasoner ... ok
[INFO] [stdout] test tests::test_selector ... ok
[INFO] [stdout] test tests::test_sequencer ... ok
[INFO] [stdout] test tests::test_planner ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 8 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]    Doc-tests emergent
[INFO] [stdout] 
[INFO] [stdout] running 38 tests
[INFO] [stdout] test src/score_mapping.rs - score_mapping::InverseScoreMapping (line 200) ... ok
[INFO] [stdout] test src/score_mapping.rs - score_mapping::NoScoreMapping (line 44) ... ok
[INFO] [stdout] test src/score_mapping.rs - score_mapping::ChainedScoreMapping (line 93) ... ok
[INFO] [stdout] test src/combinators/count.rs - combinators::count::CombinatorCount (line 40) ... ok
[INFO] [stdout] test src/score_mapping.rs - score_mapping::ReverseScoreMapping (line 178) ... ok
[INFO] [stdout] test src/consideration.rs - consideration::Consideration (line 22) ... ok
[INFO] [stdout] test src/combinators/any.rs - combinators::any::CombinatorAny (line 8) ... ok
[INFO] [stdout] test src/condition.rs - condition::Condition (line 20) ... ok
[INFO] [stdout] test src/condition.rs - condition::ClosureCondition (line 50) ... ok
[INFO] [stdout] test src/decision_makers/mod.rs - decision_makers::DecisionMaker (line 84) ... ok
[INFO] [stdout] test src/consideration.rs - consideration::ConditionConsideration (line 164) ... ok
[INFO] [stdout] test src/consideration.rs - consideration::ConsiderationRemap (line 98) ... ok
[INFO] [stdout] test src/condition.rs - condition::ConsiderationCondition (line 124) ... ok
[INFO] [stdout] test src/decision_makers/selector.rs - decision_makers::selector::SelectorStatePicker (line 268) ... ok
[INFO] [stdout] test src/condition.rs - condition::ConditionInvert (line 85) ... ok
[INFO] [stdout] test src/evaluators/product.rs - evaluators::product::EvaluatorProduct (line 8) ... ok
[INFO] [stdout] test src/memory/blackboard.rs - memory::blackboard::Blackboard (line 17) ... ok
[INFO] [stdout] test src/score_mapping.rs - score_mapping::ClosureScoreMapping (line 61) ... ok
[INFO] [stdout] test src/evaluators/min.rs - evaluators::min::EvaluatorMin (line 8) ... ok
[INFO] [stdout] test src/task.rs - task::Task (line 36) ... ok
[INFO] [stdout] test src/task.rs - task::ClosureTask (line 99) ... ok
[INFO] [stdout] test src/score_mapping.rs - score_mapping::ScoreRemap (line 143) ... ok
[INFO] [stdout] test src/decision_makers/reasoner.rs - decision_makers::reasoner::Reasoner (line 89) ... ok
[INFO] [stdout] test src/decision_makers/selector.rs - decision_makers::selector::ClosureSelectorStatePicker (line 299) ... ok
[INFO] [stdout] test src/consideration.rs - consideration::ClosureConsideration (line 61) ... ok
[INFO] [stdout] test src/decision_makers/machinery.rs - decision_makers::machinery::Machinery (line 192) ... ok
[INFO] [stdout] test src/decision_makers/selector.rs - decision_makers::selector::OrderedSelectorStatePicker (line 330) ... ok
[INFO] [stdout] test src/combinators/all.rs - combinators::all::CombinatorAll (line 8) ... ok
[INFO] [stdout] test src/decision_makers/sequencer.rs - decision_makers::sequencer::Sequencer (line 43) ... ok
[INFO] [stdout] test src/evaluators/sum.rs - evaluators::sum::EvaluatorSum (line 8) ... ok
[INFO] [stdout] test src/builders/lod.rs - builders::lod::Lod (line 29) ... ok
[INFO] [stdout] test src/evaluators/max.rs - evaluators::max::EvaluatorMax (line 8) ... ok
[INFO] [stdout] test src/decision_makers/selector.rs - decision_makers::selector::Selector (line 86) ... ok
[INFO] [stdout] test src/memory/datatable.rs - memory::datatable::DataTable (line 6) ... ok
[INFO] [stdout] test src/score_mapping.rs - score_mapping::ScoreMapping (line 12) ... ok
[INFO] [stdout] test src/decision_makers/parallelizer.rs - decision_makers::parallelizer::Parallelizer (line 43) ... ok
[INFO] [stdout] test src/builders/behavior_tree.rs - builders::behavior_tree::BehaviorTree (line 111) ... ok
[INFO] [stdout] test src/decision_makers/planner.rs - decision_makers::planner::Planner (line 242) ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 38 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 1.36s
[INFO] [stdout] 
[INFO] running `Command { std: "docker" "inspect" "558da431488fb348739ed2df14b6601203c5264721388d229e00d069a31161fa", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "558da431488fb348739ed2df14b6601203c5264721388d229e00d069a31161fa", kill_on_drop: false }`
[INFO] [stdout] 558da431488fb348739ed2df14b6601203c5264721388d229e00d069a31161fa