[INFO] fetching crate emergent 1.5.13...
[INFO] testing emergent-1.5.13 against master#61469b682c2b0bf9cebc4622f1859e2bb3b7ebca for pr-91743
[INFO] extracting crate emergent 1.5.13 into /workspace/builds/worker-37/source
[INFO] validating manifest of crates.io crate emergent 1.5.13 on toolchain 61469b682c2b0bf9cebc4622f1859e2bb3b7ebca
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+61469b682c2b0bf9cebc4622f1859e2bb3b7ebca" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] started tweaking crates.io crate emergent 1.5.13
[INFO] finished tweaking crates.io crate emergent 1.5.13
[INFO] tweaked toml for crates.io crate emergent 1.5.13 written to /workspace/builds/worker-37/source/Cargo.toml
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+61469b682c2b0bf9cebc4622f1859e2bb3b7ebca" "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" "+61469b682c2b0bf9cebc4622f1859e2bb3b7ebca" "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:d190cb871061d98bc6d0581d85cb2ecb09a0f8a142ba5463de30be9999fc3251" "/opt/rustwide/cargo-home/bin/cargo" "+61469b682c2b0bf9cebc4622f1859e2bb3b7ebca" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] 5e97d78a59d53c9b0f72e6f393d0c76a768a482c6e2d3c8ead384e05808a98d2
[INFO] running `Command { std: "docker" "start" "-a" "5e97d78a59d53c9b0f72e6f393d0c76a768a482c6e2d3c8ead384e05808a98d2", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "5e97d78a59d53c9b0f72e6f393d0c76a768a482c6e2d3c8ead384e05808a98d2", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "5e97d78a59d53c9b0f72e6f393d0c76a768a482c6e2d3c8ead384e05808a98d2", kill_on_drop: false }`
[INFO] [stdout] 5e97d78a59d53c9b0f72e6f393d0c76a768a482c6e2d3c8ead384e05808a98d2
[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:d190cb871061d98bc6d0581d85cb2ecb09a0f8a142ba5463de30be9999fc3251" "/opt/rustwide/cargo-home/bin/cargo" "+61469b682c2b0bf9cebc4622f1859e2bb3b7ebca" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] fcf0685c92260ef74151b8f5ac4da263bc3cacf6439d57aa524c6681eea76dc0
[INFO] running `Command { std: "docker" "start" "-a" "fcf0685c92260ef74151b8f5ac4da263bc3cacf6439d57aa524c6681eea76dc0", kill_on_drop: false }`
[INFO] [stderr]    Compiling emergent v1.5.13 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 1.11s
[INFO] running `Command { std: "docker" "inspect" "fcf0685c92260ef74151b8f5ac4da263bc3cacf6439d57aa524c6681eea76dc0", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "fcf0685c92260ef74151b8f5ac4da263bc3cacf6439d57aa524c6681eea76dc0", kill_on_drop: false }`
[INFO] [stdout] fcf0685c92260ef74151b8f5ac4da263bc3cacf6439d57aa524c6681eea76dc0
[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:d190cb871061d98bc6d0581d85cb2ecb09a0f8a142ba5463de30be9999fc3251" "/opt/rustwide/cargo-home/bin/cargo" "+61469b682c2b0bf9cebc4622f1859e2bb3b7ebca" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 6c757d2dc8798afb18371a131c980ed2d6da90f96f0a1941b17633d5609d58d7
[INFO] running `Command { std: "docker" "start" "-a" "6c757d2dc8798afb18371a131c980ed2d6da90f96f0a1941b17633d5609d58d7", kill_on_drop: false }`
[INFO] [stderr]    Compiling emergent v1.5.13 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 2.15s
[INFO] [stderr]   Executable unittests src/lib.rs (/opt/rustwide/target/debug/deps/emergent-aa0828089148c757)
[INFO] running `Command { std: "docker" "inspect" "6c757d2dc8798afb18371a131c980ed2d6da90f96f0a1941b17633d5609d58d7", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "6c757d2dc8798afb18371a131c980ed2d6da90f96f0a1941b17633d5609d58d7", kill_on_drop: false }`
[INFO] [stdout] 6c757d2dc8798afb18371a131c980ed2d6da90f96f0a1941b17633d5609d58d7
[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:d190cb871061d98bc6d0581d85cb2ecb09a0f8a142ba5463de30be9999fc3251" "/opt/rustwide/cargo-home/bin/cargo" "+61469b682c2b0bf9cebc4622f1859e2bb3b7ebca" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] 88c47db50b90625b7fa2ba997046d72dd49b0c8c51b0d53bc65c639c69fbc834
[INFO] running `Command { std: "docker" "start" "-a" "88c47db50b90625b7fa2ba997046d72dd49b0c8c51b0d53bc65c639c69fbc834", kill_on_drop: false }`
[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 src/lib.rs (/opt/rustwide/target/debug/deps/emergent-aa0828089148c757)
[INFO] [stdout] 
[INFO] [stdout] running 10 tests
[INFO] [stdout] test tests::test_decision_making_task ... ok
[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_planner ... ok
[INFO] [stdout] test tests::test_selector ... ok
[INFO] [stdout] test tests::test_send_sync ... ok
[INFO] [stdout] test tests::test_sequencer ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 10 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s
[INFO] [stdout] 
[INFO] [stderr]    Doc-tests emergent
[INFO] [stdout] 
[INFO] [stdout] running 38 tests
[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/condition.rs - condition::ConditionInvert (line 85) ... ok
[INFO] [stdout] test src/combinators/count.rs - combinators::count::CombinatorCount (line 40) ... ok
[INFO] [stdout] test src/task.rs - task::Task (line 34) ... ok
[INFO] [stdout] test src/consideration.rs - consideration::ConsiderationRemap (line 98) ... ok
[INFO] [stdout] test src/consideration.rs - consideration::ClosureConsideration (line 61) ... ok
[INFO] [stdout] test src/combinators/any.rs - combinators::any::CombinatorAny (line 8) ... ok
[INFO] [stdout] test src/evaluators/product.rs - evaluators::product::EvaluatorProduct (line 8) ... ok
[INFO] [stdout] test src/consideration.rs - consideration::ConditionConsideration (line 164) ... ok
[INFO] [stdout] test src/score_mapping.rs - score_mapping::ClosureScoreMapping (line 61) ... ok
[INFO] [stdout] test src/score_mapping.rs - score_mapping::NoScoreMapping (line 44) ... ok
[INFO] [stdout] test src/score_mapping.rs - score_mapping::InverseScoreMapping (line 200) ... ok
[INFO] [stdout] test src/task.rs - task::ClosureTask (line 86) ... ok
[INFO] [stdout] test src/decision_makers/mod.rs - decision_makers::DecisionMaker (line 83) ... ok
[INFO] [stdout] test src/score_mapping.rs - score_mapping::ChainedScoreMapping (line 93) ... ok
[INFO] [stdout] test src/evaluators/min.rs - evaluators::min::EvaluatorMin (line 8) ... ok
[INFO] [stdout] test src/score_mapping.rs - score_mapping::ScoreMapping (line 12) ... ok
[INFO] [stdout] test src/consideration.rs - consideration::Consideration (line 22) ... ok
[INFO] [stdout] test src/combinators/all.rs - combinators::all::CombinatorAll (line 8) ... ok
[INFO] [stdout] test src/evaluators/sum.rs - evaluators::sum::EvaluatorSum (line 8) ... ok
[INFO] [stdout] test src/decision_makers/selector.rs - decision_makers::selector::ClosureSelectorStatePicker (line 311) ... ok
[INFO] [stdout] test src/score_mapping.rs - score_mapping::ReverseScoreMapping (line 178) ... ok
[INFO] [stdout] test src/score_mapping.rs - score_mapping::ScoreRemap (line 143) ... ok
[INFO] [stdout] test src/decision_makers/selector.rs - decision_makers::selector::OrderedSelectorStatePicker (line 342) ... 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 280) ... ok
[INFO] [stdout] test src/decision_makers/reasoner.rs - decision_makers::reasoner::Reasoner (line 89) ... ok
[INFO] [stdout] test src/decision_makers/sequencer.rs - decision_makers::sequencer::Sequencer (line 43) ... ok
[INFO] [stdout] test src/evaluators/max.rs - evaluators::max::EvaluatorMax (line 8) ... ok
[INFO] [stdout] test src/memory/blackboard.rs - memory::blackboard::Blackboard (line 17) ... 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/decision_makers/machinery.rs - decision_makers::machinery::Machinery (line 192) ... ok
[INFO] [stdout] test src/decision_makers/parallelizer.rs - decision_makers::parallelizer::Parallelizer (line 43) ... ok
[INFO] [stdout] test src/builders/lod.rs - builders::lod::Lod (line 29) ... 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 0.99s
[INFO] [stdout] 
[INFO] running `Command { std: "docker" "inspect" "88c47db50b90625b7fa2ba997046d72dd49b0c8c51b0d53bc65c639c69fbc834", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "88c47db50b90625b7fa2ba997046d72dd49b0c8c51b0d53bc65c639c69fbc834", kill_on_drop: false }`
[INFO] [stdout] 88c47db50b90625b7fa2ba997046d72dd49b0c8c51b0d53bc65c639c69fbc834