[INFO] cloning repository https://github.com/gventino/like-a-clockwork
[INFO] running `Command { std: "git" "-c" "credential.helper=" "-c" "credential.helper=/workspace/cargo-home/bin/git-credential-null" "clone" "--bare" "https://github.com/gventino/like-a-clockwork" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fgventino%2Flike-a-clockwork", kill_on_drop: false }`
[INFO] [stderr] Cloning into bare repository '/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fgventino%2Flike-a-clockwork'...
[INFO] running `Command { std: "git" "rev-parse" "HEAD", kill_on_drop: false }`
[INFO] [stdout] feca3be53997e3d97e98b2d70dc950b204c6b80a
[INFO] testing gventino/like-a-clockwork against 1.97.0-beta.6 for beta-1.98-2
[INFO] running `Command { std: "git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fgventino%2Flike-a-clockwork" "/workspace/builds/worker-6-tc1/source", kill_on_drop: false }`
[INFO] [stderr] Cloning into '/workspace/builds/worker-6-tc1/source'...
[INFO] [stderr] done.
[INFO] started tweaking git repo https://github.com/gventino/like-a-clockwork
[INFO] finished tweaking git repo https://github.com/gventino/like-a-clockwork
[INFO] tweaked toml for git repo https://github.com/gventino/like-a-clockwork written to /workspace/builds/worker-6-tc1/source/Cargo.toml
[INFO] validating manifest of git repo https://github.com/gventino/like-a-clockwork on toolchain 1.97.0-beta.6
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+1.97.0-beta.6" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate git repo https://github.com/gventino/like-a-clockwork 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.97.0-beta.6" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-m" "1610612736" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:3d5ced03c013a94a2f102a4510f48a6e9184255caf5fd8244f58017bde7f5210" "sleep" "infinity", kill_on_drop: false }`
[INFO] [stdout] 43803a40118f1fbc29dba1545381c23b8219e2ba52d163974a1e28023d8c928f
[INFO] running `Command { std: "docker" "start" "43803a40118f1fbc29dba1545381c23b8219e2ba52d163974a1e28023d8c928f", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "43803a40118f1fbc29dba1545381c23b8219e2ba52d163974a1e28023d8c928f", 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" "43803a40118f1fbc29dba1545381c23b8219e2ba52d163974a1e28023d8c928f" "/opt/rustwide/cargo-home/bin/cargo" "+1.97.0-beta.6" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "43803a40118f1fbc29dba1545381c23b8219e2ba52d163974a1e28023d8c928f", 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" "43803a40118f1fbc29dba1545381c23b8219e2ba52d163974a1e28023d8c928f" "/opt/rustwide/cargo-home/bin/cargo" "+1.97.0-beta.6" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stderr]    Compiling libc v0.2.183
[INFO] [stderr]    Compiling num-traits v0.2.19
[INFO] [stderr]    Compiling syn v2.0.117
[INFO] [stderr]    Compiling rmp v0.8.15
[INFO] [stderr]    Compiling getrandom v0.4.2
[INFO] [stderr]    Compiling uuid v1.22.0
[INFO] [stderr]    Compiling serde_derive v1.0.228
[INFO] [stderr]    Compiling thiserror-impl v2.0.18
[INFO] [stderr]    Compiling thiserror v2.0.18
[INFO] [stderr]    Compiling serde v1.0.228
[INFO] [stderr]    Compiling rmp-serde v1.3.1
[INFO] [stderr]    Compiling like-a-clockwork v0.1.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 9.52s
[INFO] running `Command { std: "docker" "inspect" "43803a40118f1fbc29dba1545381c23b8219e2ba52d163974a1e28023d8c928f", 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" "43803a40118f1fbc29dba1545381c23b8219e2ba52d163974a1e28023d8c928f" "/opt/rustwide/cargo-home/bin/cargo" "+1.97.0-beta.6" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stderr]    Compiling like-a-clockwork v0.1.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 2.11s
[INFO] running `Command { std: "docker" "inspect" "43803a40118f1fbc29dba1545381c23b8219e2ba52d163974a1e28023d8c928f", 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" "43803a40118f1fbc29dba1545381c23b8219e2ba52d163974a1e28023d8c928f" "/opt/rustwide/cargo-home/bin/cargo" "+1.97.0-beta.6" "test" "--frozen", kill_on_drop: false }`
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.08s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/like_a_clockwork-53764fced7f18f42)
[INFO] [stdout] 
[INFO] [stdout] running 130 tests
[INFO] [stdout] test causality::tests::compare_concurrent ... ok
[INFO] [stdout] test causality::tests::compare_equal_vectors ... ok
[INFO] [stdout] test causality::tests::compare_inverse_symmetry ... ok
[INFO] [stdout] test causality::tests::display_happens_after ... ok
[INFO] [stdout] test causality::tests::display_happens_before ... ok
[INFO] [stdout] test causality::tests::compare_less_or_equal_with_one_strict ... ok
[INFO] [stdout] test causality::tests::compare_strictly_greater ... ok
[INFO] [stdout] test causality::tests::double_inverse_is_identity ... ok
[INFO] [stdout] test causality::tests::compare_with_different_sized_vectors ... ok
[INFO] [stdout] test causality::tests::display_concurrent ... ok
[INFO] [stdout] test causality::tests::display_equal ... ok
[INFO] [stdout] test causality::tests::inverse_concurrent_returns_concurrent ... ok
[INFO] [stdout] test causality::tests::compare_strictly_less ... ok
[INFO] [stdout] test causality::tests::inverse_equal_returns_equal ... ok
[INFO] [stdout] test causality::tests::inverse_happens_before_returns_happens_after ... ok
[INFO] [stdout] test causality::tests::is_causal_true_for_happens_after ... ok
[INFO] [stdout] test causality::tests::is_causal_true_for_happens_before ... ok
[INFO] [stdout] test causality::tests::is_concurrent_false_for_others ... ok
[INFO] [stdout] test causality::tests::is_concurrent_true_for_concurrent ... ok
[INFO] [stdout] test causality::tests::is_causal_false_for_concurrent ... ok
[INFO] [stdout] test event::tests::accessors_return_correct_values ... ok
[INFO] [stdout] test causality::tests::is_causal_false_for_equal ... ok
[INFO] [stdout] test event::tests::from_headers_invalid_vector_returns_error ... ok
[INFO] [stdout] test event::tests::from_headers_roundtrip ... ok
[INFO] [stdout] test event::tests::new_captures_vector_timestamp ... ok
[INFO] [stdout] test event::tests::from_json_roundtrip ... ok
[INFO] [stdout] test event::tests::from_headers_missing_vector_returns_error ... ok
[INFO] [stdout] test event::tests::new_rejects_empty_event_type ... ok
[INFO] [stdout] test event::tests::new_generates_unique_event_ids ... ok
[INFO] [stdout] test event::tests::new_preserves_payload ... ok
[INFO] [stdout] test event::tests::new_preserves_event_type ... ok
[INFO] [stdout] test causality::tests::serde_json_roundtrip_all_variants ... ok
[INFO] [stdout] test causality::tests::inverse_happens_after_returns_happens_before ... ok
[INFO] [stdout] test event::tests::from_headers_missing_event_id_returns_error ... ok
[INFO] [stdout] test event::tests::to_json_contains_causality_field ... ok
[INFO] [stdout] test event::tests::new_accepts_empty_payload ... ok
[INFO] [stdout] test lamport::tests::new_creates_clock_with_zero_time ... ok
[INFO] [stdout] test lamport::tests::receive_advances_past_remote_timestamp ... ok
[INFO] [stdout] test lamport::tests::receive_with_equal_remote_increments ... ok
[INFO] [stdout] test event::tests::serde_json_roundtrip ... ok
[INFO] [stdout] test event::tests::to_headers_contains_event_id ... ok
[INFO] [stdout] test event::tests::to_headers_contains_event_type ... ok
[INFO] [stdout] test event::tests::to_headers_contains_vector_clock ... ok
[INFO] [stdout] test lamport::tests::serde_json_roundtrip_clock ... ok
[INFO] [stdout] test lamport::tests::tick_increments_monotonically ... ok
[INFO] [stdout] test lamport::tests::send_timestamp_contains_correct_node_id ... ok
[INFO] [stdout] test lamport::tests::tick_returns_one_on_first_call ... ok
[INFO] [stdout] test lamport::tests::timestamp_ordering_tiebreak_by_node_id ... ok
[INFO] [stdout] test lamport::tests::timestamp_parse_empty_node_id ... ok
[INFO] [stdout] test lamport::tests::timestamp_ordering_by_time ... ok
[INFO] [stdout] test lamport::tests::timestamp_parse_invalid_time ... ok
[INFO] [stdout] test lamport::tests::timestamp_parse_missing_delimiter ... ok
[INFO] [stdout] test lamport::tests::timestamp_parse_roundtrip ... ok
[INFO] [stdout] test transport::binary::tests::binary_extract_event_roundtrip ... ok
[INFO] [stdout] test transport::binary::tests::binary_extract_lamport_roundtrip ... ok
[INFO] [stdout] test transport::binary::tests::binary_extract_lamport_missing_returns_none ... ok
[INFO] [stdout] test transport::binary::tests::binary_extract_vector_corrupt_bytes_returns_err ... ok
[INFO] [stdout] test transport::binary::tests::binary_extract_vector_roundtrip ... ok
[INFO] [stdout] test lamport::tests::serde_json_roundtrip_timestamp ... ok
[INFO] [stdout] test transport::binary::tests::binary_inject_event_sets_all_headers ... ok
[INFO] [stdout] test transport::binary::tests::binary_inject_lamport_sets_utf8_bytes ... ok
[INFO] [stdout] test event::tests::to_headers_does_not_include_payload ... ok
[INFO] [stdout] test transport::binary::tests::binary_inject_vector_produces_msgpack ... ok
[INFO] [stdout] test transport::binary::tests::binary_msgpack_is_compact ... ok
[INFO] [stdout] test transport::json::tests::json_extract_missing_causality_returns_err ... ok
[INFO] [stdout] test transport::json::tests::json_has_causality_true_when_present ... ok
[INFO] [stdout] test transport::json::tests::json_has_causality_false_when_absent ... ok
[INFO] [stdout] test transport::binary::tests::binary_extract_vector_missing_returns_none ... ok
[INFO] [stdout] test lamport::tests::receive_with_lower_remote_still_increments ... ok
[INFO] [stdout] test lamport::tests::new_preserves_node_id ... ok
[INFO] [stdout] test transport::json::tests::json_inject_adds_causality_field ... ok
[INFO] [stdout] test lamport::tests::timestamp_accessors_return_correct_values ... ok
[INFO] [stdout] test transport::tests::hashmap_binary_header_map_keys ... ok
[INFO] [stdout] test transport::json::tests::json_inject_then_extract_roundtrip ... ok
[INFO] [stdout] test transport::tests::hashmap_header_map_get_missing_returns_none ... ok
[INFO] [stdout] test transport::tests::hashmap_binary_header_map_set_and_get ... ok
[INFO] [stdout] test transport::text::tests::text_extract_event_roundtrip ... ok
[INFO] [stdout] test transport::tests::hashmap_binary_header_map_get_missing_returns_none ... ok
[INFO] [stdout] test lamport::tests::timestamp_display_format ... ok
[INFO] [stdout] test transport::json::tests::json_extract_returns_payload_and_timestamp ... ok
[INFO] [stdout] test transport::tests::hashmap_header_map_set_and_get ... ok
[INFO] [stdout] test transport::tests::hashmap_header_map_keys ... ok
[INFO] [stdout] test lamport::tests::send_increments_and_returns_timestamp ... ok
[INFO] [stdout] test transport::text::tests::text_extract_event_missing_required_header_returns_err ... ok
[INFO] [stdout] test transport::text::tests::text_extract_lamport_malformed_returns_err ... ok
[INFO] [stdout] test transport::text::tests::text_extract_lamport_missing_returns_none ... ok
[INFO] [stdout] test transport::text::tests::text_extract_lamport_roundtrip ... ok
[INFO] [stdout] test transport::text::tests::text_extract_vector_missing_returns_none ... ok
[INFO] [stdout] test transport::text::tests::text_extract_vector_malformed_returns_err ... ok
[INFO] [stdout] test transport::text::tests::text_extract_vector_roundtrip ... ok
[INFO] [stdout] test transport::text::tests::text_inject_event_sets_all_headers ... ok
[INFO] [stdout] test transport::text::tests::text_inject_vector_sets_correct_header ... ok
[INFO] [stdout] test transport::text::tests::text_inject_lamport_sets_correct_header ... ok
[INFO] [stdout] test vector::tests::from_map_preserves_values ... ok
[INFO] [stdout] test vector::tests::concurrent_writes_detected ... ok
[INFO] [stdout] test vector::tests::fork_join_causal_graph ... ok
[INFO] [stdout] test vector::tests::get_returns_zero_for_unknown_node ... ok
[INFO] [stdout] test vector::tests::new_includes_self_in_clocks ... ok
[INFO] [stdout] test vector::tests::new_initializes_all_clocks_to_zero ... ok
[INFO] [stdout] test vector::tests::merge_takes_max_without_incrementing ... ok
[INFO] [stdout] test vector::tests::merge_adds_unknown_nodes ... ok
[INFO] [stdout] test vector::tests::merge_is_commutative ... ok
[INFO] [stdout] test vector::tests::get_returns_value_for_known_node ... ok
[INFO] [stdout] test vector::tests::receive_takes_max_for_all_nodes ... ok
[INFO] [stdout] test vector::tests::receive_takes_max_then_increments_local ... ok
[INFO] [stdout] test vector::tests::receive_with_lower_remote_still_increments_local ... ok
[INFO] [stdout] test vector::tests::receive_with_unknown_node_adds_it ... ok
[INFO] [stdout] test vector::tests::relation_delegates_to_compare ... ok
[INFO] [stdout] test vector::tests::send_increments_and_returns_snapshot ... ok
[INFO] [stdout] test vector::tests::send_snapshot_is_independent_copy ... ok
[INFO] [stdout] test vector::tests::serde_json_roundtrip_vector_clock ... ok
[INFO] [stdout] test vector::tests::serde_json_roundtrip_vector_timestamp ... ok
[INFO] [stdout] test vector::tests::snapshot_does_not_mutate_clock ... ok
[INFO] [stdout] test vector::tests::snapshot_returns_current_state ... ok
[INFO] [stdout] test vector::tests::three_process_message_chain ... ok
[INFO] [stdout] test lamport::tests::new_rejects_node_id_with_colon - should panic ... ok
[INFO] [stdout] test lamport::tests::new_rejects_empty_node_id - should panic ... ok
[INFO] [stdout] test vector::tests::new_rejects_empty_node_id - should panic ... ok
[INFO] [stdout] test vector::tests::tick_does_not_affect_other_nodes ... ok
[INFO] [stdout] test vector::tests::tick_increments_only_local_node ... ok
[INFO] [stdout] test vector::tests::new_rejects_invalid_chars_in_node_id - should panic ... ok
[INFO] [stdout] test vector::tests::timestamp_display_format ... ok
[INFO] [stdout] test vector::tests::new_rejects_duplicate_peer - should panic ... ok
[INFO] [stdout] test vector::tests::timestamp_parse_empty_node_id ... ok
[INFO] [stdout] test vector::tests::timestamp_parse_empty_string ... ok
[INFO] [stdout] test vector::tests::timestamp_parse_invalid_time ... ok
[INFO] [stdout] test vector::tests::timestamp_parse_missing_delimiter ... ok
[INFO] [stdout] test vector::tests::timestamp_parse_roundtrip ... ok
[INFO] [stdout] test event::tests::with_id_uses_provided_id ... ok
[INFO] [stdout] test transport::json::tests::json_inject_overwrites_existing_causality ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 130 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.14s
[INFO] [stdout] 
[INFO] [stderr]    Doc-tests like_a_clockwork
[INFO] [stdout] 
[INFO] [stdout] running 0 tests
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] running `Command { std: "docker" "inspect" "43803a40118f1fbc29dba1545381c23b8219e2ba52d163974a1e28023d8c928f", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "43803a40118f1fbc29dba1545381c23b8219e2ba52d163974a1e28023d8c928f", kill_on_drop: false }`
[INFO] [stdout] 43803a40118f1fbc29dba1545381c23b8219e2ba52d163974a1e28023d8c928f
