Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

Sign up for GitHub

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

Jump to bottom

quiche::h3::Connection::poll crashed occasionally #878

Open
psionic12 opened this issue Mar 23, 2021 · 2 comments
Open

quiche::h3::Connection::poll crashed occasionally #878

psionic12 opened this issue Mar 23, 2021 · 2 comments

Comments

@psionic12
Copy link

psionic12 commented Mar 23, 2021 •
edited
Loading

I got some crashes when running quiche, it seems that quiche::h3::Connection::poll() crashed occasionally due to out of range access (not for sure), here's a core dump:

03-23 11:01:48.048  9735  9735 F DEBUG   :       #00 pc 0000000000073898  /apex/com.android.runtime/lib64/bionic/libc.so (abort+160) (BuildId: 6aaa192fa70426ea767b3bcf55b19a30)
03-23 11:01:48.048  9735  9735 F DEBUG   :       #01 pc 0000000000398bf4  /data/app/com.network.moudle-SDIf-_y3YUilDAP_CxYK6Q==/lib/arm64/libhttp3.so (BuildId: 257d18d9856135cac2896e9a12a6f6339bfc6027)
03-23 11:01:48.048  9735  9735 F DEBUG   :       #02 pc 000000000038ae34  /data/app/com.network.moudle-SDIf-_y3YUilDAP_CxYK6Q==/lib/arm64/libhttp3.so (BuildId: 257d18d9856135cac2896e9a12a6f6339bfc6027)
03-23 11:01:48.048  9735  9735 F DEBUG   :       #03 pc 000000000038cae4  /data/app/com.network.moudle-SDIf-_y3YUilDAP_CxYK6Q==/lib/arm64/libhttp3.so (rust_panic+72) (BuildId: 257d18d9856135cac2896e9a12a6f6339bfc6027)
03-23 11:01:48.048  9735  9735 F DEBUG   :       #04 pc 000000000038c998  /data/app/com.network.moudle-SDIf-_y3YUilDAP_CxYK6Q==/lib/arm64/libhttp3.so (std::panicking::rust_panic_with_hook::ha70546d04f6d504c+452) (BuildId: 257d18d9856135cac2896e9a12a6f6339bfc6027)
03-23 11:01:48.048  9735  9735 F DEBUG   :       #05 pc 000000000038c498  /data/app/com.network.moudle-SDIf-_y3YUilDAP_CxYK6Q==/lib/arm64/libhttp3.so (BuildId: 257d18d9856135cac2896e9a12a6f6339bfc6027)
03-23 11:01:48.048  9735  9735 F DEBUG   :       #06 pc 00000000003888e0  /data/app/com.network.moudle-SDIf-_y3YUilDAP_CxYK6Q==/lib/arm64/libhttp3.so (BuildId: 257d18d9856135cac2896e9a12a6f6339bfc6027)
03-23 11:01:48.048  9735  9735 F DEBUG   :       #07 pc 000000000038c42c  /data/app/com.network.moudle-SDIf-_y3YUilDAP_CxYK6Q==/lib/arm64/libhttp3.so (rust_begin_unwind+60) (BuildId: 257d18d9856135cac2896e9a12a6f6339bfc6027)
03-23 11:01:48.048  9735  9735 F DEBUG   :       #08 pc 00000000003e77a8  /data/app/com.network.moudle-SDIf-_y3YUilDAP_CxYK6Q==/lib/arm64/libhttp3.so (core::panicking::panic_fmt::h3593cc7d76ed531a+36) (BuildId: 257d18d9856135cac2896e9a12a6f6339bfc6027)
03-23 11:01:48.048  9735  9735 F DEBUG   :       #09 pc 00000000003e772c  /data/app/com.network.moudle-SDIf-_y3YUilDAP_CxYK6Q==/lib/arm64/libhttp3.so (core::panicking::panic::he3e49b5a00610813+48) (BuildId: 257d18d9856135cac2896e9a12a6f6339bfc6027)
03-23 11:01:48.048  9735  9735 F DEBUG   :       #10 pc 00000000003dec7c  /data/app/com.network.moudle-SDIf-_y3YUilDAP_CxYK6Q==/lib/arm64/libhttp3.so (alloc::raw_vec::capacity_overflow::hbc7cd64494a88c37+24) (BuildId: 257d18d9856135cac2896e9a12a6f6339bfc6027)
03-23 11:01:48.048  9735  9735 F DEBUG   :       #11 pc 00000000003272ac  /data/app/com.network.moudle-SDIf-_y3YUilDAP_CxYK6Q==/lib/arm64/libhttp3.so (BuildId: 257d18d9856135cac2896e9a12a6f6339bfc6027)
03-23 11:01:48.049  9735  9735 F DEBUG   :       #12 pc 0000000000329644  /data/app/com.network.moudle-SDIf-_y3YUilDAP_CxYK6Q==/lib/arm64/libhttp3.so (alloc::raw_vec::RawVec$LT$T$C$A$GT$::reserve::h06de1b2869cf3126+32) (BuildId: 257d18d9856135cac2896e9a12a6f6339bfc6027)
03-23 11:01:48.049  9735  9735 F DEBUG   :       #13 pc 00000000002f7f88  /data/app/com.network.moudle-SDIf-_y3YUilDAP_CxYK6Q==/lib/arm64/libhttp3.so (alloc::vec::Vec$LT$T$GT$::reserve::h24edae75ff760a31+32) (BuildId: 257d18d9856135cac2896e9a12a6f6339bfc6027)
03-23 11:01:48.049  9735  9735 F DEBUG   :       #14 pc 0000000000433a08  /data/app/com.network.moudle-SDIf-_y3YUilDAP_CxYK6Q==/lib/arm64/libhttp3.so (alloc::vec::Vec$LT$T$GT$::extend_desugared::hc5dfb8e6b2e38c9e+216) (BuildId: 257d18d9856135cac2896e9a12a6f6339bfc6027)
03-23 11:01:48.049  9735  9735 F DEBUG   :       #15 pc 0000000000436374  /data/app/com.network.moudle-SDIf-_y3YUilDAP_CxYK6Q==/lib/arm64/libhttp3.so (_$LT$alloc..vec..Vec$LT$T$GT$$u20$as$u20$alloc..vec..SpecExtend$LT$T$C$I$GT$$GT$::spec_extend::h2345cc79ae98e850+48) (BuildId: 257d18d9856135cac2896e9a12a6f6339bfc6027)
03-23 11:01:48.049  9735  9735 F DEBUG   :       #16 pc 0000000000438290  /data/app/com.network.moudle-SDIf-_y3YUilDAP_CxYK6Q==/lib/arm64/libhttp3.so (_$LT$alloc..vec..Vec$LT$T$GT$$u20$as$u20$alloc..vec..SpecFromIterNested$LT$T$C$I$GT$$GT$::from_iter::h7f7ac09f1c564b85+308) (BuildId: 257d18d9856135cac2896e9a12a6f6339bfc6027)
03-23 11:01:48.049  9735  9735 F DEBUG   :       #17 pc 0000000000436ce8  /data/app/com.network.moudle-SDIf-_y3YUilDAP_CxYK6Q==/lib/arm64/libhttp3.so (_$LT$alloc..vec..Vec$LT$T$GT$$u20$as$u20$alloc..vec..SpecFromIter$LT$T$C$I$GT$$GT$::from_iter::h903944d4b1de11ea+52) (BuildId: 257d18d9856135cac2896e9a12a6f6339bfc6027)
03-23 11:01:48.049  9735  9735 F DEBUG   :       #18 pc 00000000004387b0  /data/app/com.network.moudle-SDIf-_y3YUilDAP_CxYK6Q==/lib/arm64/libhttp3.so (_$LT$alloc..vec..Vec$LT$T$GT$$u20$as$u20$core..iter..traits..collect..FromIterator$LT$T$GT$$GT$::from_iter::h0fb288be8650142c+64) (BuildId: 257d18d9856135cac2896e9a12a6f6339bfc6027)
03-23 11:01:48.049  9735  9735 F DEBUG   :       #19 pc 0000000000415138  /data/app/com.network.moudle-SDIf-_y3YUilDAP_CxYK6Q==/lib/arm64/libhttp3.so (core::iter::traits::iterator::Iterator::collect::he8750c2eb3612821+52) (BuildId: 257d18d9856135cac2896e9a12a6f6339bfc6027)
03-23 11:01:48.049  9735  9735 F DEBUG   :       #20 pc 000000000045417c  /data/app/com.network.moudle-SDIf-_y3YUilDAP_CxYK6Q==/lib/arm64/libhttp3.so (BuildId: 257d18d9856135cac2896e9a12a6f6339bfc6027)
03-23 11:01:48.049  9735  9735 F DEBUG   :       #21 pc 0000000000453af4  /data/app/com.network.moudle-SDIf-_y3YUilDAP_CxYK6Q==/lib/arm64/libhttp3.so (quiche::stream::StreamMap::readable::h5ee47b080967049a+16) (BuildId: 257d18d9856135cac2896e9a12a6f6339bfc6027)
03-23 11:01:48.049  9735  9735 F DEBUG   :       #22 pc 000000000028429c  /data/app/com.network.moudle-SDIf-_y3YUilDAP_CxYK6Q==/lib/arm64/libhttp3.so (BuildId: 257d18d9856135cac2896e9a12a6f6339bfc6027)
03-23 11:01:48.049  9735  9735 F DEBUG   :       #23 pc 000000000040ded4  /data/app/com.network.moudle-SDIf-_y3YUilDAP_CxYK6Q==/lib/arm64/libhttp3.so (quiche::h3::Connection::poll::h0643128810d0eff2+1696) (BuildId: 257d18d9856135cac2896e9a12a6f6339bfc6027)
03-23 11:01:48.049  9735  9735 F DEBUG   :       #24 pc 00000000002747f8  /data/app/com.network.moudle-SDIf-_y3YUilDAP_CxYK6Q==/lib/arm64/libhttp3.so (quiche_h3_conn_poll+28) (BuildId: 257d18d9856135cac2896e9a12a6f6339bfc6027)
03-23 11:01:48.049  9735  9735 F DEBUG   :       #25 pc 0000000000269c78  /data/app/com.network.moudle-SDIf-_y3YUilDAP_CxYK6Q==/lib/arm64/libhttp3.so (my:h3::Working::HandleH3Events()+128) (BuildId: 257d18d9856135cac2896e9a12a6f6339bfc6027)
03-23 11:01:48.049  9735  9735 F DEBUG   :       #26 pc 000000000026ae30  /data/app/com.network.moudle-SDIf-_y3YUilDAP_CxYK6Q==/lib/arm64/libhttp3.so (my:h3::Working::OnMessage(unsigned int, void*)+236) (BuildId: 257d18d9856135cac2896e9a12a6f6339bfc6027)
03-23 11:01:48.049  9735  9735 F DEBUG   :       #27 pc 000000000026418c  /data/app/com.network.moudle-SDIf-_y3YUilDAP_CxYK6Q==/lib/arm64/libhttp3.so (my:h3::StateMachine::SendMessage(unsigned int, void*)+96) (BuildId: 257d18d9856135cac2896e9a12a6f6339bfc6027)
03-23 11:01:48.049  9735  9735 F DEBUG   :       #28 pc 0000000000269308  /data/app/com.network.moudle-SDIf-_y3YUilDAP_CxYK6Q==/lib/arm64/libhttp3.so (my:h3::Connection::OnReadable()+660) (BuildId: 257d18d9856135cac2896e9a12a6f6339bfc6027)
03-23 11:01:48.049  9735  9735 F DEBUG   :       #29 pc 0000000000246dfc  /data/app/com.network.moudle-SDIf-_y3YUilDAP_CxYK6Q==/lib/arm64/libhttp3.so (my:h3::ConnectionPool::Loop()+1024) (BuildId: 257d18d9856135cac2896e9a12a6f6339bfc6027)
03-23 11:01:48.049  9735  9735 F DEBUG   :       #30 pc 0000000000254dd8  /data/app/com.network.moudle-SDIf-_y3YUilDAP_CxYK6Q==/lib/arm64/libhttp3.so (BuildId: 257d18d9856135cac2896e9a12a6f6339bfc6027)
03-23 11:01:48.049  9735  9735 F DEBUG   :       #31 pc 0000000000254cd4  /data/app/com.network.moudle-SDIf-_y3YUilDAP_CxYK6Q==/lib/arm64/libhttp3.so (BuildId: 257d18d9856135cac2896e9a12a6f6339bfc6027)
03-23 11:01:48.050  9735  9735 F DEBUG   :       #32 pc 00000000002545c0  /data/app/com.network.moudle-SDIf-_y3YUilDAP_CxYK6Q==/lib/arm64/libhttp3.so (_ZNSt6__ndk114__thread_proxyINS_5tupleIJNS_10unique_ptrINS_15__thread_structENS_14default_deleteIS3_EEEEMN6mh314ConnectionPoolEFvvEPS9_EEEEEPvSE_+128) (BuildId: 257d18d9856135cac2896e9a12a6f6339bfc6027)
03-23 11:01:48.050  9735  9735 F DEBUG   :       #33 pc 00000000000d6eb0  /apex/com.android.runtime/lib64/bionic/libc.so (__pthread_start(void*)+36) (BuildId: 6aaa192fa70426ea767b3bcf55b19a30)
03-23 11:01:48.050  9735  9735 F DEBUG   :       #34 pc 0000000000075314  /apex/com.android.runtime/lib64/bionic/libc.so (__start_thread+64) (BuildId: 6aaa192fa70426ea767b3bcf55b19a30)

what did I do wrong that may lead this crash happen?
@psionic12
Copy link
Author

I enabled the debug log and captured one before crash:

03-23 15:01:29.085 29195 29195 D MyLog   : Connection pool initialized
03-23 15:01:29.123 29195 29236 D MyLog   : Incoming message
03-23 15:01:29.123 29195 29236 D MyLog   : new request 0x7c5c007980 for lltest2.corp.qunar.com:443
03-23 15:01:29.123 29195 29236 D MyLog   : No available connections found, create one
03-23 15:01:29.124 29195 29238 D MyLog   : request sent, wait for response
03-23 15:01:29.126 29195 29236 D MyLog   : Add new fd 66 to epoll.
03-23 15:01:29.126 29195 29236 D MyLog   : Create socket.
03-23 15:01:29.127 29195 29236 D MyLog   : Create quiche connection.
03-23 15:01:29.127 29195 29236 D MyLog   : pipe read would block: Try again
03-23 15:01:29.128 29195 29236 D QuicheLog: quiche::tls: f8 write message lvl=Initial len=252
03-23 15:01:29.128 29195 29236 D QuicheLog: quiche: f8 tx pkt Initial version=babababa dcid=b56bd80fd4d3e24afe622b64d028f698 scid=f8 len=272 pn=0
03-23 15:01:29.128 29195 29236 D QuicheLog: quiche: f8 tx frm CRYPTO off=0 len=252
03-23 15:01:29.128 29195 29236 D QuicheLog: quiche::recovery: f8 timer=998.16927ms latest_rtt=0ns srtt=None min_rtt=0ns rttvar=166.5ms loss_time=[None, None, None] loss_probes=[0, 0, 0] cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=300 app_limited=true congestion_recovery_start_time=None delivered=0 delivered_time=855.469µs recent_delivered_packet_sent_time=856.719µs app_limited_at_pkt=0  hystart=window_end=None last_round_min_rtt=None current_round_min_rtt=None rtt_sample_count=0 lss_start_time=None  
03-23 15:01:29.128 29195 29236 D MyLog   : flush all data in socket 66
03-23 15:01:29.147 29195 29236 D QuicheLog: quiche: f8 rx pkt VersionNegotiation version=0 dcid=f8 scid=b56bd80fd4d3e24afe622b64d028f698 versions=[ff00001d, ff00001e, ff00001f, ff000020, 1]
03-23 15:01:29.147 29195 29236 D QuicheLog: quiche: f8 dropped epoch 0 state
03-23 15:01:29.147 29195 29236 D MyLog   : Read finished.
03-23 15:01:29.147 29195 29236 D MyLog   : Handshaking still ongoing.
03-23 15:01:29.147 29195 29236 D QuicheLog: quiche::tls: f8 write message lvl=Initial len=252
03-23 15:01:29.148 29195 29236 D QuicheLog: quiche: f8 tx pkt Initial version=ff00001d dcid=b56bd80fd4d3e24afe622b64d028f698 scid=f8 len=272 pn=1
03-23 15:01:29.148 29195 29236 D QuicheLog: quiche: f8 tx frm CRYPTO off=0 len=252
03-23 15:01:29.148 29195 29236 D QuicheLog: quiche::recovery: f8 timer=998.345521ms latest_rtt=0ns srtt=None min_rtt=0ns rttvar=166.5ms loss_time=[None, None, None] loss_probes=[0, 0, 0] cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=300 app_limited=true congestion_recovery_start_time=None delivered=0 delivered_time=20.655365ms recent_delivered_packet_sent_time=20.656407ms app_limited_at_pkt=0  hystart=window_end=None last_round_min_rtt=None current_round_min_rtt=None rtt_sample_count=0 lss_start_time=None  
03-23 15:01:29.148 29195 29236 D MyLog   : flush all data in socket 66
03-23 15:01:29.148 29195 29236 D MyLog   : flush all data in socket 66
03-23 15:01:29.153 29195 29236 D QuicheLog: quiche: f8 rx pkt Initial version=ff00001d dcid=f8 scid=c3e66c4bc08390dc0c04433877f3d0e000e43660 token= len=116 pn=0
03-23 15:01:29.154 29195 29236 D QuicheLog: quiche: f8 rx frm CRYPTO off=0 len=90
03-23 15:01:29.155 29195 29236 D QuicheLog: quiche::tls: f8 set write secret lvl=Handshake
03-23 15:01:29.155 29195 29236 D QuicheLog: quiche::tls: f8 set read secret lvl=Handshake
03-23 15:01:29.155 29195 29236 D QuicheLog: quiche: f8 rx frm ACK delay=0 blocks=[1..1]
03-23 15:01:29.155 29195 29236 D QuicheLog: quiche::recovery: f8 packet newly acked 1
03-23 15:01:29.155 29195 29236 D QuicheLog: quiche: f8 rx pkt Handshake version=ff00001d dcid=f8 scid=c3e66c4bc08390dc0c04433877f3d0e000e43660 len=1069 pn=0
03-23 15:01:29.155 29195 29236 D QuicheLog: quiche: f8 rx frm CRYPTO off=0 len=1048
03-23 15:01:29.156 29195 29236 D QuicheLog: quiche: f8 rx pkt Handshake version=ff00001d dcid=f8 scid=c3e66c4bc08390dc0c04433877f3d0e000e43660 len=1216 pn=1
03-23 15:01:29.156 29195 29236 D QuicheLog: quiche: f8 rx frm CRYPTO off=1048 len=1194
03-23 15:01:29.156 29195 29236 D QuicheLog: quiche: f8 rx pkt Handshake version=ff00001d dcid=f8 scid=c3e66c4bc08390dc0c04433877f3d0e000e43660 len=1072 pn=2
03-23 15:01:29.156 29195 29236 D QuicheLog: quiche: f8 rx frm CRYPTO off=2242 len=1050
03-23 15:01:29.156 29195 29236 D MyLog   : Read finished.
03-23 15:01:29.156 29195 29236 D MyLog   : Handshaking still ongoing.
03-23 15:01:29.156 29195 29236 D QuicheLog: quiche: f8 tx pkt Initial version=ff00001d dcid=c3e66c4bc08390dc0c04433877f3d0e000e43660 scid=f8 len=22 pn=2
03-23 15:01:29.156 29195 29236 D QuicheLog: quiche: f8 tx frm ACK delay=336 blocks=[0..0]
03-23 15:01:29.156 29195 29236 D QuicheLog: quiche::recovery: f8 timer=16.274947ms latest_rtt=6.373021ms srtt=Some(6.373021ms) min_rtt=6.373021ms rttvar=3.18651ms loss_time=[None, None, None] loss_probes=[0, 0, 0] cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=0 app_limited=true congestion_recovery_start_time=None delivered=300 delivered_time=2.867031ms recent_delivered_packet_sent_time=29.225209ms app_limited_at_pkt=0  hystart=window_end=None last_round_min_rtt=None current_round_min_rtt=None rtt_sample_count=0 lss_start_time=None  
03-23 15:01:29.156 29195 29236 D QuicheLog: quiche: f8 tx pkt Handshake version=ff00001d dcid=c3e66c4bc08390dc0c04433877f3d0e000e43660 scid=f8 len=21 pn=0
03-23 15:01:29.156 29195 29236 D QuicheLog: quiche: f8 tx frm ACK delay=58 blocks=[0..2]
03-23 15:01:29.156 29195 29236 D QuicheLog: quiche::recovery: f8 timer=16.103072ms latest_rtt=6.373021ms srtt=Some(6.373021ms) min_rtt=6.373021ms rttvar=3.18651ms loss_time=[None, None, None] loss_probes=[0, 0, 0] cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=0 app_limited=true congestion_recovery_start_time=None delivered=300 delivered_time=3.027552ms recent_delivered_packet_sent_time=29.385469ms app_limited_at_pkt=0  hystart=window_end=None last_round_min_rtt=None current_round_min_rtt=None rtt_sample_count=0 lss_start_time=None  
03-23 15:01:29.156 29195 29236 D QuicheLog: quiche: f8 dropped epoch 0 state
03-23 15:01:29.157 29195 29236 D MyLog   : flush all data in socket 66
03-23 15:01:29.157 29195 29236 D MyLog   : flush all data in socket 66
03-23 15:01:29.164 29195 29236 D QuicheLog: quiche: f8 rx pkt Handshake version=ff00001d dcid=f8 scid=c3e66c4bc08390dc0c04433877f3d0e000e43660 len=1216 pn=3
03-23 15:01:29.165 29195 29236 D QuicheLog: quiche: f8 rx frm CRYPTO off=3292 len=1194
03-23 15:01:29.165 29195 29236 D QuicheLog: quiche: f8 rx pkt Handshake version=ff00001d dcid=f8 scid=c3e66c4bc08390dc0c04433877f3d0e000e43660 len=486 pn=4
03-23 15:01:29.165 29195 29236 D QuicheLog: quiche: f8 rx frm CRYPTO off=4486 len=464
03-23 15:01:29.166 29195 29236 D QuicheLog: quiche::tls: f8 write message lvl=Handshake len=36
03-23 15:01:29.166 29195 29236 D QuicheLog: quiche::tls: f8 set write secret lvl=OneRTT
03-23 15:01:29.166 29195 29236 D QuicheLog: quiche::tls: f8 set read secret lvl=OneRTT
03-23 15:01:29.167 29195 29236 D QuicheLog: quiche: f8 connection established: proto=Ok("h3-29") cipher=Some(AES128_GCM) curve=Some("X25519") sigalg=Some("rsa_pss_rsae_sha256") resumed=false TransportParams { original_destination_connection_id: Some(b56bd80fd4d3e24afe622b64d028f698), max_idle_timeout: 60000, stateless_reset_token: Some([151, 40, 87, 196, 244, 48, 38, 153, 90, 208, 193, 17, 55, 23, 146, 184]), max_udp_payload_size: 65527, initial_max_data: 1048576, initial_max_stream_data_bidi_local: 65536, initial_max_stream_data_bidi_remote: 65536, initial_max_stream_data_uni: 65536, initial_max_streams_bidi: 16, initial_max_streams_uni: 16, ack_delay_exponent: 3, max_ack_delay: 25, disable_active_migration: false, active_conn_id_limit: 2, initial_source_connection_id: Some(c3e66c4bc08390dc0c04433877f3d0e000e43660), retry_source_connection_id: None, max_datagram_frame_size: None }
03-23 15:01:29.167 29195 29236 D MyLog   : Read finished.
03-23 15:01:29.167 29195 29236 D QuicheLog: quiche::h3: f8 open GREASE stream 14
03-23 15:01:29.167 29195 29236 D QuicheLog: quiche::h3: f8 tx frm GREASE stream=0
03-23 15:01:29.167 29195 29236 D QuicheLog: quiche::h3: f8 tx frm HEADERS stream=0 len=23 fin=true
03-23 15:01:29.167 29195 29236 D MyLog   : http3 connection created, stream id: 0
03-23 15:01:29.168 29195 29236 D QuicheLog: quiche: f8 tx pkt Handshake version=ff00001d dcid=c3e66c4bc08390dc0c04433877f3d0e000e43660 scid=f8 len=62 pn=1
03-23 15:01:29.168 29195 29236 D QuicheLog: quiche: f8 tx frm ACK delay=307 blocks=[0..4]
03-23 15:01:29.168 29195 29236 D QuicheLog: quiche: f8 tx frm CRYPTO off=0 len=36
03-23 15:01:29.168 29195 29236 D QuicheLog: quiche::recovery: f8 timer=18.916821ms latest_rtt=6.373021ms srtt=Some(6.373021ms) min_rtt=6.373021ms rttvar=3.18651ms loss_time=[None, None, None] loss_probes=[0, 0, 0] cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=93 app_limited=true congestion_recovery_start_time=None delivered=300 delivered_time=14.367708ms recent_delivered_packet_sent_time=40.726042ms app_limited_at_pkt=300  hystart=window_end=None last_round_min_rtt=None current_round_min_rtt=None rtt_sample_count=0 lss_start_time=None  
03-23 15:01:29.168 29195 29236 D QuicheLog: quiche: f8 tx pkt Short dcid=c3e66c4bc08390dc0c04433877f3d0e000e43660 key_phase=false len=40 pn=0
03-23 15:01:29.168 29195 29236 D QuicheLog: quiche: f8 tx frm STREAM id=2 off=0 len=19 fin=false
03-23 15:01:29.168 29195 29236 D QuicheLog: quiche::recovery: f8 timer=18.733123ms latest_rtt=6.373021ms srtt=Some(6.373021ms) min_rtt=6.373021ms rttvar=3.18651ms loss_time=[None, None, None] loss_probes=[0, 0, 0] cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=155 app_limited=true congestion_recovery_start_time=None delivered=300 delivered_time=14.545312ms recent_delivered_packet_sent_time=40.903386ms app_limited_at_pkt=300  hystart=window_end=Some(0) last_round_min_rtt=None current_round_min_rtt=None rtt_sample_count=0 lss_start_time=None  
03-23 15:01:29.168 29195 29236 D QuicheLog: quiche: f8 tx pkt Short dcid=c3e66c4bc08390dc0c04433877f3d0e000e43660 key_phase=false len=22 pn=1
03-23 15:01:29.168 29195 29236 D QuicheLog: quiche: f8 tx frm STREAM id=6 off=0 len=1 fin=false
03-23 15:01:29.168 29195 29236 D QuicheLog: quiche::recovery: f8 timer=18.403853ms latest_rtt=6.373021ms srtt=Some(6.373021ms) min_rtt=6.373021ms rttvar=3.18651ms loss_time=[None, None, None] loss_probes=[0, 0, 0] cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=199 app_limited=true congestion_recovery_start_time=None delivered=300 delivered_time=14.877604ms recent_delivered_packet_sent_time=41.235677ms app_limited_at_pkt=300  hystart=window_end=Some(0) last_round_min_rtt=None current_round_min_rtt=None rtt_sample_count=0 lss_start_time=None  
03-23 15:01:29.168 29195 29236 D QuicheLog: quiche: f8 tx pkt Short dcid=c3e66c4bc08390dc0c04433877f3d0e000e43660 key_phase=false len=22 pn=2
03-23 15:01:29.168 29195 29236 D QuicheLog: quiche: f8 tx frm STREAM id=10 off=0 len=1 fin=false
03-23 15:01:29.168 29195 29236 D QuicheLog: quiche::recovery: f8 timer=18.13453ms latest_rtt=6.373021ms srtt=Some(6.373021ms) min_rtt=6.373021ms rttvar=3.18651ms loss_time=[None, None, None] loss_probes=[0, 0, 0] cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=243 app_limited=true congestion_recovery_start_time=None delivered=300 delivered_time=15.145104ms recent_delivered_packet_sent_time=41.50323ms app_limited_at_pkt=300  hystart=window_end=Some(0) last_round_min_rtt=None current_round_min_rtt=None rtt_sample_count=0 lss_start_time=None  
03-23 15:01:29.169 29195 29236 D QuicheLog: quiche: f8 tx pkt Short dcid=c3e66c4bc08390dc0c04433877f3d0e000e43660 key_phase=false len=82 pn=3
03-23 15:01:29.169 29195 29236 D QuicheLog: quiche: f8 tx frm STREAM id=0 off=0 len=61 fin=true
03-23 15:01:29.169 29195 29236 D QuicheLog: quiche::recovery: f8 timer=17.887238ms latest_rtt=6.373021ms srtt=Some(6.373021ms) min_rtt=6.373021ms rttvar=3.18651ms loss_time=[None, None, None] loss_probes=[0, 0, 0] cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=347 app_limited=true congestion_recovery_start_time=None delivered=300 delivered_time=15.392916ms recent_delivered_packet_sent_time=41.751042ms app_limited_at_pkt=300  hystart=window_end=Some(0) last_round_min_rtt=None current_round_min_rtt=None rtt_sample_count=0 lss_start_time=None  
03-23 15:01:29.169 29195 29236 D QuicheLog: quiche: f8 tx pkt Short dcid=c3e66c4bc08390dc0c04433877f3d0e000e43660 key_phase=false len=47 pn=4
03-23 15:01:29.169 29195 29236 D QuicheLog: quiche: f8 tx frm STREAM id=14 off=0 len=26 fin=true
03-23 15:01:29.169 29195 29236 D QuicheLog: quiche::recovery: f8 timer=17.65354ms latest_rtt=6.373021ms srtt=Some(6.373021ms) min_rtt=6.373021ms rttvar=3.18651ms loss_time=[None, None, None] loss_probes=[0, 0, 0] cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=416 app_limited=true congestion_recovery_start_time=None delivered=300 delivered_time=15.626354ms recent_delivered_packet_sent_time=41.984427ms app_limited_at_pkt=300  hystart=window_end=Some(0) last_round_min_rtt=None current_round_min_rtt=None rtt_sample_count=0 lss_start_time=None  
03-23 15:01:29.169 29195 29236 D MyLog   : flush all data in socket 66
03-23 15:01:29.175 29195 29236 D QuicheLog: quiche: f8 rx pkt Handshake version=ff00001d dcid=f8 scid=c3e66c4bc08390dc0c04433877f3d0e000e43660 len=22 pn=5
03-23 15:01:29.175 29195 29236 D QuicheLog: quiche: f8 rx frm ACK delay=0 blocks=[0..1]
03-23 15:01:29.175 29195 29236 D QuicheLog: quiche::recovery: f8 packet newly acked 0
03-23 15:01:29.175 29195 29236 D QuicheLog: quiche::recovery: f8 packet newly acked 1
03-23 15:01:29.175 29195 29236 D QuicheLog: quiche: f8 rx pkt Short dcid=f8 key_phase=false len=480 pn=0
03-23 15:01:29.175 29195 29236 D QuicheLog: quiche: f8 rx frm CRYPTO off=0 len=428
03-23 15:01:29.175 29195 29236 D QuicheLog: quiche: f8 rx frm HANDSHAKE_DONE
03-23 15:01:29.176 29195 29236 D QuicheLog: quiche: f8 dropped epoch 1 state
03-23 15:01:29.176 29195 29236 D QuicheLog: quiche: f8 rx frm STREAM id=3 off=0 len=1 fin=false
03-23 15:01:29.176 29195 29236 D QuicheLog: quiche: f8 rx frm STREAM id=3 off=1 len=9 fin=false
03-23 15:01:29.176 29195 29236 D QuicheLog: quiche: f8 rx frm MAX_STREAM_DATA stream=2 max=65537
03-23 15:01:29.176 29195 29236 D QuicheLog: quiche: f8 rx frm MAX_STREAM_DATA stream=2 max=65555
03-23 15:01:29.176 29195 29236 D QuicheLog: quiche: f8 rx pkt Short dcid=f8 key_phase=false len=28 pn=1
03-23 15:01:29.176 29195 29236 D QuicheLog: quiche: f8 rx frm MAX_STREAM_DATA stream=6 max=65537
03-23 15:01:29.176 29195 29236 D QuicheLog: quiche: f8 rx frm ACK delay=0 blocks=[0..1]
03-23 15:01:29.176 29195 29236 D QuicheLog: quiche::recovery: f8 packet newly acked 0
03-23 15:01:29.176 29195 29236 D QuicheLog: quiche::recovery: f8 packet newly acked 1
03-23 15:01:29.176 29195 29236 D QuicheLog: quiche: f8 rx pkt Short dcid=f8 key_phase=false len=23 pn=2
03-23 15:01:29.176 29195 29236 D QuicheLog: quiche: f8 rx frm MAX_STREAM_DATA stream=10 max=65537
03-23 15:01:29.176 29195 29236 D QuicheLog: quiche: f8 rx pkt Short dcid=f8 key_phase=false len=22 pn=3
03-23 15:01:29.176 29195 29236 D QuicheLog: quiche: f8 rx frm ACK delay=0 blocks=[0..3]
03-23 15:01:29.176 29195 29236 D QuicheLog: quiche::recovery: f8 packet newly acked 2
03-23 15:01:29.176 29195 29236 D QuicheLog: quiche::recovery: f8 packet newly acked 3
03-23 15:01:29.176 29195 29236 D QuicheLog: quiche: f8 rx pkt Short dcid=f8 key_phase=false len=21 pn=4
03-23 15:01:29.176 29195 29236 D QuicheLog: quiche: f8 rx frm MAX_STREAMS type=uni max=17
03-23 15:01:29.176 29195 29236 D QuicheLog: quiche: f8 rx frm PADDING len=2
03-23 15:01:29.176 29195 29236 D MyLog   : Read finished.

@wxzcyy
Copy link

wxzcyy commented Dec 7, 2022

Do you solve the problem？

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Assignees
No one assigned
Labels
None yet
Projects
None yet
Milestone
No milestone
Development

No branches or pull requests
2 participants
@psionic12 @wxzcyy