[INFO] fetching crate emob-eichrecht 0.1.0...
[INFO] testing emob-eichrecht-0.1.0 against 1.100.0-beta.1+cargoflags=--release for beta-release-1.100-2
[INFO] extracting crate emob-eichrecht 0.1.0 into /workspace/builds/worker-7-tc2/source
[INFO] started tweaking crates.io crate emob-eichrecht 0.1.0
[INFO] finished tweaking crates.io crate emob-eichrecht 0.1.0
[INFO] tweaked toml for crates.io crate emob-eichrecht 0.1.0 written to /workspace/builds/worker-7-tc2/source/Cargo.toml
[INFO] validating manifest of crates.io crate emob-eichrecht 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-eichrecht 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-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] 62a2bd4e9383c440e23770e76c63b89dc7434f763d060e0dc204098a28d9e5a5
[INFO] running `Command { std: "docker" "start" "62a2bd4e9383c440e23770e76c63b89dc7434f763d060e0dc204098a28d9e5a5", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "62a2bd4e9383c440e23770e76c63b89dc7434f763d060e0dc204098a28d9e5a5", 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" "62a2bd4e9383c440e23770e76c63b89dc7434f763d060e0dc204098a28d9e5a5" "/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" "62a2bd4e9383c440e23770e76c63b89dc7434f763d060e0dc204098a28d9e5a5", 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" "62a2bd4e9383c440e23770e76c63b89dc7434f763d060e0dc204098a28d9e5a5" "/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 generic-array v0.14.9
[INFO] [stderr]    Compiling num-traits v0.2.19
[INFO] [stderr]    Compiling rust_decimal v1.42.1
[INFO] [stderr]    Compiling time-macros v0.2.32
[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 v2.0.119
[INFO] [stderr]    Compiling serde_derive v1.0.229
[INFO] [stderr]    Compiling rand_core v0.6.4
[INFO] [stderr]    Compiling der v0.7.10
[INFO] [stderr]    Compiling ff v0.13.1
[INFO] [stderr]    Compiling group v0.13.0
[INFO] [stderr]    Compiling block-buffer v0.10.4
[INFO] [stderr]    Compiling crypto-common v0.1.6
[INFO] [stderr]    Compiling crypto-bigint v0.5.5
[INFO] [stderr]    Compiling digest v0.10.7
[INFO] [stderr]    Compiling spki v0.7.3
[INFO] [stderr]    Compiling sec1 v0.7.3
[INFO] [stderr]    Compiling hmac v0.12.1
[INFO] [stderr]    Compiling signature v2.2.0
[INFO] [stderr]    Compiling sha2 v0.10.9
[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 v1.0.229
[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 rust_decimal_macros v1.40.0
[INFO] [stderr]    Compiling k256 v0.13.4
[INFO] [stderr]    Compiling p384 v0.13.1
[INFO] [stderr]    Compiling p192 v0.13.0
[INFO] [stderr]    Compiling p256 v0.13.2
[INFO] [stderr]    Compiling emob-core v0.1.0
[INFO] [stderr]    Compiling emob-eichrecht v0.1.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `release` profile [optimized] target(s) in 31.63s
[INFO] running `Command { std: "docker" "inspect" "62a2bd4e9383c440e23770e76c63b89dc7434f763d060e0dc204098a28d9e5a5", 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" "62a2bd4e9383c440e23770e76c63b89dc7434f763d060e0dc204098a28d9e5a5" "/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-eichrecht v0.1.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `release` profile [optimized] target(s) in 9.33s
[INFO] running `Command { std: "docker" "inspect" "62a2bd4e9383c440e23770e76c63b89dc7434f763d060e0dc204098a28d9e5a5", 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" "62a2bd4e9383c440e23770e76c63b89dc7434f763d060e0dc204098a28d9e5a5" "/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.14s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/release/build/emob-eichrecht/b185d9573c4d3303/out/emob_eichrecht-b185d9573c4d3303)
[INFO] [stdout] 
[INFO] [stdout] running 115 tests
[INFO] [stdout] test chain::tests::a_carried_forward_begin_marker_is_not_a_second_transaction ... ok
[INFO] [stdout] test chain::tests::a_manipulated_meter_blocks_the_invoice ... ok
[INFO] [stdout] test chain::tests::a_failed_user_assignment_blocks_everything ... ok
[INFO] [stdout] test chain::tests::a_missing_begin_marker_is_caught ... ok
[INFO] [stdout] test chain::tests::a_missing_end_marker_is_caught ... ok
[INFO] [stdout] test chain::tests::a_swapped_signing_component_is_caught ... ok
[INFO] [stdout] test chain::tests::a_record_without_an_assignment_is_silent_rather_than_contradicting ... ok
[INFO] [stdout] test chain::tests::a_substitute_value_blocks_the_invoice ... ok
[INFO] [stdout] test chain::tests::a_duplicated_record_is_caught_too ... ok
[INFO] [stdout] test chain::tests::a_synchronised_session_bills_both_quantities ... ok
[INFO] [stdout] test chain::tests::a_fiscal_first_record_does_not_switch_the_marker_rules_off ... ok
[INFO] [stdout] test chain::tests::a_clean_session_bills_the_difference ... ok
[INFO] [stdout] test chain::tests::a_relative_clock_is_good_enough_for_a_duration ... ok
[INFO] [stdout] test chain::tests::a_register_running_backwards_is_a_fault_not_a_credit ... ok
[INFO] [stdout] test chain::tests::a_swapped_component_is_caught_even_when_the_first_record_names_none ... ok
[INFO] [stdout] test chain::tests::an_unknown_error_flag_blocks_rather_than_being_ignored ... ok
[INFO] [stdout] test chain::tests::an_empty_chain_is_a_finding_not_a_panic ... ok
[INFO] [stdout] test chain::tests::an_unsynchronised_clock_takes_the_duration_and_leaves_the_energy ... ok
[INFO] [stdout] test chain::tests::every_finding_disqualifies_something_it_can_name ... ok
[INFO] [stdout] test chain::tests::an_energy_error_flag_takes_the_energy_and_leaves_the_duration ... ok
[INFO] [stdout] test chain::tests::an_exception_marker_makes_both_quantities_unusable ... ok
[INFO] [stdout] test chain::tests::the_compensated_cable_loss_is_carried_rather_than_subtracted ... ok
[INFO] [stdout] test chain::tests::readings_on_different_registers_do_not_subtract ... ok
[INFO] [stdout] test chain::tests::the_identification_strength_is_the_weakest_any_record_asserted ... ok
[INFO] [stdout] test chain::tests::the_cable_loss_is_converted_from_the_registers_own_unit ... ok
[INFO] [stdout] test chain::tests::the_register_states_which_way_the_energy_went ... ok
[INFO] [stdout] test chain::tests::two_transactions_in_one_chain_are_caught ... ok
[INFO] [stdout] test chain::tests::cable_loss_must_be_reset_at_the_start_of_a_transaction ... ok
[INFO] [stdout] test chain::tests::cable_loss_on_a_register_that_does_not_accumulate_is_refused ... ok
[INFO] [stdout] test chain::tests::every_finding_renders_a_useful_message ... ok
[INFO] [stdout] test chain::tests::one_record_carrying_both_markers_is_a_whole_session ... ok
[INFO] [stdout] test chain::tests::wh_registers_convert_to_kwh ... ok
[INFO] [stdout] test evidence::tests::an_unregistered_station_cannot_bill ... ok
[INFO] [stdout] test chain::tests::deleting_a_record_is_caught_though_every_signature_still_holds ... ok
[INFO] [stdout] test evidence::tests::a_bad_clock_is_a_problem_that_still_bills_the_energy ... ok
[INFO] [stdout] test chain::tests::an_energy_error_flag_blocks_the_invoice ... ok
[INFO] [stdout] test ocmf::model::tests::a_substitute_value_is_not_a_measurement ... ok
[INFO] [stdout] test ocmf::model::tests::an_unknown_meter_state_is_an_error_not_an_ok ... ok
[INFO] [stdout] test chain::tests::readings_in_different_units_do_not_subtract ... ok
[INFO] [stdout] test chain::tests::an_unassigned_session_reports_no_strength_rather_than_a_weak_one ... ok
[INFO] [stdout] test chain::tests::time_running_backwards_is_caught ... ok
[INFO] [stdout] test ocmf::model::tests::pagination_round_trips ... ok
[INFO] [stdout] test ocmf::model::tests::time_status_gates_only_time_billing ... ok
[INFO] [stdout] test ocmf::model::tests::identification_errors_are_not_assignments ... ok
[INFO] [stdout] test ocmf::model::tests::every_ending_marker_ends_the_transaction ... ok
[INFO] [stdout] test ocmf::model::tests::only_a_working_meter_is_billable ... ok
[INFO] [stdout] test ocmf::model::tests::pagination_rejects_leading_zeros_and_junk ... ok
[INFO] [stdout] test chain::tests::a_time_error_flag_takes_the_duration_and_nothing_else ... ok
[INFO] [stdout] test ocmf::obis::tests::an_unrecognised_code_is_carried_rather_than_guessed ... ok
[INFO] [stdout] test ocmf::model::tests::units_know_whether_they_are_energy ... ok
[INFO] [stdout] test evidence::tests::a_deleted_middle_record_is_caught_after_verification ... ok
[INFO] [stdout] test chain::tests::mixed_contexts_are_refused ... ok
[INFO] [stdout] test ocmf::obis::tests::two_spellings_of_one_register_are_two_codes ... ok
[INFO] [stdout] test ocmf::parse::tests::a_fiscal_record_has_no_identification_section ... ok
[INFO] [stdout] test ocmf::parse::tests::a_malformed_field_names_itself ... ok
[INFO] [stdout] test ocmf::parse::tests::omitted_fields_carry_forward_within_the_record ... ok
[INFO] [stdout] test ocmf::parse::tests::a_pipe_inside_a_tariff_text_does_not_tear_the_record_in_half ... ok
[INFO] [stdout] test evidence::tests::a_genuine_session_bills ... ok
[INFO] [stdout] test ocmf::parse::tests::parses_the_specification_example ... ok
[INFO] [stdout] test ocmf::model::tests::unknown_error_flags_are_kept_not_dropped ... ok
[INFO] [stdout] test ocmf::parse::tests::base64_signatures_decode_too ... ok
[INFO] [stdout] test ocmf::parse::tests::framing_is_strict ... ok
[INFO] [stdout] test ocmf::parse::tests::missing_mandatory_fields_are_refused ... ok
[INFO] [stdout] test ocmf::parse::tests::reading_values_keep_the_scale_the_meter_stated ... ok
[INFO] [stdout] test ocmf::parse::tests::the_signature_defaults_come_from_the_spec ... ok
[INFO] [stdout] test ocmf::parse::tests::the_signed_span_is_the_text_that_arrived ... ok
[INFO] [stdout] test evidence::tests::the_identification_comes_off_the_records ... ok
[INFO] [stdout] test evidence::tests::a_tampered_value_rates_nothing_and_says_why ... ok
[INFO] [stdout] test ocmf::parse::tests::the_whole_record_survives_as_well_as_the_signed_span ... ok
[INFO] [stdout] test ocmf::parse::tests::time_and_its_status_are_parsed_together ... ok
[INFO] [stdout] test chain::tests::a_register_this_crate_cannot_classify_states_no_direction ... ok
[INFO] [stdout] test ocmf::verify::tests::a_changed_digit_in_the_real_record_is_caught ... ok
[INFO] [stdout] test evidence::tests::the_digest_is_recorded_for_every_verified_record ... ok
[INFO] [stdout] test ocmf::verify::tests::a_fourth_vendors_record_verifies_and_bills ... ok
[INFO] [stdout] test ocmf::verify::tests::a_high_s_signature_from_a_real_meter_verifies ... ok
[INFO] [stdout] test ocmf::verify::tests::a_quoted_reading_value_keeps_its_scale ... ok
[INFO] [stdout] test ocmf::verify::tests::every_key_type_pairs_with_a_supported_algorithm ... ok
[INFO] [stdout] test ocmf::verify::tests::a_real_secp192r1_meter_record_verifies ... ok
[INFO] [stdout] test ocmf::verify::tests::a_genuine_signature_verifies ... ok
[INFO] [stdout] test ocmf::verify::tests::a_curve_mismatch_says_so_rather_than_failing_vaguely ... ok
[INFO] [stdout] test ocmf::verify::tests::a_single_changed_digit_fails ... ok
[INFO] [stdout] test ocmf::verify::tests::brainpool_is_refused_loudly_not_silently ... ok
[INFO] [stdout] test ocmf::verify::tests::a_compressed_sec1_key_works_too ... ok
[INFO] [stdout] test ocmf::verify::tests::key_type_accepts_the_synonyms_registries_actually_use ... ok
[INFO] [stdout] test registry::tests::a_meter_serial_finds_its_key ... ok
[INFO] [stdout] test registry::tests::a_shared_gateway_needs_both_serials ... ok
[INFO] [stdout] test registry::tests::an_unregistered_component_says_so ... ok
[INFO] [stdout] test registry::tests::a_record_naming_no_component_cannot_be_verified ... ok
[INFO] [stdout] test ocmf::verify::tests::reformatting_the_payload_breaks_the_signature ... ok
[INFO] [stdout] test ocmf::verify::tests::a_different_key_fails ... ok
[INFO] [stdout] test ocmf::verify::tests::normalising_s_does_not_make_a_forgery_verify ... ok
[INFO] [stdout] test transparency::tests::a_container_this_crate_did_not_write_reads_and_verifies ... ok
[INFO] [stdout] test ocmf::verify::tests::the_digest_is_a_stable_content_address ... ok
[INFO] [stdout] test ocmf::verify::tests::the_fixed_width_integers_real_meters_emit_are_accepted ... ok
[INFO] [stdout] test registry::tests::a_gap_between_windows_is_a_gap_rather_than_a_guess ... ok
[INFO] [stdout] test ocmf::verify::tests::a_signature_that_is_not_two_integers_is_refused ... ok
[INFO] [stdout] test registry::tests::a_key_swap_keeps_old_sessions_verifiable ... ok
[INFO] [stdout] test ocmf::obis::tests::ordinary_iec_codes_still_state_a_direction ... ok
[INFO] [stdout] test ocmf::obis::tests::the_code_survives_as_text ... ok
[INFO] [stdout] test ocmf::obis::tests::the_ocmf_reserved_range_states_direction_scope_and_point ... ok
[INFO] [stdout] test transparency::tests::a_container_this_reader_does_not_understand_is_refused_rather_than_guessed ... ok
[INFO] [stdout] test transparency::tests::a_record_that_did_not_verify_has_no_key_binding_to_export ... ok
[INFO] [stdout] test transparency::tests::a_key_the_file_left_out_is_absent_rather_than_invented ... ok
[INFO] [stdout] test transparency::tests::a_record_that_is_a_whole_transaction_is_labelled_neither_half ... ok
[INFO] [stdout] test transparency::tests::a_file_this_crate_wrote_reads_back_byte_for_byte ... ok
[INFO] [stdout] test transparency::tests::an_empty_evidence_record_produces_a_well_formed_empty_file ... ok
[INFO] [stdout] test transparency::tests::an_ampersand_in_a_tariff_text_does_not_break_the_file ... ok
[INFO] [stdout] test transparency::tests::a_session_that_does_not_bill_still_gets_a_file ... ok
[INFO] [stdout] test transparency::tests::the_context_labels_are_the_ones_the_verifier_names ... ok
[INFO] [stdout] test transparency::tests::an_escaped_record_survives_the_round_trip ... ok
[INFO] [stdout] test transparency::tests::the_file_holds_each_record_verbatim_beside_the_key_that_checked_it ... ok
[INFO] [stdout] test transparency::tests::quotes_are_left_alone_in_the_dataset ... ok
[INFO] [stdout] test transparency::tests::the_elements_come_out_in_the_order_the_schema_sequences_them ... ok
[INFO] [stdout] test transparency::tests::every_record_of_one_session_carries_one_transaction_id ... ok
[INFO] [stdout] test transparency::tests::the_operators_own_transaction_number_can_be_used_instead ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 115 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.03s
[INFO] [stdout] 
[INFO] [stderr]    Doc-tests emob_eichrecht
[INFO] [stdout] 
[INFO] [stdout] running 5 tests
[INFO] [stdout] test src/ocmf/verify.rs - ocmf::verify::verify (line 236) - compile ... ok
[INFO] [stdout] test src/ocmf/parse.rs - ocmf::parse::parse (line 110) ... ok
[INFO] [stdout] test src/lib.rs - (line 48) ... ok
[INFO] [stdout] test src/chain.rs - chain::validate (line 522) ... ok
[INFO] [stdout] test src/transparency.rs - transparency::to_xml (line 89) ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 5 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s
[INFO] [stdout] 
[INFO] [stdout] all doctests ran in 0.40s; merged doctests compilation took 0.39s
[INFO] running `Command { std: "docker" "inspect" "62a2bd4e9383c440e23770e76c63b89dc7434f763d060e0dc204098a28d9e5a5", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "62a2bd4e9383c440e23770e76c63b89dc7434f763d060e0dc204098a28d9e5a5", kill_on_drop: false }`
[INFO] [stdout] 62a2bd4e9383c440e23770e76c63b89dc7434f763d060e0dc204098a28d9e5a5
