0.047890394s s2n_quic:server: platform_feature_configured: configuration=Gso { max_segments: 1 } 0.048053359s s2n_quic:server: platform_feature_configured: configuration=BaseMtu { mtu: 1228 } 0.048071753s s2n_quic:server: platform_feature_configured: configuration=InitialMtu { mtu: 1280 } 0.048082023s s2n_quic:server: platform_feature_configured: configuration=MaxMtu { mtu: 9000 } 0.048181128s s2n_quic:server: platform_feature_configured: configuration=Gro { enabled: true } 0.048206065s s2n_quic:server: platform_feature_configured: configuration=Ecn { enabled: true } 0.049891288s s2n_quic:server: platform_event_loop_started: local_address=[::]:443 0.050037201s s2n_quic:server: platform_event_loop_wakeup: timeout_expired=false rx_ready=false tx_ready=true application_wakeup=false 0.050081674s s2n_quic:server: platform_rx: count=0 syscalls=1 blocked_syscalls=1 total_errors=0 dropped_errors=0 0.050142438s s2n_quic:server: platform_event_loop_sleep: timeout=None processing_duration=108µs 0.841111106s s2n_quic:server: platform_event_loop_wakeup: timeout_expired=false rx_ready=true tx_ready=false application_wakeup=false 0.841209601s s2n_quic:server: platform_rx: count=3 syscalls=2 blocked_syscalls=1 total_errors=0 dropped_errors=0 0.841295191s s2n_quic:server: version_information: server_versions=[1] client_versions=[1463896404] chosen_version=None 0.841328633s s2n_quic:server: endpoint_datagram_dropped: len=1207 reason=UnsupportedVersion 0.841355744s s2n_quic:server: version_information: server_versions=[1] client_versions=[1463896404] chosen_version=None 0.841371664s s2n_quic:server: endpoint_datagram_dropped: len=1207 reason=UnsupportedVersion 0.841391251s s2n_quic:server: version_information: server_versions=[1] client_versions=[1463896404] chosen_version=None 0.841405818s s2n_quic:server: endpoint_datagram_dropped: len=1207 reason=UnsupportedVersion 0.841454339s s2n_quic:server: endpoint_packet_sent: packet_header=VersionNegotiation 0.841470850s s2n_quic:server: endpoint_datagram_sent: len=15 gso_offset=0 0.841487100s s2n_quic:server: endpoint_packet_sent: packet_header=VersionNegotiation 0.841499273s s2n_quic:server: endpoint_datagram_sent: len=15 gso_offset=0 0.841514060s s2n_quic:server: endpoint_packet_sent: packet_header=VersionNegotiation 0.841523168s s2n_quic:server: endpoint_datagram_sent: len=15 gso_offset=0 0.841541572s s2n_quic:server: platform_event_loop_sleep: timeout=None processing_duration=445µs 1.049099004s s2n_quic:server: platform_event_loop_wakeup: timeout_expired=true rx_ready=false tx_ready=false application_wakeup=false 1.049195495s s2n_quic:server: platform_tx: count=3 syscalls=1 blocked_syscalls=0 total_errors=0 dropped_errors=0 1.049230350s s2n_quic:server: platform_event_loop_sleep: timeout=None processing_duration=145µs 1.768842473s s2n_quic:server: platform_event_loop_wakeup: timeout_expired=false rx_ready=true tx_ready=false application_wakeup=false 1.768935918s s2n_quic:server: platform_rx: count=1 syscalls=2 blocked_syscalls=1 total_errors=0 dropped_errors=0 1.768997623s s2n_quic:server: version_information: server_versions=[1] client_versions=[] chosen_version=Some(1) 1.769403873s s2n_quic:server:conn: key_update: key_type=Initial cipher_suite=TLS_AES_128_GCM_SHA256 id=0 1.769488541s s2n_quic:server:conn: connection_started: path=Path { local_addr: 193.167.100.100:443, local_cid: 0x50c8c625131aa627e524166e027b33c5, remote_addr: [::ffff:193.167.0.100]:55358, remote_cid: 0xdf5b19fd624f0caa, id: 0, is_active: true } id=0 1.769516925s s2n_quic:server:conn: mtu_updated: path_id=0 mtu=1232 cause=NewPath search_complete=false id=0 1.769553864s s2n_quic:server:conn: datagram_received: len=1200 id=0 1.769596543s s2n_quic:server:conn: frame_received: packet_header=Initial { number: 0, version: 1 } path=Path { local_addr: 193.167.100.100:443, local_cid: 0x50c8c625131aa627e524166e027b33c5, remote_addr: [::ffff:193.167.0.100]:55358, remote_cid: 0xdf5b19fd624f0caa, id: 0, is_active: true } frame=Crypto { offset: 0, len: 249 } id=0 1.769664661s s2n_quic:server:conn: tls_client_hello: payload=[[3, 3, 179, 96, 67, 25, 235, 226, 245, 145, 64, 218, 74, 217, 94, 218, 91, 168, 19, 231, 59, 214, 14, 54, 47, 62, 193, 113, 4, 241, 57, 20, 188, 237, 0, 0, 6, 19, 1, 19, 2, 19, 3, 1, 0, 0, 198, 0, 0, 0, 12, 0, 10, 0, 0, 7, 115, 101, 114, 118, 101, 114, 52, 0, 5, 0, 5, 1, 0, 0, 0, 0, 0, 10, 0, 10, 0, 8, 0, 29, 0, 23, 0, 24, 0, 25, 0, 11, 0, 2, 1, 0, 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, 255, 1, 0, 1, 0, 0, 23, 0, 0, 0, 16, 0, 13, 0, 11, 10, 104, 113, 45, 105, 110, 116, 101, 114, 111, 112, 0, 18, 0, 0, 0, 43, 0, 3, 2, 3, 4, 0, 51, 0, 38, 0, 36, 0, 29, 0, 32, 189, 151, 216, 50, 185, 99, 103, 204, 53, 10, 31, 167, 97, 211, 59, 231, 213, 8, 185, 21, 240, 201, 173, 151, 118, 114, 162, 215, 143, 216, 191, 20, 0, 57, 0, 40, 3, 2, 69, 192, 4, 4, 128, 16, 0, 0, 5, 4, 128, 16, 0, 0, 6, 4, 128, 16, 0, 0, 7, 4, 128, 16, 0, 0, 12, 0, 15, 8, 223, 91, 25, 253, 98, 79, 12, 170]] id=0 1.770012622s s2n_quic:server:conn: key_update: key_type=Handshake cipher_suite=TLS_AES_128_GCM_SHA256 id=0 1.770187559s s2n_quic:server:conn: application_protocol_information: chosen_application_protocol=[104, 113, 45, 105, 110, 116, 101, 114, 111, 112] id=0 1.770218858s s2n_quic:server:conn: server_name_information: chosen_server_name="server4" id=0 1.770233736s s2n_quic:server:conn: key_exchange_group: chosen_group_name="x25519" contains_kem=false id=0 1.770269052s s2n_quic:server:conn: transport_parameters_received: transport_parameters=TransportParameters { original_destination_connection_id: None, initial_source_connection_id: Some(0xdf5b19fd624f0caa), retry_source_connection_id: None, stateless_reset_token: None, preferred_address: None, migration_support: false, max_idle_timeout: 0ns, ack_delay_exponent: 3, max_ack_delay: 25ms, max_udp_payload_size: 1472, active_connection_id_limit: 2, initial_max_stream_data_bidi_local: 1048576, initial_max_stream_data_bidi_remote: 1048576, initial_max_stream_data_uni: 1048576, initial_max_streams_bidi: 0, initial_max_streams_uni: 0, max_datagram_frame_size: 0, dc_supported_versions: [] } id=0 1.770343651s s2n_quic:server:conn: key_update: key_type=OneRtt { generation: 0 } cipher_suite=TLS_AES_128_GCM_SHA256 id=0 1.770421266s s2n_quic:server:conn: packet_dropped: reason=DecodingFailed { path: Path { local_addr: 193.167.100.100:443, local_cid: 0x50c8c625131aa627e524166e027b33c5, remote_addr: [::ffff:193.167.0.100]:55358, remote_cid: 0xdf5b19fd624f0caa, id: 0, is_active: true } } id=0 1.770616652s 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.770649363s s2n_quic:server:conn: ack_range_sent: packet_header=Initial { number: 0, version: 1 } path_id=0 ack_range=0..=0 id=0 1.770683147s 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.770731968s s2n_quic:server:conn: packet_sent: packet_header=Initial { number: 0, version: 1 } packet_len=151 id=0 1.770790858s s2n_quic:server:conn: frame_sent: packet_header=Handshake { number: 0, version: 1 } path_id=0 frame=Crypto { offset: 0, len: 685 } id=0 1.770815675s s2n_quic:server:conn: frame_sent: packet_header=Handshake { number: 0, version: 1 } path_id=0 frame=Padding id=0 1.770843316s s2n_quic:server:conn: packet_sent: packet_header=Handshake { number: 0, version: 1 } packet_len=1081 id=0 1.770883942s s2n_quic:server:conn: datagram_sent: len=1232 gso_offset=0 id=0 1.770937352s s2n_quic:server:conn: recovery_metrics: path=Path { local_addr: 193.167.100.100:443, local_cid: 0x50c8c625131aa627e524166e027b33c5, remote_addr: [::ffff:193.167.0.100]:55358, remote_cid: 0xdf5b19fd624f0caa, 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.771031839s s2n_quic:server: platform_event_loop_sleep: timeout=Some(998.5ms) processing_duration=2.203ms 2.763074122s s2n_quic:server: platform_event_loop_wakeup: timeout_expired=false rx_ready=true tx_ready=false application_wakeup=false 2.763173187s s2n_quic:server: platform_tx: count=1 syscalls=1 blocked_syscalls=0 total_errors=0 dropped_errors=0 2.763192994s s2n_quic:server: platform_rx: count=1 syscalls=2 blocked_syscalls=1 total_errors=0 dropped_errors=0 2.763249931s s2n_quic:server: version_information: server_versions=[1] client_versions=[] chosen_version=Some(1) 2.763316505s s2n_quic:server:conn: datagram_received: len=1200 id=0 2.763386195s s2n_quic:server:conn: packet_received: packet_header=Initial { number: 1, version: 1 } id=0 2.763418315s s2n_quic:server:conn: frame_received: packet_header=Initial { number: 1, version: 1 } path=Path { local_addr: 193.167.100.100:443, local_cid: 0x50c8c625131aa627e524166e027b33c5, remote_addr: [::ffff:193.167.0.100]:55358, remote_cid: 0xdf5b19fd624f0caa, id: 0, is_active: true } frame=Crypto { offset: 0, len: 249 } id=0 2.763520898s s2n_quic:server:conn: packet_dropped: reason=DecodingFailed { path: Path { local_addr: 193.167.100.100:443, local_cid: 0x50c8c625131aa627e524166e027b33c5, remote_addr: [::ffff:193.167.0.100]:55358, remote_cid: 0xdf5b19fd624f0caa, id: 0, is_active: true } } id=0 2.763659637s s2n_quic:server:conn: frame_sent: packet_header=Initial { number: 1, version: 1 } path_id=0 frame=Ack { ecn_counts: None, largest_acknowledged: 1, ack_range_count: 1 } id=0 2.763691076s s2n_quic:server:conn: ack_range_sent: packet_header=Initial { number: 1, version: 1 } path_id=0 ack_range=0..=1 id=0 2.763723186s s2n_quic:server:conn: frame_sent: packet_header=Initial { number: 1, version: 1 } path_id=0 frame=Padding id=0 2.763792916s s2n_quic:server:conn: packet_sent: packet_header=Initial { number: 1, version: 1 } packet_len=1232 id=0 2.763847709s s2n_quic:server:conn: datagram_sent: len=1232 gso_offset=0 id=0 2.763900979s s2n_quic:server:conn: recovery_metrics: path=Path { local_addr: 193.167.100.100:443, local_cid: 0x50c8c625131aa627e524166e027b33c5, remote_addr: [::ffff:193.167.0.100]:55358, remote_cid: 0xdf5b19fd624f0caa, 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 2.763971140s s2n_quic:server: platform_event_loop_sleep: timeout=Some(5.56ms) processing_duration=911µs 2.770249617s s2n_quic:server: platform_event_loop_wakeup: timeout_expired=true rx_ready=false tx_ready=false application_wakeup=false 2.770297867s s2n_quic:server: platform_tx: count=1 syscalls=1 blocked_syscalls=0 total_errors=0 dropped_errors=0 2.770365314s s2n_quic:server:conn: recovery_metrics: path=Path { local_addr: 193.167.100.100:443, local_cid: 0x50c8c625131aa627e524166e027b33c5, remote_addr: [::ffff:193.167.0.100]:55358, remote_cid: 0xdf5b19fd624f0caa, id: 0, is_active: true } min_rtt=333ms smoothed_rtt=333ms latest_rtt=333ms rtt_variance=166.5ms max_ack_delay=25ms pto_count=1 congestion_window=12320 bytes_in_flight=1232 congestion_limited=false id=0 2.770408765s s2n_quic:server:conn: recovery_metrics: path=Path { local_addr: 193.167.100.100:443, local_cid: 0x50c8c625131aa627e524166e027b33c5, remote_addr: [::ffff:193.167.0.100]:55358, remote_cid: 0xdf5b19fd624f0caa, id: 0, is_active: true } min_rtt=333ms smoothed_rtt=333ms latest_rtt=333ms rtt_variance=166.5ms max_ack_delay=25ms pto_count=1 congestion_window=12320 bytes_in_flight=1232 congestion_limited=false id=0 2.770456675s s2n_quic:server:conn: recovery_metrics: path=Path { local_addr: 193.167.100.100:443, local_cid: 0x50c8c625131aa627e524166e027b33c5, remote_addr: [::ffff:193.167.0.100]:55358, remote_cid: 0xdf5b19fd624f0caa, id: 0, is_active: true } min_rtt=333ms smoothed_rtt=333ms latest_rtt=333ms rtt_variance=166.5ms max_ack_delay=25ms pto_count=1 congestion_window=12320 bytes_in_flight=1232 congestion_limited=false id=0 2.770583191s s2n_quic:server:conn: packet_skipped: number=2 space=Initial reason=PtoProbe id=0 2.770634608s s2n_quic:server:conn: frame_sent: packet_header=Initial { number: 3, version: 1 } path_id=0 frame=Ack { ecn_counts: None, largest_acknowledged: 1, ack_range_count: 1 } id=0 2.770656759s s2n_quic:server:conn: ack_range_sent: packet_header=Initial { number: 3, version: 1 } path_id=0 ack_range=0..=1 id=0 2.770687687s s2n_quic:server:conn: frame_sent: packet_header=Initial { number: 3, version: 1 } path_id=0 frame=Crypto { offset: 0, len: 90 } id=0 2.770734074s s2n_quic:server:conn: packet_sent: packet_header=Initial { number: 3, version: 1 } packet_len=151 id=0 2.770790409s s2n_quic:server:conn: packet_skipped: number=1 space=Handshake reason=PtoProbe id=0 2.770827258s s2n_quic:server:conn: frame_sent: packet_header=Handshake { number: 2, version: 1 } path_id=0 frame=Crypto { offset: 0, len: 685 } id=0 2.770853597s s2n_quic:server:conn: frame_sent: packet_header=Handshake { number: 2, version: 1 } path_id=0 frame=Padding id=0 2.770886368s s2n_quic:server:conn: packet_sent: packet_header=Handshake { number: 2, version: 1 } packet_len=1049 id=0 2.770918248s s2n_quic:server:conn: datagram_sent: len=1200 gso_offset=0 id=0 2.770954736s s2n_quic:server:conn: packet_skipped: number=4 space=Initial reason=PtoProbe id=0 2.770988730s s2n_quic:server:conn: frame_sent: packet_header=Initial { number: 5, version: 1 } path_id=0 frame=Ack { ecn_counts: None, largest_acknowledged: 1, ack_range_count: 1 } id=0 2.771007786s s2n_quic:server:conn: ack_range_sent: packet_header=Initial { number: 5, version: 1 } path_id=0 ack_range=0..=1 id=0 2.771030839s s2n_quic:server:conn: frame_sent: packet_header=Initial { number: 5, version: 1 } path_id=0 frame=Crypto { offset: 0, len: 90 } id=0 2.771067948s s2n_quic:server:conn: packet_sent: packet_header=Initial { number: 5, version: 1 } packet_len=151 id=0 2.771087365s s2n_quic:server:conn: packet_skipped: number=3 space=Handshake reason=PtoProbe id=0 2.771109937s s2n_quic:server:conn: frame_sent: packet_header=Handshake { number: 4, version: 1 } path_id=0 frame=Crypto { offset: 0, len: 685 } id=0 2.771129664s s2n_quic:server:conn: frame_sent: packet_header=Handshake { number: 4, version: 1 } path_id=0 frame=Padding id=0 2.771153659s s2n_quic:server:conn: packet_sent: packet_header=Handshake { number: 4, version: 1 } packet_len=1049 id=0 2.771178275s s2n_quic:server:conn: datagram_sent: len=1200 gso_offset=0 id=0 2.771220173s s2n_quic:server:conn: recovery_metrics: path=Path { local_addr: 193.167.100.100:443, local_cid: 0x50c8c625131aa627e524166e027b33c5, remote_addr: [::ffff:193.167.0.100]:55358, remote_cid: 0xdf5b19fd624f0caa, id: 0, is_active: true } min_rtt=333ms smoothed_rtt=333ms latest_rtt=333ms rtt_variance=166.5ms max_ack_delay=25ms pto_count=1 congestion_window=12320 bytes_in_flight=3632 congestion_limited=false id=0 2.771287389s s2n_quic:server: platform_event_loop_sleep: timeout=Some(1.997275s) processing_duration=1.04ms 3.274868693s s2n_quic:server: platform_event_loop_wakeup: timeout_expired=false rx_ready=true tx_ready=false application_wakeup=false 3.274975933s s2n_quic:server: platform_tx: count=2 syscalls=1 blocked_syscalls=0 total_errors=0 dropped_errors=0 3.274996292s s2n_quic:server: platform_rx: count=2 syscalls=2 blocked_syscalls=1 total_errors=0 dropped_errors=0 3.275053769s s2n_quic:server: version_information: server_versions=[1] client_versions=[] chosen_version=Some(1) 3.275102410s s2n_quic:server:conn: datagram_received: len=1200 id=0 3.275155469s s2n_quic:server:conn: packet_received: packet_header=Initial { number: 2, version: 1 } id=0 3.275184664s s2n_quic:server:conn: frame_received: packet_header=Initial { number: 2, version: 1 } path=Path { local_addr: 193.167.100.100:443, local_cid: 0x50c8c625131aa627e524166e027b33c5, remote_addr: [::ffff:193.167.0.100]:55358, remote_cid: 0xdf5b19fd624f0caa, id: 0, is_active: true } frame=Ack { ecn_counts: None, largest_acknowledged: 0, ack_range_count: 1 } id=0 3.275240028s s2n_quic:server:conn: ack_range_received: packet_header=Initial { number: 2, version: 1 } path=Path { local_addr: 193.167.100.100:443, local_cid: 0x50c8c625131aa627e524166e027b33c5, remote_addr: [::ffff:193.167.0.100]:55358, remote_cid: 0xdf5b19fd624f0caa, id: 0, is_active: true } ack_range=0..=0 id=0 3.275307183s s2n_quic:server:conn: pacing_rate_updated: path_id=0 bytes_per_second=22456 burst_size=12320 pacing_gain=2.77 id=0 3.275351967s s2n_quic:server:conn: ecn_state_changed: path=Path { local_addr: 193.167.100.100:443, local_cid: 0x50c8c625131aa627e524166e027b33c5, remote_addr: [::ffff:193.167.0.100]:55358, remote_cid: 0xdf5b19fd624f0caa, id: 0, is_active: true } state=Failed id=0 3.275377244s s2n_quic:server:conn: delivery_rate_sampled: path_id=0 rate_sample=RateSample { interval: 1.504503s, delivered_bytes: 151, lost_bytes: 0, ecn_ce_count: 0, is_app_limited: true, prior_delivered_bytes: 0, bytes_in_flight: 151, prior_lost_bytes: 0, prior_ecn_ce_count: 0, delivery_rate_bytes_per_second: 100 } id=0 3.275407351s s2n_quic:server:conn: recovery_metrics: path=Path { local_addr: 193.167.100.100:443, local_cid: 0x50c8c625131aa627e524166e027b33c5, remote_addr: [::ffff:193.167.0.100]:55358, remote_cid: 0xdf5b19fd624f0caa, id: 0, is_active: true } min_rtt=1.504503s smoothed_rtt=1.504503s latest_rtt=1.504503s rtt_variance=752.2515ms max_ack_delay=25ms pto_count=0 congestion_window=12471 bytes_in_flight=3481 congestion_limited=false id=0 3.275492350s s2n_quic:server:conn: packet_received: packet_header=Handshake { number: 0, version: 1 } id=0 3.275523598s s2n_quic:server:conn: frame_received: packet_header=Handshake { number: 0, version: 1 } path=Path { local_addr: 193.167.100.100:443, local_cid: 0x50c8c625131aa627e524166e027b33c5, remote_addr: [::ffff:193.167.0.100]:55358, remote_cid: 0xdf5b19fd624f0caa, id: 0, is_active: true } frame=Ack { ecn_counts: None, largest_acknowledged: 0, ack_range_count: 1 } id=0 3.275550348s s2n_quic:server:conn: ack_range_received: packet_header=Handshake { number: 0, version: 1 } path=Path { local_addr: 193.167.100.100:443, local_cid: 0x50c8c625131aa627e524166e027b33c5, remote_addr: [::ffff:193.167.0.100]:55358, remote_cid: 0xdf5b19fd624f0caa, id: 0, is_active: true } ack_range=0..=0 id=0 3.275594281s s2n_quic:server:conn: delivery_rate_sampled: path_id=0 rate_sample=RateSample { interval: 1.504503s, delivered_bytes: 1232, lost_bytes: 0, ecn_ce_count: 0, is_app_limited: true, prior_delivered_bytes: 0, bytes_in_flight: 1232, prior_lost_bytes: 0, prior_ecn_ce_count: 0, delivery_rate_bytes_per_second: 818 } id=0 3.275621762s s2n_quic:server:conn: recovery_metrics: path=Path { local_addr: 193.167.100.100:443, local_cid: 0x50c8c625131aa627e524166e027b33c5, remote_addr: [::ffff:193.167.0.100]:55358, remote_cid: 0xdf5b19fd624f0caa, id: 0, is_active: true } min_rtt=1.504503s smoothed_rtt=1.504503s latest_rtt=1.504503s rtt_variance=752.2515ms max_ack_delay=25ms pto_count=0 congestion_window=13552 bytes_in_flight=2400 congestion_limited=false id=0 3.275655114s s2n_quic:server:conn: frame_received: packet_header=Handshake { number: 0, version: 1 } path=Path { local_addr: 193.167.100.100:443, local_cid: 0x50c8c625131aa627e524166e027b33c5, remote_addr: [::ffff:193.167.0.100]:55358, remote_cid: 0xdf5b19fd624f0caa, id: 0, is_active: true } frame=Crypto { offset: 0, len: 36 } id=0 3.275715828s s2n_quic:server:conn: key_space_discarded: space=Initial id=0 3.275737879s s2n_quic:server:conn: recovery_metrics: path=Path { local_addr: 193.167.100.100:443, local_cid: 0x50c8c625131aa627e524166e027b33c5, remote_addr: [::ffff:193.167.0.100]:55358, remote_cid: 0xdf5b19fd624f0caa, id: 0, is_active: true } min_rtt=1.504503s smoothed_rtt=1.504503s latest_rtt=1.504503s rtt_variance=752.2515ms max_ack_delay=25ms pto_count=0 congestion_window=13552 bytes_in_flight=2400 congestion_limited=false id=0 3.275879364s s2n_quic:server:conn: handshake_status_updated: status=Complete id=0 3.275902237s s2n_quic:server:conn: handshake_status_updated: status=Confirmed id=0 3.275926131s s2n_quic:server:conn: tls_exporter_ready: session=TlsSession { .. } id=0 3.275969402s s2n_quic:server:conn: key_space_discarded: space=Handshake id=0 3.275985032s s2n_quic:server:conn: recovery_metrics: path=Path { local_addr: 193.167.100.100:443, local_cid: 0x50c8c625131aa627e524166e027b33c5, remote_addr: [::ffff:193.167.0.100]:55358, remote_cid: 0xdf5b19fd624f0caa, id: 0, is_active: true } min_rtt=1.504503s smoothed_rtt=1.504503s latest_rtt=1.504503s rtt_variance=752.2515ms max_ack_delay=25ms pto_count=0 congestion_window=13552 bytes_in_flight=2098 congestion_limited=false id=0 3.276058148s s2n_quic:server:conn: packet_received: packet_header=OneRtt { number: 0 } id=0 3.276086381s s2n_quic:server:conn: frame_received: packet_header=OneRtt { number: 0 } path=Path { local_addr: 193.167.100.100:443, local_cid: 0x50c8c625131aa627e524166e027b33c5, remote_addr: [::ffff:193.167.0.100]:55358, remote_cid: 0xdf5b19fd624f0caa, id: 0, is_active: true } frame=NewConnectionId { sequence_number: 1, retire_prior_to: 0 } id=0 3.276138018s s2n_quic:server:conn: connection_id_updated: path_id=0 cid_consumer=Local previous=0xdf5b19fd624f0caa current=0x7bb046e3f1605eee id=0 3.276162143s s2n_quic:server:conn: frame_received: packet_header=OneRtt { number: 0 } path=Path { local_addr: 193.167.100.100:443, local_cid: 0x50c8c625131aa627e524166e027b33c5, remote_addr: [::ffff:193.167.0.100]:55358, remote_cid: 0x7bb046e3f1605eee, id: 0, is_active: true } frame=NewConnectionId { sequence_number: 2, retire_prior_to: 0 } id=0 3.276207578s s2n_quic:server:conn: frame_received: packet_header=OneRtt { number: 0 } path=Path { local_addr: 193.167.100.100:443, local_cid: 0x50c8c625131aa627e524166e027b33c5, remote_addr: [::ffff:193.167.0.100]:55358, remote_cid: 0x7bb046e3f1605eee, id: 0, is_active: true } frame=Padding id=0 3.276313716s s2n_quic:server:conn: datagram_received: len=54 id=0 3.276351397s s2n_quic:server:conn: packet_received: packet_header=OneRtt { number: 1 } id=0 3.276371675s s2n_quic:server:conn: frame_received: packet_header=OneRtt { number: 1 } path=Path { local_addr: 193.167.100.100:443, local_cid: 0x50c8c625131aa627e524166e027b33c5, remote_addr: [::ffff:193.167.0.100]:55358, remote_cid: 0x7bb046e3f1605eee, id: 0, is_active: true } frame=Stream { id: 0, offset: 0, len: 17, is_fin: true } id=0 3.276434793s s2n_quic:server:conn: rx_stream_progress: bytes=17 id=0 3.276541763s s2n_quic:server:conn: frame_sent: packet_header=OneRtt { number: 0 } path_id=0 frame=Ping id=0 3.276576017s s2n_quic:server:conn: frame_sent: packet_header=OneRtt { number: 0 } path_id=0 frame=Padding id=0 3.276613437s s2n_quic:server:conn: packet_sent: packet_header=OneRtt { number: 0 } packet_len=1452 id=0 3.276635068s s2n_quic:server:conn: datagram_sent: len=1452 gso_offset=0 id=0 3.276674882s s2n_quic:server:conn: frame_sent: packet_header=OneRtt { number: 1 } path_id=0 frame=Ack { ecn_counts: None, largest_acknowledged: 1, ack_range_count: 1 } id=0 3.276693116s s2n_quic:server:conn: ack_range_sent: packet_header=OneRtt { number: 1 } path_id=0 ack_range=0..=1 id=0 3.276707473s s2n_quic:server:conn: frame_sent: packet_header=OneRtt { number: 1 } path_id=0 frame=HandshakeDone id=0 3.276733722s 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 3.276766764s 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 3.276790479s s2n_quic:server:conn: frame_sent: packet_header=OneRtt { number: 1 } path_id=0 frame=RetireConnectionId id=0 3.276837156s s2n_quic:server:conn: packet_sent: packet_header=OneRtt { number: 1 } packet_len=107 id=0 3.276855851s s2n_quic:server:conn: datagram_sent: len=107 gso_offset=0 id=0 3.276891618s s2n_quic:server:conn: recovery_metrics: path=Path { local_addr: 193.167.100.100:443, local_cid: 0x50c8c625131aa627e524166e027b33c5, remote_addr: [::ffff:193.167.0.100]:55358, remote_cid: 0x7bb046e3f1605eee, id: 0, is_active: true } min_rtt=1.504503s smoothed_rtt=1.504503s latest_rtt=1.504503s rtt_variance=752.2515ms max_ack_delay=25ms pto_count=0 congestion_window=13552 bytes_in_flight=1559 congestion_limited=false id=0 3.276951710s s2n_quic:server: platform_event_loop_sleep: timeout=Some(4.538067s) processing_duration=2.098ms Accepted a QUIC connection from [::ffff:193.167.0.100]:55358 on 193.167.100.100:443 3.277862314s s2n_quic_qns::server::h09: 1024 bytes ready to send on Stream(0) 3.277916916s s2n_quic_qns::server::h09: 1024 bytes sent on Stream(0) 3.277984813s s2n_quic:server: platform_event_loop_wakeup: timeout_expired=false rx_ready=false tx_ready=false application_wakeup=true 3.278014769s s2n_quic:server: platform_tx: count=2 syscalls=1 blocked_syscalls=0 total_errors=0 dropped_errors=0 3.278052269s s2n_quic:server:conn: frame_sent: packet_header=OneRtt { number: 2 } path_id=0 frame=HandshakeDone id=0 3.278098406s s2n_quic:server:conn: frame_sent: packet_header=OneRtt { number: 2 } path_id=0 frame=Stream { id: 0, offset: 0, len: 1024, is_fin: false } id=0 3.278150683s s2n_quic:server:conn: packet_sent: packet_header=OneRtt { number: 2 } packet_len=1055 id=0 3.278168437s s2n_quic:server:conn: datagram_sent: len=1055 gso_offset=0 id=0 3.278202380s s2n_quic:server:conn: recovery_metrics: path=Path { local_addr: 193.167.100.100:443, local_cid: 0x50c8c625131aa627e524166e027b33c5, remote_addr: [::ffff:193.167.0.100]:55358, remote_cid: 0x7bb046e3f1605eee, id: 0, is_active: true } min_rtt=1.504503s smoothed_rtt=1.504503s latest_rtt=1.504503s rtt_variance=752.2515ms max_ack_delay=25ms pto_count=0 congestion_window=13552 bytes_in_flight=2614 congestion_limited=false id=0 3.278238838s s2n_quic:server:conn: tx_stream_progress: bytes=1024 id=0 3.278275898s s2n_quic:server: platform_event_loop_sleep: timeout=Some(4.538265s) processing_duration=292µs 3.278457788s s2n_quic:server: platform_event_loop_wakeup: timeout_expired=false rx_ready=false tx_ready=false application_wakeup=true 3.278488535s s2n_quic:server: platform_tx: count=1 syscalls=1 blocked_syscalls=0 total_errors=0 dropped_errors=0 3.278525554s s2n_quic:server:conn: frame_sent: packet_header=OneRtt { number: 3 } path_id=0 frame=HandshakeDone id=0 3.278559648s s2n_quic:server:conn: frame_sent: packet_header=OneRtt { number: 3 } path_id=0 frame=Stream { id: 0, offset: 1024, len: 0, is_fin: true } id=0 3.278586128s s2n_quic:server:conn: frame_sent: packet_header=OneRtt { number: 3 } path_id=0 frame=Padding id=0 3.278618779s s2n_quic:server:conn: packet_sent: packet_header=OneRtt { number: 3 } packet_len=45 id=0 3.278634278s s2n_quic:server:conn: datagram_sent: len=45 gso_offset=0 id=0 3.278668031s s2n_quic:server:conn: recovery_metrics: path=Path { local_addr: 193.167.100.100:443, local_cid: 0x50c8c625131aa627e524166e027b33c5, remote_addr: [::ffff:193.167.0.100]:55358, remote_cid: 0x7bb046e3f1605eee, id: 0, is_active: true } min_rtt=1.504503s smoothed_rtt=1.504503s latest_rtt=1.504503s rtt_variance=752.2515ms max_ack_delay=25ms pto_count=0 congestion_window=13552 bytes_in_flight=2659 congestion_limited=false id=0 3.278724026s s2n_quic:server: platform_event_loop_sleep: timeout=Some(4.53829s) processing_duration=267µs 4.273411991s s2n_quic:server: platform_event_loop_wakeup: timeout_expired=false rx_ready=true tx_ready=false application_wakeup=false 4.273512469s s2n_quic:server: platform_tx: count=1 syscalls=1 blocked_syscalls=0 total_errors=0 dropped_errors=0 4.273532146s s2n_quic:server: platform_rx: count=1 syscalls=2 blocked_syscalls=1 total_errors=0 dropped_errors=0 4.273635419s s2n_quic:server:conn: datagram_dropped: local_addr=193.167.100.100:443 remote_addr=[::ffff:193.167.0.100]:55358 destination_cid=0x50c8c625131aa627e524166e027b33c5 source_cid=Some(0xdf5b19fd624f0caa) len=57 reason=InvalidSourceConnectionId id=0 4.273801780s s2n_quic:server: platform_event_loop_sleep: timeout=Some(3.543216s) processing_duration=402µs 4.273903891s s2n_quic:server: platform_event_loop_wakeup: timeout_expired=false rx_ready=true tx_ready=false application_wakeup=false 4.273945459s s2n_quic:server: platform_rx: count=1 syscalls=2 blocked_syscalls=1 total_errors=0 dropped_errors=0 4.273982588s s2n_quic:server:conn: datagram_dropped: local_addr=193.167.100.100:443 remote_addr=[::ffff:193.167.0.100]:55358 destination_cid=0x50c8c625131aa627e524166e027b33c5 source_cid=Some(0xdf5b19fd624f0caa) len=59 reason=InvalidSourceConnectionId id=0 4.274033794s s2n_quic:server: platform_event_loop_sleep: timeout=Some(3.54298s) processing_duration=131µs 4.779442215s s2n_quic:server: platform_event_loop_wakeup: timeout_expired=false rx_ready=true tx_ready=false application_wakeup=false 4.779541601s s2n_quic:server: platform_rx: count=1 syscalls=2 blocked_syscalls=1 total_errors=0 dropped_errors=0 4.779626069s s2n_quic:server:conn: datagram_received: len=69 id=0 4.779695499s s2n_quic:server:conn: packet_received: packet_header=OneRtt { number: 2 } id=0 4.779718663s s2n_quic:server:conn: connection_id_updated: path_id=0 cid_consumer=Remote previous=0x50c8c625131aa627e524166e027b33c5 current=0xbcd6b8d1db27cc9f37a017370e1f5068 id=0 4.779797490s s2n_quic:server:conn: frame_received: packet_header=OneRtt { number: 2 } path=Path { local_addr: 193.167.100.100:443, local_cid: 0xbcd6b8d1db27cc9f37a017370e1f5068, remote_addr: [::ffff:193.167.0.100]:55358, remote_cid: 0x7bb046e3f1605eee, id: 0, is_active: true } frame=Ack { ecn_counts: None, largest_acknowledged: 1, ack_range_count: 1 } id=0 4.779855399s s2n_quic:server:conn: ack_range_received: packet_header=OneRtt { number: 2 } path=Path { local_addr: 193.167.100.100:443, local_cid: 0xbcd6b8d1db27cc9f37a017370e1f5068, remote_addr: [::ffff:193.167.0.100]:55358, remote_cid: 0x7bb046e3f1605eee, id: 0, is_active: true } ack_range=0..=1 id=0 4.779898680s s2n_quic:server:conn: mtu_updated: path_id=0 mtu=1452 cause=ProbeAcknowledged search_complete=false id=0 4.779916914s s2n_quic:server:conn: handshake_status_updated: status=HandshakeDoneAcked id=0 4.780003235s s2n_quic:server:conn: delivery_rate_sampled: path_id=0 rate_sample=RateSample { interval: 1.503073s, delivered_bytes: 1559, lost_bytes: 0, ecn_ce_count: 0, is_app_limited: true, prior_delivered_bytes: 1232, bytes_in_flight: 1559, prior_lost_bytes: 0, prior_ecn_ce_count: 0, delivery_rate_bytes_per_second: 1037 } id=0 4.780069168s s2n_quic:server:conn: recovery_metrics: path=Path { local_addr: 193.167.100.100:443, local_cid: 0xbcd6b8d1db27cc9f37a017370e1f5068, remote_addr: [::ffff:193.167.0.100]:55358, remote_cid: 0x7bb046e3f1605eee, id: 0, is_active: true } min_rtt=1.503073s smoothed_rtt=1.50432425s latest_rtt=1.503073s rtt_variance=564.546125ms max_ack_delay=25ms pto_count=0 congestion_window=17531 bytes_in_flight=1100 congestion_limited=false id=0 4.780103252s s2n_quic:server:conn: frame_received: packet_header=OneRtt { number: 2 } path=Path { local_addr: 193.167.100.100:443, local_cid: 0xbcd6b8d1db27cc9f37a017370e1f5068, remote_addr: [::ffff:193.167.0.100]:55358, remote_cid: 0x7bb046e3f1605eee, id: 0, is_active: true } frame=NewConnectionId { sequence_number: 3, retire_prior_to: 1 } id=0 4.780139420s s2n_quic:server:conn: frame_received: packet_header=OneRtt { number: 2 } path=Path { local_addr: 193.167.100.100:443, local_cid: 0xbcd6b8d1db27cc9f37a017370e1f5068, remote_addr: [::ffff:193.167.0.100]:55358, remote_cid: 0x7bb046e3f1605eee, id: 0, is_active: true } frame=RetireConnectionId id=0 4.780252421s s2n_quic:server:conn: frame_sent: packet_header=OneRtt { number: 4 } path_id=0 frame=Ping id=0 4.780309318s s2n_quic:server:conn: frame_sent: packet_header=OneRtt { number: 4 } path_id=0 frame=Padding id=0 4.780347409s s2n_quic:server:conn: packet_sent: packet_header=OneRtt { number: 4 } packet_len=5202 id=0 4.780363960s s2n_quic:server:conn: datagram_sent: len=5202 gso_offset=0 id=0 4.780403073s s2n_quic:server:conn: recovery_metrics: path=Path { local_addr: 193.167.100.100:443, local_cid: 0xbcd6b8d1db27cc9f37a017370e1f5068, remote_addr: [::ffff:193.167.0.100]:55358, remote_cid: 0x7bb046e3f1605eee, id: 0, is_active: true } min_rtt=1.503073s smoothed_rtt=1.50432425s latest_rtt=1.503073s rtt_variance=564.546125ms max_ack_delay=25ms pto_count=0 congestion_window=17531 bytes_in_flight=6302 congestion_limited=false id=0 4.780470319s s2n_quic:server: platform_event_loop_sleep: timeout=Some(24.115ms) processing_duration=1.042ms 4.780575416s s2n_quic:server: platform_event_loop_wakeup: timeout_expired=false rx_ready=true tx_ready=false application_wakeup=false 4.780612124s s2n_quic:server: platform_tx_error: errno=90 4.780624147s s2n_quic:server: platform_tx: count=0 syscalls=1 blocked_syscalls=0 total_errors=1 dropped_errors=0 4.780636119s s2n_quic:server: platform_rx: count=2 syscalls=2 blocked_syscalls=1 total_errors=0 dropped_errors=0 4.780669161s s2n_quic:server:conn: datagram_received: len=39 id=0 4.780703946s s2n_quic:server:conn: packet_received: packet_header=OneRtt { number: 3 } id=0 4.780726068s s2n_quic:server:conn: frame_received: packet_header=OneRtt { number: 3 } path=Path { local_addr: 193.167.100.100:443, local_cid: 0xbcd6b8d1db27cc9f37a017370e1f5068, remote_addr: [::ffff:193.167.0.100]:55358, remote_cid: 0x7bb046e3f1605eee, id: 0, is_active: true } frame=Ack { ecn_counts: None, largest_acknowledged: 3, ack_range_count: 1 } id=0 4.780801038s s2n_quic:server:conn: ack_range_received: packet_header=OneRtt { number: 3 } path=Path { local_addr: 193.167.100.100:443, local_cid: 0xbcd6b8d1db27cc9f37a017370e1f5068, remote_addr: [::ffff:193.167.0.100]:55358, remote_cid: 0x7bb046e3f1605eee, id: 0, is_active: true } ack_range=0..=3 id=0 4.780882771s s2n_quic:server:conn: delivery_rate_sampled: path_id=0 rate_sample=RateSample { interval: 1.503073s, delivered_bytes: 2659, lost_bytes: 0, ecn_ce_count: 0, is_app_limited: true, prior_delivered_bytes: 1232, bytes_in_flight: 1559, prior_lost_bytes: 0, prior_ecn_ce_count: 0, delivery_rate_bytes_per_second: 1769 } id=0 4.780928867s s2n_quic:server:conn: recovery_metrics: path=Path { local_addr: 193.167.100.100:443, local_cid: 0xbcd6b8d1db27cc9f37a017370e1f5068, remote_addr: [::ffff:193.167.0.100]:55358, remote_cid: 0x7bb046e3f1605eee, id: 0, is_active: true } min_rtt=1.50215s smoothed_rtt=1.504052467s latest_rtt=1.50215s rtt_variance=423.953155ms max_ack_delay=25ms pto_count=0 congestion_window=18631 bytes_in_flight=5202 congestion_limited=false id=0 4.781009137s s2n_quic:server:conn: datagram_received: len=38 id=0 4.781049322s s2n_quic:server:conn: packet_received: packet_header=OneRtt { number: 4 } id=0 4.781070131s s2n_quic:server:conn: frame_received: packet_header=OneRtt { number: 4 } path=Path { local_addr: 193.167.100.100:443, local_cid: 0xbcd6b8d1db27cc9f37a017370e1f5068, remote_addr: [::ffff:193.167.0.100]:55358, remote_cid: 0x7bb046e3f1605eee, id: 0, is_active: true } frame=ConnectionClose id=0 4.781097072s s2n_quic:server:conn: connection_close_frame_received: packet_header=OneRtt { number: 4 } path=Path { local_addr: 193.167.100.100:443, local_cid: 0xbcd6b8d1db27cc9f37a017370e1f5068, remote_addr: [::ffff:193.167.0.100]:55358, remote_cid: 0x7bb046e3f1605eee, id: 0, is_active: true } frame=ConnectionCloseFrame { error_code: 0, frame_type: Some(0), reason: None } id=0 4.781122870s s2n_quic:server:conn: connection_closed: error=Closed { initiator: Remote, source: Location { file: "/app/quic/s2n-quic-transport/src/space/mod.rs", line: 966, col: 32 } } id=0 4.781170459s s2n_quic:server: platform_event_loop_sleep: timeout=None processing_duration=597µs 4.781215884s s2n_quic_qns::server::h09: Final stats: MyConnectionContext { packet_sent: 12, stream_requests: 1 } 4.804579228s s2n_quic:server: platform_event_loop_wakeup: timeout_expired=true rx_ready=false tx_ready=false application_wakeup=false 4.804702479s s2n_quic:server: platform_event_loop_sleep: timeout=None processing_duration=136µs