[INFO] fetching crate z3 0.19.15... [INFO] testing z3-0.19.15 against master#1f7f8ea0721a3b1eb73e6c6d25cccb371434b320 for pr-154065-1 [INFO] extracting crate z3 0.19.15 into /workspace/builds/worker-0-tc1/source [INFO] started tweaking crates.io crate z3 0.19.15 [INFO] removed 0 missing tests [INFO] finished tweaking crates.io crate z3 0.19.15 [INFO] tweaked toml for crates.io crate z3 0.19.15 written to /workspace/builds/worker-0-tc1/source/Cargo.toml [INFO] validating manifest of crates.io crate z3 0.19.15 on toolchain 1f7f8ea0721a3b1eb73e6c6d25cccb371434b320 [INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+1f7f8ea0721a3b1eb73e6c6d25cccb371434b320" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }` [INFO] crate crates.io crate z3 0.19.15 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" "+1f7f8ea0721a3b1eb73e6c6d25cccb371434b320" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }` [INFO] [stderr] Blocking waiting for file lock on package cache [INFO] [stderr] Updating crates.io index [INFO] [stderr] Blocking waiting for file lock on package cache [INFO] [stderr] Downloading crates ... [INFO] [stderr] Downloaded ppmd-rust v1.4.0 [INFO] [stderr] Downloaded deflate64 v0.1.11 [INFO] [stderr] Downloaded typed-path v0.12.3 [INFO] [stderr] Downloaded z3-sys v0.10.9 [INFO] [stderr] Downloaded zip v8.3.0 [INFO] [stderr] Downloaded lzma-rust2 v0.16.2 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-tc1/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:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+1f7f8ea0721a3b1eb73e6c6d25cccb371434b320" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }` [INFO] [stdout] 5667ba080b1f59daab5967093fc55e2dffd05c28d27553f332e250e8d797d3d1 [INFO] running `Command { std: "docker" "start" "-a" "5667ba080b1f59daab5967093fc55e2dffd05c28d27553f332e250e8d797d3d1", kill_on_drop: false }` [INFO] running `Command { std: "docker" "inspect" "5667ba080b1f59daab5967093fc55e2dffd05c28d27553f332e250e8d797d3d1", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "5667ba080b1f59daab5967093fc55e2dffd05c28d27553f332e250e8d797d3d1", kill_on_drop: false }` [INFO] [stdout] 5667ba080b1f59daab5967093fc55e2dffd05c28d27553f332e250e8d797d3d1 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-tc1/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" "RUSTDOCFLAGS=--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:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+1f7f8ea0721a3b1eb73e6c6d25cccb371434b320" "build" "--frozen" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] 3c32d1339fdaadf1fba848e00f1c925f44ac576b055f8ffacfa54af84f89e05c [INFO] running `Command { std: "docker" "start" "-a" "3c32d1339fdaadf1fba848e00f1c925f44ac576b055f8ffacfa54af84f89e05c", kill_on_drop: false }` [INFO] [stderr] Compiling bytes v1.11.1 [INFO] [stderr] Compiling pin-project-lite v0.2.17 [INFO] [stderr] Compiling libc v0.2.183 [INFO] [stderr] Compiling cfg-if v1.0.4 [INFO] [stderr] Compiling futures-core v0.3.32 [INFO] [stderr] Compiling cc v1.2.57 [INFO] [stderr] Compiling memchr v2.8.0 [INFO] [stderr] Compiling zerofrom v0.1.6 [INFO] [stderr] Compiling itoa v1.0.17 [INFO] [stderr] Compiling smallvec v1.15.1 [INFO] [stderr] Compiling litemap v0.8.1 [INFO] [stderr] Compiling yoke v0.8.1 [INFO] [stderr] Compiling writeable v0.6.2 [INFO] [stderr] Compiling zerovec v0.11.5 [INFO] [stderr] Compiling zerotrie v0.2.3 [INFO] [stderr] Compiling once_cell v1.21.4 [INFO] [stderr] Compiling zeroize v1.8.2 [INFO] [stderr] Compiling icu_normalizer_data v2.1.1 [INFO] [stderr] Compiling icu_properties_data v2.1.2 [INFO] [stderr] Compiling futures-task v0.3.32 [INFO] [stderr] Compiling untrusted v0.9.0 [INFO] [stderr] Compiling percent-encoding v2.3.2 [INFO] [stderr] Compiling rustls-pki-types v1.14.0 [INFO] [stderr] Compiling futures-io v0.3.32 [INFO] [stderr] Compiling slab v0.4.12 [INFO] [stderr] Compiling http v1.4.0 [INFO] [stderr] Compiling num-traits v0.2.19 [INFO] [stderr] Compiling tinystr v0.8.2 [INFO] [stderr] Compiling potential_utf v0.1.4 [INFO] [stderr] Compiling futures-util v0.3.32 [INFO] [stderr] Compiling httparse v1.10.1 [INFO] [stderr] Compiling icu_collections v2.1.1 [INFO] [stderr] Compiling try-lock v0.2.5 [INFO] [stderr] Compiling tower-service v0.3.3 [INFO] [stderr] Compiling want v0.3.1 [INFO] [stderr] Compiling icu_locale_core v2.1.1 [INFO] [stderr] Compiling serde_core v1.0.228 [INFO] [stderr] Compiling clang-sys v1.8.1 [INFO] [stderr] Compiling ring v0.17.14 [INFO] [stderr] Compiling socket2 v0.6.3 [INFO] [stderr] Compiling mio v1.1.1 [INFO] [stderr] Compiling getrandom v0.2.17 [INFO] [stderr] Compiling icu_provider v2.1.1 [INFO] [stderr] Compiling tracing-core v0.1.36 [INFO] [stderr] Compiling tokio v1.50.0 [INFO] [stderr] Compiling icu_properties v2.1.2 [INFO] [stderr] Compiling http-body v1.0.1 [INFO] [stderr] Compiling icu_normalizer v2.1.1 [INFO] [stderr] Compiling futures-channel v0.3.32 [INFO] [stderr] Compiling atomic-waker v1.1.2 [INFO] [stderr] Compiling pin-utils v0.1.0 [INFO] [stderr] Compiling serde v1.0.228 [INFO] [stderr] Compiling subtle v2.6.1 [INFO] [stderr] Compiling tracing v0.1.44 [INFO] [stderr] Compiling form_urlencoded v1.2.2 [INFO] [stderr] Compiling sync_wrapper v1.0.2 [INFO] [stderr] Compiling regex-syntax v0.8.10 [INFO] [stderr] Compiling utf8_iter v1.0.4 [INFO] [stderr] Compiling base64 v0.22.1 [INFO] [stderr] Compiling ipnet v2.12.0 [INFO] [stderr] Compiling idna_adapter v1.2.1 [INFO] [stderr] Compiling tower-layer v0.3.3 [INFO] [stderr] Compiling bitflags v2.11.0 [INFO] [stderr] Compiling nom v7.1.3 [INFO] [stderr] Compiling idna v1.1.0 [INFO] [stderr] Compiling webpki-roots v1.0.6 [INFO] [stderr] Compiling regex-automata v0.4.14 [INFO] [stderr] Compiling rustls v0.23.37 [INFO] [stderr] Compiling libloading v0.8.9 [INFO] [stderr] Compiling iri-string v0.7.10 [INFO] [stderr] Compiling ryu v1.0.23 [INFO] [stderr] Compiling bindgen v0.72.1 [INFO] [stderr] Compiling itertools v0.13.0 [INFO] [stderr] Compiling hyper v1.8.1 [INFO] [stderr] Compiling tower v0.5.3 [INFO] [stderr] Compiling rustls-webpki v0.103.9 [INFO] [stderr] Compiling serde_urlencoded v0.7.1 [INFO] [stderr] Compiling regex v1.12.3 [INFO] [stderr] Compiling hyper-util v0.1.20 [INFO] [stderr] Compiling cexpr v0.6.0 [INFO] [stderr] Compiling tower-http v0.6.8 [INFO] [stderr] Compiling url v2.5.8 [INFO] [stderr] Compiling num-integer v0.1.46 [INFO] [stderr] Compiling http-body-util v0.1.3 [INFO] [stderr] Compiling rustc-hash v2.1.1 [INFO] [stderr] Compiling num-bigint v0.4.6 [INFO] [stderr] Compiling num-iter v0.1.45 [INFO] [stderr] Compiling num-complex v0.4.6 [INFO] [stderr] Compiling num-rational v0.4.2 [INFO] [stderr] Compiling num v0.4.3 [INFO] [stderr] Compiling tokio-rustls v0.26.4 [INFO] [stderr] Compiling hyper-rustls v0.27.7 [INFO] [stderr] Compiling reqwest v0.12.28 [INFO] [stderr] Compiling z3-sys v0.10.9 [INFO] [stderr] Compiling z3 v0.19.15 (/opt/rustwide/workdir) [INFO] [stderr] Finished `dev` profile [unoptimized + debuginfo] target(s) in 1m 07s [INFO] running `Command { std: "docker" "inspect" "3c32d1339fdaadf1fba848e00f1c925f44ac576b055f8ffacfa54af84f89e05c", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "3c32d1339fdaadf1fba848e00f1c925f44ac576b055f8ffacfa54af84f89e05c", kill_on_drop: false }` [INFO] [stdout] 3c32d1339fdaadf1fba848e00f1c925f44ac576b055f8ffacfa54af84f89e05c [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-tc1/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" "RUSTDOCFLAGS=--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:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+1f7f8ea0721a3b1eb73e6c6d25cccb371434b320" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] cb5464ac036aab62f0eab8a5e0cb6497713351f713036bb70aea53c49ba23d4a [INFO] running `Command { std: "docker" "start" "-a" "cb5464ac036aab62f0eab8a5e0cb6497713351f713036bb70aea53c49ba23d4a", kill_on_drop: false }` [INFO] [stderr] Compiling itertools v0.13.0 [INFO] [stderr] Compiling log v0.4.29 [INFO] [stderr] Compiling aho-corasick v1.1.4 [INFO] [stderr] Compiling anstream v0.6.21 [INFO] [stderr] Compiling jiff v0.2.23 [INFO] [stderr] Compiling rayon v1.11.0 [INFO] [stderr] Compiling reqwest v0.12.28 [INFO] [stderr] Compiling bindgen v0.72.1 [INFO] [stderr] Compiling regex-automata v0.4.14 [INFO] [stderr] Compiling regex v1.12.3 [INFO] [stderr] Compiling env_filter v1.0.0 [INFO] [stderr] Compiling env_logger v0.11.9 [INFO] [stderr] Compiling z3-sys v0.10.9 [INFO] [stderr] Compiling z3 v0.19.15 (/opt/rustwide/workdir) [INFO] [stderr] Finished `test` profile [unoptimized + debuginfo] target(s) in 34.62s [INFO] running `Command { std: "docker" "inspect" "cb5464ac036aab62f0eab8a5e0cb6497713351f713036bb70aea53c49ba23d4a", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "cb5464ac036aab62f0eab8a5e0cb6497713351f713036bb70aea53c49ba23d4a", kill_on_drop: false }` [INFO] [stdout] cb5464ac036aab62f0eab8a5e0cb6497713351f713036bb70aea53c49ba23d4a [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-tc1/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" "RUSTDOCFLAGS=--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:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+1f7f8ea0721a3b1eb73e6c6d25cccb371434b320" "test" "--frozen", kill_on_drop: false }` [INFO] [stdout] c03fd518fbbbeb590086f1e93f784e0f56ba0938811410ca0a3e836df9600703 [INFO] running `Command { std: "docker" "start" "-a" "c03fd518fbbbeb590086f1e93f784e0f56ba0938811410ca0a3e836df9600703", kill_on_drop: false }` [INFO] [stderr] Finished `test` profile [unoptimized + debuginfo] target(s) in 0.25s [INFO] [stderr] Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/z3-f63d9ad6926609d6) [INFO] [stdout] [INFO] [stdout] running 8 tests [INFO] [stdout] test model::tests::test_unsat ... ok [INFO] [stdout] test func_decl::test::test_translate_func_decl ... ok [INFO] [stdout] test translate::synchronization::thread_tests::test_send_vec ... ok [INFO] [stdout] test translate::synchronization::thread_tests::test_send ... ok [INFO] [stdout] test model::tests::test_sort_universes ... ok [INFO] [stdout] test ast::float::tests::test_nonstandard_float ... ok [INFO] [stdout] test translate::synchronization::thread_tests::test_round_trip ... ok [INFO] [stdout] test translate::synchronization::rayon_tests::test_rayon ... ok [INFO] [stdout] [INFO] [stdout] test result: ok. 8 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 1.30s [INFO] [stdout] [INFO] [stderr] Running tests/algebraic_tests.rs (/opt/rustwide/target/debug/deps/algebraic_tests-81728d6e13e9ffa1) [INFO] [stdout] [INFO] [stdout] running 31 tests [INFO] [stdout] test clone_produces_algebraically_equal_value ... ok [INFO] [stdout] test div ... ok [INFO] [stdout] test cube_root_of_8_is_2 ... ok [INFO] [stdout] test all_algebraic_values_satisfy_is_app ... ok [INFO] [stdout] test display_and_debug_produce_nonempty_strings ... ok [INFO] [stdout] test add ... ok [INFO] [stdout] test eq_algebraic_reflexive_and_discriminating ... ok [INFO] [stdout] test rational_algebraic_has_numeral_kind ... ok [INFO] [stdout] test root_and_power_are_inverses ... ok [INFO] [stdout] test from_algebraic_widens_to_concrete_real ... ok [INFO] [stdout] test ast_ne_returns_satisfiable_bool_for_distinct_values ... ok [INFO] [stdout] test is_value_true_for_rational_numerals ... ok [INFO] [stdout] test is_value_false_for_symbolic_real ... ok [INFO] [stdout] test ast_eq_returns_satisfiable_bool_for_equal_values ... ok [INFO] [stdout] test sign_of_negative ... ok [INFO] [stdout] test mul ... ok [INFO] [stdout] test lt_and_gt ... ok [INFO] [stdout] test roundtrip_rational_through_real ... ok [INFO] [stdout] test sort_of_computed_algebraic_is_real ... ok [INFO] [stdout] test sqrt2_is_irrational ... ok [INFO] [stdout] test sort_of_rational_algebraic_is_real ... ok [INFO] [stdout] test try_from_rational_real_succeeds ... ok [INFO] [stdout] test widened_algebraic_makes_constraint_unsat ... ignored, Fails when run with z3 4.8; likely an outdated upstream bug [INFO] [stdout] test try_from_symbolic_real_fails ... ok [INFO] [stdout] test sign_of_positive ... ok [INFO] [stdout] test sort_after_arithmetic_is_real ... ok [INFO] [stdout] test sign_of_zero ... ok [INFO] [stdout] test sqrt2_lies_between_one_and_two ... ok [INFO] [stdout] test sub ... ok [INFO] [stdout] test solver_returns_algebraic_value_convertible_via_try_from ... ok [INFO] [stdout] test widened_algebraic_used_as_symbolic_bound ... ok [INFO] [stdout] [INFO] [stdout] test result: ok. 30 passed; 0 failed; 1 ignored; 0 measured; 0 filtered out; finished in 0.31s [INFO] [stdout] [INFO] [stderr] Running tests/ast_vector_tests.rs (/opt/rustwide/target/debug/deps/ast_vector_tests-ba251e94d99b4dfa) [INFO] [stdout] [INFO] [stdout] running 25 tests [INFO] [stdout] test collect_map_over_simplify ... ok [INFO] [stdout] test collect_mapped_ast_transformations ... ok [INFO] [stdout] test from_iterator_of_ast ... ok [INFO] [stdout] test from_slice_of_ast ... ok [INFO] [stdout] test collect_filter_map_selects_typed_elements ... ok [INFO] [stdout] test from_vec_of_ast ... ok [INFO] [stdout] test collect_from_range_map_creates_asts_inline ... ok [INFO] [stdout] test collect_from_chained_iterators ... ok [INFO] [stdout] test into_iter_consuming_yields_all_elements ... ok [INFO] [stdout] test collect_after_map ... ok [INFO] [stdout] test collect_roundtrip_through_iter ... ok [INFO] [stdout] test into_iter_exact_size ... ok [INFO] [stdout] test iter_yields_all_elements ... ok [INFO] [stdout] test ref_into_iter_borrows ... ok [INFO] [stdout] test from_empty_vec ... ok [INFO] [stdout] test from_slice_idiomatic_replaces_push_loop ... ok [INFO] [stdout] test iter_exact_size ... ok [INFO] [stdout] test try_into_typed_vec_wrong_type_fails ... ok [INFO] [stdout] test try_from_ast_vector_for_vec_int ... ok [INFO] [stdout] test try_into_typed_vec_bool_success ... ok [INFO] [stdout] test try_from_ast_vector_for_vec_bool ... ok [INFO] [stdout] test try_into_typed_vec_empty ... ok [INFO] [stdout] test try_from_ast_vector_type_mismatch_error ... ok [INFO] [stdout] test solver_get_assertions_returns_correct_count ... ok [INFO] [stdout] test solver_get_consequences_roundtrip ... ok [INFO] [stdout] [INFO] [stdout] test result: ok. 25 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.28s [INFO] [stdout] [INFO] [stderr] Running tests/datatype_builder_tests.rs (/opt/rustwide/target/debug/deps/datatype_builder_tests-77b372c6ebf10792) [INFO] [stdout] [INFO] [stdout] running 3 tests [INFO] [stdout] test test_datatype_accessor_constructors ... ok [INFO] [stderr] Running tests/lib.rs (/opt/rustwide/target/debug/deps/lib-576ca2fcd9b14698) [INFO] [stdout] test test_create_datatypes_with_explicit_accessors_and_constructors ... ok [INFO] [stdout] test test_create_datatypes_empty_should_panic - should panic ... ok [INFO] [stdout] [INFO] [stdout] test result: ok. 3 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.10s [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] running 169 tests [INFO] [stdout] test algebraic_tests::display_and_debug_produce_nonempty_strings ... ok [INFO] [stdout] test algebraic_tests::clone_produces_algebraically_equal_value ... ok [INFO] [stdout] test algebraic_tests::div ... ok [INFO] [stdout] test algebraic_tests::all_algebraic_values_satisfy_is_app ... ok [INFO] [stdout] test algebraic_tests::cube_root_of_8_is_2 ... ok [INFO] [stdout] test algebraic_tests::add ... ok [INFO] [stdout] test algebraic_tests::mul ... ok [INFO] [stdout] test algebraic_tests::is_value_true_for_rational_numerals ... ok [INFO] [stdout] test algebraic_tests::lt_and_gt ... ok [INFO] [stdout] test algebraic_tests::is_value_false_for_symbolic_real ... ok [INFO] [stdout] test algebraic_tests::roundtrip_rational_through_real ... ok [INFO] [stdout] test algebraic_tests::eq_algebraic_reflexive_and_discriminating ... ok [INFO] [stdout] test algebraic_tests::sign_of_zero ... ok [INFO] [stdout] test algebraic_tests::from_algebraic_widens_to_concrete_real ... ok [INFO] [stdout] test algebraic_tests::ast_eq_returns_satisfiable_bool_for_equal_values ... ok [INFO] [stdout] test algebraic_tests::sign_of_positive ... ok [INFO] [stdout] test algebraic_tests::sort_after_arithmetic_is_real ... ok [INFO] [stdout] test algebraic_tests::ast_ne_returns_satisfiable_bool_for_distinct_values ... ok [INFO] [stdout] test algebraic_tests::sign_of_negative ... ok [INFO] [stdout] test algebraic_tests::root_and_power_are_inverses ... ok [INFO] [stdout] test algebraic_tests::rational_algebraic_has_numeral_kind ... ok [INFO] [stdout] test algebraic_tests::sort_of_computed_algebraic_is_real ... ok [INFO] [stdout] test algebraic_tests::sqrt2_lies_between_one_and_two ... ok [INFO] [stdout] test algebraic_tests::widened_algebraic_makes_constraint_unsat ... ignored, Fails when run with z3 4.8; likely an outdated upstream bug [INFO] [stdout] test algebraic_tests::sub ... ok [INFO] [stdout] test algebraic_tests::solver_returns_algebraic_value_convertible_via_try_from ... ok [INFO] [stdout] test ast_vector_tests::collect_from_chained_iterators ... ok [INFO] [stdout] test algebraic_tests::try_from_rational_real_succeeds ... ok [INFO] [stdout] test algebraic_tests::sort_of_rational_algebraic_is_real ... ok [INFO] [stdout] test ast_vector_tests::collect_from_range_map_creates_asts_inline ... ok [INFO] [stdout] test algebraic_tests::sqrt2_is_irrational ... ok [INFO] [stdout] test algebraic_tests::try_from_symbolic_real_fails ... ok [INFO] [stdout] test ast_vector_tests::collect_filter_map_selects_typed_elements ... ok [INFO] [stdout] test ast_vector_tests::from_empty_vec ... ok [INFO] [stdout] test ast_vector_tests::collect_roundtrip_through_iter ... ok [INFO] [stdout] test ast_vector_tests::from_slice_idiomatic_replaces_push_loop ... ok [INFO] [stdout] test ast_vector_tests::from_vec_of_ast ... ok [INFO] [stdout] test ast_vector_tests::from_slice_of_ast ... ok [INFO] [stdout] test ast_vector_tests::into_iter_exact_size ... ok [INFO] [stdout] test ast_vector_tests::iter_exact_size ... ok [INFO] [stdout] test ast_vector_tests::into_iter_consuming_yields_all_elements ... ok [INFO] [stdout] test ast_vector_tests::collect_mapped_ast_transformations ... ok [INFO] [stdout] test ast_vector_tests::from_iterator_of_ast ... ok [INFO] [stdout] test ast_vector_tests::iter_yields_all_elements ... ok [INFO] [stdout] test ast_vector_tests::solver_get_assertions_returns_correct_count ... ok [INFO] [stdout] test ast_vector_tests::collect_after_map ... ok [INFO] [stdout] test ast_vector_tests::try_from_ast_vector_for_vec_int ... ok [INFO] [stdout] test ast_vector_tests::ref_into_iter_borrows ... ok [INFO] [stdout] test ast_vector_tests::try_from_ast_vector_for_vec_bool ... ok [INFO] [stdout] test ast_vector_tests::try_from_ast_vector_type_mismatch_error ... ok [INFO] [stdout] test ast_vector_tests::try_into_typed_vec_empty ... ok [INFO] [stdout] test ast_vector_tests::collect_map_over_simplify ... ok [INFO] [stdout] test check_application_of_tactic_to_goal ... ok [INFO] [stdout] test ast_vector_tests::try_into_typed_vec_bool_success ... ok [INFO] [stdout] test get_version ... ok [INFO] [stdout] test ops::into_ast_int ... ok [INFO] [stdout] test ops::test_ast_attributes ... ok [INFO] [stdout] test algebraic_tests::widened_algebraic_used_as_symbolic_bound ... ok [INFO] [stdout] test iterate_all_solutions ... ok [INFO] [stdout] test ast_vector_tests::try_into_typed_vec_wrong_type_fails ... ok [INFO] [stdout] test function_ref_count ... ok [INFO] [stdout] test objectives::test_optimize_assert_soft_and_get_objectives ... ok [INFO] [stdout] test ops::test_bool_ops ... ok [INFO] [stdout] test ops::test_bv_ops ... ok [INFO] [stdout] test ops::test_ast_children ... ok [INFO] [stdout] test ops::test_float_ops ... ok [INFO] [stdout] test get_model_without_check_does_not_exit ... ok [INFO] [stdout] test ops::test_int_ops ... ok [INFO] [stdout] test ast_vector_tests::solver_get_consequences_roundtrip ... ok [INFO] [stdout] test ops::test_eq ... ok [INFO] [stdout] test ops::test_float32_ops ... ok [INFO] [stdout] test ops::test_func_decl_attributes ... ok [INFO] [stdout] test ops::test_double_ops ... ok [INFO] [stdout] test ops::test_real_sum ... ok [INFO] [stdout] test ops::test_pow_ret_real ... ok [INFO] [stdout] test ops::test_real_product ... ok [INFO] [stdout] test ops::test_int_product ... ok [INFO] [stdout] test ops::test_real_ops ... ok [INFO] [stdout] test semver_tests::test_solve_simple_semver_example ... ok [INFO] [stdout] test ops::test_int_sum ... ok [INFO] [stdout] test ops::test_real_approx ... ok [INFO] [stdout] test test_arbitrary_size_int ... ok [INFO] [stdout] test return_number_args_in_given_entry ... ok [INFO] [stdout] test quantifier_elimination_tests::lite_eliminates_existential_over_reals ... ok [INFO] [stdout] test test_ast_safe_eq ... ok [INFO] [stdout] test test_arbitrary_size_real_from_bigrational ... ok [INFO] [stdout] test test_ast_safe_decl ... ok [INFO] [stdout] test test_atleast ... ok [INFO] [stdout] test test_arbitrary_size_real ... ok [INFO] [stdout] test test_atmost ... ok [INFO] [stdout] test test_array_example1 ... ok [INFO] [stdout] test test_arbitrary_size_int_from_bigint ... ok [INFO] [stdout] test test_array_store_select ... ok [INFO] [stdout] test test_config ... ok [INFO] [stdout] test test_consequences ... ok [INFO] [stdout] test test_bitvector_from_str ... ok [INFO] [stdout] test test_dynamic_as_set ... ok [INFO] [stdout] test test_context ... ok [INFO] [stdout] test test_ast_translate ... ok [INFO] [stdout] test test_bitvectors ... ok [INFO] [stdout] test test_compare_trait_resolution ... ok [INFO] [stdout] test test_datatype_builder ... ok [INFO] [stdout] test test_cloning_ast ... ok [INFO] [stdout] test test_float ... ok [INFO] [stdout] test test_fixedpoint_horn_clauses ... ok [INFO] [stdout] test test_floating_point_bits ... ok [INFO] [stdout] test test_format ... ok [INFO] [stderr] WARNING: unknown parameter 'idontexist' [INFO] [stderr] Legal parameters are: [INFO] [stderr] auto_config (bool) (default: true) [INFO] [stderr] debug_ref_count (bool) (default: false) [INFO] [stderr] dot_proof_file (string) (default: proof.dot) [INFO] [stderr] dump_models (bool) (default: false) [INFO] [stderr] memory_high_watermark (unsigned int) (default: 0) [INFO] [stderr] memory_max_alloc_count (unsigned int) (default: 0) [INFO] [stderr] memory_max_size (unsigned int) (default: 0) [INFO] [stderr] model (bool) (default: true) [INFO] [stderr] model_validate (bool) (default: false) [INFO] [stderr] proof (bool) (default: false) [INFO] [stderr] rlimit (unsigned int) (default: 0) [INFO] [stderr] smtlib2_compliant (bool) (default: false) [INFO] [stderr] stats (bool) (default: false) [INFO] [stderr] timeout (unsigned int) (default: 4294967295) [INFO] [stderr] trace (bool) (default: false) [INFO] [stderr] trace_file_name (string) (default: z3.log) [INFO] [stderr] type_check (bool) (default: true) [INFO] [stderr] unicode (bool) [INFO] [stderr] unsat_core (bool) (default: false) [INFO] [stderr] verbose (unsigned int) (default: 0) [INFO] [stderr] warning (bool) (default: true) [INFO] [stderr] well_sorted_check (bool) (default: false) [INFO] [stderr] [INFO] [stdout] test test_goal_get_precision ... ok [INFO] [stdout] test test_global_params ... ok [INFO] [stdout] test test_goal_depth ... ok [INFO] [stdout] test test_goal_is_inconsistent ... ok [INFO] [stdout] test test_goal_is_sat ... ok [INFO] [stdout] test test_goal_num_expr ... ok [INFO] [stdout] test test_goal_size ... ok [INFO] [stderr] (sat.stats :conflicts :restarts :learned/bin :gc :time) [INFO] [stderr] (sat.stats :decisions :clauses/bin :units :memory ) [INFO] [stderr] (sat.stats 0 0 0 0/0 0/0 2 0 106.11 0.00) [INFO] [stderr] (sat.stats 0 0 0 0/0 0/0 2 0 106.11 0.00) [INFO] [stderr] (sat.stats 0 0 0 0/0 0/0 2 0 106.11 0.00) [INFO] [stdout] test test_goal_reset ... ok [INFO] [stderr] (sat.stats :conflicts :restarts :learned/bin :gc :time) [INFO] [stdout] test test_goal_get_formulas ... ok [INFO] [stderr] (sat.stats :decisions :clauses/bin :units :memory ) [INFO] [stderr] (sat.stats 0 0 0 0/0 0/0 2 0 149.55 0.00) [INFO] [stderr] (sat.stats 0 0 0 0/0 0/0 2 0 149.52 0.00) [INFO] [stderr] (sat.stats 0 0 0 0/0 0/0 2 0 149.62 0.00) [INFO] [stderr] (sat.stats :conflicts :restarts :learned/bin :gc :time) [INFO] [stderr] (sat.stats :decisions :clauses/bin :units :memory ) [INFO] [stderr] (sat.stats 0 0 0 0/0 0/0 2 0 149.65 0.00) [INFO] [stderr] (sat.stats 0 0 0 0/0 0/0 2 0 149.55 0.00) [INFO] [stderr] (sat.stats 0 0 0 0/0 0/0 2 0 149.55 0.00) [INFO] [stderr] (sat.stats :conflicts :restarts :learned/bin :gc :time) [INFO] [stdout] test quantifier_elimination_tests::model_project_eliminates_variable ... ok [INFO] [stderr] (sat.stats :decisions :clauses/bin :units :memory ) [INFO] [stderr] (sat.stats 0 0 0 0/0 0/0 2 0 132.86 0.00) [INFO] [stderr] (sat.stats 0 0 0 0/0 0/0 2 0 150.92 0.01) [INFO] [stderr] (sat.stats 0 0 0 0/0 0/0 2 0 150.92 0.01) [INFO] [stderr] (optimize:check-sat) [INFO] [stderr] (sat.stats :conflicts :restarts :learned/bin :gc :time) [INFO] [stderr] (sat.stats :decisions :clauses/bin :units :memory ) [INFO] [stderr] (sat.stats 0 0 0 0/0 0/0 2 0 150.92 0.00) [INFO] [stderr] (sat.stats 0 0 0 0/0 0/0 2 0 150.92 0.00) [INFO] [stderr] (sat.stats 0 0 0 0/0 0/0 2 0 150.92 0.00) [INFO] [stderr] (optimize:check-sat) [INFO] [stderr] (sat.stats :conflicts :restarts :learned/bin :gc :time) [INFO] [stderr] (sat.stats :decisions :clauses/bin :units :memory ) [INFO] [stderr] (sat.stats 0 0 0 0/0 0/0 0 0 150.92 0.00) [INFO] [stderr] (sat.stats 0 1 0 0/0 0/0 0 0 150.92 0.00) [INFO] [stderr] (optimize:check-sat) [INFO] [stdout] test test_optimize_get_unsat_core ... ok [INFO] [stderr] (optimize:sat) [INFO] [stderr] (sat.stats :conflicts :restarts :learned/bin :gc :time) [INFO] [stderr] (sat.stats :decisions :clauses/bin :units :memory ) [INFO] [stderr] (sat.stats 0 0 0 0/0 0/0 2 0 132.34 0.00) [INFO] [stderr] (sat.stats 0 0 0 0/0 0/0 2 0 132.34 0.00) [INFO] [stderr] (sat.stats 0 0 0 0/0 0/0 2 0 132.34 0.00) [INFO] [stdout] test test_get_unsat_core ... ok [INFO] [stdout] test test_optimize_new_from_smtlib2 ... ok [INFO] [stdout] test test_goal_apply_tactic ... ok [INFO] [stdout] test test_model_iter ... ok [INFO] [stderr] (smt.stats :restarts :decisions :clauses/bin :simplify :memory) [INFO] [stdout] test test_probe_eq ... ok [INFO] [stderr] (smt.stats :conflicts :propagations :lemmas :deletions ) [INFO] [stderr] (smt.stats 0 0 0 0 0/0 0 0 0 138.34) [INFO] [stdout] test test_probe_debug ... ok [INFO] [stdout] test test_probe_gt ... ok [INFO] [stdout] test test_mutually_recursive_datatype ... ok [INFO] [stderr] (optimize:check-sat) [INFO] [stdout] test test_probe_gte ... ok [INFO] [stdout] test test_probe_le ... ok [INFO] [stdout] test test_probe_lt ... ok [INFO] [stdout] test test_probe_ne ... ok [INFO] [stdout] test test_optimize_unknown ... ok [INFO] [stdout] test test_probe_names ... ok [INFO] [stderr] (sat.stats :conflicts :restarts :learned/bin :gc :time) [INFO] [stderr] (sat.stats :decisions :clauses/bin :units :memory ) [INFO] [stderr] (sat.stats 0 0 0 11626/6327 0/0 14 0 190.52 0.00) [INFO] [stderr] (sat.stats 27 92 0 10655/5759 22/4 161 0 198.64 0.01) [INFO] [stderr] (smt.stats :restarts :decisions :clauses/bin :simplify :memory) [INFO] [stderr] (smt.stats :conflicts :propagations :lemmas :deletions ) [INFO] [stderr] (smt.stats 0 0 0 0 0/0 0 0 0 138.51) [INFO] [stderr] (smt.stats :restarts :decisions :clauses/bin :simplify :memory) [INFO] [stdout] test test_model_translate ... ok [INFO] [stderr] (smt.stats :conflicts :propagations :lemmas :deletions ) [INFO] [stderr] (smt.stats 0 0 1 2 0/0 0 1 3 136.93) [INFO] [stderr] (smt.stats 0 0 1 2 0/0 0 1 3 137.02) [INFO] [stderr] (smt.stats 0 0 1 2 0/0 0 1 3 137.02) [INFO] [stdout] test test_params ... ok [INFO] [stdout] test test_float_add ... ok [INFO] [stdout] test test_recursive_datatype ... ok [INFO] [stdout] test test_rec_func_def ... ok [INFO] [stdout] test test_pb_ops_model ... ok [INFO] [stdout] test test_real_cmp ... ok [INFO] [stdout] test test_rec_func_def_unsat ... ok [INFO] [stdout] test test_round_towards_nearest_even ... ok [INFO] [stdout] test test_regex_union ... ok [INFO] [stdout] test test_regex_capital_foobar_intersect_az_plus_is_unsat ... ok [INFO] [stdout] test test_regex_union2 ... ok [INFO] [stdout] test test_solver_get_assertions_lifetime ... ok [INFO] [stdout] test test_round_towards_nearest_away ... ok [INFO] [stderr] (smt.stats :restarts :decisions :clauses/bin :simplify :memory) [INFO] [stderr] (smt.stats :conflicts :propagations :lemmas :deletions ) [INFO] [stderr] (smt.stats 0 0 0 0 0/0 0 0 0 139.84) [INFO] [stderr] (smt.stats :restarts :decisions :clauses/bin :simplify :memory) [INFO] [stderr] (smt.stats :conflicts :propagations :lemmas :deletions ) [INFO] [stderr] (smt.stats 0 1 0 2 2/0 0 1 0 156.58) [INFO] [stdout] test test_solver_new_from_smtlib2 ... ok [INFO] [stderr] (smt.stats 0 1 0 2 2/0 0 2 0 149.00) [INFO] [stderr] (smt.stats 0 1 0 4 0/0 0 4 4 158.36) [INFO] [stdout] test test_set_membership ... ok [INFO] [stdout] test test_sorts_and_symbols ... ok [INFO] [stdout] test test_solver_to_smtlib2 ... ok [INFO] [stdout] test test_string_as_string ... ok [INFO] [stdout] test test_solving ... ok [INFO] [stderr] (smt.stats :restarts :decisions :clauses/bin :simplify :memory) [INFO] [stderr] (smt.stats :conflicts :propagations :lemmas :deletions ) [INFO] [stderr] (smt.stats 0 0 0 0 0/0 0 0 0 205.61) [INFO] [stdout] test test_string_concat ... ok [INFO] [stderr] (smt.stats :restarts :decisions :clauses/bin :simplify :memory) [INFO] [stderr] (smt.stats :conflicts :propagations :lemmas :deletions ) [INFO] [stderr] (smt.stats 0 0 0 0 0/0 0 0 0 189.69) [INFO] [stdout] test test_solving_for_model ... ok [INFO] [stdout] test test_solving_for_model_cloned ... ok [INFO] [stdout] test test_string_eq ... ok [INFO] [stdout] test test_string_suffix ... ok [INFO] [stdout] test test_tactic_conditions ... ok [INFO] [stdout] test test_tactic_fail ... ok [INFO] [stderr] (sat.stats :conflicts :restarts :learned/bin :gc :time) [INFO] [stderr] (sat.stats :decisions :clauses/bin :units :memory ) [INFO] [stderr] (sat.stats 0 0 0 0/0 0/0 2 0 196.50 0.00) [INFO] [stderr] (sat.stats :conflicts :restarts :learned/bin :gc :time) [INFO] [stderr] (sat.stats :decisions :clauses/bin :units :memory ) [INFO] [stderr] (sat.stats 0 0 0 0/0 0/0 2 0 153.57 0.00) [INFO] [stderr] (sat.stats 0 0 0 0/0 0/0 2 0 153.57 0.00) [INFO] [stderr] (sat.stats 0 0 0 0/0 0/0 2 0 153.57 0.00) [INFO] [stderr] (sat.stats 0 0 0 0/0 0/0 2 0 177.95 0.01) [INFO] [stderr] (sat.stats 0 0 0 0/0 0/0 2 0 177.95 0.01) [INFO] [stdout] test test_string_prefix ... ok [INFO] [stdout] test test_tactic_or_else ... ok [INFO] [stdout] test test_tactic_and_then ... ok [INFO] [stdout] test test_tactic_skip ... ok [INFO] [stdout] test test_substitution ... ok [INFO] [stdout] test test_tactic_try_for ... ok [INFO] [stdout] test test_tactic_cond ... ok [INFO] [stdout] test test_tactic_with ... ok [INFO] [stdout] test test_solver_translate ... ok [INFO] [stdout] test test_solver_unknown has been running for over 60 seconds [ERROR] error running command: no output for 300 seconds [INFO] running `Command { std: "docker" "inspect" "c03fd518fbbbeb590086f1e93f784e0f56ba0938811410ca0a3e836df9600703", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "c03fd518fbbbeb590086f1e93f784e0f56ba0938811410ca0a3e836df9600703", kill_on_drop: false }` [INFO] [stdout] c03fd518fbbbeb590086f1e93f784e0f56ba0938811410ca0a3e836df9600703