[INFO] fetching crate vcad-kernel-optics 0.10.0...
[INFO] testing vcad-kernel-optics-0.10.0 against 1.99.0-beta.8 for beta-1.100-2
[INFO] extracting crate vcad-kernel-optics 0.10.0 into /workspace/builds/worker-4-tc1/source
[INFO] started tweaking crates.io crate vcad-kernel-optics 0.10.0
[INFO] removed 0 missing examples
[INFO] removed 0 missing tests
[INFO] finished tweaking crates.io crate vcad-kernel-optics 0.10.0
[INFO] tweaked toml for crates.io crate vcad-kernel-optics 0.10.0 written to /workspace/builds/worker-4-tc1/source/Cargo.toml
[INFO] validating manifest of crates.io crate vcad-kernel-optics 0.10.0 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 vcad-kernel-optics 0.10.0 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] [stderr]     Updating crates.io index
[INFO] [stderr]  Downloading crates ...
[INFO] [stderr]   Downloaded vcad-receipt v0.10.0
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-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] 3505eb3289121ffbf59610c87b1ba9ec35f8f9456954376584fa0fc87ef18477
[INFO] running `Command { std: "docker" "start" "3505eb3289121ffbf59610c87b1ba9ec35f8f9456954376584fa0fc87ef18477", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "3505eb3289121ffbf59610c87b1ba9ec35f8f9456954376584fa0fc87ef18477", 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" "3505eb3289121ffbf59610c87b1ba9ec35f8f9456954376584fa0fc87ef18477" "/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" "3505eb3289121ffbf59610c87b1ba9ec35f8f9456954376584fa0fc87ef18477", 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" "3505eb3289121ffbf59610c87b1ba9ec35f8f9456954376584fa0fc87ef18477" "/opt/rustwide/cargo-home/bin/cargo" "+1.99.0-beta.8" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stderr]    Compiling proc-macro2 v1.0.106
[INFO] [stderr]    Compiling quote v1.0.44
[INFO] [stderr]    Compiling serde_core v1.0.228
[INFO] [stderr]    Compiling zmij v1.0.17
[INFO] [stderr]    Compiling serde v1.0.228
[INFO] [stderr]    Compiling serde_json v1.0.149
[INFO] [stderr]    Compiling itoa v1.0.17
[INFO] [stderr]    Compiling memchr v2.7.6
[INFO] [stderr]    Compiling syn v2.0.114
[INFO] [stderr]    Compiling serde_derive v1.0.228
[INFO] [stderr]    Compiling vcad-receipt v0.10.0
[INFO] [stderr]    Compiling vcad-kernel-optics v0.10.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 10.26s
[INFO] running `Command { std: "docker" "inspect" "3505eb3289121ffbf59610c87b1ba9ec35f8f9456954376584fa0fc87ef18477", 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" "3505eb3289121ffbf59610c87b1ba9ec35f8f9456954376584fa0fc87ef18477" "/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 vcad-kernel-optics v0.10.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 1.49s
[INFO] running `Command { std: "docker" "inspect" "3505eb3289121ffbf59610c87b1ba9ec35f8f9456954376584fa0fc87ef18477", 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" "3505eb3289121ffbf59610c87b1ba9ec35f8f9456954376584fa0fc87ef18477" "/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.04s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/vcad_kernel_optics-081b858bd6ae5857)
[INFO] [stdout] 
[INFO] [stdout] running 33 tests
[INFO] [stdout] test optimize::tests::infeasible_regions_are_never_accepted ... ok
[INFO] [stdout] test paraxial::tests::diverging_system_fails_closed ... ok
[INFO] [stdout] test paraxial::tests::lagrange_invariant_is_conserved ... ok
[INFO] [stdout] test paraxial::tests::recurrence_and_matrix_agree ... ok
[INFO] [stdout] test prescription::tests::serde_round_trip ... ok
[INFO] [stdout] test prescription::tests::validation_is_fail_closed ... ok
[INFO] [stdout] test prescription::tests::vertex_accumulates_thickness ... ok
[INFO] [stdout] test spot::tests::airy_radius_reference_value ... ok
[INFO] [stdout] test receipt::tests::broken_traces_refuse_to_become_claims ... ok
[INFO] [stdout] test glass::tests::air_is_exactly_one_and_dispersionless ... ok
[INFO] [stdout] test glass::tests::normal_dispersion_holds ... ok
[INFO] [stdout] test spot::tests::defocus_blur_matches_similar_triangles ... ok
[INFO] [stdout] test optimize::tests::descends_a_smooth_bowl ... ok
[INFO] [stdout] test spot::tests::pupil_lattice_is_disk_uniform ... ok
[INFO] [stdout] test spot::tests::every_ray_is_accounted_for ... ok
[INFO] [stdout] test glass::tests::sellmeier_recovers_catalog_nd_and_abbe ... ok
[INFO] [stdout] test thirdorder::tests::equiconvex_vs_best_form_ratio ... ok
[INFO] [stdout] test thirdorder::tests::ucurve_is_a_parabola_with_positive_curvature ... ok
[INFO] [stdout] test trace::tests::biconvex_bends_ray_toward_axis ... ok
[INFO] [stdout] test trace::tests::concave_surface_hits_near_sheet ... ok
[INFO] [stdout] test trace::tests::tir_is_reported_not_dropped ... ok
[INFO] [stdout] test trace::tests::vignetting_names_the_surface ... ok
[INFO] [stdout] test spot::tests::spot_collapses_at_paraxial_focus ... ok
[INFO] [stdout] test trace::tests::plane_normal_incidence_is_exact ... ok
[INFO] [stdout] test glass::tests::constant_glass_reports_infinite_abbe ... ok
[INFO] [stdout] test receipt::tests::claims_carry_airy_context_and_provenance ... ok
[INFO] [stdout] test prescription::tests::sag_matches_sphere_closed_form ... ok
[INFO] [stdout] test optimize::tests::multi_start_escapes_a_bad_basin ... ok
[INFO] [stdout] test thirdorder::tests::lsa_scales_as_h_squared ... ok
[INFO] [stdout] test thirdorder::tests::best_form_matches_textbook_value ... ok
[INFO] [stdout] test optimize::tests::survives_astronomically_small_objectives ... ok
[INFO] [stdout] test receipt::tests::design_claims_are_predicted_basis ... ok
[INFO] [stdout] test trace::tests::snell_invariant_at_steep_incidence ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 33 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/analytic.rs (/opt/rustwide/target/debug/deps/analytic-c3e714e2b58d3212)
[INFO] [stdout] 
[INFO] [stdout] running 7 tests
[INFO] [stdout] test exact_trace_paraxial_limit ... ok
[INFO] [stdout] test achromat_condition_kills_chromatic_shift ... ok
[INFO] [stdout] test published_doublet_efl ... ok
[INFO] [stdout] test thick_lens_closed_forms ... ok
[INFO] [stdout] test seidel_ucurve ... ok
[INFO] [stdout] test abbe_chromatic_shift ... ok
[INFO] [stdout] test published_achromat_beats_singlet_spot ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 7 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]    Doc-tests vcad_kernel_optics
[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] running `Command { std: "docker" "inspect" "3505eb3289121ffbf59610c87b1ba9ec35f8f9456954376584fa0fc87ef18477", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "3505eb3289121ffbf59610c87b1ba9ec35f8f9456954376584fa0fc87ef18477", kill_on_drop: false }`
[INFO] [stdout] 3505eb3289121ffbf59610c87b1ba9ec35f8f9456954376584fa0fc87ef18477
