0.045977615s s2n_quic:client: platform_feature_configured: configuration=Gso { max_segments: 1 } 0.046069867s s2n_quic:client: platform_feature_configured: configuration=BaseMtu { mtu: 1228 } 0.046085606s s2n_quic:client: platform_feature_configured: configuration=InitialMtu { mtu: 1280 } 0.046095244s s2n_quic:client: platform_feature_configured: configuration=MaxMtu { mtu: 9000 } 0.046190743s s2n_quic:client: platform_feature_configured: configuration=Gro { enabled: true } 0.046211401s s2n_quic:client: platform_feature_configured: configuration=Ecn { enabled: true } 0.047510545s s2n_quic:client: platform_event_loop_started: local_address=[::]:32847 0.047598149s s2n_quic:client: platform_event_loop_wakeup: timeout_expired=false rx_ready=false tx_ready=true application_wakeup=false 0.047622324s s2n_quic:client: platform_rx: count=0 syscalls=1 blocked_syscalls=1 total_errors=0 dropped_errors=0 0.047660054s s2n_quic:client: platform_event_loop_sleep: timeout=None processing_duration=63µs 0.048335023s s2n_quic:client:conn: key_update: key_type=Initial cipher_suite=TLS_AES_128_GCM_SHA256 id=0 0.048412268s s2n_quic:client:conn: connection_started: path=Path { local_addr: 0.0.0.0:0, local_cid: 0x63cbf7b38a94928783eb5b4d41e5aea3, remote_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, remote_cid: 0x677b583ce0b359d9, id: 0, is_active: true } id=0 0.048439288s s2n_quic:client:conn: mtu_updated: path_id=0 mtu=1232 cause=NewPath search_complete=false id=0 0.048591923s s2n_quic:client:conn: server_name_information: chosen_server_name="server6" id=0 0.048682392s s2n_quic:client: platform_event_loop_wakeup: timeout_expired=false rx_ready=false tx_ready=false application_wakeup=true 0.048816362s s2n_quic:client:conn: frame_sent: packet_header=Initial { number: 0, version: 1 } path_id=0 frame=Crypto { offset: 0, len: 287 } id=0 0.048860374s s2n_quic:client:conn: frame_sent: packet_header=Initial { number: 0, version: 1 } path_id=0 frame=Padding id=0 0.048906329s s2n_quic:client:conn: packet_sent: packet_header=Initial { number: 0, version: 1 } packet_len=1232 id=0 0.048925155s s2n_quic:client:conn: datagram_sent: len=1232 gso_offset=0 id=0 0.048966071s s2n_quic:client:conn: recovery_metrics: path=Path { local_addr: 0.0.0.0:0, local_cid: 0x63cbf7b38a94928783eb5b4d41e5aea3, remote_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, remote_cid: 0x677b583ce0b359d9, id: 0, is_active: true } min_rtt=333ms smoothed_rtt=333ms latest_rtt=333ms rtt_variance=166.5ms max_ack_delay=0ns pto_count=0 congestion_window=12320 bytes_in_flight=1232 congestion_limited=false id=0 0.049029489s s2n_quic:client: platform_event_loop_sleep: timeout=Some(998.679ms) processing_duration=346µs 0.089455939s s2n_quic:client: platform_event_loop_wakeup: timeout_expired=false rx_ready=true tx_ready=false application_wakeup=false 0.089501835s s2n_quic:client: platform_tx: count=1 syscalls=1 blocked_syscalls=0 total_errors=0 dropped_errors=0 0.089519017s s2n_quic:client: platform_rx: count=1 syscalls=2 blocked_syscalls=1 total_errors=0 dropped_errors=0 0.089585431s s2n_quic:client:conn: datagram_received: len=1232 id=0 0.089633841s s2n_quic:client:conn: packet_received: packet_header=Initial { number: 118421, version: 1 } id=0 0.089669628s s2n_quic:client:conn: frame_received: packet_header=Initial { number: 118421, version: 1 } path=Path { local_addr: [fd00:cafe:cafe:0:0:0:0:100]:32847, local_cid: 0x63cbf7b38a94928783eb5b4d41e5aea3, remote_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, remote_cid: 0x677b583ce0b359d9, id: 0, is_active: true } frame=Ack { ecn_counts: Some(EcnCounts { ect_0_count: 1, ect_1_count: 0, ce_count: 0 }), largest_acknowledged: 0, ack_range_count: 1 } id=0 0.089715173s s2n_quic:client:conn: ack_range_received: packet_header=Initial { number: 118421, version: 1 } path=Path { local_addr: [fd00:cafe:cafe:0:0:0:0:100]:32847, local_cid: 0x63cbf7b38a94928783eb5b4d41e5aea3, remote_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, remote_cid: 0x677b583ce0b359d9, id: 0, is_active: true } ack_range=0..=0 id=0 0.089782348s s2n_quic:client:conn: mtu_updated: path_id=0 mtu=1232 cause=InitialMtuPacketAcknowledged search_complete=false id=0 0.089835798s s2n_quic:client:conn: pacing_rate_updated: path_id=0 bytes_per_second=827337 burst_size=12320 pacing_gain=2.77 id=0 0.089881724s s2n_quic:client:conn: delivery_rate_sampled: path_id=0 rate_sample=RateSample { interval: 40.836ms, delivered_bytes: 1232, lost_bytes: 0, ecn_ce_count: 0, is_app_limited: true, prior_delivered_bytes: 0, bytes_in_flight: 1232, prior_lost_bytes: 0, prior_ecn_ce_count: 0, delivery_rate_bytes_per_second: 30169 } id=0 0.089915276s s2n_quic:client:conn: recovery_metrics: path=Path { local_addr: [fd00:cafe:cafe:0:0:0:0:100]:32847, local_cid: 0x63cbf7b38a94928783eb5b4d41e5aea3, remote_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, remote_cid: 0x677b583ce0b359d9, id: 0, is_active: true } min_rtt=40.836ms smoothed_rtt=40.836ms latest_rtt=40.836ms rtt_variance=20.418ms max_ack_delay=0ns pto_count=0 congestion_window=13552 bytes_in_flight=0 congestion_limited=false id=0 0.089952045s s2n_quic:client:conn: frame_received: packet_header=Initial { number: 118421, version: 1 } path=Path { local_addr: [fd00:cafe:cafe:0:0:0:0:100]:32847, local_cid: 0x63cbf7b38a94928783eb5b4d41e5aea3, remote_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, remote_cid: 0x677b583ce0b359d9, id: 0, is_active: true } frame=Crypto { offset: 0, len: 123 } id=0 0.089995516s s2n_quic:client:conn: tls_server_hello: payload=[[3, 3, 127, 140, 97, 107, 11, 184, 231, 49, 112, 172, 247, 122, 115, 45, 72, 255, 154, 11, 11, 14, 55, 23, 211, 205, 155, 223, 130, 41, 54, 201, 79, 37, 0, 19, 1, 0, 0, 79, 0, 43, 0, 2, 3, 4, 0, 51, 0, 69, 0, 23, 0, 65, 4, 119, 255, 244, 253, 130, 121, 15, 246, 115, 239, 12, 119, 123, 218, 13, 128, 121, 249, 7, 197, 138, 211, 109, 121, 212, 152, 69, 177, 172, 239, 15, 4, 212, 0, 61, 72, 95, 15, 195, 113, 118, 221, 238, 55, 250, 120, 240, 211, 215, 190, 5, 137, 228, 128, 191, 88, 172, 102, 208, 32, 167, 176, 181, 194]] id=0 0.090250572s s2n_quic:client:conn: key_update: key_type=Handshake cipher_suite=TLS_AES_128_GCM_SHA256 id=0 0.090332084s s2n_quic:client:conn: packet_received: packet_header=Handshake { number: 0, version: 1 } id=0 0.090361859s s2n_quic:client:conn: frame_received: packet_header=Handshake { number: 0, version: 1 } path=Path { local_addr: [fd00:cafe:cafe:0:0:0:0:100]:32847, local_cid: 0x63cbf7b38a94928783eb5b4d41e5aea3, remote_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, remote_cid: 0x8e60c45bbe46af0d, id: 0, is_active: true } frame=Crypto { offset: 0, len: 701 } id=0 0.090838910s s2n_quic:client:conn: application_protocol_information: chosen_application_protocol=[104, 113, 45, 105, 110, 116, 101, 114, 111, 112] id=0 0.090872612s s2n_quic:client:conn: key_exchange_group: chosen_group_name="secp256r1" contains_kem=false id=0 0.090910503s s2n_quic:client:conn: transport_parameters_received: transport_parameters=TransportParameters { original_destination_connection_id: Some(0x677b583ce0b359d9), initial_source_connection_id: Some(0x8e60c45bbe46af0d), retry_source_connection_id: None, stateless_reset_token: Some([45, 35, 62, 145, 173, 202, 56, 138, 36, 230, 192, 154, 80, 238, 16, 13]), preferred_address: None, migration_support: true, max_idle_timeout: 180s, ack_delay_exponent: 3, max_ack_delay: 10ms, max_udp_payload_size: 1440, active_connection_id_limit: 8, initial_max_stream_data_bidi_local: 2097152, initial_max_stream_data_bidi_remote: 65635, initial_max_stream_data_uni: 65535, initial_max_streams_bidi: 512, initial_max_streams_uni: 512, max_datagram_frame_size: 0, dc_supported_versions: [] } id=0 0.090984461s s2n_quic:client:conn: key_update: key_type=OneRtt { generation: 0 } cipher_suite=TLS_AES_128_GCM_SHA256 id=0 0.091079619s s2n_quic:client:conn: handshake_status_updated: status=Complete id=0 0.091102070s s2n_quic:client:conn: tls_exporter_ready: session=TlsSession { .. } id=0 0.091136044s s2n_quic:client:conn: packet_received: packet_header=Handshake { number: 1, version: 1 } id=0 0.091153987s s2n_quic:client:conn: frame_received: packet_header=Handshake { number: 1, version: 1 } path=Path { local_addr: [fd00:cafe:cafe:0:0:0:0:100]:32847, local_cid: 0x63cbf7b38a94928783eb5b4d41e5aea3, remote_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, remote_cid: 0x8e60c45bbe46af0d, id: 0, is_active: true } frame=Ping id=0 0.091177161s s2n_quic:client:conn: frame_received: packet_header=Handshake { number: 1, version: 1 } path=Path { local_addr: [fd00:cafe:cafe:0:0:0:0:100]:32847, local_cid: 0x63cbf7b38a94928783eb5b4d41e5aea3, remote_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, remote_cid: 0x8e60c45bbe46af0d, id: 0, is_active: true } frame=Padding id=0 0.091293648s s2n_quic:client:conn: frame_sent: packet_header=Initial { number: 1, version: 1 } path_id=0 frame=Ack { ecn_counts: Some(EcnCounts { ect_0_count: 0, ect_1_count: 1, ce_count: 0 }), largest_acknowledged: 118421, ack_range_count: 1 } id=0 0.091325678s s2n_quic:client:conn: ack_range_sent: packet_header=Initial { number: 1, version: 1 } path_id=0 ack_range=118421..=118421 id=0 0.091342910s s2n_quic:client:conn: frame_sent: packet_header=Initial { number: 1, version: 1 } path_id=0 frame=Padding id=0 0.091368968s s2n_quic:client:conn: packet_sent: packet_header=Initial { number: 1, version: 1 } packet_len=70 id=0 0.091398534s s2n_quic:client:conn: frame_sent: packet_header=Handshake { number: 0, version: 1 } path_id=0 frame=Ack { ecn_counts: Some(EcnCounts { ect_0_count: 0, ect_1_count: 2, ce_count: 0 }), largest_acknowledged: 1, ack_range_count: 1 } id=0 0.091414203s s2n_quic:client:conn: ack_range_sent: packet_header=Handshake { number: 0, version: 1 } path_id=0 ack_range=0..=1 id=0 0.091433028s s2n_quic:client:conn: frame_sent: packet_header=Handshake { number: 0, version: 1 } path_id=0 frame=Crypto { offset: 0, len: 36 } id=0 0.091455580s s2n_quic:client:conn: frame_sent: packet_header=Handshake { number: 0, version: 1 } path_id=0 frame=Padding id=0 0.091480547s s2n_quic:client:conn: packet_sent: packet_header=Handshake { number: 0, version: 1 } packet_len=1162 id=0 0.091498520s s2n_quic:client:conn: key_space_discarded: space=Initial id=0 0.091511124s s2n_quic:client:conn: recovery_metrics: path=Path { local_addr: [fd00:cafe:cafe:0:0:0:0:100]:32847, local_cid: 0x63cbf7b38a94928783eb5b4d41e5aea3, remote_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, remote_cid: 0x8e60c45bbe46af0d, id: 0, is_active: true } min_rtt=40.836ms smoothed_rtt=40.836ms latest_rtt=40.836ms rtt_variance=20.418ms max_ack_delay=10ms pto_count=0 congestion_window=13552 bytes_in_flight=1162 congestion_limited=false id=0 0.091579982s s2n_quic:client:conn: datagram_sent: len=1232 gso_offset=0 id=0 0.091619556s s2n_quic:client:conn: recovery_metrics: path=Path { local_addr: [fd00:cafe:cafe:0:0:0:0:100]:32847, local_cid: 0x63cbf7b38a94928783eb5b4d41e5aea3, remote_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, remote_cid: 0x8e60c45bbe46af0d, id: 0, is_active: true } min_rtt=40.836ms smoothed_rtt=40.836ms latest_rtt=40.836ms rtt_variance=20.418ms max_ack_delay=10ms pto_count=0 congestion_window=13552 bytes_in_flight=1162 congestion_limited=false id=0 0.091677654s s2n_quic:client: platform_event_loop_sleep: timeout=Some(122.09ms) processing_duration=2.224ms 0.092111373s s2n_quic:client: platform_event_loop_wakeup: timeout_expired=false rx_ready=false tx_ready=false application_wakeup=true 0.092154584s s2n_quic:client: platform_tx: count=1 syscalls=1 blocked_syscalls=0 total_errors=0 dropped_errors=0 0.092232319s s2n_quic:client:conn: frame_sent: packet_header=OneRtt { number: 0 } path_id=0 frame=Stream { id: 4, offset: 0, len: 0, is_fin: false } id=0 0.092280539s s2n_quic:client:conn: frame_sent: packet_header=OneRtt { number: 0 } path_id=0 frame=Stream { id: 0, offset: 0, len: 17, is_fin: true } id=0 0.092306488s s2n_quic:client:conn: frame_sent: packet_header=OneRtt { number: 0 } path_id=0 frame=Stream { id: 4, offset: 0, len: 17, is_fin: true } id=0 0.092347665s s2n_quic:client:conn: packet_sent: packet_header=OneRtt { number: 0 } packet_len=69 id=0 0.092365287s s2n_quic:client:conn: datagram_sent: len=69 gso_offset=0 id=0 0.092402166s s2n_quic:client:conn: recovery_metrics: path=Path { local_addr: [fd00:cafe:cafe:0:0:0:0:100]:32847, local_cid: 0x63cbf7b38a94928783eb5b4d41e5aea3, remote_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, remote_cid: 0x8e60c45bbe46af0d, id: 0, is_active: true } min_rtt=40.836ms smoothed_rtt=40.836ms latest_rtt=40.836ms rtt_variance=20.418ms max_ack_delay=10ms pto_count=0 congestion_window=13552 bytes_in_flight=1231 congestion_limited=false id=0 0.092436410s s2n_quic:client:conn: tx_stream_progress: bytes=34 id=0 0.092469151s s2n_quic:client: platform_event_loop_sleep: timeout=Some(121.298ms) processing_duration=360µs 0.092630993s s2n_quic:client: platform_event_loop_wakeup: timeout_expired=false rx_ready=true tx_ready=false application_wakeup=false 0.092660448s s2n_quic:client: platform_tx: count=1 syscalls=1 blocked_syscalls=0 total_errors=0 dropped_errors=0 0.092673032s s2n_quic:client: platform_rx: count=2 syscalls=2 blocked_syscalls=1 total_errors=0 dropped_errors=0 0.092704370s s2n_quic:client:conn: datagram_received: len=311 id=0 0.092742752s s2n_quic:client:conn: packet_received: packet_header=OneRtt { number: 0 } id=0 0.092802323s s2n_quic:client:conn: frame_received: packet_header=OneRtt { number: 0 } path=Path { local_addr: [fd00:cafe:cafe:0:0:0:0:100]:32847, local_cid: 0x63cbf7b38a94928783eb5b4d41e5aea3, remote_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, remote_cid: 0x8e60c45bbe46af0d, id: 0, is_active: true } frame=Crypto { offset: 0, len: 133 } id=0 0.092834924s s2n_quic:client:conn: frame_received: packet_header=OneRtt { number: 0 } path=Path { local_addr: [fd00:cafe:cafe:0:0:0:0:100]:32847, local_cid: 0x63cbf7b38a94928783eb5b4d41e5aea3, remote_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, remote_cid: 0x8e60c45bbe46af0d, id: 0, is_active: true } frame=NewToken id=0 0.092859008s s2n_quic:client:conn: frame_received: packet_header=OneRtt { number: 0 } path=Path { local_addr: [fd00:cafe:cafe:0:0:0:0:100]:32847, local_cid: 0x63cbf7b38a94928783eb5b4d41e5aea3, remote_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, remote_cid: 0x8e60c45bbe46af0d, id: 0, is_active: true } frame=NewConnectionId { sequence_number: 1, retire_prior_to: 0 } id=0 0.092896198s s2n_quic:client:conn: connection_id_updated: path_id=0 cid_consumer=Local previous=0x8e60c45bbe46af0d current=0xf49064752b5cb68f id=0 0.092916646s s2n_quic:client:conn: frame_received: packet_header=OneRtt { number: 0 } path=Path { local_addr: [fd00:cafe:cafe:0:0:0:0:100]:32847, local_cid: 0x63cbf7b38a94928783eb5b4d41e5aea3, remote_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, remote_cid: 0xf49064752b5cb68f, id: 0, is_active: true } frame=NewConnectionId { sequence_number: 2, retire_prior_to: 0 } id=0 0.092943125s s2n_quic:client:conn: frame_received: packet_header=OneRtt { number: 0 } path=Path { local_addr: [fd00:cafe:cafe:0:0:0:0:100]:32847, local_cid: 0x63cbf7b38a94928783eb5b4d41e5aea3, remote_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, remote_cid: 0xf49064752b5cb68f, id: 0, is_active: true } frame=Padding id=0 0.093078258s s2n_quic:client:conn: datagram_received: len=1440 id=0 0.093126919s s2n_quic:client:conn: packet_received: packet_header=OneRtt { number: 1 } id=0 0.093146024s s2n_quic:client:conn: frame_received: packet_header=OneRtt { number: 1 } path=Path { local_addr: [fd00:cafe:cafe:0:0:0:0:100]:32847, local_cid: 0x63cbf7b38a94928783eb5b4d41e5aea3, remote_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, remote_cid: 0xf49064752b5cb68f, id: 0, is_active: true } frame=Ping id=0 0.093190647s s2n_quic:client:conn: frame_received: packet_header=OneRtt { number: 1 } path=Path { local_addr: [fd00:cafe:cafe:0:0:0:0:100]:32847, local_cid: 0x63cbf7b38a94928783eb5b4d41e5aea3, remote_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, remote_cid: 0xf49064752b5cb68f, id: 0, is_active: true } frame=Padding id=0 0.093262712s s2n_quic:client:conn: frame_sent: packet_header=OneRtt { number: 1 } path_id=0 frame=Ack { ecn_counts: Some(EcnCounts { ect_0_count: 0, ect_1_count: 2, ce_count: 0 }), largest_acknowledged: 1, ack_range_count: 1 } id=0 0.093288169s s2n_quic:client:conn: ack_range_sent: packet_header=OneRtt { number: 1 } path_id=0 ack_range=0..=1 id=0 0.093307004s s2n_quic:client:conn: frame_sent: packet_header=OneRtt { number: 1 } path_id=0 frame=RetireConnectionId id=0 0.093332763s s2n_quic:client:conn: frame_sent: packet_header=OneRtt { number: 1 } path_id=0 frame=Padding id=0 0.093363660s s2n_quic:client:conn: packet_sent: packet_header=OneRtt { number: 1 } packet_len=45 id=0 0.093379269s s2n_quic:client:conn: datagram_sent: len=45 gso_offset=0 id=0 0.093415517s s2n_quic:client:conn: recovery_metrics: path=Path { local_addr: [fd00:cafe:cafe:0:0:0:0:100]:32847, local_cid: 0x63cbf7b38a94928783eb5b4d41e5aea3, remote_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, remote_cid: 0xf49064752b5cb68f, id: 0, is_active: true } min_rtt=40.836ms smoothed_rtt=40.836ms latest_rtt=40.836ms rtt_variance=20.418ms max_ack_delay=10ms pto_count=0 congestion_window=13552 bytes_in_flight=1276 congestion_limited=false id=0 0.093467123s s2n_quic:client: platform_event_loop_sleep: timeout=Some(120.3ms) processing_duration=838µs 0.124381481s s2n_quic:client: platform_event_loop_wakeup: timeout_expired=false rx_ready=true tx_ready=false application_wakeup=false 0.124427016s s2n_quic:client: platform_tx: count=1 syscalls=1 blocked_syscalls=0 total_errors=0 dropped_errors=0 0.124444208s s2n_quic:client: platform_rx: count=1 syscalls=2 blocked_syscalls=1 total_errors=0 dropped_errors=0 0.124485936s s2n_quic:client:conn: datagram_received: len=1232 id=0 0.124527684s s2n_quic:client:conn: packet_received: packet_header=OneRtt { number: 2 } id=0 0.124555716s s2n_quic:client:conn: frame_received: packet_header=OneRtt { number: 2 } path=Path { local_addr: [fd00:cafe:cafe:0:0:0:0:100]:32847, local_cid: 0x63cbf7b38a94928783eb5b4d41e5aea3, remote_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, remote_cid: 0xf49064752b5cb68f, id: 0, is_active: true } frame=Ack { ecn_counts: Some(EcnCounts { ect_0_count: 1, ect_1_count: 0, ce_count: 0 }), largest_acknowledged: 0, ack_range_count: 1 } id=0 0.124592555s s2n_quic:client:conn: ack_range_received: packet_header=OneRtt { number: 2 } path=Path { local_addr: [fd00:cafe:cafe:0:0:0:0:100]:32847, local_cid: 0x63cbf7b38a94928783eb5b4d41e5aea3, remote_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, remote_cid: 0xf49064752b5cb68f, id: 0, is_active: true } ack_range=0..=0 id=0 0.124686681s s2n_quic:client:conn: delivery_rate_sampled: path_id=0 rate_sample=RateSample { interval: 33.21ms, delivered_bytes: 69, lost_bytes: 0, ecn_ce_count: 0, is_app_limited: true, prior_delivered_bytes: 1232, bytes_in_flight: 1231, prior_lost_bytes: 0, prior_ecn_ce_count: 0, delivery_rate_bytes_per_second: 2077 } id=0 0.124730663s s2n_quic:client:conn: recovery_metrics: path=Path { local_addr: [fd00:cafe:cafe:0:0:0:0:100]:32847, local_cid: 0x63cbf7b38a94928783eb5b4d41e5aea3, remote_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, remote_cid: 0xf49064752b5cb68f, id: 0, is_active: true } min_rtt=32.28ms smoothed_rtt=40.836ms latest_rtt=32.28ms rtt_variance=20.418ms max_ack_delay=10ms pto_count=0 congestion_window=13621 bytes_in_flight=1207 congestion_limited=false id=0 0.124816753s s2n_quic:client:conn: frame_received: packet_header=OneRtt { number: 2 } path=Path { local_addr: [fd00:cafe:cafe:0:0:0:0:100]:32847, local_cid: 0x63cbf7b38a94928783eb5b4d41e5aea3, remote_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, remote_cid: 0xf49064752b5cb68f, id: 0, is_active: true } frame=HandshakeDone id=0 0.124843573s s2n_quic:client:conn: handshake_status_updated: status=HandshakeDoneAcked id=0 0.124854073s s2n_quic:client:conn: handshake_status_updated: status=Confirmed id=0 0.124873168s s2n_quic:client:conn: frame_received: packet_header=OneRtt { number: 2 } path=Path { local_addr: [fd00:cafe:cafe:0:0:0:0:100]:32847, local_cid: 0x63cbf7b38a94928783eb5b4d41e5aea3, remote_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, remote_cid: 0xf49064752b5cb68f, id: 0, is_active: true } frame=Stream { id: 0, offset: 0, len: 1187, is_fin: false } id=0 0.124925056s s2n_quic:client:conn: rx_stream_progress: bytes=1187 id=0 0.124943039s s2n_quic:client:conn: key_space_discarded: space=Handshake id=0 0.124955342s s2n_quic:client:conn: recovery_metrics: path=Path { local_addr: [fd00:cafe:cafe:0:0:0:0:100]:32847, local_cid: 0x63cbf7b38a94928783eb5b4d41e5aea3, remote_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, remote_cid: 0xf49064752b5cb68f, id: 0, is_active: true } min_rtt=32.28ms smoothed_rtt=40.836ms latest_rtt=32.28ms rtt_variance=20.418ms max_ack_delay=10ms pto_count=0 congestion_window=13621 bytes_in_flight=1207 congestion_limited=false id=0 0.125086166s s2n_quic:client:conn: frame_sent: packet_header=OneRtt { number: 2 } path_id=0 frame=Ping id=0 0.125121692s s2n_quic:client:conn: frame_sent: packet_header=OneRtt { number: 2 } path_id=0 frame=Padding id=0 0.125155706s s2n_quic:client:conn: packet_sent: packet_header=OneRtt { number: 2 } packet_len=1452 id=0 0.125184480s s2n_quic:client:conn: datagram_sent: len=1452 gso_offset=0 id=0 0.125233150s s2n_quic:client:conn: frame_sent: packet_header=OneRtt { number: 3 } path_id=0 frame=Ack { ecn_counts: Some(EcnCounts { ect_0_count: 0, ect_1_count: 3, ce_count: 0 }), largest_acknowledged: 2, ack_range_count: 1 } id=0 0.125253358s s2n_quic:client:conn: ack_range_sent: packet_header=OneRtt { number: 3 } path_id=0 ack_range=0..=2 id=0 0.125273065s s2n_quic:client:conn: frame_sent: packet_header=OneRtt { number: 3 } path_id=0 frame=NewConnectionId { sequence_number: 1, retire_prior_to: 1 } id=0 0.125287381s s2n_quic:client:conn: frame_sent: packet_header=OneRtt { number: 3 } path_id=0 frame=NewConnectionId { sequence_number: 2, retire_prior_to: 1 } id=0 0.125300125s s2n_quic:client:conn: frame_sent: packet_header=OneRtt { number: 3 } path_id=0 frame=NewConnectionId { sequence_number: 3, retire_prior_to: 1 } id=0 0.125336082s s2n_quic:client:conn: packet_sent: packet_header=OneRtt { number: 3 } packet_len=143 id=0 0.125353164s s2n_quic:client:conn: datagram_sent: len=143 gso_offset=0 id=0 0.125384723s s2n_quic:client:conn: recovery_metrics: path=Path { local_addr: [fd00:cafe:cafe:0:0:0:0:100]:32847, local_cid: 0x63cbf7b38a94928783eb5b4d41e5aea3, remote_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, remote_cid: 0xf49064752b5cb68f, id: 0, is_active: true } min_rtt=32.28ms smoothed_rtt=40.836ms latest_rtt=32.28ms rtt_variance=20.418ms max_ack_delay=10ms pto_count=0 congestion_window=13621 bytes_in_flight=1640 congestion_limited=false id=0 0.125437662s s2n_quic:client: platform_event_loop_sleep: timeout=Some(132.139ms) processing_duration=1.06ms 0.125653996s s2n_quic:client: platform_event_loop_wakeup: timeout_expired=false rx_ready=true tx_ready=false application_wakeup=false 0.125684362s s2n_quic:client: platform_tx: count=2 syscalls=1 blocked_syscalls=0 total_errors=0 dropped_errors=0 0.125696966s s2n_quic:client: platform_rx: count=1 syscalls=2 blocked_syscalls=1 total_errors=0 dropped_errors=0 0.125729677s s2n_quic:client:conn: datagram_received: len=1232 id=0 0.125777566s s2n_quic:client:conn: packet_received: packet_header=OneRtt { number: 3 } id=0 0.125803043s s2n_quic:client:conn: frame_received: packet_header=OneRtt { number: 3 } path=Path { local_addr: [fd00:cafe:cafe:0:0:0:0:100]:32847, local_cid: 0x63cbf7b38a94928783eb5b4d41e5aea3, remote_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, remote_cid: 0xf49064752b5cb68f, id: 0, is_active: true } frame=Stream { id: 0, offset: 1187, len: 1194, is_fin: false } id=0 0.125850773s s2n_quic:client:conn: rx_stream_progress: bytes=1194 id=0 0.125903651s s2n_quic:client: platform_event_loop_sleep: timeout=Some(24.813ms) processing_duration=251µs 0.133053422s s2n_quic:client: platform_event_loop_wakeup: timeout_expired=false rx_ready=true tx_ready=false application_wakeup=false 0.133093606s s2n_quic:client: platform_rx: count=1 syscalls=2 blocked_syscalls=1 total_errors=0 dropped_errors=0 0.133132950s s2n_quic:client:conn: datagram_received: len=1440 id=0 0.133170390s s2n_quic:client:conn: packet_received: packet_header=OneRtt { number: 4 } id=0 0.133196569s s2n_quic:client:conn: frame_received: packet_header=OneRtt { number: 4 } path=Path { local_addr: [fd00:cafe:cafe:0:0:0:0:100]:32847, local_cid: 0x63cbf7b38a94928783eb5b4d41e5aea3, remote_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, remote_cid: 0xf49064752b5cb68f, id: 0, is_active: true } frame=Ack { ecn_counts: Some(EcnCounts { ect_0_count: 2, ect_1_count: 0, ce_count: 0 }), largest_acknowledged: 1, ack_range_count: 1 } id=0 0.133223840s s2n_quic:client:conn: ack_range_received: packet_header=OneRtt { number: 4 } path=Path { local_addr: [fd00:cafe:cafe:0:0:0:0:100]:32847, local_cid: 0x63cbf7b38a94928783eb5b4d41e5aea3, remote_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, remote_cid: 0xf49064752b5cb68f, id: 0, is_active: true } ack_range=0..=1 id=0 0.133295824s s2n_quic:client:conn: delivery_rate_sampled: path_id=0 rate_sample=RateSample { interval: 33.21ms, delivered_bytes: 114, lost_bytes: 0, ecn_ce_count: 0, is_app_limited: true, prior_delivered_bytes: 1232, bytes_in_flight: 1231, prior_lost_bytes: 0, prior_ecn_ce_count: 0, delivery_rate_bytes_per_second: 3432 } id=0 0.133332512s s2n_quic:client:conn: recovery_metrics: path=Path { local_addr: [fd00:cafe:cafe:0:0:0:0:100]:32847, local_cid: 0x63cbf7b38a94928783eb5b4d41e5aea3, remote_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, remote_cid: 0xf49064752b5cb68f, id: 0, is_active: true } min_rtt=32.28ms smoothed_rtt=40.5935ms latest_rtt=39.883ms rtt_variance=15.7985ms max_ack_delay=10ms pto_count=0 congestion_window=13666 bytes_in_flight=1595 congestion_limited=false id=0 0.133366506s s2n_quic:client:conn: frame_received: packet_header=OneRtt { number: 4 } path=Path { local_addr: [fd00:cafe:cafe:0:0:0:0:100]:32847, local_cid: 0x63cbf7b38a94928783eb5b4d41e5aea3, remote_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, remote_cid: 0xf49064752b5cb68f, id: 0, is_active: true } frame=NewConnectionId { sequence_number: 3, retire_prior_to: 0 } id=0 0.133403785s s2n_quic:client:conn: frame_received: packet_header=OneRtt { number: 4 } path=Path { local_addr: [fd00:cafe:cafe:0:0:0:0:100]:32847, local_cid: 0x63cbf7b38a94928783eb5b4d41e5aea3, remote_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, remote_cid: 0xf49064752b5cb68f, id: 0, is_active: true } frame=Stream { id: 0, offset: 2381, len: 1365, is_fin: false } id=0 0.133457846s s2n_quic:client:conn: rx_stream_progress: bytes=1365 id=0 0.133515974s s2n_quic:client: platform_event_loop_sleep: timeout=Some(17.2ms) processing_duration=465µs 0.141507696s s2n_quic:client: platform_event_loop_wakeup: timeout_expired=false rx_ready=true tx_ready=false application_wakeup=false 0.141541068s s2n_quic:client: platform_rx: count=1 syscalls=2 blocked_syscalls=1 total_errors=0 dropped_errors=0 0.141573649s s2n_quic:client:conn: datagram_received: len=1440 id=0 0.141604506s s2n_quic:client:conn: packet_received: packet_header=OneRtt { number: 5 } id=0 0.141624193s s2n_quic:client:conn: frame_received: packet_header=OneRtt { number: 5 } path=Path { local_addr: [fd00:cafe:cafe:0:0:0:0:100]:32847, local_cid: 0x63cbf7b38a94928783eb5b4d41e5aea3, remote_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, remote_cid: 0xf49064752b5cb68f, id: 0, is_active: true } frame=Stream { id: 0, offset: 3746, len: 1374, is_fin: true } id=0 0.141666732s s2n_quic:client:conn: frame_received: packet_header=OneRtt { number: 5 } path=Path { local_addr: [fd00:cafe:cafe:0:0:0:0:100]:32847, local_cid: 0x63cbf7b38a94928783eb5b4d41e5aea3, remote_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, remote_cid: 0xf49064752b5cb68f, id: 0, is_active: true } frame=Stream { id: 4, offset: 0, len: 24, is_fin: false } id=0 0.141708851s s2n_quic:client:conn: rx_stream_progress: bytes=1398 id=0 0.141777208s s2n_quic:client: platform_event_loop_sleep: timeout=Some(8.94ms) processing_duration=270µs 0.149968943s s2n_quic:client: platform_event_loop_wakeup: timeout_expired=false rx_ready=true tx_ready=false application_wakeup=false 0.150003568s s2n_quic:client: platform_rx: count=1 syscalls=2 blocked_syscalls=1 total_errors=0 dropped_errors=0 0.150036749s s2n_quic:client:conn: datagram_received: len=1440 id=0 0.150068418s s2n_quic:client:conn: packet_received: packet_header=OneRtt { number: 6 } id=0 0.150086973s s2n_quic:client:conn: frame_received: packet_header=OneRtt { number: 6 } path=Path { local_addr: [fd00:cafe:cafe:0:0:0:0:100]:32847, local_cid: 0x63cbf7b38a94928783eb5b4d41e5aea3, remote_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, remote_cid: 0xf49064752b5cb68f, id: 0, is_active: true } frame=Stream { id: 4, offset: 24, len: 1403, is_fin: false } id=0 0.150132969s s2n_quic:client:conn: rx_stream_progress: bytes=1403 id=0 0.150195395s s2n_quic:client:conn: frame_sent: packet_header=OneRtt { number: 4 } path_id=0 frame=Ack { ecn_counts: Some(EcnCounts { ect_0_count: 0, ect_1_count: 7, ce_count: 0 }), largest_acknowledged: 6, ack_range_count: 1 } id=0 0.150218458s s2n_quic:client:conn: ack_range_sent: packet_header=OneRtt { number: 4 } path_id=0 ack_range=2..=6 id=0 0.150241291s s2n_quic:client:conn: frame_sent: packet_header=OneRtt { number: 4 } path_id=0 frame=Padding id=0 0.150270576s s2n_quic:client:conn: packet_sent: packet_header=OneRtt { number: 4 } packet_len=45 id=0 0.150285584s s2n_quic:client:conn: datagram_sent: len=45 gso_offset=0 id=0 0.150316511s s2n_quic:client:conn: recovery_metrics: path=Path { local_addr: [fd00:cafe:cafe:0:0:0:0:100]:32847, local_cid: 0x63cbf7b38a94928783eb5b4d41e5aea3, remote_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, remote_cid: 0xf49064752b5cb68f, id: 0, is_active: true } min_rtt=32.28ms smoothed_rtt=40.5935ms latest_rtt=39.883ms rtt_variance=15.7985ms max_ack_delay=10ms pto_count=0 congestion_window=13666 bytes_in_flight=1595 congestion_limited=false id=0 0.150365864s s2n_quic:client: platform_event_loop_sleep: timeout=Some(88.488ms) processing_duration=399µs 0.158449907s s2n_quic:client: platform_event_loop_wakeup: timeout_expired=false rx_ready=true tx_ready=false application_wakeup=false 0.158483479s s2n_quic:client: platform_tx: count=1 syscalls=1 blocked_syscalls=0 total_errors=0 dropped_errors=0 0.158496634s s2n_quic:client: platform_rx: count=1 syscalls=2 blocked_syscalls=1 total_errors=0 dropped_errors=0 0.158534885s s2n_quic:client:conn: datagram_received: len=1440 id=0 0.158567506s s2n_quic:client:conn: packet_received: packet_header=OneRtt { number: 7 } id=0 0.158586722s s2n_quic:client:conn: frame_received: packet_header=OneRtt { number: 7 } path=Path { local_addr: [fd00:cafe:cafe:0:0:0:0:100]:32847, local_cid: 0x63cbf7b38a94928783eb5b4d41e5aea3, remote_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, remote_cid: 0xf49064752b5cb68f, id: 0, is_active: true } frame=Stream { id: 4, offset: 1427, len: 1402, is_fin: false } id=0 0.158633419s s2n_quic:client:conn: rx_stream_progress: bytes=1402 id=0 0.158686148s s2n_quic:client: platform_event_loop_sleep: timeout=Some(24.837ms) processing_duration=238µs 0.164661065s s2n_quic:client: platform_event_loop_wakeup: timeout_expired=false rx_ready=true tx_ready=false application_wakeup=false 0.164701291s s2n_quic:client: platform_rx: count=1 syscalls=2 blocked_syscalls=1 total_errors=0 dropped_errors=0 0.164740373s s2n_quic:client:conn: datagram_received: len=1440 id=0 0.164822597s s2n_quic:client:conn: packet_received: packet_header=OneRtt { number: 8 } id=0 0.164844438s s2n_quic:client:conn: connection_id_updated: path_id=0 cid_consumer=Remote previous=0x63cbf7b38a94928783eb5b4d41e5aea3 current=0x49063cc44c4cd5c7cf7bfb6fa933ea6c id=0 0.164873442s s2n_quic:client:conn: frame_received: packet_header=OneRtt { number: 8 } path=Path { local_addr: [fd00:cafe:cafe:0:0:0:0:100]:32847, local_cid: 0x49063cc44c4cd5c7cf7bfb6fa933ea6c, remote_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, remote_cid: 0xf49064752b5cb68f, id: 0, is_active: true } frame=Ack { ecn_counts: Some(EcnCounts { ect_0_count: 4, ect_1_count: 0, ce_count: 0 }), largest_acknowledged: 3, ack_range_count: 1 } id=0 0.164899651s s2n_quic:client:conn: ack_range_received: packet_header=OneRtt { number: 8 } path=Path { local_addr: [fd00:cafe:cafe:0:0:0:0:100]:32847, local_cid: 0x49063cc44c4cd5c7cf7bfb6fa933ea6c, remote_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, remote_cid: 0xf49064752b5cb68f, id: 0, is_active: true } ack_range=1..=3 id=0 0.164929186s s2n_quic:client:conn: mtu_updated: path_id=0 mtu=1452 cause=ProbeAcknowledged search_complete=false id=0 0.164971735s s2n_quic:client:conn: delivery_rate_sampled: path_id=0 rate_sample=RateSample { interval: 40.256ms, delivered_bytes: 1640, lost_bytes: 0, ecn_ce_count: 0, is_app_limited: true, prior_delivered_bytes: 1301, bytes_in_flight: 1640, prior_lost_bytes: 0, prior_ecn_ce_count: 0, delivery_rate_bytes_per_second: 40739 } id=0 0.165005057s s2n_quic:client:conn: recovery_metrics: path=Path { local_addr: [fd00:cafe:cafe:0:0:0:0:100]:32847, local_cid: 0x49063cc44c4cd5c7cf7bfb6fa933ea6c, remote_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, remote_cid: 0xf49064752b5cb68f, id: 0, is_active: true } min_rtt=32.28ms smoothed_rtt=40.380434ms latest_rtt=39.656ms rtt_variance=12.275ms max_ack_delay=10ms pto_count=0 congestion_window=17701 bytes_in_flight=0 congestion_limited=false id=0 0.165037428s s2n_quic:client:conn: frame_received: packet_header=OneRtt { number: 8 } path=Path { local_addr: [fd00:cafe:cafe:0:0:0:0:100]:32847, local_cid: 0x49063cc44c4cd5c7cf7bfb6fa933ea6c, remote_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, remote_cid: 0xf49064752b5cb68f, id: 0, is_active: true } frame=RetireConnectionId id=0 0.165065901s s2n_quic:client:conn: frame_received: packet_header=OneRtt { number: 8 } path=Path { local_addr: [fd00:cafe:cafe:0:0:0:0:100]:32847, local_cid: 0x49063cc44c4cd5c7cf7bfb6fa933ea6c, remote_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, remote_cid: 0xf49064752b5cb68f, id: 0, is_active: true } frame=Stream { id: 4, offset: 2829, len: 1391, is_fin: false } id=0 0.165125692s s2n_quic:client:conn: rx_stream_progress: bytes=1391 id=0 0.165202957s s2n_quic:client:conn: frame_sent: packet_header=OneRtt { number: 5 } path_id=0 frame=Ping id=0 0.165268629s s2n_quic:client:conn: frame_sent: packet_header=OneRtt { number: 5 } path_id=0 frame=Padding id=0 0.165307191s s2n_quic:client:conn: packet_sent: packet_header=OneRtt { number: 5 } packet_len=5202 id=0 0.165326096s s2n_quic:client:conn: datagram_sent: len=5202 gso_offset=0 id=0 0.165364769s s2n_quic:client:conn: recovery_metrics: path=Path { local_addr: [fd00:cafe:cafe:0:0:0:0:100]:32847, local_cid: 0x49063cc44c4cd5c7cf7bfb6fa933ea6c, remote_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, remote_cid: 0xf49064752b5cb68f, id: 0, is_active: true } min_rtt=32.28ms smoothed_rtt=40.380434ms latest_rtt=39.656ms rtt_variance=12.275ms max_ack_delay=10ms pto_count=0 congestion_window=17701 bytes_in_flight=5202 congestion_limited=false id=0 0.165423799s s2n_quic:client: platform_event_loop_sleep: timeout=Some(18.1ms) processing_duration=765µs 0.170924362s s2n_quic:client: platform_event_loop_wakeup: timeout_expired=false rx_ready=true tx_ready=false application_wakeup=false 0.170961711s s2n_quic:client: platform_tx_error: errno=90 0.170973303s s2n_quic:client: platform_tx: count=0 syscalls=1 blocked_syscalls=0 total_errors=1 dropped_errors=0 0.170985716s s2n_quic:client: platform_rx: count=1 syscalls=2 blocked_syscalls=1 total_errors=0 dropped_errors=0 0.171017545s s2n_quic:client:conn: datagram_received: len=1440 id=0 0.171048723s s2n_quic:client:conn: packet_received: packet_header=OneRtt { number: 9 } id=0 0.171067899s s2n_quic:client:conn: frame_received: packet_header=OneRtt { number: 9 } path=Path { local_addr: [fd00:cafe:cafe:0:0:0:0:100]:32847, local_cid: 0x49063cc44c4cd5c7cf7bfb6fa933ea6c, remote_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, remote_cid: 0xf49064752b5cb68f, id: 0, is_active: true } frame=Stream { id: 4, offset: 4220, len: 1402, is_fin: false } id=0 0.171114657s s2n_quic:client:conn: rx_stream_progress: bytes=1402 id=0 0.171158118s s2n_quic:client: platform_event_loop_sleep: timeout=Some(12.365ms) processing_duration=237µs 0.177164144s s2n_quic:client: platform_event_loop_wakeup: timeout_expired=false rx_ready=true tx_ready=false application_wakeup=false 0.177204209s s2n_quic:client: platform_rx: count=1 syscalls=2 blocked_syscalls=1 total_errors=0 dropped_errors=0 0.177243232s s2n_quic:client:conn: datagram_received: len=1440 id=0 0.177280541s s2n_quic:client:conn: packet_received: packet_header=OneRtt { number: 10 } id=0 0.177303634s s2n_quic:client:conn: frame_received: packet_header=OneRtt { number: 10 } path=Path { local_addr: [fd00:cafe:cafe:0:0:0:0:100]:32847, local_cid: 0x49063cc44c4cd5c7cf7bfb6fa933ea6c, remote_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, remote_cid: 0xf49064752b5cb68f, id: 0, is_active: true } frame=Stream { id: 4, offset: 5622, len: 1402, is_fin: false } id=0 0.177359037s s2n_quic:client:conn: rx_stream_progress: bytes=1402 id=0 0.177410533s s2n_quic:client: platform_event_loop_sleep: timeout=Some(6.113ms) processing_duration=249µs 0.182112867s s2n_quic:client: platform_event_loop_wakeup: timeout_expired=false rx_ready=true tx_ready=false application_wakeup=false 0.182152982s s2n_quic:client: platform_rx: count=1 syscalls=2 blocked_syscalls=1 total_errors=0 dropped_errors=0 0.182192035s s2n_quic:client:conn: datagram_received: len=1440 id=0 0.182229555s s2n_quic:client:conn: packet_received: packet_header=OneRtt { number: 11 } id=0 0.182252738s s2n_quic:client:conn: frame_received: packet_header=OneRtt { number: 11 } path=Path { local_addr: [fd00:cafe:cafe:0:0:0:0:100]:32847, local_cid: 0x49063cc44c4cd5c7cf7bfb6fa933ea6c, remote_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, remote_cid: 0xf49064752b5cb68f, id: 0, is_active: true } frame=Stream { id: 4, offset: 7024, len: 1402, is_fin: false } id=0 0.182321948s s2n_quic:client:conn: rx_stream_progress: bytes=1402 id=0 0.182375988s s2n_quic:client: platform_event_loop_sleep: timeout=Some(1.148ms) processing_duration=265µs 0.183661687s s2n_quic:client: platform_event_loop_wakeup: timeout_expired=true rx_ready=false tx_ready=false application_wakeup=false 0.183714776s s2n_quic:client:conn: recovery_metrics: path=Path { local_addr: [fd00:cafe:cafe:0:0:0:0:100]:32847, local_cid: 0x49063cc44c4cd5c7cf7bfb6fa933ea6c, remote_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, remote_cid: 0xf49064752b5cb68f, id: 0, is_active: true } min_rtt=32.28ms smoothed_rtt=40.380434ms latest_rtt=39.656ms rtt_variance=12.275ms max_ack_delay=10ms pto_count=0 congestion_window=17701 bytes_in_flight=5202 congestion_limited=false id=0 0.183811116s s2n_quic:client:conn: frame_sent: packet_header=OneRtt { number: 6 } path_id=0 frame=Ack { ecn_counts: Some(EcnCounts { ect_0_count: 0, ect_1_count: 12, ce_count: 0 }), largest_acknowledged: 11, ack_range_count: 1 } id=0 0.183842194s s2n_quic:client:conn: ack_range_sent: packet_header=OneRtt { number: 6 } path_id=0 ack_range=3..=11 id=0 0.183866219s s2n_quic:client:conn: frame_sent: packet_header=OneRtt { number: 6 } path_id=0 frame=Padding id=0 0.183900713s s2n_quic:client:conn: ecn_state_changed: path=Path { local_addr: [fd00:cafe:cafe:0:0:0:0:100]:32847, local_cid: 0x49063cc44c4cd5c7cf7bfb6fa933ea6c, remote_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, remote_cid: 0xf49064752b5cb68f, id: 0, is_active: true } state=Unknown id=0 0.183921853s s2n_quic:client:conn: packet_sent: packet_header=OneRtt { number: 6 } packet_len=45 id=0 0.183934566s s2n_quic:client:conn: datagram_sent: len=45 gso_offset=0 id=0 0.183967167s s2n_quic:client:conn: recovery_metrics: path=Path { local_addr: [fd00:cafe:cafe:0:0:0:0:100]:32847, local_cid: 0x49063cc44c4cd5c7cf7bfb6fa933ea6c, remote_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, remote_cid: 0xf49064752b5cb68f, id: 0, is_active: true } min_rtt=32.28ms smoothed_rtt=40.380434ms latest_rtt=39.656ms rtt_variance=12.275ms max_ack_delay=10ms pto_count=0 congestion_window=17701 bytes_in_flight=5202 congestion_limited=false id=0 0.184022450s s2n_quic:client: platform_event_loop_sleep: timeout=Some(80.642ms) processing_duration=362µs 0.186651355s s2n_quic:client: platform_event_loop_wakeup: timeout_expired=false rx_ready=true tx_ready=false application_wakeup=false 0.186693834s s2n_quic:client: platform_tx: count=1 syscalls=1 blocked_syscalls=0 total_errors=0 dropped_errors=0 0.186710816s s2n_quic:client: platform_rx: count=1 syscalls=2 blocked_syscalls=1 total_errors=0 dropped_errors=0 0.186784173s s2n_quic:client:conn: datagram_received: len=1440 id=0 0.186833365s s2n_quic:client:conn: packet_received: packet_header=OneRtt { number: 12 } id=0 0.186859484s s2n_quic:client:conn: frame_received: packet_header=OneRtt { number: 12 } path=Path { local_addr: [fd00:cafe:cafe:0:0:0:0:100]:32847, local_cid: 0x49063cc44c4cd5c7cf7bfb6fa933ea6c, remote_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, remote_cid: 0xf49064752b5cb68f, id: 0, is_active: true } frame=Stream { id: 4, offset: 8426, len: 1402, is_fin: false } id=0 0.186923193s s2n_quic:client:conn: rx_stream_progress: bytes=1402 id=0 0.186985238s s2n_quic:client: platform_event_loop_sleep: timeout=Some(24.751ms) processing_duration=337µs 0.190689329s s2n_quic:client: platform_event_loop_wakeup: timeout_expired=false rx_ready=true tx_ready=false application_wakeup=false 0.190733351s s2n_quic:client: platform_rx: count=1 syscalls=2 blocked_syscalls=1 total_errors=0 dropped_errors=0 0.190809584s s2n_quic:client:conn: datagram_received: len=567 id=0 0.190856772s s2n_quic:client:conn: packet_received: packet_header=OneRtt { number: 13 } id=0 0.190891376s s2n_quic:client:conn: frame_received: packet_header=OneRtt { number: 13 } path=Path { local_addr: [fd00:cafe:cafe:0:0:0:0:100]:32847, local_cid: 0x49063cc44c4cd5c7cf7bfb6fa933ea6c, remote_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, remote_cid: 0xf49064752b5cb68f, id: 0, is_active: true } frame=Stream { id: 4, offset: 9828, len: 412, is_fin: true } id=0 0.190946749s s2n_quic:client:conn: frame_received: packet_header=OneRtt { number: 13 } path=Path { local_addr: [fd00:cafe:cafe:0:0:0:0:100]:32847, local_cid: 0x49063cc44c4cd5c7cf7bfb6fa933ea6c, remote_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, remote_cid: 0xf49064752b5cb68f, id: 0, is_active: true } frame=Padding id=0 0.190989529s s2n_quic:client:conn: rx_stream_progress: bytes=412 id=0 0.191037609s s2n_quic:client: platform_event_loop_sleep: timeout=Some(20.699ms) processing_duration=351µs 0.191304517s s2n_quic:client:conn: connection_closed: error=Closed { initiator: Local, source: Location { file: "/app/quic/s2n-quic-transport/src/connection/connection_impl.rs", line: 567, col: 61 } } id=0 0.191384917s s2n_quic:client:conn: frame_sent: packet_header=OneRtt { number: 7 } path_id=0 frame=ConnectionClose id=0 0.191428318s s2n_quic:client:conn: frame_sent: packet_header=OneRtt { number: 7 } path_id=0 frame=Padding id=0 0.191469835s s2n_quic:client:conn: packet_sent: packet_header=OneRtt { number: 7 } packet_len=45 id=0 0.191543082s s2n_quic:client: platform_event_loop_wakeup: timeout_expired=false rx_ready=false tx_ready=false application_wakeup=true 0.191601461s s2n_quic:client:conn: datagram_sent: len=45 gso_offset=0 id=0 0.191644982s s2n_quic:client: platform_event_loop_sleep: timeout=Some(20.091ms) processing_duration=103µs 0.193189273s s2n_quic:client: platform_event_loop_wakeup: timeout_expired=false rx_ready=true tx_ready=false application_wakeup=false 0.193221954s s2n_quic:client: platform_tx: count=1 syscalls=1 blocked_syscalls=0 total_errors=0 dropped_errors=0 0.193234738s s2n_quic:client: platform_rx: count=1 syscalls=2 blocked_syscalls=1 total_errors=0 dropped_errors=0 0.193266217s s2n_quic:client:conn: datagram_received: len=1440 id=0 0.193298628s s2n_quic:client:conn: packet_received: packet_header=OneRtt { number: 14 } id=0 0.193317853s s2n_quic:client:conn: frame_received: packet_header=OneRtt { number: 14 } path=Path { local_addr: [fd00:cafe:cafe:0:0:0:0:100]:32847, local_cid: 0x49063cc44c4cd5c7cf7bfb6fa933ea6c, remote_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, remote_cid: 0xf49064752b5cb68f, id: 0, is_active: true } frame=Stream { id: 4, offset: 5622, len: 1402, is_fin: false } id=0 0.193371674s s2n_quic:client: platform_event_loop_sleep: timeout=Some(18.364ms) processing_duration=184µs 0.197946219s s2n_quic:client: platform_event_loop_wakeup: timeout_expired=false rx_ready=true tx_ready=false application_wakeup=false 0.197983579s s2n_quic:client: platform_rx: count=1 syscalls=2 blocked_syscalls=1 total_errors=0 dropped_errors=0 0.198022241s s2n_quic:client:conn: datagram_received: len=1440 id=0 0.198059591s s2n_quic:client:conn: packet_received: packet_header=OneRtt { number: 15 } id=0 0.198083135s s2n_quic:client:conn: frame_received: packet_header=OneRtt { number: 15 } path=Path { local_addr: [fd00:cafe:cafe:0:0:0:0:100]:32847, local_cid: 0x49063cc44c4cd5c7cf7bfb6fa933ea6c, remote_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, remote_cid: 0xf49064752b5cb68f, id: 0, is_active: true } frame=Stream { id: 4, offset: 7024, len: 1402, is_fin: false } id=0 0.198147715s s2n_quic:client: platform_event_loop_sleep: timeout=Some(13.589ms) processing_duration=203µs 0.201982059s s2n_quic:client: platform_event_loop_wakeup: timeout_expired=false rx_ready=true tx_ready=false application_wakeup=false 0.202019680s s2n_quic:client: platform_rx: count=1 syscalls=2 blocked_syscalls=1 total_errors=0 dropped_errors=0 0.202065926s s2n_quic:client:conn: datagram_received: len=567 id=0 0.202104828s s2n_quic:client:conn: packet_received: packet_header=OneRtt { number: 16 } id=0 0.202128883s s2n_quic:client:conn: frame_received: packet_header=OneRtt { number: 16 } path=Path { local_addr: [fd00:cafe:cafe:0:0:0:0:100]:32847, local_cid: 0x49063cc44c4cd5c7cf7bfb6fa933ea6c, remote_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, remote_cid: 0xf49064752b5cb68f, id: 0, is_active: true } frame=Stream { id: 4, offset: 9828, len: 412, is_fin: true } id=0 0.202159120s s2n_quic:client:conn: frame_received: packet_header=OneRtt { number: 16 } path=Path { local_addr: [fd00:cafe:cafe:0:0:0:0:100]:32847, local_cid: 0x49063cc44c4cd5c7cf7bfb6fa933ea6c, remote_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, remote_cid: 0xf49064752b5cb68f, id: 0, is_active: true } frame=Padding id=0 0.202218621s s2n_quic:client: platform_event_loop_sleep: timeout=Some(9.518ms) processing_duration=238µs 0.211363022s s2n_quic:client: platform_event_loop_wakeup: timeout_expired=true rx_ready=false tx_ready=false application_wakeup=false 0.211427272s s2n_quic:client:conn: recovery_metrics: path=Path { local_addr: [fd00:cafe:cafe:0:0:0:0:100]:32847, local_cid: 0x49063cc44c4cd5c7cf7bfb6fa933ea6c, remote_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, remote_cid: 0xf49064752b5cb68f, id: 0, is_active: true } min_rtt=32.28ms smoothed_rtt=40.380434ms latest_rtt=39.656ms rtt_variance=12.275ms max_ack_delay=10ms pto_count=0 congestion_window=17701 bytes_in_flight=5202 congestion_limited=false id=0 0.211490550s s2n_quic:client: platform_event_loop_sleep: timeout=Some(21.42ms) processing_duration=130µs 0.212041278s s2n_quic:client: platform_event_loop_wakeup: timeout_expired=false rx_ready=true tx_ready=false application_wakeup=false 0.212071214s s2n_quic:client: platform_rx: count=1 syscalls=2 blocked_syscalls=1 total_errors=0 dropped_errors=0 0.212103815s s2n_quic:client:conn: datagram_received: len=567 id=0 0.212135263s s2n_quic:client:conn: packet_received: packet_header=OneRtt { number: 17 } id=0 0.212155220s s2n_quic:client:conn: frame_received: packet_header=OneRtt { number: 17 } path=Path { local_addr: [fd00:cafe:cafe:0:0:0:0:100]:32847, local_cid: 0x49063cc44c4cd5c7cf7bfb6fa933ea6c, remote_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, remote_cid: 0xf49064752b5cb68f, id: 0, is_active: true } frame=Stream { id: 4, offset: 9828, len: 412, is_fin: true } id=0 0.212180668s s2n_quic:client:conn: frame_received: packet_header=OneRtt { number: 17 } path=Path { local_addr: [fd00:cafe:cafe:0:0:0:0:100]:32847, local_cid: 0x49063cc44c4cd5c7cf7bfb6fa933ea6c, remote_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, remote_cid: 0xf49064752b5cb68f, id: 0, is_active: true } frame=Padding id=0 0.212230872s s2n_quic:client: platform_event_loop_sleep: timeout=Some(20.679ms) processing_duration=191µs 0.222137796s s2n_quic:client: platform_event_loop_wakeup: timeout_expired=false rx_ready=true tx_ready=false application_wakeup=false 0.222173904s s2n_quic:client: platform_rx: count=1 syscalls=2 blocked_syscalls=1 total_errors=0 dropped_errors=0 0.222212536s s2n_quic:client:conn: datagram_received: len=567 id=0 0.222250206s s2n_quic:client:conn: packet_received: packet_header=OneRtt { number: 18 } id=0 0.222273840s s2n_quic:client:conn: frame_received: packet_header=OneRtt { number: 18 } path=Path { local_addr: [fd00:cafe:cafe:0:0:0:0:100]:32847, local_cid: 0x49063cc44c4cd5c7cf7bfb6fa933ea6c, remote_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, remote_cid: 0xf49064752b5cb68f, id: 0, is_active: true } frame=Stream { id: 4, offset: 9828, len: 412, is_fin: true } id=0 0.222303626s s2n_quic:client:conn: frame_received: packet_header=OneRtt { number: 18 } path=Path { local_addr: [fd00:cafe:cafe:0:0:0:0:100]:32847, local_cid: 0x49063cc44c4cd5c7cf7bfb6fa933ea6c, remote_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, remote_cid: 0xf49064752b5cb68f, id: 0, is_active: true } frame=Padding id=0 0.222370480s s2n_quic:client: platform_event_loop_sleep: timeout=Some(10.54ms) processing_duration=234µs 0.222457543s s2n_quic:client: platform_event_loop_wakeup: timeout_expired=false rx_ready=true tx_ready=false application_wakeup=false 0.222485415s s2n_quic:client: platform_rx: count=1 syscalls=2 blocked_syscalls=1 total_errors=0 dropped_errors=0 0.222523255s s2n_quic:client:conn: datagram_received: len=39 id=0 0.222559643s s2n_quic:client:conn: packet_received: packet_header=OneRtt { number: 19 } id=0 0.222582566s s2n_quic:client:conn: frame_received: packet_header=OneRtt { number: 19 } path=Path { local_addr: [fd00:cafe:cafe:0:0:0:0:100]:32847, local_cid: 0x49063cc44c4cd5c7cf7bfb6fa933ea6c, remote_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, remote_cid: 0xf49064752b5cb68f, id: 0, is_active: true } frame=ConnectionClose id=0 0.222609747s s2n_quic:client:conn: connection_close_frame_received: packet_header=OneRtt { number: 19 } path=Path { local_addr: [fd00:cafe:cafe:0:0:0:0:100]:32847, local_cid: 0x49063cc44c4cd5c7cf7bfb6fa933ea6c, remote_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, remote_cid: 0xf49064752b5cb68f, id: 0, is_active: true } frame=ConnectionCloseFrame { error_code: 0, frame_type: Some(0), reason: None } id=0 0.222655402s s2n_quic:client: platform_event_loop_sleep: timeout=Some(10.254ms) processing_duration=200µs 0.232797384s s2n_quic:client: platform_event_loop_wakeup: timeout_expired=true rx_ready=false tx_ready=false application_wakeup=false 0.232856955s s2n_quic:client:conn: recovery_metrics: path=Path { local_addr: [fd00:cafe:cafe:0:0:0:0:100]:32847, local_cid: 0x49063cc44c4cd5c7cf7bfb6fa933ea6c, remote_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, remote_cid: 0xf49064752b5cb68f, id: 0, is_active: true } min_rtt=32.28ms smoothed_rtt=40.380434ms latest_rtt=39.656ms rtt_variance=12.275ms max_ack_delay=10ms pto_count=0 congestion_window=17701 bytes_in_flight=5202 congestion_limited=false id=0 0.232916776s s2n_quic:client:conn: datagram_sent: len=45 gso_offset=0 id=0 0.232963253s s2n_quic:client: platform_event_loop_sleep: timeout=Some(4.13ms) processing_duration=167µs 0.238168595s s2n_quic:client: platform_event_loop_wakeup: timeout_expired=true rx_ready=false tx_ready=false application_wakeup=false 0.238210513s s2n_quic:client: platform_tx: count=1 syscalls=1 blocked_syscalls=0 total_errors=0 dropped_errors=0 0.238250077s s2n_quic:client:conn: recovery_metrics: path=Path { local_addr: [fd00:cafe:cafe:0:0:0:0:100]:32847, local_cid: 0x49063cc44c4cd5c7cf7bfb6fa933ea6c, remote_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, remote_cid: 0xf49064752b5cb68f, id: 0, is_active: true } min_rtt=32.28ms smoothed_rtt=40.380434ms latest_rtt=39.656ms rtt_variance=12.275ms max_ack_delay=10ms pto_count=0 congestion_window=17701 bytes_in_flight=5202 congestion_limited=false id=0 0.238310811s s2n_quic:client: platform_event_loop_sleep: timeout=Some(26.354ms) processing_duration=144µs 0.264523198s s2n_quic:client: platform_event_loop_wakeup: timeout_expired=true rx_ready=false tx_ready=false application_wakeup=false 0.264578522s s2n_quic:client:conn: recovery_metrics: path=Path { local_addr: [fd00:cafe:cafe:0:0:0:0:100]:32847, local_cid: 0x49063cc44c4cd5c7cf7bfb6fa933ea6c, remote_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, remote_cid: 0xf49064752b5cb68f, id: 0, is_active: true } min_rtt=32.28ms smoothed_rtt=40.380434ms latest_rtt=39.656ms rtt_variance=12.275ms max_ack_delay=10ms pto_count=1 congestion_window=17701 bytes_in_flight=5202 congestion_limited=false id=0 0.264626591s s2n_quic:client: platform_event_loop_sleep: timeout=Some(21.239ms) processing_duration=105µs 0.285773257s s2n_quic:client: platform_event_loop_wakeup: timeout_expired=true rx_ready=false tx_ready=false application_wakeup=false 0.285835603s s2n_quic:client:conn: recovery_metrics: path=Path { local_addr: [fd00:cafe:cafe:0:0:0:0:100]:32847, local_cid: 0x49063cc44c4cd5c7cf7bfb6fa933ea6c, remote_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, remote_cid: 0xf49064752b5cb68f, id: 0, is_active: true } min_rtt=32.28ms smoothed_rtt=40.380434ms latest_rtt=39.656ms rtt_variance=12.275ms max_ack_delay=10ms pto_count=1 congestion_window=17701 bytes_in_flight=5202 congestion_limited=false id=0 0.285886027s s2n_quic:client: platform_event_loop_sleep: timeout=Some(78.258ms) processing_duration=115µs 0.365183873s s2n_quic:client: platform_event_loop_wakeup: timeout_expired=true rx_ready=false tx_ready=false application_wakeup=false 0.365258372s s2n_quic:client:conn: recovery_metrics: path=Path { local_addr: [fd00:cafe:cafe:0:0:0:0:100]:32847, local_cid: 0x49063cc44c4cd5c7cf7bfb6fa933ea6c, remote_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, remote_cid: 0xf49064752b5cb68f, id: 0, is_active: true } min_rtt=32.28ms smoothed_rtt=40.380434ms latest_rtt=39.656ms rtt_variance=12.275ms max_ack_delay=10ms pto_count=2 congestion_window=17701 bytes_in_flight=5202 congestion_limited=false id=0 0.365326409s s2n_quic:client: platform_event_loop_sleep: timeout=Some(124.409ms) processing_duration=146µs 0.489661955s s2n_quic:client: platform_event_loop_wakeup: timeout_expired=true rx_ready=false tx_ready=false application_wakeup=false 0.489773803s s2n_quic:client:conn: recovery_metrics: path=Path { local_addr: [fd00:cafe:cafe:0:0:0:0:100]:32847, local_cid: 0x49063cc44c4cd5c7cf7bfb6fa933ea6c, remote_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, remote_cid: 0xf49064752b5cb68f, id: 0, is_active: true } min_rtt=32.28ms smoothed_rtt=40.380434ms latest_rtt=39.656ms rtt_variance=12.275ms max_ack_delay=10ms pto_count=2 congestion_window=17701 bytes_in_flight=5202 congestion_limited=false id=0 0.489930546s s2n_quic:client: platform_event_loop_sleep: timeout=None processing_duration=271µs