[INFO] fetching crate curia 0.2.2...
[INFO] testing curia-0.2.2 against 1.100.0-beta.1+cargoflags=--release for beta-release-1.100-2
[INFO] extracting crate curia 0.2.2 into /workspace/builds/worker-7-tc2/source
[INFO] removed /workspace/builds/worker-7-tc2/source/rust-toolchain.toml
[INFO] started tweaking crates.io crate curia 0.2.2
[INFO] removed 0 missing tests
[INFO] finished tweaking crates.io crate curia 0.2.2
[INFO] tweaked toml for crates.io crate curia 0.2.2 written to /workspace/builds/worker-7-tc2/source/Cargo.toml
[INFO] validating manifest of crates.io crate curia 0.2.2 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 curia 0.2.2 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] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-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:77db811e55d90add9212f6832c23229e3de5f1c1f9905e8cb07319ea15df73ac" "sleep" "infinity", kill_on_drop: false }`
[INFO] [stdout] fe93d7ea36b4ca9a9f2c940b2387585b0bf0dab9bc46d27fe441e8a6bae9508b
[INFO] running `Command { std: "docker" "start" "fe93d7ea36b4ca9a9f2c940b2387585b0bf0dab9bc46d27fe441e8a6bae9508b", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "fe93d7ea36b4ca9a9f2c940b2387585b0bf0dab9bc46d27fe441e8a6bae9508b", 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" "fe93d7ea36b4ca9a9f2c940b2387585b0bf0dab9bc46d27fe441e8a6bae9508b" "/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" "fe93d7ea36b4ca9a9f2c940b2387585b0bf0dab9bc46d27fe441e8a6bae9508b", 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" "fe93d7ea36b4ca9a9f2c940b2387585b0bf0dab9bc46d27fe441e8a6bae9508b" "/opt/rustwide/cargo-home/bin/cargo" "+1.100.0-beta.1" "build" "--frozen" "--message-format=json" "--release", kill_on_drop: false }`
[INFO] [stderr]    Compiling serde_core v1.0.229
[INFO] [stderr]    Compiling num-traits v0.2.19
[INFO] [stderr]    Compiling serde v1.0.229
[INFO] [stderr]    Compiling syn v3.0.3
[INFO] [stderr]    Compiling syn v2.0.119
[INFO] [stderr]    Compiling time-macros v0.2.32
[INFO] [stderr]    Compiling serde_json v1.0.151
[INFO] [stderr]    Compiling spin v0.9.9
[INFO] [stderr]    Compiling futures-core v0.3.34
[INFO] [stderr]    Compiling memchr v2.8.3
[INFO] [stderr]    Compiling fastrand v2.5.0
[INFO] [stderr]    Compiling tracing-log v0.2.0
[INFO] [stderr]    Compiling flume v0.12.0
[INFO] [stderr]    Compiling tracing-subscriber v0.3.23
[INFO] [stderr]    Compiling time v0.3.55
[INFO] [stderr]    Compiling serde_derive v1.0.229
[INFO] [stderr]    Compiling thiserror-impl v2.0.20
[INFO] [stderr]    Compiling thiserror v2.0.20
[INFO] [stderr]    Compiling tracing-attributes v0.1.31
[INFO] [stderr]    Compiling tracing v0.1.44
[INFO] [stderr]    Compiling chrono v0.4.45
[INFO] [stderr]    Compiling curia v0.2.2 (/opt/rustwide/workdir)
[INFO] [stderr] warning: unused dependency `tracing`
[INFO] [stderr]   --> Cargo.toml:22:15
[INFO] [stderr]    |
[INFO] [stderr] 22 | [dependencies.tracing]
[INFO] [stderr]    |               ^^^^^^^^
[INFO] [stderr]    |
[INFO] [stderr]    = note: `cargo::unused_dependencies` is set to `warn` by default
[INFO] [stderr] help: consider removing the dependency on `tracing`
[INFO] [stderr] warning: `curia` (manifest) generated 1 warning
[INFO] [stderr]     Finished `release` profile [optimized] target(s) in 38.83s
[INFO] running `Command { std: "docker" "inspect" "fe93d7ea36b4ca9a9f2c940b2387585b0bf0dab9bc46d27fe441e8a6bae9508b", 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" "fe93d7ea36b4ca9a9f2c940b2387585b0bf0dab9bc46d27fe441e8a6bae9508b" "/opt/rustwide/cargo-home/bin/cargo" "+1.100.0-beta.1" "test" "--frozen" "--no-run" "--message-format=json" "--release", kill_on_drop: false }`
[INFO] [stderr]    Compiling curia v0.2.2 (/opt/rustwide/workdir)
[INFO] [stderr] warning: unused dependency `tracing`
[INFO] [stderr]   --> Cargo.toml:22:15
[INFO] [stderr]    |
[INFO] [stderr] 22 | [dependencies.tracing]
[INFO] [stderr]    |               ^^^^^^^^
[INFO] [stderr]    |
[INFO] [stderr]    = note: `cargo::unused_dependencies` is set to `warn` by default
[INFO] [stderr] help: consider removing the dependency on `tracing`
[INFO] [stderr] help: to still use for development builds, move to `dev-dependencies`
[INFO] [stderr] warning: `curia` (manifest) generated 1 warning
[INFO] [stderr]     Finished `release` profile [optimized] target(s) in 7.58s
[INFO] running `Command { std: "docker" "inspect" "fe93d7ea36b4ca9a9f2c940b2387585b0bf0dab9bc46d27fe441e8a6bae9508b", 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" "fe93d7ea36b4ca9a9f2c940b2387585b0bf0dab9bc46d27fe441e8a6bae9508b" "/opt/rustwide/cargo-home/bin/cargo" "+1.100.0-beta.1" "test" "--frozen" "--release", kill_on_drop: false }`
[INFO] [stderr] warning: unused dependency `tracing`
[INFO] [stderr]   --> Cargo.toml:22:15
[INFO] [stderr]    |
[INFO] [stderr] 22 | [dependencies.tracing]
[INFO] [stderr]    |               ^^^^^^^^
[INFO] [stderr]    |
[INFO] [stderr]    = note: `cargo::unused_dependencies` is set to `warn` by default
[INFO] [stderr] help: consider removing the dependency on `tracing`
[INFO] [stderr] help: to still use for development builds, move to `dev-dependencies`
[INFO] [stderr] warning: `curia` (manifest) generated 1 warning
[INFO] [stderr]     Finished `release` profile [optimized] target(s) in 0.10s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/release/build/curia/d3b2e8dd8e6f6ee7/out/curia-d3b2e8dd8e6f6ee7)
[INFO] [stdout] 
[INFO] [stdout] running 50 tests
[INFO] [stdout] test rotation::naming::archive::tests::a_file_name_containing_a_separator_still_round_trips ... ok
[INFO] [stdout] test rotation::naming::archive::tests::a_collision_suffixed_archive_sorts_after_the_one_it_followed ... ok
[INFO] [stdout] test rotation::naming::archive::tests::a_malformed_collision_suffix_is_rejected ... ok
[INFO] [stdout] test rotation::naming::archive::tests::a_malformed_stamp_is_rejected ... ok
[INFO] [stdout] test rotation::naming::archive::tests::a_foreign_file_sharing_the_prefix_is_not_an_archive ... ok
[INFO] [stdout] test rotation::naming::archive::tests::an_archive_of_a_different_log_is_rejected ... ok
[INFO] [stdout] test rotation::naming::archive::tests::archives_order_oldest_first_across_a_day_boundary ... ok
[INFO] [stdout] test rotation::naming::archive::tests::a_collision_suffixed_name_survives_a_build_and_parse_round_trip ... ok
[INFO] [stdout] test rotation::naming::archive::tests::a_file_without_the_log_extension_is_rejected ... ok
[INFO] [stdout] test rotation::naming::archive::tests::a_suffix_of_ten_sorts_after_a_suffix_of_two ... ok
[INFO] [stdout] test rotation::naming::archive::tests::suffixes_are_taken_in_order_and_never_reuse_an_existing_archive ... ok
[INFO] [stdout] test rotation::naming::archive::tests::the_first_collision_takes_suffix_one ... ok
[INFO] [stdout] test rotation::naming::archive::tests::the_active_file_is_not_an_archive ... ok
[INFO] [stdout] test rotation::naming::stamp::tests::a_malformed_stamp_is_rejected ... ok
[INFO] [stdout] test rotation::naming::archive::tests::a_free_name_is_used_unsuffixed ... ok
[INFO] [stdout] test rotation::naming::stamp::tests::a_rendered_stamp_is_exactly_as_wide_as_the_shape ... ok
[INFO] [stdout] test rotation::naming::archive::tests::a_gap_in_the_suffixes_is_filled_rather_than_skipped ... ok
[INFO] [stdout] test rotation::naming::archive::tests::an_unsuffixed_name_survives_a_build_and_parse_round_trip ... ok
[INFO] [stdout] test rotation::naming::stamp::tests::every_stamp_component_is_padded_to_a_fixed_width ... ok
[INFO] [stdout] test rotation::naming::stamp::tests::milliseconds_are_truncated_rather_than_rounded_up ... ok
[INFO] [stdout] test rotation::naming::stamp::tests::stamps_order_as_instants_across_a_day_boundary ... ok
[INFO] [stdout] test rotation::policy::tests::a_write_that_lands_exactly_on_the_limit_appends ... ok
[INFO] [stdout] test rotation::policy::tests::a_write_that_stays_below_the_limit_appends ... ok
[INFO] [stdout] test rotation::policy::tests::append_onto_a_file_with_room_left_appends ... ok
[INFO] [stdout] test rotation::policy::tests::append_onto_a_full_file_rotates ... ok
[INFO] [stdout] test rotation::policy::tests::append_onto_a_missing_file_appends ... ok
[INFO] [stdout] test rotation::policy::tests::append_onto_a_missing_file_appends_even_when_the_limit_is_zero ... ok
[INFO] [stdout] test rotation::policy::tests::keep_all_archives_the_active_file_and_deletes_nothing ... ok
[INFO] [stdout] test rotation::policy::tests::keep_one_discards_the_active_file_and_leaves_foreign_archives_alone ... ok
[INFO] [stdout] test rotation::policy::tests::a_write_that_would_pass_the_limit_rotates ... ok
[INFO] [stdout] test rotation::policy::tests::keep_some_deletes_nothing_when_fewer_archives_exist_than_the_budget ... ok
[INFO] [stdout] test rotation::policy::tests::keep_some_deletes_one_when_the_archives_already_fill_the_budget ... ok
[INFO] [stdout] test rotation::policy::tests::keep_some_three_counts_the_new_archive_towards_the_budget ... ok
[INFO] [stdout] test rotation::policy::tests::keep_some_zero_behaves_exactly_like_keep_one ... ok
[INFO] [stdout] test rotation::policy::tests::an_oversized_write_into_an_empty_file_appends_rather_than_rotating_forever ... ok
[INFO] [stdout] test rotation::naming::stamp::tests::a_rendered_stamp_parses_back ... ok
[INFO] [stdout] test rotation::policy::tests::keep_some_deletes_the_whole_overhang_when_a_previous_run_left_more ... ok
[INFO] [stdout] test rotation::policy::tests::keep_some_one_archives_and_deletes_every_older_archive ... ok
[INFO] [stdout] test rotation::policy::tests::rotate_on_open_with_nothing_to_archive_appends ... ok
[INFO] [stdout] test rotation::policy::tests::rotate_on_open_with_a_previous_session_on_disk_rotates ... ok
[INFO] [stdout] test rotation::policy::tests::startup_never_deletes_under_a_strategy_that_creates_no_archives ... ok
[INFO] [stdout] test rotation::policy::tests::startup_deletes_nothing_when_the_directory_is_within_budget ... ok
[INFO] [stdout] test rotation::policy::tests::the_write_decision_does_not_overflow_on_an_unbounded_limit ... ok
[INFO] [stdout] test strategy::file_open::tests::the_two_variants_compare_by_value ... ok
[INFO] [stdout] test strategy::rotation::tests::a_config_file_deserialises_back_into_the_same_variant ... ok
[INFO] [stdout] test strategy::timezone::tests::local_never_panics_and_falls_back_to_utc_when_the_offset_is_unknown ... ok
[INFO] [stdout] test strategy::timezone::tests::utc_reports_a_zero_offset ... ok
[INFO] [stdout] test strategy::rotation::tests::the_variant_names_are_the_config_file_surface ... ok
[INFO] [stdout] test rotation::policy::tests::startup_trims_an_over_full_directory_down_to_the_budget ... ok
[INFO] [stdout] test rotation::rotating_file::tests::the_active_file_is_the_name_with_a_log_extension ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 50 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.05s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 4 tests
[INFO] [stdout] test verbose::a_target_override_admits_its_own_module ... ok
[INFO] [stderr]      Running tests/gate.rs (/opt/rustwide/target/release/build/curia/292bb062e17ea4c0/out/gate-292bb062e17ea4c0)
[INFO] [stdout] test an_admitted_level_is_evaluated_once_and_delivered ... ok
[INFO] [stdout] test a_level_past_every_threshold_never_evaluates_its_arguments ... ok
[INFO] [stdout] test a_level_refused_for_this_target_never_evaluates_its_arguments ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 4 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/lib.rs (/opt/rustwide/target/release/build/curia/9f44cf0d4572f6a7/out/lib-9f44cf0d4572f6a7)
[INFO] [stdout] 
[INFO] [stdout] running 59 tests
[INFO] [stdout] test bridge::a_log_origin_target_is_restored_from_its_field ... ok
[INFO] [stdout] test bridge::inner_span_fields_win_over_outer_ones ... ok
[INFO] [stdout] test bridge::a_refused_span_is_never_recorded ... ok
[INFO] [stdout] test bridge::a_sink_below_the_event_level_is_not_called ... ok
[INFO] [stdout] test bridge::a_target_level_override_suppresses_a_verbose_crate ... ok
[INFO] [stdout] test bridge::a_log_origin_event_is_judged_by_its_restored_target ... ok
[INFO] [stdout] test bridge::a_refused_event_is_never_built ... ok
[INFO] [stdout] test bridge::event_fields_win_over_span_fields ... ok
[INFO] [stdout] test bridge::an_enormous_debug_field_is_truncated_rather_than_flooding_the_log ... ok
[INFO] [stdout] test bridge::span_fields_are_inherited_by_events ... ok
[INFO] [stdout] test dispatcher::emit_before_install_is_a_no_op ... ok
[INFO] [stdout] test dispatcher::a_dispatcher_without_sinks_admits_nothing ... ok
[INFO] [stdout] test dispatcher::enabled_refuses_a_level_no_sink_accepts ... ok
[INFO] [stdout] test bridge::the_level_hint_is_the_widest_threshold ... ok
[INFO] [stdout] test bridge::the_max_level_floors_everything_without_an_override ... ok
[INFO] [stdout] test dispatcher::severe_events_reach_a_less_verbose_sink ... ok
[INFO] [stdout] test dispatcher::sink_below_event_level_is_not_called ... ok
[INFO] [stdout] test bridge::tracing_levels_map_onto_our_levels ... ok
[INFO] [stdout] test dispatcher::enabled_honours_a_per_target_override ... ok
[INFO] [stdout] test dispatcher::a_panicking_sink_does_not_stop_the_others ... ok
[INFO] [stdout] test dispatcher::the_max_threshold_is_capped_by_the_filter_when_the_sinks_are_more_verbose ... ok
[INFO] [stdout] test dispatcher::the_max_threshold_is_capped_by_the_most_verbose_sink ... ok
[INFO] [stdout] test filter::a_bare_directive_sets_the_global_threshold ... ok
[INFO] [stdout] test filter::a_longer_prefix_wins_over_a_shorter_one ... ok
[INFO] [stdout] test filter::the_default_filter_admits_everything ... ok
[INFO] [stdout] test filter::the_widest_threshold_is_a_verbose_target_rather_than_a_quieter_global ... ok
[INFO] [stdout] test filter::a_directive_string_with_no_global_defaults_to_info ... ok
[INFO] [stdout] test filter::the_widest_threshold_is_the_global_one_when_every_target_is_quieter ... ok
[INFO] [stdout] test filter::widest_and_narrowest_order_off_below_every_level ... ok
[INFO] [stdout] test macros::a_brace_block_still_reaches_the_fields_arm ... ok
[INFO] [stdout] test macros::a_format_call_can_still_carry_fields_through_the_block_arm ... ok
[INFO] [stdout] test macros::a_format_string_with_one_argument_becomes_the_message ... ok
[INFO] [stdout] test macros::a_preformatted_message_needs_no_trailing_block ... ok
[INFO] [stdout] test filter::off_admits_nothing_for_a_matching_target ... ok
[INFO] [stdout] test macros::call_site_metadata_is_recorded ... ok
[INFO] [stdout] test macros::inline_block_form_accepts_string_keys_and_nested_values ... ok
[INFO] [stdout] test macros::inline_block_form_captures_typed_fields ... ok
[INFO] [stdout] test macros::message_only_form_carries_no_fields ... ok
[INFO] [stdout] test filter::a_threshold_of_off_admits_no_level_at_all ... ok
[INFO] [stdout] test rotation::a_single_write_larger_than_the_limit_lands_whole_in_an_empty_file ... ok
[INFO] [stdout] test filter::an_unparseable_directive_is_skipped_and_the_rest_still_apply ... ok
[INFO] [stdout] test rotation::appending_reopens_the_same_file_and_keeps_what_was_there ... ok
[INFO] [stdout] test macros::a_format_string_with_several_arguments_becomes_the_message ... ok
[INFO] [stdout] test dispatcher::unserializable_values_are_recorded_rather_than_dropped ... ok
[INFO] [stdout] test rotation::files_that_are_not_ours_are_never_deleted ... ok
[INFO] [stdout] test rotation::a_rotation_never_renames_over_an_existing_archive ... ok
[INFO] [stdout] test rotation::opening_with_rotate_on_an_empty_file_archives_nothing ... ok
[INFO] [stdout] test rotation::keep_one_leaves_only_the_active_file ... ok
[INFO] [stdout] test rotation::keep_all_accumulates_one_archive_for_every_rotation ... ok
[INFO] [stdout] test rotation::keep_some_leaves_exactly_the_budgeted_archives_beside_the_active_file ... ok
[INFO] [stdout] test rotation::opening_with_rotate_archives_the_previous_session ... ok
[INFO] [stdout] test sink::file::the_formatter_output_is_what_lands_on_disk ... ok
[INFO] [stdout] test rotation::keep_some_zero_behaves_the_same_way_as_keep_one ... ok
[INFO] [stdout] test rotation::nothing_written_across_a_rotation_boundary_is_lost_or_duplicated ... ok
[INFO] [stdout] test rotation::startup_leaves_archives_alone_under_a_strategy_that_creates_none ... ok
[INFO] [stdout] test rotation::startup_prunes_archives_a_previous_configuration_left_behind ... ok
[INFO] [stdout] test rotation::opening_with_rotate_on_an_absent_file_archives_nothing ... ok
[INFO] [stdout] test sink::file::a_burst_past_the_queue_bound_never_blocks_and_never_loses_silently ... ok
[INFO] [stdout] test sink::file::the_sink_reports_its_registered_level ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 59 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.02s
[INFO] [stdout] 
[INFO] [stderr]    Doc-tests curia
[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] running `Command { std: "docker" "inspect" "fe93d7ea36b4ca9a9f2c940b2387585b0bf0dab9bc46d27fe441e8a6bae9508b", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "fe93d7ea36b4ca9a9f2c940b2387585b0bf0dab9bc46d27fe441e8a6bae9508b", kill_on_drop: false }`
[INFO] [stdout] fe93d7ea36b4ca9a9f2c940b2387585b0bf0dab9bc46d27fe441e8a6bae9508b
