[INFO] fetching crate rapina-macros 0.11.0...
[INFO] testing rapina-macros-0.11.0 against master#562dee4820c458d823175268e41601d4c060588a for pr-154210-2
[INFO] extracting crate rapina-macros 0.11.0 into /workspace/builds/worker-3-tc1/source
[INFO] started tweaking crates.io crate rapina-macros 0.11.0
[INFO] finished tweaking crates.io crate rapina-macros 0.11.0
[INFO] tweaked toml for crates.io crate rapina-macros 0.11.0 written to /workspace/builds/worker-3-tc1/source/Cargo.toml
[INFO] validating manifest of crates.io crate rapina-macros 0.11.0 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 rapina-macros 0.11.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" "+562dee4820c458d823175268e41601d4c060588a" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-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] 39d3c501d4c46800965bdf4e778f281ac07a1ea7d47a1693407bf604c475ba63
[INFO] running `Command { std: "docker" "start" "-a" "39d3c501d4c46800965bdf4e778f281ac07a1ea7d47a1693407bf604c475ba63", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "39d3c501d4c46800965bdf4e778f281ac07a1ea7d47a1693407bf604c475ba63", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "39d3c501d4c46800965bdf4e778f281ac07a1ea7d47a1693407bf604c475ba63", kill_on_drop: false }`
[INFO] [stdout] 39d3c501d4c46800965bdf4e778f281ac07a1ea7d47a1693407bf604c475ba63
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-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] 948738847b0e9b21863a450357220088541a311e77f969e1e3ecc5be70a9128b
[INFO] running `Command { std: "docker" "start" "-a" "948738847b0e9b21863a450357220088541a311e77f969e1e3ecc5be70a9128b", kill_on_drop: false }`
[INFO] [stderr]    Compiling proc-macro2 v1.0.106
[INFO] [stderr]    Compiling quote v1.0.45
[INFO] [stderr]    Compiling syn v2.0.117
[INFO] [stderr]    Compiling rapina-macros v0.11.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 6.61s
[INFO] running `Command { std: "docker" "inspect" "948738847b0e9b21863a450357220088541a311e77f969e1e3ecc5be70a9128b", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "948738847b0e9b21863a450357220088541a311e77f969e1e3ecc5be70a9128b", kill_on_drop: false }`
[INFO] [stdout] 948738847b0e9b21863a450357220088541a311e77f969e1e3ecc5be70a9128b
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-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] 2ff41026d5af32d54d875084908c7168f306b5bff42a9acef63d4f5a55e92815
[INFO] running `Command { std: "docker" "start" "-a" "2ff41026d5af32d54d875084908c7168f306b5bff42a9acef63d4f5a55e92815", kill_on_drop: false }`
[INFO] [stderr]    Compiling rapina-macros v0.11.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 3.93s
[INFO] running `Command { std: "docker" "inspect" "2ff41026d5af32d54d875084908c7168f306b5bff42a9acef63d4f5a55e92815", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "2ff41026d5af32d54d875084908c7168f306b5bff42a9acef63d4f5a55e92815", kill_on_drop: false }`
[INFO] [stdout] 2ff41026d5af32d54d875084908c7168f306b5bff42a9acef63d4f5a55e92815
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-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] 2da9f595aa03a2865df66cd43a643805500a4b6b4435d51cb89f7fb689a40696
[INFO] running `Command { std: "docker" "start" "-a" "2da9f595aa03a2865df66cd43a643805500a4b6b4435d51cb89f7fb689a40696", kill_on_drop: false }`
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.05s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/rapina_macros-192dbbacb133b380)
[INFO] [stdout] 
[INFO] [stdout] running 116 tests
[INFO] [stdout] test schema::analyze::tests::test_analyze_belongs_to_relationship ... ok
[INFO] [stdout] test schema::analyze::tests::test_analyze_composite_primary_key ... ok
[INFO] [stdout] test schema::analyze::tests::test_analyze_optional_belongs_to ... ok
[INFO] [stdout] test schema::analyze::tests::test_analyze_has_many_relationship ... ok
[INFO] [stdout] test schema::analyze::tests::test_analyze_primary_key_with_extra_fields ... ok
[INFO] [stdout] test schema::analyze::tests::test_analyze_simple_schema ... ok
[INFO] [stdout] test schema::analyze::tests::test_created_at_allowed_with_timestamps_none ... ok
[INFO] [stdout] test schema::analyze::tests::test_duplicate_entity_error ... ok
[INFO] [stdout] test schema::analyze::tests::test_unknown_type_error ... ok
[INFO] [stdout] test schema::analyze::tests::test_unknown_entity_in_vec_error ... ok
[INFO] [stdout] test schema::generate::tests::test_generate_belongs_to ... ok
[INFO] [stdout] test schema::analyze::tests::test_analyze_preserves_field_attrs ... ok
[INFO] [stdout] test schema::analyze::tests::test_analyze_primary_key_column_not_found ... ok
[INFO] [stdout] test schema::generate::tests::test_generate_belongs_to_chained_uuid_pk ... ok
[INFO] [stdout] test schema::analyze::tests::test_analyze_primary_key_must_be_scalar ... ok
[INFO] [stdout] test schema::generate::tests::test_generate_composite_pk_with_extra_fields ... ok
[INFO] [stdout] test schema::generate::tests::test_generate_custom_column_name ... ok
[INFO] [stdout] test schema::generate::tests::test_generate_custom_table_name ... ok
[INFO] [stdout] test schema::generate::tests::test_generate_default_pk_unchanged ... ok
[INFO] [stdout] test schema::generate::tests::test_generate_indexed_field ... ok
[INFO] [stdout] test schema::generate::tests::test_generate_float_field_omits_eq ... ok
[INFO] [stdout] test schema::generate::tests::test_generate_has_many ... ok
[INFO] [stdout] test schema::generate::tests::test_generate_no_timestamps ... ok
[INFO] [stdout] test schema::generate::tests::test_generate_only_created_at ... ok
[INFO] [stdout] test schema::generate::tests::test_generate_only_updated_at ... ok
[INFO] [stdout] test schema::generate::tests::test_generate_optional_belongs_to ... ok
[INFO] [stdout] test schema::generate::tests::test_generate_simple_entity ... ok
[INFO] [stdout] test schema::generate::tests::test_generate_text_column ... ok
[INFO] [stdout] test schema::generate::tests::test_generate_single_custom_pk ... ok
[INFO] [stdout] test schema::generate::tests::test_generate_composite_pk_preserves_field_order ... ok
[INFO] [stdout] test schema::generate::tests::test_schema_macro_uuid_pk_integration ... ok
[INFO] [stdout] test schema::generate::tests::test_to_pascal_case ... ok
[INFO] [stdout] test schema::generate::tests::test_generate_unique_field ... ok
[INFO] [stdout] test schema::parse::tests::test_default_no_primary_key ... ok
[INFO] [stdout] test schema::parse::tests::test_default_timestamps_enabled ... ok
[INFO] [stdout] test schema::generate::tests::test_generate_no_float_field_includes_eq ... ok
[INFO] [stdout] test schema::parse::tests::test_duplicate_field_error ... ok
[INFO] [stdout] test schema::parse::tests::test_id_allowed_with_custom_primary_key ... ok
[INFO] [stdout] test schema::parse::tests::test_id_rejected_without_custom_primary_key ... ok
[INFO] [stdout] test schema::parse::tests::test_parse_combined_field_attrs ... ok
[INFO] [stdout] test schema::parse::tests::test_parse_index_attr ... ok
[INFO] [stdout] test schema::parse::tests::test_parse_multiple_entities ... ok
[INFO] [stdout] test schema::parse::tests::test_parse_multiple_field_attrs ... ok
[INFO] [stdout] test schema::parse::tests::test_parse_option_field ... ok
[INFO] [stdout] test schema::parse::tests::test_parse_primary_key_composite ... ok
[INFO] [stdout] test schema::generate::tests::test_generate_belongs_to_uuid_pk ... ok
[INFO] [stdout] test schema::parse::tests::test_parse_primary_key_single ... ok
[INFO] [stdout] test schema::parse::tests::test_parse_primary_key_with_table_name ... ok
[INFO] [stdout] test schema::parse::tests::test_parse_primary_key_with_timestamps_none ... ok
[INFO] [stdout] test schema::parse::tests::test_parse_simple_entity ... ok
[INFO] [stdout] test schema::parse::tests::test_parse_primary_key_three_columns ... ok
[INFO] [stdout] test schema::analyze::tests::test_created_at_rejected_with_default_timestamps ... ok
[INFO] [stdout] test schema::parse::tests::test_parse_timestamps_none ... ok
[INFO] [stdout] test schema::parse::tests::test_parse_vec_field ... ok
[INFO] [stdout] test schema::generate::tests::test_generate_composite_primary_key ... ok
[INFO] [stdout] test schema::parse::tests::test_parse_timestamps_created_at_only ... ok
[INFO] [stdout] test schema::parse::tests::test_reserved_field_error ... ok
[INFO] [stdout] test schema::analyze::tests::test_analyze_preserves_entity_attrs ... ok
[INFO] [stdout] test schema::parse::tests::test_unknown_entity_attr_error ... ok
[INFO] [stdout] test schema::parse::tests::test_unknown_field_attr_error ... ok
[INFO] [stdout] test schema::parse::tests::test_parse_table_name_attr ... ok
[INFO] [stdout] test tests::job_macro_all_retry_attrs_combined ... ok
[INFO] [stdout] test tests::job_macro_defaults_retry_policy_and_delay ... ok
[INFO] [stdout] test tests::job_macro_fixed_retry_policy ... ok
[INFO] [stdout] test tests::job_macro_invalid_retry_policy_is_compile_error ... ok
[INFO] [stdout] test tests::job_macro_unknown_attr_error_mentions_retry_attrs ... ok
[INFO] [stdout] test tests::job_macro_retry_delay_integer_literal ... ok
[INFO] [stdout] test tests::test_cache_attr_injects_ttl_header ... ok
[INFO] [stdout] test tests::test_custom_type_name_not_misclassified ... ok
[INFO] [stdout] test tests::job_macro_none_retry_policy ... ok
[INFO] [stdout] test tests::job_macro_retry_delay_float_literal ... ok
[INFO] [stdout] test tests::test_emits_route_descriptor ... ok
[INFO] [stdout] test tests::test_delete_with_json_body_no_request_schema ... ok
[INFO] [stdout] test schema::parse::tests::test_parse_column_attr ... ok
[INFO] [stdout] test schema::parse::tests::test_parse_timestamps_updated_at_only ... ok
[INFO] [stdout] test schema::parse::tests::test_parse_unique_attr ... ok
[INFO] [stdout] test tests::test_cache_attr_with_extractors ... ok
[INFO] [stdout] test tests::test_emits_route_descriptor_with_method ... ok
[INFO] [stdout] test tests::test_group_param_joins_path ... ok
[INFO] [stdout] test tests::test_function_with_multiple_extractors ... ok
[INFO] [stdout] test tests::test_generates_handler_with_extractors ... ok
[INFO] [stdout] test tests::test_group_param_with_nested_prefix ... ok
[INFO] [stdout] test tests::test_errors_attr_generates_error_responses ... ok
[INFO] [stdout] test tests::test_group_with_cache_attr ... ok
[INFO] [stdout] test tests::test_get_with_json_body_no_request_schema ... ok
[INFO] [stdout] test tests::test_group_with_all_methods ... ok
[INFO] [stdout] test tests::test_group_with_errors_attr ... ok
[INFO] [stdout] test tests::test_group_with_public_attr ... ok
[INFO] [stdout] test tests::test_group_with_trailing_slash_normalized ... ok
[INFO] [stdout] test tests::test_form_body_generates_request_schema_and_content_type ... ok
[INFO] [stdout] test tests::test_generates_struct_with_handler_impl ... ok
[INFO] [stdout] test tests::test_join_paths_empty_prefix ... ok
[INFO] [stdout] test tests::test_join_paths_trailing_slash ... ok
[INFO] [stdout] test tests::test_join_paths_empty_path ... ok
[INFO] [stdout] test tests::test_no_body_no_request_schema_or_content_type ... ok
[INFO] [stdout] test tests::test_json_return_type_generates_response_schema ... ok
[INFO] [stdout] test tests::test_no_closure_wrapper_for_type_inference ... ok
[INFO] [stdout] test tests::test_non_json_return_type_no_response_schema ... ok
[INFO] [stdout] test tests::test_option_form_generates_optional_request_body ... ok
[INFO] [stdout] test tests::test_option_json_generates_optional_request_body ... ok
[INFO] [stdout] test tests::test_public_attr_below_route_sets_is_public ... ok
[INFO] [stdout] test tests::test_relay_macro_exact_match ... ok
[INFO] [stdout] test tests::test_relay_macro_extracts_additional_params ... ok
[INFO] [stdout] test tests::test_relay_macro_generates_wrapper_and_inventory ... ok
[INFO] [stdout] test tests::test_json_body_generates_request_schema_and_content_type ... ok
[INFO] [stdout] test tests::test_multiple_parts_only_extractors_positional ... ok
[INFO] [stdout] test tests::test_join_paths_basic ... ok
[INFO] [stdout] test tests::test_user_state_variable_not_shadowed ... ok
[INFO] [stdout] test tests::test_without_group_param_backward_compatible ... ok
[INFO] [stdout] test tests::test_validated_json_generates_request_schema_and_content_type ... ok
[INFO] [stdout] test tests::test_validated_form_generates_request_schema_and_content_type ... ok
[INFO] [stdout] test tests::test_result_json_return_type_generates_response_schema ... ok
[INFO] [stdout] test tests::test_two_body_extractors_no_macro_panic ... ok
[INFO] [stdout] test tests::test_no_cache_attr_no_ttl_header ... ok
[INFO] [stdout] test tests::test_group_prefix_must_start_with_slash - should panic ... ok
[INFO] [stdout] test tests::test_invalid_input_panics - should panic ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 116 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.09s
[INFO] [stdout] 
[INFO] [stderr]    Doc-tests rapina_macros
[INFO] [stdout] 
[INFO] [stdout] running 7 tests
[INFO] [stdout] test src/lib.rs - get (line 57) ... ignored
[INFO] [stdout] test src/lib.rs - job (line 696) ... ignored
[INFO] [stdout] test src/lib.rs - job (line 706) ... ignored
[INFO] [stdout] test src/lib.rs - patch (line 101) ... ignored
[INFO] [stdout] test src/lib.rs - public (line 128) ... ignored
[INFO] [stdout] test src/lib.rs - relay (line 569) ... ignored
[INFO] [stdout] test src/lib.rs - schema (line 1003) ... ignored
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 7 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stdout] all doctests ran in 0.38s; merged doctests compilation took 0.37s
[INFO] running `Command { std: "docker" "inspect" "2da9f595aa03a2865df66cd43a643805500a4b6b4435d51cb89f7fb689a40696", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "2da9f595aa03a2865df66cd43a643805500a4b6b4435d51cb89f7fb689a40696", kill_on_drop: false }`
[INFO] [stdout] 2da9f595aa03a2865df66cd43a643805500a4b6b4435d51cb89f7fb689a40696