[INFO] fetching crate shiguredo_srt 2026.1.0-canary.0...
[INFO] testing shiguredo_srt-2026.1.0-canary.0 against try#dec9417b8611e34e787a3e4c37686b5131f9e5c5 for pr-154210-2
[INFO] extracting crate shiguredo_srt 2026.1.0-canary.0 into /workspace/builds/worker-1-tc2/source
[INFO] started tweaking crates.io crate shiguredo_srt 2026.1.0-canary.0
[INFO] removed 0 missing tests
[INFO] finished tweaking crates.io crate shiguredo_srt 2026.1.0-canary.0
[INFO] tweaked toml for crates.io crate shiguredo_srt 2026.1.0-canary.0 written to /workspace/builds/worker-1-tc2/source/Cargo.toml
[INFO] validating manifest of crates.io crate shiguredo_srt 2026.1.0-canary.0 on toolchain dec9417b8611e34e787a3e4c37686b5131f9e5c5
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+dec9417b8611e34e787a3e4c37686b5131f9e5c5" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate crates.io crate shiguredo_srt 2026.1.0-canary.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" "+dec9417b8611e34e787a3e4c37686b5131f9e5c5" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-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:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+dec9417b8611e34e787a3e4c37686b5131f9e5c5" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] 46b29f00d9fcb3cc400110e41c8500d981ba29a37f73b093d2267c5dd69db1e9
[INFO] running `Command { std: "docker" "start" "-a" "46b29f00d9fcb3cc400110e41c8500d981ba29a37f73b093d2267c5dd69db1e9", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "46b29f00d9fcb3cc400110e41c8500d981ba29a37f73b093d2267c5dd69db1e9", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "46b29f00d9fcb3cc400110e41c8500d981ba29a37f73b093d2267c5dd69db1e9", kill_on_drop: false }`
[INFO] [stdout] 46b29f00d9fcb3cc400110e41c8500d981ba29a37f73b093d2267c5dd69db1e9
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-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:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+dec9417b8611e34e787a3e4c37686b5131f9e5c5" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] eb6bc91e89e32ff3ce388eb1421dc7fcfc08772dc38bb305cc2045e7c6c0c30e
[INFO] running `Command { std: "docker" "start" "-a" "eb6bc91e89e32ff3ce388eb1421dc7fcfc08772dc38bb305cc2045e7c6c0c30e", kill_on_drop: false }`
[INFO] [stderr]    Compiling libc v0.2.180
[INFO] [stderr]    Compiling find-msvc-tools v0.1.8
[INFO] [stderr]    Compiling aws-lc-rs v1.15.3
[INFO] [stderr]    Compiling jobserver v0.1.34
[INFO] [stderr]    Compiling cc v1.2.53
[INFO] [stderr]    Compiling cmake v0.1.57
[INFO] [stderr]    Compiling aws-lc-sys v0.36.0
[INFO] [stderr]    Compiling shiguredo_srt v2026.1.0-canary.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 31.42s
[INFO] running `Command { std: "docker" "inspect" "eb6bc91e89e32ff3ce388eb1421dc7fcfc08772dc38bb305cc2045e7c6c0c30e", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "eb6bc91e89e32ff3ce388eb1421dc7fcfc08772dc38bb305cc2045e7c6c0c30e", kill_on_drop: false }`
[INFO] [stdout] eb6bc91e89e32ff3ce388eb1421dc7fcfc08772dc38bb305cc2045e7c6c0c30e
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-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:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+dec9417b8611e34e787a3e4c37686b5131f9e5c5" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] b859f2f7eebcd3c84080eb2dee3b0a2f51f00b083dbeeb1739be573577207187
[INFO] running `Command { std: "docker" "start" "-a" "b859f2f7eebcd3c84080eb2dee3b0a2f51f00b083dbeeb1739be573577207187", kill_on_drop: false }`
[INFO] [stderr]    Compiling shiguredo_srt v2026.1.0-canary.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 1.97s
[INFO] running `Command { std: "docker" "inspect" "b859f2f7eebcd3c84080eb2dee3b0a2f51f00b083dbeeb1739be573577207187", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "b859f2f7eebcd3c84080eb2dee3b0a2f51f00b083dbeeb1739be573577207187", kill_on_drop: false }`
[INFO] [stdout] b859f2f7eebcd3c84080eb2dee3b0a2f51f00b083dbeeb1739be573577207187
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-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:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+dec9417b8611e34e787a3e4c37686b5131f9e5c5" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] 9e03c8470fc6eb3e3ebec641e6b3bc813ae2f7ca2561979603f06514073ffef3
[INFO] running `Command { std: "docker" "start" "-a" "9e03c8470fc6eb3e3ebec641e6b3bc813ae2f7ca2561979603f06514073ffef3", kill_on_drop: false }`
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.03s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/shiguredo_srt-90e898d70de3712a)
[INFO] [stdout] 
[INFO] [stdout] running 79 tests
[INFO] [stdout] test crypto::tests::test_wrap_unwrap_sek ... ok
[INFO] [stdout] test crypto::tests::test_km_refresh_should_pre_announce ... ok
[INFO] [stdout] test srt_congestion::tests::test_live_cc_congestion_recovery ... ok
[INFO] [stdout] test crypto::tests::test_derive_kek ... ok
[INFO] [stdout] test srt_congestion::tests::test_live_cc_cwnd_dynamic_adjustment ... ok
[INFO] [stdout] test crypto::tests::test_km_refresh_encrypt_with_key_switch ... ok
[INFO] [stdout] test crypto::tests::test_km_refresh_state_transitions ... ok
[INFO] [stdout] test crypto::tests::test_key_length ... ok
[INFO] [stdout] test srt_congestion::tests::test_live_cc_input_rate_measurement ... ok
[INFO] [stdout] test srt_congestion::tests::test_live_cc_max_bw_auto_adjustment ... ok
[INFO] [stdout] test srt_congestion::tests::test_live_cc_new ... ok
[INFO] [stdout] test srt_congestion::tests::test_live_cc_on_nak_received ... ok
[INFO] [stdout] test srt_congestion::tests::test_live_cc_on_ack_received ... ok
[INFO] [stdout] test srt_congestion::tests::test_live_cc_on_packet_sent ... ok
[INFO] [stdout] test srt_congestion::tests::test_live_cc_set_max_bandwidth ... ok
[INFO] [stdout] test srt_connection::tests::test_caller_initial_state ... ok
[INFO] [stdout] test srt_connection::tests::test_connection_options_default ... ok
[INFO] [stdout] test srt_connection::tests::test_listener_initial_state ... ok
[INFO] [stdout] test srt_connection::tests::test_loss_list_encode_decode_mixed ... ok
[INFO] [stdout] test srt_connection::tests::test_loss_list_encode_decode_single ... ok
[INFO] [stdout] test srt_connection::tests::test_loss_list_encode_empty ... ok
[INFO] [stdout] test srt_handshake::tests::test_congestion_extension_with_sid ... ok
[INFO] [stdout] test srt_handshake::tests::test_congestion_extension_file ... ok
[INFO] [stdout] test srt_handshake::tests::test_handshake_encode_decode ... ok
[INFO] [stdout] test srt_handshake::tests::test_congestion_extension_live ... ok
[INFO] [stdout] test srt_handshake::tests::test_km_error_response ... ok
[INFO] [stdout] test srt_handshake::tests::test_km_extension_in_handshake ... ok
[INFO] [stdout] test srt_handshake::tests::test_km_message_encode_decode ... ok
[INFO] [stdout] test srt_handshake::tests::test_no_sid_extension ... ok
[INFO] [stdout] test srt_handshake::tests::test_sid_extension_access_control ... ok
[INFO] [stdout] test srt_handshake::tests::test_sid_extension_basic ... ok
[INFO] [stdout] test srt_handshake::tests::test_sid_extension_empty ... ok
[INFO] [stdout] test srt_handshake::tests::test_no_congestion_extension ... ok
[INFO] [stdout] test srt_connection::tests::test_loss_list_encode_decode_range ... ok
[INFO] [stdout] test crypto::tests::test_encrypt_decrypt ... ok
[INFO] [stdout] test srt_handshake::tests::test_hs_extension ... ok
[INFO] [stdout] test srt_handshake::tests::test_sid_extension_exact_4_bytes ... ok
[INFO] [stdout] test srt_packet::tests::test_control_packet_encode_decode ... ok
[INFO] [stdout] test srt_handshake::tests::test_sid_extension_long_string ... ok
[INFO] [stdout] test srt_packet::tests::test_data_packet_encode_decode ... ok
[INFO] [stdout] test srt_packet::tests::test_packet_position ... ok
[INFO] [stdout] test srt_receiver::tests::test_ack_includes_recv_rate ... ok
[INFO] [stdout] test srt_receiver::tests::test_ack_timestamp_tracker ... ok
[INFO] [stdout] test srt_receiver::tests::test_jitter_calculation ... ok
[INFO] [stdout] test srt_receiver::tests::test_ack_timestamp_tracker_max_entries ... ok
[INFO] [stdout] test srt_receiver::tests::test_link_capacity_estimator_no_samples ... ok
[INFO] [stdout] test srt_receiver::tests::test_jitter_no_packets ... ok
[INFO] [stdout] test srt_receiver::tests::test_link_capacity_estimator_single_interval ... ok
[INFO] [stdout] test srt_receiver::tests::test_loss_rate_calculation ... ok
[INFO] [stdout] test srt_receiver::tests::test_link_capacity_estimator ... ok
[INFO] [stdout] test srt_receiver::tests::test_receiver_buffer_nak_generation ... ok
[INFO] [stdout] test srt_receiver::tests::test_receiver_buffer_duplicate ... ok
[INFO] [stdout] test srt_receiver::tests::test_receiver_buffer_pop_ready ... ok
[INFO] [stdout] test srt_receiver::tests::test_receiver_buffer_loss_detection ... ok
[INFO] [stdout] test srt_receiver::tests::test_receiver_buffer_receive_in_order ... ok
[INFO] [stdout] test srt_receiver::tests::test_receiver_buffer_new ... ok
[INFO] [stdout] test srt_receiver::tests::test_receiving_rate_estimator ... ok
[INFO] [stdout] test srt_receiver::tests::test_receiving_rate_estimator_no_packets ... ok
[INFO] [stdout] test srt_sender::tests::test_packet_pacing ... ok
[INFO] [stdout] test srt_receiver::tests::test_rtt_calculation_with_ack_timestamps ... ok
[INFO] [stdout] test srt_receiver::tests::test_total_bytes_received ... ok
[INFO] [stdout] test srt_sender::tests::test_sender_buffer_ack ... ok
[INFO] [stdout] test srt_sender::tests::test_sender_buffer_nak ... ok
[INFO] [stdout] test srt_sender::tests::test_sender_buffer_new ... ok
[INFO] [stdout] test srt_sender::tests::test_sender_buffer_push ... ok
[INFO] [stdout] test srt_handshake::tests::test_sid_extension_with_padding ... ok
[INFO] [stdout] test stream_id::tests::test_encode_with_mode ... ok
[INFO] [stdout] test stream_id::tests::test_parse_basic ... ok
[INFO] [stdout] test stream_id::tests::test_encode_basic ... ok
[INFO] [stdout] test stream_id::tests::test_parse_no_prefix ... ok
[INFO] [stdout] test srt_receiver::tests::test_loss_rate_zero ... ok
[INFO] [stdout] test stream_id::tests::test_parse_with_custom ... ok
[INFO] [stdout] test stream_id::tests::test_parse_with_host ... ok
[INFO] [stdout] test stream_id::tests::test_parse_with_session ... ok
[INFO] [stdout] test stream_id::tests::test_parse_with_type_and_mode ... ok
[INFO] [stdout] test stream_id::tests::test_parse_wrong_prefix ... ok
[INFO] [stdout] test srt_receiver::tests::test_receiver_buffer_ack_generation ... ok
[INFO] [stdout] test srt_sender::tests::test_sequence_less_than ... ok
[INFO] [stdout] test stream_id::tests::test_roundtrip ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 79 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.02s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/sansio_test.rs (/opt/rustwide/target/debug/deps/sansio_test-2e9f76ef67dba218)
[INFO] [stdout] 
[INFO] [stdout] running 27 tests
[INFO] [stdout] test test_ack_ackack_flow ... ok
[INFO] [stdout] test test_bidirectional_communication ... ok
[INFO] [stdout] test test_caller_connect_twice ... ok
[INFO] [stdout] test test_can_send ... ok
[INFO] [stdout] test test_data_transfer_caller_to_listener ... ok
[INFO] [stdout] test test_debug_mode ... ok
[INFO] [stdout] test test_disconnect ... ok
[INFO] [stdout] test test_handshake_with_stream_id ... ok
[INFO] [stdout] test test_handshake_without_encryption ... ok
[INFO] [stdout] test test_inactivity_timeout ... ok
[INFO] [stdout] test test_has_retransmit ... ok
[INFO] [stdout] test test_listener_receive_shutdown ... ok
[INFO] [stdout] test test_large_data_transfer ... ok
[INFO] [stdout] test test_multiple_data_packets ... ok
[INFO] [stdout] test test_packet_pacing ... ok
[INFO] [stdout] test test_retransmit_timer ... ok
[INFO] [stdout] test test_send_before_connected ... ok
[INFO] [stdout] test test_process_retransmit ... ok
[INFO] [stdout] test test_multiple_sends_before_transfer ... ok
[INFO] [stdout] test test_sender_stats ... ok
[INFO] [stdout] test test_receiver_stats ... ok
[INFO] [stdout] test test_nak_timer ... ok
[INFO] [stdout] test test_handshake_with_encryption ... ok
[INFO] [stdout] test test_keepalive_timer ... ok
[INFO] [stdout] test test_data_transfer_with_encryption ... ok
[INFO] [stdout] test test_handshake_with_aes256 ... ok
[INFO] [stdout] test test_receive_encrypted_data_with_aes256 ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 27 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s
[INFO] [stdout] 
[INFO] [stderr]    Doc-tests shiguredo_srt
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stdout] test src/stream_id.rs - stream_id (line 23) ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.02s
[INFO] [stdout] 
[INFO] [stdout] all doctests ran in 0.89s; merged doctests compilation took 0.86s
[INFO] running `Command { std: "docker" "inspect" "9e03c8470fc6eb3e3ebec641e6b3bc813ae2f7ca2561979603f06514073ffef3", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "9e03c8470fc6eb3e3ebec641e6b3bc813ae2f7ca2561979603f06514073ffef3", kill_on_drop: false }`
[INFO] [stdout] 9e03c8470fc6eb3e3ebec641e6b3bc813ae2f7ca2561979603f06514073ffef3