[INFO] fetching crate patch-prolog 0.2.1...
[INFO] testing patch-prolog-0.2.1 against master#562dee4820c458d823175268e41601d4c060588a for pr-154210-2
[INFO] extracting crate patch-prolog 0.2.1 into /workspace/builds/worker-1-tc1/source
[INFO] started tweaking crates.io crate patch-prolog 0.2.1
[INFO] removed 0 missing tests
[INFO] finished tweaking crates.io crate patch-prolog 0.2.1
[INFO] tweaked toml for crates.io crate patch-prolog 0.2.1 written to /workspace/builds/worker-1-tc1/source/Cargo.toml
[INFO] validating manifest of crates.io crate patch-prolog 0.2.1 on toolchain 562dee4820c458d823175268e41601d4c060588a
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+562dee4820c458d823175268e41601d4c060588a" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate crates.io crate patch-prolog 0.2.1 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" "+562dee4820c458d823175268e41601d4c060588a" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr]     Updating crates.io index
[INFO] [stderr]  Downloading crates ...
[INFO] [stderr]   Downloaded patch-prolog-core v0.2.1
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-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" "+562dee4820c458d823175268e41601d4c060588a" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] 37c520aef7a63173bf5e4e7610ceac5b184b5f82966d96d0526d5d9b780797a0
[INFO] running `Command { std: "docker" "start" "-a" "37c520aef7a63173bf5e4e7610ceac5b184b5f82966d96d0526d5d9b780797a0", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "37c520aef7a63173bf5e4e7610ceac5b184b5f82966d96d0526d5d9b780797a0", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "37c520aef7a63173bf5e4e7610ceac5b184b5f82966d96d0526d5d9b780797a0", kill_on_drop: false }`
[INFO] [stdout] 37c520aef7a63173bf5e4e7610ceac5b184b5f82966d96d0526d5d9b780797a0
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-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" "+562dee4820c458d823175268e41601d4c060588a" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] c89610f90db193a6d14f600e8c38610cf445a38674b2eea2071114b08d391ad0
[INFO] running `Command { std: "docker" "start" "-a" "c89610f90db193a6d14f600e8c38610cf445a38674b2eea2071114b08d391ad0", kill_on_drop: false }`
[INFO] [stderr]    Compiling bincode v1.3.3
[INFO] [stderr]    Compiling patch-prolog-core v0.2.1
[INFO] [stderr]    Compiling patch-prolog v0.2.1 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 3.27s
[INFO] running `Command { std: "docker" "inspect" "c89610f90db193a6d14f600e8c38610cf445a38674b2eea2071114b08d391ad0", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "c89610f90db193a6d14f600e8c38610cf445a38674b2eea2071114b08d391ad0", kill_on_drop: false }`
[INFO] [stdout] c89610f90db193a6d14f600e8c38610cf445a38674b2eea2071114b08d391ad0
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-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" "+562dee4820c458d823175268e41601d4c060588a" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 6a6cc225f17ac8a0dc5979b296eb527a441931e6c53cdbbf446fadc9bf9e6c71
[INFO] running `Command { std: "docker" "start" "-a" "6a6cc225f17ac8a0dc5979b296eb527a441931e6c53cdbbf446fadc9bf9e6c71", kill_on_drop: false }`
[INFO] [stderr]    Compiling patch-prolog v0.2.1 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 1.22s
[INFO] running `Command { std: "docker" "inspect" "6a6cc225f17ac8a0dc5979b296eb527a441931e6c53cdbbf446fadc9bf9e6c71", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "6a6cc225f17ac8a0dc5979b296eb527a441931e6c53cdbbf446fadc9bf9e6c71", kill_on_drop: false }`
[INFO] [stdout] 6a6cc225f17ac8a0dc5979b296eb527a441931e6c53cdbbf446fadc9bf9e6c71
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-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" "+562dee4820c458d823175268e41601d4c060588a" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] 9fd03c206258268382fbe9778ac3edb632ae75a702dc6c2291771a3c3f2ba419
[INFO] running `Command { std: "docker" "start" "-a" "9fd03c206258268382fbe9778ac3edb632ae75a702dc6c2291771a3c3f2ba419", kill_on_drop: false }`
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.08s
[INFO] [stderr]      Running unittests src/main.rs (/opt/rustwide/target/debug/deps/patch_prolog-c808be3e406a50b3)
[INFO] [stdout] 
[INFO] [stderr]      Running tests/integration.rs (/opt/rustwide/target/debug/deps/integration-2c9a23b137f0f72b)
[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] [stdout] 
[INFO] [stdout] running 156 tests
[INFO] [stdout] test test_arg_out_of_range ... ok
[INFO] [stdout] test test_arithmetic_abs ... ok
[INFO] [stdout] test test_arg_compound ... ok
[INFO] [stdout] test test_arithmetic_pipeline ... ok
[INFO] [stdout] test test_arithmetic_sign ... ok
[INFO] [stdout] test test_atom_concat_in_rule ... ok
[INFO] [stdout] test test_atom_chars_reverse ... ok
[INFO] [stdout] test test_atom_chars_rejects_non_atom_element ... ok
[INFO] [stdout] test test_atom_chars_rejects_multi_char_atom ... ok
[INFO] [stdout] test test_atom_chars_pipeline ... ok
[INFO] [stdout] test test_arithmetic_max_min ... ok
[INFO] [stdout] test test_atom_chars_roundtrip ... ok
[INFO] [stdout] test test_atom_length_in_rule ... ok
[INFO] [stdout] test test_between_basic ... ok
[INFO] [stdout] test test_arithmetic_combined ... ok
[INFO] [stdout] test test_between_bound_x_below_range ... ok
[INFO] [stdout] test test_between_bound_x_fast_path ... ok
[INFO] [stdout] test test_between_bound_x_large_range_findall ... ok
[INFO] [stdout] test test_between_conjunction_in_negation ... ok
[INFO] [stdout] test test_between_unbound_x_naf ... ok
[INFO] [stdout] test test_between_in_rule ... ok
[INFO] [stdout] test test_between_empty ... ok
[INFO] [stdout] test test_call_meta_predicate ... ok
[INFO] [stdout] test test_compare_numbers ... ok
[INFO] [stdout] test test_between_single ... ok
[INFO] [stdout] test test_between_bound_x_out_of_range ... ok
[INFO] [stdout] test test_copy_term_aliasing ... ok
[INFO] [stdout] test test_between_low_greater_than_high ... ok
[INFO] [stdout] test test_complex_recursive_query ... ok
[INFO] [stdout] test test_compare_atoms ... ok
[INFO] [stdout] test test_copy_term_basic ... ok
[INFO] [stdout] test test_compare_compound_with_bound_vars ... ok
[INFO] [stdout] test test_between_step_limit_in_negation ... ok
[INFO] [stdout] test test_between_step_limit_in_findall ... ok
[INFO] [stdout] test test_between_bound_x_large_range_naf ... ok
[INFO] [stdout] test test_copy_term_nested_list ... ok
[INFO] [stdout] test test_copy_term_preserves_long_list ... ok
[INFO] [stdout] test test_cut_in_negation ... ok
[INFO] [stdout] test test_copy_term_ground ... ok
[INFO] [stdout] test test_copy_term_list ... ok
[INFO] [stdout] test test_cut_prevents_all_alternatives ... ok
[INFO] [stdout] test test_cut_in_findall_stops_clause_iteration ... ok
[INFO] [stdout] test test_disjunction_in_rule ... ok
[INFO] [stdout] test test_division_by_zero ... ok
[INFO] [stdout] test test_cut_in_try_solve_no_leak_after_once ... ok
[INFO] [stdout] test test_empty_knowledge_base ... ok
[INFO] [stdout] test test_depth_limit_custom ... ok
[INFO] [stdout] test test_cut_in_once ... ok
[INFO] [stdout] test test_cut_prevents_backtracking ... ok
[INFO] [stdout] test test_family_relationships ... ok
[INFO] [stdout] test test_findall_empty_result ... ok
[INFO] [stdout] test test_findall_step_limit_returns_error ... ok
[INFO] [stdout] test test_findall_without_cut_collects_all ... ok
[INFO] [stdout] test test_findall_with_filter ... ok
[INFO] [stdout] test test_float_div_by_int_zero ... ok
[INFO] [stdout] test test_findall_once_collects_one ... ok
[INFO] [stdout] test test_float_formatting_in_number_chars ... ok
[INFO] [stdout] test test_findall_steps_accumulate_globally ... ok
[INFO] [stdout] test test_findall_functor_list ... ok
[INFO] [stdout] test test_findall_once_member ... ok
[INFO] [stdout] test test_functor_atom ... ok
[INFO] [stdout] test test_functor_construct ... ok
[INFO] [stdout] test test_float_overflow_literal_rejected ... ok
[INFO] [stdout] test test_functor_construct_in_once ... ok
[INFO] [stdout] test test_functor_arity_too_large ... ok
[INFO] [stdout] test test_ground_query_false ... ok
[INFO] [stdout] test test_functor_decompose ... ok
[INFO] [stdout] test test_functor_in_rule ... ok
[INFO] [stdout] test test_if_then_else_in_rule ... ok
[INFO] [stdout] test test_functor_negative_arity ... ok
[INFO] [stdout] test test_ground_query_true ... ok
[INFO] [stdout] test test_functor_number ... ok
[INFO] [stdout] test test_functor_list_in_once ... ok
[INFO] [stdout] test test_if_then_else_condition_bindings_propagate ... ok
[INFO] [stdout] test test_if_then_else_keeps_bindings ... ok
[INFO] [stdout] test test_index_ground_miss_returns_empty ... ok
[INFO] [stdout] test test_iso_integer_float_ordering ... ok
[INFO] [stdout] test test_integer_division_negative ... ok
[INFO] [stdout] test test_integer_overflow_detected ... ok
[INFO] [stdout] test test_integer_division_operator ... ok
[INFO] [stdout] test test_mod_vs_rem_difference ... ok
[INFO] [stdout] test test_is_list_with_bound_tail ... ok
[INFO] [stdout] test test_iso_float_integer_ordering ... ok
[INFO] [stdout] test test_msort_basic ... ok
[INFO] [stdout] test test_mod_floored_semantics ... ok
[INFO] [stdout] test test_is_list_with_constructed_list ... ok
[INFO] [stdout] test test_mod_large_negative_divisor ... ok
[INFO] [stdout] test test_msort_numbers ... ok
[INFO] [stdout] test test_msort_preserves_duplicates ... ok
[INFO] [stdout] test test_naf_parses_with_operator ... ok
[INFO] [stdout] test test_between_overflow_at_max ... ok
[INFO] [stdout] test test_negation_number_chars_reverse ... ok
[INFO] [stdout] test test_negation_between_bound ... ok
[INFO] [stdout] test test_negation_as_failure_pipeline ... ok
[INFO] [stdout] test test_no_occurs_check_unify ... ok
[INFO] [stdout] test test_list_operations ... ok
[INFO] [stdout] test test_number_chars_integer ... ok
[INFO] [stdout] test test_multiple_queries_sequential ... ok
[INFO] [stdout] test test_between_with_findall ... ok
[INFO] [stdout] test test_naf_step_limit_returns_error_not_success ... ok
[INFO] [stdout] test test_number_chars_rejects_nan ... ok
[INFO] [stdout] test test_number_chars_integer_elements_error ... ok
[INFO] [stdout] test test_number_codes_invalid_syntax_errors ... ok
[INFO] [stdout] test test_number_chars_invalid_syntax_errors ... ok
[INFO] [stdout] test test_number_chars_rejects_bad_elements ... ok
[INFO] [stdout] test test_number_codes_atom_elements_error ... ok
[INFO] [stdout] test test_number_chars_rejects_infinity ... ok
[INFO] [stdout] test test_naf_precedence_with_is ... ok
[INFO] [stdout] test test_number_chars_unify_failure_backtracks ... ok
[INFO] [stdout] test test_number_codes_integer ... ok
[INFO] [stdout] test test_number_chars_valid_still_works ... ok
[INFO] [stdout] test test_float_formatting_in_number_codes ... ok
[INFO] [stdout] test test_no_matching_predicate ... ok
[INFO] [stdout] test test_number_chars_reverse ... ok
[INFO] [stdout] test test_number_chars_reverse_in_once ... ok
[INFO] [stdout] test test_number_codes_rejects_bad_elements ... ok
[INFO] [stdout] test test_parse_error_detection ... ok
[INFO] [stdout] test test_plus_forward ... ok
[INFO] [stdout] test test_plus_backward ... ok
[INFO] [stdout] test test_number_codes_reverse_in_once ... ok
[INFO] [stdout] test test_plus_overflow ... ok
[INFO] [stdout] test test_rem_negative_dividend ... ok
[INFO] [stdout] test test_rem_operator ... ok
[INFO] [stdout] test test_semicolon_comma_precedence_multiple ... ok
[INFO] [stdout] test test_sort_empty ... ok
[INFO] [stdout] test test_semicolon_comma_precedence_in_body ... ok
[INFO] [stdout] test test_solution_limit_respected ... ok
[INFO] [stdout] test test_sort_in_rule ... ok
[INFO] [stdout] test test_sort_removes_duplicates ... ok
[INFO] [stdout] test test_type_checking_in_rules ... ok
[INFO] [stdout] test test_step_limit_in_try_solve_once ... ok
[INFO] [stdout] test test_succ_overflow ... ok
[INFO] [stdout] test test_unify_with_occurs_check_success ... ok
[INFO] [stdout] test test_term_ordering_numbers_before_atoms ... ok
[INFO] [stdout] test test_once_limits_to_first_solution ... ok
[INFO] [stdout] test test_succ_zero_fails ... ok
[INFO] [stdout] test test_term_ordering_operators ... ok
[INFO] [stdout] test test_once_between_conjunction ... ok
[INFO] [stdout] test test_unbound_variable_in_arithmetic ... ok
[INFO] [stdout] test test_succ_backward ... ok
[INFO] [stdout] test test_succ_forward ... ok
[INFO] [stdout] test test_once_in_rule ... ok
[INFO] [stdout] test test_univ_atom ... ok
[INFO] [stdout] test test_term_ordering_compound_with_bound_vars ... ok
[INFO] [stdout] test test_univ_decompose ... ok
[INFO] [stdout] test test_univ_in_rule ... ok
[INFO] [stdout] test test_univ_list_in_once ... ok
[INFO] [stdout] test test_univ_number ... ok
[INFO] [stdout] test test_univ_number_construct_still_works ... ok
[INFO] [stdout] test test_univ_reconstruct_in_rule ... ok
[INFO] [stdout] test test_univ_type_error_non_atom_functor ... ok
[INFO] [stdout] test test_univ_construct ... ok
[INFO] [stdout] test test_univ_unbound_functor_errors ... ok
[INFO] [stdout] test test_unify_with_occurs_check_circular_fails ... ok
[INFO] [stdout] test test_number_codes_reverse ... ok
[INFO] [stdout] test test_depth_limit_prevents_stack_overflow ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 156 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.10s
[INFO] [stdout] 
[INFO] running `Command { std: "docker" "inspect" "9fd03c206258268382fbe9778ac3edb632ae75a702dc6c2291771a3c3f2ba419", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "9fd03c206258268382fbe9778ac3edb632ae75a702dc6c2291771a3c3f2ba419", kill_on_drop: false }`
[INFO] [stdout] 9fd03c206258268382fbe9778ac3edb632ae75a702dc6c2291771a3c3f2ba419