[INFO] fetching crate tiny-counter 0.1.0...
[INFO] testing tiny-counter-0.1.0 against 1.94.0 for beta-1.95-1
[INFO] extracting crate tiny-counter 0.1.0 into /workspace/builds/worker-3-tc1/source
[INFO] started tweaking crates.io crate tiny-counter 0.1.0
[INFO] removed 0 missing examples
[INFO] removed 0 missing tests
[INFO] finished tweaking crates.io crate tiny-counter 0.1.0
[INFO] tweaked toml for crates.io crate tiny-counter 0.1.0 written to /workspace/builds/worker-3-tc1/source/Cargo.toml
[INFO] validating manifest of crates.io crate tiny-counter 0.1.0 on toolchain 1.94.0
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+1.94.0" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate crates.io crate tiny-counter 0.1.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" "+1.94.0" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr]  Downloading crates ...
[INFO] [stderr]   Downloaded zmij v1.0.7
[INFO] [stderr]   Downloaded proptest v1.9.0
[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" "+1.94.0" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] 3f1cee52891d201378636c11f193c3d9c1ac5676e991bba59aaffe47c4f0b8ae
[INFO] running `Command { std: "docker" "start" "-a" "3f1cee52891d201378636c11f193c3d9c1ac5676e991bba59aaffe47c4f0b8ae", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "3f1cee52891d201378636c11f193c3d9c1ac5676e991bba59aaffe47c4f0b8ae", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "3f1cee52891d201378636c11f193c3d9c1ac5676e991bba59aaffe47c4f0b8ae", kill_on_drop: false }`
[INFO] [stdout] 3f1cee52891d201378636c11f193c3d9c1ac5676e991bba59aaffe47c4f0b8ae
[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=warn" "-e" "RUSTDOCFLAGS=--cap-lints=warn" "-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" "+1.94.0" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 8b797ec2e37ba5d97b4e3564f2d21e3cc7a979d0bcde1dfca986b7895a319d03
[INFO] running `Command { std: "docker" "start" "-a" "8b797ec2e37ba5d97b4e3564f2d21e3cc7a979d0bcde1dfca986b7895a319d03", kill_on_drop: false }`
[INFO] [stderr]    Compiling proc-macro2 v1.0.104
[INFO] [stderr]    Compiling parking_lot_core v0.9.12
[INFO] [stderr]    Compiling dashmap v6.1.0
[INFO] [stderr]    Compiling quote v1.0.42
[INFO] [stderr]    Compiling syn v2.0.112
[INFO] [stderr]    Compiling serde_derive v1.0.228
[INFO] [stderr]    Compiling thiserror-impl v1.0.69
[INFO] [stderr]    Compiling thiserror v1.0.69
[INFO] [stderr]    Compiling serde v1.0.228
[INFO] [stderr]    Compiling bincode v1.3.3
[INFO] [stderr]    Compiling chrono v0.4.42
[INFO] [stderr]    Compiling tiny-counter v0.1.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 12.48s
[INFO] running `Command { std: "docker" "inspect" "8b797ec2e37ba5d97b4e3564f2d21e3cc7a979d0bcde1dfca986b7895a319d03", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "8b797ec2e37ba5d97b4e3564f2d21e3cc7a979d0bcde1dfca986b7895a319d03", kill_on_drop: false }`
[INFO] [stdout] 8b797ec2e37ba5d97b4e3564f2d21e3cc7a979d0bcde1dfca986b7895a319d03
[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=warn" "-e" "RUSTDOCFLAGS=--cap-lints=warn" "-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" "+1.94.0" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] d14b0e2f22d3e2031a64c286bda6a4cb506093841b5ae416ff9d9b32260abebb
[INFO] running `Command { std: "docker" "start" "-a" "d14b0e2f22d3e2031a64c286bda6a4cb506093841b5ae416ff9d9b32260abebb", kill_on_drop: false }`
[INFO] [stderr]    Compiling syn v2.0.112
[INFO] [stderr]    Compiling zerocopy v0.8.31
[INFO] [stderr]    Compiling zmij v1.0.7
[INFO] [stderr]    Compiling serde_json v1.0.148
[INFO] [stderr]    Compiling regex-syntax v0.8.8
[INFO] [stderr]    Compiling getrandom v0.3.4
[INFO] [stderr]    Compiling ciborium-io v0.2.2
[INFO] [stderr]    Compiling clap_builder v4.5.53
[INFO] [stderr]    Compiling wait-timeout v0.2.1
[INFO] [stderr]    Compiling quick-error v1.2.3
[INFO] [stderr]    Compiling bit-vec v0.8.0
[INFO] [stderr]    Compiling criterion-plot v0.5.0
[INFO] [stderr]    Compiling is-terminal v0.4.17
[INFO] [stderr]    Compiling anes v0.1.6
[INFO] [stderr]    Compiling unarray v0.1.4
[INFO] [stderr]    Compiling rand_core v0.9.3
[INFO] [stderr]    Compiling tempfile v3.24.0
[INFO] [stderr]    Compiling bit-set v0.8.0
[INFO] [stderr]    Compiling rand v0.9.2
[INFO] [stderr]    Compiling rand_xorshift v0.4.0
[INFO] [stderr]    Compiling rusty-fork v0.3.1
[INFO] [stderr]    Compiling clap v4.5.53
[INFO] [stderr]    Compiling regex-automata v0.4.13
[INFO] [stderr]    Compiling zerocopy-derive v0.8.31
[INFO] [stderr]    Compiling serde_derive v1.0.228
[INFO] [stderr]    Compiling thiserror-impl v1.0.69
[INFO] [stderr]    Compiling tokio-macros v2.6.0
[INFO] [stderr]    Compiling tokio v1.48.0
[INFO] [stderr]    Compiling regex v1.12.2
[INFO] [stderr]    Compiling thiserror v1.0.69
[INFO] [stderr]    Compiling serde v1.0.228
[INFO] [stderr]    Compiling chrono v0.4.42
[INFO] [stderr]    Compiling bincode v1.3.3
[INFO] [stderr]    Compiling tinytemplate v1.2.1
[INFO] [stderr]    Compiling half v2.7.1
[INFO] [stderr]    Compiling ppv-lite86 v0.2.21
[INFO] [stderr]    Compiling ciborium-ll v0.2.2
[INFO] [stderr]    Compiling rand_chacha v0.9.0
[INFO] [stderr]    Compiling ciborium v0.2.2
[INFO] [stderr]    Compiling proptest v1.9.0
[INFO] [stderr]    Compiling tiny-counter v0.1.0 (/opt/rustwide/workdir)
[INFO] [stderr]    Compiling criterion v0.5.1
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 28.46s
[INFO] running `Command { std: "docker" "inspect" "d14b0e2f22d3e2031a64c286bda6a4cb506093841b5ae416ff9d9b32260abebb", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "d14b0e2f22d3e2031a64c286bda6a4cb506093841b5ae416ff9d9b32260abebb", kill_on_drop: false }`
[INFO] [stdout] d14b0e2f22d3e2031a64c286bda6a4cb506093841b5ae416ff9d9b32260abebb
[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=warn" "-e" "RUSTDOCFLAGS=--cap-lints=warn" "-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" "+1.94.0" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] e2e9b1fb20725285f0a5cbdb3c0925a796b912e2545715dc01a4499320a13600
[INFO] running `Command { std: "docker" "start" "-a" "e2e9b1fb20725285f0a5cbdb3c0925a796b912e2545715dc01a4499320a13600", kill_on_drop: false }`
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.15s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/tiny_counter-53afec472356d430)
[INFO] [stdout] 
[INFO] [stdout] running 419 tests
[INFO] [stdout] test clock::tests::test_system_clock_is_send_sync ... ok
[INFO] [stdout] test clock::tests::test_system_clock_returns_real_time ... ok
[INFO] [stdout] test clock::tests::test_arc_dyn_clock_pattern ... ok
[INFO] [stdout] test clock::tests::test_test_clock_advance ... ok
[INFO] [stdout] test clock::tests::test_system_clock_new ... ok
[INFO] [stdout] test clock::tests::test_test_clock_is_clone ... ok
[INFO] [stdout] test clock::tests::test_test_clock_new ... ok
[INFO] [stdout] test clock::tests::test_test_clock_new_at ... ok
[INFO] [stdout] test clock::tests::test_test_clock_set ... ok
[INFO] [stdout] test config_converter::tests::test_conversion_disjoint_bucket_intervals ... ok
[INFO] [stdout] test config_converter::tests::test_conversion_empty_counter ... ok
[INFO] [stdout] test config_converter::tests::test_conversion_handles_gaps_offset ... ok
[INFO] [stdout] test config_converter::tests::test_conversion_handles_gaps ... ok
[INFO] [stdout] test config_converter::tests::test_conversion_preserves_starting_instant ... ok
[INFO] [stdout] test config_converter::tests::test_conversion_overlapping_bucket_intervals ... ok
[INFO] [stdout] test count_ring::tests::test_complex_workflow ... ok
[INFO] [stdout] test config_converter::tests::test_conversion_handles_future_events_gracefully ... ok
[INFO] [stdout] test count_ring::tests::test_get_out_of_bounds ... ok
[INFO] [stdout] test count_ring::tests::test_increment_beyond_bucket_count ... ok
[INFO] [stdout] test config_converter::tests::test_conversion_adds_missing_time_units ... ok
[INFO] [stdout] test config_converter::tests::test_conversion_skips_events_too_old_for_target ... ok
[INFO] [stdout] test count_ring::tests::test_buckets_reference ... ok
[INFO] [stdout] test config_converter::tests::test_conversion_removes_extra_time_units ... ok
[INFO] [stdout] test count_ring::tests::test_increment_past_buckets ... ok
[INFO] [stdout] test count_ring::tests::test_invariant_bucket_count ... ok
[INFO] [stdout] test count_ring::tests::test_new_creates_ring_with_zero_buckets ... ok
[INFO] [stdout] test config_converter::tests::test_conversion_preserves_count_with_disjoint_intervals ... ok
[INFO] [stdout] test count_ring::tests::test_property_sum_range_additive ... ok
[INFO] [stdout] test count_ring::tests::test_rotate_at_bucket_count ... ok
[INFO] [stdout] test count_ring::tests::test_rotate_single ... ok
[INFO] [stdout] test count_ring::tests::test_increment_overflow_saturates ... ok
[INFO] [stdout] test count_ring::tests::test_increment_bucket_0 ... ok
[INFO] [stdout] test config_converter::tests::test_conversion_touching_bucket_intervals ... ok
[INFO] [stdout] test count_ring::tests::test_sum_range_out_of_bounds ... ok
[INFO] [stdout] test count_ring::tests::test_rotate_beyond_bucket_count ... ok
[INFO] [stdout] test count_ring::tests::test_sum_range_empty ... ok
[INFO] [stdout] test count_ring::tests::test_rotate_multiple ... ok
[INFO] [stdout] test count_ring::tests::test_sum_range_partial ... ok
[INFO] [stdout] test count_ring::tests::test_truncate_maintains_invariant ... ok
[INFO] [stdout] test count_ring::tests::test_increment_multiple_times ... ok
[INFO] [stdout] test count_ring::tests::test_sum_range_saturates_at_u32_max ... ok
[INFO] [stdout] test counter::config::tests::test_as_vec_clones_internal_vec ... ok
[INFO] [stdout] test counter::config::tests::test_from_intervals_empty_slice ... ok
[INFO] [stdout] test counter::config::tests::test_empty_creates_config_with_no_intervals ... ok
[INFO] [stdout] test count_ring::tests::test_sum_range_full ... ok
[INFO] [stdout] test counter::config::tests::test_partial_eq_default_configs ... ok
[INFO] [stdout] test counter::config::tests::test_partial_eq_different_bucket_counts ... ok
[INFO] [stdout] test counter::config::tests::test_partial_eq_different_lengths ... ok
[INFO] [stdout] test counter::config::tests::test_default_config_has_six_intervals ... ok
[INFO] [stdout] test counter::config::tests::test_empty_config_as_vec_is_empty ... ok
[INFO] [stdout] test counter::config::tests::test_partial_eq_different_time_units ... ok
[INFO] [stdout] test counter::config::tests::test_get_finds_by_time_unit ... ok
[INFO] [stdout] test counter::config::tests::test_new_stores_configs_in_vec ... ok
[INFO] [stdout] test counter::config::tests::test_from_intervals_creates_config_from_slice ... ok
[INFO] [stdout] test counter::config::tests::test_partial_eq_same_configs_same_order ... ok
[INFO] [stdout] test counter::config::tests::test_partial_eq_empty_configs ... ok
[INFO] [stdout] test counter::config::tests::test_time_units_iterator ... ok
[INFO] [stdout] test counter::tests::test_first_seen_returns_none_for_untracked_time_unit ... ok
[INFO] [stdout] test counter::tests::test_first_seen_finds_oldest_nonzero_bucket ... ok
[INFO] [stdout] test counter::tests::test_first_seen_with_current_bucket ... ok
[INFO] [stdout] test counter::config::tests::test_partial_eq_same_configs_different_order ... ok
[INFO] [stdout] test counter::tests::test_integration_record_once_query_at_different_granularities ... ok
[INFO] [stdout] test counter::tests::test_first_seen_with_single_event ... ok
[INFO] [stdout] test counter::tests::test_first_seen_returns_none_if_all_buckets_zero ... ok
[INFO] [stdout] test counter::tests::test_different_bucket_counts_per_interval ... ok
[INFO] [stdout] test counter::tests::test_last_seen_returns_none_if_all_buckets_zero ... ok
[INFO] [stdout] test counter::tests::test_last_seen_finds_first_nonzero_bucket ... ok
[INFO] [stdout] test counter::tests::test_merge_combines_counts ... ok
[INFO] [stdout] test counter::tests::test_first_last_seen_in_worked_example ... ok
[INFO] [stdout] test counter::tests::test_merge_converts_different_bucket_counts ... ok
[INFO] [stdout] test counter::tests::test_last_seen_returns_none_for_untracked_time_unit ... ok
[INFO] [stdout] test counter::tests::test_merge_is_associative ... ok
[INFO] [stdout] test counter::tests::test_merge_is_commutative ... ok
[INFO] [stdout] test counter::tests::test_merge_handles_alignment_when_starting_instant_differs ... ok
[INFO] [stdout] test counter::tests::test_merge_preserves_most_recent_starting_instant ... ok
[INFO] [stdout] test counter::tests::test_new_creates_counter_with_multiple_intervals ... ok
[INFO] [stdout] test counter::tests::test_merge_with_different_time_units_converts_config ... ok
[INFO] [stdout] test counter::tests::test_record_updates_all_intervals_simultaneously ... ok
[INFO] [stdout] test error::tests::test_config_mismatch_error_display ... ok
[INFO] [stdout] test error::tests::test_error_is_send_sync ... ok
[INFO] [stdout] test error::tests::test_future_event_error_display ... ok
[INFO] [stdout] test error::tests::test_event_not_found_error_display ... ok
[INFO] [stdout] test error::tests::test_invalid_time_unit_ever_error_display ... ok
[INFO] [stdout] test error::tests::test_invalid_auto_persist_interval_error_display ... ok
[INFO] [stdout] test error::tests::test_invalid_bucket_count_error_display ... ok
[INFO] [stdout] test error::tests::test_invalid_range_error_display ... ok
[INFO] [stdout] test error::tests::test_invalid_hour_error_display ... ok
[INFO] [stdout] test counter::tests::test_merge_with_subset_configs_converts ... ok
[INFO] [stdout] test error::tests::test_serialization_error_display ... ok
[INFO] [stdout] test counter::tests::test_merge_with_same_time_units_but_different_bucket_counts ... ok
[INFO] [stdout] test counter::config::tests::test_from_intervals_panics_on_duplicates - should panic ... ok
[INFO] [stdout] test counter::config::tests::test_new_panics_on_duplicate_time_units - should panic ... ok
[INFO] [stdout] test clock::tests::test_test_clock_advance_is_additive ... ok
[INFO] [stdout] test counter::tests::test_query_with_minutes_returns_some ... ok
[INFO] [stdout] test counter::tests::test_query_with_untracked_time_unit_returns_none ... ok
[INFO] [stdout] test counter::tests::test_merge_with_multiple_time_units_and_different_bucket_counts ... ok
[INFO] [stdout] test counter::tests::test_record_at_propagates_errors ... ok
[INFO] [stdout] test interval::tests::test_complex_workflow_with_time_advancement ... ok
[INFO] [stdout] test interval::tests::test_increment_adds_to_bucket_0 ... ok
[INFO] [stdout] test interval::config::tests::test_interval_config_debug ... ok
[INFO] [stdout] test interval::config::tests::test_interval_config_rejects_ever ... ok
[INFO] [stdout] test interval::tests::test_advance_if_needed_with_small_time_jump ... ok
[INFO] [stdout] test interval::tests::test_advance_if_needed_with_large_time_jump ... ok
[INFO] [stdout] test counter::tests::test_record_at_updates_all_intervals_simultaneously ... ok
[INFO] [stdout] test interval::tests::test_increment_at_with_past_event_1_hour_ago ... ok
[INFO] [stdout] test interval::tests::test_increment_at_with_past_event_2_days_ago ... ok
[INFO] [stdout] test interval::config::tests::test_interval_config_accessors ... ok
[INFO] [stdout] test interval::config::tests::test_interval_config_creation ... ok
[INFO] [stdout] test interval::config::tests::test_interval_config_is_clone ... ok
[INFO] [stdout] test interval::config::tests::test_interval_config_zero_buckets_rejected ... ok
[INFO] [stdout] test interval::tests::test_query_range_delegates_correctly ... ok
[INFO] [stdout] test storage::file_per_event::tests::test_atomic_write_overwrites_existing ... ok
[INFO] [stdout] test storage::file_per_event::tests::test_atomic_write_no_temp_files_left ... ok
[INFO] [stdout] test interval::tests::test_advance_if_needed_with_no_time_jump ... ok
[INFO] [stdout] test interval::tests::test_new_creates_counter_with_zero_buckets ... ok
[INFO] [stdout] test interval::tests::test_property_advance_idempotent ... ok
[INFO] [stdout] test formatter::bincode::tests::test_bincode_roundtrip ... ok
[INFO] [stdout] test interval::tests::test_property_increment_at_then_query ... ok
[INFO] [stdout] test interval::tests::test_increment_at_same_interval_before_starting_instant ... ok
[INFO] [stdout] test error::tests::test_storage_error_display ... ok
[INFO] [stdout] test formatter::bincode::tests::test_bincode_extension ... ok
[INFO] [stdout] test interval::config::tests::test_interval_config_ever_error_message ... ok
[INFO] [stdout] test interval::tests::test_property_starting_instant_monotonic ... ok
[INFO] [stdout] test storage::file_per_event::tests::test_encode_key_with_colon ... ok
[INFO] [stdout] test storage::file_per_event::tests::test_delete_removes_file ... ok
[INFO] [stdout] test clock::tests::test_test_clock_concurrent_access ... ok
[INFO] [stdout] test storage::file_per_event::tests::test_encode_decode_roundtrip ... ok
[INFO] [stdout] test storage::file_per_event::tests::test_legitimate_key_simple ... ok
[INFO] [stdout] test storage::file_per_event::tests::test_delete_with_invalid_key ... ok
[INFO] [stdout] test storage::file_per_event::tests::test_encode_key_with_slash ... ok
[INFO] [stdout] test storage::file_per_event::tests::test_encode_simple_key ... ok
[INFO] [stdout] test storage::file_per_event::tests::test_encode_unicode_roundtrip ... ok
[INFO] [stdout] test storage::file_per_event::tests::test_keys_with_special_characters ... ok
[INFO] [stdout] test storage::file_per_event::tests::test_extension_in_filename ... ok
[INFO] [stdout] test storage::file_per_event::tests::test_key_with_backslash ... ok
[INFO] [stdout] test storage::file_per_event::tests::test_legitimate_key_with_special_chars ... ok
[INFO] [stdout] test storage::file_per_event::tests::test_load_nonexistent_returns_none ... ok
[INFO] [stdout] test storage::file_per_event::tests::test_legitimate_key_with_spaces ... ok
[INFO] [stdout] test storage::file_per_event::tests::test_new_creates_directory_if_missing ... ok
[INFO] [stdout] test storage::file_per_event::tests::test_path_traversal_current_directory ... ok
[INFO] [stdout] test storage::file_per_event::tests::test_legitimate_key_with_colon ... ok
[INFO] [stdout] test storage::memory::tests::test_default_trait ... ok
[INFO] [stdout] test storage::file_per_event::tests::test_list_keys_returns_all_keys ... ok
[INFO] [stdout] test storage::memory::tests::test_delete_removes_data ... ok
[INFO] [stdout] test storage::memory::tests::test_load_nonexistent_key_returns_none ... ok
[INFO] [stdout] test storage::memory::tests::test_save_overwrites_existing_key ... ok
[INFO] [stdout] test storage::memory::tests::test_save_then_load_returns_same_data ... ok
[INFO] [stdout] test storage::file_per_event::tests::test_key_with_forward_slash ... ok
[INFO] [stdout] test store::builder::tests::test_build_default ... ok
[INFO] [stdout] test store::builder::tests::test_build_with_all_options ... ok
[INFO] [stdout] test store::builder::tests::test_build_with_analytics_preset ... ok
[INFO] [stdout] test storage::file_per_event::tests::test_path_traversal_absolute_path ... ok
[INFO] [stdout] test store::builder::tests::test_build_with_custom_config ... ok
[INFO] [stdout] test storage::file_per_event::tests::test_path_traversal_parent_reference ... ok
[INFO] [stdout] test storage::file_per_event::tests::test_path_traversal_embedded_slash ... ok
[INFO] [stdout] test store::builder::tests::test_builder_fluent_api ... ok
[INFO] [stdout] test store::builder::tests::test_build_with_rate_limiting_preset ... ok
[INFO] [stdout] test store::builder::tests::test_custom_config_after_preset ... ok
[INFO] [stdout] test storage::file_per_event::tests::test_path_traversal_relative_parent ... ok
[INFO] [stdout] test store::builder::tests::test_build_with_clock ... ok
[INFO] [stdout] test storage::file_per_event::tests::test_overwrite_existing_key ... ok
[INFO] [stdout] test store::builder::tests::test_build_with_storage ... ok
[INFO] [stdout] test storage::file_per_event::tests::test_path_traversal_url_encoded_backslash ... ok
[INFO] [stdout] test store::builder::tests::test_for_analytics_preset ... ok
[INFO] [stdout] test store::builder::tests::test_default_builder ... ok
[INFO] [stdout] test storage::file_per_event::tests::test_path_traversal_windows_style ... ok
[INFO] [stdout] test store::builder::tests::test_for_rate_limiting_preset ... ok
[INFO] [stdout] test store::builder::tests::test_new_builder ... ok
[INFO] [stdout] test storage::file_per_event::tests::test_persistence_across_instances ... ok
[INFO] [stdout] test storage::file_per_event::tests::test_path_traversal_url_encoded_slash ... ok
[INFO] [stdout] test storage::file_per_event::tests::test_save_and_load_roundtrip ... ok
[INFO] [stdout] test store::builder::tests::test_preset_overwrites_previous_config ... ok
[INFO] [stdout] test store::builder::tests::test_track_days ... ok
[INFO] [stdout] test store::builder::tests::test_track_days_rejects_too_large ... ok
[INFO] [stdout] test store::builder::tests::test_track_hours ... ok
[INFO] [stdout] test store::builder::tests::test_track_hours_accepts_valid_values ... ok
[INFO] [stdout] test store::builder::tests::test_track_hours_rejects_too_large ... ok
[INFO] [stdout] test store::builder::tests::test_default_formatter_is_bincode ... ok
[INFO] [stdout] test store::builder::tests::test_event_store_builder_method ... ok
[INFO] [stdout] test store::builder::tests::test_track_minutes_rejects_zero ... ok
[INFO] [stdout] test store::builder::tests::test_track_months ... ok
[INFO] [stdout] test store::builder::tests::test_track_weeks ... ok
[INFO] [stdout] test store::builder::tests::test_track_weeks_rejects_too_large ... ok
[INFO] [stdout] test store::builder::tests::test_track_weeks_rejects_zero ... ok
[INFO] [stdout] test store::builder::tests::test_track_days_rejects_zero ... ok
[INFO] [stdout] test store::builder::tests::test_track_hours_rejects_zero ... ok
[INFO] [stdout] test store::builder::tests::test_track_minutes ... ok
[INFO] [stdout] test store::builder::tests::test_track_months_rejects_zero ... ok
[INFO] [stdout] test store::builder::tests::test_track_years ... ok
[INFO] [stdout] test store::builder::tests::test_formatter_used_in_persistence ... ok
[INFO] [stdout] test store::builder::tests::test_track_minutes_accepts_valid_values ... ok
[INFO] [stdout] test store::builder::tests::test_track_minutes_rejects_too_large ... ok
[INFO] [stdout] test store::builder::tests::test_track_years_rejects_zero ... ok
[INFO] [stdout] test store::builder::tests::test_with_clock ... ok
[INFO] [stdout] test store::builder::tests::test_with_format_bincode ... ok
[INFO] [stdout] test storage::file_per_event::tests::test_load_with_invalid_key ... ok
[INFO] [stdout] test store::builder::tests::test_with_storage ... ok
[INFO] [stdout] test interval::tests::test_increment_at_same_interval_after_starting_instant ... ok
[INFO] [stdout] test interval::tests::test_increment_at_with_future_event_returns_error ... ok
[INFO] [stdout] test store::limiter::tests::test_allowed_convenience_method ... ok
[INFO] [stdout] test store::builder::tests::test_track_years_rejects_too_large ... ok
[INFO] [stdout] test store::limiter::tests::test_at_least_constraint_fails ... ok
[INFO] [stdout] test store::limiter::tests::test_at_least_with_flexible_windows ... ok
[INFO] [stdout] test store::limiter::tests::test_at_most_constraint_fails ... ok
[INFO] [stdout] test store::limiter::tests::test_at_most_constraint_passes ... ok
[INFO] [stdout] test store::limiter::tests::test_at_least_constraint_passes ... ok
[INFO] [stdout] test store::limiter::tests::test_check_and_record_failure_does_not_record ... ok
[INFO] [stdout] test store::limiter::tests::test_check_and_record_success ... ok
[INFO] [stdout] test store::limiter::tests::test_business_hours_schedule_during_hours ... ok
[INFO] [stdout] test store::limiter::tests::test_custom_schedule ... ok
[INFO] [stdout] test store::limiter::tests::test_duration_conversion_to_time_window ... ok
[INFO] [stdout] test store::limiter::tests::test_cooldown_constraint_fails ... ok
[INFO] [stdout] test store::limiter::tests::test_flexible_time_windows_30_minutes ... ok
[INFO] [stdout] test store::limiter::tests::test_cooldown_constraint_passes ... ok
[INFO] [stdout] test store::limiter::tests::test_flexible_time_windows_backward_compatible ... ok
[INFO] [stdout] test store::limiter::tests::test_flexible_time_windows_with_tuple ... ok
[INFO] [stdout] test store::concurrency_tests::test_persist_while_recording ... ok
[INFO] [stdout] test store::limiter::tests::test_multiple_constraints_all_pass ... ok
[INFO] [stdout] test store::concurrency_tests::test_concurrent_query_many_while_recording ... ok
[INFO] [stdout] test store::limiter::tests::test_multiple_constraints_first_fails ... ok
[INFO] [stdout] test store::limiter::tests::test_reservation_auto_cancel_on_drop ... ok
[INFO] [stdout] test store::concurrency_tests::test_concurrent_query_many_from_multiple_threads ... ok
[INFO] [stdout] test store::limiter::tests::test_reservation_commit_records_event ... ok
[INFO] [stdout] test store::builder::tests::test_track_months_rejects_too_large ... ok
[INFO] [stdout] test store::limiter::tests::test_reservation_explicit_cancel ... ok
[INFO] [stdout] test store::limiter::tests::test_retry_after_calculation_for_at_most ... ok
[INFO] [stdout] test store::limiter::tests::test_reservation_transactional_pattern ... ok
[INFO] [stdout] test store::limiter::tests::test_reservation_with_multiple_constraints ... ok
[INFO] [stdout] test store::limiter::tests::test_schedule_hours_accepts_valid_boundary_cases ... ok
[INFO] [stdout] test store::concurrency_tests::test_concurrent_record_from_multiple_threads ... ok
[INFO] [stdout] test store::limiter::tests::test_reservation_prevents_race_condition ... ok
[INFO] [stdout] test store::concurrency_tests::test_concurrent_query_ratio_from_multiple_threads ... ok
[INFO] [stdout] test store::concurrency_tests::test_concurrent_query_delta_from_multiple_threads ... ok
[INFO] [stdout] test store::concurrency_tests::test_concurrent_query_delta_while_recording ... ok
[INFO] [stdout] test store::limiter::tests::test_schedule_hours_validates_start_hour ... ok
[INFO] [stdout] test store::limiter::tests::test_within_constraint_fails_never_seen ... ok
[INFO] [stdout] test store::limiter::tests::test_weekdays_schedule ... ok
[INFO] [stdout] test store::concurrency_tests::test_query_while_recording ... ok
[INFO] [stdout] test store::concurrency_tests::test_concurrent_query_ratio_while_recording ... ok
[INFO] [stdout] test store::limiter::tests::test_schedule_hours_rejects_equal_hours ... ok
[INFO] [stdout] test store::limiter::tests::test_within_constraint_fails_too_long_ago ... ok
[INFO] [stdout] test store::query::tests::test_average_nonzero ... ok
[INFO] [stdout] test store::limiter::tests::test_within_constraint_passes ... ok
[INFO] [stdout] test store::query::tests::test_count_nonzero ... ok
[INFO] [stdout] test store::query::tests::test_average_nonzero_handles_large_values ... ok
[INFO] [stdout] test store::query::tests::test_delta_query_ever ... ok
[INFO] [stdout] test store::limiter::tests::test_reservation_concurrent_limits ... ok
[INFO] [stdout] test store::query::tests::test_delta_query_negative ... ok
[INFO] [stdout] test store::query::tests::test_ever_into_buckets_does_not_hang ... ok
[INFO] [stdout] test store::query::tests::test_ever_uses_longest_time_unit ... ok
[INFO] [stdout] test store::query::tests::test_delta_query_positive ... ok
[INFO] [stdout] test store::query::tests::test_first_seen_consistency_with_last_seen ... ok
[INFO] [stdout] test store::query::tests::test_delta_query_zero ... ok
[INFO] [stdout] test store::query::tests::test_first_seen_returns_oldest_event_disjoint_muilti_buckets_under ... ok
[INFO] [stdout] test store::query::tests::test_ever_uses_time_unit_ever_variant ... ok
[INFO] [stdout] test store::query::tests::test_first_seen_returns_oldest_event_disjoint_buckets ... ok
[INFO] [stdout] test store::query::tests::test_ever_with_nonexistent_event ... ok
[INFO] [stdout] test store::query::tests::test_first_seen_returns_oldest_event_disjoint_muilti_buckets_over ... ok
[INFO] [stdout] test store::query::tests::test_first_seen_in_specific_time_unit ... ok
[INFO] [stdout] test store::query::tests::test_first_seen_with_single_event_disjoint_intervals ... ok
[INFO] [stdout] test store::query::tests::test_first_seen_returns_oldest_event_overlapping_buckets ... ok
[INFO] [stdout] test store::query::tests::test_last_seen_gap_event_disjoint_intervals ... ok
[INFO] [stdout] test store::query::tests::test_first_seen_with_no_events ... ok
[INFO] [stdout] test store::query::tests::test_last_seen_recent_event_in_smallest_bucket ... ok
[INFO] [stdout] test store::query::tests::test_last_seen_returns_recent_event_touching_intervals ... ok
[INFO] [stdout] test store::query::tests::test_first_seen_returns_oldest_event_touching_buckets ... ok
[INFO] [stdout] test store::query::tests::test_last_seen_returns_recent_event_overlapping_intervals ... ok
[INFO] [stdout] test store::query::tests::test_last_seen_with_bucket_midway_ago ... ok
[INFO] [stdout] test store::query::tests::test_last_seen_event_in_gap_multiple_intervals ... ok
[INFO] [stdout] test store::query::tests::test_first_seen_with_single_event_touching_intervals ... ok
[INFO] [stdout] test store::query::tests::test_multi_query_sum ... ok
[INFO] [stdout] test store::query::tests::test_query_last_days_sum ... ok
[INFO] [stdout] test store::query::tests::test_query_nonexistent_event ... ok
[INFO] [stdout] test store::query::tests::test_range_query_take ... ok
[INFO] [stdout] test store::query::tests::test_range_query_first_seen ... ok
[INFO] [stdout] test store::query::tests::test_ratio_query_ever ... ok
[INFO] [stdout] test store::tests::test_balance_delta_zero ... ok
[INFO] [stdout] test store::query::tests::test_multi_range_query_handles_large_values ... ok
[INFO] [stdout] test store::query::tests::test_range_query_average ... ok
[INFO] [stdout] test store::query::tests::test_ratio_query ... ok
[INFO] [stdout] test store::query::tests::test_ratio_query_division_by_zero ... ok
[INFO] [stdout] test store::tests::test_balance_delta_negative ... ok
[INFO] [stdout] test store::tests::test_balance_delta_positive ... ok
[INFO] [stdout] test store::tests::test_default_trait ... ok
[INFO] [stdout] test store::tests::test_compact_without_storage_returns_error ... ok
[INFO] [stdout] test store::tests::test_dirty_tracking_becomes_dirty_after_record ... ok
[INFO] [stdout] test store::tests::test_default_config_includes_all_six_time_units ... ok
[INFO] [stdout] test store::tests::test_close_persists_and_consumes ... ok
[INFO] [stdout] test store::tests::test_close_returns_error_on_persist_failure ... ok
[INFO] [stdout] test store::tests::test_compact_advances_and_saves ... ok
[INFO] [stdout] test store::tests::test_export_dirty_returns_empty_map_when_nothing_dirty ... ok
[INFO] [stdout] test store::tests::test_import_event_creates_new_counter ... ok
[INFO] [stdout] test store::tests::test_export_dirty_returns_only_dirty_counters ... ok
[INFO] [stdout] test store::tests::test_integration_record_multiple_events_query_each ... ok
[INFO] [stdout] test store::tests::test_import_event_overwrites_existing ... ok
[INFO] [stdout] test store::tests::test_memory_usage_empty_store ... ok
[INFO] [stdout] test store::tests::test_memory_usage_calculation ... ok
[INFO] [stdout] test store::tests::test_memory_usage_with_events ... ok
[INFO] [stdout] test store::tests::test_merge_all_combines_multiple_events ... ok
[INFO] [stdout] test store::tests::test_merge_event_combines_counts ... ok
[INFO] [stdout] test store::tests::test_merge_event_creates_counter_if_not_exists ... ok
[INFO] [stdout] test store::query::tests::test_multi_query_ever ... ok
[INFO] [stdout] test store::tests::test_import_all_batch_imports ... ok
[INFO] [stdout] test store::tests::test_merge_is_associative_at_store_level ... ok
[INFO] [stdout] test store::tests::test_new_creates_empty_store_with_default_intervals ... ok
[INFO] [stdout] test store::tests::test_merge_is_commutative_at_store_level ... ok
[INFO] [stdout] test store::tests::test_persist_all ... ok
[INFO] [stdout] test store::tests::test_multiple_records_to_same_event ... ok
[INFO] [stdout] test store::tests::test_persist_only_dirty_events ... ok
[INFO] [stdout] test store::tests::test_persist_clears_dirty_events ... ok
[INFO] [stdout] test store::tests::test_persist_all_without_storage_returns_error ... ok
[INFO] [stdout] test store::tests::test_persist_with_storage ... ok
[INFO] [stdout] test store::tests::test_persist_without_storage_returns_error ... ok
[INFO] [stdout] test store::tests::test_query_many ... ok
[INFO] [stdout] test store::tests::test_query_delta ... ok
[INFO] [stdout] test store::tests::test_query_nonexistent_event_returns_none ... ok
[INFO] [stdout] test store::tests::test_query_returns_query_builder ... ok
[INFO] [stdout] test store::tests::test_query_ratio ... ok
[INFO] [stdout] test store::tests::test_query_returns_none_for_nonexistent_event ... ok
[INFO] [stdout] test store::tests::test_record_ago_outside_tracking_window_silently_drops ... ok
[INFO] [stdout] test store::tests::test_record_at_with_past_time ... ok
[INFO] [stdout] test store::tests::test_record_at_with_time_before_creation ... ok
[INFO] [stdout] test store::tests::test_record_count_with_count_5 ... ok
[INFO] [stdout] test store::tests::test_record_count_ago_outside_tracking_window_silently_drops ... ok
[INFO] [stdout] test store::tests::test_record_creates_counter_with_default_config ... ok
[INFO] [stdout] test store::tests::test_reset_dirty ... ok
[INFO] [stdout] test store::tests::test_serialization_roundtrip ... ok
[INFO] [stdout] test store::tests::test_record_creates_counter_on_demand ... ok
[INFO] [stdout] test sync_time::tests::test_synchronized_start_is_idempotent ... ok
[INFO] [stdout] test sync_time::tests::test_synchronized_start_is_in_past_or_now ... ok
[INFO] [stdout] test sync_time::tests::test_synchronized_start_returns_current_year ... ok
[INFO] [stdout] test sync_time::tests::test_synchronized_start_returns_jan_1 ... ok
[INFO] [stdout] test sync_time::tests::test_synchronized_start_returns_midnight ... ok
[INFO] [stdout] test time_unit::tests::test_bucket_end_days_calendar ... ok
[INFO] [stdout] test time_unit::tests::test_bucket_end_months_calendar ... ok
[INFO] [stdout] test time_unit::tests::test_bucket_end_weeks_calendar ... ok
[INFO] [stdout] test time_unit::tests::test_bucket_end_years_calendar ... ok
[INFO] [stdout] test time_unit::tests::test_bucket_midway_days_calendar ... ok
[INFO] [stdout] test time_unit::tests::test_bucket_midway_months_calendar ... ok
[INFO] [stdout] test time_unit::tests::test_bucket_midway_weeks_calendar ... ok
[INFO] [stdout] test time_unit::tests::test_bucket_midway_years_calendar ... ok
[INFO] [stdout] test time_unit::tests::test_bucket_start_calendar ... ok
[INFO] [stdout] test time_unit::tests::test_bucket_start_months_calendar ... ok
[INFO] [stdout] test time_unit::tests::test_bucket_time_days_calendar ... ok
[INFO] [stdout] test time_unit::tests::test_duration_days ... ok
[INFO] [stdout] test store::tests::test_dirty_tracking_starts_clean ... ok
[INFO] [stdout] test time_unit::tests::test_duration_minutes ... ok
[INFO] [stdout] test store::tests::test_compact_while_recording ... ok
[INFO] [stdout] test time_unit::tests::test_duration_weeks ... ok
[INFO] [stdout] test time_unit::tests::test_duration_hours ... ok
[INFO] [stdout] test time_unit::tests::test_duration_months ... ok
[INFO] [stdout] test time_unit::tests::test_duration_years ... ok
[INFO] [stdout] test time_unit::tests::test_first_moment_ever_days_calendar ... ok
[INFO] [stdout] test time_unit::tests::test_first_moment_ever_months_calendar ... ok
[INFO] [stdout] test time_unit::tests::test_first_moment_ever_weeks_calendar ... ok
[INFO] [stdout] test time_unit::tests::test_first_moment_ever_years_calendar ... ok
[INFO] [stdout] test store::limiter::tests::test_cooldown_never_seen_passes ... ok
[INFO] [stdout] test time_unit::tests::test_month_bucket_end_crossing_year_backward ... ok
[INFO] [stdout] test time_unit::tests::test_num_rotations_days_calendar ... ok
[INFO] [stdout] test time_unit::tests::test_num_rotations_days_crossing_year ... ok
[INFO] [stdout] test time_unit::tests::test_large_time_jump ... ok
[INFO] [stdout] test time_unit::tests::test_num_rotations_future_time ... ok
[INFO] [stdout] test time_unit::tests::test_num_rotations_minutes ... ok
[INFO] [stdout] test time_unit::tests::test_num_rotations_months_calendar ... ok
[INFO] [stdout] test time_unit::tests::test_num_rotations_months_calendar_year_boundary ... ok
[INFO] [stdout] test time_unit::tests::test_num_rotations_months_calendar_multi_year_span ... ok
[INFO] [stdout] test time_unit::tests::test_num_rotations_months_crossing_year ... ok
[INFO] [stdout] test time_unit::tests::test_num_rotations_partial_unit ... ok
[INFO] [stdout] test time_unit::tests::test_num_rotations_seconds ... ok
[INFO] [stdout] test time_unit::tests::test_num_rotations_same_time ... ok
[INFO] [stdout] test time_unit::tests::test_num_rotations_transitive ... ok
[INFO] [stdout] test time_unit::tests::test_num_rotations_past_time ... ok
[INFO] [stdout] test time_unit::tests::test_num_rotations_weeks ... ok
[INFO] [stdout] test time_unit::tests::test_num_rotations_weeks_calendar_year_boundary ... ok
[INFO] [stdout] test time_unit::tests::test_num_rotations_years ... ok
[INFO] [stdout] test time_unit::tests::test_num_rotations_years_multiple ... ok
[INFO] [stdout] test time_unit::tests::test_rotate_start_interval_days_calendar ... ok
[INFO] [stdout] test time_unit::tests::test_rotate_start_interval_months_calendar ... ok
[INFO] [stdout] test time_unit::tests::test_rotate_start_interval_weeks_calendar ... ok
[INFO] [stdout] test time_unit::tests::test_rotate_start_interval_years_calendar ... ok
[INFO] [stdout] test time_unit::tests::test_time_unit_ever_duration_panics - should panic ... ok
[INFO] [stdout] test time_unit::tests::test_time_unit_ever_is_largest ... ok
[INFO] [stdout] test time_unit::tests::test_time_unit_is_copy ... ok
[INFO] [stdout] test time_unit::tests::test_time_unit_is_eq ... ok
[INFO] [stdout] test time_unit::tests::test_time_unit_is_hash ... ok
[INFO] [stdout] test time_unit::tests::test_time_unit_ord_reflexive ... ok
[INFO] [stdout] test time_unit::tests::test_time_unit_ord_transitive ... ok
[INFO] [stdout] test time_unit::tests::test_time_window_from_duration_days ... ok
[INFO] [stdout] test time_unit::tests::test_time_window_from_duration_hours ... ok
[INFO] [stdout] test time_unit::tests::test_time_window_from_duration_minutes ... ok
[INFO] [stdout] test time_unit::tests::test_time_window_from_duration_seconds ... ok
[INFO] [stdout] test time_unit::tests::test_time_window_from_negative_duration_days ... ok
[INFO] [stdout] test time_unit::tests::test_time_window_from_negative_duration_hours ... ok
[INFO] [stdout] test time_unit::tests::test_time_window_from_negative_duration_minutes ... ok
[INFO] [stdout] test time_unit::tests::test_time_unit_ord ... ok
[INFO] [stdout] test time_unit::tests::test_time_window_from_negative_duration_seconds ... ok
[INFO] [stdout] test time_unit::tests::test_time_window_from_time_unit ... ok
[INFO] [stdout] test time_unit::tests::test_time_window_from_tuple ... ok
[INFO] [stdout] test time_unit::tests::test_week_spanning_year_boundary ... ok
[INFO] [stdout] test traits::tests::test_clock_is_send_sync ... ok
[INFO] [stdout] test traits::tests::test_storage_delete ... ok
[INFO] [stdout] test traits::tests::test_clock_trait_works ... ok
[INFO] [stdout] test traits::tests::test_storage_is_send_sync ... ok
[INFO] [stdout] test traits::tests::test_storage_load_nonexistent ... ok
[INFO] [stdout] test traits::tests::test_storage_save_and_load ... ok
[INFO] [stdout] test store::limiter::tests::test_flexible_time_windows_with_duration ... ok
[INFO] [stdout] test store::limiter::tests::test_schedule_hours_constructor ... ok
[INFO] [stdout] test store::limiter::tests::test_schedule_hours_validates_range ... ok
[INFO] [stdout] test store::limiter::tests::test_schedule_hours_validates_end_hour ... ok
[INFO] [stdout] test store::tests::test_export_all_includes_disk_only_counters ... ok
[INFO] [stdout] test store::tests::test_export_all_returns_all_counters ... ok
[INFO] [stdout] test store::tests::test_export_all_returns_empty_map_for_empty_store ... ok
[INFO] [stdout] test store::tests::test_record_count_ago ... ok
[INFO] [stdout] test store::tests::test_tracked_intervals_returns_default_config ... ok
[INFO] [stdout] test store::tests::test_record_ago_with_duration ... ok
[INFO] [stdout] test store::limiter::tests::test_usage_returns_correct_counts ... ok
[INFO] [stdout] test store::tests::test_default_configs_have_256_buckets_total ... ok
[INFO] [stdout] test time_unit::tests::test_num_rotations_hours ... ok
[INFO] [stdout] test time_unit::tests::test_num_rotations_weeks_calendar_multi_year_span ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 419 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.36s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/concurrency/mod.rs (/opt/rustwide/target/debug/deps/concurrency-a4640177ccf2f53c)
[INFO] [stdout] 
[INFO] [stdout] running 17 tests
[INFO] [stdout] test basic::concurrent_query_while_recording ... ok
[INFO] [stdout] test basic::stress_concurrent_query_delta ... ok
[INFO] [stdout] test basic::concurrent_record_from_multiple_threads ... ok
[INFO] [stdout] test basic::stress_concurrent_record_and_query ... ok
[INFO] [stdout] test basic::stress_mixed_workload ... ok
[INFO] [stdout] test basic::stress_concurrent_query_many ... ok
[INFO] [stdout] test basic::stress_concurrent_query_ratio ... ok
[INFO] [stdout] test basic::test_get_counter_race_with_concurrent_record ... ok
[INFO] [stdout] test basic::stress_persistence_under_load ... ok
[INFO] [stdout] test basic::stress_reservation_commit_cancel ... ok
[INFO] [stdout] test basic::stress_reservation_respects_limits ... ok
[INFO] [stdout] test storage_concurrency::test_file_per_event_concurrent_reads_while_writing ... ok
[INFO] [stdout] test storage_concurrency::test_file_per_event_concurrent_writes_different_keys ... ok
[INFO] [stdout] test storage_concurrency::test_file_per_event_concurrent_writes_same_key ... ok
[INFO] [stdout] test storage_concurrency::test_file_per_event_concurrent_mixed_operations ... ok
[INFO] [stdout] test basic::stress_high_contention_single_event ... ok
[INFO] [stderr]      Running tests/config/mod.rs (/opt/rustwide/target/debug/deps/config-ef6157d64ca8dad6)
[INFO] [stdout] test storage_concurrency::test_file_per_event_high_contention_stress ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 17 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 1.38s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 28 tests
[INFO] [stdout] test builder::builder_rejects_zero_bucket_count ... ok
[INFO] [stdout] test builder::builder_rejects_all_zero_buckets ... ok
[INFO] [stdout] test builder::builder_preset_for_rate_limiting ... ok
[INFO] [stdout] test builder::builder_preset_for_analytics ... ok
[INFO] [stdout] test builder::builder_custom_configuration ... ok
[INFO] [stdout] test builder::create_store_with_builder ... ok
[INFO] [stdout] test builder::builder_with_custom_clock ... ok
[INFO] [stdout] test builder::create_store_with_defaults ... ok
[INFO] [stdout] test builder::mix_of_valid_and_zero_buckets_rejected ... ok
[INFO] [stdout] test builder::query_with_seconds ... ok
[INFO] [stdout] test builder::memory_usage_is_reasonable ... ok
[INFO] [stdout] test builder::tracked_intervals_returns_configuration ... ok
[INFO] [stdout] test conversion::conversion_preserves_first_seen ... ok
[INFO] [stdout] test conversion::convert_days_to_hours ... ok
[INFO] [stdout] test conversion::convert_days_to_hours_after_midnight ... ok
[INFO] [stdout] test conversion::convert_empty_store ... ok
[INFO] [stdout] test conversion::convert_with_bucket_count_increase ... ok
[INFO] [stdout] test conversion::convert_days_to_hours_before_midnight ... ok
[INFO] [stdout] test conversion::convert_with_data_loss_outside_window ... ok
[INFO] [stdout] test conversion::round_trip_hours_to_days_to_hours ... ok
[INFO] [stdout] test conversion::convert_with_bucket_count_decrease ... ok
[INFO] [stdout] test conversion::round_trip_hours_to_days_to_hours_after_midnight ... ok
[INFO] [stdout] test conversion::round_trip_hours_to_days_to_hours_before_midnight ... ok
[INFO] [stdout] test conversion::round_trip_with_different_bucket_counts ... ok
[INFO] [stdout] test conversion::convert_hours_to_days ... ok
[INFO] [stdout] test conversion::conversion_is_deterministic ... ok
[INFO] [stdout] test conversion::conversion_round_trip_preserves_counts ... ok
[INFO] [stderr]      Running tests/limits/mod.rs (/opt/rustwide/target/debug/deps/limits-2beec73385b6c24c)
[INFO] [stdout] test conversion::conversion_preserves_or_loses_old_data ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 28 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.20s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 30 tests
[INFO] [stdout] test allowed::allowed_returns_true_when_check_passes ... ok
[INFO] [stdout] test at_least::at_least_requires_minimum_count ... ok
[INFO] [stdout] test allowed::allowed_returns_false_when_check_fails ... ok
[INFO] [stdout] test at_least::at_least_requires_prerequisite ... ok
[INFO] [stdout] test at_most::at_most_at_exact_limit ... ok
[INFO] [stdout] test at_most::at_most_rejects_over_limit ... ok
[INFO] [stdout] test at_most::at_most_multiple_time_windows ... ok
[INFO] [stdout] test at_most::at_most_with_duration ... ok
[INFO] [stdout] test check::check_does_not_record ... ok
[INFO] [stdout] test check::check_and_record_records_on_success ... ok
[INFO] [stdout] test check::check_and_record_does_not_record_on_failure ... ok
[INFO] [stdout] test cooldown::cooldown_allows_after_duration ... ok
[INFO] [stdout] test reservations::reservation_auto_cancels_on_drop ... ok
[INFO] [stdout] test reservations::reservation_commit_records_event ... ok
[INFO] [stdout] test reservations::reservation_cancel_does_not_record ... ok
[INFO] [stdout] test schedule::invalid_schedule_hours_rejected ... ok
[INFO] [stdout] test reservations::reservation_prevents_over_limit ... ok
[INFO] [stdout] test schedule::outside_of_schedule_allows_after_hours ... ok
[INFO] [stdout] test schedule::during_weekends ... ok
[INFO] [stdout] test usage::usage_returns_limit_info ... ok
[INFO] [stdout] test within::within_requires_recent_prerequisite ... ok
[INFO] [stdout] test schedule::during_business_hours ... ok
[INFO] [stdout] test schedule::during_weekdays ... ok
[INFO] [stdout] test combining::multiple_constraints_all_must_pass ... ok
[INFO] [stdout] test combining::multiple_constraints_any_failure_rejects ... ok
[INFO] [stdout] test cooldown::cooldown_enforces_wait_period ... ok
[INFO] [stdout] test schedule::outside_business_hours_rejects ... ok
[INFO] [stdout] test schedule::weekdays_rejects_weekend ... ok
[INFO] [stdout] test usage::usage_at_limit ... ok
[INFO] [stdout] test at_most::at_most_allows_within_limit ... ok
[INFO] [stderr]      Running tests/performance/mod.rs (/opt/rustwide/target/debug/deps/performance-e4a6b2c1c11c1984)
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 30 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.02s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 8 tests
[INFO] [stdout] test basic::record_large_counts_performance ... ok
[INFO] [stdout] test basic::query_many_performance ... ok
[INFO] [stdout] test basic::record_multiple_events_performance ... FAILED
[INFO] [stdout] test basic::record_performance_baseline ... FAILED
[INFO] [stdout] test basic::aggregation_performance ... ok
[INFO] [stdout] test basic::query_performance_baseline ... ok
[INFO] [stdout] test basic::delta_query_performance ... ok
[INFO] [stdout] test basic::ratio_query_performance ... ok
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout] 
[INFO] [stdout] ---- basic::record_multiple_events_performance stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'basic::record_multiple_events_performance' (1082) panicked at tests/performance/basic.rs:40:5:
[INFO] [stdout] Recording 1k events across 100 IDs took 63.008139ms, expected < 50ms
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x55947a829802 - std::backtrace_rs::backtrace::libunwind::trace::hff358a60abf734fc
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/std/src/../../backtrace/src/backtrace/libunwind.rs:117:9
[INFO] [stdout]    1:     0x55947a829802 - std::backtrace_rs::backtrace::trace_unsynchronized::h3b121f916dd95ec6
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/std/src/../../backtrace/src/backtrace/mod.rs:66:14
[INFO] [stdout]    2:     0x55947a829802 - std::sys::backtrace::_print_fmt::hde0a62ded68798e9
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/std/src/sys/backtrace.rs:74:9
[INFO] [stdout]    3:     0x55947a829802 - <std::sys::backtrace::BacktraceLock::print::DisplayBacktrace as core::fmt::Display>::fmt::h93773fc827e3113d
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/std/src/sys/backtrace.rs:44:26
[INFO] [stdout]    4:     0x55947a839bea - core::fmt::rt::Argument::fmt::h01eff69902dad97f
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/core/src/fmt/rt.rs:152:76
[INFO] [stdout]    5:     0x55947a839bea - core::fmt::write::hed7b5c73d82ecb7c
[INFO] [stdout]    6:     0x55947a7f7f46 - std::io::default_write_fmt::h2f696ff5b8bbaa4b
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/std/src/io/mod.rs:639:11
[INFO] [stdout]    7:     0x55947a7f7f46 - std::io::Write::write_fmt::h5e66814db8a9cfce
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/std/src/io/mod.rs:1994:13
[INFO] [stdout]    8:     0x55947a808bb9 - std::sys::backtrace::BacktraceLock::print::h8b1d6fcc5a56d1a3
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/std/src/sys/backtrace.rs:47:9
[INFO] [stdout]    9:     0x55947a808bb9 - std::panicking::default_hook::{{closure}}::h2be84df4f189ae36
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/std/src/panicking.rs:292:27
[INFO] [stdout]   10:     0x55947a808a51 - std::panicking::default_hook::hf0ea8939246f43a9
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/std/src/panicking.rs:316:9
[INFO] [stdout]   11:     0x55947a75b9fe - <alloc::boxed::Box<F,A> as core::ops::function::Fn<Args>>::call::h02d35e3888986252
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/alloc/src/boxed.rs:2220:9
[INFO] [stdout]   12:     0x55947a75b9fe - test::test_main_with_exit_callback::{{closure}}::h939640a27d647173
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/test/src/lib.rs:145:21
[INFO] [stdout]   13:     0x55947a808e32 - <alloc::boxed::Box<F,A> as core::ops::function::Fn<Args>>::call::hc9df09ff391af3e7
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/alloc/src/boxed.rs:2220:9
[INFO] [stdout]   14:     0x55947a808e32 - std::panicking::panic_with_hook::hb4bd9ac1123582a0
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/std/src/panicking.rs:833:13
[INFO] [stdout]   15:     0x55947a808c78 - std::panicking::panic_handler::{{closure}}::hde00dd15f5637fe2
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/std/src/panicking.rs:698:13
[INFO] [stdout]   16:     0x55947a803f29 - std::sys::backtrace::__rust_end_short_backtrace::hb72197fa777c1785
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/std/src/sys/backtrace.rs:182:18
[INFO] [stdout]   17:     0x55947a7ec27d - __rustc[4425a7e20b4c8619]::rust_begin_unwind
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/std/src/panicking.rs:689:5
[INFO] [stdout]   18:     0x55947a84129c - core::panicking::panic_fmt::ha59b517dd231f4da
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/core/src/panicking.rs:80:14
[INFO] [stdout]   19:     0x55947a739d26 - performance::basic::record_multiple_events_performance::h37d0b7b8c542796e
[INFO] [stdout]                                at /opt/rustwide/workdir/tests/performance/basic.rs:40:5
[INFO] [stdout]   20:     0x55947a739e97 - performance::basic::record_multiple_events_performance::{{closure}}::hf22d816d9f324bb9
[INFO] [stdout]                                at /opt/rustwide/workdir/tests/performance/basic.rs:30:40
[INFO] [stdout]   21:     0x55947a73b496 - core::ops::function::FnOnce::call_once::h94b794422cb679f8
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   22:     0x55947a75b7bb - core::ops::function::FnOnce::call_once::hddb3cd395c36bfbb
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   23:     0x55947a75b7bb - test::__rust_begin_short_backtrace::h0b6a7601d9750bfa
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/test/src/lib.rs:663:18
[INFO] [stdout]   24:     0x55947a76f1ca - test::run_test_in_process::{{closure}}::h444209903f00b347
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/test/src/lib.rs:686:74
[INFO] [stdout]   25:     0x55947a76f1ca - <core::panic::unwind_safe::AssertUnwindSafe<F> as core::ops::function::FnOnce<()>>::call_once::hfb3ba8f46e537649
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/core/src/panic/unwind_safe.rs:274:9
[INFO] [stdout]   26:     0x55947a76f1ca - std::panicking::catch_unwind::do_call::h1672a45911fcf9dc
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/std/src/panicking.rs:581:40
[INFO] [stdout]   27:     0x55947a76f1ca - std::panicking::catch_unwind::h0fadaee22787a6dd
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/std/src/panicking.rs:544:19
[INFO] [stdout]   28:     0x55947a76f1ca - std::panic::catch_unwind::hc47ad190f2c8e188
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/std/src/panic.rs:359:14
[INFO] [stdout]   29:     0x55947a76f1ca - test::run_test_in_process::hcd7faaf934f29999
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/test/src/lib.rs:686:27
[INFO] [stdout]   30:     0x55947a76f1ca - test::run_test::{{closure}}::hddc4550da4871867
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/test/src/lib.rs:607:43
[INFO] [stdout]   31:     0x55947a749624 - test::run_test::{{closure}}::h98ae26689f13ed94
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/test/src/lib.rs:637:41
[INFO] [stdout]   32:     0x55947a749624 - std::sys::backtrace::__rust_begin_short_backtrace::h3ec4d1f21ad44588
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/std/src/sys/backtrace.rs:166:18
[INFO] [stdout]   33:     0x55947a74cfc2 - std::thread::lifecycle::spawn_unchecked::{{closure}}::{{closure}}::hd8bf2c73d479939f
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/std/src/thread/lifecycle.rs:91:13
[INFO] [stdout]   34:     0x55947a74cfc2 - <core::panic::unwind_safe::AssertUnwindSafe<F> as core::ops::function::FnOnce<()>>::call_once::h3b4248f358af2491
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/core/src/panic/unwind_safe.rs:274:9
[INFO] [stdout]   35:     0x55947a74cfc2 - std::panicking::catch_unwind::do_call::hda10a64f4b8daebe
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/std/src/panicking.rs:581:40
[INFO] [stdout]   36:     0x55947a74cfc2 - std::panicking::catch_unwind::h659e5ee8336ec8c7
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/std/src/panicking.rs:544:19
[INFO] [stdout]   37:     0x55947a74cfc2 - std::panic::catch_unwind::h9d4d66a538912c18
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/std/src/panic.rs:359:14
[INFO] [stdout]   38:     0x55947a74cfc2 - std::thread::lifecycle::spawn_unchecked::{{closure}}::h7557906bb51d79f1
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/std/src/thread/lifecycle.rs:89:26
[INFO] [stdout]   39:     0x55947a74cfc2 - core::ops::function::FnOnce::call_once{{vtable.shim}}::h9e460d1d7fc83d35
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   40:     0x55947a7ff1bf - <alloc::boxed::Box<F,A> as core::ops::function::FnOnce<Args>>::call_once::h61282da819d64ea9
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/alloc/src/boxed.rs:2206:9
[INFO] [stdout]   41:     0x55947a7ff1bf - std::sys::thread::unix::Thread::new::thread_start::h982f9ea829d1b5fb
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/std/src/sys/thread/unix.rs:127:17
[INFO] [stdout]   42:     0x736ea9c79aa4 - <unknown>
[INFO] [stdout]   43:     0x736ea9d06a64 - clone
[INFO] [stdout]   44:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- basic::record_performance_baseline stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'basic::record_performance_baseline' (1083) panicked at tests/performance/basic.rs:22:5:
[INFO] [stdout] Recording 10k events took 181.139651ms, expected < 100ms
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x55947a829802 - std::backtrace_rs::backtrace::libunwind::trace::hff358a60abf734fc
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/std/src/../../backtrace/src/backtrace/libunwind.rs:117:9
[INFO] [stdout]    1:     0x55947a829802 - std::backtrace_rs::backtrace::trace_unsynchronized::h3b121f916dd95ec6
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/std/src/../../backtrace/src/backtrace/mod.rs:66:14
[INFO] [stdout]    2:     0x55947a829802 - std::sys::backtrace::_print_fmt::hde0a62ded68798e9
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/std/src/sys/backtrace.rs:74:9
[INFO] [stdout]    3:     0x55947a829802 - <std::sys::backtrace::BacktraceLock::print::DisplayBacktrace as core::fmt::Display>::fmt::h93773fc827e3113d
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/std/src/sys/backtrace.rs:44:26
[INFO] [stdout]    4:     0x55947a839bea - core::fmt::rt::Argument::fmt::h01eff69902dad97f
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/core/src/fmt/rt.rs:152:76
[INFO] [stdout]    5:     0x55947a839bea - core::fmt::write::hed7b5c73d82ecb7c
[INFO] [stdout]    6:     0x55947a7f7f46 - std::io::default_write_fmt::h2f696ff5b8bbaa4b
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/std/src/io/mod.rs:639:11
[INFO] [stdout]    7:     0x55947a7f7f46 - std::io::Write::write_fmt::h5e66814db8a9cfce
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/std/src/io/mod.rs:1994:13
[INFO] [stdout]    8:     0x55947a808bb9 - std::sys::backtrace::BacktraceLock::print::h8b1d6fcc5a56d1a3
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/std/src/sys/backtrace.rs:47:9
[INFO] [stdout]    9:     0x55947a808bb9 - std::panicking::default_hook::{{closure}}::h2be84df4f189ae36
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/std/src/panicking.rs:292:27
[INFO] [stdout]   10:     0x55947a808a51 - std::panicking::default_hook::hf0ea8939246f43a9
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/std/src/panicking.rs:316:9
[INFO] [stdout]   11:     0x55947a75b9fe - <alloc::boxed::Box<F,A> as core::ops::function::Fn<Args>>::call::h02d35e3888986252
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/alloc/src/boxed.rs:2220:9
[INFO] [stdout]   12:     0x55947a75b9fe - test::test_main_with_exit_callback::{{closure}}::h939640a27d647173
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/test/src/lib.rs:145:21
[INFO] [stdout]   13:     0x55947a808e32 - <alloc::boxed::Box<F,A> as core::ops::function::Fn<Args>>::call::hc9df09ff391af3e7
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/alloc/src/boxed.rs:2220:9
[INFO] [stdout]   14:     0x55947a808e32 - std::panicking::panic_with_hook::hb4bd9ac1123582a0
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/std/src/panicking.rs:833:13
[INFO] [stdout]   15:     0x55947a808c78 - std::panicking::panic_handler::{{closure}}::hde00dd15f5637fe2
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/std/src/panicking.rs:698:13
[INFO] [stdout]   16:     0x55947a803f29 - std::sys::backtrace::__rust_end_short_backtrace::hb72197fa777c1785
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/std/src/sys/backtrace.rs:182:18
[INFO] [stdout]   17:     0x55947a7ec27d - __rustc[4425a7e20b4c8619]::rust_begin_unwind
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/std/src/panicking.rs:689:5
[INFO] [stdout]   18:     0x55947a84129c - core::panicking::panic_fmt::ha59b517dd231f4da
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/core/src/panicking.rs:80:14
[INFO] [stdout]   19:     0x55947a739933 - performance::basic::record_performance_baseline::h658a95bd48aa9f97
[INFO] [stdout]                                at /opt/rustwide/workdir/tests/performance/basic.rs:22:5
[INFO] [stdout]   20:     0x55947a739967 - performance::basic::record_performance_baseline::{{closure}}::h181e14625fe26a8c
[INFO] [stdout]                                at /opt/rustwide/workdir/tests/performance/basic.rs:13:33
[INFO] [stdout]   21:     0x55947a73b416 - core::ops::function::FnOnce::call_once::h8c34639c9d68ab0d
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   22:     0x55947a75b7bb - core::ops::function::FnOnce::call_once::hddb3cd395c36bfbb
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   23:     0x55947a75b7bb - test::__rust_begin_short_backtrace::h0b6a7601d9750bfa
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/test/src/lib.rs:663:18
[INFO] [stdout]   24:     0x55947a76f1ca - test::run_test_in_process::{{closure}}::h444209903f00b347
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/test/src/lib.rs:686:74
[INFO] [stdout]   25:     0x55947a76f1ca - <core::panic::unwind_safe::AssertUnwindSafe<F> as core::ops::function::FnOnce<()>>::call_once::hfb3ba8f46e537649
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/core/src/panic/unwind_safe.rs:274:9
[INFO] [stdout]   26:     0x55947a76f1ca - std::panicking::catch_unwind::do_call::h1672a45911fcf9dc
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/std/src/panicking.rs:581:40
[INFO] [stdout]   27:     0x55947a76f1ca - std::panicking::catch_unwind::h0fadaee22787a6dd
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/std/src/panicking.rs:544:19
[INFO] [stdout]   28:     0x55947a76f1ca - std::panic::catch_unwind::hc47ad190f2c8e188
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/std/src/panic.rs:359:14
[INFO] [stdout]   29:     0x55947a76f1ca - test::run_test_in_process::hcd7faaf934f29999
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/test/src/lib.rs:686:27
[INFO] [stdout]   30:     0x55947a76f1ca - test::run_test::{{closure}}::hddc4550da4871867
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/test/src/lib.rs:607:43
[INFO] [stdout]   31:     0x55947a749624 - test::run_test::{{closure}}::h98ae26689f13ed94
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/test/src/lib.rs:637:41
[INFO] [stdout]   32:     0x55947a749624 - std::sys::backtrace::__rust_begin_short_backtrace::h3ec4d1f21ad44588
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/std/src/sys/backtrace.rs:166:18
[INFO] [stdout]   33:     0x55947a74cfc2 - std::thread::lifecycle::spawn_unchecked::{{closure}}::{{closure}}::hd8bf2c73d479939f
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/std/src/thread/lifecycle.rs:91:13
[INFO] [stdout]   34:     0x55947a74cfc2 - <core::panic::unwind_safe::AssertUnwindSafe<F> as core::ops::function::FnOnce<()>>::call_once::h3b4248f358af2491
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/core/src/panic/unwind_safe.rs:274:9
[INFO] [stdout]   35:     0x55947a74cfc2 - std::panicking::catch_unwind::do_call::hda10a64f4b8daebe
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/std/src/panicking.rs:581:40
[INFO] [stdout]   36:     0x55947a74cfc2 - std::panicking::catch_unwind::h659e5ee8336ec8c7
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/std/src/panicking.rs:544:19
[INFO] [stdout]   37:     0x55947a74cfc2 - std::panic::catch_unwind::h9d4d66a538912c18
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/std/src/panic.rs:359:14
[INFO] [stdout]   38:     0x55947a74cfc2 - std::thread::lifecycle::spawn_unchecked::{{closure}}::h7557906bb51d79f1
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/std/src/thread/lifecycle.rs:89:26
[INFO] [stdout]   39:     0x55947a74cfc2 - core::ops::function::FnOnce::call_once{{vtable.shim}}::h9e460d1d7fc83d35
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   40:     0x55947a7ff1bf - <alloc::boxed::Box<F,A> as core::ops::function::FnOnce<Args>>::call_once::h61282da819d64ea9
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/alloc/src/boxed.rs:2206:9
[INFO] [stdout]   41:     0x55947a7ff1bf - std::sys::thread::unix::Thread::new::thread_start::h982f9ea829d1b5fb
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/std/src/sys/thread/unix.rs:127:17
[INFO] [stdout]   42:     0x736ea9c79aa4 - <unknown>
[INFO] [stdout]   43:     0x736ea9d06a64 - clone
[INFO] [stdout]   44:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout]     basic::record_multiple_events_performance
[INFO] [stdout]     basic::record_performance_baseline
[INFO] [stdout] 
[INFO] [stdout] test result: FAILED. 6 passed; 2 failed; 0 ignored; 0 measured; 0 filtered out; finished in 1.28s
[INFO] [stdout] 
[INFO] [stderr] error: test failed, to rerun pass `--test performance`
[INFO] running `Command { std: "docker" "inspect" "e2e9b1fb20725285f0a5cbdb3c0925a796b912e2545715dc01a4499320a13600", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "e2e9b1fb20725285f0a5cbdb3c0925a796b912e2545715dc01a4499320a13600", kill_on_drop: false }`
[INFO] [stdout] e2e9b1fb20725285f0a5cbdb3c0925a796b912e2545715dc01a4499320a13600
