[INFO] fetching crate rustlavel-otel 0.8.1...
[INFO] testing rustlavel-otel-0.8.1 against 1.100.0-beta.1 for beta-1.100-2
[INFO] extracting crate rustlavel-otel 0.8.1 into /workspace/builds/worker-6-tc2/source
[INFO] started tweaking crates.io crate rustlavel-otel 0.8.1
[INFO] removed 0 missing tests
[INFO] finished tweaking crates.io crate rustlavel-otel 0.8.1
[INFO] tweaked toml for crates.io crate rustlavel-otel 0.8.1 written to /workspace/builds/worker-6-tc2/source/Cargo.toml
[INFO] validating manifest of crates.io crate rustlavel-otel 0.8.1 on toolchain 1.100.0-beta.1
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+1.100.0-beta.1" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate crates.io crate rustlavel-otel 0.8.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" "+1.100.0-beta.1" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-tc2/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] 2195e67bd29ac237a6b8be4fe9b11594fef547804775c87ecc955b73423bb23d
[INFO] running `Command { std: "docker" "start" "2195e67bd29ac237a6b8be4fe9b11594fef547804775c87ecc955b73423bb23d", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "2195e67bd29ac237a6b8be4fe9b11594fef547804775c87ecc955b73423bb23d", 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" "2195e67bd29ac237a6b8be4fe9b11594fef547804775c87ecc955b73423bb23d" "/opt/rustwide/cargo-home/bin/cargo" "+1.100.0-beta.1" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "2195e67bd29ac237a6b8be4fe9b11594fef547804775c87ecc955b73423bb23d", 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" "2195e67bd29ac237a6b8be4fe9b11594fef547804775c87ecc955b73423bb23d" "/opt/rustwide/cargo-home/bin/cargo" "+1.100.0-beta.1" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stderr]    Compiling libc v0.2.189
[INFO] [stderr]    Compiling find-msvc-tools v0.1.11
[INFO] [stderr]    Compiling fs_extra v1.3.0
[INFO] [stderr]    Compiling dunce v1.0.5
[INFO] [stderr]    Compiling zeroize v1.9.0
[INFO] [stderr]    Compiling aws-lc-rs v1.18.0
[INFO] [stderr]    Compiling rustls v0.23.43
[INFO] [stderr]    Compiling once_cell v1.21.4
[INFO] [stderr]    Compiling rustlavel-core v0.8.1
[INFO] [stderr]    Compiling syn v3.0.4
[INFO] [stderr]    Compiling rustls-pki-types v1.15.1
[INFO] [stderr]    Compiling webpki-roots v1.0.9
[INFO] [stderr]    Compiling webpki-roots v0.26.11
[INFO] [stderr]    Compiling jobserver v0.1.35
[INFO] [stderr]    Compiling parking_lot_core v0.9.12
[INFO] [stderr]    Compiling errno v0.3.14
[INFO] [stderr]    Compiling mio v1.2.2
[INFO] [stderr]    Compiling socket2 v0.6.5
[INFO] [stderr]    Compiling signal-hook-registry v1.4.8
[INFO] [stderr]    Compiling parking_lot v0.12.5
[INFO] [stderr]    Compiling cc v1.4.4
[INFO] [stderr]    Compiling cmake v0.1.58
[INFO] [stderr]    Compiling aws-lc-sys v0.44.0
[INFO] [stderr]    Compiling tokio-macros v2.7.2
[INFO] [stderr]    Compiling tokio v1.53.1
[INFO] [stderr]    Compiling rustlavel-http v0.8.1
[INFO] [stderr]    Compiling rustls-webpki v0.103.15
[INFO] [stderr]    Compiling tokio-rustls v0.26.4
[INFO] [stderr]    Compiling rustlavel-client v0.8.1
[INFO] [stderr]    Compiling rustlavel-otel v0.8.1 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 32.56s
[INFO] running `Command { std: "docker" "inspect" "2195e67bd29ac237a6b8be4fe9b11594fef547804775c87ecc955b73423bb23d", 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" "2195e67bd29ac237a6b8be4fe9b11594fef547804775c87ecc955b73423bb23d" "/opt/rustwide/cargo-home/bin/cargo" "+1.100.0-beta.1" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stderr]    Compiling rustlavel-otel v0.8.1 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 3.46s
[INFO] running `Command { std: "docker" "inspect" "2195e67bd29ac237a6b8be4fe9b11594fef547804775c87ecc955b73423bb23d", 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" "2195e67bd29ac237a6b8be4fe9b11594fef547804775c87ecc955b73423bb23d" "/opt/rustwide/cargo-home/bin/cargo" "+1.100.0-beta.1" "test" "--frozen", kill_on_drop: false }`
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.09s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/build/rustlavel-otel/140e5f3ede141fe6/out/rustlavel_otel-140e5f3ede141fe6)
[INFO] [stdout] 
[INFO] [stdout] running 88 tests
[INFO] [stdout] test exporter::tests::a_full_queue_drops_the_newest_span_and_keeps_the_ones_already_waiting ... ok
[INFO] [stdout] test exporter::tests::a_flush_posts_the_queued_spans_with_the_protobuf_content_type ... ok
[INFO] [stdout] test exporter::tests::a_batch_larger_than_the_limit_is_split_across_requests ... ok
[INFO] [stdout] test exporter::tests::an_unsampled_span_is_never_queued ... ok
[INFO] [stdout] test exporter::tests::an_empty_meter_and_queue_send_nothing_at_all ... ok
[INFO] [stdout] test exporter::tests::backoff_grows_and_a_retry_after_header_is_capped ... ok
[INFO] [stderr] [38;5;214m WARN[0m  otel: http://collector.test/v1/traces rejected the payload with 400 Bad Request: bad field
[INFO] [stderr] [38;5;214m WARN[0m  otel: http://collector.test/v1/metrics rejected the payload with 400 Bad Request: bad field
[INFO] [stderr] [38;5;214m WARN[0m  otel: dropped 2 span(s); the export queue holds 1 and the collector is not keeping up. Raise `otel.queue`, shorten `otel.interval_ms`, or fix the collector.
[INFO] [stdout] test exporter::tests::a_rejected_payload_is_not_retried_because_it_would_be_rejected_again ... ok
[INFO] [stdout] test exporter::tests::shutdown_without_a_running_loop_still_flushes ... ok
[INFO] [stdout] test exporter::tests::the_json_protocol_sends_a_readable_body ... ok
[INFO] [stdout] test exporter::tests::a_base_endpoint_gains_the_signal_path_without_doubling_the_slash ... ok
[INFO] [stdout] test metrics::tests::a_sum_encodes_as_a_monotonic_cumulative_integer_point ... ok
[INFO] [stdout] test exporter::tests::configured_headers_are_sent_on_every_request ... ok
[INFO] [stdout] test exporter::tests::drops_are_reported_as_a_metric_once_rather_than_once_each ... ok
[INFO] [stdout] test exporter::tests::metrics_go_to_their_own_endpoint ... ok
[INFO] [stdout] test exporter::tests::only_overload_and_server_faults_are_retried ... ok
[INFO] [stdout] test exporter::tests::the_exporters_own_requests_are_recognisable_so_they_are_not_measured ... ok
[INFO] [stdout] test exporter::tests::the_protocol_is_read_from_the_names_the_specification_uses ... ok
[INFO] [stdout] test metrics::tests::a_gauge_replaces_and_a_counter_accumulates ... ok
[INFO] [stdout] test metrics::tests::a_histogram_sends_one_more_bucket_count_than_it_has_bounds ... ok
[INFO] [stdout] test metrics::tests::an_empty_meter_produces_no_payload_at_all ... ok
[INFO] [stdout] test metrics::tests::a_zero_valued_point_still_carries_a_value ... ok
[INFO] [stdout] test metrics::tests::an_observation_past_every_boundary_lands_in_the_overflow_bucket ... ok
[INFO] [stdout] test metrics::tests::histogram_buckets_are_not_cumulative ... ok
[INFO] [stdout] test metrics::tests::model_calls_produce_a_duration_and_a_token_sum_per_direction ... ok
[INFO] [stderr] [38;5;39m INFO[0m  otel: exporting `checkout` to http://collector.test/v1/traces
[INFO] [stdout] test metrics::tests::requests_are_measured_by_route_pattern_rather_than_path ... ok
[INFO] [stdout] test metrics::tests::queue_outcomes_are_counted_separately ... ok
[INFO] [stderr] [38;5;39m INFO[0m  otel: exporting `checkout` to http://collector.test/v1/traces
[INFO] [stdout] test metrics::tests::the_json_mapping_renders_counts_as_strings_and_bounds_as_numbers ... ok
[INFO] [stderr] [38;5;39m INFO[0m  otel: exporting `checkout` to http://collector.test/v1/traces
[INFO] [stdout] test plugin::tests::configuration_supplies_the_endpoint_service_and_limits ... ok
[INFO] [stdout] test plugin::tests::nonsense_limits_are_clamped_rather_than_trusted ... ok
[INFO] [stdout] test plugin::tests::a_builder_call_wins_over_configuration ... ok
[INFO] [stdout] test plugin::tests::headers_are_parsed_and_percent_decoded ... ok
[INFO] [stdout] test metrics::tests::an_unknown_event_kind_is_ignored_rather_than_guessed_at ... ok
[INFO] [stdout] test metrics::tests::an_unmatched_request_does_not_become_a_series_per_path ... ok
[INFO] [stdout] test plugin::tests::disabling_it_mounts_nothing_and_subscribes_to_nothing ... ok
[INFO] [stdout] test plugin::tests::an_unsampled_upstream_stops_this_service_recording_too ... ok
[INFO] [stdout] test plugin::tests::a_request_becomes_a_server_span_with_the_route_as_its_name ... ok
[INFO] [stdout] test plugin::tests::the_environment_is_recorded_on_the_resource ... ok
[INFO] [stderr] [38;5;39m INFO[0m  otel: exporting `checkout` to http://collector.test/v1/traces
[INFO] [stdout] test plugin::tests::a_five_hundred_is_an_error_and_a_four_hundred_is_not ... ok
[INFO] [stderr] [38;5;39m INFO[0m  otel: exporting `checkout` to http://collector.test/v1/traces
[INFO] [stdout] test protobuf::tests::a_string_field_is_tag_length_then_utf8 ... ok
[INFO] [stdout] test plugin::tests::a_query_inside_a_request_becomes_a_child_of_the_request_span ... ok
[INFO] [stdout] test protobuf::tests::default_values_are_omitted_entirely ... ok
[INFO] [stdout] test plugin::tests::the_service_name_falls_back_to_the_application_name ... ok
[INFO] [stderr] [38;5;39m INFO[0m  otel: exporting `checkout` to http://collector.test/v1/traces
[INFO] [stdout] test protobuf::tests::a_negative_int64_sign_extends_to_ten_bytes ... ok
[INFO] [stdout] test protobuf::tests::a_double_is_its_ieee_754_bits_little_endian ... ok
[INFO] [stdout] test plugin::tests::requests_are_measured_as_well_as_traced ... ok
[INFO] [stdout] test protobuf::tests::packed_doubles_are_bounds_as_the_collector_expects_them ... ok
[INFO] [stdout] test protobuf::tests::field_numbers_past_fifteen_use_a_two_byte_tag ... ok
[INFO] [stdout] test protobuf::tests::fixed64_is_eight_little_endian_bytes ... ok
[INFO] [stdout] test protobuf::tests::oneof_members_are_written_even_when_they_hold_the_default ... ok
[INFO] [stdout] test resource::tests::a_default_valued_attribute_is_still_written ... ok
[INFO] [stdout] test protobuf::tests::a_nested_message_is_length_delimited_around_its_own_bytes ... ok
[INFO] [stdout] test resource::tests::a_malformed_percent_escape_is_left_alone_rather_than_swallowed ... ok
[INFO] [stdout] test resource::tests::a_time_before_the_epoch_saturates_instead_of_wrapping ... ok
[INFO] [stdout] test resource::tests::a_resource_attribute_replaces_rather_than_duplicates ... ok
[INFO] [stdout] test resource::tests::a_string_attribute_encodes_as_key_then_any_value ... ok
[INFO] [stdout] test protobuf::tests::zero_is_skipped_by_double_and_kept_by_present_double ... ok
[INFO] [stdout] test resource::tests::resource_attribute_pairs_are_parsed_and_percent_decoded ... ok
[INFO] [stdout] test resource::tests::attribute_values_have_a_total_order_even_with_nan ... ok
[INFO] [stdout] test resource::tests::an_integer_attribute_uses_int_value_not_double_value ... ok
[INFO] [stdout] test resource::tests::json_numbers_narrow_to_integers_when_they_are_whole ... ok
[INFO] [stdout] test trace::tests::a_future_version_is_read_for_the_fields_it_shares ... ok
[INFO] [stdout] test resource::tests::the_json_mapping_keeps_doubles_as_numbers ... ok
[INFO] [stdout] test trace::tests::a_failed_event_becomes_a_span_with_an_error_status ... ok
[INFO] [stdout] test trace::tests::a_query_event_becomes_a_child_span_that_ends_when_the_event_was_recorded ... ok
[INFO] [stdout] test trace::tests::a_traceparent_round_trips_through_parse_and_format ... ok
[INFO] [stdout] test plugin::tests::the_exporters_own_traffic_is_not_measured_as_application_traffic ... ok
[INFO] [stdout] test trace::tests::a_server_span_carries_kind_two_and_both_timestamps ... ok
[INFO] [stdout] test trace::tests::a_span_encodes_its_ids_as_raw_bytes_not_hex ... ok
[INFO] [stdout] test trace::tests::an_unset_status_writes_no_status_field_and_an_error_writes_both_parts ... ok
[INFO] [stdout] test resource::tests::the_json_mapping_renders_integers_as_strings ... ok
[INFO] [stdout] test trace::tests::a_child_keeps_the_trace_and_takes_a_new_span_id ... ok
[INFO] [stdout] test trace::tests::span_names_stay_low_cardinality_whatever_the_statement ... ok
[INFO] [stdout] test trace::tests::the_current_span_is_visible_to_everything_awaited_inside_it ... ok
[INFO] [stdout] test trace::tests::the_export_request_nests_resource_scope_and_spans_in_that_order ... ok
[INFO] [stdout] test trace::tests::the_json_mapping_uses_hex_ids_and_string_timestamps ... ok
[INFO] [stdout] test trace::tests::the_sampled_flag_is_the_low_bit_of_the_last_field ... ok
[INFO] [stdout] test protobuf::tests::packed_repeated_scalars_share_one_tag_and_one_length ... ok
[INFO] [stdout] test trace::tests::http_request_events_are_left_to_the_middleware ... ok
[INFO] [stdout] test trace::tests::malformed_traceparents_are_refused_rather_than_half_read ... ok
[INFO] [stdout] test trace::tests::identifiers_are_unique_and_never_zero ... ok
[INFO] [stdout] test trace::tests::traceparent_reports_the_span_in_scope ... ok
[INFO] [stdout] test protobuf::tests::varints_match_the_specifications_worked_example ... ok
[INFO] [stdout] test protobuf::tests::a_tagged_varint_field_is_the_canonical_three_bytes ... ok
[INFO] [stderr] [38;5;39m INFO[0m  otel: exporting `checkout` to http://collector.test/v1/traces
[INFO] [stdout] test protobuf::tests::an_empty_nested_message_still_writes_its_header ... ok
[INFO] [stdout] test plugin::tests::an_incoming_traceparent_continues_the_trace_and_the_response_carries_one_back ... ok
[INFO] [stdout] test exporter::tests::the_background_loop_flushes_on_its_interval_and_on_shutdown ... ok
[INFO] [stderr] [38;5;214m WARN[0m  otel: giving up on http://collector.test/v1/traces after 3 attempt(s): no fake response is scripted for POST http://collector.test/v1/traces. Add `.on("…", …)` or a `.fallback(…)`.
[INFO] [stderr] [38;5;214m WARN[0m  otel: http://collector.test/v1/traces kept refusing the batch after 3 attempt(s); discarding it
[INFO] [stdout] test exporter::tests::a_server_error_is_retried_and_then_the_batch_is_given_up_on ... ok
[INFO] [stderr] [38;5;214m WARN[0m  otel: http://collector.test/v1/metrics kept refusing the batch after 3 attempt(s); discarding it
[INFO] [stderr] [38;5;214m WARN[0m  otel: giving up on http://collector.test/v1/metrics after 3 attempt(s): no fake response is scripted for POST http://collector.test/v1/metrics. Add `.on("…", …)` or a `.fallback(…)`.
[INFO] [stdout] test exporter::tests::an_unreachable_collector_costs_dropped_spans_and_nothing_else ... ok
[INFO] [stderr]      Running tests/collector.rs (/opt/rustwide/target/debug/build/rustlavel-otel/e962b539d2cbe061/out/collector-e962b539d2cbe061)
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 88 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 1.51s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 4 tests
[INFO] [stdout] test the_collector_parses_a_json_span_too ... ok
[INFO] [stdout] test the_collector_parses_a_protobuf_span_into_the_span_that_was_sent ... ok
[INFO] [stdout] test the_collector_parses_a_histogram_with_the_buckets_it_was_given ... ok
[INFO] [stdout] test a_request_and_the_query_inside_it_arrive_as_one_trace ... ok
[INFO] [stderr]    Doc-tests rustlavel_otel
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 4 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 2 tests
[INFO] [stdout] test src/lib.rs - (line 3) ... ignored
[INFO] [stdout] test src/plugin.rs - plugin (line 3) ... ignored
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 2 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stdout] all doctests ran in 0.26s; merged doctests compilation took 0.25s
[INFO] running `Command { std: "docker" "inspect" "2195e67bd29ac237a6b8be4fe9b11594fef547804775c87ecc955b73423bb23d", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "2195e67bd29ac237a6b8be4fe9b11594fef547804775c87ecc955b73423bb23d", kill_on_drop: false }`
[INFO] [stdout] 2195e67bd29ac237a6b8be4fe9b11594fef547804775c87ecc955b73423bb23d
