[INFO] fetching crate optlib 0.4.0...
[INFO] testing optlib-0.4.0 against try#ecabaf78506b7a4668d42dc20268c086b93f0fad for pr-87041
[INFO] extracting crate optlib 0.4.0 into /workspace/builds/worker-9/source
[INFO] validating manifest of crates.io crate optlib 0.4.0 on toolchain ecabaf78506b7a4668d42dc20268c086b93f0fad
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+ecabaf78506b7a4668d42dc20268c086b93f0fad" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] started tweaking crates.io crate optlib 0.4.0
[INFO] finished tweaking crates.io crate optlib 0.4.0
[INFO] tweaked toml for crates.io crate optlib 0.4.0 written to /workspace/builds/worker-9/source/Cargo.toml
[INFO] crate crates.io crate optlib 0.4.0 already has a lockfile, it will not be regenerated
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+ecabaf78506b7a4668d42dc20268c086b93f0fad" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] [stderr]  Downloading crates ...
[INFO] [stderr]   Downloaded num_cpus v1.12.0
[INFO] [stderr]   Downloaded num-rational v0.2.3
[INFO] [stderr]   Downloaded num-iter v0.1.40
[INFO] [stderr]   Downloaded hermit-abi v0.1.6
[INFO] [stderr]   Downloaded optlib-testfunc v0.1.0
[INFO] [stderr]   Downloaded libc v0.2.66
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-9/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-9/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:34b6a614d2c27851fe6cbf88fbd1137609cefab8b10d0615aaeb6fd47975d74e" "/opt/rustwide/cargo-home/bin/cargo" "+ecabaf78506b7a4668d42dc20268c086b93f0fad" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] bcd511d110d048f0dc0904894a04e236c1dd2b874ec6eecc0006785b7fe0c729
[INFO] running `Command { std: "docker" "start" "-a" "bcd511d110d048f0dc0904894a04e236c1dd2b874ec6eecc0006785b7fe0c729", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "bcd511d110d048f0dc0904894a04e236c1dd2b874ec6eecc0006785b7fe0c729", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "bcd511d110d048f0dc0904894a04e236c1dd2b874ec6eecc0006785b7fe0c729", kill_on_drop: false }`
[INFO] [stdout] bcd511d110d048f0dc0904894a04e236c1dd2b874ec6eecc0006785b7fe0c729
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-9/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-9/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:34b6a614d2c27851fe6cbf88fbd1137609cefab8b10d0615aaeb6fd47975d74e" "/opt/rustwide/cargo-home/bin/cargo" "+ecabaf78506b7a4668d42dc20268c086b93f0fad" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 5794149a9ecc889fdc4f20cd653035f4181a7926ea40d05ac93f1c5f1bb870be
[INFO] running `Command { std: "docker" "start" "-a" "5794149a9ecc889fdc4f20cd653035f4181a7926ea40d05ac93f1c5f1bb870be", kill_on_drop: false }`
[INFO] [stderr]    Compiling libc v0.2.66
[INFO] [stderr]    Compiling cfg-if v0.1.10
[INFO] [stderr]    Compiling num-traits v0.2.11
[INFO] [stderr]    Compiling num-integer v0.1.42
[INFO] [stderr]    Compiling num-iter v0.1.40
[INFO] [stderr]    Compiling num-complex v0.2.4
[INFO] [stderr]    Compiling getrandom v0.1.14
[INFO] [stderr]    Compiling rand_core v0.5.1
[INFO] [stderr]    Compiling rand_chacha v0.2.1
[INFO] [stderr]    Compiling num-bigint v0.2.6
[INFO] [stderr]    Compiling rand v0.7.3
[INFO] [stderr]    Compiling num-rational v0.2.3
[INFO] [stderr]    Compiling num v0.2.1
[INFO] [stderr]    Compiling optlib v0.4.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 7.51s
[INFO] running `Command { std: "docker" "inspect" "5794149a9ecc889fdc4f20cd653035f4181a7926ea40d05ac93f1c5f1bb870be", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "5794149a9ecc889fdc4f20cd653035f4181a7926ea40d05ac93f1c5f1bb870be", kill_on_drop: false }`
[INFO] [stdout] 5794149a9ecc889fdc4f20cd653035f4181a7926ea40d05ac93f1c5f1bb870be
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-9/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-9/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:34b6a614d2c27851fe6cbf88fbd1137609cefab8b10d0615aaeb6fd47975d74e" "/opt/rustwide/cargo-home/bin/cargo" "+ecabaf78506b7a4668d42dc20268c086b93f0fad" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 681f11646ba3e6271c17fb9cc577bfb166ac810795bc8668a42ae68a72328a2b
[INFO] running `Command { std: "docker" "start" "-a" "681f11646ba3e6271c17fb9cc577bfb166ac810795bc8668a42ae68a72328a2b", kill_on_drop: false }`
[INFO] [stderr]    Compiling num_cpus v1.12.0
[INFO] [stderr]    Compiling optlib-testfunc v0.1.0
[INFO] [stderr]    Compiling optlib v0.4.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 9.76s
[INFO] running `Command { std: "docker" "inspect" "681f11646ba3e6271c17fb9cc577bfb166ac810795bc8668a42ae68a72328a2b", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "681f11646ba3e6271c17fb9cc577bfb166ac810795bc8668a42ae68a72328a2b", kill_on_drop: false }`
[INFO] [stdout] 681f11646ba3e6271c17fb9cc577bfb166ac810795bc8668a42ae68a72328a2b
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-9/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-9/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:34b6a614d2c27851fe6cbf88fbd1137609cefab8b10d0615aaeb6fd47975d74e" "/opt/rustwide/cargo-home/bin/cargo" "+ecabaf78506b7a4668d42dc20268c086b93f0fad" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] d93b747f640c4ce9d58aed6a64de644903974e6308352687a8888bcf15223eb9
[INFO] running `Command { std: "docker" "start" "-a" "d93b747f640c4ce9d58aed6a64de644903974e6308352687a8888bcf15223eb9", kill_on_drop: false }`
[INFO] [stdout] 
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 0.02s
[INFO] [stdout] running 94 tests
[INFO] [stderr]      Running unittests (/opt/rustwide/target/debug/deps/optlib-d3aa9d71233be495)
[INFO] [stdout] test genetic::creation::vec_float::tests::test_population_size ... ok
[INFO] [stdout] test particleswarm::postmove::tests::test_inside_interval ... ok
[INFO] [stdout] test particleswarm::postmove::tests::test_max ... ok
[INFO] [stdout] test particleswarm::postmove::tests::test_min ... ok
[INFO] [stdout] test particleswarm::postvelocitycalc::tests::test_max_velocity_abs_empty ... ok
[INFO] [stdout] test particleswarm::postvelocitycalc::tests::test_max_velocity_abs_change ... ok
[INFO] [stdout] test particleswarm::postvelocitycalc::tests::test_max_velocity_dimensions_empty ... ok
[INFO] [stdout] test particleswarm::postvelocitycalc::tests::test_max_velocity_abs_not_change ... ok
[INFO] [stdout] test particleswarm::tests::test_find_best_particle_empty ... ok
[INFO] [stdout] test particleswarm::tests::test_compare_floats ... ok
[INFO] [stdout] test particleswarm::tests::test_find_best_particle_many_01 ... ok
[INFO] [stdout] test particleswarm::tests::test_find_best_particle_many_02 ... ok
[INFO] [stdout] test particleswarm::tests::test_particle_move_to_better ... ok
[INFO] [stdout] test particleswarm::tests::test_find_best_particle_single ... ok
[INFO] [stdout] test particleswarm::tests::test_particle_move_to_worse ... ok
[INFO] [stdout] test particleswarm::tests::test_particle_new ... ok
[INFO] [stdout] test tools::statistics::tests::call_count_data_average_empty ... ok
[INFO] [stdout] test tools::statistics::tests::call_count_data_unite_01 ... ok
[INFO] [stdout] test tools::statistics::tests::call_count_data_unite_02 ... ok
[INFO] [stdout] test tools::statistics::tests::call_count_data_unite_03 ... ok
[INFO] [stdout] test tools::statistics::tests::call_count_data_unite_empty ... ok
[INFO] [stdout] test tools::statistics::tests::convergence_unite_01 ... ok
[INFO] [stdout] test tools::statistics::tests::convergence_unite_02 ... ok
[INFO] [stdout] test tools::statistics::tests::call_count_data_average_single_one ... ok
[INFO] [stdout] test tools::statistics::tests::call_count_data_average_several ... ok
[INFO] [stdout] test tools::statistics::tests::call_count_data_average_single_zero ... ok
[INFO] [stdout] test tools::statistics::tests::get_average_convergence_empty ... ok
[INFO] [stdout] test tools::statistics::tests::get_average_convergence_several_01 ... ok
[INFO] [stdout] test tools::statistics::tests::convergence_unite_04 ... ok
[INFO] [stdout] test tools::statistics::tests::get_average_convergence_several_03 ... ok
[INFO] [stdout] test tools::statistics::tests::get_average_convergence_several_02 ... ok
[INFO] [stdout] test tools::statistics::tests::get_average_convergence_single_01 ... ok
[INFO] [stdout] test tools::statistics::tests::get_average_convergence_single_02 ... ok
[INFO] [stdout] test tools::statistics::tests::get_average_goal_none_only ... ok
[INFO] [stdout] test tools::statistics::tests::get_average_goal_empty ... ok
[INFO] [stdout] test tools::statistics::tests::get_average_goal_several ... ok
[INFO] [stdout] test tools::statistics::tests::get_average_goal_single ... ok
[INFO] [stdout] test tools::statistics::tests::get_average_vec_float_several_02 ... ok
[INFO] [stdout] test tools::statistics::tests::get_average_vec_float_several_03 ... ok
[INFO] [stdout] test tools::statistics::tests::get_average_vec_float_several_01 ... ok
[INFO] [stdout] test tools::statistics::tests::get_average_vec_float_several_04 ... ok
[INFO] [stdout] test tools::statistics::tests::get_min_iterations_empty ... ok
[INFO] [stdout] test tools::statistics::tests::get_average_vec_float_single ... ok
[INFO] [stdout] test tools::statistics::tests::get_min_iterations_several_02 ... ok
[INFO] [stdout] test tools::statistics::tests::get_min_iterations_several_01 ... ok
[INFO] [stdout] test tools::statistics::tests::get_min_iterations_single_01 ... ok
[INFO] [stdout] test tools::statistics::tests::get_standard_deviation_goal_empty ... ok
[INFO] [stdout] test tools::statistics::tests::get_min_iterations_single_02 ... ok
[INFO] [stdout] test tools::statistics::tests::get_standard_deviation_goal_several ... ok
[INFO] [stdout] test tools::statistics::tests::get_standard_deviation_goal_none_only ... ok
[INFO] [stdout] test tools::statistics::tests::get_standard_deviation_goal_several_01 ... ok
[INFO] [stdout] test tools::statistics::tests::get_standard_deviation_goal_single ... ok
[INFO] [stdout] test tools::statistics::tests::get_standard_deviation_goal_several_02 ... ok
[INFO] [stdout] test tools::statistics::tests::get_standard_deviation_vec_float_empty ... ok
[INFO] [stdout] test tools::statistics::tests::get_standard_deviation_vec_float_equal ... ok
[INFO] [stdout] test particleswarm::postmove::tests::test_mix ... ok
[INFO] [stdout] test tools::statistics::tests::get_average_vec_float_empty ... ok
[INFO] [stdout] test tools::statistics::tests::get_standard_deviation_goal_equal ... ok
[INFO] [stdout] test tools::statistics::tests::get_average_vec_float_none_only ... ok
[INFO] [stdout] test particleswarm::postvelocitycalc::tests::test_max_velocity_dimensions_not_change ... ok
[INFO] [stdout] test tools::statistics::tests::call_count_data_average_single_two ... ok
[INFO] [stdout] test tools::statistics::tests::get_standard_deviation_vec_float_none_only ... ok
[INFO] [stdout] test tools::statistics::tests::get_success_rate_goal_full_success_01 ... ok
[INFO] [stdout] test particleswarm::postvelocitycalc::tests::test_max_velocity_dimensions_fix ... ok
[INFO] [stdout] test tools::statistics::tests::get_standard_deviation_vec_float_single ... ok
[INFO] [stdout] test tools::statistics::tests::get_success_rate_goal_full_success_fail ... ok
[INFO] [stdout] test tools::statistics::tests::get_success_rate_goal_full_success_half ... ok
[INFO] [stdout] test tools::statistics::tests::get_success_rate_goal_none ... ok
[INFO] [stdout] test tools::statistics::tests::get_success_rate_goal_full_success_two_of_three_01 ... ok
[INFO] [stdout] test tools::statistics::tests::get_success_rate_vec_solution_fail_single ... ok
[INFO] [stdout] test tools::statistics::tests::get_success_rate_goal_full_success_02 ... ok
[INFO] [stdout] test tools::statistics::tests::get_success_rate_vec_solution_half_02 ... ok
[INFO] [stdout] test tools::statistics::tests::get_success_rate_goal_full_success_two_of_three_02 ... ok
[INFO] [stdout] test tools::statistics::tests::statistics_convergence_unite_empty ... ok
[INFO] [stdout] test tools::statistics::tests::get_success_rate_goal_none_many ... ok
[INFO] [stdout] test tools::statistics::tests::get_success_rate_vec_solution_half_01 ... ok
[INFO] [stdout] test tools::statistics::tests::statistics_unite_results_02 ... ok
[INFO] [stdout] test tools::statistics::tests::get_success_rate_vec_solution_success_single ... ok
[INFO] [stdout] test tools::statistics::tests::statistics_unite_results_01 ... ok
[INFO] [stdout] test tools::statistics::tests::statistics_unite_results_03 ... ok
[INFO] [stdout] test genetic::creation::vec_float::tests::test_intervals ... ok
[INFO] [stdout] test tools::tests::test_intervals ... ok
[INFO] [stdout] test particleswarm::postmove::tests::test_empty ... ok
[INFO] [stdout] test tools::tests::test_empty ... ok
[INFO] [stdout] test tools::statistics::tests::get_success_rate_goal_empty ... ok
[INFO] [stdout] test genetic::creation::vec_float::tests::empty_population - should panic ... ok
[INFO] [stdout] test genetic::creation::vec_float::tests::invalid_intervals_01 - should panic ... ok
[INFO] [stderr]      Running tests/genetic-paraboloid.rs (/opt/rustwide/target/debug/deps/genetic_paraboloid-dc30c6f2a798c9ee)
[INFO] [stdout] test genetic::creation::vec_float::tests::empty_intervals - should panic ... ok
[INFO] [stdout] test genetic::creation::vec_float::tests::invalid_intervals_02 - should panic ... ok
[INFO] [stdout] test genetic::creation::vec_float::tests::invalid_intervals_03 - should panic ... ok
[INFO] [stdout] test particleswarm::postvelocitycalc::tests::test_max_velocity_invalid_size - should panic ... ok
[INFO] [stdout] test tools::tests::invalid_intervals_01 - should panic ... ok
[INFO] [stdout] test tools::tests::invalid_intervals_02 - should panic ... ok
[INFO] [stdout] test tools::tests::invalid_intervals_03 - should panic ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 94 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.07s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stdout] test genetic_paraboloid ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 4.12s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stderr]      Running tests/genetic-rastrigin.rs (/opt/rustwide/target/debug/deps/genetic_rastrigin-64ec191809607486)
[INFO] [stdout] test genetic_rastrigin ... ok
[INFO] [stderr]      Running tests/genetic-rosenbrock.rs (/opt/rustwide/target/debug/deps/genetic_rosenbrock-51139ad52633ee41)
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.32s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stdout] test genetic_rosenbrock has been running for over 60 seconds
[ERROR] error running command: no output for 300 seconds
[INFO] running `Command { std: "docker" "inspect" "d93b747f640c4ce9d58aed6a64de644903974e6308352687a8888bcf15223eb9", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "d93b747f640c4ce9d58aed6a64de644903974e6308352687a8888bcf15223eb9", kill_on_drop: false }`
[INFO] [stdout] d93b747f640c4ce9d58aed6a64de644903974e6308352687a8888bcf15223eb9