0.049947758s s2n_quic:server: platform_feature_configured: configuration=Gso { max_segments: 1 } 0.050099332s s2n_quic:server: platform_feature_configured: configuration=BaseMtu { mtu: 1228 } 0.050116023s s2n_quic:server: platform_feature_configured: configuration=InitialMtu { mtu: 1280 } 0.050125601s s2n_quic:server: platform_feature_configured: configuration=MaxMtu { mtu: 9000 } 0.050214838s s2n_quic:server: platform_feature_configured: configuration=Gro { enabled: true } 0.050236448s s2n_quic:server: platform_feature_configured: configuration=Ecn { enabled: true } 0.051694348s s2n_quic:server: platform_event_loop_started: local_address=[::]:443 0.051823009s s2n_quic:server: platform_event_loop_wakeup: timeout_expired=false rx_ready=false tx_ready=true application_wakeup=false 0.051861070s s2n_quic:server: platform_rx: count=0 syscalls=1 blocked_syscalls=1 total_errors=0 dropped_errors=0 0.051907347s s2n_quic:server: platform_event_loop_sleep: timeout=None processing_duration=89µs 0.865235790s s2n_quic:server: platform_event_loop_wakeup: timeout_expired=false rx_ready=true tx_ready=false application_wakeup=false 0.865344434s s2n_quic:server: platform_rx: count=3 syscalls=2 blocked_syscalls=1 total_errors=0 dropped_errors=0 0.865471852s s2n_quic:server: version_information: server_versions=[1] client_versions=[1463896404] chosen_version=None 0.865525252s s2n_quic:server: endpoint_datagram_dropped: len=1207 reason=UnsupportedVersion 0.865619469s s2n_quic:server: version_information: server_versions=[1] client_versions=[1463896404] chosen_version=None 0.865662810s s2n_quic:server: endpoint_datagram_dropped: len=1207 reason=UnsupportedVersion 0.865705580s s2n_quic:server: version_information: server_versions=[1] client_versions=[1463896404] chosen_version=None 0.865723484s s2n_quic:server: endpoint_datagram_dropped: len=1207 reason=UnsupportedVersion 0.865768448s s2n_quic:server: endpoint_packet_sent: packet_header=VersionNegotiation 0.865782113s s2n_quic:server: endpoint_datagram_sent: len=15 gso_offset=0 0.865795218s s2n_quic:server: endpoint_packet_sent: packet_header=VersionNegotiation 0.865804325s s2n_quic:server: endpoint_datagram_sent: len=15 gso_offset=0 0.865815927s s2n_quic:server: endpoint_packet_sent: packet_header=VersionNegotiation 0.865825915s s2n_quic:server: endpoint_datagram_sent: len=15 gso_offset=0 0.865845683s s2n_quic:server: platform_event_loop_sleep: timeout=None processing_duration=627µs 1.051403526s s2n_quic:server: platform_event_loop_wakeup: timeout_expired=true rx_ready=false tx_ready=false application_wakeup=false 1.051496220s s2n_quic:server: platform_tx: count=3 syscalls=1 blocked_syscalls=0 total_errors=0 dropped_errors=0 1.051556112s s2n_quic:server: platform_event_loop_sleep: timeout=None processing_duration=142µs 1.064206656s s2n_quic:server: platform_event_loop_wakeup: timeout_expired=false rx_ready=true tx_ready=false application_wakeup=false 1.064264294s s2n_quic:server: platform_rx: count=1 syscalls=2 blocked_syscalls=1 total_errors=0 dropped_errors=0 1.064321722s s2n_quic:server: version_information: server_versions=[1] client_versions=[] chosen_version=Some(1) 1.064775532s s2n_quic:server:conn: key_update: key_type=Initial cipher_suite=TLS_AES_128_GCM_SHA256 id=0 1.064859629s s2n_quic:server:conn: connection_started: path=Path { local_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, local_cid: 0xe04e0e20d1f60684a36ee83b80e69478, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:54587, remote_cid: 0x, id: 0, is_active: true } id=0 1.064892731s s2n_quic:server:conn: mtu_updated: path_id=0 mtu=1232 cause=NewPath search_complete=false id=0 1.064935591s s2n_quic:server:conn: datagram_received: len=1280 id=0 1.065016653s 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: 0xe04e0e20d1f60684a36ee83b80e69478, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:54587, remote_cid: 0x, id: 0, is_active: true } frame=Padding id=0 1.065058591s 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: 0xe04e0e20d1f60684a36ee83b80e69478, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:54587, remote_cid: 0x, id: 0, is_active: true } frame=Crypto { offset: 0, len: 266 } id=0 1.065110028s s2n_quic:server:conn: tls_client_hello: payload=[[3, 3, 203, 182, 168, 126, 134, 219, 236, 95, 197, 215, 129, 66, 88, 194, 51, 213, 203, 207, 254, 81, 103, 7, 125, 204, 245, 176, 74, 233, 236, 84, 97, 23, 0, 0, 6, 19, 1, 19, 2, 19, 3, 1, 0, 0, 215, 0, 0, 0, 12, 0, 10, 0, 0, 7, 115, 101, 114, 118, 101, 114, 54, 0, 11, 0, 2, 1, 0, 255, 1, 0, 1, 0, 0, 23, 0, 0, 0, 18, 0, 0, 0, 57, 0, 57, 68, 119, 4, 204, 32, 145, 123, 5, 4, 128, 8, 0, 0, 6, 4, 128, 8, 0, 0, 7, 4, 128, 8, 0, 0, 4, 4, 128, 12, 0, 0, 8, 2, 64, 100, 9, 2, 64, 100, 1, 4, 128, 0, 117, 48, 3, 2, 69, 172, 11, 1, 26, 14, 1, 4, 15, 0, 0, 5, 0, 5, 1, 0, 0, 0, 0, 0, 10, 0, 10, 0, 8, 0, 29, 0, 23, 0, 24, 0, 25, 0, 13, 0, 26, 0, 24, 8, 4, 4, 3, 8, 7, 8, 5, 8, 6, 4, 1, 5, 1, 6, 1, 5, 3, 6, 3, 2, 1, 2, 3, 0, 16, 0, 13, 0, 11, 10, 104, 113, 45, 105, 110, 116, 101, 114, 111, 112, 0, 43, 0, 3, 2, 3, 4, 0, 51, 0, 38, 0, 36, 0, 29, 0, 32, 21, 89, 85, 23, 33, 85, 160, 170, 51, 220, 62, 198, 102, 10, 146, 197, 149, 190, 206, 96, 168, 200, 154, 85, 182, 237, 49, 30, 88, 201, 17, 40]] id=0 1.065427362s s2n_quic:server:conn: key_update: key_type=Handshake cipher_suite=TLS_AES_128_GCM_SHA256 id=0 1.065630662s s2n_quic:server:conn: application_protocol_information: chosen_application_protocol=[104, 113, 45, 105, 110, 116, 101, 114, 111, 112] id=0 1.065665207s s2n_quic:server:conn: server_name_information: chosen_server_name="server6" id=0 1.065682900s s2n_quic:server:conn: key_exchange_group: chosen_group_name="x25519" contains_kem=false id=0 1.065730048s s2n_quic:server:conn: transport_parameters_received: transport_parameters=TransportParameters { original_destination_connection_id: None, initial_source_connection_id: Some(0x), 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: 26ms, max_udp_payload_size: 1452, active_connection_id_limit: 4, initial_max_stream_data_bidi_local: 524288, initial_max_stream_data_bidi_remote: 524288, initial_max_stream_data_uni: 524288, initial_max_streams_bidi: 100, initial_max_streams_uni: 100, max_datagram_frame_size: 0, dc_supported_versions: [] } id=0 1.065803476s s2n_quic:server:conn: key_update: key_type=OneRtt { generation: 0 } cipher_suite=TLS_AES_128_GCM_SHA256 id=0 1.066030571s s2n_quic:server:conn: frame_sent: packet_header=Initial { number: 0, version: 1 } path_id=0 frame=Ack { ecn_counts: None, largest_acknowledged: 0, ack_range_count: 1 } id=0 1.066065366s s2n_quic:server:conn: ack_range_sent: packet_header=Initial { number: 0, version: 1 } path_id=0 ack_range=0..=0 id=0 1.066093920s 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.066139786s s2n_quic:server:conn: packet_sent: packet_header=Initial { number: 0, version: 1 } packet_len=143 id=0 1.066174791s s2n_quic:server:conn: frame_sent: packet_header=Handshake { number: 0, version: 1 } path_id=0 frame=Crypto { offset: 0, len: 691 } id=0 1.066199257s s2n_quic:server:conn: frame_sent: packet_header=Handshake { number: 0, version: 1 } path_id=0 frame=Padding id=0 1.066224534s s2n_quic:server:conn: packet_sent: packet_header=Handshake { number: 0, version: 1 } packet_len=1089 id=0 1.066267996s s2n_quic:server:conn: datagram_sent: len=1232 gso_offset=0 id=0 1.066326876s s2n_quic:server:conn: recovery_metrics: path=Path { local_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, local_cid: 0xe04e0e20d1f60684a36ee83b80e69478, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:54587, remote_cid: 0x, id: 0, is_active: true } min_rtt=333ms smoothed_rtt=333ms latest_rtt=333ms rtt_variance=166.5ms max_ack_delay=26ms pto_count=0 congestion_window=12320 bytes_in_flight=1232 congestion_limited=false id=0 1.066402918s s2n_quic:server: platform_event_loop_sleep: timeout=Some(998.546ms) processing_duration=2.201ms 1.100039537s s2n_quic:server: platform_event_loop_wakeup: timeout_expired=false rx_ready=true tx_ready=false application_wakeup=false 1.100125758s s2n_quic:server: platform_tx: count=1 syscalls=1 blocked_syscalls=0 total_errors=0 dropped_errors=0 1.100144824s s2n_quic:server: platform_rx: count=2 syscalls=2 blocked_syscalls=1 total_errors=0 dropped_errors=0 1.100198204s s2n_quic:server: version_information: server_versions=[1] client_versions=[] chosen_version=Some(1) 1.100257104s s2n_quic:server:conn: datagram_received: len=1280 id=0 1.100322927s s2n_quic:server:conn: packet_received: packet_header=Initial { number: 1, version: 1 } id=0 1.100359145s 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: 0xe04e0e20d1f60684a36ee83b80e69478, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:54587, remote_cid: 0x, 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 1.100409419s 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: 0xe04e0e20d1f60684a36ee83b80e69478, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:54587, remote_cid: 0x, id: 0, is_active: true } ack_range=0..=0 id=0 1.100479651s s2n_quic:server:conn: pacing_rate_updated: path_id=0 bytes_per_second=986947 burst_size=12320 pacing_gain=2.77 id=0 1.100527961s s2n_quic:server:conn: delivery_rate_sampled: path_id=0 rate_sample=RateSample { interval: 34.232ms, delivered_bytes: 143, lost_bytes: 0, ecn_ce_count: 0, is_app_limited: true, prior_delivered_bytes: 0, bytes_in_flight: 143, prior_lost_bytes: 0, prior_ecn_ce_count: 0, delivery_rate_bytes_per_second: 4177 } id=0 1.100611007s s2n_quic:server:conn: recovery_metrics: path=Path { local_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, local_cid: 0xe04e0e20d1f60684a36ee83b80e69478, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:54587, remote_cid: 0x, id: 0, is_active: true } min_rtt=34.232ms smoothed_rtt=34.232ms latest_rtt=34.232ms rtt_variance=17.116ms max_ack_delay=26ms pto_count=0 congestion_window=12463 bytes_in_flight=1089 congestion_limited=false id=0 1.100678082s 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: 0xe04e0e20d1f60684a36ee83b80e69478, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:54587, remote_cid: 0x, id: 0, is_active: true } frame=Padding id=0 1.100760507s s2n_quic:server:conn: packet_received: packet_header=Handshake { number: 0, version: 1 } id=0 1.100799169s s2n_quic:server:conn: frame_received: packet_header=Handshake { number: 0, version: 1 } path=Path { local_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, local_cid: 0xe04e0e20d1f60684a36ee83b80e69478, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:54587, remote_cid: 0x, id: 0, is_active: true } frame=Crypto { offset: 0, len: 36 } id=0 1.100870212s s2n_quic:server:conn: key_space_discarded: space=Initial id=0 1.100889749s s2n_quic:server:conn: recovery_metrics: path=Path { local_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, local_cid: 0xe04e0e20d1f60684a36ee83b80e69478, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:54587, remote_cid: 0x, id: 0, is_active: true } min_rtt=34.232ms smoothed_rtt=34.232ms latest_rtt=34.232ms rtt_variance=17.116ms max_ack_delay=26ms pto_count=0 congestion_window=12463 bytes_in_flight=1089 congestion_limited=false id=0 1.101040180s s2n_quic:server:conn: handshake_status_updated: status=Complete id=0 1.101067491s s2n_quic:server:conn: handshake_status_updated: status=Confirmed id=0 1.101095874s s2n_quic:server:conn: tls_exporter_ready: session=TlsSession { .. } id=0 1.101142992s s2n_quic:server:conn: key_space_discarded: space=Handshake id=0 1.101161768s s2n_quic:server:conn: recovery_metrics: path=Path { local_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, local_cid: 0xe04e0e20d1f60684a36ee83b80e69478, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:54587, remote_cid: 0x, id: 0, is_active: true } min_rtt=34.232ms smoothed_rtt=34.232ms latest_rtt=34.232ms rtt_variance=17.116ms max_ack_delay=26ms pto_count=0 congestion_window=12463 bytes_in_flight=1089 congestion_limited=false id=0 1.101297211s s2n_quic:server:conn: datagram_received: len=51 id=0 1.101426203s s2n_quic:server:conn: frame_sent: packet_header=OneRtt { number: 0 } path_id=0 frame=Ping id=0 1.101461669s s2n_quic:server:conn: frame_sent: packet_header=OneRtt { number: 0 } path_id=0 frame=Padding id=0 1.101501554s s2n_quic:server:conn: packet_sent: packet_header=OneRtt { number: 0 } packet_len=1452 id=0 1.101518315s s2n_quic:server:conn: datagram_sent: len=1452 gso_offset=0 id=0 1.101567277s s2n_quic:server:conn: frame_sent: packet_header=OneRtt { number: 1 } path_id=0 frame=HandshakeDone id=0 1.101599467s 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.101614635s 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.101627379s 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.101674628s s2n_quic:server:conn: packet_sent: packet_header=OneRtt { number: 1 } packet_len=127 id=0 1.101691720s s2n_quic:server:conn: datagram_sent: len=127 gso_offset=0 id=0 1.101724251s s2n_quic:server:conn: recovery_metrics: path=Path { local_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, local_cid: 0xe04e0e20d1f60684a36ee83b80e69478, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:54587, remote_cid: 0x, id: 0, is_active: true } min_rtt=34.232ms smoothed_rtt=34.232ms latest_rtt=34.232ms rtt_variance=17.116ms max_ack_delay=26ms pto_count=0 congestion_window=12463 bytes_in_flight=1579 congestion_limited=false id=0 1.101788040s s2n_quic:server: platform_event_loop_sleep: timeout=Some(128.301ms) processing_duration=1.762ms Accepted a QUIC connection from [fd00:cafe:cafe::100]:54587 on [fd00:cafe:cafe:100::100]:443 1.101995208s s2n_quic:server: platform_event_loop_wakeup: timeout_expired=false rx_ready=true tx_ready=false application_wakeup=false 1.102032748s s2n_quic:server: platform_tx: count=2 syscalls=1 blocked_syscalls=0 total_errors=0 dropped_errors=0 1.102045973s s2n_quic:server: platform_rx: count=1 syscalls=2 blocked_syscalls=1 total_errors=0 dropped_errors=0 1.102078183s s2n_quic:server:conn: datagram_received: len=74 id=0 1.102110754s s2n_quic:server:conn: packet_received: packet_header=OneRtt { number: 0 } id=0 1.102135611s s2n_quic:server:conn: frame_received: packet_header=OneRtt { number: 0 } path=Path { local_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, local_cid: 0xe04e0e20d1f60684a36ee83b80e69478, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:54587, remote_cid: 0x, id: 0, is_active: true } frame=Stream { id: 0, offset: 0, len: 17, is_fin: true } id=0 1.102197497s s2n_quic:server:conn: frame_received: packet_header=OneRtt { number: 0 } path=Path { local_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, local_cid: 0xe04e0e20d1f60684a36ee83b80e69478, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:54587, remote_cid: 0x, id: 0, is_active: true } frame=Stream { id: 4, offset: 0, len: 17, is_fin: true } id=0 1.102257459s s2n_quic:server:conn: rx_stream_progress: bytes=34 id=0 1.102313083s s2n_quic:server: platform_event_loop_sleep: timeout=Some(24.754ms) processing_duration=321µs 1.103091600s s2n_quic_qns::server::h09: 4096 bytes ready to send on Stream(4) 1.103164777s s2n_quic_qns::server::h09: 4096 bytes sent on Stream(4) 1.103241872s s2n_quic:server: platform_event_loop_wakeup: timeout_expired=false rx_ready=false tx_ready=false application_wakeup=true 1.103312664s s2n_quic:server:conn: frame_sent: packet_header=OneRtt { number: 2 } path_id=0 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 1.103333994s s2n_quic:server:conn: ack_range_sent: packet_header=OneRtt { number: 2 } path_id=0 ack_range=0..=0 id=0 1.103347910s s2n_quic:server:conn: frame_sent: packet_header=OneRtt { number: 2 } path_id=0 frame=HandshakeDone id=0 1.103384348s s2n_quic:server:conn: frame_sent: packet_header=OneRtt { number: 2 } path_id=0 frame=Stream { id: 4, offset: 0, len: 1202, is_fin: false } id=0 1.103428501s s2n_quic:server:conn: packet_sent: packet_header=OneRtt { number: 2 } packet_len=1232 id=0 1.103445683s s2n_quic:server:conn: datagram_sent: len=1232 gso_offset=0 id=0 1.103476361s s2n_quic:server:conn: frame_sent: packet_header=OneRtt { number: 3 } path_id=0 frame=HandshakeDone id=0 1.103507900s s2n_quic:server:conn: frame_sent: packet_header=OneRtt { number: 3 } path_id=0 frame=Stream { id: 4, offset: 1202, len: 1209, is_fin: false } id=0 1.103572220s s2n_quic:server:conn: packet_sent: packet_header=OneRtt { number: 3 } packet_len=1232 id=0 1.103603769s s2n_quic:server:conn: datagram_sent: len=1232 gso_offset=0 id=0 1.103636651s s2n_quic:server:conn: frame_sent: packet_header=OneRtt { number: 4 } path_id=0 frame=HandshakeDone id=0 1.103669533s s2n_quic:server:conn: frame_sent: packet_header=OneRtt { number: 4 } path_id=0 frame=Stream { id: 4, offset: 2411, len: 1209, is_fin: false } id=0 1.103706091s s2n_quic:server:conn: packet_sent: packet_header=OneRtt { number: 4 } packet_len=1232 id=0 1.103722652s s2n_quic:server:conn: datagram_sent: len=1232 gso_offset=0 id=0 1.103751696s s2n_quic:server:conn: frame_sent: packet_header=OneRtt { number: 5 } path_id=0 frame=HandshakeDone id=0 1.103783035s s2n_quic:server:conn: frame_sent: packet_header=OneRtt { number: 5 } path_id=0 frame=Stream { id: 4, offset: 3620, len: 476, is_fin: false } id=0 1.103818822s s2n_quic:server:conn: packet_sent: packet_header=OneRtt { number: 5 } packet_len=501 id=0 1.103835994s s2n_quic:server:conn: datagram_sent: len=501 gso_offset=0 id=0 1.103869226s s2n_quic:server:conn: recovery_metrics: path=Path { local_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, local_cid: 0xe04e0e20d1f60684a36ee83b80e69478, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:54587, remote_cid: 0x, id: 0, is_active: true } min_rtt=34.232ms smoothed_rtt=34.232ms latest_rtt=34.232ms rtt_variance=17.116ms max_ack_delay=26ms pto_count=0 congestion_window=12463 bytes_in_flight=5776 congestion_limited=false id=0 1.103902579s s2n_quic:server:conn: tx_stream_progress: bytes=4096 id=0 1.103939307s s2n_quic:server: platform_event_loop_sleep: timeout=Some(128.041ms) processing_duration=698µs 1.104051417s s2n_quic_qns::server::h09: 4096 bytes ready to send on Stream(0) 1.104097123s s2n_quic_qns::server::h09: 4096 bytes sent on Stream(0) 1.104171703s s2n_quic:server: platform_event_loop_wakeup: timeout_expired=false rx_ready=false tx_ready=false application_wakeup=true 1.104200216s s2n_quic:server: platform_tx: count=4 syscalls=1 blocked_syscalls=0 total_errors=0 dropped_errors=0 1.104238548s s2n_quic:server:conn: frame_sent: packet_header=OneRtt { number: 6 } path_id=0 frame=HandshakeDone id=0 1.104272812s s2n_quic:server:conn: frame_sent: packet_header=OneRtt { number: 6 } path_id=0 frame=Stream { id: 0, offset: 0, len: 1211, is_fin: false } id=0 1.104312857s s2n_quic:server:conn: packet_sent: packet_header=OneRtt { number: 6 } packet_len=1232 id=0 1.104330069s s2n_quic:server:conn: datagram_sent: len=1232 gso_offset=0 id=0 1.104360807s s2n_quic:server:conn: frame_sent: packet_header=OneRtt { number: 7 } path_id=0 frame=HandshakeDone id=0 1.104391865s s2n_quic:server:conn: frame_sent: packet_header=OneRtt { number: 7 } path_id=0 frame=Stream { id: 0, offset: 1211, len: 1209, is_fin: false } id=0 1.104431379s s2n_quic:server:conn: ecn_state_changed: path=Path { local_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, local_cid: 0xe04e0e20d1f60684a36ee83b80e69478, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:54587, remote_cid: 0x, id: 0, is_active: true } state=Unknown id=0 1.104453681s s2n_quic:server:conn: packet_sent: packet_header=OneRtt { number: 7 } packet_len=1232 id=0 1.104466194s s2n_quic:server:conn: datagram_sent: len=1232 gso_offset=0 id=0 1.104496040s s2n_quic:server:conn: frame_sent: packet_header=OneRtt { number: 8 } path_id=0 frame=HandshakeDone id=0 1.104527719s s2n_quic:server:conn: frame_sent: packet_header=OneRtt { number: 8 } path_id=0 frame=Stream { id: 0, offset: 2420, len: 1209, is_fin: false } id=0 1.104605765s s2n_quic:server:conn: packet_sent: packet_header=OneRtt { number: 8 } packet_len=1232 id=0 1.104624701s s2n_quic:server:conn: datagram_sent: len=1232 gso_offset=0 id=0 1.104655308s s2n_quic:server:conn: frame_sent: packet_header=OneRtt { number: 9 } path_id=0 frame=HandshakeDone id=0 1.104688410s s2n_quic:server:conn: frame_sent: packet_header=OneRtt { number: 9 } path_id=0 frame=Stream { id: 0, offset: 3629, len: 467, is_fin: false } id=0 1.104725149s s2n_quic:server:conn: packet_sent: packet_header=OneRtt { number: 9 } packet_len=492 id=0 1.104741870s s2n_quic:server:conn: datagram_sent: len=492 gso_offset=0 id=0 1.104773850s s2n_quic:server:conn: recovery_metrics: path=Path { local_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, local_cid: 0xe04e0e20d1f60684a36ee83b80e69478, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:54587, remote_cid: 0x, id: 0, is_active: true } min_rtt=34.232ms smoothed_rtt=34.232ms latest_rtt=34.232ms rtt_variance=17.116ms max_ack_delay=26ms pto_count=0 congestion_window=12463 bytes_in_flight=9964 congestion_limited=false id=0 1.104802894s s2n_quic:server:conn: tx_stream_progress: bytes=4096 id=0 1.104838321s s2n_quic:server: platform_event_loop_sleep: timeout=Some(128.076ms) processing_duration=667µs 1.104935202s s2n_quic_qns::server::h09: 1024 bytes ready to send on Stream(4) 1.104974085s s2n_quic_qns::server::h09: 1024 bytes sent on Stream(4) 1.105050738s s2n_quic:server: platform_event_loop_wakeup: timeout_expired=false rx_ready=false tx_ready=false application_wakeup=true 1.105079763s s2n_quic:server: platform_tx: count=4 syscalls=1 blocked_syscalls=0 total_errors=0 dropped_errors=0 1.105117613s s2n_quic:server:conn: frame_sent: packet_header=OneRtt { number: 10 } path_id=0 frame=HandshakeDone id=0 1.105153701s s2n_quic:server:conn: frame_sent: packet_header=OneRtt { number: 10 } path_id=0 frame=Stream { id: 4, offset: 4096, len: 1024, is_fin: false } id=0 1.105220556s s2n_quic:server:conn: packet_sent: packet_header=OneRtt { number: 10 } packet_len=1049 id=0 1.105244932s s2n_quic:server:conn: datagram_sent: len=1049 gso_offset=0 id=0 1.105283234s s2n_quic:server:conn: recovery_metrics: path=Path { local_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, local_cid: 0xe04e0e20d1f60684a36ee83b80e69478, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:54587, remote_cid: 0x, id: 0, is_active: true } min_rtt=34.232ms smoothed_rtt=34.232ms latest_rtt=34.232ms rtt_variance=17.116ms max_ack_delay=26ms pto_count=0 congestion_window=12463 bytes_in_flight=11013 congestion_limited=false id=0 1.105324300s s2n_quic:server:conn: tx_stream_progress: bytes=1024 id=0 1.105360107s s2n_quic:server: platform_event_loop_sleep: timeout=Some(128.434ms) processing_duration=311µs 1.105451699s s2n_quic_qns::server::h09: 4096 bytes ready to send on Stream(0) 1.105503636s s2n_quic_qns::server::h09: 4096 bytes sent on Stream(0) 1.105598774s s2n_quic:server: platform_event_loop_wakeup: timeout_expired=false rx_ready=false tx_ready=false application_wakeup=true 1.105637727s s2n_quic:server: platform_tx: count=1 syscalls=1 blocked_syscalls=0 total_errors=0 dropped_errors=0 1.105690185s s2n_quic:server:conn: frame_sent: packet_header=OneRtt { number: 11 } path_id=0 frame=HandshakeDone id=0 1.105744006s s2n_quic:server:conn: frame_sent: packet_header=OneRtt { number: 11 } path_id=0 frame=Stream { id: 0, offset: 4096, len: 1209, is_fin: false } id=0 1.105805461s s2n_quic:server:conn: packet_sent: packet_header=OneRtt { number: 11 } packet_len=1232 id=0 1.105839164s s2n_quic:server:conn: datagram_sent: len=1232 gso_offset=0 id=0 1.105889509s s2n_quic:server:conn: recovery_metrics: path=Path { local_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, local_cid: 0xe04e0e20d1f60684a36ee83b80e69478, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:54587, remote_cid: 0x, id: 0, is_active: true } min_rtt=34.232ms smoothed_rtt=34.232ms latest_rtt=34.232ms rtt_variance=17.116ms max_ack_delay=26ms pto_count=0 congestion_window=12463 bytes_in_flight=12245 congestion_limited=true id=0 1.105940855s s2n_quic:server:conn: tx_stream_progress: bytes=1213 id=0 1.105995156s s2n_quic:server: platform_event_loop_sleep: timeout=Some(128.37ms) processing_duration=397µs 1.106139667s s2n_quic_qns::server::h09: 2048 bytes ready to send on Stream(0) 1.106171967s s2n_quic_qns::server::h09: 2048 bytes sent on Stream(0) 1.133856234s s2n_quic:server: platform_event_loop_wakeup: timeout_expired=false rx_ready=true tx_ready=false application_wakeup=false 1.133914734s s2n_quic:server: platform_tx: count=1 syscalls=1 blocked_syscalls=0 total_errors=0 dropped_errors=0 1.133930423s s2n_quic:server: platform_rx: count=2 syscalls=2 blocked_syscalls=1 total_errors=0 dropped_errors=0 1.133989804s s2n_quic:server:conn: datagram_received: len=43 id=0 1.134038646s s2n_quic:server:conn: packet_received: packet_header=OneRtt { number: 1 } id=0 1.134069874s s2n_quic:server:conn: frame_received: packet_header=OneRtt { number: 1 } path=Path { local_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, local_cid: 0xe04e0e20d1f60684a36ee83b80e69478, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:54587, remote_cid: 0x, 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 1.134106002s s2n_quic:server:conn: ack_range_received: packet_header=OneRtt { number: 1 } path=Path { local_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, local_cid: 0xe04e0e20d1f60684a36ee83b80e69478, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:54587, remote_cid: 0x, id: 0, is_active: true } ack_range=0..=0 id=0 1.134143732s s2n_quic:server:conn: mtu_updated: path_id=0 mtu=1452 cause=ProbeAcknowledged search_complete=false id=0 1.134214795s s2n_quic:server:conn: ecn_state_changed: path=Path { local_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, local_cid: 0xe04e0e20d1f60684a36ee83b80e69478, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:54587, remote_cid: 0x, id: 0, is_active: true } state=Capable id=0 1.134242277s s2n_quic:server:conn: delivery_rate_sampled: path_id=0 rate_sample=RateSample { interval: 32.567ms, delivered_bytes: 1452, lost_bytes: 0, ecn_ce_count: 0, is_app_limited: true, prior_delivered_bytes: 143, bytes_in_flight: 1452, prior_lost_bytes: 0, prior_ecn_ce_count: 0, delivery_rate_bytes_per_second: 44585 } id=0 1.134273666s s2n_quic:server:conn: recovery_metrics: path=Path { local_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, local_cid: 0xe04e0e20d1f60684a36ee83b80e69478, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:54587, remote_cid: 0x, id: 0, is_active: true } min_rtt=32.567ms smoothed_rtt=34.023875ms latest_rtt=32.567ms rtt_variance=13.25325ms max_ack_delay=26ms pto_count=0 congestion_window=16140 bytes_in_flight=10793 congestion_limited=false id=0 1.134358264s s2n_quic:server:conn: datagram_received: len=45 id=0 1.134395273s s2n_quic:server:conn: packet_received: packet_header=OneRtt { number: 2 } id=0 1.134418417s s2n_quic:server:conn: connection_id_updated: path_id=0 cid_consumer=Remote previous=0xe04e0e20d1f60684a36ee83b80e69478 current=0x987083083db07aac859efadcdc1da37a id=0 1.134441961s s2n_quic:server:conn: frame_received: packet_header=OneRtt { number: 2 } path=Path { local_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, local_cid: 0x987083083db07aac859efadcdc1da37a, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:54587, remote_cid: 0x, 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 1.134463040s s2n_quic:server:conn: ack_range_received: packet_header=OneRtt { number: 2 } path=Path { local_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, local_cid: 0x987083083db07aac859efadcdc1da37a, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:54587, remote_cid: 0x, id: 0, is_active: true } ack_range=0..=1 id=0 1.134484701s s2n_quic:server:conn: handshake_status_updated: status=HandshakeDoneAcked id=0 1.134521971s s2n_quic:server:conn: delivery_rate_sampled: path_id=0 rate_sample=RateSample { interval: 32.567ms, delivered_bytes: 1579, lost_bytes: 0, ecn_ce_count: 0, is_app_limited: true, prior_delivered_bytes: 143, bytes_in_flight: 1452, prior_lost_bytes: 0, prior_ecn_ce_count: 0, delivery_rate_bytes_per_second: 48484 } id=0 1.134577705s s2n_quic:server:conn: recovery_metrics: path=Path { local_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, local_cid: 0x987083083db07aac859efadcdc1da37a, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:54587, remote_cid: 0x, id: 0, is_active: true } min_rtt=32.567ms smoothed_rtt=33.841763ms latest_rtt=32.567ms rtt_variance=10.304154ms max_ack_delay=26ms pto_count=0 congestion_window=16267 bytes_in_flight=10666 congestion_limited=false id=0 1.134609274s s2n_quic:server:conn: frame_received: packet_header=OneRtt { number: 2 } path=Path { local_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, local_cid: 0x987083083db07aac859efadcdc1da37a, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:54587, remote_cid: 0x, id: 0, is_active: true } frame=RetireConnectionId id=0 1.134699413s s2n_quic:server:conn: frame_sent: packet_header=OneRtt { number: 12 } path_id=0 frame=Ping id=0 1.134754997s s2n_quic:server:conn: frame_sent: packet_header=OneRtt { number: 12 } path_id=0 frame=Padding id=0 1.134794992s s2n_quic:server:conn: packet_sent: packet_header=OneRtt { number: 12 } packet_len=5202 id=0 1.134810611s s2n_quic:server:conn: datagram_sent: len=5202 gso_offset=0 id=0 1.134850435s s2n_quic:server:conn: recovery_metrics: path=Path { local_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, local_cid: 0x987083083db07aac859efadcdc1da37a, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:54587, remote_cid: 0x, id: 0, is_active: true } min_rtt=32.567ms smoothed_rtt=33.841763ms latest_rtt=32.567ms rtt_variance=10.304154ms max_ack_delay=26ms pto_count=0 congestion_window=16267 bytes_in_flight=15868 congestion_limited=true id=0 1.134916369s s2n_quic:server: platform_event_loop_sleep: timeout=Some(24.043ms) processing_duration=1.07ms 1.136561509s s2n_quic:server: platform_event_loop_wakeup: timeout_expired=false rx_ready=true tx_ready=false application_wakeup=false 1.136595933s s2n_quic:server: platform_tx_error: errno=90 1.136608046s s2n_quic:server: platform_tx: count=0 syscalls=1 blocked_syscalls=0 total_errors=1 dropped_errors=0 1.136620219s s2n_quic:server: platform_rx: count=1 syscalls=2 blocked_syscalls=1 total_errors=0 dropped_errors=0 1.136651698s s2n_quic:server:conn: datagram_received: len=43 id=0 1.136682966s s2n_quic:server:conn: packet_received: packet_header=OneRtt { number: 3 } id=0 1.136705649s 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: 0x987083083db07aac859efadcdc1da37a, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:54587, remote_cid: 0x, 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 1.136727981s s2n_quic:server:conn: ack_range_received: packet_header=OneRtt { number: 3 } path=Path { local_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, local_cid: 0x987083083db07aac859efadcdc1da37a, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:54587, remote_cid: 0x, id: 0, is_active: true } ack_range=0..=3 id=0 1.136784086s s2n_quic:server:conn: delivery_rate_sampled: path_id=0 rate_sample=RateSample { interval: 32.567ms, delivered_bytes: 4043, lost_bytes: 0, ecn_ce_count: 0, is_app_limited: true, prior_delivered_bytes: 143, bytes_in_flight: 1452, prior_lost_bytes: 0, prior_ecn_ce_count: 0, delivery_rate_bytes_per_second: 124144 } id=0 1.136814392s s2n_quic:server:conn: recovery_metrics: path=Path { local_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, local_cid: 0x987083083db07aac859efadcdc1da37a, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:54587, remote_cid: 0x, id: 0, is_active: true } min_rtt=32.567ms smoothed_rtt=33.780165ms latest_rtt=33.355ms rtt_variance=7.851304ms max_ack_delay=26ms pto_count=0 congestion_window=18731 bytes_in_flight=13404 congestion_limited=false id=0 1.136895595s s2n_quic:server:conn: frame_sent: packet_header=OneRtt { number: 13 } path_id=0 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 1.136921353s s2n_quic:server:conn: ack_range_sent: packet_header=OneRtt { number: 13 } path_id=0 ack_range=1..=3 id=0 1.136954966s s2n_quic:server:conn: frame_sent: packet_header=OneRtt { number: 13 } path_id=0 frame=Stream { id: 4, offset: 5120, len: 0, is_fin: true } id=0 1.136983399s s2n_quic:server:conn: frame_sent: packet_header=OneRtt { number: 13 } path_id=0 frame=Stream { id: 0, offset: 5305, len: 1417, is_fin: false } id=0 1.137026049s s2n_quic:server:conn: packet_sent: packet_header=OneRtt { number: 13 } packet_len=1452 id=0 1.137044593s s2n_quic:server:conn: datagram_sent: len=1452 gso_offset=0 id=0 1.137089758s s2n_quic:server:conn: frame_sent: packet_header=OneRtt { number: 14 } path_id=0 frame=Stream { id: 0, offset: 6722, len: 1430, is_fin: false } id=0 1.137138399s s2n_quic:server:conn: packet_sent: packet_header=OneRtt { number: 14 } packet_len=1452 id=0 1.137156363s s2n_quic:server:conn: datagram_sent: len=1452 gso_offset=0 id=0 1.137208510s s2n_quic:server:conn: frame_sent: packet_header=OneRtt { number: 15 } path_id=0 frame=Stream { id: 0, offset: 8152, len: 1430, is_fin: false } id=0 1.137251972s s2n_quic:server:conn: packet_sent: packet_header=OneRtt { number: 15 } packet_len=1452 id=0 1.137269124s s2n_quic:server:conn: datagram_sent: len=1452 gso_offset=0 id=0 1.137288370s s2n_quic:server:conn: recovery_metrics: path=Path { local_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, local_cid: 0x987083083db07aac859efadcdc1da37a, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:54587, remote_cid: 0x, id: 0, is_active: true } min_rtt=32.567ms smoothed_rtt=33.780165ms latest_rtt=33.355ms rtt_variance=7.851304ms max_ack_delay=26ms pto_count=0 congestion_window=18731 bytes_in_flight=17760 congestion_limited=true id=0 1.137314719s s2n_quic:server:conn: tx_stream_progress: bytes=4277 id=0 1.137354093s s2n_quic:server: platform_event_loop_sleep: timeout=Some(3.203ms) processing_duration=794µs 1.137975707s s2n_quic:server: platform_event_loop_wakeup: timeout_expired=false rx_ready=true tx_ready=false application_wakeup=false 1.138008338s s2n_quic:server: platform_tx: count=3 syscalls=1 blocked_syscalls=0 total_errors=0 dropped_errors=0 1.138021482s s2n_quic:server: platform_rx: count=1 syscalls=2 blocked_syscalls=1 total_errors=0 dropped_errors=0 1.138053583s s2n_quic:server:conn: datagram_received: len=43 id=0 1.138084741s s2n_quic:server:conn: packet_received: packet_header=OneRtt { number: 4 } id=0 1.138106842s 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: 0x987083083db07aac859efadcdc1da37a, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:54587, remote_cid: 0x, id: 0, is_active: true } frame=Ack { ecn_counts: Some(EcnCounts { ect_0_count: 6, ect_1_count: 0, ce_count: 0 }), largest_acknowledged: 5, ack_range_count: 1 } id=0 1.138129114s 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: 0x987083083db07aac859efadcdc1da37a, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:54587, remote_cid: 0x, id: 0, is_active: true } ack_range=0..=5 id=0 1.138183295s s2n_quic:server:conn: delivery_rate_sampled: path_id=0 rate_sample=RateSample { interval: 32.567ms, delivered_bytes: 5776, lost_bytes: 0, ecn_ce_count: 0, is_app_limited: true, prior_delivered_bytes: 143, bytes_in_flight: 1452, prior_lost_bytes: 0, prior_ecn_ce_count: 0, delivery_rate_bytes_per_second: 177357 } id=0 1.138213271s s2n_quic:server:conn: recovery_metrics: path=Path { local_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, local_cid: 0x987083083db07aac859efadcdc1da37a, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:54587, remote_cid: 0x, id: 0, is_active: true } min_rtt=32.567ms smoothed_rtt=33.90164ms latest_rtt=34.757ms rtt_variance=6.131436ms max_ack_delay=26ms pto_count=0 congestion_window=20464 bytes_in_flight=16027 congestion_limited=false id=0 1.138269096s s2n_quic:server: platform_event_loop_sleep: timeout=Some(2.288ms) processing_duration=294µs 1.140057875s s2n_quic:server: platform_event_loop_wakeup: timeout_expired=false rx_ready=true tx_ready=false application_wakeup=false 1.140087641s s2n_quic:server: platform_rx: count=1 syscalls=2 blocked_syscalls=1 total_errors=0 dropped_errors=0 1.140120152s s2n_quic:server:conn: datagram_received: len=43 id=0 1.140151090s s2n_quic:server:conn: packet_received: packet_header=OneRtt { number: 5 } id=0 1.140177869s 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: 0x987083083db07aac859efadcdc1da37a, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:54587, remote_cid: 0x, id: 0, is_active: true } frame=Ack { ecn_counts: Some(EcnCounts { ect_0_count: 8, ect_1_count: 0, ce_count: 0 }), largest_acknowledged: 7, ack_range_count: 1 } id=0 1.140212033s 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: 0x987083083db07aac859efadcdc1da37a, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:54587, remote_cid: 0x, id: 0, is_active: true } ack_range=0..=7 id=0 1.140273428s s2n_quic:server:conn: delivery_rate_sampled: path_id=0 rate_sample=RateSample { interval: 32.567ms, delivered_bytes: 8240, lost_bytes: 0, ecn_ce_count: 0, is_app_limited: true, prior_delivered_bytes: 143, bytes_in_flight: 1452, prior_lost_bytes: 0, prior_ecn_ce_count: 0, delivery_rate_bytes_per_second: 253016 } id=0 1.140308995s s2n_quic:server:conn: recovery_metrics: path=Path { local_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, local_cid: 0x987083083db07aac859efadcdc1da37a, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:54587, remote_cid: 0x, id: 0, is_active: true } min_rtt=32.567ms smoothed_rtt=34.149435ms latest_rtt=35.89ms rtt_variance=5.094167ms max_ack_delay=26ms pto_count=0 congestion_window=22928 bytes_in_flight=13563 congestion_limited=false id=0 1.140389556s s2n_quic:server:conn: recovery_metrics: path=Path { local_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, local_cid: 0x987083083db07aac859efadcdc1da37a, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:54587, remote_cid: 0x, id: 0, is_active: true } min_rtt=32.567ms smoothed_rtt=34.149435ms latest_rtt=35.89ms rtt_variance=5.094167ms max_ack_delay=26ms pto_count=0 congestion_window=22928 bytes_in_flight=13563 congestion_limited=false id=0 1.140516634s s2n_quic:server:conn: frame_sent: packet_header=OneRtt { number: 16 } path_id=0 frame=Ack { ecn_counts: Some(EcnCounts { ect_0_count: 6, ect_1_count: 0, ce_count: 0 }), largest_acknowledged: 5, ack_range_count: 1 } id=0 1.140578500s s2n_quic:server:conn: ack_range_sent: packet_header=OneRtt { number: 16 } path_id=0 ack_range=1..=5 id=0 1.140620158s s2n_quic:server:conn: frame_sent: packet_header=OneRtt { number: 16 } path_id=0 frame=Stream { id: 0, offset: 9582, len: 658, is_fin: true } id=0 1.140672075s s2n_quic:server:conn: packet_sent: packet_header=OneRtt { number: 16 } packet_len=690 id=0 1.140693475s s2n_quic:server:conn: datagram_sent: len=690 gso_offset=0 id=0 1.140737527s s2n_quic:server:conn: recovery_metrics: path=Path { local_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, local_cid: 0x987083083db07aac859efadcdc1da37a, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:54587, remote_cid: 0x, id: 0, is_active: true } min_rtt=32.567ms smoothed_rtt=34.149435ms latest_rtt=35.89ms rtt_variance=5.094167ms max_ack_delay=26ms pto_count=0 congestion_window=22928 bytes_in_flight=14253 congestion_limited=false id=0 1.140774156s s2n_quic:server:conn: tx_stream_progress: bytes=654 id=0 1.140817547s s2n_quic:server: platform_event_loop_sleep: timeout=Some(80.196ms) processing_duration=760µs 1.141456083s s2n_quic:server: platform_event_loop_wakeup: timeout_expired=false rx_ready=true tx_ready=false application_wakeup=false 1.141485297s s2n_quic:server: platform_tx: count=1 syscalls=1 blocked_syscalls=0 total_errors=0 dropped_errors=0 1.141498172s s2n_quic:server: platform_rx: count=1 syscalls=2 blocked_syscalls=1 total_errors=0 dropped_errors=0 1.141529470s s2n_quic:server:conn: datagram_received: len=43 id=0 1.141587869s s2n_quic:server:conn: packet_received: packet_header=OneRtt { number: 6 } id=0 1.141611303s 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: 0x987083083db07aac859efadcdc1da37a, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:54587, remote_cid: 0x, id: 0, is_active: true } frame=Ack { ecn_counts: Some(EcnCounts { ect_0_count: 8, ect_1_count: 0, ce_count: 0 }), largest_acknowledged: 9, ack_range_count: 1 } id=0 1.141639496s 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: 0x987083083db07aac859efadcdc1da37a, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:54587, remote_cid: 0x, id: 0, is_active: true } ack_range=0..=9 id=0 1.141693487s s2n_quic:server:conn: delivery_rate_sampled: path_id=0 rate_sample=RateSample { interval: 32.567ms, delivered_bytes: 9964, lost_bytes: 0, ecn_ce_count: 0, is_app_limited: true, prior_delivered_bytes: 143, bytes_in_flight: 1452, prior_lost_bytes: 0, prior_ecn_ce_count: 0, delivery_rate_bytes_per_second: 305953 } id=0 1.141724415s s2n_quic:server:conn: recovery_metrics: path=Path { local_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, local_cid: 0x987083083db07aac859efadcdc1da37a, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:54587, remote_cid: 0x, id: 0, is_active: true } min_rtt=32.567ms smoothed_rtt=34.542378ms latest_rtt=37.299ms rtt_variance=4.606514ms max_ack_delay=26ms pto_count=0 congestion_window=24652 bytes_in_flight=12529 congestion_limited=false id=0 1.141792903s s2n_quic:server: platform_event_loop_sleep: timeout=Some(77.661ms) processing_duration=338µs 1.143373692s s2n_quic:server: platform_event_loop_wakeup: timeout_expired=false rx_ready=true tx_ready=false application_wakeup=false 1.143403248s s2n_quic:server: platform_rx: count=1 syscalls=2 blocked_syscalls=1 total_errors=0 dropped_errors=0 1.143435979s s2n_quic:server:conn: datagram_received: len=43 id=0 1.143467188s s2n_quic:server:conn: packet_received: packet_header=OneRtt { number: 7 } id=0 1.143489459s 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: 0x987083083db07aac859efadcdc1da37a, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:54587, remote_cid: 0x, id: 0, is_active: true } frame=Ack { ecn_counts: Some(EcnCounts { ect_0_count: 8, ect_1_count: 0, ce_count: 0 }), largest_acknowledged: 11, ack_range_count: 1 } id=0 1.143512192s 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: 0x987083083db07aac859efadcdc1da37a, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:54587, remote_cid: 0x, id: 0, is_active: true } ack_range=0..=11 id=0 1.143589657s s2n_quic:server:conn: delivery_rate_sampled: path_id=0 rate_sample=RateSample { interval: 32.567ms, delivered_bytes: 12245, lost_bytes: 0, ecn_ce_count: 0, is_app_limited: true, prior_delivered_bytes: 143, bytes_in_flight: 1452, prior_lost_bytes: 0, prior_ecn_ce_count: 0, delivery_rate_bytes_per_second: 375994 } id=0 1.143625764s s2n_quic:server:conn: pacing_rate_updated: path_id=0 bytes_per_second=1031089 burst_size=2904 pacing_gain=2.77 id=0 1.143645632s s2n_quic:server:conn: recovery_metrics: path=Path { local_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, local_cid: 0x987083083db07aac859efadcdc1da37a, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:54587, remote_cid: 0x, id: 0, is_active: true } min_rtt=32.567ms smoothed_rtt=34.943704ms latest_rtt=37.757ms rtt_variance=4.257539ms max_ack_delay=26ms pto_count=0 congestion_window=26933 bytes_in_flight=10248 congestion_limited=false id=0 1.143714781s s2n_quic:server: platform_event_loop_sleep: timeout=Some(74.745ms) processing_duration=341µs 1.169586832s s2n_quic:server: platform_event_loop_wakeup: timeout_expired=false rx_ready=true tx_ready=false application_wakeup=false 1.169676009s s2n_quic:server: platform_rx: count=1 syscalls=2 blocked_syscalls=1 total_errors=0 dropped_errors=0 1.169767530s s2n_quic:server:conn: datagram_received: len=45 id=0 1.169899373s s2n_quic:server:conn: packet_received: packet_header=OneRtt { number: 8 } id=0 1.169982088s 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: 0x987083083db07aac859efadcdc1da37a, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:54587, remote_cid: 0x, id: 0, is_active: true } frame=Ack { ecn_counts: Some(EcnCounts { ect_0_count: 9, ect_1_count: 0, ce_count: 0 }), largest_acknowledged: 13, ack_range_count: 2 } id=0 1.170044094s 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: 0x987083083db07aac859efadcdc1da37a, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:54587, remote_cid: 0x, id: 0, is_active: true } ack_range=13..=13 id=0 1.170167565s 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: 0x987083083db07aac859efadcdc1da37a, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:54587, remote_cid: 0x, id: 0, is_active: true } ack_range=2..=11 id=0 1.170228319s s2n_quic:server:conn: delivery_rate_sampled: path_id=0 rate_sample=RateSample { interval: 35.479ms, delivered_bytes: 9654, lost_bytes: 0, ecn_ce_count: 0, is_app_limited: true, prior_delivered_bytes: 4186, bytes_in_flight: 14856, prior_lost_bytes: 0, prior_ecn_ce_count: 0, delivery_rate_bytes_per_second: 272104 } id=0 1.170264787s s2n_quic:server:conn: recovery_metrics: path=Path { local_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, local_cid: 0x987083083db07aac859efadcdc1da37a, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:54587, remote_cid: 0x, id: 0, is_active: true } min_rtt=32.567ms smoothed_rtt=34.680741ms latest_rtt=32.852ms rtt_variance=3.719078ms max_ack_delay=26ms pto_count=0 congestion_window=28385 bytes_in_flight=8796 congestion_limited=false id=0 1.170416190s s2n_quic:server:conn: frame_sent: packet_header=OneRtt { number: 17 } path_id=0 frame=Ack { ecn_counts: Some(EcnCounts { ect_0_count: 9, ect_1_count: 0, ce_count: 0 }), largest_acknowledged: 8, ack_range_count: 1 } id=0 1.170447800s s2n_quic:server:conn: ack_range_sent: packet_header=OneRtt { number: 17 } path_id=0 ack_range=4..=8 id=0 1.170484318s s2n_quic:server:conn: frame_sent: packet_header=OneRtt { number: 17 } path_id=0 frame=Padding id=0 1.170558126s s2n_quic:server:conn: packet_sent: packet_header=OneRtt { number: 17 } packet_len=43 id=0 1.170594975s s2n_quic:server:conn: datagram_sent: len=43 gso_offset=0 id=0 1.170641182s s2n_quic:server:conn: recovery_metrics: path=Path { local_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, local_cid: 0x987083083db07aac859efadcdc1da37a, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:54587, remote_cid: 0x, id: 0, is_active: true } min_rtt=32.567ms smoothed_rtt=34.680741ms latest_rtt=32.852ms rtt_variance=3.719078ms max_ack_delay=26ms pto_count=0 congestion_window=28385 bytes_in_flight=8796 congestion_limited=false id=0 1.170722143s s2n_quic:server: platform_event_loop_sleep: timeout=Some(2.967ms) processing_duration=1.149ms 1.171581085s s2n_quic:server: platform_event_loop_wakeup: timeout_expired=false rx_ready=true tx_ready=false application_wakeup=false 1.171616021s s2n_quic:server: platform_tx: count=1 syscalls=1 blocked_syscalls=0 total_errors=0 dropped_errors=0 1.171638212s s2n_quic:server: platform_rx: count=1 syscalls=2 blocked_syscalls=1 total_errors=0 dropped_errors=0 1.171679159s s2n_quic:server:conn: datagram_received: len=45 id=0 1.171713974s s2n_quic:server:conn: packet_received: packet_header=OneRtt { number: 9 } id=0 1.171754550s 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: 0x987083083db07aac859efadcdc1da37a, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:54587, remote_cid: 0x, id: 0, is_active: true } frame=Ack { ecn_counts: Some(EcnCounts { ect_0_count: 11, ect_1_count: 0, ce_count: 0 }), largest_acknowledged: 15, ack_range_count: 2 } id=0 1.171788003s 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: 0x987083083db07aac859efadcdc1da37a, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:54587, remote_cid: 0x, id: 0, is_active: true } ack_range=13..=15 id=0 1.171845691s 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: 0x987083083db07aac859efadcdc1da37a, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:54587, remote_cid: 0x, id: 0, is_active: true } ack_range=2..=11 id=0 1.171904301s s2n_quic:server:conn: packet_lost: packet_header=OneRtt { number: 12 } path=Path { local_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, local_cid: 0x987083083db07aac859efadcdc1da37a, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:54587, remote_cid: 0x, id: 0, is_active: true } bytes_lost=5202 is_mtu_probe=true id=0 1.171947151s s2n_quic:server:conn: delivery_rate_sampled: path_id=0 rate_sample=RateSample { interval: 35.479ms, delivered_bytes: 12558, lost_bytes: 0, ecn_ce_count: 0, is_app_limited: true, prior_delivered_bytes: 4186, bytes_in_flight: 14856, prior_lost_bytes: 0, prior_ecn_ce_count: 0, delivery_rate_bytes_per_second: 353955 } id=0 1.171972308s s2n_quic:server:conn: recovery_metrics: path=Path { local_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, local_cid: 0x987083083db07aac859efadcdc1da37a, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:54587, remote_cid: 0x, id: 0, is_active: true } min_rtt=32.567ms smoothed_rtt=34.694519ms latest_rtt=34.796ms rtt_variance=2.816871ms max_ack_delay=26ms pto_count=0 congestion_window=31289 bytes_in_flight=690 congestion_limited=false id=0 1.172076783s s2n_quic:server:conn: frame_sent: packet_header=OneRtt { number: 18 } path_id=0 frame=Ping id=0 1.172163967s s2n_quic:server:conn: frame_sent: packet_header=OneRtt { number: 18 } path_id=0 frame=Padding id=0 1.172224830s s2n_quic:server:conn: packet_sent: packet_header=OneRtt { number: 18 } packet_len=5202 id=0 1.172256259s s2n_quic:server:conn: datagram_sent: len=5202 gso_offset=0 id=0 1.172325459s s2n_quic:server:conn: recovery_metrics: path=Path { local_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, local_cid: 0x987083083db07aac859efadcdc1da37a, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:54587, remote_cid: 0x, id: 0, is_active: true } min_rtt=32.567ms smoothed_rtt=34.694519ms latest_rtt=34.796ms rtt_variance=2.816871ms max_ack_delay=26ms pto_count=0 congestion_window=31289 bytes_in_flight=5892 congestion_limited=false id=0 1.172404086s s2n_quic:server: platform_event_loop_sleep: timeout=Some(30.527ms) processing_duration=823µs 1.172510195s s2n_quic:server: platform_event_loop_wakeup: timeout_expired=false rx_ready=true tx_ready=false application_wakeup=false 1.172595334s s2n_quic:server: platform_tx_error: errno=90 1.172612166s s2n_quic:server: platform_tx: count=0 syscalls=1 blocked_syscalls=0 total_errors=1 dropped_errors=0 1.172632364s s2n_quic:server: platform_rx: count=1 syscalls=2 blocked_syscalls=1 total_errors=0 dropped_errors=0 1.172673110s s2n_quic:server:conn: datagram_received: len=38 id=0 1.172712503s s2n_quic:server:conn: packet_received: packet_header=OneRtt { number: 10 } id=0 1.172739033s 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: 0x987083083db07aac859efadcdc1da37a, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:54587, remote_cid: 0x, id: 0, is_active: true } frame=ConnectionClose id=0 1.172775902s s2n_quic:server:conn: connection_close_frame_received: packet_header=OneRtt { number: 10 } path=Path { local_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, local_cid: 0x987083083db07aac859efadcdc1da37a, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:54587, remote_cid: 0x, id: 0, is_active: true } frame=ConnectionCloseFrame { error_code: 0, frame_type: None, reason: None } id=0 1.172825205s s2n_quic:server:conn: connection_closed: error=Application { error: application::Error(0), initiator: Remote, source: Location { file: "/app/quic/s2n-quic-transport/src/space/mod.rs", line: 966, col: 32 } } id=0 1.172913690s s2n_quic:server: platform_event_loop_sleep: timeout=None processing_duration=404µs 1.173013988s s2n_quic_qns::server::h09: Final stats: MyConnectionContext { packet_sent: 21, stream_requests: 2 } 1.204449611s s2n_quic:server: platform_event_loop_wakeup: timeout_expired=true rx_ready=false tx_ready=false application_wakeup=false 1.204566801s s2n_quic:server: platform_event_loop_sleep: timeout=None processing_duration=129µs