[INFO] fetching crate emob-cdr 0.1.0...
[INFO] testing emob-cdr-0.1.0 against 1.100.0-beta.1+cargoflags=--release for beta-release-1.100-2
[INFO] extracting crate emob-cdr 0.1.0 into /workspace/builds/worker-5-tc2/source
[INFO] started tweaking crates.io crate emob-cdr 0.1.0
[INFO] removed 0 missing tests
[INFO] finished tweaking crates.io crate emob-cdr 0.1.0
[INFO] tweaked toml for crates.io crate emob-cdr 0.1.0 written to /workspace/builds/worker-5-tc2/source/Cargo.toml
[INFO] validating manifest of crates.io crate emob-cdr 0.1.0 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 emob-cdr 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.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-5-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-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] ebfe4b19da1a32e72e0341a08ec03926ef3ea484a97a4cb3d1141ffdbeeff747
[INFO] running `Command { std: "docker" "start" "ebfe4b19da1a32e72e0341a08ec03926ef3ea484a97a4cb3d1141ffdbeeff747", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "ebfe4b19da1a32e72e0341a08ec03926ef3ea484a97a4cb3d1141ffdbeeff747", 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" "ebfe4b19da1a32e72e0341a08ec03926ef3ea484a97a4cb3d1141ffdbeeff747" "/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" "ebfe4b19da1a32e72e0341a08ec03926ef3ea484a97a4cb3d1141ffdbeeff747", 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" "ebfe4b19da1a32e72e0341a08ec03926ef3ea484a97a4cb3d1141ffdbeeff747" "/opt/rustwide/cargo-home/bin/cargo" "+1.100.0-beta.1" "build" "--frozen" "--message-format=json" "--release", kill_on_drop: false }`
[INFO] [stderr]    Compiling zeroize v1.9.0
[INFO] [stderr]    Compiling rust_decimal v1.42.1
[INFO] [stderr]    Compiling time-core v0.1.9
[INFO] [stderr]    Compiling serde_json v1.0.151
[INFO] [stderr]    Compiling getrandom v0.2.17
[INFO] [stderr]    Compiling deranged v0.5.8
[INFO] [stderr]    Compiling indexmap v2.14.1
[INFO] [stderr]    Compiling syn v3.0.4
[INFO] [stderr]    Compiling syn v2.0.119
[INFO] [stderr]    Compiling rand_core v0.6.4
[INFO] [stderr]    Compiling time-macros v0.2.32
[INFO] [stderr]    Compiling generic-array v0.14.9
[INFO] [stderr]    Compiling der v0.7.10
[INFO] [stderr]    Compiling ff v0.13.1
[INFO] [stderr]    Compiling group v0.13.0
[INFO] [stderr]    Compiling crypto-common v0.1.6
[INFO] [stderr]    Compiling block-buffer v0.10.4
[INFO] [stderr]    Compiling crypto-bigint v0.5.5
[INFO] [stderr]    Compiling digest v0.10.7
[INFO] [stderr]    Compiling hmac v0.12.1
[INFO] [stderr]    Compiling signature v2.2.0
[INFO] [stderr]    Compiling sha2 v0.10.9
[INFO] [stderr]    Compiling spki v0.7.3
[INFO] [stderr]    Compiling sec1 v0.7.3
[INFO] [stderr]    Compiling rfc6979 v0.4.0
[INFO] [stderr]    Compiling pkcs8 v0.10.2
[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 elliptic-curve v0.13.8
[INFO] [stderr]    Compiling ecdsa v0.16.9
[INFO] [stderr]    Compiling primeorder v0.13.6
[INFO] [stderr]    Compiling thiserror v2.0.20
[INFO] [stderr]    Compiling rust_decimal_macros v1.40.0
[INFO] [stderr]    Compiling k256 v0.13.4
[INFO] [stderr]    Compiling p256 v0.13.2
[INFO] [stderr]    Compiling p192 v0.13.0
[INFO] [stderr]    Compiling p384 v0.13.1
[INFO] [stderr]    Compiling serde v1.0.229
[INFO] [stderr]    Compiling emob-core v0.1.0
[INFO] [stderr]    Compiling emob-session v0.1.0
[INFO] [stderr]    Compiling emob-tariff v0.1.0
[INFO] [stderr]    Compiling emob-eichrecht v0.1.0
[INFO] [stderr]    Compiling emob-cdr v0.1.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `release` profile [optimized] target(s) in 34.16s
[INFO] running `Command { std: "docker" "inspect" "ebfe4b19da1a32e72e0341a08ec03926ef3ea484a97a4cb3d1141ffdbeeff747", 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" "ebfe4b19da1a32e72e0341a08ec03926ef3ea484a97a4cb3d1141ffdbeeff747" "/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 emob-cdr v0.1.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `release` profile [optimized] target(s) in 7.06s
[INFO] running `Command { std: "docker" "inspect" "ebfe4b19da1a32e72e0341a08ec03926ef3ea484a97a4cb3d1141ffdbeeff747", 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" "ebfe4b19da1a32e72e0341a08ec03926ef3ea484a97a4cb3d1141ffdbeeff747" "/opt/rustwide/cargo-home/bin/cargo" "+1.100.0-beta.1" "test" "--frozen" "--release", kill_on_drop: false }`
[INFO] [stderr]     Finished `release` profile [optimized] target(s) in 0.10s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/release/build/emob-cdr/ed19b99c594eff4b/out/emob_cdr-ed19b99c594eff4b)
[INFO] [stdout] 
[INFO] [stdout] running 59 tests
[INFO] [stdout] test cdr::tests::a_cdr_carries_its_arithmetic ... ok
[INFO] [stdout] test cdr::tests::a_cdr_needs_a_key ... ok
[INFO] [stdout] test cdr::tests::a_cdr_carries_its_money_and_it_comes_from_its_own_periods ... ok
[INFO] [stdout] test cdr::tests::a_gap_in_a_price_history_is_reported_rather_than_guessed ... ok
[INFO] [stdout] test cdr::tests::a_draw_over_an_export_register_is_refused ... ok
[INFO] [stdout] test cdr::tests::a_record_names_the_tariff_that_priced_it_by_content ... ok
[INFO] [stdout] test cdr::tests::a_register_the_verifier_could_not_classify_makes_no_claim ... ok
[INFO] [stdout] test cdr::tests::a_period_never_starts_before_the_session_it_belongs_to ... ok
[INFO] [stdout] test cdr::tests::a_correction_names_what_it_replaces ... ok
[INFO] [stdout] test cdr::tests::a_running_session_cannot_produce_a_cdr ... ok
[INFO] [stdout] test cdr::tests::a_session_whose_readings_span_it_gains_no_occupancy_periods ... ok
[INFO] [stdout] test cdr::tests::a_tiered_tariff_prices_a_cdr_slot_by_slot ... ok
[INFO] [stdout] test cdr::tests::a_tariff_that_was_not_in_force_cannot_have_priced_the_session ... ok
[INFO] [stdout] test cdr::tests::an_unrated_cdr_has_no_price_rather_than_a_zero_one ... ok
[INFO] [stdout] test cdr::tests::a_time_priced_tariff_is_refused_when_the_clock_cannot_be_defended ... ok
[INFO] [stdout] test cdr::tests::every_record_this_builder_emits_passes_its_own_validator ... ok
[INFO] [stdout] test cdr::tests::energy_in_a_slot_the_session_calls_suspended_is_refused ... ok
[INFO] [stdout] test cdr::tests::evidence_that_is_present_and_failed_is_worse_than_evidence_that_is_absent ... ok
[INFO] [stdout] test cdr::tests::an_overstated_authorisation_is_refused ... ok
[INFO] [stdout] test cdr::tests::the_key_is_the_pair_not_the_bare_id ... ok
[INFO] [stdout] test cdr::tests::a_span_the_clock_cannot_resolve_is_not_billed_for_time ... ok
[INFO] [stdout] test ledger::tests::a_correction_chain_is_walkable ... ok
[INFO] [stdout] test cdr::tests::a_suspended_slot_is_priced_as_occupancy_and_says_so ... ok
[INFO] [stdout] test ledger::tests::a_restated_number_is_a_conflict_not_an_upsert ... ok
[INFO] [stdout] test cdr::tests::a_station_whose_clock_is_better_than_the_worst_case_may_say_so ... ok
[INFO] [stdout] test cdr::tests::a_taper_is_not_an_occupancy_even_when_it_moved_nothing ... ok
[INFO] [stdout] test cdr::tests::under_reporting_is_fine ... ok
[INFO] [stdout] test ledger::tests::two_parties_may_each_have_a_cdr_number_one ... ok
[INFO] [stdout] test cdr::tests::the_time_after_the_last_reading_is_occupancy_and_it_gets_billed ... ok
[INFO] [stdout] test cdr::tests::the_version_in_force_when_the_session_started_is_the_one_that_governs ... ok
[INFO] [stdout] test ledger::tests::a_new_cdr_is_stored ... ok
[INFO] [stdout] test ledger::tests::a_retransmission_is_not_a_second_invoice ... ok
[INFO] [stdout] test validate::tests::a_price_computed_for_a_different_quantity_blocks_settlement ... ok
[INFO] [stdout] test validate::tests::a_rating_note_travels_as_a_warning_rather_than_a_block ... ok
[INFO] [stdout] test validate::tests::a_record_that_does_not_add_up_is_blocked ... ok
[INFO] [stdout] test validate::tests::a_session_starting_mid_slot_reports_the_slot_and_the_window_separately ... ok
[INFO] [stdout] test ledger::tests::an_uncorrected_cdr_is_not_superseded ... ok
[INFO] [stdout] test validate::tests::block_rounding_bills_more_than_was_delivered_without_being_a_mismatch ... ok
[INFO] [stdout] test validate::tests::an_overstated_authorisation_blocks ... ok
[INFO] [stdout] test validate::tests::duplicate_periods_are_caught ... ok
[INFO] [stdout] test cdr::tests::interpolated_periods_travel_to_the_recipient ... ok
[INFO] [stdout] test cdr::tests::readings_that_outlast_the_session_are_refused_rather_than_clamped ... ok
[INFO] [stdout] test validate::tests::a_period_that_moved_energy_while_not_charging_contradicts_itself ... ok
[INFO] [stdout] test validate::tests::a_partner_claiming_the_wrong_direction_is_blocked ... ok
[INFO] [stdout] test validate::tests::a_period_outside_the_window_is_caught ... ok
[INFO] [stdout] test validate::tests::a_partner_pricing_a_duration_off_an_undefendable_clock_is_blocked ... ok
[INFO] [stdout] test validate::tests::nothing_is_silently_repaired ... ok
[INFO] [stdout] test cdr::tests::a_cdr_without_evidence_says_so ... ok
[INFO] [stdout] test validate::tests::energy_without_duration_is_blocked ... ok
[INFO] [stdout] test cdr::tests::a_session_wider_than_its_readings_claims_no_measurement_it_does_not_have ... ok
[INFO] [stdout] test validate::tests::every_problem_is_reported_not_just_the_first ... ok
[INFO] [stdout] test validate::tests::a_good_cdr_passes_clean ... ok
[INFO] [stdout] test validate::tests::evidence_that_contradicts_its_own_record_blocks_where_absence_warns ... ok
[INFO] [stdout] test validate::tests::interpolation_is_reported_as_a_warning ... ok
[INFO] [stdout] test ledger::tests::the_conflict_message_names_what_moved ... ok
[INFO] [stdout] test validate::tests::missing_evidence_is_a_warning_not_a_block ... ok
[INFO] [stdout] test validate::tests::out_of_order_periods_are_caught ... ok
[INFO] [stderr]      Running tests/end_to_end.rs (/opt/rustwide/target/release/build/emob-cdr/cbc154d54f2d2148/out/end_to_end-cbc154d54f2d2148)
[INFO] [stdout] test validate::tests::an_empty_record_is_blocked ... ok
[INFO] [stdout] test validate::tests::a_lawful_per_minute_tariff_settles_and_says_it_priced_no_energy ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 59 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 21 tests
[INFO] [stdout] test an_interpolated_session_still_conserves_and_says_so ... ok
[INFO] [stdout] test a_record_from_a_real_german_meter_verifies_and_bills ... ok
[INFO] [stdout] test a_fast_charger_may_not_offer_a_per_minute_only_tariff ... ok
[INFO] [stdout] test an_unbillable_session_never_reaches_a_price ... ok
[INFO] [stdout] test the_driver_of_a_real_meter_gets_a_file_their_own_verifier_reads ... ok
[INFO] [stdout] test a_tiered_tariff_prices_the_quarter_hours_the_split_produced ... ok
[INFO] [stdout] test a_substitute_reading_stops_the_chain ... ok
[INFO] [stdout] test the_price_shown_is_the_price_charged_and_it_rides_on_the_record ... ok
[INFO] [stdout] test a_v2g_discharge_cannot_be_billed_as_a_draw ... ok
[INFO] [stdout] test an_unregistered_station_cannot_bill ... ok
[INFO] [stdout] test a_priced_record_survives_the_wire_with_its_reasons_intact ... ok
[INFO] [stdout] test a_deleted_middle_record_stops_the_chain_though_every_signature_holds ... ok
[INFO] [stdout] test a_session_overstating_its_authorisation_cannot_produce_a_cdr ... ok
[INFO] [stdout] test a_partner_restating_a_settled_number_is_a_conflict ... ok
[INFO] [stdout] test an_unsynchronised_clock_bills_the_energy_and_refuses_the_occupancy_fee ... ok
[INFO] [stdout] test a_price_change_does_not_reach_back_into_a_session_already_running ... ok
[INFO] [stdout] test one_changed_digit_stops_the_whole_chain ... ok
[INFO] [stdout] test the_record_names_the_tariff_by_content_and_it_survives_the_wire ... ok
[INFO] [stdout] test the_two_ways_a_duration_stops_being_billable_are_both_enforced ... ok
[INFO] [stdout] test a_genuine_session_reaches_a_settleable_cdr ... ok
[INFO] [stdout] test the_driver_can_repeat_the_whole_check_in_software_nobody_here_wrote ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 21 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.03s
[INFO] [stdout] 
[INFO] [stderr]    Doc-tests emob_cdr
[INFO] [stdout] 
[INFO] [stdout] running 2 tests
[INFO] [stdout] test src/validate.rs - validate::validate (line 288) - compile ... ok
[INFO] [stdout] test src/cdr.rs - cdr::CdrBuilder (line 321) - compile ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 2 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stdout] all doctests ran in 0.57s; merged doctests compilation took 0.56s
[INFO] running `Command { std: "docker" "inspect" "ebfe4b19da1a32e72e0341a08ec03926ef3ea484a97a4cb3d1141ffdbeeff747", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "ebfe4b19da1a32e72e0341a08ec03926ef3ea484a97a4cb3d1141ffdbeeff747", kill_on_drop: false }`
[INFO] [stdout] ebfe4b19da1a32e72e0341a08ec03926ef3ea484a97a4cb3d1141ffdbeeff747
