Skip to content

Instantly share code, notes, and snippets.

@thinkhy

thinkhy/gist:19abb0c5d0de887992d086f030667198

Created November 27, 2019 09:30
Show Gist options
  • Save thinkhy/19abb0c5d0de887992d086f030667198 to your computer and use it in GitHub Desktop.
Save thinkhy/19abb0c5d0de887992d086f030667198 to your computer and use it in GitHub Desktop.
Download ZIP
quiche runlog
Raw
gistfile1.txt
[2019-11-27T09:27:07.046872000Z TRACE mio::sys::unix::kqueue] registering; token=Token(18446744073709551615); interests=Readable
[2019-11-27T09:27:07.047121000Z TRACE mio::poll] registering with poller
[2019-11-27T09:27:07.047146000Z TRACE mio::sys::unix::kqueue] registering; token=Token(0); interests=Readable
[2019-11-27T09:27:07.075663000Z INFO http3_client] connecting to 127.0.0.1:4433 from 127.0.0.1:57689with scid 11f3609530bd0ba68ad74e4882855455005e13dd
[2019-11-27T09:27:07.075947000Z TRACE quiche::tls] 11f3609530bd0ba68ad74e4882855455005e13dd tls writemessage lvl=Initial len=512
[2019-11-27T09:27:07.076042000Z TRACE quiche] 11f3609530bd0ba68ad74e4882855455005e13dd tx pkt Initialversion=babababa dcid=688a56ec5134560e9813fb2f61e32c53 scid=11f3609530bd0ba68ad74e4882855455005e13dd len=1153 pn=0
[2019-11-27T09:27:07.076101000Z TRACE quiche] 11f3609530bd0ba68ad74e4882855455005e13dd tx frm CRYPTO off=0 len=512
[2019-11-27T09:27:07.076132000Z TRACE quiche] 11f3609530bd0ba68ad74e4882855455005e13dd tx frm PADDINGlen=621
[2019-11-27T09:27:07.076353000Z TRACE quiche::recovery] 11f3609530bd0ba68ad74e4882855455005e13dd timer=999.347508ms inflight=1200 cwnd=14520 latest_rtt=0ns srtt=None min_rtt=0ns rttvar=0ns probes=0
[2019-11-27T09:27:07.076506000Z DEBUG http3_client] written 1200
[2019-11-27T09:27:07.076674000Z DEBUG http3_client] got 51 bytes
[2019-11-27T09:27:07.076707000Z TRACE quiche] 11f3609530bd0ba68ad74e4882855455005e13dd rx pkt VersionNegotiation version=0 dcid=11f3609530bd0ba68ad74e4882855455005e13dd scid=688a56ec5134560e9813fb2f61e32c53 versions=[ff000018, ff000017]
[2019-11-27T09:27:07.076826000Z DEBUG http3_client] done reading
[2019-11-27T09:27:07.077015000Z TRACE quiche::tls] 11f3609530bd0ba68ad74e4882855455005e13dd tls writemessage lvl=Initial len=512
[2019-11-27T09:27:07.077054000Z TRACE quiche] 11f3609530bd0ba68ad74e4882855455005e13dd tx pkt Initialversion=ff000018 dcid=688a56ec5134560e9813fb2f61e32c53 scid=11f3609530bd0ba68ad74e4882855455005e13dd len=1153 pn=1
[2019-11-27T09:27:07.077089000Z TRACE quiche] 11f3609530bd0ba68ad74e4882855455005e13dd tx frm CRYPTO off=0 len=512
[2019-11-27T09:27:07.077104000Z TRACE quiche] 11f3609530bd0ba68ad74e4882855455005e13dd tx frm PADDINGlen=621
[2019-11-27T09:27:07.077199000Z TRACE quiche::recovery] 11f3609530bd0ba68ad74e4882855455005e13dd timer=999.633417ms inflight=1200 cwnd=14520 latest_rtt=0ns srtt=None min_rtt=0ns rttvar=0ns probes=0
[2019-11-27T09:27:07.082146000Z DEBUG http3_client] written 1200
[2019-11-27T09:27:07.082194000Z DEBUG http3_client] done writing
[2019-11-27T09:27:07.082375000Z DEBUG http3_client] got 90 bytes
[2019-11-27T09:27:07.082413000Z TRACE quiche] 11f3609530bd0ba68ad74e4882855455005e13dd rx pkt Retry version=ff000018 dcid=11f3609530bd0ba68ad74e4882855455005e13dd scid=be84337f92d11c7aed92770fa0ebdae960087fa0 odcid=688a56ec5134560e9813fb2f61e32c53 token=7175696368657f000001688a56ec5134560e9813fb2f61e32c53
[2019-11-27T09:27:07.082626000Z DEBUG http3_client] done reading
[2019-11-27T09:27:07.083367000Z TRACE quiche::tls] 11f3609530bd0ba68ad74e4882855455005e13dd tls writemessage lvl=Initial len=512
[2019-11-27T09:27:07.084478000Z TRACE quiche] 11f3609530bd0ba68ad74e4882855455005e13dd tx pkt Initialversion=ff000018 dcid=be84337f92d11c7aed92770fa0ebdae960087fa0 scid=11f3609530bd0ba68ad74e4882855455005e13dd token=7175696368657f000001688a56ec5134560e9813fb2f61e32c53 len=1123 pn=2
[2019-11-27T09:27:07.084565000Z TRACE quiche] 11f3609530bd0ba68ad74e4882855455005e13dd tx frm CRYPTO off=0 len=512
[2019-11-27T09:27:07.084582000Z TRACE quiche] 11f3609530bd0ba68ad74e4882855455005e13dd tx frm PADDINGlen=591
[2019-11-27T09:27:07.084660000Z TRACE quiche::recovery] 11f3609530bd0ba68ad74e4882855455005e13dd timer=997.976054ms inflight=1200 cwnd=14520 latest_rtt=0ns srtt=None min_rtt=0ns rttvar=0ns probes=0
[2019-11-27T09:27:07.084726000Z DEBUG http3_client] written 1200
[2019-11-27T09:27:07.104092000Z DEBUG http3_client] done writing
[2019-11-27T09:27:07.104151000Z DEBUG http3_client] got 199 bytes
[2019-11-27T09:27:07.104190000Z TRACE quiche] 11f3609530bd0ba68ad74e4882855455005e13dd rx pkt Initialversion=ff000018 dcid=11f3609530bd0ba68ad74e4882855455005e13dd scid=be84337f92d11c7aed92770fa0ebdae960087fa0 token= len=149 pn=0
[2019-11-27T09:27:07.104293000Z TRACE quiche] 11f3609530bd0ba68ad74e4882855455005e13dd rx frm ACK delay=270 blocks=[2..2]
[2019-11-27T09:27:07.104334000Z TRACE quiche::recovery] 11f3609530bd0ba68ad74e4882855455005e13dd packet newly acked 2
[2019-11-27T09:27:07.104352000Z TRACE quiche::recovery] 11f3609530bd0ba68ad74e4882855455005e13dd timer=none inflight=0 cwnd=15720 latest_rtt=21.51898ms srtt=Some(21.51898ms) min_rtt=21.51898ms rttvar=10.75949ms probes=0
[2019-11-27T09:27:07.115710000Z TRACE quiche] 11f3609530bd0ba68ad74e4882855455005e13dd rx frm CRYPTO off=0 len=122
[2019-11-27T09:27:07.116321000Z TRACE quiche::tls] 11f3609530bd0ba68ad74e4882855455005e13dd tls set encryption secret lvl=Handshake
[2019-11-27T09:27:07.116454000Z DEBUG http3_client] processed 199 bytes
[2019-11-27T09:27:07.116483000Z DEBUG http3_client] got 1196 bytes
[2019-11-27T09:27:07.116506000Z TRACE quiche] 11f3609530bd0ba68ad74e4882855455005e13dd rx pkt Handshake version=ff000018 dcid=11f3609530bd0ba68ad74e4882855455005e13dd scid=be84337f92d11c7aed92770fa0ebdae960087fa0 len=1147 pn=0
[2019-11-27T09:27:07.122011000Z TRACE quiche] 11f3609530bd0ba68ad74e4882855455005e13dd rx frm CRYPTO off=0 len=1126
[2019-11-27T09:27:07.122251000Z DEBUG http3_client] processed 1196 bytes
[2019-11-27T09:27:07.122289000Z DEBUG http3_client] got 291 bytes
[2019-11-27T09:27:07.122320000Z TRACE quiche] 11f3609530bd0ba68ad74e4882855455005e13dd rx pkt Handshake version=ff000018 dcid=11f3609530bd0ba68ad74e4882855455005e13dd scid=be84337f92d11c7aed92770fa0ebdae960087fa0 len=242 pn=1
[2019-11-27T09:27:07.122388000Z TRACE quiche] 11f3609530bd0ba68ad74e4882855455005e13dd rx frm CRYPTO off=1126 len=220
[2019-11-27T09:27:07.122750000Z TRACE quiche::tls] 11f3609530bd0ba68ad74e4882855455005e13dd tls set encryption secret lvl=OneRTT
[2019-11-27T09:27:07.134905000Z TRACE quiche::tls] 11f3609530bd0ba68ad74e4882855455005e13dd tls writemessage lvl=Handshake len=36
[2019-11-27T09:27:07.135046000Z TRACE quiche] 11f3609530bd0ba68ad74e4882855455005e13dd connection established: proto=Ok("h3-24") cipher=Some(AES128_GCM) curve=Some("X25519") sigalg=Some("rsa_pss_rsae_sha256") resumed=false idle_timeout=5000 max_packet_size=1350 initial_max_data=10000000 initial_max_stream_data_bidi_local=1000000 initial_max_stream_data_bidi_remote=1000000 initial_max_stream_data_uni=1000000 initial_max_streams_bidi=100 initial_max_streams_uni=100 ack_delay_exponent=3 max_ack_delay=25 disable_active_migration=true
[2019-11-27T09:27:07.135136000Z DEBUG http3_client] processed 291 bytes
[2019-11-27T09:27:07.135165000Z DEBUG http3_client] recv() would block
[2019-11-27T09:27:07.139662000Z TRACE quiche::h3] 11f3609530bd0ba68ad74e4882855455005e13dd open GREASE stream 14
[2019-11-27T09:27:07.139695000Z INFO http3_client] sending HTTP request [Header(":method", "GET"), Header(":scheme", "https"), Header(":authority", "127.0.0.1"), Header(":path", "/"), Header("user-agent", "quiche")]
[2019-11-27T09:27:07.139763000Z TRACE quiche::h3] 11f3609530bd0ba68ad74e4882855455005e13dd tx frm GREASE stream=0
[2019-11-27T09:27:07.139798000Z TRACE quiche::h3] 11f3609530bd0ba68ad74e4882855455005e13dd tx frm HEADERS stream=0 len=22 fin=true
[2019-11-27T09:27:07.139848000Z TRACE quiche] 11f3609530bd0ba68ad74e4882855455005e13dd tx pkt Initialversion=ff000018 dcid=be84337f92d11c7aed92770fa0ebdae960087fa0 scid=11f3609530bd0ba68ad74e4882855455005e13dd token=7175696368657f000001688a56ec5134560e9813fb2f61e32c53 len=1199 pn=3
[2019-11-27T09:27:07.141325000Z TRACE quiche] 11f3609530bd0ba68ad74e4882855455005e13dd tx frm ACK delay=4458 blocks=[0..0]
[2019-11-27T09:27:07.141378000Z TRACE quiche] 11f3609530bd0ba68ad74e4882855455005e13dd tx frm PADDINGlen=1177
[2019-11-27T09:27:07.141529000Z TRACE quiche::recovery] 11f3609530bd0ba68ad74e4882855455005e13dd timer=none inflight=0 cwnd=15720 latest_rtt=21.51898ms srtt=Some(21.51898ms) min_rtt=21.51898ms rttvar=10.75949ms probes=0
[2019-11-27T09:27:07.141611000Z DEBUG http3_client] written 1276
[2019-11-27T09:27:07.141657000Z TRACE quiche] 11f3609530bd0ba68ad74e4882855455005e13dd tx pkt Handshake version=ff000018 dcid=be84337f92d11c7aed92770fa0ebdae960087fa0 scid=11f3609530bd0ba68ad74e4882855455005e13dd token=7175696368657f000001688a56ec5134560e9813fb2f61e32c53 len=61 pn=0
[2019-11-27T09:27:07.146245000Z TRACE quiche] 11f3609530bd0ba68ad74e4882855455005e13dd tx frm ACK delay=2416 blocks=[0..1]
[2019-11-27T09:27:07.146288000Z TRACE quiche] 11f3609530bd0ba68ad74e4882855455005e13dd tx frm CRYPTO off=0 len=36
[2019-11-27T09:27:07.146328000Z TRACE quiche::recovery] 11f3609530bd0ba68ad74e4882855455005e13dd timer=84.848973ms inflight=110 cwnd=15720 latest_rtt=21.51898ms srtt=Some(21.51898ms) min_rtt=21.51898ms rttvar=10.75949ms probes=0
[2019-11-27T09:27:07.146377000Z TRACE quiche] 11f3609530bd0ba68ad74e4882855455005e13dd dropped epoch 0 state
[2019-11-27T09:27:07.146416000Z DEBUG http3_client] written 110
[2019-11-27T09:27:07.147720000Z TRACE quiche] 11f3609530bd0ba68ad74e4882855455005e13dd tx pkt Short dcid=be84337f92d11c7aed92770fa0ebdae960087fa0 token=7175696368657f000001688a56ec5134560e9813fb2f61e32c53 key_phase=false len=39 pn=0
[2019-11-27T09:27:07.147797000Z TRACE quiche] 11f3609530bd0ba68ad74e4882855455005e13dd tx frm STREAM id=2 off=0 len=19 fin=false
[2019-11-27T09:27:07.147843000Z TRACE quiche::recovery] 11f3609530bd0ba68ad74e4882855455005e13dd timer=89.385706ms inflight=171 cwnd=15720 latest_rtt=21.51898ms srtt=Some(21.51898ms) min_rtt=21.51898ms rttvar=10.75949ms probes=0
[2019-11-27T09:27:07.147887000Z DEBUG http3_client] written 61
[2019-11-27T09:27:07.147922000Z TRACE quiche] 11f3609530bd0ba68ad74e4882855455005e13dd tx pkt Short dcid=be84337f92d11c7aed92770fa0ebdae960087fa0 token=7175696368657f000001688a56ec5134560e9813fb2f61e32c53 key_phase=false len=21 pn=1
[2019-11-27T09:27:07.151355000Z TRACE quiche] 11f3609530bd0ba68ad74e4882855455005e13dd tx frm STREAM id=6 off=0 len=1 fin=false
[2019-11-27T09:27:07.151414000Z TRACE quiche::recovery] 11f3609530bd0ba68ad74e4882855455005e13dd timer=86.002672ms inflight=214 cwnd=15720 latest_rtt=21.51898ms srtt=Some(21.51898ms) min_rtt=21.51898ms rttvar=10.75949ms probes=0
[2019-11-27T09:27:07.151520000Z DEBUG http3_client] written 43
[2019-11-27T09:27:07.151570000Z TRACE quiche] 11f3609530bd0ba68ad74e4882855455005e13dd tx pkt Short dcid=be84337f92d11c7aed92770fa0ebdae960087fa0 token=7175696368657f000001688a56ec5134560e9813fb2f61e32c53 key_phase=false len=21 pn=2
[2019-11-27T09:27:07.161579000Z TRACE quiche] 11f3609530bd0ba68ad74e4882855455005e13dd tx frm STREAM id=10 off=0 len=1 fin=false
[2019-11-27T09:27:07.161672000Z TRACE quiche::recovery] 11f3609530bd0ba68ad74e4882855455005e13dd timer=79.399629ms inflight=257 cwnd=15720 latest_rtt=21.51898ms srtt=Some(21.51898ms) min_rtt=21.51898ms rttvar=10.75949ms probes=0
[2019-11-27T09:27:07.161758000Z DEBUG http3_client] written 43
[2019-11-27T09:27:07.161898000Z TRACE quiche] 11f3609530bd0ba68ad74e4882855455005e13dd tx pkt Short dcid=be84337f92d11c7aed92770fa0ebdae960087fa0 token=7175696368657f000001688a56ec5134560e9813fb2f61e32c53 key_phase=false len=46 pn=3
[2019-11-27T09:27:07.161956000Z TRACE quiche] 11f3609530bd0ba68ad74e4882855455005e13dd tx frm STREAM id=14 off=0 len=26 fin=false
[2019-11-27T09:27:07.167233000Z TRACE quiche::recovery] 11f3609530bd0ba68ad74e4882855455005e13dd timer=84.16299ms inflight=325 cwnd=15720 latest_rtt=21.51898ms srtt=Some(21.51898ms) min_rtt=21.51898ms rttvar=10.75949ms probes=0
[2019-11-27T09:27:07.167315000Z DEBUG http3_client] written 68
[2019-11-27T09:27:07.167375000Z TRACE quiche] 11f3609530bd0ba68ad74e4882855455005e13dd tx pkt Short dcid=be84337f92d11c7aed92770fa0ebdae960087fa0 token=7175696368657f000001688a56ec5134560e9813fb2f61e32c53 key_phase=false len=80 pn=4
[2019-11-27T09:27:07.167430000Z TRACE quiche] 11f3609530bd0ba68ad74e4882855455005e13dd tx frm STREAM id=0 off=0 len=60 fin=true
[2019-11-27T09:27:07.167466000Z TRACE quiche::recovery] 11f3609530bd0ba68ad74e4882855455005e13dd timer=89.411842ms inflight=427 cwnd=15720 latest_rtt=21.51898ms srtt=Some(21.51898ms) min_rtt=21.51898ms rttvar=10.75949ms probes=0
[2019-11-27T09:27:07.178207000Z DEBUG http3_client] written 102
[2019-11-27T09:27:07.178373000Z DEBUG http3_client] done writing
[2019-11-27T09:27:07.178516000Z DEBUG http3_client] got 70 bytes
[2019-11-27T09:27:07.178695000Z TRACE quiche] 11f3609530bd0ba68ad74e4882855455005e13dd rx pkt Handshake version=ff000018 dcid=11f3609530bd0ba68ad74e4882855455005e13dd scid=be84337f92d11c7aed92770fa0ebdae960087fa0 len=22 pn=2
[2019-11-27T09:27:07.178786000Z TRACE quiche] 11f3609530bd0ba68ad74e4882855455005e13dd rx frm ACK delay=31 blocks=[0..0]
[2019-11-27T09:27:07.178870000Z TRACE quiche::recovery] 11f3609530bd0ba68ad74e4882855455005e13dd packet newly acked 0
[2019-11-27T09:27:07.183555000Z TRACE quiche::recovery] 11f3609530bd0ba68ad74e4882855455005e13dd timer=80.023194ms inflight=317 cwnd=15830 latest_rtt=37.042162ms srtt=Some(23.459377ms) min_rtt=21.51898msrttvar=11.950412ms probes=0
[2019-11-27T09:27:07.183628000Z DEBUG http3_client] processed 70 bytes
[2019-11-27T09:27:07.183654000Z DEBUG http3_client] got 461 bytes
[2019-11-27T09:27:07.183674000Z TRACE quiche] 11f3609530bd0ba68ad74e4882855455005e13dd rx pkt Short dcid=11f3609530bd0ba68ad74e4882855455005e13dd key_phase=false len=440 pn=0
[2019-11-27T09:27:07.183713000Z TRACE quiche] 11f3609530bd0ba68ad74e4882855455005e13dd rx frm CRYPTO off=0 len=396
[2019-11-27T09:27:07.194189000Z TRACE quiche] 11f3609530bd0ba68ad74e4882855455005e13dd rx frm STREAM id=3 off=0 len=19 fin=false
[2019-11-27T09:27:07.194272000Z DEBUG http3_client] processed 461 bytes
[2019-11-27T09:27:07.194306000Z DEBUG http3_client] got 43 bytes
[2019-11-27T09:27:07.194335000Z TRACE quiche] 11f3609530bd0ba68ad74e4882855455005e13dd rx pkt Short dcid=11f3609530bd0ba68ad74e4882855455005e13dd key_phase=false len=22 pn=1
[2019-11-27T09:27:07.194389000Z TRACE quiche] 11f3609530bd0ba68ad74e4882855455005e13dd rx frm STREAM id=7 off=0 len=1 fin=false
[2019-11-27T09:27:07.194442000Z DEBUG http3_client] processed 43 bytes
[[DEBUG]] inside loop
[2019-11-27T09:27:07.199361000Z DEBUG http3_client] got 43 bytes
[2019-11-27T09:27:07.199403000Z TRACE quiche] 11f3609530bd0ba68ad74e4882855455005e13dd rx pkt Short dcid=11f3609530bd0ba68ad74e4882855455005e13dd key_phase=false len=22 pn=2
[2019-11-27T09:27:07.199461000Z TRACE quiche] 11f3609530bd0ba68ad74e4882855455005e13dd rx frm STREAM id=11 off=0 len=1 fin=false
[2019-11-27T09:27:07.199538000Z DEBUG http3_client] processed 43 bytes
[2019-11-27T09:27:07.199563000Z DEBUG http3_client] got 68 bytes
[2019-11-27T09:27:07.199584000Z TRACE quiche] 11f3609530bd0ba68ad74e4882855455005e13dd rx pkt Short dcid=11f3609530bd0ba68ad74e4882855455005e13dd key_phase=false len=47 pn=3
[2019-11-27T09:27:07.199636000Z TRACE quiche] 11f3609530bd0ba68ad74e4882855455005e13dd rx frm STREAM id=15 off=0 len=26 fin=false
[2019-11-27T09:27:07.200901000Z DEBUG http3_client] processed 68 bytes
[2019-11-27T09:27:07.200936000Z DEBUG http3_client] got 43 bytes
[2019-11-27T09:27:07.200956000Z TRACE quiche] 11f3609530bd0ba68ad74e4882855455005e13dd rx pkt Short dcid=11f3609530bd0ba68ad74e4882855455005e13dd key_phase=false len=22 pn=4
[2019-11-27T09:27:07.201000000Z TRACE quiche] 11f3609530bd0ba68ad74e4882855455005e13dd rx frm ACK delay=11 blocks=[0..0]
[2019-11-27T09:27:07.201033000Z TRACE quiche::recovery] 11f3609530bd0ba68ad74e4882855455005e13dd packet newly acked 0
[2019-11-27T09:27:07.201049000Z TRACE quiche::recovery] 11f3609530bd0ba68ad74e4882855455005e13dd timer=84.021647ms inflight=256 cwnd=15891 latest_rtt=53.266446ms srtt=Some(27.174259ms) min_rtt=21.51898msrttvar=16.392576ms probes=0
[2019-11-27T09:27:07.215875000Z TRACE quiche] 11f3609530bd0ba68ad74e4882855455005e13dd dropped epoch 1 state
[2019-11-27T09:27:07.215952000Z DEBUG http3_client] processed 43 bytes
[2019-11-27T09:27:07.215987000Z DEBUG http3_client] got 43 bytes
[2019-11-27T09:27:07.216018000Z TRACE quiche] 11f3609530bd0ba68ad74e4882855455005e13dd rx pkt Short dcid=11f3609530bd0ba68ad74e4882855455005e13dd key_phase=false len=22 pn=5
[2019-11-27T09:27:07.216079000Z TRACE quiche] 11f3609530bd0ba68ad74e4882855455005e13dd rx frm ACK delay=10 blocks=[0..1]
[2019-11-27T09:27:07.218100000Z TRACE quiche::recovery] 11f3609530bd0ba68ad74e4882855455005e13dd packet newly acked 1
[2019-11-27T09:27:07.218131000Z TRACE quiche::recovery] 11f3609530bd0ba68ad74e4882855455005e13dd timer=96.497407ms inflight=213 cwnd=15934 latest_rtt=68.098683ms srtt=Some(32.279811ms) min_rtt=21.51898msrttvar=22.505538ms probes=0
[2019-11-27T09:27:07.218182000Z DEBUG http3_client] processed 43 bytes
[2019-11-27T09:27:07.218208000Z DEBUG http3_client] got 43 bytes
[2019-11-27T09:27:07.218227000Z TRACE quiche] 11f3609530bd0ba68ad74e4882855455005e13dd rx pkt Short dcid=11f3609530bd0ba68ad74e4882855455005e13dd key_phase=false len=22 pn=6
[2019-11-27T09:27:07.218269000Z TRACE quiche] 11f3609530bd0ba68ad74e4882855455005e13dd rx frm ACK delay=17 blocks=[0..2]
[2019-11-27T09:27:07.231974000Z TRACE quiche::recovery] 11f3609530bd0ba68ad74e4882855455005e13dd packet newly acked 2
[2019-11-27T09:27:07.232024000Z TRACE quiche::recovery] 11f3609530bd0ba68ad74e4882855455005e13dd timer=98.640708ms inflight=170 cwnd=15977 latest_rtt=66.676379ms srtt=Some(36.562381ms) min_rtt=21.51898msrttvar=25.444295ms probes=0
[2019-11-27T09:27:07.232094000Z DEBUG http3_client] processed 43 bytes
[2019-11-27T09:27:07.232126000Z DEBUG http3_client] got 43 bytes
[2019-11-27T09:27:07.232150000Z TRACE quiche] 11f3609530bd0ba68ad74e4882855455005e13dd rx pkt Short dcid=11f3609530bd0ba68ad74e4882855455005e13dd key_phase=false len=22 pn=7
[2019-11-27T09:27:07.233821000Z TRACE quiche] 11f3609530bd0ba68ad74e4882855455005e13dd rx frm ACK delay=18 blocks=[0..3]
[2019-11-27T09:27:07.233867000Z TRACE quiche::recovery] 11f3609530bd0ba68ad74e4882855455005e13dd packet newly acked 3
[2019-11-27T09:27:07.233886000Z TRACE quiche::recovery] 11f3609530bd0ba68ad74e4882855455005e13dd timer=109.101969ms inflight=102 cwnd=16045 latest_rtt=70.275722ms srtt=Some(40.758548ms) min_rtt=21.51898ms rttvar=27.475556ms probes=0
[2019-11-27T09:27:07.233932000Z DEBUG http3_client] processed 43 bytes
[2019-11-27T09:27:07.233956000Z DEBUG http3_client] got 114 bytes
[2019-11-27T09:27:07.233974000Z TRACE quiche] 11f3609530bd0ba68ad74e4882855455005e13dd rx pkt Short dcid=11f3609530bd0ba68ad74e4882855455005e13dd key_phase=false len=93 pn=8
[2019-11-27T09:27:07.248015000Z TRACE quiche] 11f3609530bd0ba68ad74e4882855455005e13dd rx frm ACK delay=85 blocks=[0..4]
[2019-11-27T09:27:07.248100000Z TRACE quiche::recovery] 11f3609530bd0ba68ad74e4882855455005e13dd packet newly acked 4
[2019-11-27T09:27:07.248124000Z TRACE quiche::recovery] 11f3609530bd0ba68ad74e4882855455005e13dd timer=none inflight=0 cwnd=16147 latest_rtt=66.632093ms srtt=Some(43.90774ms) min_rtt=21.51898ms rttvar=26.905053ms probes=0
[2019-11-27T09:27:07.248162000Z TRACE quiche] 11f3609530bd0ba68ad74e4882855455005e13dd rx frm STREAM id=0 off=0 len=65 fin=true
[2019-11-27T09:27:07.248204000Z DEBUG http3_client] processed 114 bytes
[2019-11-27T09:27:07.249612000Z DEBUG http3_client] recv() would block
[2019-11-27T09:27:07.249653000Z TRACE quiche::h3] 11f3609530bd0ba68ad74e4882855455005e13dd stream id 3 is readable
[2019-11-27T09:27:07.249711000Z TRACE quiche::h3::stream] 11f3609530bd0ba68ad74e4882855455005e13dd read 1 bytes on stream 3
[2019-11-27T09:27:07.249737000Z TRACE quiche::h3] 11f3609530bd0ba68ad74e4882855455005e13dd open peer's control stream 3
[2019-11-27T09:27:07.249762000Z TRACE quiche::h3::stream] 11f3609530bd0ba68ad74e4882855455005e13dd read 1 bytes on stream 3
[2019-11-27T09:27:07.249805000Z TRACE quiche::h3::stream] 11f3609530bd0ba68ad74e4882855455005e13dd read 1 bytes on stream 3
[2019-11-27T09:27:07.253311000Z TRACE quiche::h3::stream] 11f3609530bd0ba68ad74e4882855455005e13dd read 16 bytes on stream 3
[2019-11-27T09:27:07.253344000Z TRACE quiche::h3] 11f3609530bd0ba68ad74e4882855455005e13dd rx frm SETTINGS max_headers=None, qpack_max_table=None, qpack_blocked=None stream=3
[2019-11-27T09:27:07.253371000Z TRACE quiche::h3] 11f3609530bd0ba68ad74e4882855455005e13dd stream id 11 is readable
[2019-11-27T09:27:07.253392000Z TRACE quiche::h3::stream] 11f3609530bd0ba68ad74e4882855455005e13dd read 1 bytes on stream 11
[2019-11-27T09:27:07.253411000Z TRACE quiche::h3] 11f3609530bd0ba68ad74e4882855455005e13dd stream id 15 is readable
[2019-11-27T09:27:07.253437000Z TRACE quiche::h3::stream] 11f3609530bd0ba68ad74e4882855455005e13dd read 1 bytes on stream 15
[2019-11-27T09:27:07.264547000Z TRACE quiche::h3::stream] 11f3609530bd0ba68ad74e4882855455005e13dd read 7 bytes on stream 15
[2019-11-27T09:27:07.264602000Z TRACE quiche::h3] 11f3609530bd0ba68ad74e4882855455005e13dd stream id 7 is readable
[2019-11-27T09:27:07.264651000Z TRACE quiche::h3::stream] 11f3609530bd0ba68ad74e4882855455005e13dd read 1 bytes on stream 7
[2019-11-27T09:27:07.264684000Z TRACE quiche::h3] 11f3609530bd0ba68ad74e4882855455005e13dd stream id 0 is readable
[2019-11-27T09:27:07.264712000Z TRACE quiche::h3::stream] 11f3609530bd0ba68ad74e4882855455005e13dd read 1 bytes on stream 0
[2019-11-27T09:27:07.264737000Z TRACE quiche::h3::stream] 11f3609530bd0ba68ad74e4882855455005e13dd read 7 bytes on stream 0
[2019-11-27T09:27:07.270249000Z TRACE quiche::h3::stream] 11f3609530bd0ba68ad74e4882855455005e13dd read 1 bytes on stream 0
[2019-11-27T09:27:07.270279000Z TRACE quiche::h3::stream] 11f3609530bd0ba68ad74e4882855455005e13dd read 0 bytes on stream 0
[2019-11-27T09:27:07.270296000Z TRACE quiche::h3] 11f3609530bd0ba68ad74e4882855455005e13dd rx frm UNKNOWN stream=0
[2019-11-27T09:27:07.270319000Z TRACE quiche::h3::stream] 11f3609530bd0ba68ad74e4882855455005e13dd read 1 bytes on stream 0
[2019-11-27T09:27:07.270337000Z TRACE quiche::h3::stream] 11f3609530bd0ba68ad74e4882855455005e13dd read 7 bytes on stream 0
[2019-11-27T09:27:07.270355000Z TRACE quiche::h3::stream] 11f3609530bd0ba68ad74e4882855455005e13dd read 1 bytes on stream 0
[2019-11-27T09:27:07.279438000Z TRACE quiche::h3::stream] 11f3609530bd0ba68ad74e4882855455005e13dd read 18 bytes on stream 0
[2019-11-27T09:27:07.279500000Z TRACE quiche::h3] 11f3609530bd0ba68ad74e4882855455005e13dd rx frm UNKNOWN stream=0
[2019-11-27T09:27:07.279532000Z TRACE quiche::h3::stream] 11f3609530bd0ba68ad74e4882855455005e13dd read 1 bytes on stream 0
[2019-11-27T09:27:07.279562000Z TRACE quiche::h3::stream] 11f3609530bd0ba68ad74e4882855455005e13dd read 1 bytes on stream 0
[2019-11-27T09:27:07.279643000Z TRACE quiche::h3::stream] 11f3609530bd0ba68ad74e4882855455005e13dd read 15 bytes on stream 0
[2019-11-27T09:27:07.279668000Z TRACE quiche::h3] 11f3609530bd0ba68ad74e4882855455005e13dd rx frm HEADERS len=15 stream=0
[2019-11-27T09:27:07.284612000Z TRACE quiche::h3::qpack::decoder] Header count=0 base=0
[2019-11-27T09:27:07.284644000Z TRACE quiche::h3::qpack::decoder] Indexed index=27 static=true
[2019-11-27T09:27:07.286194000Z TRACE quiche::h3::qpack::decoder] Literal name_idx=92 static=true value="quiche"
[2019-11-27T09:27:07.286241000Z TRACE quiche::h3::qpack::decoder] Literal name_idx=4 static=true value="10"
[2019-11-27T09:27:07.286269000Z INFO http3_client] got response headers [Header(":status", "404"), Header("server", "quiche"), Header("content-length", "10")] on stream id 0
[2019-11-27T09:27:07.286317000Z TRACE quiche::h3] 11f3609530bd0ba68ad74e4882855455005e13dd stream id 0 is readable
[2019-11-27T09:27:07.294119000Z TRACE quiche::h3::stream] 11f3609530bd0ba68ad74e4882855455005e13dd read 1 bytes on stream 0
[2019-11-27T09:27:07.294170000Z TRACE quiche::h3::stream] 11f3609530bd0ba68ad74e4882855455005e13dd read 1 bytes on stream 0
[2019-11-27T09:27:07.294216000Z DEBUG http3_client] got 10 bytes of response data on stream 0
[2019-11-27T09:27:07.294256000Z DEBUG http3_client] 1/1 responses received
[2019-11-27T09:27:07.294278000Z INFO http3_client] 1/1 response(s) received in 217.642692ms, closing...
[2019-11-27T09:27:07.294322000Z TRACE quiche] 11f3609530bd0ba68ad74e4882855455005e13dd tx pkt Short dcid=be84337f92d11c7aed92770fa0ebdae960087fa0 token=7175696368657f000001688a56ec5134560e9813fb2f61e32c53 key_phase=false len=26 pn=5
[2019-11-27T09:27:07.299327000Z TRACE quiche] 11f3609530bd0ba68ad74e4882855455005e13dd tx frm APPLICATION_CLOSE err=0 reason=[6b, 74, 68, 78, 62, 79, 65]
[2019-11-27T09:27:07.299401000Z TRACE quiche::recovery] 11f3609530bd0ba68ad74e4882855455005e13dd timer=171.419138ms inflight=48 cwnd=16147 latest_rtt=66.632093ms srtt=Some(43.90774ms) min_rtt=21.51898ms rttvar=26.905053ms probes=0
[2019-11-27T09:27:07.299471000Z DEBUG http3_client] written 48
[2019-11-27T09:27:07.299530000Z DEBUG http3_client] done writing
[2019-11-27T09:27:07.824093000Z DEBUG http3_client] timed out
[2019-11-27T09:27:07.824177000Z TRACE quiche] 11f3609530bd0ba68ad74e4882855455005e13dd draining timeout expired
[2019-11-27T09:27:07.824233000Z INFO http3_client] connection closed, recv=13 sent=11 lost=0 rtt=43.90774ms cwnd=16099
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment