[INFO] cloning repository https://github.com/adelie-ai/adelie-telemetry
[INFO] running `Command { std: "git" "-c" "credential.helper=" "-c" "credential.helper=/workspace/cargo-home/bin/git-credential-null" "clone" "--bare" "https://github.com/adelie-ai/adelie-telemetry" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fadelie-ai%2Fadelie-telemetry", kill_on_drop: false }`
[INFO] [stderr] Cloning into bare repository '/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fadelie-ai%2Fadelie-telemetry'...
[INFO] running `Command { std: "git" "rev-parse" "HEAD", kill_on_drop: false }`
[INFO] [stdout] 9d8f56a674511bc657da408ce329e4b50114f1f1
[INFO] testing adelie-ai/adelie-telemetry against 1.99.0-beta.8 for beta-1.100-2
[INFO] running `Command { std: "git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fadelie-ai%2Fadelie-telemetry" "/workspace/builds/worker-1-tc1/source", kill_on_drop: false }`
[INFO] [stderr] Cloning into '/workspace/builds/worker-1-tc1/source'...
[INFO] [stderr] done.
[INFO] removed /workspace/builds/worker-1-tc1/source/rust-toolchain.toml
[INFO] started tweaking git repo https://github.com/adelie-ai/adelie-telemetry
[INFO] finished tweaking git repo https://github.com/adelie-ai/adelie-telemetry
[INFO] tweaked toml for git repo https://github.com/adelie-ai/adelie-telemetry written to /workspace/builds/worker-1-tc1/source/Cargo.toml
[INFO] validating manifest of git repo https://github.com/adelie-ai/adelie-telemetry 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 git repo https://github.com/adelie-ai/adelie-telemetry 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 find-msvc-tools v0.1.10
[INFO] [stderr]   Downloaded tonic-types v0.14.6
[INFO] [stderr]   Downloaded tonic-prost v0.14.6
[INFO] [stderr]   Downloaded tonic v0.14.6
[INFO] [stderr]   Downloaded prost-types v0.14.4
[INFO] [stderr]   Downloaded opentelemetry-appender-tracing v0.32.0
[INFO] [stderr]   Downloaded cc v1.4.1
[INFO] [stderr]   Downloaded aws-lc-rs v1.17.3
[INFO] [stderr]   Downloaded aws-lc-sys v0.43.0
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-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] 021f08e6d0ccada306a3efeeb54ce309e6f674c407ba7a16324b3dbb06310858
[INFO] running `Command { std: "docker" "start" "021f08e6d0ccada306a3efeeb54ce309e6f674c407ba7a16324b3dbb06310858", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "021f08e6d0ccada306a3efeeb54ce309e6f674c407ba7a16324b3dbb06310858", 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" "021f08e6d0ccada306a3efeeb54ce309e6f674c407ba7a16324b3dbb06310858" "/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" "021f08e6d0ccada306a3efeeb54ce309e6f674c407ba7a16324b3dbb06310858", 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" "021f08e6d0ccada306a3efeeb54ce309e6f674c407ba7a16324b3dbb06310858" "/opt/rustwide/cargo-home/bin/cargo" "+1.99.0-beta.8" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stderr]    Compiling syn v2.0.119
[INFO] [stderr]    Compiling regex-automata v0.4.18
[INFO] [stderr]    Compiling syn v3.0.3
[INFO] [stderr]    Compiling thiserror v2.0.19
[INFO] [stderr]    Compiling thiserror-impl v2.0.19
[INFO] [stderr]    Compiling matchers v0.2.0
[INFO] [stderr]    Compiling tracing-attributes v0.1.31
[INFO] [stderr]    Compiling tracing v0.1.44
[INFO] [stderr]    Compiling tracing-subscriber v0.3.23
[INFO] [stderr]    Compiling adelie-telemetry v0.1.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 12.49s
[INFO] running `Command { std: "docker" "inspect" "021f08e6d0ccada306a3efeeb54ce309e6f674c407ba7a16324b3dbb06310858", 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" "021f08e6d0ccada306a3efeeb54ce309e6f674c407ba7a16324b3dbb06310858" "/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 thiserror-impl v2.0.19
[INFO] [stderr]    Compiling tokio v1.53.1
[INFO] [stderr]    Compiling thiserror v2.0.19
[INFO] [stderr]    Compiling adelie-telemetry v0.1.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 6.82s
[INFO] running `Command { std: "docker" "inspect" "021f08e6d0ccada306a3efeeb54ce309e6f674c407ba7a16324b3dbb06310858", 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" "021f08e6d0ccada306a3efeeb54ce309e6f674c407ba7a16324b3dbb06310858" "/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.17s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/adelie_telemetry-8184272af72f44f5)
[INFO] [stdout] 
[INFO] [stdout] running 17 tests
[INFO] [stdout] test clock::tests::system_clock_never_goes_backwards ... ok
[INFO] [stdout] test config::tests::the_default_applies_when_the_variable_is_unset ... ok
[INFO] [stdout] test safe::tests::a_replacement_counts_against_the_cap_at_its_own_width ... ok
[INFO] [stdout] test config::tests::a_value_too_large_to_parse_is_named_and_the_default_applies ... ok
[INFO] [stdout] test safe::tests::a_value_within_the_cap_is_unchanged ... ok
[INFO] [stdout] test config::tests::an_unparseable_value_is_named_and_the_default_applies ... ok
[INFO] [stdout] test config::tests::with_shutdown_budget_outranks_whatever_new_resolved ... ok
[INFO] [stdout] test config::tests::a_negative_value_is_named_and_the_default_applies ... ok
[INFO] [stdout] test config::tests::an_empty_value_reads_as_unset ... ok
[INFO] [stdout] test config::tests::the_variable_sets_the_budget_in_milliseconds ... ok
[INFO] [stdout] test config::tests::zero_is_accepted_and_means_do_not_wait ... ok
[INFO] [stdout] test clock::tests::manual_clock_starts_at_zero_and_only_moves_when_advanced ... ok
[INFO] [stdout] test config::tests::a_fresh_config_registers_no_histogram_views ... ok
[INFO] [stdout] test safe::tests::the_cap_counts_bytes ... ok
[INFO] [stdout] test clock::tests::manual_clock_advances_are_cumulative_across_handles ... ok
[INFO] [stdout] test config::tests::histogram_views_are_kept_in_registration_order ... ok
[INFO] [stdout] test safe::tests::an_empty_value_renders_empty ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 17 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/acceptance_console.rs (/opt/rustwide/target/debug/deps/acceptance_console-0df77c66ce3887a1)
[INFO] [stdout] 
[INFO] [stdout] running 2 tests
[INFO] [stdout] test console_layer_writes_to_stderr_only ... ok
[INFO] [stdout] test span_close_events_carry_duration ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 2 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/acceptance_environment.rs (/opt/rustwide/target/debug/deps/acceptance_environment-231583b94f023aa6)
[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] [stderr]      Running tests/acceptance_init.rs (/opt/rustwide/target/debug/deps/acceptance_init-dbbc6a809ecb850e)
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stderr] 2026-10-06T15:08:38.583757Z  INFO adelie_telemetry: the metrics summary interval interval_ms=0 reason="the binary called with_metrics_dump_interval"
[INFO] [stderr] 2026-10-06T15:08:38.583812Z  INFO acceptance_init: the subscriber still works after the inert guard was dropped
[INFO] [stderr]      Running tests/acceptance_init_registry.rs (/opt/rustwide/target/debug/deps/acceptance_init_registry-55c461e8b6c46ba0)
[INFO] [stderr] 2026-10-06T15:08:38.586782Z  INFO adelie_telemetry: the metrics summary interval interval_ms=60000 reason="the binary called with_metrics_dump_interval"
[INFO] [stderr] 2026-10-06T15:08:38.586975Z  INFO adelie_telemetry::metrics::registry: metrics summary window_seconds=60 uptime_seconds=60 counters=2 histograms=0 value_histograms=0
[INFO] [stderr] 2026-10-06T15:08:38.587045Z  INFO adelie_telemetry::metrics::registry: counter metric="test.after.init" labels= window=1 total=1
[INFO] [stderr] 2026-10-06T15:08:38.587096Z  INFO adelie_telemetry::metrics::registry: counter metric="test.before.init" labels= window=0 total=1
[INFO] [stderr] 2026-10-06T15:08:38.587390Z  INFO adelie_telemetry::metrics::registry: metrics summary window_seconds=0 uptime_seconds=60 counters=2 histograms=0 value_histograms=0
[INFO] [stderr] 2026-10-06T15:08:38.587421Z  INFO adelie_telemetry::metrics::registry: counter metric="test.after.init" labels= window=0 total=1
[INFO] [stderr] 2026-10-06T15:08:38.587433Z  INFO adelie_telemetry::metrics::registry: counter metric="test.before.init" labels= window=0 total=1
[INFO] [stderr]      Running tests/acceptance_metrics.rs (/opt/rustwide/target/debug/deps/acceptance_metrics-2f195ed1a6a57600)
[INFO] [stdout] test init_is_idempotent ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stdout] test init_configures_the_global_registry_without_losing_measurements ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 25 tests
[INFO] [stdout] test histogram_places_measurements_in_the_right_buckets ... ok
[INFO] [stdout] test label_value_cannot_forge_a_log_line ... ok
[INFO] [stdout] test cardinality_cap_counts_a_label_set_once_across_instruments ... ok
[INFO] [stdout] test label_value_cannot_reverse_what_a_reader_sees ... ok
[INFO] [stdout] test a_bidi_override_cannot_disguise_a_tool_name ... ok
[INFO] [stdout] test a_zero_width_joiner_survives_the_sanitiser ... ok
[INFO] [stdout] test histogram_reports_nothing_when_empty ... ok
[INFO] [stdout] test metrics_accumulate_without_otel ... ok
[INFO] [stdout] test record_value_produces_a_value_histogram_summary_with_its_own_unit ... ok
[INFO] [stdout] test summary_is_deterministically_ordered ... ok
[INFO] [stdout] test metrics_cardinality_cap_is_per_metric ... ok
[INFO] [stdout] test value_histogram_places_a_measurement_at_its_exact_boundary ... ok
[INFO] [stdout] test histogram_buckets_are_the_shared_boundaries ... ok
[INFO] [stdout] test metrics_dump_interval_zero_disables ... ok
[INFO] [stdout] test label_order_does_not_split_a_series ... ok
[INFO] [stdout] test value_histogram_reports_nothing_when_empty ... ok
[INFO] [stdout] test value_histogram_shares_the_cardinality_budget ... ok
[INFO] [stdout] test reconfigure_keeps_existing_measurements ... ok
[INFO] [stdout] test metrics_cardinality_cap_folds_into_other ... ok
[INFO] [stdout] test two_value_histograms_keep_their_own_bucket_boundaries ... ok
[INFO] [stdout] test reconfigure_starts_a_fresh_window ... ok
[INFO] [stdout] test record_value_panics_in_a_debug_build_when_a_later_call_disagrees_on_unit - should panic ... ok
[INFO] [stdout] test record_value_panics_in_a_debug_build_when_a_later_call_disagrees_on_boundaries - should panic ... ok
[INFO] [stdout] test label_value_is_truncated ... ok
[INFO] [stdout] test label_value_truncation_respects_character_boundaries ... ok
[INFO] [stderr]      Running tests/acceptance_no_otel.rs (/opt/rustwide/target/debug/deps/acceptance_no_otel-89dac29262aefbfa)
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 25 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.61s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stdout] test default_build_pulls_no_opentelemetry ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.17s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/acceptance_registry_isolation.rs (/opt/rustwide/target/debug/deps/acceptance_registry_isolation-8468bf50ca1f7117)
[INFO] [stdout] 
[INFO] [stdout] running 13 tests
[INFO] [stdout] test a_scope_covers_counters_and_histograms ... ok
[INFO] [stdout] test a_scope_keeps_its_measurements_out_of_the_process_registry ... ok
[INFO] [stdout] test a_test_sees_only_its_own_measurements_1 ... ok
[INFO] [stdout] test a_scope_applies_the_cardinality_cap ... ok
[INFO] [stdout] test a_scope_takes_its_own_clock_and_settings ... ok
[INFO] [stdout] test a_test_sees_only_its_own_measurements_2 ... ok
[INFO] [stdout] test recording_returns_to_the_process_registry_when_the_scope_ends ... ok
[INFO] [stdout] test a_test_sees_only_its_own_measurements_5 ... ok
[INFO] [stdout] test a_test_sees_only_its_own_measurements_6 ... ok
[INFO] [stdout] test a_test_sees_only_its_own_measurements_7 ... ok
[INFO] [stdout] test a_test_sees_only_its_own_measurements_3 ... ok
[INFO] [stdout] test a_test_sees_only_its_own_measurements_4 ... ok
[INFO] [stderr]      Running tests/acceptance_resilience.rs (/opt/rustwide/target/debug/deps/acceptance_resilience-ffaa41514bcd7db7)
[INFO] [stdout] test a_test_sees_only_its_own_measurements_8 ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 13 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/acceptance_safe.rs (/opt/rustwide/target/debug/deps/acceptance_safe-41a1a33ab509c68a)
[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] [stdout] 
[INFO] [stdout] running 9 tests
[INFO] [stdout] test any_displayable_value_can_be_wrapped ... ok
[INFO] [stdout] test safe_strips_every_deceptive_character ... ok
[INFO] [stdout] test a_name_is_capped_tighter_than_a_message ... ok
[INFO] [stdout] test truncation_marks_the_cut ... ok
[INFO] [stdout] test nothing_renders_until_it_is_asked_for ... ok
[INFO] [stdout] test safe_and_label_agree_character_for_character ... ok
[INFO] [stdout] test safe_leaves_innocent_characters_alone ... ok
[INFO] [stdout] test an_explicit_cap_is_available ... ok
[INFO] [stderr]      Running tests/acceptance_trace_context.rs (/opt/rustwide/target/debug/deps/acceptance_trace_context-f9907cf4897fb10d)
[INFO] [stdout] test truncation_respects_character_boundaries ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 9 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 15 tests
[INFO] [stdout] test extract_traceparent_accepts_a_header_at_the_limit ... ok
[INFO] [stdout] test resolve_trace_rejects_a_malformed_header_rather_than_minting ... ok
[INFO] [stdout] test malformed_or_nil_client_id_falls_back_to_minting ... ok
[INFO] [stdout] test resolve_trace_mints_from_request_id_when_no_header_arrives ... ok
[INFO] [stdout] test minted_fallback_ids_are_distinct ... ok
[INFO] [stdout] test traceparent_accepts_an_unknown_future_version ... ok
[INFO] [stdout] test traceparent_extract_takes_precedence_over_mint ... ok
[INFO] [stdout] test trace_id_from_uuid_rejects_all_zero ... ok
[INFO] [stdout] test trace_id_mints_without_installing_telemetry ... ok
[INFO] [stdout] test traceparent_rejects_malformed_fields ... ok
[INFO] [stdout] test trace_id_from_uuid_round_trips ... ok
[INFO] [stdout] test traceparent_rejects_the_reserved_version ... ok
[INFO] [stdout] test traceparent_round_trips_through_inject_and_extract ... ok
[INFO] [stdout] test traceparent_rejects_the_zero_sentinels ... ok
[INFO] [stdout] test extract_traceparent_rejects_an_oversized_header ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 15 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.04s
[INFO] [stdout] 
[INFO] [stderr]    Doc-tests adelie_telemetry
[INFO] [stdout] 
[INFO] [stdout] running 5 tests
[INFO] [stdout] test src/lib.rs - (line 36) - compile ... ok
[INFO] [stdout] test src/metrics/mod.rs - metrics::TestScope (line 136) ... ok
[INFO] [stdout] test src/safe.rs - safe::Safe (line 35) ... ok
[INFO] [stdout] test src/metrics/mod.rs - metrics (line 8) ... ok
[INFO] [stdout] test src/lib.rs - (line 52) ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 5 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.02s
[INFO] [stdout] 
[INFO] [stdout] all doctests ran in 0.69s; merged doctests compilation took 0.67s
[INFO] running `Command { std: "docker" "inspect" "021f08e6d0ccada306a3efeeb54ce309e6f674c407ba7a16324b3dbb06310858", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "021f08e6d0ccada306a3efeeb54ce309e6f674c407ba7a16324b3dbb06310858", kill_on_drop: false }`
[INFO] [stdout] 021f08e6d0ccada306a3efeeb54ce309e6f674c407ba7a16324b3dbb06310858
