0.046292183s s2n_quic:server: platform_feature_configured: configuration=Gso { max_segments: 1 } 0.046450006s s2n_quic:server: platform_feature_configured: configuration=BaseMtu { mtu: 1228 } 0.046466576s s2n_quic:server: platform_feature_configured: configuration=InitialMtu { mtu: 1280 } 0.046476374s s2n_quic:server: platform_feature_configured: configuration=MaxMtu { mtu: 9000 } 0.046577673s s2n_quic:server: platform_feature_configured: configuration=Gro { enabled: true } 0.046595345s s2n_quic:server: platform_feature_configured: configuration=Ecn { enabled: true } 0.048013455s s2n_quic:server: platform_event_loop_started: local_address=[::]:443 0.048130672s s2n_quic:server: platform_event_loop_wakeup: timeout_expired=false rx_ready=false tx_ready=true application_wakeup=false 0.048174283s s2n_quic:server: platform_rx: count=0 syscalls=1 blocked_syscalls=1 total_errors=0 dropped_errors=0 0.048238943s s2n_quic:server: platform_event_loop_sleep: timeout=None processing_duration=110µs 0.324000489s s2n_quic:server: platform_event_loop_wakeup: timeout_expired=false rx_ready=true tx_ready=false application_wakeup=false 0.324101747s s2n_quic:server: platform_rx: count=1 syscalls=2 blocked_syscalls=1 total_errors=0 dropped_errors=0 0.324210528s s2n_quic:server: version_information: server_versions=[1] client_versions=[1463896404] chosen_version=None 0.324300044s s2n_quic:server: endpoint_datagram_dropped: len=1207 reason=UnsupportedVersion 0.324359404s s2n_quic:server: endpoint_packet_sent: packet_header=VersionNegotiation 0.324376005s s2n_quic:server: endpoint_datagram_sent: len=15 gso_offset=0 0.324397946s s2n_quic:server: platform_event_loop_sleep: timeout=None processing_duration=414µs 1.036695488s s2n_quic:server: platform_event_loop_wakeup: timeout_expired=false rx_ready=true tx_ready=false application_wakeup=false 1.036787889s s2n_quic:server: platform_tx: count=1 syscalls=1 blocked_syscalls=0 total_errors=0 dropped_errors=0 1.036804099s s2n_quic:server: platform_rx: count=1 syscalls=2 blocked_syscalls=1 total_errors=0 dropped_errors=0 1.036853972s s2n_quic:server: version_information: server_versions=[1] client_versions=[] chosen_version=Some(1) 1.037282336s s2n_quic:server:conn: key_update: key_type=Initial cipher_suite=TLS_AES_128_GCM_SHA256 id=0 1.037358107s s2n_quic:server:conn: connection_started: path=Path { local_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, local_cid: 0x79d2d2638885d8ec953e23d43e7bfba2, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:49086, remote_cid: 0x, id: 0, is_active: true } id=0 1.037389214s s2n_quic:server:conn: mtu_updated: path_id=0 mtu=1232 cause=NewPath search_complete=false id=0 1.037427615s s2n_quic:server:conn: datagram_received: len=1232 id=0 1.037468171s s2n_quic:server:conn: frame_received: packet_header=Initial { number: 9057819, version: 1 } path=Path { local_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, local_cid: 0x79d2d2638885d8ec953e23d43e7bfba2, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:49086, remote_cid: 0x, id: 0, is_active: true } frame=Crypto { offset: 0, len: 310 } id=0 1.037523604s s2n_quic:server:conn: tls_client_hello: payload=[[3, 3, 178, 197, 35, 98, 48, 100, 80, 40, 86, 223, 109, 29, 107, 148, 169, 111, 240, 3, 202, 67, 240, 110, 194, 76, 170, 42, 116, 120, 39, 239, 205, 120, 0, 0, 2, 19, 1, 1, 0, 1, 7, 0, 43, 0, 3, 2, 3, 4, 0, 10, 0, 6, 0, 4, 0, 29, 0, 23, 0, 51, 0, 107, 0, 105, 0, 23, 0, 65, 4, 111, 99, 220, 190, 169, 0, 204, 208, 165, 205, 86, 76, 59, 190, 99, 161, 46, 11, 139, 59, 87, 164, 141, 145, 215, 230, 77, 53, 9, 145, 124, 61, 167, 63, 41, 246, 30, 208, 155, 67, 61, 247, 215, 211, 6, 83, 97, 226, 31, 19, 143, 208, 41, 222, 131, 82, 44, 95, 206, 30, 5, 33, 194, 173, 0, 29, 0, 32, 87, 89, 224, 20, 2, 129, 13, 214, 235, 226, 178, 204, 234, 85, 97, 254, 216, 39, 41, 80, 130, 136, 30, 225, 106, 204, 66, 134, 100, 74, 23, 106, 0, 13, 0, 10, 0, 8, 4, 3, 5, 3, 6, 3, 8, 4, 0, 0, 0, 12, 0, 10, 0, 0, 7, 115, 101, 114, 118, 101, 114, 54, 0, 16, 0, 13, 0, 11, 10, 104, 113, 45, 105, 110, 116, 101, 114, 111, 112, 0, 45, 0, 3, 2, 1, 0, 0, 27, 0, 5, 4, 0, 1, 0, 3, 0, 57, 0, 68, 5, 4, 128, 4, 0, 0, 6, 4, 128, 4, 0, 0, 7, 4, 128, 4, 0, 0, 4, 4, 128, 40, 0, 0, 8, 2, 64, 100, 9, 2, 64, 100, 1, 4, 128, 0, 234, 96, 10, 1, 3, 3, 2, 69, 220, 14, 1, 5, 15, 0, 32, 4, 128, 0, 255, 255, 143, 240, 160, 1, 1, 0, 128, 0, 81, 120, 1, 1]] id=0 1.037590528s s2n_quic:server:conn: frame_received: packet_header=Initial { number: 9057819, version: 1 } path=Path { local_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, local_cid: 0x79d2d2638885d8ec953e23d43e7bfba2, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:49086, remote_cid: 0x, id: 0, is_active: true } frame=Padding id=0 1.037922633s s2n_quic:server:conn: key_update: key_type=Handshake cipher_suite=TLS_AES_128_GCM_SHA256 id=0 1.038077391s s2n_quic:server:conn: application_protocol_information: chosen_application_protocol=[104, 113, 45, 105, 110, 116, 101, 114, 111, 112] id=0 1.038106514s s2n_quic:server:conn: server_name_information: chosen_server_name="server6" id=0 1.038125540s s2n_quic:server:conn: key_exchange_group: chosen_group_name="secp256r1" contains_kem=false id=0 1.038163520s 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: 60s, ack_delay_exponent: 3, max_ack_delay: 25ms, max_udp_payload_size: 1500, active_connection_id_limit: 5, initial_max_stream_data_bidi_local: 262144, initial_max_stream_data_bidi_remote: 262144, initial_max_stream_data_uni: 262144, initial_max_streams_bidi: 100, initial_max_streams_uni: 100, max_datagram_frame_size: 65535, dc_supported_versions: [] } id=0 1.038260650s s2n_quic:server:conn: key_update: key_type=OneRtt { generation: 0 } cipher_suite=TLS_AES_128_GCM_SHA256 id=0 1.038503771s s2n_quic:server:conn: frame_sent: packet_header=Initial { number: 0, version: 1 } path_id=0 frame=Ack { ecn_counts: None, largest_acknowledged: 9057819, ack_range_count: 1 } id=0 1.038536331s s2n_quic:server:conn: ack_range_sent: packet_header=Initial { number: 0, version: 1 } path_id=0 ack_range=9057819..=9057819 id=0 1.038566918s 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.038609908s s2n_quic:server:conn: packet_sent: packet_header=Initial { number: 0, version: 1 } packet_len=179 id=0 1.038649481s s2n_quic:server:conn: frame_sent: packet_header=Handshake { number: 0, version: 1 } path_id=0 frame=Crypto { offset: 0, len: 683 } id=0 1.038674047s s2n_quic:server:conn: frame_sent: packet_header=Handshake { number: 0, version: 1 } path_id=0 frame=Padding id=0 1.038698963s s2n_quic:server:conn: packet_sent: packet_header=Handshake { number: 0, version: 1 } packet_len=1053 id=0 1.038740721s s2n_quic:server:conn: datagram_sent: len=1232 gso_offset=0 id=0 1.038790693s s2n_quic:server:conn: recovery_metrics: path=Path { local_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, local_cid: 0x79d2d2638885d8ec953e23d43e7bfba2, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:49086, remote_cid: 0x, 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.038859290s s2n_quic:server: platform_event_loop_sleep: timeout=Some(998.557ms) processing_duration=2.178ms 1.072144333s s2n_quic:server: platform_event_loop_wakeup: timeout_expired=false rx_ready=true tx_ready=false application_wakeup=false 1.072264917s s2n_quic:server: platform_tx: count=1 syscalls=1 blocked_syscalls=0 total_errors=0 dropped_errors=0 1.072300593s s2n_quic:server: platform_rx: count=2 syscalls=2 blocked_syscalls=1 total_errors=0 dropped_errors=0 1.072394397s s2n_quic:server:conn: datagram_received: len=90 id=0 1.072465158s s2n_quic:server:conn: packet_received: packet_header=Handshake { number: 9057819, version: 1 } id=0 1.072499312s s2n_quic:server:conn: frame_received: packet_header=Handshake { number: 9057819, version: 1 } path=Path { local_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, local_cid: 0x79d2d2638885d8ec953e23d43e7bfba2, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:49086, remote_cid: 0x, id: 0, is_active: true } frame=Crypto { offset: 0, len: 36 } id=0 1.072549825s s2n_quic:server:conn: frame_received: packet_header=Handshake { number: 9057819, version: 1 } path=Path { local_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, local_cid: 0x79d2d2638885d8ec953e23d43e7bfba2, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:49086, remote_cid: 0x, id: 0, is_active: true } frame=Ack { ecn_counts: None, largest_acknowledged: 0, ack_range_count: 1 } id=0 1.072586573s s2n_quic:server:conn: ack_range_received: packet_header=Handshake { number: 9057819, version: 1 } path=Path { local_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, local_cid: 0x79d2d2638885d8ec953e23d43e7bfba2, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:49086, remote_cid: 0x, id: 0, is_active: true } ack_range=0..=0 id=0 1.072642637s s2n_quic:server:conn: pacing_rate_updated: path_id=0 bytes_per_second=995966 burst_size=12320 pacing_gain=2.77 id=0 1.072680568s s2n_quic:server:conn: ecn_state_changed: path=Path { local_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, local_cid: 0x79d2d2638885d8ec953e23d43e7bfba2, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:49086, remote_cid: 0x, id: 0, is_active: true } state=Failed id=0 1.072702839s s2n_quic:server:conn: delivery_rate_sampled: path_id=0 rate_sample=RateSample { interval: 33.922ms, delivered_bytes: 1053, lost_bytes: 0, ecn_ce_count: 0, is_app_limited: true, prior_delivered_bytes: 0, bytes_in_flight: 1232, prior_lost_bytes: 0, prior_ecn_ce_count: 0, delivery_rate_bytes_per_second: 31041 } id=0 1.072732354s s2n_quic:server:conn: recovery_metrics: path=Path { local_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, local_cid: 0x79d2d2638885d8ec953e23d43e7bfba2, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:49086, remote_cid: 0x, id: 0, is_active: true } min_rtt=33.922ms smoothed_rtt=33.922ms latest_rtt=33.922ms rtt_variance=16.961ms max_ack_delay=25ms pto_count=0 congestion_window=13373 bytes_in_flight=179 congestion_limited=false id=0 1.072782817s s2n_quic:server:conn: key_space_discarded: space=Initial id=0 1.072797605s s2n_quic:server:conn: recovery_metrics: path=Path { local_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, local_cid: 0x79d2d2638885d8ec953e23d43e7bfba2, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:49086, remote_cid: 0x, id: 0, is_active: true } min_rtt=33.922ms smoothed_rtt=33.922ms latest_rtt=33.922ms rtt_variance=16.961ms max_ack_delay=25ms pto_count=0 congestion_window=13373 bytes_in_flight=179 congestion_limited=false id=0 1.072929730s s2n_quic:server:conn: handshake_status_updated: status=Complete id=0 1.072951500s s2n_quic:server:conn: handshake_status_updated: status=Confirmed id=0 1.072976476s s2n_quic:server:conn: tls_exporter_ready: session=TlsSession { .. } id=0 1.073026008s s2n_quic:server:conn: key_space_discarded: space=Handshake id=0 1.073042258s s2n_quic:server:conn: recovery_metrics: path=Path { local_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, local_cid: 0x79d2d2638885d8ec953e23d43e7bfba2, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:49086, remote_cid: 0x, id: 0, is_active: true } min_rtt=33.922ms smoothed_rtt=33.922ms latest_rtt=33.922ms rtt_variance=16.961ms max_ack_delay=25ms pto_count=0 congestion_window=13373 bytes_in_flight=0 congestion_limited=false id=0 1.073171208s s2n_quic:server:conn: datagram_received: len=80 id=0 1.073234545s s2n_quic:server:conn: packet_received: packet_header=OneRtt { number: 9057819 } id=0 1.073266224s s2n_quic:server:conn: frame_received: packet_header=OneRtt { number: 9057819 } path=Path { local_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, local_cid: 0x79d2d2638885d8ec953e23d43e7bfba2, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:49086, remote_cid: 0x, id: 0, is_active: true } frame=Stream { id: 0, offset: 0, len: 17, is_fin: true } id=0 1.073324792s s2n_quic:server:conn: frame_received: packet_header=OneRtt { number: 9057819 } path=Path { local_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, local_cid: 0x79d2d2638885d8ec953e23d43e7bfba2, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:49086, remote_cid: 0x, id: 0, is_active: true } frame=Stream { id: 4, offset: 0, len: 17, is_fin: true } id=0 1.073362462s s2n_quic:server:conn: frame_received: packet_header=OneRtt { number: 9057819 } path=Path { local_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, local_cid: 0x79d2d2638885d8ec953e23d43e7bfba2, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:49086, remote_cid: 0x, id: 0, is_active: true } frame=Padding id=0 1.073402326s s2n_quic:server:conn: rx_stream_progress: bytes=34 id=0 1.073520505s s2n_quic:server:conn: frame_sent: packet_header=OneRtt { number: 0 } path_id=0 frame=Ping id=0 1.073554779s s2n_quic:server:conn: frame_sent: packet_header=OneRtt { number: 0 } path_id=0 frame=Padding id=0 1.073595945s s2n_quic:server:conn: packet_sent: packet_header=OneRtt { number: 0 } packet_len=1452 id=0 1.073612466s s2n_quic:server:conn: datagram_sent: len=1452 gso_offset=0 id=0 1.073650917s s2n_quic:server:conn: frame_sent: packet_header=OneRtt { number: 1 } path_id=0 frame=Ack { ecn_counts: None, largest_acknowledged: 9057819, ack_range_count: 1 } id=0 1.073669101s s2n_quic:server:conn: ack_range_sent: packet_header=OneRtt { number: 1 } path_id=0 ack_range=9057819..=9057819 id=0 1.073683337s s2n_quic:server:conn: frame_sent: packet_header=OneRtt { number: 1 } path_id=0 frame=HandshakeDone id=0 1.073708915s 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.073723492s 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.073735905s 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.073773404s s2n_quic:server:conn: packet_sent: packet_header=OneRtt { number: 1 } packet_len=136 id=0 1.073790476s s2n_quic:server:conn: datagram_sent: len=136 gso_offset=0 id=0 1.073824008s s2n_quic:server:conn: recovery_metrics: path=Path { local_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, local_cid: 0x79d2d2638885d8ec953e23d43e7bfba2, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:49086, remote_cid: 0x, id: 0, is_active: true } min_rtt=33.922ms smoothed_rtt=33.922ms latest_rtt=33.922ms rtt_variance=16.961ms max_ack_delay=25ms pto_count=0 congestion_window=13373 bytes_in_flight=1588 congestion_limited=false id=0 1.073887015s s2n_quic:server: platform_event_loop_sleep: timeout=Some(126.369ms) processing_duration=1.757ms Accepted a QUIC connection from [fd00:cafe:cafe::100]:49086 on [fd00:cafe:cafe:100::100]:443 1.074756497s s2n_quic_qns::server::h09: 4096 bytes ready to send on Stream(4) 1.074823151s s2n_quic_qns::server::h09: 4096 bytes sent on Stream(4) 1.074875618s s2n_quic_qns::server::h09: 4096 bytes ready to send on Stream(0) 1.074903349s s2n_quic_qns::server::h09: 4096 bytes sent on Stream(0) 1.074968841s s2n_quic:server: platform_event_loop_wakeup: timeout_expired=false rx_ready=false tx_ready=false application_wakeup=true 1.074998075s s2n_quic:server: platform_tx: count=2 syscalls=1 blocked_syscalls=0 total_errors=0 dropped_errors=0 1.075036506s s2n_quic:server:conn: frame_sent: packet_header=OneRtt { number: 2 } path_id=0 frame=HandshakeDone id=0 1.075080047s s2n_quic:server:conn: frame_sent: packet_header=OneRtt { number: 2 } path_id=0 frame=Stream { id: 4, offset: 0, len: 1211, is_fin: false } id=0 1.075124600s s2n_quic:server:conn: packet_sent: packet_header=OneRtt { number: 2 } packet_len=1232 id=0 1.075142713s s2n_quic:server:conn: datagram_sent: len=1232 gso_offset=0 id=0 1.075172569s s2n_quic:server:conn: frame_sent: packet_header=OneRtt { number: 3 } path_id=0 frame=HandshakeDone id=0 1.075204097s s2n_quic:server:conn: frame_sent: packet_header=OneRtt { number: 3 } path_id=0 frame=Stream { id: 0, offset: 0, len: 1211, is_fin: false } id=0 1.075278876s s2n_quic:server:conn: packet_sent: packet_header=OneRtt { number: 3 } packet_len=1232 id=0 1.075298262s s2n_quic:server:conn: datagram_sent: len=1232 gso_offset=0 id=0 1.075328758s s2n_quic:server:conn: frame_sent: packet_header=OneRtt { number: 4 } path_id=0 frame=HandshakeDone id=0 1.075361309s s2n_quic:server:conn: frame_sent: packet_header=OneRtt { number: 4 } path_id=0 frame=Stream { id: 4, offset: 1211, len: 1209, is_fin: false } id=0 1.075398668s s2n_quic:server:conn: packet_sent: packet_header=OneRtt { number: 4 } packet_len=1232 id=0 1.075415820s s2n_quic:server:conn: datagram_sent: len=1232 gso_offset=0 id=0 1.075444323s s2n_quic:server:conn: frame_sent: packet_header=OneRtt { number: 5 } path_id=0 frame=HandshakeDone id=0 1.075474228s s2n_quic:server:conn: frame_sent: packet_header=OneRtt { number: 5 } path_id=0 frame=Stream { id: 0, offset: 1211, len: 1209, is_fin: false } id=0 1.075510946s s2n_quic:server:conn: packet_sent: packet_header=OneRtt { number: 5 } packet_len=1232 id=0 1.075528088s s2n_quic:server:conn: datagram_sent: len=1232 gso_offset=0 id=0 1.075556711s s2n_quic:server:conn: frame_sent: packet_header=OneRtt { number: 6 } path_id=0 frame=HandshakeDone id=0 1.075586917s s2n_quic:server:conn: frame_sent: packet_header=OneRtt { number: 6 } path_id=0 frame=Stream { id: 4, offset: 2420, len: 1209, is_fin: false } id=0 1.075623194s s2n_quic:server:conn: packet_sent: packet_header=OneRtt { number: 6 } packet_len=1232 id=0 1.075640126s s2n_quic:server:conn: datagram_sent: len=1232 gso_offset=0 id=0 1.075668999s s2n_quic:server:conn: frame_sent: packet_header=OneRtt { number: 7 } path_id=0 frame=HandshakeDone id=0 1.075699175s s2n_quic:server:conn: frame_sent: packet_header=OneRtt { number: 7 } path_id=0 frame=Stream { id: 0, offset: 2420, len: 1209, is_fin: false } id=0 1.075735883s s2n_quic:server:conn: packet_sent: packet_header=OneRtt { number: 7 } packet_len=1232 id=0 1.075752865s s2n_quic:server:conn: datagram_sent: len=1232 gso_offset=0 id=0 1.075781348s s2n_quic:server:conn: frame_sent: packet_header=OneRtt { number: 8 } path_id=0 frame=HandshakeDone id=0 1.075813016s s2n_quic:server:conn: frame_sent: packet_header=OneRtt { number: 8 } path_id=0 frame=Stream { id: 4, offset: 3629, len: 467, is_fin: false } id=0 1.075836820s s2n_quic:server:conn: frame_sent: packet_header=OneRtt { number: 8 } path_id=0 frame=Stream { id: 0, offset: 3629, len: 467, is_fin: false } id=0 1.075876344s s2n_quic:server:conn: packet_sent: packet_header=OneRtt { number: 8 } packet_len=965 id=0 1.075893886s s2n_quic:server:conn: datagram_sent: len=965 gso_offset=0 id=0 1.075933029s s2n_quic:server:conn: recovery_metrics: path=Path { local_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, local_cid: 0x79d2d2638885d8ec953e23d43e7bfba2, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:49086, remote_cid: 0x, id: 0, is_active: true } min_rtt=33.922ms smoothed_rtt=33.922ms latest_rtt=33.922ms rtt_variance=16.961ms max_ack_delay=25ms pto_count=0 congestion_window=13373 bytes_in_flight=9945 congestion_limited=false id=0 1.075966912s s2n_quic:server:conn: tx_stream_progress: bytes=8192 id=0 1.076003860s s2n_quic:server: platform_event_loop_sleep: timeout=Some(125.779ms) processing_duration=1.036ms 1.076143339s s2n_quic_qns::server::h09: 4096 bytes ready to send on Stream(4) 1.076188072s s2n_quic_qns::server::h09: 4096 bytes sent on Stream(4) 1.076292656s s2n_quic:server: platform_event_loop_wakeup: timeout_expired=false rx_ready=false tx_ready=false application_wakeup=true 1.076327821s s2n_quic:server: platform_tx: count=7 syscalls=1 blocked_syscalls=0 total_errors=0 dropped_errors=0 1.076367585s s2n_quic:server:conn: frame_sent: packet_header=OneRtt { number: 9 } path_id=0 frame=HandshakeDone id=0 1.076409412s s2n_quic:server:conn: frame_sent: packet_header=OneRtt { number: 9 } path_id=0 frame=Stream { id: 4, offset: 4096, len: 1209, is_fin: false } id=0 1.076453965s s2n_quic:server:conn: packet_sent: packet_header=OneRtt { number: 9 } packet_len=1232 id=0 1.076471548s s2n_quic:server:conn: datagram_sent: len=1232 gso_offset=0 id=0 1.076502705s s2n_quic:server:conn: frame_sent: packet_header=OneRtt { number: 10 } path_id=0 frame=HandshakeDone id=0 1.076538211s s2n_quic:server:conn: frame_sent: packet_header=OneRtt { number: 10 } path_id=0 frame=Stream { id: 4, offset: 5305, len: 1209, is_fin: false } id=0 1.076588053s s2n_quic:server:conn: packet_sent: packet_header=OneRtt { number: 10 } packet_len=1232 id=0 1.076606988s s2n_quic:server:conn: datagram_sent: len=1232 gso_offset=0 id=0 1.076639128s s2n_quic:server:conn: recovery_metrics: path=Path { local_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, local_cid: 0x79d2d2638885d8ec953e23d43e7bfba2, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:49086, remote_cid: 0x, id: 0, is_active: true } min_rtt=33.922ms smoothed_rtt=33.922ms latest_rtt=33.922ms rtt_variance=16.961ms max_ack_delay=25ms pto_count=0 congestion_window=13373 bytes_in_flight=12409 congestion_limited=true id=0 1.076671278s s2n_quic:server:conn: tx_stream_progress: bytes=2422 id=0 1.076711362s s2n_quic:server: platform_event_loop_sleep: timeout=Some(126.401ms) processing_duration=421µs 1.076804375s s2n_quic_qns::server::h09: 1024 bytes ready to send on Stream(0) 1.076839840s s2n_quic_qns::server::h09: 1024 bytes sent on Stream(0) 1.076882319s s2n_quic_qns::server::h09: 2048 bytes ready to send on Stream(4) 1.076905342s s2n_quic_qns::server::h09: 2048 bytes sent on Stream(4) 1.114612915s s2n_quic:server: platform_event_loop_wakeup: timeout_expired=false rx_ready=true tx_ready=false application_wakeup=false 1.114698634s s2n_quic:server: platform_tx: count=2 syscalls=1 blocked_syscalls=0 total_errors=0 dropped_errors=0 1.114714153s s2n_quic:server: platform_rx: count=1 syscalls=2 blocked_syscalls=1 total_errors=0 dropped_errors=0 1.114782780s s2n_quic:server:conn: datagram_received: len=48 id=0 1.114843012s s2n_quic:server:conn: packet_received: packet_header=OneRtt { number: 9057820 } id=0 1.114876254s s2n_quic:server:conn: frame_received: packet_header=OneRtt { number: 9057820 } path=Path { local_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, local_cid: 0x79d2d2638885d8ec953e23d43e7bfba2, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:49086, remote_cid: 0x, id: 0, is_active: true } frame=Ack { ecn_counts: None, largest_acknowledged: 9, ack_range_count: 1 } id=0 1.114929973s s2n_quic:server:conn: ack_range_received: packet_header=OneRtt { number: 9057820 } path=Path { local_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, local_cid: 0x79d2d2638885d8ec953e23d43e7bfba2, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:49086, remote_cid: 0x, id: 0, is_active: true } ack_range=0..=9 id=0 1.114967653s s2n_quic:server:conn: mtu_updated: path_id=0 mtu=1452 cause=ProbeAcknowledged search_complete=false id=0 1.114988221s s2n_quic:server:conn: handshake_status_updated: status=HandshakeDoneAcked id=0 1.115078869s s2n_quic:server:conn: delivery_rate_sampled: path_id=0 rate_sample=RateSample { interval: 41.257ms, delivered_bytes: 11177, lost_bytes: 0, ecn_ce_count: 0, is_app_limited: true, prior_delivered_bytes: 1053, bytes_in_flight: 11177, prior_lost_bytes: 0, prior_ecn_ce_count: 0, delivery_rate_bytes_per_second: 270911 } id=0 1.115119555s s2n_quic:server:conn: recovery_metrics: path=Path { local_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, local_cid: 0x79d2d2638885d8ec953e23d43e7bfba2, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:49086, remote_cid: 0x, id: 0, is_active: true } min_rtt=33.922ms smoothed_rtt=34.4815ms latest_rtt=38.399ms rtt_variance=13.83975ms max_ack_delay=25ms pto_count=0 congestion_window=26938 bytes_in_flight=1232 congestion_limited=false id=0 1.115148228s s2n_quic:server:conn: frame_received: packet_header=OneRtt { number: 9057820 } path=Path { local_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, local_cid: 0x79d2d2638885d8ec953e23d43e7bfba2, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:49086, remote_cid: 0x, id: 0, is_active: true } frame=Padding id=0 1.115309227s s2n_quic:server:conn: frame_sent: packet_header=OneRtt { number: 11 } path_id=0 frame=Ping id=0 1.115369779s s2n_quic:server:conn: frame_sent: packet_header=OneRtt { number: 11 } path_id=0 frame=Padding id=0 1.115407519s s2n_quic:server:conn: packet_sent: packet_header=OneRtt { number: 11 } packet_len=5202 id=0 1.115423468s s2n_quic:server:conn: datagram_sent: len=5202 gso_offset=0 id=0 1.115462270s s2n_quic:server:conn: frame_sent: packet_header=OneRtt { number: 12 } path_id=0 frame=Ack { ecn_counts: None, largest_acknowledged: 9057820, ack_range_count: 1 } id=0 1.115480725s s2n_quic:server:conn: ack_range_sent: packet_header=OneRtt { number: 12 } path_id=0 ack_range=9057820..=9057820 id=0 1.115522662s s2n_quic:server:conn: frame_sent: packet_header=OneRtt { number: 12 } path_id=0 frame=Stream { id: 0, offset: 4096, len: 1024, is_fin: true } id=0 1.115549031s s2n_quic:server:conn: frame_sent: packet_header=OneRtt { number: 12 } path_id=0 frame=Stream { id: 4, offset: 6514, len: 391, is_fin: false } id=0 1.115588324s s2n_quic:server:conn: packet_sent: packet_header=OneRtt { number: 12 } packet_len=1452 id=0 1.115605887s s2n_quic:server:conn: datagram_sent: len=1452 gso_offset=0 id=0 1.115649467s s2n_quic:server:conn: frame_sent: packet_header=OneRtt { number: 13 } path_id=0 frame=Stream { id: 4, offset: 6905, len: 1430, is_fin: false } id=0 1.115691044s s2n_quic:server:conn: packet_sent: packet_header=OneRtt { number: 13 } packet_len=1452 id=0 1.115707926s s2n_quic:server:conn: datagram_sent: len=1452 gso_offset=0 id=0 1.115750274s s2n_quic:server:conn: frame_sent: packet_header=OneRtt { number: 14 } path_id=0 frame=Stream { id: 4, offset: 8335, len: 1430, is_fin: false } id=0 1.115791731s s2n_quic:server:conn: packet_sent: packet_header=OneRtt { number: 14 } packet_len=1452 id=0 1.115808452s s2n_quic:server:conn: datagram_sent: len=1452 gso_offset=0 id=0 1.115827097s s2n_quic:server:conn: recovery_metrics: path=Path { local_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, local_cid: 0x79d2d2638885d8ec953e23d43e7bfba2, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:49086, remote_cid: 0x, id: 0, is_active: true } min_rtt=33.922ms smoothed_rtt=34.4815ms latest_rtt=38.399ms rtt_variance=13.83975ms max_ack_delay=25ms pto_count=0 congestion_window=26938 bytes_in_flight=10790 congestion_limited=false id=0 1.115860158s s2n_quic:server:conn: tx_stream_progress: bytes=4275 id=0 1.115905452s s2n_quic:server: platform_event_loop_sleep: timeout=Some(2.285ms) processing_duration=1.307ms 1.118109399s s2n_quic:server: platform_event_loop_wakeup: timeout_expired=true rx_ready=false tx_ready=false application_wakeup=false 1.118148632s s2n_quic:server: platform_tx_error: errno=90 1.118160995s s2n_quic:server: platform_tx: count=3 syscalls=2 blocked_syscalls=0 total_errors=1 dropped_errors=0 1.118192723s s2n_quic:server:conn: recovery_metrics: path=Path { local_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, local_cid: 0x79d2d2638885d8ec953e23d43e7bfba2, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:49086, remote_cid: 0x, id: 0, is_active: true } min_rtt=33.922ms smoothed_rtt=34.4815ms latest_rtt=38.399ms rtt_variance=13.83975ms max_ack_delay=25ms pto_count=0 congestion_window=26938 bytes_in_flight=10790 congestion_limited=false id=0 1.118351468s s2n_quic:server:conn: frame_sent: packet_header=OneRtt { number: 15 } path_id=0 frame=Stream { id: 4, offset: 9765, len: 475, is_fin: true } id=0 1.118416929s s2n_quic:server:conn: packet_sent: packet_header=OneRtt { number: 15 } packet_len=499 id=0 1.118438379s s2n_quic:server:conn: datagram_sent: len=499 gso_offset=0 id=0 1.118479275s s2n_quic:server:conn: recovery_metrics: path=Path { local_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, local_cid: 0x79d2d2638885d8ec953e23d43e7bfba2, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:49086, remote_cid: 0x, id: 0, is_active: true } min_rtt=33.922ms smoothed_rtt=34.4815ms latest_rtt=38.399ms rtt_variance=13.83975ms max_ack_delay=25ms pto_count=0 congestion_window=26938 bytes_in_flight=11289 congestion_limited=false id=0 1.118514220s s2n_quic:server:conn: tx_stream_progress: bytes=471 id=0 1.118562950s s2n_quic:server: platform_event_loop_sleep: timeout=Some(114.584ms) processing_duration=454µs 1.135793442s s2n_quic:server: platform_event_loop_wakeup: timeout_expired=false rx_ready=true tx_ready=false application_wakeup=false 1.135868812s s2n_quic:server: platform_tx: count=1 syscalls=1 blocked_syscalls=0 total_errors=0 dropped_errors=0 1.135889660s s2n_quic:server: platform_rx: count=1 syscalls=2 blocked_syscalls=1 total_errors=0 dropped_errors=0 1.135962555s s2n_quic:server:conn: datagram_received: len=48 id=0 1.136030391s s2n_quic:server:conn: packet_received: packet_header=OneRtt { number: 9057821 } id=0 1.136072309s s2n_quic:server:conn: frame_received: packet_header=OneRtt { number: 9057821 } path=Path { local_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, local_cid: 0x79d2d2638885d8ec953e23d43e7bfba2, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:49086, remote_cid: 0x, id: 0, is_active: true } frame=Ack { ecn_counts: None, largest_acknowledged: 10, ack_range_count: 1 } id=0 1.136109728s s2n_quic:server:conn: ack_range_received: packet_header=OneRtt { number: 9057821 } path=Path { local_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, local_cid: 0x79d2d2638885d8ec953e23d43e7bfba2, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:49086, remote_cid: 0x, id: 0, is_active: true } ack_range=0..=10 id=0 1.136209353s s2n_quic:server:conn: delivery_rate_sampled: path_id=0 rate_sample=RateSample { interval: 41.257ms, delivered_bytes: 12409, lost_bytes: 0, ecn_ce_count: 0, is_app_limited: true, prior_delivered_bytes: 1053, bytes_in_flight: 11177, prior_lost_bytes: 0, prior_ecn_ce_count: 0, delivery_rate_bytes_per_second: 300773 } id=0 1.136288860s s2n_quic:server:conn: recovery_metrics: path=Path { local_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, local_cid: 0x79d2d2638885d8ec953e23d43e7bfba2, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:49086, remote_cid: 0x, id: 0, is_active: true } min_rtt=33.922ms smoothed_rtt=37.302934ms latest_rtt=59.578ms rtt_variance=16.022686ms max_ack_delay=25ms pto_count=0 congestion_window=28170 bytes_in_flight=10057 congestion_limited=false id=0 1.136337581s s2n_quic:server:conn: frame_received: packet_header=OneRtt { number: 9057821 } path=Path { local_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, local_cid: 0x79d2d2638885d8ec953e23d43e7bfba2, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:49086, remote_cid: 0x, id: 0, is_active: true } frame=Padding id=0 1.136473022s s2n_quic:server: platform_event_loop_sleep: timeout=Some(108.228ms) processing_duration=691µs 1.147895864s s2n_quic:server: platform_event_loop_wakeup: timeout_expired=false rx_ready=true tx_ready=false application_wakeup=false 1.147992614s s2n_quic:server: platform_rx: count=1 syscalls=2 blocked_syscalls=1 total_errors=0 dropped_errors=0 1.148082701s s2n_quic:server:conn: datagram_received: len=48 id=0 1.148169021s s2n_quic:server:conn: packet_received: packet_header=OneRtt { number: 9057822 } id=0 1.148249770s s2n_quic:server:conn: frame_received: packet_header=OneRtt { number: 9057822 } path=Path { local_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, local_cid: 0x79d2d2638885d8ec953e23d43e7bfba2, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:49086, remote_cid: 0x, id: 0, is_active: true } frame=Ack { ecn_counts: None, largest_acknowledged: 12, ack_range_count: 2 } id=0 1.148295665s s2n_quic:server:conn: ack_range_received: packet_header=OneRtt { number: 9057822 } path=Path { local_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, local_cid: 0x79d2d2638885d8ec953e23d43e7bfba2, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:49086, remote_cid: 0x, id: 0, is_active: true } ack_range=12..=12 id=0 1.148397695s s2n_quic:server:conn: ack_range_received: packet_header=OneRtt { number: 9057822 } path=Path { local_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, local_cid: 0x79d2d2638885d8ec953e23d43e7bfba2, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:49086, remote_cid: 0x, id: 0, is_active: true } ack_range=0..=10 id=0 1.148447827s s2n_quic:server:conn: delivery_rate_sampled: path_id=0 rate_sample=RateSample { interval: 38.928ms, delivered_bytes: 2684, lost_bytes: 0, ecn_ce_count: 0, is_app_limited: false, prior_delivered_bytes: 12230, bytes_in_flight: 7886, prior_lost_bytes: 0, prior_ecn_ce_count: 0, delivery_rate_bytes_per_second: 68947 } id=0 1.148488022s s2n_quic:server:conn: recovery_metrics: path=Path { local_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, local_cid: 0x79d2d2638885d8ec953e23d43e7bfba2, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:49086, remote_cid: 0x, id: 0, is_active: true } min_rtt=32.761ms smoothed_rtt=36.735187ms latest_rtt=32.761ms rtt_variance=13.152496ms max_ack_delay=25ms pto_count=0 congestion_window=29622 bytes_in_flight=8605 congestion_limited=false id=0 1.148534739s s2n_quic:server:conn: frame_received: packet_header=OneRtt { number: 9057822 } path=Path { local_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, local_cid: 0x79d2d2638885d8ec953e23d43e7bfba2, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:49086, remote_cid: 0x, id: 0, is_active: true } frame=Padding id=0 1.148652768s s2n_quic:server: platform_event_loop_sleep: timeout=Some(7.951ms) processing_duration=772µs 1.150559160s s2n_quic:server: platform_event_loop_wakeup: timeout_expired=false rx_ready=true tx_ready=false application_wakeup=false 1.150661439s s2n_quic:server: platform_rx: count=1 syscalls=2 blocked_syscalls=1 total_errors=0 dropped_errors=0 1.150733974s s2n_quic:server:conn: datagram_received: len=45 id=0 1.150825336s s2n_quic:server:conn: packet_received: packet_header=OneRtt { number: 9057823 } id=0 1.150872123s s2n_quic:server:conn: frame_received: packet_header=OneRtt { number: 9057823 } path=Path { local_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, local_cid: 0x79d2d2638885d8ec953e23d43e7bfba2, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:49086, remote_cid: 0x, id: 0, is_active: true } frame=ConnectionClose id=0 1.150934509s s2n_quic:server:conn: connection_close_frame_received: packet_header=OneRtt { number: 9057823 } path=Path { local_addr: [fd00:cafe:cafe:100:0:0:0:100]:443, local_cid: 0x79d2d2638885d8ec953e23d43e7bfba2, remote_addr: [fd00:cafe:cafe:0:0:0:0:100]:49086, remote_cid: 0x, id: 0, is_active: true } frame=ConnectionCloseFrame { error_code: 0, frame_type: None, reason: Some("No Error") } id=0 1.150972409s 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: 995, column: 32 } } id=0 1.151054471s s2n_quic:server: platform_event_loop_sleep: timeout=None processing_duration=496µs 1.151146883s s2n_quic_qns::server::h09: Final stats: MyConnectionContext { packet_sent: 18, stream_requests: 2 } 1.157491773s s2n_quic:server: platform_event_loop_wakeup: timeout_expired=true rx_ready=false tx_ready=false application_wakeup=false 1.157580097s s2n_quic:server: platform_event_loop_sleep: timeout=None processing_duration=95µs