0.051381078s s2n_quic:server: platform_feature_configured: configuration=Gso { max_segments: 1 } 0.051536800s s2n_quic:server: platform_feature_configured: configuration=BaseMtu { mtu: 1228 } 0.051553551s s2n_quic:server: platform_feature_configured: configuration=InitialMtu { mtu: 1280 } 0.051563300s s2n_quic:server: platform_feature_configured: configuration=MaxMtu { mtu: 9000 } 0.051658137s s2n_quic:server: platform_feature_configured: configuration=Gro { enabled: true } 0.051680439s s2n_quic:server: platform_feature_configured: configuration=Ecn { enabled: true } 0.053280064s s2n_quic:server: platform_event_loop_started: local_address=[::]:443 0.053414727s s2n_quic:server: platform_event_loop_wakeup: timeout_expired=false rx_ready=false tx_ready=true application_wakeup=false 0.053453028s s2n_quic:server: platform_rx: count=0 syscalls=1 blocked_syscalls=1 total_errors=0 dropped_errors=0 0.053501799s s2n_quic:server: platform_event_loop_sleep: timeout=None processing_duration=90µs 0.869433230s s2n_quic:server: platform_event_loop_wakeup: timeout_expired=false rx_ready=true tx_ready=false application_wakeup=false 0.869548406s s2n_quic:server: platform_rx: count=3 syscalls=2 blocked_syscalls=1 total_errors=0 dropped_errors=0 0.869676316s s2n_quic:server: version_information: server_versions=[1] client_versions=[1463896404] chosen_version=None 0.869731869s s2n_quic:server: endpoint_datagram_dropped: len=1207 reason=UnsupportedVersion 0.869772345s s2n_quic:server: version_information: server_versions=[1] client_versions=[1463896404] chosen_version=None 0.869799667s s2n_quic:server: endpoint_datagram_dropped: len=1207 reason=UnsupportedVersion 0.869820926s s2n_quic:server: version_information: server_versions=[1] client_versions=[1463896404] chosen_version=None 0.869833319s s2n_quic:server: endpoint_datagram_dropped: len=1207 reason=UnsupportedVersion 0.869877593s s2n_quic:server: endpoint_packet_sent: packet_header=VersionNegotiation 0.869891288s s2n_quic:server: endpoint_datagram_sent: len=15 gso_offset=0 0.869904623s s2n_quic:server: endpoint_packet_sent: packet_header=VersionNegotiation 0.869913840s s2n_quic:server: endpoint_datagram_sent: len=15 gso_offset=0 0.869925482s s2n_quic:server: endpoint_packet_sent: packet_header=VersionNegotiation 0.869934339s s2n_quic:server: endpoint_datagram_sent: len=15 gso_offset=0 0.869952172s s2n_quic:server: platform_event_loop_sleep: timeout=None processing_duration=537µs 1.052513127s s2n_quic:server: platform_event_loop_wakeup: timeout_expired=true rx_ready=false tx_ready=false application_wakeup=false 1.052610198s s2n_quic:server: platform_tx: count=3 syscalls=1 blocked_syscalls=0 total_errors=0 dropped_errors=0 1.052649622s s2n_quic:server: platform_event_loop_sleep: timeout=None processing_duration=151µs 1.066674398s s2n_quic:server: platform_event_loop_wakeup: timeout_expired=false rx_ready=true tx_ready=false application_wakeup=false 1.066716286s s2n_quic:server: platform_rx: count=1 syscalls=2 blocked_syscalls=1 total_errors=0 dropped_errors=0 1.066766811s s2n_quic:server: version_information: server_versions=[1] client_versions=[] chosen_version=Some(1) 1.067171980s s2n_quic:server:conn: key_update: key_type=Initial cipher_suite=TLS_AES_128_GCM_SHA256 id=0 1.067242823s s2n_quic:server:conn: connection_started: path=Path { local_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, local_cid: 0x04c9370baa4a412eee392dd012fd760e, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:33213, remote_cid: 0xe5c0ce6224c30b55, id: 0, is_active: true } id=0 1.067271296s s2n_quic:server:conn: mtu_updated: path_id=0 mtu=1232 cause=NewPath search_complete=false id=0 1.067308646s s2n_quic:server:conn: datagram_received: len=1200 id=0 1.067352197s 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: 0x04c9370baa4a412eee392dd012fd760e, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:33213, remote_cid: 0xe5c0ce6224c30b55, id: 0, is_active: true } frame=Crypto { offset: 0, len: 305 } id=0 1.067416809s s2n_quic:server:conn: tls_client_hello: payload=[[3, 3, 3, 113, 131, 136, 99, 91, 56, 23, 135, 141, 149, 15, 197, 17, 92, 72, 35, 77, 72, 44, 236, 225, 21, 7, 76, 14, 155, 132, 134, 200, 21, 124, 0, 0, 8, 19, 1, 19, 2, 19, 3, 0, 255, 1, 0, 0, 252, 0, 0, 0, 12, 0, 10, 0, 0, 7, 115, 101, 114, 118, 101, 114, 54, 0, 11, 0, 4, 3, 0, 1, 2, 0, 10, 0, 10, 0, 8, 0, 23, 0, 29, 0, 24, 0, 25, 0, 35, 0, 0, 0, 16, 0, 13, 0, 11, 10, 104, 113, 45, 105, 110, 116, 101, 114, 111, 112, 0, 22, 0, 0, 0, 23, 0, 0, 0, 13, 0, 32, 0, 30, 4, 3, 5, 3, 6, 3, 7, 8, 8, 7, 8, 8, 8, 9, 8, 10, 8, 11, 8, 4, 8, 5, 8, 6, 4, 1, 5, 1, 6, 1, 0, 43, 0, 3, 2, 3, 4, 0, 45, 0, 2, 1, 1, 0, 51, 0, 71, 0, 69, 0, 23, 0, 65, 4, 245, 219, 190, 194, 84, 86, 74, 120, 78, 140, 150, 209, 166, 73, 247, 186, 177, 204, 73, 227, 39, 234, 120, 20, 129, 148, 59, 90, 25, 177, 191, 28, 120, 93, 87, 25, 99, 224, 30, 236, 56, 186, 46, 126, 144, 99, 196, 68, 115, 177, 185, 11, 158, 143, 88, 1, 254, 168, 89, 206, 56, 110, 38, 112, 0, 57, 0, 57, 1, 4, 128, 1, 212, 192, 3, 2, 68, 176, 4, 4, 128, 16, 0, 0, 5, 4, 129, 0, 0, 0, 6, 4, 129, 0, 0, 0, 7, 4, 129, 0, 0, 0, 8, 2, 64, 128, 9, 2, 64, 128, 12, 0, 14, 1, 8, 15, 8, 229, 192, 206, 98, 36, 195, 11, 85]] id=0 1.067478594s 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: 0x04c9370baa4a412eee392dd012fd760e, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:33213, remote_cid: 0xe5c0ce6224c30b55, id: 0, is_active: true } frame=Padding id=0 1.067825835s s2n_quic:server:conn: key_update: key_type=Handshake cipher_suite=TLS_AES_128_GCM_SHA256 id=0 1.067982918s s2n_quic:server:conn: application_protocol_information: chosen_application_protocol=[104, 113, 45, 105, 110, 116, 101, 114, 111, 112] id=0 1.068012474s s2n_quic:server:conn: server_name_information: chosen_server_name="server6" id=0 1.068027742s s2n_quic:server:conn: key_exchange_group: chosen_group_name="secp256r1" contains_kem=false id=0 1.068067647s s2n_quic:server:conn: transport_parameters_received: transport_parameters=TransportParameters { original_destination_connection_id: None, initial_source_connection_id: Some(0xe5c0ce6224c30b55), retry_source_connection_id: None, stateless_reset_token: None, preferred_address: None, migration_support: false, max_idle_timeout: 120s, ack_delay_exponent: 3, max_ack_delay: 25ms, max_udp_payload_size: 1200, active_connection_id_limit: 8, initial_max_stream_data_bidi_local: 16777216, initial_max_stream_data_bidi_remote: 16777216, initial_max_stream_data_uni: 16777216, initial_max_streams_bidi: 128, initial_max_streams_uni: 128, max_datagram_frame_size: 0, dc_supported_versions: [] } id=0 1.068185869s s2n_quic:server:conn: key_update: key_type=OneRtt { generation: 0 } cipher_suite=TLS_AES_128_GCM_SHA256 id=0 1.068422672s 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.068454031s s2n_quic:server:conn: ack_range_sent: packet_header=Initial { number: 0, version: 1 } path_id=0 ack_range=0..=0 id=0 1.068487584s s2n_quic:server:conn: frame_sent: packet_header=Initial { number: 0, version: 1 } path_id=0 frame=Crypto { offset: 0, len: 123 } id=0 1.068533740s s2n_quic:server:conn: packet_sent: packet_header=Initial { number: 0, version: 1 } packet_len=184 id=0 1.068568846s s2n_quic:server:conn: frame_sent: packet_header=Handshake { number: 0, version: 1 } path_id=0 frame=Crypto { offset: 0, len: 687 } id=0 1.068592861s s2n_quic:server:conn: frame_sent: packet_header=Handshake { number: 0, version: 1 } path_id=0 frame=Padding id=0 1.068624580s s2n_quic:server:conn: packet_sent: packet_header=Handshake { number: 0, version: 1 } packet_len=1048 id=0 1.068667831s s2n_quic:server:conn: datagram_sent: len=1232 gso_offset=0 id=0 1.068718246s s2n_quic:server:conn: recovery_metrics: path=Path { local_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, local_cid: 0x04c9370baa4a412eee392dd012fd760e, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:33213, remote_cid: 0xe5c0ce6224c30b55, 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.068793837s s2n_quic:server: platform_event_loop_sleep: timeout=Some(998.549ms) processing_duration=2.121ms 1.102626284s s2n_quic:server: platform_event_loop_wakeup: timeout_expired=false rx_ready=true tx_ready=false application_wakeup=false 1.102666950s s2n_quic:server: platform_tx: count=1 syscalls=1 blocked_syscalls=0 total_errors=0 dropped_errors=0 1.102681838s s2n_quic:server: platform_rx: count=1 syscalls=2 blocked_syscalls=1 total_errors=0 dropped_errors=0 1.102730249s s2n_quic:server:conn: datagram_received: len=92 id=0 1.102771696s s2n_quic:server:conn: packet_received: packet_header=Handshake { number: 0, version: 1 } id=0 1.102795982s 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: 0x04c9370baa4a412eee392dd012fd760e, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:33213, remote_cid: 0xe5c0ce6224c30b55, id: 0, is_active: true } frame=Crypto { offset: 0, len: 36 } id=0 1.102855313s s2n_quic:server:conn: key_space_discarded: space=Initial id=0 1.102872956s s2n_quic:server:conn: recovery_metrics: path=Path { local_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, local_cid: 0x04c9370baa4a412eee392dd012fd760e, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:33213, remote_cid: 0xe5c0ce6224c30b55, 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.102981570s s2n_quic:server:conn: handshake_status_updated: status=Complete id=0 1.103003110s s2n_quic:server:conn: handshake_status_updated: status=Confirmed id=0 1.103022687s s2n_quic:server:conn: tls_exporter_ready: session=TlsSession { .. } id=0 1.103060467s s2n_quic:server:conn: key_space_discarded: space=Handshake id=0 1.103103638s s2n_quic:server:conn: recovery_metrics: path=Path { local_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, local_cid: 0x04c9370baa4a412eee392dd012fd760e, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:33213, remote_cid: 0xe5c0ce6224c30b55, 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=1048 congestion_limited=false id=0 1.103267926s s2n_quic:server:conn: frame_sent: packet_header=OneRtt { number: 0 } path_id=0 frame=Ping id=0 1.103305125s s2n_quic:server:conn: frame_sent: packet_header=OneRtt { number: 0 } path_id=0 frame=Padding id=0 1.103343628s s2n_quic:server:conn: packet_sent: packet_header=OneRtt { number: 0 } packet_len=1452 id=0 1.103360389s s2n_quic:server:conn: datagram_sent: len=1452 gso_offset=0 id=0 1.103390585s s2n_quic:server:conn: frame_sent: packet_header=OneRtt { number: 1 } path_id=0 frame=HandshakeDone id=0 1.103415833s 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.103430480s 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.103449125s 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.103487627s s2n_quic:server:conn: packet_sent: packet_header=OneRtt { number: 1 } packet_len=135 id=0 1.103508897s s2n_quic:server:conn: datagram_sent: len=135 gso_offset=0 id=0 1.103559893s s2n_quic:server:conn: recovery_metrics: path=Path { local_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, local_cid: 0x04c9370baa4a412eee392dd012fd760e, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:33213, remote_cid: 0xe5c0ce6224c30b55, 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=1587 congestion_limited=false id=0 1.103620255s s2n_quic:server: platform_event_loop_sleep: timeout=Some(1.023627s) processing_duration=997µs Accepted a QUIC connection from [fd00:cafe:cafe::100]:33213 on [fd00:cafe:cafe:100::100]:443 1.103815171s s2n_quic:server: platform_event_loop_wakeup: timeout_expired=false rx_ready=true tx_ready=false application_wakeup=false 1.103844405s s2n_quic:server: platform_tx: count=2 syscalls=1 blocked_syscalls=0 total_errors=0 dropped_errors=0 1.103856969s s2n_quic:server: platform_rx: count=4 syscalls=2 blocked_syscalls=1 total_errors=0 dropped_errors=0 1.103881925s s2n_quic:server: version_information: server_versions=[1] client_versions=[] chosen_version=Some(1) 1.103907012s s2n_quic:server:conn: datagram_received: len=1200 id=0 1.103953660s s2n_quic:server:conn: datagram_received: len=59 id=0 1.103994406s s2n_quic:server:conn: datagram_received: len=58 id=0 1.104028059s s2n_quic:server:conn: packet_received: packet_header=OneRtt { number: 0 } id=0 1.104053406s 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: 0x04c9370baa4a412eee392dd012fd760e, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:33213, remote_cid: 0xe5c0ce6224c30b55, id: 0, is_active: true } frame=Stream { id: 0, offset: 0, len: 17, is_fin: true } id=0 1.104154526s s2n_quic:server:conn: rx_stream_progress: bytes=17 id=0 1.104213787s s2n_quic:server:conn: datagram_received: len=58 id=0 1.104248262s s2n_quic:server:conn: packet_received: packet_header=OneRtt { number: 1 } id=0 1.104267908s 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: 0x04c9370baa4a412eee392dd012fd760e, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:33213, remote_cid: 0xe5c0ce6224c30b55, id: 0, is_active: true } frame=Stream { id: 4, offset: 0, len: 17, is_fin: true } id=0 1.104320266s s2n_quic:server:conn: rx_stream_progress: bytes=17 id=0 1.104393073s s2n_quic:server: platform_event_loop_sleep: timeout=Some(24.484ms) processing_duration=579µs 1.105182170s s2n_quic_qns::server::h09: 4096 bytes ready to send on Stream(0) 1.105235691s s2n_quic_qns::server::h09: 4096 bytes sent on Stream(0) 1.105307766s s2n_quic:server: platform_event_loop_wakeup: timeout_expired=false rx_ready=false tx_ready=false application_wakeup=true 1.105367457s s2n_quic:server:conn: frame_sent: packet_header=OneRtt { number: 2 } path_id=0 frame=Ack { ecn_counts: None, largest_acknowledged: 1, ack_range_count: 1 } id=0 1.105388707s s2n_quic:server:conn: ack_range_sent: packet_header=OneRtt { number: 2 } path_id=0 ack_range=0..=1 id=0 1.105402703s s2n_quic:server:conn: frame_sent: packet_header=OneRtt { number: 2 } path_id=0 frame=HandshakeDone id=0 1.105438200s s2n_quic:server:conn: frame_sent: packet_header=OneRtt { number: 2 } path_id=0 frame=Stream { id: 0, offset: 0, len: 1197, is_fin: false } id=0 1.105487622s s2n_quic:server:conn: packet_sent: packet_header=OneRtt { number: 2 } packet_len=1232 id=0 1.105506848s s2n_quic:server:conn: datagram_sent: len=1232 gso_offset=0 id=0 1.105538157s s2n_quic:server:conn: frame_sent: packet_header=OneRtt { number: 3 } path_id=0 frame=HandshakeDone id=0 1.105570818s s2n_quic:server:conn: frame_sent: packet_header=OneRtt { number: 3 } path_id=0 frame=Stream { id: 0, offset: 1197, len: 1201, is_fin: false } id=0 1.105607267s s2n_quic:server:conn: packet_sent: packet_header=OneRtt { number: 3 } packet_len=1232 id=0 1.105624569s s2n_quic:server:conn: datagram_sent: len=1232 gso_offset=0 id=0 1.105655847s s2n_quic:server:conn: frame_sent: packet_header=OneRtt { number: 4 } path_id=0 frame=HandshakeDone id=0 1.105703216s s2n_quic:server:conn: frame_sent: packet_header=OneRtt { number: 4 } path_id=0 frame=Stream { id: 0, offset: 2398, len: 1201, is_fin: false } id=0 1.105744994s s2n_quic:server:conn: packet_sent: packet_header=OneRtt { number: 4 } packet_len=1232 id=0 1.105762437s s2n_quic:server:conn: datagram_sent: len=1232 gso_offset=0 id=0 1.105793555s s2n_quic:server:conn: frame_sent: packet_header=OneRtt { number: 5 } path_id=0 frame=HandshakeDone id=0 1.105826277s s2n_quic:server:conn: frame_sent: packet_header=OneRtt { number: 5 } path_id=0 frame=Stream { id: 0, offset: 3599, len: 497, is_fin: false } id=0 1.105862875s s2n_quic:server:conn: packet_sent: packet_header=OneRtt { number: 5 } packet_len=530 id=0 1.105879867s s2n_quic:server:conn: datagram_sent: len=530 gso_offset=0 id=0 1.105913460s s2n_quic:server:conn: recovery_metrics: path=Path { local_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, local_cid: 0x04c9370baa4a412eee392dd012fd760e, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:33213, remote_cid: 0xe5c0ce6224c30b55, 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=5813 congestion_limited=false id=0 1.105947544s s2n_quic:server:conn: tx_stream_progress: bytes=4096 id=0 1.105984693s s2n_quic:server: platform_event_loop_sleep: timeout=Some(1.023358s) processing_duration=677µs 1.106120788s s2n_quic_qns::server::h09: 4096 bytes ready to send on Stream(4) 1.106165552s s2n_quic_qns::server::h09: 4096 bytes sent on Stream(4) 1.106231666s s2n_quic:server: platform_event_loop_wakeup: timeout_expired=false rx_ready=false tx_ready=false application_wakeup=true 1.106259999s s2n_quic:server: platform_tx: count=4 syscalls=1 blocked_syscalls=0 total_errors=0 dropped_errors=0 1.106296808s s2n_quic:server:conn: frame_sent: packet_header=OneRtt { number: 6 } path_id=0 frame=HandshakeDone id=0 1.106331252s s2n_quic:server:conn: frame_sent: packet_header=OneRtt { number: 6 } path_id=0 frame=Stream { id: 4, offset: 0, len: 1203, is_fin: false } id=0 1.106373451s s2n_quic:server:conn: packet_sent: packet_header=OneRtt { number: 6 } packet_len=1232 id=0 1.106403698s s2n_quic:server:conn: datagram_sent: len=1232 gso_offset=0 id=0 1.106436660s s2n_quic:server:conn: frame_sent: packet_header=OneRtt { number: 7 } path_id=0 frame=HandshakeDone id=0 1.106470032s s2n_quic:server:conn: frame_sent: packet_header=OneRtt { number: 7 } path_id=0 frame=Stream { id: 4, offset: 1203, len: 1201, is_fin: false } id=0 1.106511820s s2n_quic:server:conn: ecn_state_changed: path=Path { local_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, local_cid: 0x04c9370baa4a412eee392dd012fd760e, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:33213, remote_cid: 0xe5c0ce6224c30b55, id: 0, is_active: true } state=Unknown id=0 1.106541236s s2n_quic:server:conn: packet_sent: packet_header=OneRtt { number: 7 } packet_len=1232 id=0 1.106554721s s2n_quic:server:conn: datagram_sent: len=1232 gso_offset=0 id=0 1.106585599s s2n_quic:server:conn: frame_sent: packet_header=OneRtt { number: 8 } path_id=0 frame=HandshakeDone id=0 1.106618250s s2n_quic:server:conn: frame_sent: packet_header=OneRtt { number: 8 } path_id=0 frame=Stream { id: 4, offset: 2404, len: 1201, is_fin: false } id=0 1.106661350s s2n_quic:server:conn: packet_sent: packet_header=OneRtt { number: 8 } packet_len=1232 id=0 1.106682530s s2n_quic:server:conn: datagram_sent: len=1232 gso_offset=0 id=0 1.106719509s s2n_quic:server:conn: frame_sent: packet_header=OneRtt { number: 9 } path_id=0 frame=HandshakeDone id=0 1.106765525s s2n_quic:server:conn: frame_sent: packet_header=OneRtt { number: 9 } path_id=0 frame=Stream { id: 4, offset: 3605, len: 491, is_fin: false } id=0 1.106810119s s2n_quic:server:conn: packet_sent: packet_header=OneRtt { number: 9 } packet_len=524 id=0 1.106827552s s2n_quic:server:conn: datagram_sent: len=524 gso_offset=0 id=0 1.106861215s s2n_quic:server:conn: recovery_metrics: path=Path { local_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, local_cid: 0x04c9370baa4a412eee392dd012fd760e, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:33213, remote_cid: 0xe5c0ce6224c30b55, 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=10033 congestion_limited=false id=0 1.106891211s s2n_quic:server:conn: tx_stream_progress: bytes=4096 id=0 1.106925976s s2n_quic:server: platform_event_loop_sleep: timeout=Some(1.023353s) processing_duration=694µs 1.107041492s s2n_quic_qns::server::h09: 1024 bytes ready to send on Stream(0) 1.107105121s s2n_quic_qns::server::h09: 1024 bytes sent on Stream(0) 1.107193096s s2n_quic:server: platform_event_loop_wakeup: timeout_expired=false rx_ready=false tx_ready=false application_wakeup=true 1.107222571s s2n_quic:server: platform_tx: count=4 syscalls=1 blocked_syscalls=0 total_errors=0 dropped_errors=0 1.107259530s s2n_quic:server:conn: frame_sent: packet_header=OneRtt { number: 10 } path_id=0 frame=HandshakeDone id=0 1.107295718s s2n_quic:server:conn: frame_sent: packet_header=OneRtt { number: 10 } path_id=0 frame=Stream { id: 0, offset: 4096, len: 1024, is_fin: false } id=0 1.107339811s s2n_quic:server:conn: packet_sent: packet_header=OneRtt { number: 10 } packet_len=1057 id=0 1.107357284s s2n_quic:server:conn: datagram_sent: len=1057 gso_offset=0 id=0 1.107391297s s2n_quic:server:conn: recovery_metrics: path=Path { local_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, local_cid: 0x04c9370baa4a412eee392dd012fd760e, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:33213, remote_cid: 0xe5c0ce6224c30b55, 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=11090 congestion_limited=true id=0 1.107421474s s2n_quic:server:conn: tx_stream_progress: bytes=1024 id=0 1.107457501s s2n_quic:server: platform_event_loop_sleep: timeout=Some(1.023784s) processing_duration=271µs 1.107540557s s2n_quic_qns::server::h09: 4096 bytes ready to send on Stream(4) 1.107581524s s2n_quic_qns::server::h09: 4096 bytes sent on Stream(4) 1.107646235s s2n_quic:server: platform_event_loop_wakeup: timeout_expired=false rx_ready=false tx_ready=false application_wakeup=true 1.107674407s s2n_quic:server: platform_tx: count=1 syscalls=1 blocked_syscalls=0 total_errors=0 dropped_errors=0 1.107693603s s2n_quic:server: platform_event_loop_sleep: timeout=Some(1.023547s) processing_duration=49µs 1.107769345s s2n_quic_qns::server::h09: 2048 bytes ready to send on Stream(4) 1.107805232s s2n_quic_qns::server::h09: 2048 bytes sent on Stream(4) 1.137827437s s2n_quic:server: platform_event_loop_wakeup: timeout_expired=false rx_ready=true tx_ready=false application_wakeup=false 1.137866410s s2n_quic:server: platform_rx: count=2 syscalls=2 blocked_syscalls=1 total_errors=0 dropped_errors=0 1.137909310s s2n_quic:server:conn: datagram_received: len=44 id=0 1.137944567s s2n_quic:server:conn: packet_received: packet_header=OneRtt { number: 2 } id=0 1.137968762s 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: 0x04c9370baa4a412eee392dd012fd760e, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:33213, remote_cid: 0xe5c0ce6224c30b55, id: 0, is_active: true } frame=Ack { ecn_counts: None, largest_acknowledged: 2, ack_range_count: 2 } id=0 1.138011572s 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: 0x04c9370baa4a412eee392dd012fd760e, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:33213, remote_cid: 0xe5c0ce6224c30b55, id: 0, is_active: true } ack_range=2..=2 id=0 1.138093395s 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: 0x04c9370baa4a412eee392dd012fd760e, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:33213, remote_cid: 0xe5c0ce6224c30b55, id: 0, is_active: true } ack_range=0..=0 id=0 1.138127619s s2n_quic:server:conn: mtu_updated: path_id=0 mtu=1452 cause=ProbeAcknowledged search_complete=false id=0 1.138162915s s2n_quic:server:conn: pacing_rate_updated: path_id=0 bytes_per_second=1223332 burst_size=12320 pacing_gain=2.77 id=0 1.138205595s s2n_quic:server:conn: ecn_state_changed: path=Path { local_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, local_cid: 0x04c9370baa4a412eee392dd012fd760e, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:33213, remote_cid: 0xe5c0ce6224c30b55, id: 0, is_active: true } state=Failed id=0 1.138229370s s2n_quic:server:conn: delivery_rate_sampled: path_id=0 rate_sample=RateSample { interval: 34.645ms, delivered_bytes: 2684, lost_bytes: 0, ecn_ce_count: 0, is_app_limited: true, prior_delivered_bytes: 0, bytes_in_flight: 2819, prior_lost_bytes: 0, prior_ecn_ce_count: 0, delivery_rate_bytes_per_second: 77471 } id=0 1.138255268s s2n_quic:server:conn: recovery_metrics: path=Path { local_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, local_cid: 0x04c9370baa4a412eee392dd012fd760e, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:33213, remote_cid: 0xe5c0ce6224c30b55, id: 0, is_active: true } min_rtt=32.549ms smoothed_rtt=32.549ms latest_rtt=32.549ms rtt_variance=16.2745ms max_ack_delay=25ms pto_count=0 congestion_window=17204 bytes_in_flight=8406 congestion_limited=false id=0 1.138328575s s2n_quic:server:conn: datagram_received: len=65 id=0 1.138365575s s2n_quic:server:conn: packet_received: packet_header=OneRtt { number: 8 } id=0 1.138385963s 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: 0x04c9370baa4a412eee392dd012fd760e, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:33213, remote_cid: 0xe5c0ce6224c30b55, id: 0, is_active: true } frame=NewConnectionId { sequence_number: 1, retire_prior_to: 0 } id=0 1.138433642s s2n_quic:server:conn: connection_id_updated: path_id=0 cid_consumer=Local previous=0xe5c0ce6224c30b55 current=0x687655ed53cd5de6 id=0 1.138503523s s2n_quic:server:conn: frame_sent: packet_header=OneRtt { number: 11 } path_id=0 frame=Ping id=0 1.138557114s s2n_quic:server:conn: frame_sent: packet_header=OneRtt { number: 11 } path_id=0 frame=Padding id=0 1.138594073s s2n_quic:server:conn: packet_sent: packet_header=OneRtt { number: 11 } packet_len=5202 id=0 1.138610614s s2n_quic:server:conn: datagram_sent: len=5202 gso_offset=0 id=0 1.138645880s s2n_quic:server:conn: frame_sent: packet_header=OneRtt { number: 12 } path_id=0 frame=Ack { ecn_counts: None, largest_acknowledged: 8, ack_range_count: 2 } id=0 1.138664054s s2n_quic:server:conn: ack_range_sent: packet_header=OneRtt { number: 12 } path_id=0 ack_range=8..=8 id=0 1.138676377s s2n_quic:server:conn: ack_range_sent: packet_header=OneRtt { number: 12 } path_id=0 ack_range=2..=2 id=0 1.138689311s s2n_quic:server:conn: frame_sent: packet_header=OneRtt { number: 12 } path_id=0 frame=HandshakeDone id=0 1.138708988s s2n_quic:server:conn: frame_sent: packet_header=OneRtt { number: 12 } path_id=0 frame=RetireConnectionId id=0 1.138740246s s2n_quic:server:conn: frame_sent: packet_header=OneRtt { number: 12 } path_id=0 frame=Stream { id: 4, offset: 4096, len: 1411, is_fin: false } id=0 1.138784970s s2n_quic:server:conn: packet_sent: packet_header=OneRtt { number: 12 } packet_len=1452 id=0 1.138803605s s2n_quic:server:conn: datagram_sent: len=1452 gso_offset=0 id=0 1.138822891s s2n_quic:server:conn: recovery_metrics: path=Path { local_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, local_cid: 0x04c9370baa4a412eee392dd012fd760e, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:33213, remote_cid: 0x687655ed53cd5de6, id: 0, is_active: true } min_rtt=32.549ms smoothed_rtt=32.549ms latest_rtt=32.549ms rtt_variance=16.2745ms max_ack_delay=25ms pto_count=0 congestion_window=17204 bytes_in_flight=15060 congestion_limited=false id=0 1.138850312s s2n_quic:server:conn: tx_stream_progress: bytes=1415 id=0 1.138892722s s2n_quic:server: platform_event_loop_sleep: timeout=Some(972µs) processing_duration=1.068ms 1.139023156s s2n_quic:server: platform_event_loop_wakeup: timeout_expired=false rx_ready=true tx_ready=false application_wakeup=false 1.139054755s s2n_quic:server: platform_tx_error: errno=90 1.139066347s s2n_quic:server: platform_tx: count=1 syscalls=2 blocked_syscalls=0 total_errors=1 dropped_errors=0 1.139099299s s2n_quic:server: platform_rx: count=1 syscalls=2 blocked_syscalls=1 total_errors=0 dropped_errors=0 1.139131359s s2n_quic:server:conn: datagram_received: len=65 id=0 1.139162487s s2n_quic:server:conn: packet_received: packet_header=OneRtt { number: 9 } id=0 1.139182054s 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: 0x04c9370baa4a412eee392dd012fd760e, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:33213, remote_cid: 0x687655ed53cd5de6, id: 0, is_active: true } frame=NewConnectionId { sequence_number: 2, retire_prior_to: 0 } id=0 1.139262354s s2n_quic:server:conn: handshake_status_updated: status=HandshakeDoneLost id=0 1.139315233s s2n_quic:server:conn: packet_lost: packet_header=OneRtt { number: 1 } path=Path { local_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, local_cid: 0x04c9370baa4a412eee392dd012fd760e, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:33213, remote_cid: 0x687655ed53cd5de6, id: 0, is_active: true } bytes_lost=135 is_mtu_probe=false id=0 1.139343195s s2n_quic:server:conn: congestion: path=Path { local_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, local_cid: 0x04c9370baa4a412eee392dd012fd760e, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:33213, remote_cid: 0x687655ed53cd5de6, id: 0, is_active: true } source=PacketLoss id=0 1.139363404s s2n_quic:server:conn: recovery_metrics: path=Path { local_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, local_cid: 0x04c9370baa4a412eee392dd012fd760e, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:33213, remote_cid: 0x687655ed53cd5de6, id: 0, is_active: true } min_rtt=32.549ms smoothed_rtt=32.549ms latest_rtt=32.549ms rtt_variance=16.2745ms max_ack_delay=25ms pto_count=0 congestion_window=17204 bytes_in_flight=14925 congestion_limited=false id=0 1.139442912s s2n_quic:server: platform_event_loop_sleep: timeout=Some(1.416ms) processing_duration=421µs 1.139604896s s2n_quic:server: platform_event_loop_wakeup: timeout_expired=false rx_ready=true tx_ready=false application_wakeup=false 1.139631075s s2n_quic:server: platform_rx: count=1 syscalls=2 blocked_syscalls=1 total_errors=0 dropped_errors=0 1.139662524s s2n_quic:server:conn: datagram_received: len=44 id=0 1.139693402s s2n_quic:server:conn: packet_received: packet_header=OneRtt { number: 10 } id=0 1.139714491s 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: 0x04c9370baa4a412eee392dd012fd760e, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:33213, remote_cid: 0x687655ed53cd5de6, id: 0, is_active: true } frame=Ack { ecn_counts: None, largest_acknowledged: 4, ack_range_count: 2 } id=0 1.139736983s 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: 0x04c9370baa4a412eee392dd012fd760e, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:33213, remote_cid: 0x687655ed53cd5de6, id: 0, is_active: true } ack_range=2..=4 id=0 1.139787688s 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: 0x04c9370baa4a412eee392dd012fd760e, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:33213, remote_cid: 0x687655ed53cd5de6, id: 0, is_active: true } ack_range=0..=0 id=0 1.139823214s s2n_quic:server:conn: delivery_rate_sampled: path_id=0 rate_sample=RateSample { interval: 36.405ms, delivered_bytes: 5148, lost_bytes: 135, ecn_ce_count: 0, is_app_limited: true, prior_delivered_bytes: 0, bytes_in_flight: 5283, prior_lost_bytes: 0, prior_ecn_ce_count: 0, delivery_rate_bytes_per_second: 141409 } id=0 1.139848913s s2n_quic:server:conn: recovery_metrics: path=Path { local_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, local_cid: 0x04c9370baa4a412eee392dd012fd760e, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:33213, remote_cid: 0x687655ed53cd5de6, id: 0, is_active: true } min_rtt=32.549ms smoothed_rtt=32.7675ms latest_rtt=34.309ms rtt_variance=12.642875ms max_ack_delay=25ms pto_count=0 congestion_window=19668 bytes_in_flight=12461 congestion_limited=false id=0 1.139908715s s2n_quic:server:conn: recovery_metrics: path=Path { local_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, local_cid: 0x04c9370baa4a412eee392dd012fd760e, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:33213, remote_cid: 0x687655ed53cd5de6, id: 0, is_active: true } min_rtt=32.549ms smoothed_rtt=32.7675ms latest_rtt=34.309ms rtt_variance=12.642875ms max_ack_delay=25ms pto_count=0 congestion_window=19668 bytes_in_flight=12461 congestion_limited=false id=0 1.139985669s s2n_quic:server:conn: frame_sent: packet_header=OneRtt { number: 13 } path_id=0 frame=Ack { ecn_counts: None, largest_acknowledged: 10, ack_range_count: 2 } id=0 1.140016396s s2n_quic:server:conn: ack_range_sent: packet_header=OneRtt { number: 13 } path_id=0 ack_range=8..=10 id=0 1.140029230s s2n_quic:server:conn: ack_range_sent: packet_header=OneRtt { number: 13 } path_id=0 ack_range=2..=2 id=0 1.140041824s s2n_quic:server:conn: frame_sent: packet_header=OneRtt { number: 13 } path_id=0 frame=HandshakeDone id=0 1.140061150s s2n_quic:server:conn: frame_sent: packet_header=OneRtt { number: 13 } path_id=0 frame=NewConnectionId { sequence_number: 1, retire_prior_to: 1 } id=0 1.140101095s s2n_quic:server:conn: frame_sent: packet_header=OneRtt { number: 13 } path_id=0 frame=NewConnectionId { sequence_number: 2, retire_prior_to: 1 } id=0 1.140121183s s2n_quic:server:conn: frame_sent: packet_header=OneRtt { number: 13 } path_id=0 frame=NewConnectionId { sequence_number: 3, retire_prior_to: 1 } id=0 1.140149045s s2n_quic:server:conn: frame_sent: packet_header=OneRtt { number: 13 } path_id=0 frame=Stream { id: 0, offset: 5120, len: 0, is_fin: true } id=0 1.140174833s s2n_quic:server:conn: frame_sent: packet_header=OneRtt { number: 13 } path_id=0 frame=Stream { id: 4, offset: 5507, len: 1301, is_fin: false } id=0 1.140214357s s2n_quic:server:conn: packet_sent: packet_header=OneRtt { number: 13 } packet_len=1452 id=0 1.140232471s s2n_quic:server:conn: datagram_sent: len=1452 gso_offset=0 id=0 1.140269390s s2n_quic:server:conn: frame_sent: packet_header=OneRtt { number: 14 } path_id=0 frame=Ack { ecn_counts: None, largest_acknowledged: 10, ack_range_count: 2 } id=0 1.140287835s s2n_quic:server:conn: ack_range_sent: packet_header=OneRtt { number: 14 } path_id=0 ack_range=8..=10 id=0 1.140299897s s2n_quic:server:conn: ack_range_sent: packet_header=OneRtt { number: 14 } path_id=0 ack_range=2..=2 id=0 1.140312591s s2n_quic:server:conn: frame_sent: packet_header=OneRtt { number: 14 } path_id=0 frame=HandshakeDone id=0 1.140340483s s2n_quic:server:conn: frame_sent: packet_header=OneRtt { number: 14 } path_id=0 frame=Stream { id: 4, offset: 6808, len: 1414, is_fin: false } id=0 1.140384365s s2n_quic:server:conn: packet_sent: packet_header=OneRtt { number: 14 } packet_len=1452 id=0 1.140403321s s2n_quic:server:conn: datagram_sent: len=1452 gso_offset=0 id=0 1.140421946s s2n_quic:server:conn: recovery_metrics: path=Path { local_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, local_cid: 0x04c9370baa4a412eee392dd012fd760e, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:33213, remote_cid: 0x687655ed53cd5de6, id: 0, is_active: true } min_rtt=32.549ms smoothed_rtt=32.7675ms latest_rtt=34.309ms rtt_variance=12.642875ms max_ack_delay=25ms pto_count=0 congestion_window=19668 bytes_in_flight=15365 congestion_limited=false id=0 1.140449177s s2n_quic:server:conn: tx_stream_progress: bytes=2715 id=0 1.140481788s s2n_quic:server: platform_event_loop_sleep: timeout=Some(2.749ms) processing_duration=879µs 1.141123159s s2n_quic:server: platform_event_loop_wakeup: timeout_expired=false rx_ready=true tx_ready=false application_wakeup=false 1.141155580s s2n_quic:server: platform_tx: count=2 syscalls=1 blocked_syscalls=0 total_errors=0 dropped_errors=0 1.141168584s s2n_quic:server: platform_rx: count=1 syscalls=2 blocked_syscalls=1 total_errors=0 dropped_errors=0 1.141199442s s2n_quic:server:conn: datagram_received: len=44 id=0 1.141230470s s2n_quic:server:conn: packet_received: packet_header=OneRtt { number: 11 } id=0 1.141251810s 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: 0x04c9370baa4a412eee392dd012fd760e, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:33213, remote_cid: 0x687655ed53cd5de6, id: 0, is_active: true } frame=Ack { ecn_counts: None, largest_acknowledged: 6, ack_range_count: 2 } id=0 1.141274332s 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: 0x04c9370baa4a412eee392dd012fd760e, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:33213, remote_cid: 0x687655ed53cd5de6, id: 0, is_active: true } ack_range=2..=6 id=0 1.141330026s 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: 0x04c9370baa4a412eee392dd012fd760e, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:33213, remote_cid: 0x687655ed53cd5de6, id: 0, is_active: true } ack_range=0..=0 id=0 1.141372045s s2n_quic:server:conn: delivery_rate_sampled: path_id=0 rate_sample=RateSample { interval: 37.942ms, delivered_bytes: 6910, lost_bytes: 135, ecn_ce_count: 0, is_app_limited: true, prior_delivered_bytes: 0, bytes_in_flight: 7045, prior_lost_bytes: 0, prior_ecn_ce_count: 0, delivery_rate_bytes_per_second: 182120 } id=0 1.141397873s s2n_quic:server:conn: recovery_metrics: path=Path { local_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, local_cid: 0x04c9370baa4a412eee392dd012fd760e, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:33213, remote_cid: 0x687655ed53cd5de6, id: 0, is_active: true } min_rtt=32.549ms smoothed_rtt=33.033434ms latest_rtt=34.91ms rtt_variance=10.014029ms max_ack_delay=25ms pto_count=0 congestion_window=21430 bytes_in_flight=13603 congestion_limited=false id=0 1.141454149s s2n_quic:server: platform_event_loop_sleep: timeout=Some(1.777ms) processing_duration=333µs 1.143106052s s2n_quic:server: platform_event_loop_wakeup: timeout_expired=false rx_ready=true tx_ready=false application_wakeup=false 1.143137491s s2n_quic:server: platform_rx: count=1 syscalls=2 blocked_syscalls=1 total_errors=0 dropped_errors=0 1.143172767s s2n_quic:server:conn: datagram_received: len=44 id=0 1.143217571s s2n_quic:server:conn: packet_received: packet_header=OneRtt { number: 12 } id=0 1.143241355s 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: 0x04c9370baa4a412eee392dd012fd760e, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:33213, remote_cid: 0x687655ed53cd5de6, id: 0, is_active: true } frame=Ack { ecn_counts: None, largest_acknowledged: 8, ack_range_count: 2 } id=0 1.143264068s s2n_quic:server:conn: ack_range_received: packet_header=OneRtt { number: 12 } path=Path { local_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, local_cid: 0x04c9370baa4a412eee392dd012fd760e, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:33213, remote_cid: 0x687655ed53cd5de6, id: 0, is_active: true } ack_range=2..=8 id=0 1.143317378s s2n_quic:server:conn: ack_range_received: packet_header=OneRtt { number: 12 } path=Path { local_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, local_cid: 0x04c9370baa4a412eee392dd012fd760e, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:33213, remote_cid: 0x687655ed53cd5de6, id: 0, is_active: true } ack_range=0..=0 id=0 1.143353997s s2n_quic:server:conn: delivery_rate_sampled: path_id=0 rate_sample=RateSample { interval: 39.912ms, delivered_bytes: 9374, lost_bytes: 135, ecn_ce_count: 0, is_app_limited: true, prior_delivered_bytes: 0, bytes_in_flight: 9509, prior_lost_bytes: 0, prior_ecn_ce_count: 0, delivery_rate_bytes_per_second: 234866 } id=0 1.143378893s s2n_quic:server:conn: recovery_metrics: path=Path { local_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, local_cid: 0x04c9370baa4a412eee392dd012fd760e, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:33213, remote_cid: 0x687655ed53cd5de6, id: 0, is_active: true } min_rtt=32.549ms smoothed_rtt=33.512628ms latest_rtt=36.88ms rtt_variance=8.468912ms max_ack_delay=25ms pto_count=0 congestion_window=23894 bytes_in_flight=11139 congestion_limited=false id=0 1.143438785s s2n_quic:server:conn: recovery_metrics: path=Path { local_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, local_cid: 0x04c9370baa4a412eee392dd012fd760e, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:33213, remote_cid: 0x687655ed53cd5de6, id: 0, is_active: true } min_rtt=32.549ms smoothed_rtt=33.512628ms latest_rtt=36.88ms rtt_variance=8.468912ms max_ack_delay=25ms pto_count=0 congestion_window=23894 bytes_in_flight=11139 congestion_limited=false id=0 1.143505029s s2n_quic:server:conn: frame_sent: packet_header=OneRtt { number: 15 } path_id=0 frame=Ack { ecn_counts: None, largest_acknowledged: 12, ack_range_count: 2 } id=0 1.143527421s s2n_quic:server:conn: ack_range_sent: packet_header=OneRtt { number: 15 } path_id=0 ack_range=8..=12 id=0 1.143545625s s2n_quic:server:conn: ack_range_sent: packet_header=OneRtt { number: 15 } path_id=0 ack_range=2..=2 id=0 1.143559141s s2n_quic:server:conn: frame_sent: packet_header=OneRtt { number: 15 } path_id=0 frame=HandshakeDone id=0 1.143588716s s2n_quic:server:conn: frame_sent: packet_header=OneRtt { number: 15 } path_id=0 frame=Stream { id: 4, offset: 8222, len: 1414, is_fin: false } id=0 1.143630374s s2n_quic:server:conn: packet_sent: packet_header=OneRtt { number: 15 } packet_len=1452 id=0 1.143647967s s2n_quic:server:conn: datagram_sent: len=1452 gso_offset=0 id=0 1.143683814s s2n_quic:server:conn: frame_sent: packet_header=OneRtt { number: 16 } path_id=0 frame=Ack { ecn_counts: None, largest_acknowledged: 12, ack_range_count: 2 } id=0 1.143702329s s2n_quic:server:conn: ack_range_sent: packet_header=OneRtt { number: 16 } path_id=0 ack_range=8..=12 id=0 1.143714441s s2n_quic:server:conn: ack_range_sent: packet_header=OneRtt { number: 16 } path_id=0 ack_range=2..=2 id=0 1.143726905s s2n_quic:server:conn: frame_sent: packet_header=OneRtt { number: 16 } path_id=0 frame=HandshakeDone id=0 1.143755689s s2n_quic:server:conn: frame_sent: packet_header=OneRtt { number: 16 } path_id=0 frame=Stream { id: 4, offset: 9636, len: 604, is_fin: true } id=0 1.143794802s s2n_quic:server:conn: packet_sent: packet_header=OneRtt { number: 16 } packet_len=644 id=0 1.143812555s s2n_quic:server:conn: datagram_sent: len=644 gso_offset=0 id=0 1.143831320s s2n_quic:server:conn: recovery_metrics: path=Path { local_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, local_cid: 0x04c9370baa4a412eee392dd012fd760e, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:33213, remote_cid: 0x687655ed53cd5de6, id: 0, is_active: true } min_rtt=32.549ms smoothed_rtt=33.512628ms latest_rtt=36.88ms rtt_variance=8.468912ms max_ack_delay=25ms pto_count=0 congestion_window=23894 bytes_in_flight=13235 congestion_limited=false id=0 1.143858201s s2n_quic:server:conn: tx_stream_progress: bytes=2014 id=0 1.143890351s s2n_quic:server: platform_event_loop_sleep: timeout=Some(1.714ms) processing_duration=786µs 1.144487760s s2n_quic:server: platform_event_loop_wakeup: timeout_expired=false rx_ready=true tx_ready=false application_wakeup=false 1.144520982s s2n_quic:server: platform_tx: count=2 syscalls=1 blocked_syscalls=0 total_errors=0 dropped_errors=0 1.144534177s s2n_quic:server: platform_rx: count=1 syscalls=2 blocked_syscalls=1 total_errors=0 dropped_errors=0 1.144565095s s2n_quic:server:conn: datagram_received: len=44 id=0 1.144595992s s2n_quic:server:conn: packet_received: packet_header=OneRtt { number: 13 } id=0 1.144617453s s2n_quic:server:conn: frame_received: packet_header=OneRtt { number: 13 } path=Path { local_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, local_cid: 0x04c9370baa4a412eee392dd012fd760e, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:33213, remote_cid: 0x687655ed53cd5de6, id: 0, is_active: true } frame=Ack { ecn_counts: None, largest_acknowledged: 10, ack_range_count: 2 } id=0 1.144640075s s2n_quic:server:conn: ack_range_received: packet_header=OneRtt { number: 13 } path=Path { local_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, local_cid: 0x04c9370baa4a412eee392dd012fd760e, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:33213, remote_cid: 0x687655ed53cd5de6, id: 0, is_active: true } ack_range=2..=10 id=0 1.144696290s s2n_quic:server:conn: ack_range_received: packet_header=OneRtt { number: 13 } path=Path { local_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, local_cid: 0x04c9370baa4a412eee392dd012fd760e, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:33213, remote_cid: 0x687655ed53cd5de6, id: 0, is_active: true } ack_range=0..=0 id=0 1.144732097s s2n_quic:server:conn: delivery_rate_sampled: path_id=0 rate_sample=RateSample { interval: 41.307ms, delivered_bytes: 10955, lost_bytes: 135, ecn_ce_count: 0, is_app_limited: true, prior_delivered_bytes: 0, bytes_in_flight: 11090, prior_lost_bytes: 0, prior_ecn_ce_count: 0, delivery_rate_bytes_per_second: 265209 } id=0 1.144763276s s2n_quic:server:conn: recovery_metrics: path=Path { local_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, local_cid: 0x04c9370baa4a412eee392dd012fd760e, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:33213, remote_cid: 0x687655ed53cd5de6, id: 0, is_active: true } min_rtt=32.549ms smoothed_rtt=33.985921ms latest_rtt=37.312ms rtt_variance=7.298277ms max_ack_delay=25ms pto_count=0 congestion_window=25475 bytes_in_flight=11654 congestion_limited=false id=0 1.144825362s s2n_quic:server:conn: recovery_metrics: path=Path { local_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, local_cid: 0x04c9370baa4a412eee392dd012fd760e, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:33213, remote_cid: 0x687655ed53cd5de6, id: 0, is_active: true } min_rtt=32.549ms smoothed_rtt=33.985921ms latest_rtt=37.312ms rtt_variance=7.298277ms max_ack_delay=25ms pto_count=0 congestion_window=25475 bytes_in_flight=11654 congestion_limited=false id=0 1.144875726s s2n_quic:server: platform_event_loop_sleep: timeout=Some(86.784ms) processing_duration=389µs 1.170974848s s2n_quic:server: platform_event_loop_wakeup: timeout_expired=false rx_ready=true tx_ready=false application_wakeup=false 1.171043707s s2n_quic:server: platform_rx: count=1 syscalls=2 blocked_syscalls=1 total_errors=0 dropped_errors=0 1.171126873s s2n_quic:server:conn: datagram_received: len=65 id=0 1.171182076s s2n_quic:server:conn: packet_received: packet_header=OneRtt { number: 14 } id=0 1.171208626s s2n_quic:server:conn: frame_received: packet_header=OneRtt { number: 14 } path=Path { local_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, local_cid: 0x04c9370baa4a412eee392dd012fd760e, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:33213, remote_cid: 0x687655ed53cd5de6, id: 0, is_active: true } frame=NewConnectionId { sequence_number: 3, retire_prior_to: 0 } id=0 1.171343910s s2n_quic:server: platform_event_loop_sleep: timeout=Some(24.753ms) processing_duration=379µs 1.172367206s s2n_quic:server: platform_event_loop_wakeup: timeout_expired=false rx_ready=true tx_ready=false application_wakeup=false 1.172397793s s2n_quic:server: platform_rx: count=1 syscalls=2 blocked_syscalls=1 total_errors=0 dropped_errors=0 1.172430564s s2n_quic:server:conn: datagram_received: len=41 id=0 1.172462334s s2n_quic:server:conn: packet_received: packet_header=OneRtt { number: 15 } id=0 1.172482442s s2n_quic:server:conn: frame_received: packet_header=OneRtt { number: 15 } path=Path { local_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, local_cid: 0x04c9370baa4a412eee392dd012fd760e, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:33213, remote_cid: 0x687655ed53cd5de6, id: 0, is_active: true } frame=ConnectionClose id=0 1.172511216s s2n_quic:server:conn: connection_close_frame_received: packet_header=OneRtt { number: 15 } path=Path { local_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, local_cid: 0x04c9370baa4a412eee392dd012fd760e, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:33213, remote_cid: 0x687655ed53cd5de6, id: 0, is_active: true } frame=ConnectionCloseFrame { error_code: 7, frame_type: Some(0), reason: None } id=0 1.172540530s s2n_quic:server:conn: connection_closed: error=Transport { code: transport::error::Code(VarInt(7), "FRAME_ENCODING_ERROR"), frame_type: 0, reason: "", initiator: Remote, source: Location { file: "/app/quic/s2n-quic-transport/src/space/mod.rs", line: 966, col: 32 } } id=0 1.172633915s s2n_quic:server: platform_event_loop_sleep: timeout=None processing_duration=267µs 1.172716079s s2n_quic_qns::server::h09: Final stats: MyConnectionContext { packet_sent: 19, stream_requests: 2 } 1.173462687s s2n_quic:server: platform_event_loop_wakeup: timeout_expired=false rx_ready=true tx_ready=false application_wakeup=false 1.173504375s s2n_quic:server: platform_rx: count=2 syscalls=2 blocked_syscalls=1 total_errors=0 dropped_errors=0 1.173540312s s2n_quic:server: endpoint_datagram_dropped: len=41 reason=UnknownDestinationConnectionId 1.173563977s s2n_quic:server: endpoint_datagram_dropped: len=41 reason=UnknownDestinationConnectionId 1.173585988s s2n_quic:server: platform_event_loop_sleep: timeout=None processing_duration=126µs 1.196778901s s2n_quic:server: platform_event_loop_wakeup: timeout_expired=true rx_ready=false tx_ready=false application_wakeup=false 1.196870442s s2n_quic:server: platform_event_loop_sleep: timeout=None processing_duration=103µs