[INFO] fetching crate foca 0.19.0...
[INFO] testing foca-0.19.0 against try#b83b707f97d809763b7861afa7638871f3339a33 for pr-145838-1
[INFO] extracting crate foca 0.19.0 into /workspace/builds/worker-2-tc2/source
[INFO] started tweaking crates.io crate foca 0.19.0
[INFO] removed 0 missing examples
[INFO] finished tweaking crates.io crate foca 0.19.0
[INFO] tweaked toml for crates.io crate foca 0.19.0 written to /workspace/builds/worker-2-tc2/source/Cargo.toml
[INFO] validating manifest of crates.io crate foca 0.19.0 on toolchain b83b707f97d809763b7861afa7638871f3339a33
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+b83b707f97d809763b7861afa7638871f3339a33" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate crates.io crate foca 0.19.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" "+b83b707f97d809763b7861afa7638871f3339a33" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-tc2/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:7ad1b28ee6f5f7f699f6cf7015098d6ccdd96d6f2d78dd06228f5b4c9faf309c" "/opt/rustwide/cargo-home/bin/cargo" "+b83b707f97d809763b7861afa7638871f3339a33" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] 84695d1137fdfc5ad3651313099f7a2a6d4086979eba6b0e90d0f282bc84ec55
[INFO] running `Command { std: "docker" "start" "-a" "84695d1137fdfc5ad3651313099f7a2a6d4086979eba6b0e90d0f282bc84ec55", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "84695d1137fdfc5ad3651313099f7a2a6d4086979eba6b0e90d0f282bc84ec55", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "84695d1137fdfc5ad3651313099f7a2a6d4086979eba6b0e90d0f282bc84ec55", kill_on_drop: false }`
[INFO] [stdout] 84695d1137fdfc5ad3651313099f7a2a6d4086979eba6b0e90d0f282bc84ec55
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-tc2/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:7ad1b28ee6f5f7f699f6cf7015098d6ccdd96d6f2d78dd06228f5b4c9faf309c" "/opt/rustwide/cargo-home/bin/cargo" "+b83b707f97d809763b7861afa7638871f3339a33" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 38e1bdf5459a23a986a578c0ecdf1bca234b5a8d768c844a1763c92461d87072
[INFO] running `Command { std: "docker" "start" "-a" "38e1bdf5459a23a986a578c0ecdf1bca234b5a8d768c844a1763c92461d87072", kill_on_drop: false }`
[INFO] [stderr]    Compiling rand_core v0.9.3
[INFO] [stderr]    Compiling bytes v1.7.2
[INFO] [stderr]    Compiling rand v0.9.0
[INFO] [stderr]    Compiling foca v0.19.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 1.63s
[INFO] running `Command { std: "docker" "inspect" "38e1bdf5459a23a986a578c0ecdf1bca234b5a8d768c844a1763c92461d87072", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "38e1bdf5459a23a986a578c0ecdf1bca234b5a8d768c844a1763c92461d87072", kill_on_drop: false }`
[INFO] [stdout] 38e1bdf5459a23a986a578c0ecdf1bca234b5a8d768c844a1763c92461d87072
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-tc2/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:7ad1b28ee6f5f7f699f6cf7015098d6ccdd96d6f2d78dd06228f5b4c9faf309c" "/opt/rustwide/cargo-home/bin/cargo" "+b83b707f97d809763b7861afa7638871f3339a33" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] c89a14461158a9704eb1213606910ff532a733fcdf0fa231d6a474ee954d84ba
[INFO] running `Command { std: "docker" "start" "-a" "c89a14461158a9704eb1213606910ff532a733fcdf0fa231d6a474ee954d84ba", kill_on_drop: false }`
[INFO] [stderr]    Compiling proc-macro2 v1.0.87
[INFO] [stderr]    Compiling libc v0.2.159
[INFO] [stderr]    Compiling getrandom v0.3.2
[INFO] [stderr]    Compiling tracing-core v0.1.32
[INFO] [stderr]    Compiling serde v1.0.210
[INFO] [stderr]    Compiling regex-automata v0.4.8
[INFO] [stderr]    Compiling thread_local v1.1.8
[INFO] [stderr]    Compiling clap v2.34.0
[INFO] [stderr]    Compiling tracing-log v0.2.0
[INFO] [stderr]    Compiling tracing v0.1.40
[INFO] [stderr]    Compiling quote v1.0.37
[INFO] [stderr]    Compiling mio v1.0.2
[INFO] [stderr]    Compiling socket2 v0.5.7
[INFO] [stderr]    Compiling getrandom v0.2.15
[INFO] [stderr]    Compiling syn v2.0.79
[INFO] [stderr]    Compiling rand_core v0.9.3
[INFO] [stderr]    Compiling regex v1.11.0
[INFO] [stderr]    Compiling tracing-subscriber v0.3.18
[INFO] [stderr]    Compiling zerocopy-derive v0.7.35
[INFO] [stderr]    Compiling serde_derive v1.0.210
[INFO] [stderr]    Compiling tokio-macros v2.4.0
[INFO] [stderr]    Compiling tokio v1.40.0
[INFO] [stderr]    Compiling zerocopy v0.7.35
[INFO] [stderr]    Compiling ppv-lite86 v0.2.20
[INFO] [stderr]    Compiling rand_chacha v0.9.0
[INFO] [stderr]    Compiling rand v0.9.0
[INFO] [stderr]    Compiling foca v0.19.0 (/opt/rustwide/workdir)
[INFO] [stderr]    Compiling uuid v0.8.2
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 39.66s
[INFO] running `Command { std: "docker" "inspect" "c89a14461158a9704eb1213606910ff532a733fcdf0fa231d6a474ee954d84ba", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "c89a14461158a9704eb1213606910ff532a733fcdf0fa231d6a474ee954d84ba", kill_on_drop: false }`
[INFO] [stdout] c89a14461158a9704eb1213606910ff532a733fcdf0fa231d6a474ee954d84ba
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-tc2/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:7ad1b28ee6f5f7f699f6cf7015098d6ccdd96d6f2d78dd06228f5b4c9faf309c" "/opt/rustwide/cargo-home/bin/cargo" "+b83b707f97d809763b7861afa7638871f3339a33" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] 75294bfcb59b60d021838a19098e1eadf1ad5ebed4512244c8e3c6096084b77d
[INFO] running `Command { std: "docker" "start" "-a" "75294bfcb59b60d021838a19098e1eadf1ad5ebed4512244c8e3c6096084b77d", kill_on_drop: false }`
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.11s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/foca-5131a63cce61033d)
[INFO] [stdout] 
[INFO] [stdout] running 78 tests
[INFO] [stdout] test broadcast::tests::fill_does_nothing_if_buffer_full ... ok
[INFO] [stdout] test broadcast::tests::fill_with_len_prefix ... ok
[INFO] [stdout] test broadcast::tests::piggyback_behaviour ... ok
[INFO] [stdout] test broadcast::tests::piggyback_consumes_largest_first ... ok
[INFO] [stdout] test broadcast::tests::piggyback_respects_limit ... ok
[INFO] [stdout] test broadcast::tests::highest_max_tx_is_consumed_first ... ok
[INFO] [stdout] test member::tests::apply_summary_behaviour ... ok
[INFO] [stdout] test member::tests::choose_active_members_behaviour ... ok
[INFO] [stdout] test member::tests::next_walks_sequentially_then_shuffles ... ok
[INFO] [stdout] test member::tests::alive_transitions ... ok
[INFO] [stdout] test member::tests::sets_replaced_id_on_addr_conflict ... ok
[INFO] [stdout] test member::tests::apply_existing_if_behaviour ... ok
[INFO] [stdout] test runtime::tests::timers_sort ... ok
[INFO] [stdout] test testing::tests::header_roundtrip ... ok
[INFO] [stdout] test testing::tests::member_roundtrip ... ok
[INFO] [stdout] test tests::announce_to_wrong_id_is_accepted_if_same_prefix ... ok
[INFO] [stdout] test tests::apply_with_no_broadcast_doesnt_increase_backlog ... ok
[INFO] [stdout] test tests::auto_rejoin_behaviour ... ok
[INFO] [stdout] test tests::can_join_with_another_client ... ok
[INFO] [stdout] test member::tests::down_never_transitions ... ok
[INFO] [stdout] test tests::can_recover_from_incomplete_probe_cycle ... ok
[INFO] [stdout] test tests::cannot_learn_about_own_previous_identity ... ok
[INFO] [stdout] test tests::cant_receive_announce_with_extra_data ... ok
[INFO] [stdout] test tests::cant_probe_when_not_connected ... ok
[INFO] [stdout] test tests::cant_receive_data_from_same_addr ... ok
[INFO] [stdout] test tests::cant_receive_data_from_same_identity ... ok
[INFO] [stdout] test tests::cant_use_broadcasts_by_default ... ok
[INFO] [stdout] test tests::change_identity_gossips_immediately ... ok
[INFO] [stdout] test tests::changing_identity_resets_timer_token ... ok
[INFO] [stdout] test tests::codec_errors_are_forwarded_correctly ... ok
[INFO] [stdout] test tests::custom_broadcast ... ok
[INFO] [stdout] test tests::debug_no_panic_when_handling_data_with_self_updates ... ok
[INFO] [stdout] test tests::declaring_a_member_as_down_notifies_them ... ok
[INFO] [stdout] test tests::does_not_mark_renewed_identity_as_down ... ok
[INFO] [stdout] test tests::discards_known_old_identities_data ... ok
[INFO] [stdout] test tests::feed_contains_only_active_members ... ok
[INFO] [stdout] test tests::feed_does_not_contain_trailing_jumk ... ok
[INFO] [stdout] test tests::going_idle_clears_probe_state ... ok
[INFO] [stdout] test member::tests::next_yields_none_with_no_active_members ... ok
[INFO] [stdout] test member::tests::remove_if_down_works ... ok
[INFO] [stdout] test member::tests::suspect_transitions ... ok
[INFO] [stdout] test testing::tests::message_roundtrip ... ok
[INFO] [stdout] test tests::cant_change_config_probe_timers ... ok
[INFO] [stdout] test tests::feed_fits_as_many_as_it_can ... ok
[INFO] [stdout] test tests::gossips_when_being_suspected ... ok
[INFO] [stdout] test tests::invariants ... ok
[INFO] [stdout] test tests::incarnation_does_not_increase_for_stale_suspicion ... ok
[INFO] [stdout] test tests::more_data_than_allowed_causes_error ... ok
[INFO] [stdout] test tests::leave_cluster_doesnt_gossip_to_duplicates ... ok
[INFO] [stdout] test tests::handles_member_addr_conflict ... ok
[INFO] [stdout] test tests::no_indirect_cycle_if_probed_disappears ... ok
[INFO] [stdout] test tests::notification_triggers ... ok
[INFO] [stdout] test tests::notifies_on_conflict_resolution ... ok
[INFO] [stdout] test tests::periodic_announce_behaviour ... ok
[INFO] [stdout] test tests::periodic_announce_cannot_be_enabled_at_runtime ... ok
[INFO] [stdout] test tests::periodic_announce_to_down_members_behaviour ... ok
[INFO] [stdout] test tests::periodic_announce_to_down_members_cannot_be_enabled_at_runtime ... ok
[INFO] [stdout] test tests::periodic_gossip_cannot_be_enabled_at_runtime ... ok
[INFO] [stdout] test tests::leave_cluster_gossips_about_our_death ... ok
[INFO] [stdout] test tests::lightweight_messages ... ok
[INFO] [stdout] test tests::probe_cycle_requires_correct_probe_number ... ok
[INFO] [stdout] test tests::message_from_aware_suspect_refutes_suspicion ... ok
[INFO] [stdout] test tests::message_from_down_member_is_replied_with_turn_undead ... ok
[INFO] [stdout] test tests::probe_ping_ack_cycle ... ok
[INFO] [stdout] test tests::not_submitting_indirect_probe_timer_causes_probe_error ... ok
[INFO] [stdout] test tests::probe_receiving_indirect_ack_sends_forwarded_ack ... ok
[INFO] [stdout] test tests::probe_receiving_ping_req_sends_indirect_ping ... ok
[INFO] [stdout] test tests::probe_receiving_indirect_ping_sends_indirect_ack ... ok
[INFO] [stdout] test tests::new_down_member_triggers_remove_down_scheduling ... ok
[INFO] [stdout] test tests::probe_receiving_ping_replies_with_ack ... ok
[INFO] [stdout] test tests::reacts_to_turnundead_even_if_sender_is_down ... ok
[INFO] [stdout] test tests::periodic_gossip_behaviour ... ok
[INFO] [stdout] test tests::suspicion_refutal ... ok
[INFO] [stdout] test tests::trailing_data_is_error ... ok
[INFO] [stdout] test tests::probe_valid_indirect_ack_completes_succesfully ... ok
[INFO] [stdout] test tests::receiving_indirect_for_ourselves_causes_error ... ok
[INFO] [stdout] test tests::renew_during_probe_shouldnt_cause_errors ... ok
[INFO] [stdout] test tests::piggyback_behaviour ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 78 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s
[INFO] [stdout] 
[INFO] [stderr]    Doc-tests foca
[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" "75294bfcb59b60d021838a19098e1eadf1ad5ebed4512244c8e3c6096084b77d", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "75294bfcb59b60d021838a19098e1eadf1ad5ebed4512244c8e3c6096084b77d", kill_on_drop: false }`
[INFO] [stdout] 75294bfcb59b60d021838a19098e1eadf1ad5ebed4512244c8e3c6096084b77d