0.047116586s s2n_quic:server: platform_feature_configured: configuration=Gso { max_segments: 1 } 0.047276615s s2n_quic:server: platform_feature_configured: configuration=BaseMtu { mtu: 1228 } 0.047293256s s2n_quic:server: platform_feature_configured: configuration=InitialMtu { mtu: 1280 } 0.047303045s s2n_quic:server: platform_feature_configured: configuration=MaxMtu { mtu: 9000 } 0.047399144s s2n_quic:server: platform_feature_configured: configuration=Gro { enabled: true } 0.047421807s s2n_quic:server: platform_feature_configured: configuration=Ecn { enabled: true } 0.048915398s s2n_quic:server: platform_event_loop_started: local_address=[::]:443 0.049025243s s2n_quic:server: platform_event_loop_wakeup: timeout_expired=false rx_ready=false tx_ready=true application_wakeup=false 0.049068584s s2n_quic:server: platform_rx: count=0 syscalls=1 blocked_syscalls=1 total_errors=0 dropped_errors=0 0.049124528s s2n_quic:server: platform_event_loop_sleep: timeout=None processing_duration=101µs 0.820016291s s2n_quic:server: platform_event_loop_wakeup: timeout_expired=false rx_ready=true tx_ready=false application_wakeup=false 0.820115206s s2n_quic:server: platform_rx: count=3 syscalls=2 blocked_syscalls=1 total_errors=0 dropped_errors=0 0.820210323s s2n_quic:server: version_information: server_versions=[1] client_versions=[1463896404] chosen_version=None 0.820240239s s2n_quic:server: endpoint_datagram_dropped: len=1207 reason=UnsupportedVersion 0.820266989s s2n_quic:server: version_information: server_versions=[1] client_versions=[1463896404] chosen_version=None 0.820282478s s2n_quic:server: endpoint_datagram_dropped: len=1207 reason=UnsupportedVersion 0.820301904s s2n_quic:server: version_information: server_versions=[1] client_versions=[1463896404] chosen_version=None 0.820316942s s2n_quic:server: endpoint_datagram_dropped: len=1207 reason=UnsupportedVersion 0.820366154s s2n_quic:server: endpoint_packet_sent: packet_header=VersionNegotiation 0.820382906s s2n_quic:server: endpoint_datagram_sent: len=15 gso_offset=0 0.820398956s s2n_quic:server: endpoint_packet_sent: packet_header=VersionNegotiation 0.820410407s s2n_quic:server: endpoint_datagram_sent: len=15 gso_offset=0 0.820425425s s2n_quic:server: endpoint_packet_sent: packet_header=VersionNegotiation 0.820436796s s2n_quic:server: endpoint_datagram_sent: len=15 gso_offset=0 0.820457275s s2n_quic:server: platform_event_loop_sleep: timeout=None processing_duration=455µs 1.040728737s s2n_quic:server: platform_event_loop_wakeup: timeout_expired=false rx_ready=true tx_ready=false application_wakeup=false 1.040823875s s2n_quic:server: platform_tx: count=3 syscalls=1 blocked_syscalls=0 total_errors=0 dropped_errors=0 1.040840245s s2n_quic:server: platform_rx: count=1 syscalls=2 blocked_syscalls=1 total_errors=0 dropped_errors=0 1.040889938s s2n_quic:server: version_information: server_versions=[1] client_versions=[] chosen_version=Some(1) 1.041250041s s2n_quic:server:conn: key_update: key_type=Initial cipher_suite=TLS_AES_128_GCM_SHA256 id=0 1.041327385s s2n_quic:server:conn: connection_started: path=Path { local_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, local_cid: 0x3e7cb0db1a09335878e9f9d3a423f496, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:52193, remote_cid: 0x529fce6bba77e07a, id: 0, is_active: true } id=0 1.041355037s s2n_quic:server:conn: mtu_updated: path_id=0 mtu=1232 cause=NewPath search_complete=false id=0 1.041396464s s2n_quic:server:conn: datagram_received: len=1200 id=0 1.041439735s s2n_quic:server:conn: frame_received: packet_header=Initial { number: 0, version: 1 } path=Path { local_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, local_cid: 0x3e7cb0db1a09335878e9f9d3a423f496, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:52193, remote_cid: 0x529fce6bba77e07a, id: 0, is_active: true } frame=Crypto { offset: 0, len: 248 } id=0 1.041495619s s2n_quic:server:conn: tls_client_hello: payload=[[3, 3, 218, 228, 185, 166, 147, 77, 236, 196, 7, 211, 7, 8, 192, 127, 74, 189, 101, 103, 7, 48, 225, 2, 235, 238, 40, 186, 130, 226, 16, 162, 254, 16, 0, 0, 6, 19, 1, 19, 2, 19, 3, 1, 0, 0, 197, 0, 0, 0, 12, 0, 10, 0, 0, 7, 115, 101, 114, 118, 101, 114, 54, 0, 10, 0, 8, 0, 6, 0, 29, 0, 23, 0, 24, 0, 16, 0, 13, 0, 11, 10, 104, 113, 45, 105, 110, 116, 101, 114, 111, 112, 0, 13, 0, 20, 0, 18, 4, 3, 8, 4, 4, 1, 5, 3, 8, 5, 5, 1, 8, 6, 6, 1, 2, 1, 0, 51, 0, 38, 0, 36, 0, 29, 0, 32, 130, 58, 43, 110, 168, 4, 240, 67, 247, 40, 215, 182, 25, 207, 234, 215, 57, 185, 165, 119, 225, 3, 66, 129, 60, 162, 209, 143, 21, 87, 201, 79, 0, 45, 0, 2, 1, 1, 0, 43, 0, 3, 2, 3, 4, 0, 57, 0, 69, 1, 4, 128, 0, 117, 48, 4, 4, 128, 240, 0, 0, 5, 4, 128, 96, 0, 0, 7, 4, 128, 0, 128, 0, 8, 2, 64, 100, 9, 2, 64, 100, 14, 1, 8, 128, 0, 222, 26, 2, 103, 16, 192, 0, 0, 0, 255, 2, 222, 26, 2, 103, 16, 128, 0, 113, 88, 1, 2, 15, 8, 82, 159, 206, 107, 186, 119, 224, 122]] id=0 1.041570229s s2n_quic:server:conn: frame_received: packet_header=Initial { number: 0, version: 1 } path=Path { local_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, local_cid: 0x3e7cb0db1a09335878e9f9d3a423f496, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:52193, remote_cid: 0x529fce6bba77e07a, id: 0, is_active: true } frame=Padding id=0 1.041919341s s2n_quic:server:conn: key_update: key_type=Handshake cipher_suite=TLS_AES_128_GCM_SHA256 id=0 1.042113213s s2n_quic:server:conn: application_protocol_information: chosen_application_protocol=[104, 113, 45, 105, 110, 116, 101, 114, 111, 112] id=0 1.042146255s s2n_quic:server:conn: server_name_information: chosen_server_name="server6" id=0 1.042161003s s2n_quic:server:conn: key_exchange_group: chosen_group_name="x25519" contains_kem=false id=0 1.042199725s s2n_quic:server:conn: transport_parameters_received: transport_parameters=TransportParameters { original_destination_connection_id: None, initial_source_connection_id: Some(0x529fce6bba77e07a), retry_source_connection_id: None, stateless_reset_token: None, preferred_address: None, migration_support: true, max_idle_timeout: 30s, ack_delay_exponent: 3, max_ack_delay: 25ms, max_udp_payload_size: 65527, active_connection_id_limit: 8, initial_max_stream_data_bidi_local: 6291456, initial_max_stream_data_bidi_remote: 0, initial_max_stream_data_uni: 32768, initial_max_streams_bidi: 100, initial_max_streams_uni: 100, max_datagram_frame_size: 0, dc_supported_versions: [] } id=0 1.042272150s s2n_quic:server:conn: key_update: key_type=OneRtt { generation: 0 } cipher_suite=TLS_AES_128_GCM_SHA256 id=0 1.042494005s s2n_quic:server:conn: frame_sent: packet_header=Initial { number: 0, version: 1 } path_id=0 frame=Ack { ecn_counts: Some(EcnCounts { ect_0_count: 0, ect_1_count: 1, ce_count: 0 }), largest_acknowledged: 0, ack_range_count: 1 } id=0 1.042526025s s2n_quic:server:conn: ack_range_sent: packet_header=Initial { number: 0, version: 1 } path_id=0 ack_range=0..=0 id=0 1.042559417s s2n_quic:server:conn: frame_sent: packet_header=Initial { number: 0, version: 1 } path_id=0 frame=Crypto { offset: 0, len: 90 } id=0 1.042605083s s2n_quic:server:conn: packet_sent: packet_header=Initial { number: 0, version: 1 } packet_len=154 id=0 1.042661989s s2n_quic:server:conn: frame_sent: packet_header=Handshake { number: 0, version: 1 } path_id=0 frame=Crypto { offset: 0, len: 690 } id=0 1.042693417s s2n_quic:server:conn: frame_sent: packet_header=Handshake { number: 0, version: 1 } path_id=0 frame=Padding id=0 1.042722852s s2n_quic:server:conn: packet_sent: packet_header=Handshake { number: 0, version: 1 } packet_len=1078 id=0 1.042764661s s2n_quic:server:conn: datagram_sent: len=1232 gso_offset=0 id=0 1.042817469s s2n_quic:server:conn: recovery_metrics: path=Path { local_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, local_cid: 0x3e7cb0db1a09335878e9f9d3a423f496, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:52193, remote_cid: 0x529fce6bba77e07a, id: 0, is_active: true } min_rtt=333ms smoothed_rtt=333ms latest_rtt=333ms rtt_variance=166.5ms max_ack_delay=25ms pto_count=0 congestion_window=12320 bytes_in_flight=1232 congestion_limited=false id=0 1.042896236s s2n_quic:server: platform_event_loop_sleep: timeout=Some(998.521ms) processing_duration=2.182ms 1.077535279s s2n_quic:server: platform_event_loop_wakeup: timeout_expired=false rx_ready=true tx_ready=false application_wakeup=false 1.077614777s s2n_quic:server: platform_tx: count=1 syscalls=1 blocked_syscalls=0 total_errors=0 dropped_errors=0 1.077656245s s2n_quic:server: platform_rx: count=1 syscalls=2 blocked_syscalls=1 total_errors=0 dropped_errors=0 1.077713251s s2n_quic:server: version_information: server_versions=[1] client_versions=[] chosen_version=Some(1) 1.077772793s s2n_quic:server:conn: datagram_received: len=1200 id=0 1.077834408s s2n_quic:server:conn: packet_received: packet_header=Initial { number: 1, version: 1 } id=0 1.077864965s s2n_quic:server:conn: frame_received: packet_header=Initial { number: 1, version: 1 } path=Path { local_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, local_cid: 0x3e7cb0db1a09335878e9f9d3a423f496, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:52193, remote_cid: 0x529fce6bba77e07a, id: 0, is_active: true } frame=Ack { ecn_counts: None, largest_acknowledged: 0, ack_range_count: 1 } id=0 1.077908756s s2n_quic:server:conn: ack_range_received: packet_header=Initial { number: 1, version: 1 } path=Path { local_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, local_cid: 0x3e7cb0db1a09335878e9f9d3a423f496, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:52193, remote_cid: 0x529fce6bba77e07a, id: 0, is_active: true } ack_range=0..=0 id=0 1.077973217s s2n_quic:server:conn: pacing_rate_updated: path_id=0 bytes_per_second=957657 burst_size=12320 pacing_gain=2.77 id=0 1.078011709s s2n_quic:server:conn: ecn_state_changed: path=Path { local_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, local_cid: 0x3e7cb0db1a09335878e9f9d3a423f496, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:52193, remote_cid: 0x529fce6bba77e07a, id: 0, is_active: true } state=Failed id=0 1.078034191s s2n_quic:server:conn: delivery_rate_sampled: path_id=0 rate_sample=RateSample { interval: 35.279ms, delivered_bytes: 154, lost_bytes: 0, ecn_ce_count: 0, is_app_limited: true, prior_delivered_bytes: 0, bytes_in_flight: 154, prior_lost_bytes: 0, prior_ecn_ce_count: 0, delivery_rate_bytes_per_second: 4365 } id=0 1.078064087s s2n_quic:server:conn: recovery_metrics: path=Path { local_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, local_cid: 0x3e7cb0db1a09335878e9f9d3a423f496, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:52193, remote_cid: 0x529fce6bba77e07a, id: 0, is_active: true } min_rtt=35.279ms smoothed_rtt=35.279ms latest_rtt=35.279ms rtt_variance=17.6395ms max_ack_delay=25ms pto_count=0 congestion_window=12474 bytes_in_flight=1078 congestion_limited=false id=0 1.078112026s s2n_quic:server:conn: frame_received: packet_header=Initial { number: 1, version: 1 } path=Path { local_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, local_cid: 0x3e7cb0db1a09335878e9f9d3a423f496, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:52193, remote_cid: 0x529fce6bba77e07a, id: 0, is_active: true } frame=Padding id=0 1.078184171s s2n_quic:server:conn: packet_received: packet_header=Handshake { number: 2, version: 1 } id=0 1.078211753s s2n_quic:server:conn: frame_received: packet_header=Handshake { number: 2, version: 1 } path=Path { local_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, local_cid: 0x3e7cb0db1a09335878e9f9d3a423f496, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:52193, remote_cid: 0x529fce6bba77e07a, id: 0, is_active: true } frame=Ack { ecn_counts: None, largest_acknowledged: 0, ack_range_count: 1 } id=0 1.078240837s s2n_quic:server:conn: ack_range_received: packet_header=Handshake { number: 2, version: 1 } path=Path { local_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, local_cid: 0x3e7cb0db1a09335878e9f9d3a423f496, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:52193, remote_cid: 0x529fce6bba77e07a, id: 0, is_active: true } ack_range=0..=0 id=0 1.078288105s s2n_quic:server:conn: delivery_rate_sampled: path_id=0 rate_sample=RateSample { interval: 35.279ms, 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: 34921 } id=0 1.078314906s s2n_quic:server:conn: recovery_metrics: path=Path { local_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, local_cid: 0x3e7cb0db1a09335878e9f9d3a423f496, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:52193, remote_cid: 0x529fce6bba77e07a, id: 0, is_active: true } min_rtt=35.279ms smoothed_rtt=35.279ms latest_rtt=35.279ms rtt_variance=17.6395ms max_ack_delay=25ms pto_count=0 congestion_window=13552 bytes_in_flight=0 congestion_limited=false id=0 1.078342427s s2n_quic:server:conn: frame_received: packet_header=Handshake { number: 2, version: 1 } path=Path { local_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, local_cid: 0x3e7cb0db1a09335878e9f9d3a423f496, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:52193, remote_cid: 0x529fce6bba77e07a, id: 0, is_active: true } frame=Crypto { offset: 0, len: 36 } id=0 1.078407088s s2n_quic:server:conn: key_space_discarded: space=Initial id=0 1.078423999s s2n_quic:server:conn: recovery_metrics: path=Path { local_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, local_cid: 0x3e7cb0db1a09335878e9f9d3a423f496, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:52193, remote_cid: 0x529fce6bba77e07a, id: 0, is_active: true } min_rtt=35.279ms smoothed_rtt=35.279ms latest_rtt=35.279ms rtt_variance=17.6395ms max_ack_delay=25ms pto_count=0 congestion_window=13552 bytes_in_flight=0 congestion_limited=false id=0 1.078538092s s2n_quic:server:conn: handshake_status_updated: status=Complete id=0 1.078558881s s2n_quic:server:conn: handshake_status_updated: status=Confirmed id=0 1.078582285s s2n_quic:server:conn: tls_exporter_ready: session=TlsSession { .. } id=0 1.078624975s s2n_quic:server:conn: key_space_discarded: space=Handshake id=0 1.078671372s s2n_quic:server:conn: recovery_metrics: path=Path { local_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, local_cid: 0x3e7cb0db1a09335878e9f9d3a423f496, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:52193, remote_cid: 0x529fce6bba77e07a, id: 0, is_active: true } min_rtt=35.279ms smoothed_rtt=35.279ms latest_rtt=35.279ms rtt_variance=17.6395ms max_ack_delay=25ms pto_count=0 congestion_window=13552 bytes_in_flight=0 congestion_limited=false id=0 1.078757733s s2n_quic:server:conn: packet_received: packet_header=OneRtt { number: 3 } id=0 1.078785014s s2n_quic:server:conn: frame_received: packet_header=OneRtt { number: 3 } path=Path { local_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, local_cid: 0x3e7cb0db1a09335878e9f9d3a423f496, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:52193, remote_cid: 0x529fce6bba77e07a, id: 0, is_active: true } frame=NewConnectionId { sequence_number: 1, retire_prior_to: 0 } id=0 1.078836831s s2n_quic:server:conn: connection_id_updated: path_id=0 cid_consumer=Local previous=0x529fce6bba77e07a current=0xd323ecc697ff06bf id=0 1.078859733s s2n_quic:server:conn: frame_received: packet_header=OneRtt { number: 3 } path=Path { local_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, local_cid: 0x3e7cb0db1a09335878e9f9d3a423f496, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:52193, remote_cid: 0xd323ecc697ff06bf, id: 0, is_active: true } frame=Stream { id: 0, offset: 0, len: 17, is_fin: true } id=0 1.078936617s s2n_quic:server:conn: rx_stream_progress: bytes=17 id=0 1.079087459s s2n_quic:server:conn: frame_sent: packet_header=OneRtt { number: 0 } path_id=0 frame=Ping id=0 1.079135579s s2n_quic:server:conn: frame_sent: packet_header=OneRtt { number: 0 } path_id=0 frame=Padding id=0 1.079176826s s2n_quic:server:conn: packet_sent: packet_header=OneRtt { number: 0 } packet_len=1452 id=0 1.079193888s s2n_quic:server:conn: datagram_sent: len=1452 gso_offset=0 id=0 1.079233282s s2n_quic:server:conn: frame_sent: packet_header=OneRtt { number: 1 } path_id=0 frame=Ack { ecn_counts: Some(EcnCounts { ect_0_count: 0, ect_1_count: 1, ce_count: 0 }), largest_acknowledged: 3, ack_range_count: 1 } id=0 1.079251586s s2n_quic:server:conn: ack_range_sent: packet_header=OneRtt { number: 1 } path_id=0 ack_range=3..=3 id=0 1.079266123s s2n_quic:server:conn: frame_sent: packet_header=OneRtt { number: 1 } path_id=0 frame=HandshakeDone id=0 1.079286531s s2n_quic:server:conn: frame_sent: packet_header=OneRtt { number: 1 } path_id=0 frame=NewConnectionId { sequence_number: 1, retire_prior_to: 1 } id=0 1.079300136s s2n_quic:server:conn: frame_sent: packet_header=OneRtt { number: 1 } path_id=0 frame=NewConnectionId { sequence_number: 2, retire_prior_to: 1 } id=0 1.079312630s s2n_quic:server:conn: frame_sent: packet_header=OneRtt { number: 1 } path_id=0 frame=NewConnectionId { sequence_number: 3, retire_prior_to: 1 } id=0 1.079329401s s2n_quic:server:conn: frame_sent: packet_header=OneRtt { number: 1 } path_id=0 frame=RetireConnectionId id=0 1.079369576s s2n_quic:server:conn: packet_sent: packet_header=OneRtt { number: 1 } packet_len=146 id=0 1.079387510s s2n_quic:server:conn: datagram_sent: len=146 gso_offset=0 id=0 1.079419489s s2n_quic:server:conn: recovery_metrics: path=Path { local_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, local_cid: 0x3e7cb0db1a09335878e9f9d3a423f496, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:52193, remote_cid: 0xd323ecc697ff06bf, id: 0, is_active: true } min_rtt=35.279ms smoothed_rtt=35.279ms latest_rtt=35.279ms rtt_variance=17.6395ms max_ack_delay=25ms pto_count=0 congestion_window=13552 bytes_in_flight=1598 congestion_limited=false id=0 1.079483519s s2n_quic:server: platform_event_loop_sleep: timeout=Some(130.407ms) processing_duration=1.962ms Accepted a QUIC connection from [fd00:cafe:cafe::100]:52193 on [fd00:cafe:cafe:100::100]:443 1.080190791s s2n_quic_qns::server::h09: 4096 bytes ready to send on Stream(0) 1.080245052s s2n_quic_qns::server::h09: 4096 bytes sent on Stream(0) 1.080313159s s2n_quic:server: platform_event_loop_wakeup: timeout_expired=false rx_ready=false tx_ready=false application_wakeup=true 1.080341612s s2n_quic:server: platform_tx: count=2 syscalls=1 blocked_syscalls=0 total_errors=0 dropped_errors=0 1.080378291s s2n_quic:server:conn: frame_sent: packet_header=OneRtt { number: 2 } path_id=0 frame=HandshakeDone id=0 1.080416702s s2n_quic:server:conn: frame_sent: packet_header=OneRtt { number: 2 } path_id=0 frame=Stream { id: 0, offset: 0, len: 1203, is_fin: false } id=0 1.080456247s s2n_quic:server:conn: packet_sent: packet_header=OneRtt { number: 2 } packet_len=1232 id=0 1.080473348s s2n_quic:server:conn: datagram_sent: len=1232 gso_offset=0 id=0 1.080501501s s2n_quic:server:conn: frame_sent: packet_header=OneRtt { number: 3 } path_id=0 frame=HandshakeDone id=0 1.080531437s s2n_quic:server:conn: frame_sent: packet_header=OneRtt { number: 3 } path_id=0 frame=Stream { id: 0, offset: 1203, len: 1201, is_fin: false } id=0 1.080565511s s2n_quic:server:conn: packet_sent: packet_header=OneRtt { number: 3 } packet_len=1232 id=0 1.080582051s s2n_quic:server:conn: datagram_sent: len=1232 gso_offset=0 id=0 1.080609563s s2n_quic:server:conn: frame_sent: packet_header=OneRtt { number: 4 } path_id=0 frame=HandshakeDone id=0 1.080674464s s2n_quic:server:conn: frame_sent: packet_header=OneRtt { number: 4 } path_id=0 frame=Stream { id: 0, offset: 2404, len: 1201, is_fin: false } id=0 1.080723406s s2n_quic:server:conn: packet_sent: packet_header=OneRtt { number: 4 } packet_len=1232 id=0 1.080741139s s2n_quic:server:conn: datagram_sent: len=1232 gso_offset=0 id=0 1.080770935s s2n_quic:server:conn: frame_sent: packet_header=OneRtt { number: 5 } path_id=0 frame=HandshakeDone id=0 1.080804698s s2n_quic:server:conn: frame_sent: packet_header=OneRtt { number: 5 } path_id=0 frame=Stream { id: 0, offset: 3605, len: 491, is_fin: false } id=0 1.080846225s s2n_quic:server:conn: packet_sent: packet_header=OneRtt { number: 5 } packet_len=524 id=0 1.080862916s s2n_quic:server:conn: datagram_sent: len=524 gso_offset=0 id=0 1.080894245s s2n_quic:server:conn: recovery_metrics: path=Path { local_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, local_cid: 0x3e7cb0db1a09335878e9f9d3a423f496, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:52193, remote_cid: 0xd323ecc697ff06bf, id: 0, is_active: true } min_rtt=35.279ms smoothed_rtt=35.279ms latest_rtt=35.279ms rtt_variance=17.6395ms max_ack_delay=25ms pto_count=0 congestion_window=13552 bytes_in_flight=5818 congestion_limited=false id=0 1.080927126s s2n_quic:server:conn: tx_stream_progress: bytes=4096 id=0 1.080963123s s2n_quic:server: platform_event_loop_sleep: timeout=Some(130.232ms) processing_duration=651µs 1.081005643s s2n_quic_qns::server::h09: 1024 bytes ready to send on Stream(0) 1.081039967s s2n_quic_qns::server::h09: 1024 bytes sent on Stream(0) 1.081192222s s2n_quic:server: platform_event_loop_wakeup: timeout_expired=false rx_ready=false tx_ready=false application_wakeup=true 1.081221767s s2n_quic:server: platform_tx: count=4 syscalls=1 blocked_syscalls=0 total_errors=0 dropped_errors=0 1.081258596s s2n_quic:server:conn: frame_sent: packet_header=OneRtt { number: 6 } path_id=0 frame=HandshakeDone id=0 1.081294753s s2n_quic:server:conn: frame_sent: packet_header=OneRtt { number: 6 } path_id=0 frame=Stream { id: 0, offset: 4096, len: 1024, is_fin: true } id=0 1.081334327s s2n_quic:server:conn: packet_sent: packet_header=OneRtt { number: 6 } packet_len=1057 id=0 1.081351549s s2n_quic:server:conn: datagram_sent: len=1057 gso_offset=0 id=0 1.081383379s s2n_quic:server:conn: recovery_metrics: path=Path { local_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, local_cid: 0x3e7cb0db1a09335878e9f9d3a423f496, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:52193, remote_cid: 0xd323ecc697ff06bf, id: 0, is_active: true } min_rtt=35.279ms smoothed_rtt=35.279ms latest_rtt=35.279ms rtt_variance=17.6395ms max_ack_delay=25ms pto_count=0 congestion_window=13552 bytes_in_flight=6875 congestion_limited=false id=0 1.081413064s s2n_quic:server:conn: tx_stream_progress: bytes=1024 id=0 1.081447719s s2n_quic:server: platform_event_loop_sleep: timeout=Some(130.628ms) processing_duration=256µs 1.111891630s s2n_quic:server: platform_event_loop_wakeup: timeout_expired=false rx_ready=true tx_ready=false application_wakeup=false 1.111982319s s2n_quic:server: platform_tx: count=1 syscalls=1 blocked_syscalls=0 total_errors=0 dropped_errors=0 1.112000634s s2n_quic:server: platform_rx: count=1 syscalls=2 blocked_syscalls=1 total_errors=0 dropped_errors=0 1.112076846s s2n_quic:server:conn: datagram_received: len=42 id=0 1.112159781s s2n_quic:server:conn: packet_received: packet_header=OneRtt { number: 4 } id=0 1.112184868s s2n_quic:server:conn: connection_id_updated: path_id=0 cid_consumer=Remote previous=0x3e7cb0db1a09335878e9f9d3a423f496 current=0xa46044ce3e69d6472bf15a881f474b57 id=0 1.112221897s s2n_quic:server:conn: frame_received: packet_header=OneRtt { number: 4 } path=Path { local_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, local_cid: 0xa46044ce3e69d6472bf15a881f474b57, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:52193, remote_cid: 0xd323ecc697ff06bf, id: 0, is_active: true } frame=Ack { ecn_counts: None, largest_acknowledged: 1, ack_range_count: 1 } id=0 1.112270448s s2n_quic:server:conn: ack_range_received: packet_header=OneRtt { number: 4 } path=Path { local_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, local_cid: 0xa46044ce3e69d6472bf15a881f474b57, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:52193, remote_cid: 0xd323ecc697ff06bf, id: 0, is_active: true } ack_range=0..=1 id=0 1.112318498s s2n_quic:server:conn: mtu_updated: path_id=0 mtu=1452 cause=ProbeAcknowledged search_complete=false id=0 1.112340709s s2n_quic:server:conn: handshake_status_updated: status=HandshakeDoneAcked id=0 1.112419807s s2n_quic:server:conn: delivery_rate_sampled: path_id=0 rate_sample=RateSample { interval: 32.982ms, delivered_bytes: 1598, lost_bytes: 0, ecn_ce_count: 0, is_app_limited: true, prior_delivered_bytes: 1232, bytes_in_flight: 1598, prior_lost_bytes: 0, prior_ecn_ce_count: 0, delivery_rate_bytes_per_second: 48450 } id=0 1.112458169s s2n_quic:server:conn: recovery_metrics: path=Path { local_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, local_cid: 0xa46044ce3e69d6472bf15a881f474b57, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:52193, remote_cid: 0xd323ecc697ff06bf, id: 0, is_active: true } min_rtt=32.982ms smoothed_rtt=34.991875ms latest_rtt=32.982ms rtt_variance=13.803875ms max_ack_delay=25ms pto_count=0 congestion_window=17570 bytes_in_flight=5277 congestion_limited=false id=0 1.112488706s s2n_quic:server:conn: frame_received: packet_header=OneRtt { number: 4 } path=Path { local_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, local_cid: 0xa46044ce3e69d6472bf15a881f474b57, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:52193, remote_cid: 0xd323ecc697ff06bf, id: 0, is_active: true } frame=RetireConnectionId id=0 1.112601266s s2n_quic:server:conn: frame_sent: packet_header=OneRtt { number: 7 } path_id=0 frame=Ping id=0 1.112679072s s2n_quic:server:conn: frame_sent: packet_header=OneRtt { number: 7 } path_id=0 frame=Padding id=0 1.112722753s s2n_quic:server:conn: packet_sent: packet_header=OneRtt { number: 7 } packet_len=5202 id=0 1.112739645s s2n_quic:server:conn: datagram_sent: len=5202 gso_offset=0 id=0 1.112775702s s2n_quic:server:conn: recovery_metrics: path=Path { local_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, local_cid: 0xa46044ce3e69d6472bf15a881f474b57, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:52193, remote_cid: 0xd323ecc697ff06bf, id: 0, is_active: true } min_rtt=32.982ms smoothed_rtt=34.991875ms latest_rtt=32.982ms rtt_variance=13.803875ms max_ack_delay=25ms pto_count=0 congestion_window=17570 bytes_in_flight=10479 congestion_limited=false id=0 1.112840543s s2n_quic:server: platform_event_loop_sleep: timeout=Some(24.197ms) processing_duration=964µs 1.113652079s s2n_quic:server: platform_event_loop_wakeup: timeout_expired=false rx_ready=true tx_ready=false application_wakeup=false 1.113689519s s2n_quic:server: platform_tx_error: errno=90 1.113701832s s2n_quic:server: platform_tx: count=0 syscalls=1 blocked_syscalls=0 total_errors=1 dropped_errors=0 1.113713995s s2n_quic:server: platform_rx: count=1 syscalls=2 blocked_syscalls=1 total_errors=0 dropped_errors=0 1.113745364s s2n_quic:server:conn: datagram_received: len=40 id=0 1.113775750s s2n_quic:server:conn: packet_received: packet_header=OneRtt { number: 5 } id=0 1.113796059s s2n_quic:server:conn: frame_received: packet_header=OneRtt { number: 5 } path=Path { local_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, local_cid: 0xa46044ce3e69d6472bf15a881f474b57, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:52193, remote_cid: 0xd323ecc697ff06bf, id: 0, is_active: true } frame=Ack { ecn_counts: None, largest_acknowledged: 3, ack_range_count: 1 } id=0 1.113824311s s2n_quic:server:conn: ack_range_received: packet_header=OneRtt { number: 5 } path=Path { local_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, local_cid: 0xa46044ce3e69d6472bf15a881f474b57, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:52193, remote_cid: 0xd323ecc697ff06bf, id: 0, is_active: true } ack_range=0..=3 id=0 1.113877390s s2n_quic:server:conn: delivery_rate_sampled: path_id=0 rate_sample=RateSample { interval: 32.982ms, delivered_bytes: 4062, lost_bytes: 0, ecn_ce_count: 0, is_app_limited: true, prior_delivered_bytes: 1232, bytes_in_flight: 1598, prior_lost_bytes: 0, prior_ecn_ce_count: 0, delivery_rate_bytes_per_second: 123158 } id=0 1.113906745s s2n_quic:server:conn: recovery_metrics: path=Path { local_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, local_cid: 0xa46044ce3e69d6472bf15a881f474b57, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:52193, remote_cid: 0xd323ecc697ff06bf, id: 0, is_active: true } min_rtt=32.982ms smoothed_rtt=34.788763ms latest_rtt=33.373ms rtt_variance=10.759122ms max_ack_delay=25ms pto_count=0 congestion_window=20034 bytes_in_flight=8015 congestion_limited=false id=0 1.113966938s s2n_quic:server: platform_event_loop_sleep: timeout=Some(23.07ms) processing_duration=334µs 1.115108961s s2n_quic:server: platform_event_loop_wakeup: timeout_expired=false rx_ready=true tx_ready=false application_wakeup=false 1.115138106s s2n_quic:server: platform_rx: count=1 syscalls=2 blocked_syscalls=1 total_errors=0 dropped_errors=0 1.115169474s s2n_quic:server:conn: datagram_received: len=40 id=0 1.115199521s s2n_quic:server:conn: packet_received: packet_header=OneRtt { number: 6 } id=0 1.115219939s s2n_quic:server:conn: frame_received: packet_header=OneRtt { number: 6 } path=Path { local_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, local_cid: 0xa46044ce3e69d6472bf15a881f474b57, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:52193, remote_cid: 0xd323ecc697ff06bf, id: 0, is_active: true } frame=Ack { ecn_counts: None, largest_acknowledged: 5, ack_range_count: 1 } id=0 1.115241900s s2n_quic:server:conn: ack_range_received: packet_header=OneRtt { number: 6 } path=Path { local_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, local_cid: 0xa46044ce3e69d6472bf15a881f474b57, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:52193, remote_cid: 0xd323ecc697ff06bf, id: 0, is_active: true } ack_range=0..=5 id=0 1.115300860s s2n_quic:server:conn: delivery_rate_sampled: path_id=0 rate_sample=RateSample { interval: 32.982ms, delivered_bytes: 5818, lost_bytes: 0, ecn_ce_count: 0, is_app_limited: true, prior_delivered_bytes: 1232, bytes_in_flight: 1598, prior_lost_bytes: 0, prior_ecn_ce_count: 0, delivery_rate_bytes_per_second: 176399 } id=0 1.115331597s s2n_quic:server:conn: recovery_metrics: path=Path { local_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, local_cid: 0xa46044ce3e69d6472bf15a881f474b57, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:52193, remote_cid: 0xd323ecc697ff06bf, id: 0, is_active: true } min_rtt=32.982ms smoothed_rtt=34.789165ms latest_rtt=34.798ms rtt_variance=8.070149ms max_ack_delay=25ms pto_count=0 congestion_window=21790 bytes_in_flight=6259 congestion_limited=false id=0 1.115391229s s2n_quic:server: platform_event_loop_sleep: timeout=Some(21.645ms) processing_duration=283µs 1.116215248s s2n_quic:server: platform_event_loop_wakeup: timeout_expired=false rx_ready=true tx_ready=false application_wakeup=false 1.116244173s s2n_quic:server: platform_rx: count=1 syscalls=2 blocked_syscalls=1 total_errors=0 dropped_errors=0 1.116275661s s2n_quic:server:conn: datagram_received: len=60 id=0 1.116306178s s2n_quic:server:conn: packet_received: packet_header=OneRtt { number: 7 } id=0 1.116326687s s2n_quic:server:conn: frame_received: packet_header=OneRtt { number: 7 } path=Path { local_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, local_cid: 0xa46044ce3e69d6472bf15a881f474b57, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:52193, remote_cid: 0xd323ecc697ff06bf, id: 0, is_active: true } frame=Ack { ecn_counts: None, largest_acknowledged: 6, ack_range_count: 1 } id=0 1.116354789s s2n_quic:server:conn: ack_range_received: packet_header=OneRtt { number: 7 } path=Path { local_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, local_cid: 0xa46044ce3e69d6472bf15a881f474b57, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:52193, remote_cid: 0xd323ecc697ff06bf, id: 0, is_active: true } ack_range=0..=6 id=0 1.116420251s s2n_quic:server:conn: delivery_rate_sampled: path_id=0 rate_sample=RateSample { interval: 32.982ms, delivered_bytes: 6875, lost_bytes: 0, ecn_ce_count: 0, is_app_limited: true, prior_delivered_bytes: 1232, bytes_in_flight: 1598, prior_lost_bytes: 0, prior_ecn_ce_count: 0, delivery_rate_bytes_per_second: 208447 } id=0 1.116450688s s2n_quic:server:conn: recovery_metrics: path=Path { local_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, local_cid: 0xa46044ce3e69d6472bf15a881f474b57, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:52193, remote_cid: 0xd323ecc697ff06bf, id: 0, is_active: true } min_rtt=32.982ms smoothed_rtt=34.817515ms latest_rtt=35.024ms rtt_variance=6.109319ms max_ack_delay=25ms pto_count=0 congestion_window=22847 bytes_in_flight=5202 congestion_limited=false id=0 1.116479001s s2n_quic:server:conn: frame_received: packet_header=OneRtt { number: 7 } path=Path { local_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, local_cid: 0xa46044ce3e69d6472bf15a881f474b57, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:52193, remote_cid: 0xd323ecc697ff06bf, id: 0, is_active: true } frame=Stream { id: 4, offset: 0, len: 17, is_fin: true } id=0 1.116541288s s2n_quic:server:conn: rx_stream_progress: bytes=17 id=0 1.116610287s s2n_quic:server:conn: frame_sent: packet_header=OneRtt { number: 8 } path_id=0 frame=Ack { ecn_counts: Some(EcnCounts { ect_0_count: 0, ect_1_count: 5, ce_count: 0 }), largest_acknowledged: 7, ack_range_count: 1 } id=0 1.116655581s s2n_quic:server:conn: ack_range_sent: packet_header=OneRtt { number: 8 } path_id=0 ack_range=4..=7 id=0 1.116688593s s2n_quic:server:conn: frame_sent: packet_header=OneRtt { number: 8 } path_id=0 frame=Padding id=0 1.116718940s s2n_quic:server:conn: packet_sent: packet_header=OneRtt { number: 8 } packet_len=45 id=0 1.116734288s s2n_quic:server:conn: datagram_sent: len=45 gso_offset=0 id=0 1.116765937s s2n_quic:server:conn: recovery_metrics: path=Path { local_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, local_cid: 0xa46044ce3e69d6472bf15a881f474b57, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:52193, remote_cid: 0xd323ecc697ff06bf, id: 0, is_active: true } min_rtt=32.982ms smoothed_rtt=34.817515ms latest_rtt=35.024ms rtt_variance=6.109319ms max_ack_delay=25ms pto_count=0 congestion_window=22847 bytes_in_flight=5202 congestion_limited=false id=0 1.116817113s s2n_quic:server: platform_event_loop_sleep: timeout=Some(32.75ms) processing_duration=603µs 1.117137321s s2n_quic_qns::server::h09: 4096 bytes ready to send on Stream(4) 1.117181173s s2n_quic_qns::server::h09: 4096 bytes sent on Stream(4) 1.117242879s s2n_quic:server: platform_event_loop_wakeup: timeout_expired=false rx_ready=false tx_ready=false application_wakeup=true 1.117270500s s2n_quic:server: platform_tx: count=1 syscalls=1 blocked_syscalls=0 total_errors=0 dropped_errors=0 1.117324481s s2n_quic:server:conn: frame_sent: packet_header=OneRtt { number: 9 } path_id=0 frame=Stream { id: 4, offset: 0, len: 1424, is_fin: false } id=0 1.117370467s s2n_quic:server:conn: packet_sent: packet_header=OneRtt { number: 9 } packet_len=1452 id=0 1.117387629s s2n_quic:server:conn: datagram_sent: len=1452 gso_offset=0 id=0 1.117428896s s2n_quic:server:conn: frame_sent: packet_header=OneRtt { number: 10 } path_id=0 frame=Stream { id: 4, offset: 1424, len: 1422, is_fin: false } id=0 1.117474181s s2n_quic:server:conn: packet_sent: packet_header=OneRtt { number: 10 } packet_len=1452 id=0 1.117491814s s2n_quic:server:conn: datagram_sent: len=1452 gso_offset=0 id=0 1.117533782s s2n_quic:server:conn: frame_sent: packet_header=OneRtt { number: 11 } path_id=0 frame=Stream { id: 4, offset: 2846, len: 1250, is_fin: false } id=0 1.117573717s s2n_quic:server:conn: packet_sent: packet_header=OneRtt { number: 11 } packet_len=1282 id=0 1.117590478s s2n_quic:server:conn: datagram_sent: len=1282 gso_offset=0 id=0 1.117608672s s2n_quic:server:conn: recovery_metrics: path=Path { local_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, local_cid: 0xa46044ce3e69d6472bf15a881f474b57, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:52193, remote_cid: 0xd323ecc697ff06bf, id: 0, is_active: true } min_rtt=32.982ms smoothed_rtt=34.817515ms latest_rtt=35.024ms rtt_variance=6.109319ms max_ack_delay=25ms pto_count=0 congestion_window=22847 bytes_in_flight=9388 congestion_limited=false id=0 1.117656852s s2n_quic:server:conn: tx_stream_progress: bytes=4096 id=0 1.117701315s s2n_quic:server: platform_event_loop_sleep: timeout=Some(2.619ms) processing_duration=459µs 1.117789790s s2n_quic_qns::server::h09: 4096 bytes ready to send on Stream(4) 1.117827451s s2n_quic_qns::server::h09: 4096 bytes sent on Stream(4) 1.117886141s s2n_quic:server: platform_event_loop_wakeup: timeout_expired=false rx_ready=false tx_ready=false application_wakeup=true 1.117912570s s2n_quic:server: platform_tx: count=3 syscalls=1 blocked_syscalls=0 total_errors=0 dropped_errors=0 1.117931315s s2n_quic:server: platform_event_loop_sleep: timeout=Some(2.388ms) processing_duration=46µs 1.117964457s s2n_quic_qns::server::h09: 2048 bytes ready to send on Stream(4) 1.117991538s s2n_quic_qns::server::h09: 2048 bytes sent on Stream(4) 1.121172431s s2n_quic:server: platform_event_loop_wakeup: timeout_expired=true rx_ready=false tx_ready=false application_wakeup=false 1.121237883s s2n_quic:server:conn: recovery_metrics: path=Path { local_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, local_cid: 0xa46044ce3e69d6472bf15a881f474b57, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:52193, remote_cid: 0xd323ecc697ff06bf, id: 0, is_active: true } min_rtt=32.982ms smoothed_rtt=34.817515ms latest_rtt=35.024ms rtt_variance=6.109319ms max_ack_delay=25ms pto_count=0 congestion_window=22847 bytes_in_flight=9388 congestion_limited=false id=0 1.121375290s s2n_quic:server:conn: frame_sent: packet_header=OneRtt { number: 12 } path_id=0 frame=Stream { id: 4, offset: 4096, len: 1422, is_fin: false } id=0 1.121438358s s2n_quic:server:conn: packet_sent: packet_header=OneRtt { number: 12 } packet_len=1452 id=0 1.121459377s s2n_quic:server:conn: datagram_sent: len=1452 gso_offset=0 id=0 1.121509761s s2n_quic:server:conn: frame_sent: packet_header=OneRtt { number: 13 } path_id=0 frame=Stream { id: 4, offset: 5518, len: 1422, is_fin: false } id=0 1.121557060s s2n_quic:server:conn: packet_sent: packet_header=OneRtt { number: 13 } packet_len=1452 id=0 1.121577588s s2n_quic:server:conn: datagram_sent: len=1452 gso_offset=0 id=0 1.121626279s s2n_quic:server:conn: frame_sent: packet_header=OneRtt { number: 14 } path_id=0 frame=Stream { id: 4, offset: 6940, len: 1422, is_fin: false } id=0 1.121707631s s2n_quic:server:conn: packet_sent: packet_header=OneRtt { number: 14 } packet_len=1452 id=0 1.121728590s s2n_quic:server:conn: datagram_sent: len=1452 gso_offset=0 id=0 1.121750441s s2n_quic:server:conn: recovery_metrics: path=Path { local_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, local_cid: 0xa46044ce3e69d6472bf15a881f474b57, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:52193, remote_cid: 0xd323ecc697ff06bf, id: 0, is_active: true } min_rtt=32.982ms smoothed_rtt=34.817515ms latest_rtt=35.024ms rtt_variance=6.109319ms max_ack_delay=25ms pto_count=0 congestion_window=22847 bytes_in_flight=13744 congestion_limited=false id=0 1.121790866s s2n_quic:server:conn: tx_stream_progress: bytes=4270 id=0 1.121836672s s2n_quic:server: platform_event_loop_sleep: timeout=Some(1.516ms) processing_duration=666µs 1.124031924s s2n_quic:server: platform_event_loop_wakeup: timeout_expired=true rx_ready=false tx_ready=false application_wakeup=false 1.124062972s s2n_quic:server: platform_tx: count=3 syscalls=1 blocked_syscalls=0 total_errors=0 dropped_errors=0 1.124092377s s2n_quic:server:conn: recovery_metrics: path=Path { local_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, local_cid: 0xa46044ce3e69d6472bf15a881f474b57, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:52193, remote_cid: 0xd323ecc697ff06bf, id: 0, is_active: true } min_rtt=32.982ms smoothed_rtt=34.817515ms latest_rtt=35.024ms rtt_variance=6.109319ms max_ack_delay=25ms pto_count=0 congestion_window=22847 bytes_in_flight=13744 congestion_limited=false id=0 1.124166315s s2n_quic:server:conn: frame_sent: packet_header=OneRtt { number: 15 } path_id=0 frame=Stream { id: 4, offset: 8362, len: 1422, is_fin: false } id=0 1.124221267s s2n_quic:server:conn: packet_sent: packet_header=OneRtt { number: 15 } packet_len=1452 id=0 1.124239652s s2n_quic:server:conn: datagram_sent: len=1452 gso_offset=0 id=0 1.124283764s s2n_quic:server:conn: frame_sent: packet_header=OneRtt { number: 16 } path_id=0 frame=Stream { id: 4, offset: 9784, len: 456, is_fin: true } id=0 1.124324861s s2n_quic:server:conn: packet_sent: packet_header=OneRtt { number: 16 } packet_len=488 id=0 1.124345049s s2n_quic:server:conn: datagram_sent: len=488 gso_offset=0 id=0 1.124366289s s2n_quic:server:conn: recovery_metrics: path=Path { local_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, local_cid: 0xa46044ce3e69d6472bf15a881f474b57, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:52193, remote_cid: 0xd323ecc697ff06bf, id: 0, is_active: true } min_rtt=32.982ms smoothed_rtt=34.817515ms latest_rtt=35.024ms rtt_variance=6.109319ms max_ack_delay=25ms pto_count=0 congestion_window=22847 bytes_in_flight=15684 congestion_limited=false id=0 1.124398278s s2n_quic:server:conn: tx_stream_progress: bytes=1874 id=0 1.124442100s s2n_quic:server: platform_event_loop_sleep: timeout=Some(1.943ms) processing_duration=410µs 1.127652348s s2n_quic:server: platform_event_loop_wakeup: timeout_expired=true rx_ready=false tx_ready=false application_wakeup=false 1.127695038s s2n_quic:server: platform_tx: count=2 syscalls=1 blocked_syscalls=0 total_errors=0 dropped_errors=0 1.127732809s s2n_quic:server:conn: recovery_metrics: path=Path { local_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, local_cid: 0xa46044ce3e69d6472bf15a881f474b57, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:52193, remote_cid: 0xd323ecc697ff06bf, id: 0, is_active: true } min_rtt=32.982ms smoothed_rtt=34.817515ms latest_rtt=35.024ms rtt_variance=6.109319ms max_ack_delay=25ms pto_count=0 congestion_window=22847 bytes_in_flight=15684 congestion_limited=false id=0 1.127798922s s2n_quic:server: platform_event_loop_sleep: timeout=Some(21.769ms) processing_duration=169µs 1.150011225s s2n_quic:server: platform_event_loop_wakeup: timeout_expired=true rx_ready=false tx_ready=false application_wakeup=false 1.150151257s s2n_quic:server:conn: recovery_metrics: path=Path { local_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, local_cid: 0xa46044ce3e69d6472bf15a881f474b57, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:52193, remote_cid: 0xd323ecc697ff06bf, id: 0, is_active: true } min_rtt=32.982ms smoothed_rtt=34.817515ms latest_rtt=35.024ms rtt_variance=6.109319ms max_ack_delay=25ms pto_count=0 congestion_window=22847 bytes_in_flight=15684 congestion_limited=false id=0 1.150264118s s2n_quic:server: platform_event_loop_sleep: timeout=Some(58.126ms) processing_duration=266µs 1.150781535s s2n_quic:server: platform_event_loop_wakeup: timeout_expired=false rx_ready=true tx_ready=false application_wakeup=false 1.150814306s s2n_quic:server: platform_rx: count=1 syscalls=2 blocked_syscalls=1 total_errors=0 dropped_errors=0 1.150875931s s2n_quic:server:conn: datagram_received: len=40 id=0 1.150927267s s2n_quic:server:conn: packet_received: packet_header=OneRtt { number: 8 } id=0 1.150956331s s2n_quic:server:conn: frame_received: packet_header=OneRtt { number: 8 } path=Path { local_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, local_cid: 0xa46044ce3e69d6472bf15a881f474b57, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:52193, remote_cid: 0xd323ecc697ff06bf, id: 0, is_active: true } frame=Ack { ecn_counts: None, largest_acknowledged: 10, ack_range_count: 1 } id=0 1.150984284s s2n_quic:server:conn: ack_range_received: packet_header=OneRtt { number: 8 } path=Path { local_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, local_cid: 0xa46044ce3e69d6472bf15a881f474b57, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:52193, remote_cid: 0xd323ecc697ff06bf, id: 0, is_active: true } ack_range=8..=10 id=0 1.151074642s s2n_quic:server:conn: packet_lost: packet_header=OneRtt { number: 7 } path=Path { local_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, local_cid: 0xa46044ce3e69d6472bf15a881f474b57, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:52193, remote_cid: 0xd323ecc697ff06bf, id: 0, is_active: true } bytes_lost=5202 is_mtu_probe=true id=0 1.151113214s s2n_quic:server:conn: delivery_rate_sampled: path_id=0 rate_sample=RateSample { interval: 38.234ms, delivered_bytes: 2904, lost_bytes: 0, ecn_ce_count: 0, is_app_limited: true, prior_delivered_bytes: 8107, bytes_in_flight: 8106, prior_lost_bytes: 0, prior_ecn_ce_count: 0, delivery_rate_bytes_per_second: 75953 } id=0 1.151144032s s2n_quic:server:conn: recovery_metrics: path=Path { local_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, local_cid: 0xa46044ce3e69d6472bf15a881f474b57, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:52193, remote_cid: 0xd323ecc697ff06bf, id: 0, is_active: true } min_rtt=32.982ms smoothed_rtt=34.659073ms latest_rtt=33.556ms rtt_variance=4.898865ms max_ack_delay=25ms pto_count=0 congestion_window=25751 bytes_in_flight=7578 congestion_limited=false id=0 1.151252014s s2n_quic:server:conn: frame_sent: packet_header=OneRtt { number: 17 } path_id=0 frame=Ping id=0 1.151325070s s2n_quic:server:conn: frame_sent: packet_header=OneRtt { number: 17 } path_id=0 frame=Padding id=0 1.151378129s s2n_quic:server:conn: packet_sent: packet_header=OneRtt { number: 17 } packet_len=5202 id=0 1.151690623s s2n_quic:server:conn: datagram_sent: len=5202 gso_offset=0 id=0 1.151753621s s2n_quic:server:conn: recovery_metrics: path=Path { local_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, local_cid: 0xa46044ce3e69d6472bf15a881f474b57, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:52193, remote_cid: 0xd323ecc697ff06bf, id: 0, is_active: true } min_rtt=32.982ms smoothed_rtt=34.659073ms latest_rtt=33.556ms rtt_variance=4.898865ms max_ack_delay=25ms pto_count=0 congestion_window=25751 bytes_in_flight=12780 congestion_limited=false id=0 1.151820947s s2n_quic:server: platform_event_loop_sleep: timeout=Some(65.191ms) processing_duration=1.04ms 1.153748627s s2n_quic:server: platform_event_loop_wakeup: timeout_expired=false rx_ready=true tx_ready=false application_wakeup=false 1.153791176s s2n_quic:server: platform_tx_error: errno=90 1.153813879s s2n_quic:server: platform_tx: count=0 syscalls=1 blocked_syscalls=0 total_errors=1 dropped_errors=0 1.153837372s s2n_quic:server: platform_rx: count=1 syscalls=2 blocked_syscalls=1 total_errors=0 dropped_errors=0 1.153886194s s2n_quic:server:conn: datagram_received: len=40 id=0 1.153946326s s2n_quic:server:conn: packet_received: packet_header=OneRtt { number: 9 } id=0 1.153985209s s2n_quic:server:conn: frame_received: packet_header=OneRtt { number: 9 } path=Path { local_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, local_cid: 0xa46044ce3e69d6472bf15a881f474b57, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:52193, remote_cid: 0xd323ecc697ff06bf, id: 0, is_active: true } frame=Ack { ecn_counts: None, largest_acknowledged: 12, ack_range_count: 1 } id=0 1.154017058s s2n_quic:server:conn: ack_range_received: packet_header=OneRtt { number: 9 } path=Path { local_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, local_cid: 0xa46044ce3e69d6472bf15a881f474b57, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:52193, remote_cid: 0xd323ecc697ff06bf, id: 0, is_active: true } ack_range=8..=12 id=0 1.154074536s s2n_quic:server:conn: delivery_rate_sampled: path_id=0 rate_sample=RateSample { interval: 38.234ms, delivered_bytes: 5638, lost_bytes: 0, ecn_ce_count: 0, is_app_limited: true, prior_delivered_bytes: 8107, bytes_in_flight: 8106, prior_lost_bytes: 0, prior_ecn_ce_count: 0, delivery_rate_bytes_per_second: 147460 } id=0 1.154106405s s2n_quic:server:conn: recovery_metrics: path=Path { local_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, local_cid: 0xa46044ce3e69d6472bf15a881f474b57, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:52193, remote_cid: 0xd323ecc697ff06bf, id: 0, is_active: true } min_rtt=32.532ms smoothed_rtt=34.393188ms latest_rtt=32.532ms rtt_variance=4.205916ms max_ack_delay=25ms pto_count=0 congestion_window=28485 bytes_in_flight=10046 congestion_limited=false id=0 1.154170114s s2n_quic:server: platform_event_loop_sleep: timeout=Some(62.841ms) processing_duration=424µs 1.156350408s s2n_quic:server: platform_event_loop_wakeup: timeout_expired=false rx_ready=true tx_ready=false application_wakeup=false 1.156404720s s2n_quic:server: platform_rx: count=1 syscalls=2 blocked_syscalls=1 total_errors=0 dropped_errors=0 1.156448682s s2n_quic:server:conn: datagram_received: len=40 id=0 1.156499978s s2n_quic:server:conn: packet_received: packet_header=OneRtt { number: 10 } id=0 1.156534953s s2n_quic:server:conn: frame_received: packet_header=OneRtt { number: 10 } path=Path { local_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, local_cid: 0xa46044ce3e69d6472bf15a881f474b57, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:52193, remote_cid: 0xd323ecc697ff06bf, id: 0, is_active: true } frame=Ack { ecn_counts: None, largest_acknowledged: 14, ack_range_count: 1 } id=0 1.156575368s s2n_quic:server:conn: ack_range_received: packet_header=OneRtt { number: 10 } path=Path { local_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, local_cid: 0xa46044ce3e69d6472bf15a881f474b57, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:52193, remote_cid: 0xd323ecc697ff06bf, id: 0, is_active: true } ack_range=8..=14 id=0 1.156671218s s2n_quic:server:conn: delivery_rate_sampled: path_id=0 rate_sample=RateSample { interval: 38.234ms, delivered_bytes: 8542, lost_bytes: 0, ecn_ce_count: 0, is_app_limited: true, prior_delivered_bytes: 8107, bytes_in_flight: 8106, prior_lost_bytes: 0, prior_ecn_ce_count: 0, delivery_rate_bytes_per_second: 223413 } id=0 1.156721842s s2n_quic:server:conn: recovery_metrics: path=Path { local_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, local_cid: 0xa46044ce3e69d6472bf15a881f474b57, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:52193, remote_cid: 0xd323ecc697ff06bf, id: 0, is_active: true } min_rtt=32.532ms smoothed_rtt=34.479661ms latest_rtt=35.092ms rtt_variance=3.32739ms max_ack_delay=25ms pto_count=0 congestion_window=31389 bytes_in_flight=7142 congestion_limited=false id=0 1.156792875s s2n_quic:server: platform_event_loop_sleep: timeout=Some(60.219ms) processing_duration=446µs 1.157753870s s2n_quic:server: platform_event_loop_wakeup: timeout_expired=false rx_ready=true tx_ready=false application_wakeup=false 1.157791992s s2n_quic:server: platform_rx: count=2 syscalls=2 blocked_syscalls=1 total_errors=0 dropped_errors=0 1.157861441s s2n_quic:server:conn: datagram_received: len=40 id=0 1.157933476s s2n_quic:server:conn: packet_received: packet_header=OneRtt { number: 11 } id=0 1.157972068s s2n_quic:server:conn: frame_received: packet_header=OneRtt { number: 11 } path=Path { local_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, local_cid: 0xa46044ce3e69d6472bf15a881f474b57, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:52193, remote_cid: 0xd323ecc697ff06bf, id: 0, is_active: true } frame=Ack { ecn_counts: None, largest_acknowledged: 16, ack_range_count: 1 } id=0 1.158007374s s2n_quic:server:conn: ack_range_received: packet_header=OneRtt { number: 11 } path=Path { local_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, local_cid: 0xa46044ce3e69d6472bf15a881f474b57, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:52193, remote_cid: 0xd323ecc697ff06bf, id: 0, is_active: true } ack_range=8..=16 id=0 1.158093194s s2n_quic:server:conn: delivery_rate_sampled: path_id=0 rate_sample=RateSample { interval: 38.234ms, delivered_bytes: 10482, lost_bytes: 0, ecn_ce_count: 0, is_app_limited: true, prior_delivered_bytes: 8107, bytes_in_flight: 8106, prior_lost_bytes: 0, prior_ecn_ce_count: 0, delivery_rate_bytes_per_second: 274153 } id=0 1.158127068s s2n_quic:server:conn: recovery_metrics: path=Path { local_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, local_cid: 0xa46044ce3e69d6472bf15a881f474b57, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:52193, remote_cid: 0xd323ecc697ff06bf, id: 0, is_active: true } min_rtt=32.532ms smoothed_rtt=34.381824ms latest_rtt=33.701ms rtt_variance=2.691206ms max_ack_delay=25ms pto_count=0 congestion_window=33329 bytes_in_flight=5202 congestion_limited=false id=0 1.158215252s s2n_quic:server:conn: datagram_received: len=38 id=0 1.158259646s s2n_quic:server:conn: packet_received: packet_header=OneRtt { number: 12 } id=0 1.158284612s s2n_quic:server:conn: frame_received: packet_header=OneRtt { number: 12 } path=Path { local_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, local_cid: 0xa46044ce3e69d6472bf15a881f474b57, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:52193, remote_cid: 0xd323ecc697ff06bf, id: 0, is_active: true } frame=ConnectionClose id=0 1.158318866s s2n_quic:server:conn: connection_close_frame_received: packet_header=OneRtt { number: 12 } path=Path { local_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, local_cid: 0xa46044ce3e69d6472bf15a881f474b57, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:52193, remote_cid: 0xd323ecc697ff06bf, id: 0, is_active: true } frame=ConnectionCloseFrame { error_code: 0, frame_type: Some(0), reason: None } id=0 1.158348371s s2n_quic:server:conn: connection_closed: error=Closed { initiator: Remote, source: Location { file: "/app/quic/s2n-quic-transport/src/space/mod.rs", line: 966, col: 32 } } id=0 1.158405989s s2n_quic:server: platform_event_loop_sleep: timeout=None processing_duration=655µs 1.158449540s s2n_quic_qns::server::h09: Final stats: MyConnectionContext { packet_sent: 20, stream_requests: 2 } 1.218323273s s2n_quic:server: platform_event_loop_wakeup: timeout_expired=true rx_ready=false tx_ready=false application_wakeup=false 1.218442576s s2n_quic:server: platform_event_loop_sleep: timeout=None processing_duration=132µs