[INFO] fetching crate proto-types 0.2.2...
[INFO] testing proto-types-0.2.2 against master#562dee4820c458d823175268e41601d4c060588a for pr-154210-2
[INFO] extracting crate proto-types 0.2.2 into /workspace/builds/worker-7-tc1/source
[INFO] started tweaking crates.io crate proto-types 0.2.2
[INFO] finished tweaking crates.io crate proto-types 0.2.2
[INFO] tweaked toml for crates.io crate proto-types 0.2.2 written to /workspace/builds/worker-7-tc1/source/Cargo.toml
[INFO] validating manifest of crates.io crate proto-types 0.2.2 on toolchain 562dee4820c458d823175268e41601d4c060588a
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+562dee4820c458d823175268e41601d4c060588a" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate crates.io crate proto-types 0.2.2 already has a lockfile, it will not be regenerated
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+562dee4820c458d823175268e41601d4c060588a" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr]     Updating crates.io index
[INFO] [stderr]  Downloading crates ...
[INFO] [stderr]   Downloaded dsl_auto_type v0.2.0
[INFO] [stderr]   Downloaded murmur3 v0.4.1
[INFO] [stderr]   Downloaded diesel_table_macro_syntax v0.3.0
[INFO] [stderr]   Downloaded better_any v0.2.1
[INFO] [stderr]   Downloaded rsqlite-vfs v0.1.0
[INFO] [stderr]   Downloaded diesel_derives v2.3.7
[INFO] [stderr]   Downloaded antlr4rust v0.5.2
[INFO] [stderr]   Downloaded cel v0.12.0
[INFO] [stderr]   Downloaded diesel v2.3.6
[INFO] [stderr]   Downloaded libsqlite3-sys v0.35.0
[INFO] [stderr]   Downloaded sqlite-wasm-rs v0.5.2
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-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" "+562dee4820c458d823175268e41601d4c060588a" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] 50f697ad4f31918fbf19a78c81654e5805eaed1d287dc166feb711351025c01a
[INFO] running `Command { std: "docker" "start" "-a" "50f697ad4f31918fbf19a78c81654e5805eaed1d287dc166feb711351025c01a", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "50f697ad4f31918fbf19a78c81654e5805eaed1d287dc166feb711351025c01a", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "50f697ad4f31918fbf19a78c81654e5805eaed1d287dc166feb711351025c01a", kill_on_drop: false }`
[INFO] [stdout] 50f697ad4f31918fbf19a78c81654e5805eaed1d287dc166feb711351025c01a
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-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=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-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" "+562dee4820c458d823175268e41601d4c060588a" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] d5d7f92e325e74cce45492599993f79983d7ad7920ad091375c7bb1b85e9dea4
[INFO] running `Command { std: "docker" "start" "-a" "d5d7f92e325e74cce45492599993f79983d7ad7920ad091375c7bb1b85e9dea4", kill_on_drop: false }`
[INFO] [stderr]    Compiling quote v1.0.44
[INFO] [stderr]    Compiling either v1.15.0
[INFO] [stderr]    Compiling thiserror v2.0.18
[INFO] [stderr]    Compiling bytes v1.11.0
[INFO] [stderr]    Compiling proc-macro2 v1.0.106
[INFO] [stderr]    Compiling num-traits v0.2.19
[INFO] [stderr]    Compiling itertools v0.14.0
[INFO] [stderr]    Compiling syn v2.0.114
[INFO] [stderr]    Compiling chrono v0.4.43
[INFO] [stderr]    Compiling prost-derive v0.14.3
[INFO] [stderr]    Compiling thiserror-impl v2.0.18
[INFO] [stderr]    Compiling prost v0.14.3
[INFO] [stderr]    Compiling proto-types v0.2.2 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 10.01s
[INFO] running `Command { std: "docker" "inspect" "d5d7f92e325e74cce45492599993f79983d7ad7920ad091375c7bb1b85e9dea4", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "d5d7f92e325e74cce45492599993f79983d7ad7920ad091375c7bb1b85e9dea4", kill_on_drop: false }`
[INFO] [stdout] d5d7f92e325e74cce45492599993f79983d7ad7920ad091375c7bb1b85e9dea4
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-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=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-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" "+562dee4820c458d823175268e41601d4c060588a" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] b6c1c5aa661e37217369888639cfefe865c205e69613e42f81361b3615f8246c
[INFO] running `Command { std: "docker" "start" "-a" "b6c1c5aa661e37217369888639cfefe865c205e69613e42f81361b3615f8246c", kill_on_drop: false }`
[INFO] [stderr]    Compiling proto-types v0.2.2 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 2.07s
[INFO] running `Command { std: "docker" "inspect" "b6c1c5aa661e37217369888639cfefe865c205e69613e42f81361b3615f8246c", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "b6c1c5aa661e37217369888639cfefe865c205e69613e42f81361b3615f8246c", kill_on_drop: false }`
[INFO] [stdout] b6c1c5aa661e37217369888639cfefe865c205e69613e42f81361b3615f8246c
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-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=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-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" "+562dee4820c458d823175268e41601d4c060588a" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] ffd352741cd0a1acfa888f131c11f5e6e42871dc1ab842ce3b45829f70e336eb
[INFO] running `Command { std: "docker" "start" "-a" "ffd352741cd0a1acfa888f131c11f5e6e42871dc1ab842ce3b45829f70e336eb", kill_on_drop: false }`
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.12s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/proto_types-7c2b838864d5e429)
[INFO] [stdout] 
[INFO] [stdout] running 65 tests
[INFO] [stdout] test duration::duration_operations::tests::chrono_test::test_add_sub ... ok
[INFO] [stdout] test duration::duration_operations::tests::std_test::test_eq_ord ... ok
[INFO] [stdout] test duration::duration_operations::tests::test_div_bug_fix ... ok
[INFO] [stdout] test duration::duration_operations::tests::chrono_test::test_eq_ord ... ok
[INFO] [stdout] test duration::duration_operations::tests::test_eq_ord ... ok
[INFO] [stdout] test duration::duration_operations::tests::test_div_edge_cases ... ok
[INFO] [stdout] test duration::duration_operations::tests::test_get_data_smoke_test ... ok
[INFO] [stdout] test duration::duration_operations::tests::test_add_sub ... ok
[INFO] [stdout] test duration::duration_operations::tests::test_mul_overflow_checks ... ok
[INFO] [stdout] test duration::duration_operations::tests::std_test::test_add_sub ... ok
[INFO] [stdout] test duration::formatting::tests::test_duration_display_cases ... ok
[INFO] [stdout] test duration::formatting::tests::test_canonical_display ... ok
[INFO] [stdout] test duration::formatting::tests::test_duration_display_negative ... ok
[INFO] [stdout] test duration::formatting::tests::test_get_data_basic_units ... ok
[INFO] [stdout] test duration::formatting::tests::test_format_if_nonzero ... ok
[INFO] [stdout] test duration::formatting::tests::test_get_data_greedy_decomposition ... ok
[INFO] [stdout] test duration::formatting::tests::test_get_data_negative ... ok
[INFO] [stdout] test duration::formatting::tests::test_unit_display_formatting ... ok
[INFO] [stdout] test duration::test::basic_tests ... ok
[INFO] [stdout] test timestamp::timestamp_conversions::tests::chrono_tests::test_chrono_naive_utc_assumption ... ok
[INFO] [stdout] test timestamp::timestamp_conversions::tests::chrono_tests::test_chrono_utc_roundtrip ... ok
[INFO] [stdout] test timestamp::timestamp_conversions::tests::chrono_tests::test_fixed_offset_conversions ... ok
[INFO] [stdout] test timestamp::timestamp_conversions::tests::test_display_rfc3339 ... ok
[INFO] [stdout] test timestamp::timestamp_conversions::tests::test_from_str_rfc3339 ... ok
[INFO] [stdout] test timestamp::timestamp_conversions::tests::test_string_roundtrip ... ok
[INFO] [stdout] test timestamp::timestamp_conversions::tests::test_system_time_epoch ... ok
[INFO] [stdout] test timestamp::timestamp_conversions::tests::test_system_time_pre_epoch ... ok
[INFO] [stdout] test timestamp::timestamp_conversions::tests::test_system_time_roundtrip ... ok
[INFO] [stdout] test timestamp::timestamp_impls::tests::test_from_unix_millis_negative ... ok
[INFO] [stdout] test timestamp::timestamp_impls::tests::test_from_unix_millis_positive ... ok
[INFO] [stdout] test timestamp::timestamp_impls::tests::test_from_unix_millis_zero ... ok
[INFO] [stdout] test timestamp::timestamp_impls::tests::test_is_future ... ok
[INFO] [stdout] test timestamp::timestamp_impls::tests::test_is_past ... ok
[INFO] [stdout] test timestamp::timestamp_impls::tests::test_is_within_future_range ... ok
[INFO] [stdout] test timestamp::timestamp_impls::tests::test_is_within_past_range ... ok
[INFO] [stdout] test timestamp::timestamp_impls::tests::test_round_trip_millis ... ok
[INFO] [stdout] test timestamp::timestamp_impls::tests::test_total_millis_basic ... ok
[INFO] [stdout] test timestamp::timestamp_impls::tests::test_total_millis_negative_normalization ... ok
[INFO] [stdout] test timestamp::timestamp_impls::tests::test_total_millis_overflow ... ok
[INFO] [stdout] test timestamp::timestamp_operations::tests::chrono_test::test_add_saturation_max ... ok
[INFO] [stdout] test timestamp::timestamp_impls::tests::test_total_millis_boundary ... ok
[INFO] [stdout] test timestamp::timestamp_operations::tests::chrono_test::test_subtraction_borrowing_nanos ... ok
[INFO] [stdout] test timestamp::timestamp_operations::tests::chrono_test::test_simple_addition ... ok
[INFO] [stdout] test timestamp::timestamp_operations::tests::chrono_test::test_sub_saturation_min ... ok
[INFO] [stdout] test timestamp::timestamp_operations::tests::chrono_test::test_nano_overflow_addition ... ok
[INFO] [stdout] test timestamp::timestamp_operations::tests::std_duration::test_nano_overflow_addition ... ok
[INFO] [stdout] test timestamp::timestamp_operations::tests::chrono_test::test_subtraction_crossing_zero ... ok
[INFO] [stdout] test timestamp::timestamp_operations::tests::std_duration::test_simple_addition ... ok
[INFO] [stdout] test timestamp::timestamp_operations::tests::chrono_test::test_simple_subtraction ... ok
[INFO] [stdout] test timestamp::timestamp_operations::tests::std_duration::test_simple_subtraction ... ok
[INFO] [stdout] test timestamp::timestamp_operations::tests::std_duration::test_add_saturation_max ... ok
[INFO] [stdout] test timestamp::timestamp_operations::tests::std_duration::test_subtraction_borrowing_nanos ... ok
[INFO] [stdout] test timestamp::timestamp_operations::tests::std_duration::test_subtraction_crossing_zero ... ok
[INFO] [stdout] test timestamp::timestamp_operations::tests::test_add_saturation_max ... ok
[INFO] [stdout] test timestamp::timestamp_operations::tests::std_duration::test_add_saturation_with_nanos ... ok
[INFO] [stdout] test timestamp::timestamp_operations::tests::test_add_saturation_with_nanos ... ok
[INFO] [stdout] test timestamp::timestamp_operations::tests::std_duration::test_sub_saturation_min ... ok
[INFO] [stdout] test timestamp::timestamp_operations::tests::test_nano_overflow_addition ... ok
[INFO] [stdout] test timestamp::timestamp_operations::tests::test_simple_addition ... ok
[INFO] [stdout] test timestamp::timestamp_operations::tests::test_simple_subtraction ... ok
[INFO] [stdout] test timestamp::timestamp_operations::tests::test_sub_double_negative_saturation ... ok
[INFO] [stdout] test timestamp::timestamp_operations::tests::test_sub_saturation_min ... ok
[INFO] [stdout] test timestamp::timestamp_operations::tests::test_subtraction_crossing_zero ... ok
[INFO] [stdout] test timestamp::timestamp_operations::tests::test_subtraction_borrowing_nanos ... ok
[INFO] [stdout] test timestamp::timestamp_operations::tests::test_add_negative_duration ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 65 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s
[INFO] [stdout] 
[INFO] [stderr]    Doc-tests proto_types
[INFO] [stdout] 
[INFO] [stdout] running 2 tests
[INFO] [stdout] test src/timestamp/timestamp_impls.rs - timestamp::timestamp_impls::Timestamp::from_unix_millis (line 53) ... ok
[INFO] [stdout] test src/timestamp/timestamp_impls.rs - timestamp::timestamp_impls::Timestamp::checked_total_i64_millis (line 90) ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 2 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s
[INFO] [stdout] 
[INFO] [stdout] all doctests ran in 0.53s; merged doctests compilation took 0.52s
[INFO] running `Command { std: "docker" "inspect" "ffd352741cd0a1acfa888f131c11f5e6e42871dc1ab842ce3b45829f70e336eb", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "ffd352741cd0a1acfa888f131c11f5e6e42871dc1ab842ce3b45829f70e336eb", kill_on_drop: false }`
[INFO] [stdout] ffd352741cd0a1acfa888f131c11f5e6e42871dc1ab842ce3b45829f70e336eb