search

microsoft / msquic

Cross-platform, C implementation of the IETF QUIC protocol, exposed to C, C++, C# and Rust.
MIT License
4.05k stars 530 forks source link

Failed to use self-signed certificate on client. #3495

Open FH0 opened 1 year ago

FH0 commented 1 year ago

Describe the bug

I want to use self-signed certificate on client instead of using QUIC_CREDENTIAL_FLAG_NO_CERTIFICATE_VALIDATION.

I test with src/tools/sample/sample.c with two additional line. image

Starting server:

# ./bin/Release/quicsample -server -cert_file:tls.crt -key_file:tls.key
Press Enter to exit.

[conn][0x7ffb38012fe0] Shut down by transport, 0xbebc32b
[conn][0x7ffb38012fe0] All done
[conn][0x7ffb38012fe0] Connected
[strm][0x7ffb3802f2a0] Peer started
[strm][0x7ffb3802f2a0] Data received
[strm][0x7ffb3802f2a0] Peer shut down
[strm][0x7ffb3802f2a0] Sending data...
[strm][0x7ffb3802f2a0] Data sent
[strm][0x7ffb3802f2a0] All done
[conn][0x7ffb38012fe0] Successfully shut down on idle.
[conn][0x7ffb38012fe0] All done

Starting client. The result of the first run should be the same as the second because I added the certificate manually.

# ./bin/Release/quicsample -client -target:localhost
[conn][0x55c334058330] Connecting...
[conn][0x55c334058330] Shut down by transport, 0xbebc32b
[conn][0x55c334058330] All done

# ./bin/Release/quicsample -client -target:localhost -unsecure
[conn][0x5631d67ba330] Connecting...
[conn][0x5631d67ba330] Connected
[strm][0x7f66a8015270] Starting...
[strm][0x7f66a8015270] Sending data...
[strm][0x7f66a8015270] Data sent
[conn][0x5631d67ba330] Resumption ticket received (1198 bytes):
0100000001314475010243E8030245C00404810000000504800100000604800100000704800100000801010E0104C0000000FF03DE1A0243E82D2D2D2D2D424547494E2053534C2053455353494F4E20504152414D45544552532D2D2D2D2D0A4D49494444674942415149434177514541684D43424343656438746C472F354F56414B54314F686F69757A3636654D4A4D6E344C3468456B586E78644150726E0A514151776D7A6B6843566A7051654E766476654B794A616A4E612F7650597145305A3073357A584B366647725453673376676A5A564D4C6E4D777648444F6F430A493979666F5159434247514C6E753269424149434843436A676746664D494942577A43434151476741774942416749514962664863536F6E344E544D74554F790A4E57576D7644414B42676771686B6A4F50515144416A41414D4234584454497A4D444D784D4449784D5459304F566F58445449304D444D774F5449784D5459300A4F566F774144425A4D424D4742797147534D34394167454743437147534D34394177454841304941424E42586E4138566D314371654E4D78736535317931492B0A7471627345704B76696D593839352B4A317345756C573668595477794D776D33372B4B4C685A7A467449594C6B2F76667A346D6C7A794B4C3078745A5142326A0A585442624D41344741315564447745422F775145417749434244415442674E56485355454444414B4267677242674546425163444154414D42674E5648524D420A41663845416A41414D43594741315564455145422F7751634D42714344536F755A586868625842735A53356A6232324343577876593246736147397A6444414B0A42676771686B6A4F5051514441674E49414442464169423073763036506B6A78446E33467A5332706A615A656B32494D6A2B73557765474E67506C496368394A0A51514968414B3663506F557634447753747176353642444C722F7979654A4961715A516A67626574636F5856326F6B6D70414945414B6B4541674963494B71430A4152514567674551696B59524673662B31523255446F4866794431555846476732495335742B4A6B6F4E55635045686C4F5931367A33426B6959447A553146450A454E5A55705A4B4E3756344362537A497562646754733450362F56374D4C457264303258447934557858374A2B7A63447467685768624855753855654A6849530A7A504B56316567447054704D4655747457507858685867653265775073385575626765754B304B5458446E7446727032435467345633476C31344E59366657340A763254567248455A4B48424B317244354A64646F5962746C6462675A77396339467745577A444E4466344678762B694D74344B316138716C6F716775433866550A396535412F4F385270776C39437072545A2B4B4B44464544654A566C756241664F6957617874687439372B705574486A466737723830456A7573514B4A4A316E0A624835666E4169397962486F6F6A754D522B75676A65647638665631574E424A6F3641504D303734694546707859334F3455697542774946414D4136637236760A4277494641502F2F2F2F2B7743415147633246746347786C0A2D2D2D2D2D454E442053534C2053455353494F4E20504152414D45544552532D2D2D2D2D0A
[strm][0x7f66a8015270] Data received
[strm][0x7f66a8015270] Peer shut down
[strm][0x7f66a8015270] All done
[conn][0x5631d67ba330] Successfully shut down on idle.
[conn][0x5631d67ba330] All done

tls.crt:

# openssl x509 -noout -text -in tls.crt
Certificate:
    Data:
        Version: 3 (0x2)
        Serial Number:
            21:b7:c7:71:2a:27:e0:d4:cc:b5:43:b2:35:65:a6:bc
        Signature Algorithm: ecdsa-with-SHA256
        Issuer:
        Validity
            Not Before: Mar 10 21:16:49 2023 GMT
            Not After : Mar  9 21:16:49 2024 GMT
        Subject:
        Subject Public Key Info:
            Public Key Algorithm: id-ecPublicKey
                Public-Key: (256 bit)
                pub:
                    04:d0:57:9c:0f:15:9b:50:aa:78:d3:31:b1:ee:75:
                    cb:52:3e:b6:a6:ec:12:92:af:8a:66:3c:f7:9f:89:
                    d6:c1:2e:95:6e:a1:61:3c:32:33:09:b7:ef:e2:8b:
                    85:9c:c5:b4:86:0b:93:fb:df:cf:89:a5:cf:22:8b:
                    d3:1b:59:40:1d
                ASN1 OID: prime256v1
                NIST CURVE: P-256
        X509v3 extensions:
            X509v3 Key Usage: critical
                Certificate Sign
            X509v3 Extended Key Usage:
                TLS Web Server Authentication
            X509v3 Basic Constraints: critical
                CA:FALSE
            X509v3 Subject Alternative Name: critical
                DNS:*.example.com, DNS:localhost
    Signature Algorithm: ecdsa-with-SHA256
         30:45:02:20:74:b2:fd:3a:3e:48:f1:0e:7d:c5:cd:2d:a9:8d:
         a6:5e:93:62:0c:8f:eb:14:c1:e1:8d:80:f9:48:72:1f:49:41:
         02:21:00:ae:9c:3e:85:2f:e0:3c:12:b6:ab:f9:e8:10:cb:af:
         fc:b2:78:92:1a:a9:94:23:81:b7:ad:72:85:d5:da:89:26

tls.crt.txt tls.key.txt

Affected OS

Additional OS information

No response

MsQuic version

main

Steps taken to reproduce bug

.

Expected behavior

.

Actual outcome

.

Additional details

No response

nibanks commented 1 year ago

Could you please collect and share the logs?

FH0 commented 1 year ago

I installed donet-6.0 and started ./scripts/prepare-machine.ps1 -ForTest.

I try to run cmake with '-DDEBUG=1' and run make with DEBUG=1.

I try to use log_wrapper.sh and step-by-step-command.

All these attempts have failed. I am sorry that I cannot provide logs.

# ./scripts/log_wrapper.sh ./x86_64-linux-gnu/bin/Release/quicsample -server -cert_file:tls.crt -key_file:tls.key
Spawning a session daemon
Session msquic created.
Traces will be output to /root/go/src/github.com/isrc-cas/gt/dep/msquic/msquic_lttng6/data
UST event CLOG_* created in channel channel0
UST context vpid added to all channels
UST context vtid added to all channels
Tracing started for session msquic
Press Enter to exit.

[conn][0x7f0d3c012fe0] Shut down by transport, 0xbebc330
[conn][0x7f0d3c012fe0] All done

Error: Can't find valid lttng config /root/.lttngrc
Did you create a session? (lttng create <my_session>)
Error: Session name msquic not found
[error] Cannot open any trace for reading.

[error] opening trace "msquic_lttng6/data" for reading.

[error] none of the specified trace paths could be opened.
# ./scripts/log_wrapper.sh ./x86_64-linux-gnu/bin/Release/quicsample -client -target:localhost
Destroying session msquic..
Session msquic destroyed
Session msquic created.
Traces will be output to /root/go/src/github.com/isrc-cas/gt/dep/msquic/msquic_lttng7/data
UST event CLOG_* created in channel channel0
UST context vpid added to all channels
UST context vtid added to all channels
Tracing started for session msquic
[conn][0x5583517bfec0] Connecting...
[conn][0x5583517bfec0] Shut down by transport, 0xbebc330
[conn][0x5583517bfec0] All done
Waiting for data availability
Tracing stopped for session msquic
Destroying session msquic..
Session msquic destroyed
[error] Cannot open any trace for reading.

[error] opening trace "msquic_lttng7/data" for reading.

[error] none of the specified trace paths could be opened.
FH0 commented 1 year ago

If you add the above two lines and compile src/tools/sample/sample.c, then run the client and server with the tls.key and tls.cert I provided without finding any problems, it would be unbelievable.

By the way, am I using my self-signed certificate correctly? I find some docs. But it is still hard for me to understand.

FH0 commented 1 year ago

-DQUIC_ENABLE_LOGGING=ON -DQUIC_ENABLE_LOGGING=lttng should be passed to cmake.

server log

```js [6][75e6.75e6][06:12:11.235293][ dso] Loaded [6][75e6.75e6][06:12:11.235300][ api] MsQuicOpenVersion [6][75e6.75e6][06:12:11.235791][ dso] Initialized (AvailMem = 10443476992 bytes) [6][75e6.75e6][06:12:11.235796][ lib] Failed to open global settings, 0x95 [6][75e6.75e6][06:12:11.235798][ lib] Settings 0x559e8136b420 Updated [6][75e6.75e6][06:12:11.235799][sett] SendBufferingEnabled = 1 [6][75e6.75e6][06:12:11.235799][sett] PacingEnabled = 1 [6][75e6.75e6][06:12:11.235799][sett] MigrationEnabled = 1 [6][75e6.75e6][06:12:11.235800][sett] DatagramReceiveEnabled = 0 [6][75e6.75e6][06:12:11.235800][sett] MaxOperationsPerDrain = 16 [6][75e6.75e6][06:12:11.235800][sett] RetryMemoryLimit = 65 [6][75e6.75e6][06:12:11.235801][sett] LoadBalancingMode = 0 [6][75e6.75e6][06:12:11.235801][sett] FixedServerID = 0 [6][75e6.75e6][06:12:11.235801][sett] MaxStatelessOperations = 16 [6][75e6.75e6][06:12:11.235801][sett] MaxWorkerQueueDelayUs = 250000 [6][75e6.75e6][06:12:11.235802][sett] InitialWindowPackets = 10 [6][75e6.75e6][06:12:11.235802][sett] SendIdleTimeoutMs = 1000 [6][75e6.75e6][06:12:11.235802][sett] InitialRttMs = 333 [6][75e6.75e6][06:12:11.235802][sett] MaxAckDelayMs = 25 [6][75e6.75e6][06:12:11.235802][sett] DisconnectTimeoutMs = 16000 [6][75e6.75e6][06:12:11.235803][sett] KeepAliveIntervalMs = 0 [6][75e6.75e6][06:12:11.235803][sett] IdleTimeoutMs = 30000 [6][75e6.75e6][06:12:11.235803][sett] HandshakeIdleTimeoutMs = 10000 [6][75e6.75e6][06:12:11.235803][sett] PeerBidiStreamCount = 0 [6][75e6.75e6][06:12:11.235804][sett] PeerUnidiStreamCount = 0 [6][75e6.75e6][06:12:11.235804][sett] TlsClientMaxSendBuffer = 8192 [6][75e6.75e6][06:12:11.235804][sett] TlsServerMaxSendBuffer = 0 [6][75e6.75e6][06:12:11.235804][sett] StreamRecvWindowDefault= 65536 [6][75e6.75e6][06:12:11.235805][sett] StreamRecvBufferDefault= 4096 [6][75e6.75e6][06:12:11.235805][sett] ConnFlowControlWindow = 16777216 [6][75e6.75e6][06:12:11.235805][sett] MaxBytesPerKey = 274877906944 [6][75e6.75e6][06:12:11.235805][sett] ServerResumptionLevel = 0 [6][75e6.75e6][06:12:11.235806][sett] Version Negotiation Ext Enabled = 0 [6][75e6.75e6][06:12:11.235806][sett] MinimumMtu = 1288 [6][75e6.75e6][06:12:11.235806][sett] MaximumMtu = 1500 [6][75e6.75e6][06:12:11.235806][sett] MtuCompleteTimeout = 600000000 [6][75e6.75e6][06:12:11.235807][sett] MtuMissingProbeCount = 3 [6][75e6.75e6][06:12:11.235807][sett] MaxBindingStatelessOper= 100 [6][75e6.75e6][06:12:11.235807][sett] StatelessOperExpirMs = 100 [6][75e6.75e6][06:12:11.235808][sett] CongestionControlAlgorithm = 0 [6][75e6.75e6][06:12:11.235808][sett] DestCidUpdateIdleTimeoutMs = 20000 [6][75e6.75e6][06:12:11.235808][sett] GreaseQuicBitEnabled = 0 [6][75e6.75e6][06:12:11.235808][sett] EcnEnabled = 0 [6][75e6.75e6][06:12:11.235809][sett] HyStartEnabled = 0 [6][75e6.75e6][06:12:11.235809][ lib] CID Length = 9 [6][75e6.75e6][06:12:11.235820][ lib] Initialized, PartitionCount=12 [6][75e6.75e6][06:12:11.235820][ lib] Version 2.2.0.0 [6][75e6.75e6][06:12:11.235820][ lib] AddRef [6][75e6.75e6][06:12:11.235821][ api] MsQuicOpenVersion, status=0x0 [6][75e6.75e6][06:12:11.235821][ api] Enter 2 (0x0). [0][75e6.75e9][06:12:11.235926][ lib][0x559e8285dff0] Worker start [1][75e6.75ea][06:12:11.235932][ lib][0x559e8285e060] Worker start [2][75e6.75eb][06:12:11.235949][ lib][0x559e8285e0d0] Worker start [3][75e6.75ec][06:12:11.236396][ lib][0x559e8285e140] Worker start [4][75e6.75ed][06:12:11.236398][ lib][0x559e8285e1b0] Worker start [5][75e6.75ee][06:12:11.236418][ lib][0x559e8285e220] Worker start [7][75e6.75f0][06:12:11.236455][ lib][0x559e8285e300] Worker start [8][75e6.75f1][06:12:11.236479][ lib][0x559e8285e370] Worker start [9][75e6.75f2][06:12:11.236510][ lib][0x559e8285e3e0] Worker start [10][75e6.75f3][06:12:11.236927][ lib][0x559e8285e450] Worker start [6][75e6.75ef][06:12:11.236944][ lib][0x559e8285e290] Worker start [11][75e6.75f4][06:12:11.236951][ lib][0x559e8285e4c0] Worker start [8][75e6.75e6][06:12:11.237313][data] Initialized, DatapathFeatures=12 [8][75e6.75e6][06:12:11.237324][wrkr][0x559e82861410] Created, IdealProc=0 Owner=0x559e82850500 [8][75e6.75e6][06:12:11.237336][wrkr][0x559e82861b50] Created, IdealProc=1 Owner=0x559e82850500 [0][75e6.75e9][06:12:11.237341][wrkr][0x559e82861410] IsActive = 1, Arg = 1651856383 [0][75e6.75e9][06:12:11.237342][wrkr][0x559e82861410] IsActive = 0, Arg = 4294967295 [0][75e6.75e9][06:12:11.237342][wrkr][0x559e82861410] QueueDelay = 0 [8][75e6.75e6][06:12:11.237345][wrkr][0x559e82862290] Created, IdealProc=2 Owner=0x559e82850500 [8][75e6.75e6][06:12:11.237355][wrkr][0x559e828629d0] Created, IdealProc=3 Owner=0x559e82850500 [1][75e6.75ea][06:12:11.237357][wrkr][0x559e82861b50] IsActive = 1, Arg = 1651856398 [1][75e6.75ea][06:12:11.237357][wrkr][0x559e82861b50] IsActive = 0, Arg = 4294967295 [1][75e6.75ea][06:12:11.237358][wrkr][0x559e82861b50] QueueDelay = 0 [2][75e6.75eb][06:12:11.237360][wrkr][0x559e82862290] IsActive = 1, Arg = 1651856403 [2][75e6.75eb][06:12:11.237360][wrkr][0x559e82862290] IsActive = 0, Arg = 4294967295 [2][75e6.75eb][06:12:11.237361][wrkr][0x559e82862290] QueueDelay = 0 [8][75e6.75e6][06:12:11.237366][wrkr][0x559e82863110] Created, IdealProc=4 Owner=0x559e82850500 [3][75e6.75ec][06:12:11.237373][wrkr][0x559e828629d0] IsActive = 1, Arg = 1651856415 [3][75e6.75ec][06:12:11.237374][wrkr][0x559e828629d0] IsActive = 0, Arg = 4294967295 [3][75e6.75ec][06:12:11.237374][wrkr][0x559e828629d0] QueueDelay = 0 [8][75e6.75e6][06:12:11.237376][wrkr][0x559e82863850] Created, IdealProc=5 Owner=0x559e82850500 [4][75e6.75ed][06:12:11.237384][wrkr][0x559e82863110] IsActive = 1, Arg = 1651856426 [4][75e6.75ed][06:12:11.237385][wrkr][0x559e82863110] IsActive = 0, Arg = 4294967295 [4][75e6.75ed][06:12:11.237385][wrkr][0x559e82863110] QueueDelay = 0 [8][75e6.75e6][06:12:11.237387][wrkr][0x559e82863f90] Created, IdealProc=6 Owner=0x559e82850500 [5][75e6.75ee][06:12:11.237396][wrkr][0x559e82863850] IsActive = 1, Arg = 1651856438 [5][75e6.75ee][06:12:11.237396][wrkr][0x559e82863850] IsActive = 0, Arg = 4294967295 [5][75e6.75ee][06:12:11.237396][wrkr][0x559e82863850] QueueDelay = 0 [8][75e6.75e6][06:12:11.237400][wrkr][0x559e828646d0] Created, IdealProc=7 Owner=0x559e82850500 [6][75e6.75ef][06:12:11.237407][wrkr][0x559e82863f90] IsActive = 1, Arg = 1651856450 [6][75e6.75ef][06:12:11.237407][wrkr][0x559e82863f90] IsActive = 0, Arg = 4294967295 [6][75e6.75ef][06:12:11.237408][wrkr][0x559e82863f90] QueueDelay = 0 [8][75e6.75e6][06:12:11.237412][wrkr][0x559e82864e10] Created, IdealProc=8 Owner=0x559e82850500 [8][75e6.75e6][06:12:11.237414][wrkr][0x559e82865550] Created, IdealProc=9 Owner=0x559e82850500 [7][75e6.75f0][06:12:11.237420][wrkr][0x559e828646d0] IsActive = 1, Arg = 1651856462 [7][75e6.75f0][06:12:11.237421][wrkr][0x559e828646d0] IsActive = 0, Arg = 4294967295 [7][75e6.75f0][06:12:11.237421][wrkr][0x559e828646d0] QueueDelay = 0 [8][75e6.75e6][06:12:11.237425][wrkr][0x559e82865c90] Created, IdealProc=10 Owner=0x559e82850500 [9][75e6.75f2][06:12:11.237434][wrkr][0x559e82865550] IsActive = 1, Arg = 1651856476 [9][75e6.75f2][06:12:11.237434][wrkr][0x559e82865550] IsActive = 0, Arg = 4294967295 [9][75e6.75f2][06:12:11.237434][wrkr][0x559e82865550] QueueDelay = 0 [8][75e6.75e6][06:12:11.237437][wrkr][0x559e828663d0] Created, IdealProc=11 Owner=0x559e82850500 [10][75e6.75f3][06:12:11.237445][wrkr][0x559e82865c90] IsActive = 1, Arg = 1651856488 [10][75e6.75f3][06:12:11.237446][wrkr][0x559e82865c90] IsActive = 0, Arg = 4294967295 [10][75e6.75f3][06:12:11.237446][wrkr][0x559e82865c90] QueueDelay = 0 [8][75e6.75e6][06:12:11.237449][ reg][0x559e82850500] Created, AppName=quicsample, ExecProfile=0 [8][75e6.75e6][06:12:11.237450][ api] Exit 0 [8][75e6.75e6][06:12:11.237456][ api] Enter 5 (0x559e82850500). [8][75e6.75e6][06:12:11.237457][cnfg][0x559e8285d720] Failed to open app specific settings, 0x95 [11][75e6.75f4][06:12:11.237457][wrkr][0x559e828663d0] IsActive = 1, Arg = 1651856500 [8][75e6.75e6][06:12:11.237458][cnfg][0x559e8285d720] Created, Registration=0x559e82850500 [11][75e6.75f4][06:12:11.237458][wrkr][0x559e828663d0] IsActive = 0, Arg = 4294967295 [8][75e6.75e6][06:12:11.237458][cnfg][0x559e8285d720] Settings 0x559e8285d760 Updated [11][75e6.75f4][06:12:11.237458][wrkr][0x559e828663d0] QueueDelay = 0 [8][75e6.75e6][06:12:11.237459][sett] SendBufferingEnabled = 1 [8][75e6.75e6][06:12:11.237459][sett] PacingEnabled = 1 [8][75e6.75e6][06:12:11.237459][sett] MigrationEnabled = 1 [8][75e6.75e6][06:12:11.237459][sett] DatagramReceiveEnabled = 0 [8][75e6.75e6][06:12:11.237459][sett] MaxOperationsPerDrain = 16 [8][75e6.75e6][06:12:11.237460][sett] RetryMemoryLimit = 65 [8][75e6.75e6][06:12:11.237460][sett] LoadBalancingMode = 0 [8][75e6.75e6][06:12:11.237460][sett] FixedServerID = 0 [8][75e6.75e6][06:12:11.237460][sett] MaxStatelessOperations = 16 [8][75e6.75e6][06:12:11.237461][sett] MaxWorkerQueueDelayUs = 250000 [8][75e6.75e6][06:12:11.237461][sett] InitialWindowPackets = 10 [8][75e6.75e6][06:12:11.237461][sett] SendIdleTimeoutMs = 1000 [8][75e6.75e6][06:12:11.237461][sett] InitialRttMs = 333 [8][75e6.75e6][06:12:11.237461][sett] MaxAckDelayMs = 25 [8][75e6.75e6][06:12:11.237462][sett] DisconnectTimeoutMs = 16000 [8][75e6.75e6][06:12:11.237462][sett] KeepAliveIntervalMs = 0 [8][75e6.75e6][06:12:11.237462][sett] IdleTimeoutMs = 1000 [8][75e6.75e6][06:12:11.237462][sett] HandshakeIdleTimeoutMs = 10000 [8][75e6.75e6][06:12:11.237462][sett] PeerBidiStreamCount = 1 [8][75e6.75e6][06:12:11.237463][sett] PeerUnidiStreamCount = 0 [8][75e6.75e6][06:12:11.237463][sett] TlsClientMaxSendBuffer = 8192 [8][75e6.75e6][06:12:11.237463][sett] TlsServerMaxSendBuffer = 0 [8][75e6.75e6][06:12:11.237463][sett] StreamRecvWindowDefault= 65536 [8][75e6.75e6][06:12:11.237463][sett] StreamRecvBufferDefault= 4096 [8][75e6.75e6][06:12:11.237464][sett] ConnFlowControlWindow = 16777216 [8][75e6.75e6][06:12:11.237464][sett] MaxBytesPerKey = 274877906944 [8][75e6.75e6][06:12:11.237464][sett] ServerResumptionLevel = 2 [8][75e6.75e6][06:12:11.237464][sett] Version Negotiation Ext Enabled = 0 [8][75e6.75e6][06:12:11.237465][sett] MinimumMtu = 1288 [8][75e6.75e6][06:12:11.237465][sett] MaximumMtu = 1500 [8][75e6.75e6][06:12:11.237465][sett] MtuCompleteTimeout = 600000000 [8][75e6.75e6][06:12:11.237465][sett] MtuMissingProbeCount = 3 [8][75e6.75e6][06:12:11.237465][sett] MaxBindingStatelessOper= 100 [8][75e6.75e6][06:12:11.237466][sett] StatelessOperExpirMs = 100 [8][75e6.75e6][06:12:11.237466][sett] CongestionControlAlgorithm = 0 [8][75e6.75e6][06:12:11.237466][sett] DestCidUpdateIdleTimeoutMs = 20000 [8][75e6.75e6][06:12:11.237466][sett] GreaseQuicBitEnabled = 0 [8][75e6.75e6][06:12:11.237467][sett] EcnEnabled = 0 [8][75e6.75e6][06:12:11.237467][sett] HyStartEnabled = 0 [8][75e6.75e6][06:12:11.237467][ api] Exit 0 [8][75e6.75e6][06:12:11.237467][ api] Enter 7 (0x559e8285d720). [8][75e6.75e6][06:12:11.237673][ api] Exit 0 [8][75e6.75e6][06:12:11.237675][ api] Enter 8 (0x559e82850500). [8][75e6.75e6][06:12:11.237676][list][0x559e82871c30] Created, Registration=0x559e82850500 [8][75e6.75e6][06:12:11.237676][ api] Exit 0 [8][75e6.75e6][06:12:11.237677][ api] Enter 10 (0x559e82871c30). [8][75e6.75e6][06:12:11.237677][ lib] Shared server state initializing [8][75e6.75e6][06:12:11.237677][ api] Enter 2 (0x0). [8][75e6.75e6][06:12:11.237683][wrkr][0x559e82873170] Created, IdealProc=0 Owner=0x559e8286eb90 [8][75e6.75e6][06:12:11.237699][wrkr][0x559e828738b0] Created, IdealProc=1 Owner=0x559e8286eb90 [0][75e6.75e9][06:12:11.237703][wrkr][0x559e82873170] IsActive = 1, Arg = 1651856746 [0][75e6.75e9][06:12:11.237704][wrkr][0x559e82873170] IsActive = 0, Arg = 4294967295 [0][75e6.75e9][06:12:11.237704][wrkr][0x559e82873170] QueueDelay = 0 [8][75e6.75e6][06:12:11.237709][wrkr][0x559e82873ff0] Created, IdealProc=2 Owner=0x559e8286eb90 [1][75e6.75ea][06:12:11.237716][wrkr][0x559e828738b0] IsActive = 1, Arg = 1651856758 [1][75e6.75ea][06:12:11.237716][wrkr][0x559e828738b0] IsActive = 0, Arg = 4294967295 [1][75e6.75ea][06:12:11.237716][wrkr][0x559e828738b0] QueueDelay = 0 [8][75e6.75e6][06:12:11.237719][wrkr][0x559e82874730] Created, IdealProc=3 Owner=0x559e8286eb90 [2][75e6.75eb][06:12:11.237724][wrkr][0x559e82873ff0] IsActive = 1, Arg = 1651856766 [2][75e6.75eb][06:12:11.237724][wrkr][0x559e82873ff0] IsActive = 0, Arg = 4294967295 [2][75e6.75eb][06:12:11.237725][wrkr][0x559e82873ff0] QueueDelay = 0 [8][75e6.75e6][06:12:11.237729][wrkr][0x559e82874e70] Created, IdealProc=4 Owner=0x559e8286eb90 [3][75e6.75ec][06:12:11.237735][wrkr][0x559e82874730] IsActive = 1, Arg = 1651856778 [3][75e6.75ec][06:12:11.237735][wrkr][0x559e82874730] IsActive = 0, Arg = 4294967295 [3][75e6.75ec][06:12:11.237736][wrkr][0x559e82874730] QueueDelay = 0 [8][75e6.75e6][06:12:11.237739][wrkr][0x559e828755b0] Created, IdealProc=5 Owner=0x559e8286eb90 [4][75e6.75ed][06:12:11.237745][wrkr][0x559e82874e70] IsActive = 1, Arg = 1651856787 [4][75e6.75ed][06:12:11.237745][wrkr][0x559e82874e70] IsActive = 0, Arg = 4294967295 [4][75e6.75ed][06:12:11.237745][wrkr][0x559e82874e70] QueueDelay = 0 [8][75e6.75e6][06:12:11.237749][wrkr][0x559e82875cf0] Created, IdealProc=6 Owner=0x559e8286eb90 [5][75e6.75ee][06:12:11.237756][wrkr][0x559e828755b0] IsActive = 1, Arg = 1651856799 [5][75e6.75ee][06:12:11.237757][wrkr][0x559e828755b0] IsActive = 0, Arg = 4294967295 [5][75e6.75ee][06:12:11.237757][wrkr][0x559e828755b0] QueueDelay = 0 [8][75e6.75e6][06:12:11.237760][wrkr][0x559e82876430] Created, IdealProc=7 Owner=0x559e8286eb90 [6][75e6.75ef][06:12:11.237765][wrkr][0x559e82875cf0] IsActive = 1, Arg = 1651856808 [6][75e6.75ef][06:12:11.237766][wrkr][0x559e82875cf0] IsActive = 0, Arg = 4294967295 [6][75e6.75ef][06:12:11.237766][wrkr][0x559e82875cf0] QueueDelay = 0 [8][75e6.75e6][06:12:11.237770][wrkr][0x559e82876b70] Created, IdealProc=8 Owner=0x559e8286eb90 [8][75e6.75e6][06:12:11.237771][wrkr][0x559e828772b0] Created, IdealProc=9 Owner=0x559e8286eb90 [7][75e6.75f0][06:12:11.237776][wrkr][0x559e82876430] IsActive = 1, Arg = 1651856819 [7][75e6.75f0][06:12:11.237776][wrkr][0x559e82876430] IsActive = 0, Arg = 4294967295 [7][75e6.75f0][06:12:11.237777][wrkr][0x559e82876430] QueueDelay = 0 [8][75e6.75e6][06:12:11.237781][wrkr][0x559e828779f0] Created, IdealProc=10 Owner=0x559e8286eb90 [9][75e6.75f2][06:12:11.237787][wrkr][0x559e828772b0] IsActive = 1, Arg = 1651856829 [9][75e6.75f2][06:12:11.237788][wrkr][0x559e828772b0] IsActive = 0, Arg = 4294967295 [9][75e6.75f2][06:12:11.237788][wrkr][0x559e828772b0] QueueDelay = 0 [8][75e6.75e6][06:12:11.237792][wrkr][0x559e82878130] Created, IdealProc=11 Owner=0x559e8286eb90 [10][75e6.75f3][06:12:11.237798][wrkr][0x559e828779f0] IsActive = 1, Arg = 1651856841 [10][75e6.75f3][06:12:11.237799][wrkr][0x559e828779f0] IsActive = 0, Arg = 4294967295 [10][75e6.75f3][06:12:11.237799][wrkr][0x559e828779f0] QueueDelay = 0 [8][75e6.75e6][06:12:11.237802][ reg][0x559e8286eb90] Created, AppName=Stateless, ExecProfile=255 [8][75e6.75e6][06:12:11.237803][ api] Exit 0 [11][75e6.75f4][06:12:11.237808][wrkr][0x559e82878130] IsActive = 1, Arg = 1651856851 [11][75e6.75f4][06:12:11.237809][wrkr][0x559e82878130] IsActive = 0, Arg = 4294967295 [11][75e6.75f4][06:12:11.237809][wrkr][0x559e82878130] QueueDelay = 0 [8][75e6.75e6][06:12:11.237810][data][0x559e82879cb0] Created, local=[::]:4567, remote=None [8][75e6.75e6][06:12:11.238524][bind][0x559e828792d0] Created, Udp=0x559e82879cb0 LocalAddr=[::]:4567 RemoteAddr=*:0 [8][75e6.75e6][06:12:11.238525][ lib] Now in use. [8][75e6.75e6][06:12:11.238535][list][0x559e82871c30] Started, Binding=0x559e828792d0, LocalAddr=*:4567, ALPN=sample [8][75e6.75e6][06:12:11.238535][ api] Exit 0 [8][75e6.75f1][06:12:11.238556][wrkr][0x559e82876b70] IsActive = 1, Arg = 1651857599 [8][75e6.75f1][06:12:11.238557][wrkr][0x559e82876b70] IsActive = 0, Arg = 4294967295 [8][75e6.75f1][06:12:11.238557][wrkr][0x559e82876b70] QueueDelay = 0 [8][75e6.75f1][06:12:11.238557][wrkr][0x559e82864e10] IsActive = 1, Arg = 1651857599 [8][75e6.75f1][06:12:11.238557][wrkr][0x559e82864e10] IsActive = 0, Arg = 4294967295 [8][75e6.75f1][06:12:11.238558][wrkr][0x559e82864e10] QueueDelay = 0 ```

client log

```js [4][75ff.75ff][06:12:14.883285][ dso] Loaded [4][75ff.75ff][06:12:14.883290][ api] MsQuicOpenVersion [4][75ff.75ff][06:12:14.883792][ dso] Initialized (AvailMem = 10443476992 bytes) [4][75ff.75ff][06:12:14.883797][ lib] Failed to open global settings, 0x95 [4][75ff.75ff][06:12:14.883799][ lib] Settings 0x56159566d420 Updated [4][75ff.75ff][06:12:14.883801][sett] SendBufferingEnabled = 1 [4][75ff.75ff][06:12:14.883802][sett] PacingEnabled = 1 [4][75ff.75ff][06:12:14.883802][sett] MigrationEnabled = 1 [4][75ff.75ff][06:12:14.883802][sett] DatagramReceiveEnabled = 0 [4][75ff.75ff][06:12:14.883803][sett] MaxOperationsPerDrain = 16 [4][75ff.75ff][06:12:14.883803][sett] RetryMemoryLimit = 65 [4][75ff.75ff][06:12:14.883803][sett] LoadBalancingMode = 0 [4][75ff.75ff][06:12:14.883803][sett] FixedServerID = 0 [4][75ff.75ff][06:12:14.883804][sett] MaxStatelessOperations = 16 [4][75ff.75ff][06:12:14.883804][sett] MaxWorkerQueueDelayUs = 250000 [4][75ff.75ff][06:12:14.883804][sett] InitialWindowPackets = 10 [4][75ff.75ff][06:12:14.883804][sett] SendIdleTimeoutMs = 1000 [4][75ff.75ff][06:12:14.883805][sett] InitialRttMs = 333 [4][75ff.75ff][06:12:14.883805][sett] MaxAckDelayMs = 25 [4][75ff.75ff][06:12:14.883805][sett] DisconnectTimeoutMs = 16000 [4][75ff.75ff][06:12:14.883805][sett] KeepAliveIntervalMs = 0 [4][75ff.75ff][06:12:14.883806][sett] IdleTimeoutMs = 30000 [4][75ff.75ff][06:12:14.883806][sett] HandshakeIdleTimeoutMs = 10000 [4][75ff.75ff][06:12:14.883806][sett] PeerBidiStreamCount = 0 [4][75ff.75ff][06:12:14.883806][sett] PeerUnidiStreamCount = 0 [4][75ff.75ff][06:12:14.883807][sett] TlsClientMaxSendBuffer = 8192 [4][75ff.75ff][06:12:14.883807][sett] TlsServerMaxSendBuffer = 0 [4][75ff.75ff][06:12:14.883807][sett] StreamRecvWindowDefault= 65536 [4][75ff.75ff][06:12:14.883807][sett] StreamRecvBufferDefault= 4096 [4][75ff.75ff][06:12:14.883808][sett] ConnFlowControlWindow = 16777216 [4][75ff.75ff][06:12:14.883808][sett] MaxBytesPerKey = 274877906944 [4][75ff.75ff][06:12:14.883808][sett] ServerResumptionLevel = 0 [4][75ff.75ff][06:12:14.883808][sett] Version Negotiation Ext Enabled = 0 [4][75ff.75ff][06:12:14.883809][sett] MinimumMtu = 1288 [4][75ff.75ff][06:12:14.883809][sett] MaximumMtu = 1500 [4][75ff.75ff][06:12:14.883809][sett] MtuCompleteTimeout = 600000000 [4][75ff.75ff][06:12:14.883810][sett] MtuMissingProbeCount = 3 [4][75ff.75ff][06:12:14.883810][sett] MaxBindingStatelessOper= 100 [4][75ff.75ff][06:12:14.883810][sett] StatelessOperExpirMs = 100 [4][75ff.75ff][06:12:14.883810][sett] CongestionControlAlgorithm = 0 [4][75ff.75ff][06:12:14.883811][sett] DestCidUpdateIdleTimeoutMs = 20000 [4][75ff.75ff][06:12:14.883811][sett] GreaseQuicBitEnabled = 0 [4][75ff.75ff][06:12:14.883811][sett] EcnEnabled = 0 [4][75ff.75ff][06:12:14.883811][sett] HyStartEnabled = 0 [4][75ff.75ff][06:12:14.883812][ lib] CID Length = 9 [4][75ff.75ff][06:12:14.883822][ lib] Initialized, PartitionCount=12 [4][75ff.75ff][06:12:14.883823][ lib] Version 2.2.0.0 [4][75ff.75ff][06:12:14.883823][ lib] AddRef [4][75ff.75ff][06:12:14.883824][ api] MsQuicOpenVersion, status=0x0 [4][75ff.75ff][06:12:14.883824][ api] Enter 2 (0x0). [1][75ff.7603][06:12:14.883928][ lib][0x561596e16060] Worker start [0][75ff.7602][06:12:14.883936][ lib][0x561596e15ff0] Worker start [2][75ff.7604][06:12:14.883952][ lib][0x561596e160d0] Worker start [3][75ff.7605][06:12:14.884109][ lib][0x561596e16140] Worker start [5][75ff.7607][06:12:14.884159][ lib][0x561596e16220] Worker start [6][75ff.7608][06:12:14.884189][ lib][0x561596e16290] Worker start [7][75ff.7609][06:12:14.884214][ lib][0x561596e16300] Worker start [8][75ff.760a][06:12:14.884239][ lib][0x561596e16370] Worker start [9][75ff.760b][06:12:14.884268][ lib][0x561596e163e0] Worker start [10][75ff.760c][06:12:14.884429][ lib][0x561596e16450] Worker start [11][75ff.760d][06:12:14.884460][ lib][0x561596e164c0] Worker start [4][75ff.75ff][06:12:14.884816][data] Initialized, DatapathFeatures=12 [4][75ff.75ff][06:12:14.884826][wrkr][0x561596e19410] Created, IdealProc=0 Owner=0x561596e08500 [4][75ff.75ff][06:12:14.884837][wrkr][0x561596e19b50] Created, IdealProc=1 Owner=0x561596e08500 [0][75ff.7602][06:12:14.884844][wrkr][0x561596e19410] IsActive = 1, Arg = 1655503886 [0][75ff.7602][06:12:14.884845][wrkr][0x561596e19410] IsActive = 0, Arg = 4294967295 [0][75ff.7602][06:12:14.884845][wrkr][0x561596e19410] QueueDelay = 0 [4][75ff.75ff][06:12:14.884847][wrkr][0x561596e1a290] Created, IdealProc=2 Owner=0x561596e08500 [1][75ff.7603][06:12:14.884856][wrkr][0x561596e19b50] IsActive = 1, Arg = 1655503898 [1][75ff.7603][06:12:14.884857][wrkr][0x561596e19b50] IsActive = 0, Arg = 4294967295 [1][75ff.7603][06:12:14.884858][wrkr][0x561596e19b50] QueueDelay = 0 [4][75ff.75ff][06:12:14.884858][wrkr][0x561596e1a9d0] Created, IdealProc=3 Owner=0x561596e08500 [2][75ff.7604][06:12:14.884863][wrkr][0x561596e1a290] IsActive = 1, Arg = 1655503906 [2][75ff.7604][06:12:14.884864][wrkr][0x561596e1a290] IsActive = 0, Arg = 4294967295 [2][75ff.7604][06:12:14.884864][wrkr][0x561596e1a290] QueueDelay = 0 [4][75ff.75ff][06:12:14.884868][wrkr][0x561596e1b110] Created, IdealProc=4 Owner=0x561596e08500 [4][75ff.75ff][06:12:14.884870][wrkr][0x561596e1b850] Created, IdealProc=5 Owner=0x561596e08500 [3][75ff.7605][06:12:14.884875][wrkr][0x561596e1a9d0] IsActive = 1, Arg = 1655503917 [3][75ff.7605][06:12:14.884875][wrkr][0x561596e1a9d0] IsActive = 0, Arg = 4294967295 [3][75ff.7605][06:12:14.884875][wrkr][0x561596e1a9d0] QueueDelay = 0 [4][75ff.75ff][06:12:14.884879][wrkr][0x561596e1bf90] Created, IdealProc=6 Owner=0x561596e08500 [5][75ff.7607][06:12:14.884886][wrkr][0x561596e1b850] IsActive = 1, Arg = 1655503929 [5][75ff.7607][06:12:14.884887][wrkr][0x561596e1b850] IsActive = 0, Arg = 4294967295 [5][75ff.7607][06:12:14.884887][wrkr][0x561596e1b850] QueueDelay = 0 [4][75ff.75ff][06:12:14.884890][wrkr][0x561596e1c6d0] Created, IdealProc=7 Owner=0x561596e08500 [6][75ff.7608][06:12:14.884897][wrkr][0x561596e1bf90] IsActive = 1, Arg = 1655503939 [6][75ff.7608][06:12:14.884897][wrkr][0x561596e1bf90] IsActive = 0, Arg = 4294967295 [6][75ff.7608][06:12:14.884897][wrkr][0x561596e1bf90] QueueDelay = 0 [4][75ff.75ff][06:12:14.884900][wrkr][0x561596e1ce10] Created, IdealProc=8 Owner=0x561596e08500 [7][75ff.7609][06:12:14.884906][wrkr][0x561596e1c6d0] IsActive = 1, Arg = 1655503949 [7][75ff.7609][06:12:14.884907][wrkr][0x561596e1c6d0] IsActive = 0, Arg = 4294967295 [7][75ff.7609][06:12:14.884907][wrkr][0x561596e1c6d0] QueueDelay = 0 [4][75ff.75ff][06:12:14.884910][wrkr][0x561596e1d550] Created, IdealProc=9 Owner=0x561596e08500 [8][75ff.760a][06:12:14.884917][wrkr][0x561596e1ce10] IsActive = 1, Arg = 1655503959 [8][75ff.760a][06:12:14.884917][wrkr][0x561596e1ce10] IsActive = 0, Arg = 4294967295 [8][75ff.760a][06:12:14.884917][wrkr][0x561596e1ce10] QueueDelay = 0 [4][75ff.75ff][06:12:14.884920][wrkr][0x561596e1dc90] Created, IdealProc=10 Owner=0x561596e08500 [9][75ff.760b][06:12:14.884927][wrkr][0x561596e1d550] IsActive = 1, Arg = 1655503969 [9][75ff.760b][06:12:14.884927][wrkr][0x561596e1d550] IsActive = 0, Arg = 4294967295 [9][75ff.760b][06:12:14.884928][wrkr][0x561596e1d550] QueueDelay = 0 [4][75ff.75ff][06:12:14.884931][wrkr][0x561596e1e3d0] Created, IdealProc=11 Owner=0x561596e08500 [10][75ff.760c][06:12:14.884937][wrkr][0x561596e1dc90] IsActive = 1, Arg = 1655503980 [10][75ff.760c][06:12:14.884938][wrkr][0x561596e1dc90] IsActive = 0, Arg = 4294967295 [10][75ff.760c][06:12:14.884938][wrkr][0x561596e1dc90] QueueDelay = 0 [4][75ff.75ff][06:12:14.884941][ reg][0x561596e08500] Created, AppName=quicsample, ExecProfile=0 [4][75ff.75ff][06:12:14.884942][ api] Exit 0 [4][75ff.75ff][06:12:14.884947][ api] Enter 5 (0x561596e08500). [4][75ff.75ff][06:12:14.884948][cnfg][0x561596e15720] Failed to open app specific settings, 0x95 [11][75ff.760d][06:12:14.884948][wrkr][0x561596e1e3d0] IsActive = 1, Arg = 1655503990 [4][75ff.75ff][06:12:14.884948][cnfg][0x561596e15720] Created, Registration=0x561596e08500 [11][75ff.760d][06:12:14.884949][wrkr][0x561596e1e3d0] IsActive = 0, Arg = 4294967295 [11][75ff.760d][06:12:14.884949][wrkr][0x561596e1e3d0] QueueDelay = 0 [4][75ff.75ff][06:12:14.884949][cnfg][0x561596e15720] Settings 0x561596e15760 Updated [4][75ff.75ff][06:12:14.884949][sett] SendBufferingEnabled = 1 [4][75ff.75ff][06:12:14.884949][sett] PacingEnabled = 1 [4][75ff.75ff][06:12:14.884950][sett] MigrationEnabled = 1 [4][75ff.75ff][06:12:14.884950][sett] DatagramReceiveEnabled = 0 [4][75ff.75ff][06:12:14.884950][sett] MaxOperationsPerDrain = 16 [4][75ff.75ff][06:12:14.884950][sett] RetryMemoryLimit = 65 [4][75ff.75ff][06:12:14.884950][sett] LoadBalancingMode = 0 [4][75ff.75ff][06:12:14.884951][sett] FixedServerID = 0 [4][75ff.75ff][06:12:14.884951][sett] MaxStatelessOperations = 16 [4][75ff.75ff][06:12:14.884951][sett] MaxWorkerQueueDelayUs = 250000 [4][75ff.75ff][06:12:14.884951][sett] InitialWindowPackets = 10 [4][75ff.75ff][06:12:14.884951][sett] SendIdleTimeoutMs = 1000 [4][75ff.75ff][06:12:14.884952][sett] InitialRttMs = 333 [4][75ff.75ff][06:12:14.884952][sett] MaxAckDelayMs = 25 [4][75ff.75ff][06:12:14.884952][sett] DisconnectTimeoutMs = 16000 [4][75ff.75ff][06:12:14.884952][sett] KeepAliveIntervalMs = 0 [4][75ff.75ff][06:12:14.884952][sett] IdleTimeoutMs = 1000 [4][75ff.75ff][06:12:14.884953][sett] HandshakeIdleTimeoutMs = 10000 [4][75ff.75ff][06:12:14.884953][sett] PeerBidiStreamCount = 0 [4][75ff.75ff][06:12:14.884953][sett] PeerUnidiStreamCount = 0 [4][75ff.75ff][06:12:14.884953][sett] TlsClientMaxSendBuffer = 8192 [4][75ff.75ff][06:12:14.884953][sett] TlsServerMaxSendBuffer = 0 [4][75ff.75ff][06:12:14.884954][sett] StreamRecvWindowDefault= 65536 [4][75ff.75ff][06:12:14.884954][sett] StreamRecvBufferDefault= 4096 [4][75ff.75ff][06:12:14.884954][sett] ConnFlowControlWindow = 16777216 [4][75ff.75ff][06:12:14.884954][sett] MaxBytesPerKey = 274877906944 [4][75ff.75ff][06:12:14.884954][sett] ServerResumptionLevel = 0 [4][75ff.75ff][06:12:14.884954][sett] Version Negotiation Ext Enabled = 0 [4][75ff.75ff][06:12:14.884955][sett] MinimumMtu = 1288 [4][75ff.75ff][06:12:14.884955][sett] MaximumMtu = 1500 [4][75ff.75ff][06:12:14.884956][sett] MtuCompleteTimeout = 600000000 [4][75ff.75ff][06:12:14.884956][sett] MtuMissingProbeCount = 3 [4][75ff.75ff][06:12:14.884956][sett] MaxBindingStatelessOper= 100 [4][75ff.75ff][06:12:14.884956][sett] StatelessOperExpirMs = 100 [4][75ff.75ff][06:12:14.884956][sett] CongestionControlAlgorithm = 0 [4][75ff.75ff][06:12:14.884957][sett] DestCidUpdateIdleTimeoutMs = 20000 [4][75ff.75ff][06:12:14.884957][sett] GreaseQuicBitEnabled = 0 [4][75ff.75ff][06:12:14.884957][sett] EcnEnabled = 0 [4][75ff.75ff][06:12:14.884957][sett] HyStartEnabled = 0 [4][75ff.75ff][06:12:14.884957][ api] Exit 0 [4][75ff.75ff][06:12:14.884958][ api] Enter 7 (0x561596e15720). [4][75ff.75ff][06:12:14.885124][ api] Exit 0 [4][75ff.75ff][06:12:14.885125][ api] Enter 12 (0x561596e08500). [4][75ff.75ff][06:12:14.885129][conn][0x561596e27b00] Created, IsServer=0, CorrelationId=0 [4][75ff.75ff][06:12:14.885135][conn][0x561596e27b00] OUT: BytesSent=0 InFlight=0 InFlightMax=327440 CWnd=654880 SSThresh=4294967295 ConnFC=0 ISB=131072 PostedBytes=0 SRtt=0 [4][75ff.75ff][06:12:14.885136][conn][0x561596e27b00] OUT: StreamFC=0 StreamSendWindow=0 [4][75ff.75ff][06:12:14.885136][conn][0x561596e27b00] CUBIC: SlowStartThreshold=4294967295 K=0 WindowMax=0 WindowLastMax=0 [4][75ff.75ff][06:12:14.885139][conn][0x561596e27b00] Path[0] Initialized [4][75ff.75ff][06:12:14.885139][conn][0x561596e27b00] (SeqNum=0) New Destination CID: 92795D86E2BB7119 [4][75ff.75ff][06:12:14.885140][conn][0x561596e27b00] Initialize complete [4][75ff.75ff][06:12:14.885140][conn][0x561596e27b00] Assigned worker: 0x561596e1b110 [4][75ff.75ff][06:12:14.885141][conn][0x561596e27b00] Registered with 0x561596e08500 [4][75ff.75ff][06:12:14.885141][ api] Exit 0 [4][75ff.75ff][06:12:14.885159][ api] Enter 15 (0x561596e27b00). [4][75ff.75ff][06:12:14.885160][conn][0x561596e27b00] Scheduling: 1 [4][75ff.75ff][06:12:14.885160][ api] Exit 4294967294 [4][75ff.75ff][06:12:14.885161][ api] Enter 6 (0x561596e15720). [4][75ff.75ff][06:12:14.885161][ api] Exit [4][75ff.75ff][06:12:14.885161][ api] Enter 3 (0x561596e08500). [4][75ff.75ff][06:12:14.885162][ reg][0x561596e08500] Cleaning up [4][75ff.7606][06:12:14.885174][ lib][0x561596e161b0] Worker start [4][75ff.7606][06:12:14.885176][wrkr][0x561596e1b110] IsActive = 1, Arg = 1655504219 [4][75ff.7606][06:12:14.885176][conn][0x561596e27b00] Scheduling: 2 [4][75ff.7606][06:12:14.885176][wrkr][0x561596e1b110] QueueDelay = 2 [4][75ff.7606][06:12:14.885177][conn][0x561596e27b00] Execute: 2 [4][75ff.7606][06:12:14.885293][conn][0x561596e27b00] New Remote IP: 127.0.0.1:4567 [4][75ff.7606][06:12:14.885296][data][0x7ff604001960] Created, local=None, remote=127.0.0.1:4567 [4][75ff.7606][06:12:14.885306][bind][0x7ff604001790] Created, Udp=0x7ff604001960 LocalAddr=127.0.0.1:56999 RemoteAddr=127.0.0.1:4567 [4][75ff.7606][06:12:14.885307][ lib] Now in use. [4][75ff.7606][06:12:14.885307][conn][0x561596e27b00] (SeqNum=0) New Source CID: [4][75ff.7606][06:12:14.885308][conn][0x561596e27b00] New Local IP: 127.0.0.1:56999 [4][75ff.7606][06:12:14.885319][conn][0x561596e27b00] Configuration set, 0x561596e15720 [4][75ff.7606][06:12:14.885319][conn][0x561596e27b00] Applying new settings [4][75ff.7606][06:12:14.885320][conn][0x561596e27b00] OUT: BytesSent=0 InFlight=0 InFlightMax=6100 CWnd=12200 SSThresh=4294967295 ConnFC=0 ISB=131072 PostedBytes=0 SRtt=0 [4][75ff.7606][06:12:14.885320][conn][0x561596e27b00] OUT: StreamFC=0 StreamSendWindow=0 [4][75ff.7606][06:12:14.885320][conn][0x561596e27b00] CUBIC: SlowStartThreshold=4294967295 K=0 WindowMax=0 WindowLastMax=0 [4][75ff.7606][06:12:14.885321][sett] SendBufferingEnabled = 1 [4][75ff.7606][06:12:14.885321][sett] PacingEnabled = 1 [4][75ff.7606][06:12:14.885321][sett] MigrationEnabled = 1 [4][75ff.7606][06:12:14.885321][sett] DatagramReceiveEnabled = 0 [4][75ff.7606][06:12:14.885321][sett] MaxOperationsPerDrain = 16 [4][75ff.7606][06:12:14.885322][sett] RetryMemoryLimit = 65 [4][75ff.7606][06:12:14.885322][sett] LoadBalancingMode = 0 [4][75ff.7606][06:12:14.885322][sett] FixedServerID = 0 [4][75ff.7606][06:12:14.885322][sett] MaxStatelessOperations = 16 [4][75ff.7606][06:12:14.885322][sett] MaxWorkerQueueDelayUs = 250000 [4][75ff.7606][06:12:14.885323][sett] InitialWindowPackets = 10 [4][75ff.7606][06:12:14.885323][sett] SendIdleTimeoutMs = 1000 [4][75ff.7606][06:12:14.885323][sett] InitialRttMs = 333 [4][75ff.7606][06:12:14.885323][sett] MaxAckDelayMs = 25 [4][75ff.7606][06:12:14.885323][sett] DisconnectTimeoutMs = 16000 [4][75ff.7606][06:12:14.885324][sett] KeepAliveIntervalMs = 0 [4][75ff.7606][06:12:14.885324][sett] IdleTimeoutMs = 1000 [4][75ff.7606][06:12:14.885324][sett] HandshakeIdleTimeoutMs = 10000 [4][75ff.7606][06:12:14.885324][sett] PeerBidiStreamCount = 0 [4][75ff.7606][06:12:14.885324][sett] PeerUnidiStreamCount = 0 [4][75ff.7606][06:12:14.885324][sett] TlsClientMaxSendBuffer = 8192 [4][75ff.7606][06:12:14.885325][sett] TlsServerMaxSendBuffer = 0 [4][75ff.7606][06:12:14.885325][sett] StreamRecvWindowDefault= 65536 [4][75ff.7606][06:12:14.885325][sett] StreamRecvBufferDefault= 4096 [4][75ff.7606][06:12:14.885325][sett] ConnFlowControlWindow = 16777216 [4][75ff.7606][06:12:14.885325][sett] MaxBytesPerKey = 274877906944 [4][75ff.7606][06:12:14.885326][sett] ServerResumptionLevel = 0 [4][75ff.7606][06:12:14.885326][sett] Version Negotiation Ext Enabled = 0 [4][75ff.7606][06:12:14.885326][sett] MinimumMtu = 1248 [4][75ff.7606][06:12:14.885326][sett] MaximumMtu = 1500 [4][75ff.7606][06:12:14.885326][sett] MtuCompleteTimeout = 600000000 [4][75ff.7606][06:12:14.885327][sett] MtuMissingProbeCount = 3 [4][75ff.7606][06:12:14.885327][sett] MaxBindingStatelessOper= 100 [4][75ff.7606][06:12:14.885327][sett] StatelessOperExpirMs = 100 [4][75ff.7606][06:12:14.885327][sett] CongestionControlAlgorithm = 0 [4][75ff.7606][06:12:14.885327][sett] DestCidUpdateIdleTimeoutMs = 20000 [4][75ff.7606][06:12:14.885328][sett] GreaseQuicBitEnabled = 0 [4][75ff.7606][06:12:14.885328][sett] EcnEnabled = 0 [4][75ff.7606][06:12:14.885328][sett] HyStartEnabled = 0 [4][75ff.7606][06:12:14.885329][conn][0x561596e27b00] QUIC Version: 16777216 [4][75ff.7606][06:12:14.885333][conn][0x561596e27b00] Handshake start [4][75ff.7606][06:12:14.885334][conn][0x561596e27b00] Encoding Transport Parameters (Server = 0) [4][75ff.7606][06:12:14.885335][conn][0x561596e27b00] TP: Idle Timeout (1000 ms) [4][75ff.7606][06:12:14.885335][conn][0x561596e27b00] TP: Max Udp Payload Size (1472 bytes) [4][75ff.7606][06:12:14.885335][conn][0x561596e27b00] TP: Max Data (16777216 bytes) [4][75ff.7606][06:12:14.885336][conn][0x561596e27b00] TP: Max Local Bidirectional Stream Data (65536 bytes) [4][75ff.7606][06:12:14.885336][conn][0x561596e27b00] TP: Max Remote Bidirectional Stream Data (65536 bytes) [4][75ff.7606][06:12:14.885336][conn][0x561596e27b00] TP: Max Unidirectional Stream Data (65536) [4][75ff.7606][06:12:14.885337][conn][0x561596e27b00] TP: ACK Delay Exponent (8) [4][75ff.7606][06:12:14.885337][conn][0x561596e27b00] TP: Max ACK Delay (26 ms) [4][75ff.7606][06:12:14.885337][conn][0x561596e27b00] TP: Connection ID Limit (4) [4][75ff.7606][06:12:14.885338][conn][0x561596e27b00] TP: Initial Source Connection ID () [4][75ff.7606][06:12:14.885338][conn][0x561596e27b00] TP: Min ACK Delay (1000 us) [4][75ff.7606][06:12:14.885338][conn][0x561596e27b00] Encoded 54 bytes for QUIC TP [4][75ff.7606][06:12:14.885339][conn][0x561596e27b00] TLS context Created [4][75ff.7606][06:12:14.885401][conn][0x561596e27b00] Sending 267 handshake bytes (Level = 0) [4][75ff.7606][06:12:14.885402][conn][0x561596e27b00] Scheduling flags 0x2 to 0x0 [4][75ff.7606][06:12:14.885403][conn][0x561596e27b00] Queueing send flush, reason=0 [4][75ff.7606][06:12:14.885404][conn][0x561596e27b00] QS:267 MAX:0 UNA:0 NXT:0 RECOV:0-0 [4][75ff.7606][06:12:14.885405][conn][0x561596e27b00] Execute: 4 [4][75ff.7606][06:12:14.885405][conn][0x561596e27b00] Flushing Send. Allowance=12200 bytes [4][75ff.7606][06:12:14.885409][pack][5497558138881] Created in batch 5497558138881 [4][75ff.7606][06:12:14.885410][conn][0x561596e27b00] Sending 267 crypto bytes, offset=0 [4][75ff.7606][06:12:14.885410][conn][0x561596e27b00] QS:267 MAX:267 UNA:0 NXT:267 RECOV:0-0 [4][75ff.7606][06:12:14.885410][conn][0x561596e27b00] unACKed: [0, 267] [4][75ff.7606][06:12:14.885412][C][TX][0] LH Ver:0x16777216 DestCid:92795d86e2bb7119 SrcCid: Type:I (Token 0 bytes) (Payload 1202 bytes) [4][75ff.7606][06:12:14.885415][C][TX][0] CRYPTO Offset:0 Len:267 [4][75ff.7606][06:12:14.885416][C][TX][0] PADDING Len:911 [4][75ff.7606][06:12:14.885416][pack][5497558138881] Encrypting [4][75ff.7606][06:12:14.885418][pack][5497558138881] Finalizing [4][75ff.7606][06:12:14.885419][conn][0x561596e27b00][TX][0] 1 (1220 bytes) [4][75ff.7606][06:12:14.885420][conn][0x561596e27b00] Setting 4, delay=10000000 us [4][75ff.7606][06:12:14.885420][time][0x561596e1b1b0] Updating Connection 0x561596e27b00. [4][75ff.7606][06:12:14.885421][time][0x561596e1b1b0] Next Expiration = {5960471759, 0x561596e27b00}. [4][75ff.7606][06:12:14.885421][conn][0x561596e27b00] OUT: BytesSent=1220 InFlight=1220 InFlightMax=6100 CWnd=12200 SSThresh=4294967295 ConnFC=0 ISB=131072 PostedBytes=0 SRtt=0 [4][75ff.7606][06:12:14.885421][conn][0x561596e27b00] OUT: StreamFC=0 StreamSendWindow=0 [4][75ff.7606][06:12:14.885422][conn][0x561596e27b00] Sending batch. 1 datagrams [4][75ff.7606][06:12:14.885422][data][0x7ff604001960] Send 1220 bytes in 1 buffers (segment=1220) Dst=127.0.0.1:4567, Src=127.0.0.1:56999 [4][75e6.75ed][06:12:14.885458][data][0x559e82879cb0] Recv 1220 bytes (segment=0) Src=127.0.0.1:4567 Dst=127.0.0.1:56999 [4][75e6.75ed][06:12:14.885462][pack][5497558138881] Received [4][75e6.75ed][06:12:14.885465][conn][0x7eff880130b0] Created, IsServer=1, CorrelationId=0 [4][75e6.75ed][06:12:14.885468][conn][0x7eff880130b0] OUT: BytesSent=0 InFlight=0 InFlightMax=327440 CWnd=654880 SSThresh=4294967295 ConnFC=0 ISB=131072 PostedBytes=0 SRtt=0 [4][75e6.75ed][06:12:14.885470][conn][0x7eff880130b0] OUT: StreamFC=0 StreamSendWindow=0 [4][75e6.75ed][06:12:14.885470][conn][0x7eff880130b0] CUBIC: SlowStartThreshold=4294967295 K=0 WindowMax=0 WindowLastMax=0 [4][75e6.75ed][06:12:14.885472][conn][0x7eff880130b0] Path[0] Initialized [4][75e6.75ed][06:12:14.885472][conn][0x7eff880130b0] QUIC Version: 16777216 [4][75e6.75ed][06:12:14.885473][conn][0x7eff880130b0] New Local IP: 127.0.0.1:4567 [4][75e6.75ed][06:12:14.885473][conn][0x7eff880130b0] New Remote IP: 127.0.0.1:56999 [4][75e6.75ed][06:12:14.885473][conn][0x7eff880130b0] (SeqNum=0) New Destination CID: [4][75e6.75ed][06:12:14.885474][conn][0x7eff880130b0] (SeqNum=0) New Source CID: 92795D86E2BB7119 [4][75e6.75ed][06:12:14.885474][conn][0x7eff880130b0] Assigned worker: 0x559e82874e70 [4][75e6.75ed][06:12:14.885475][conn][0x7eff880130b0] Registered with 0x559e8286eb90 [4][75e6.75ed][06:12:14.885476][conn][0x7eff880130b0] Scheduling: 1 [4][75e6.75ed][06:12:14.885477][conn][0x7eff880130b0] Queuing 1 UDP datagrams [4][75e6.75ed][06:12:14.885479][wrkr][0x559e82874e70] IsActive = 1, Arg = 1655504522 [4][75e6.75ed][06:12:14.885480][ lib] Perf counters Rundown, Counters=01000000000000000000000000000000000000000000000000000000000000000100000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000001000000000000000000000000000000C4040000000000000000000000000000010000000000000000000000000000000000000000000000000000000000000001000000000000000100000000000000010000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000 [4][75e6.75ed][06:12:14.885480][conn][0x7eff880130b0] Scheduling: 2 [4][75e6.75ed][06:12:14.885481][wrkr][0x559e82874e70] QueueDelay = 0 [4][75e6.75ed][06:12:14.885492][conn][0x7eff880130b0] Initialize complete [4][75e6.75ed][06:12:14.885492][conn][0x7eff880130b0] Execute: 1 [4][75e6.75ed][06:12:14.885493][conn][0x7eff880130b0] Recv 1 UDP datagrams [4][75e6.75ed][06:12:14.885493][conn][0x7eff880130b0] IN: BytesRecv=1220 [4][75e6.75ed][06:12:14.885494][conn][0x7eff880130b0] Batch Recv 1 UDP datagrams [4][75e6.75ed][06:12:14.885495][pack][5497558138881] Decrypting [4][75e6.75ed][06:12:14.885499][S][RX][0] LH Ver:0x16777216 DestCid:92795d86e2bb7119 SrcCid: Type:I (Token 0 bytes) (Payload 1202 bytes) [4][75e6.75ed][06:12:14.885499][S][RX][0] CRYPTO Offset:0 Len:267 [4][75e6.75ed][06:12:14.885500][S][RX][0] PADDING Len:911 [4][75e6.75ed][06:12:14.885500][conn][0x7eff880130b0][RX][0]  (1204 bytes) [4][75e6.75ed][06:12:14.885502][conn][0x7eff880130b0] Received 267 crypto bytes, offset=0 Ready=1 [4][75e6.75ed][06:12:14.885503][conn][0x7eff880130b0] Decoding Transport Parameters (Server = 0) (54 bytes) [4][75e6.75ed][06:12:14.885504][conn][0x7eff880130b0] TP: Idle Timeout (1000 ms) [4][75e6.75ed][06:12:14.885504][conn][0x7eff880130b0] TP: Max Udp Payload Size (1472 bytes) [4][75e6.75ed][06:12:14.885504][conn][0x7eff880130b0] TP: Max Data (16777216 bytes) [4][75e6.75ed][06:12:14.885505][conn][0x7eff880130b0] TP: Max Local Bidirectional Stream Data (65536 bytes) [4][75e6.75ed][06:12:14.885505][conn][0x7eff880130b0] TP: Max Remote Bidirectional Stream Data (65536 bytes) [4][75e6.75ed][06:12:14.885506][conn][0x7eff880130b0] TP: Max Unidirectional Stream Data (65536) [4][75e6.75ed][06:12:14.885506][conn][0x7eff880130b0] TP: ACK Delay Exponent (8) [4][75e6.75ed][06:12:14.885506][conn][0x7eff880130b0] TP: Max ACK Delay (26 ms) [4][75e6.75ed][06:12:14.885507][conn][0x7eff880130b0] TP: Connection ID Limit (4) [4][75e6.75ed][06:12:14.885507][conn][0x7eff880130b0] TP: Initial Source Connection ID () [4][75e6.75ed][06:12:14.885508][conn][0x7eff880130b0] TP: Min ACK Delay (1000 us) [4][75e6.75ed][06:12:14.885508][conn][0x7eff880130b0] Peer Transport Parameters Set [4][75e6.75ed][06:12:14.885509][conn][0x7eff880130b0] Datagram send shutdown [4][75e6.75ed][06:12:14.885511][conn][0x7eff880130b0] Unregistered from 0x559e8286eb90 [4][75e6.75ed][06:12:14.885511][conn][0x7eff880130b0] Registered with 0x559e82850500 [4][75e6.75ed][06:12:14.885513][conn][0x7eff880130b0] (SeqNum=0) New Source CID: 8433294F6C7ED8F67F [4][75e6.75ed][06:12:14.885513][list][0x559e82871c30] Indicating NEW_CONNECTION 0x7eff880130b0 [4][75e6.75ed][06:12:14.885514][ api] Enter 16 (0x7eff880130b0). [4][75e6.75ed][06:12:14.885514][ api] Exit 4294967294 [4][75e6.75ed][06:12:14.885515][S][RX][0] Marked for ACK (ECN=0) [4][75e6.75ed][06:12:14.885516][conn][0x7eff880130b0] Starting ACK_DELAY timer for 25 ms [4][75e6.75ed][06:12:14.885516][conn][0x7eff880130b0] Setting 1, delay=25000 us [4][75e6.75ed][06:12:14.885518][time][0x559e82874f10] Updating Connection 0x7eff880130b0. [4][75e6.75ed][06:12:14.885519][time][0x559e82874f10] Next Expiration = {5950496855, 0x7eff880130b0}. [4][75e6.75ed][06:12:14.885519][conn][0x7eff880130b0] Setting 4, delay=10000000 us [4][75e6.75ed][06:12:14.885520][time][0x559e82874f10] Removing Connection 0x7eff880130b0. [4][75e6.75ed][06:12:14.885520][time][0x559e82874f10] Next Expiration = {NULL}. [4][75e6.75ed][06:12:14.885520][conn][0x7eff880130b0] Assigned worker: 0x559e82863110 [4][75e6.75ed][06:12:14.885521][conn][0x7eff880130b0] Scheduling: 1 [4][75e6.75ed][06:12:14.885521][wrkr][0x559e82863110] IsActive = 1, Arg = 1655504522 [4][75e6.75ed][06:12:14.885521][conn][0x7eff880130b0] Scheduling: 2 [4][75e6.75ed][06:12:14.885522][wrkr][0x559e82863110] QueueDelay = 0 [4][75e6.75ed][06:12:14.885522][time][0x559e828631b0] Updating Connection 0x7eff880130b0. [4][75e6.75ed][06:12:14.885522][time][0x559e828631b0] Next Expiration = {5950496855, 0x7eff880130b0}. [4][75e6.75ed][06:12:14.885522][conn][0x7eff880130b0] Indicating QUIC_CONNECTION_EVENT_IDEAL_PROCESSOR_CHANGED [4][75e6.75ed][06:12:14.885523][conn][0x7eff880130b0] Execute: 3 [4][75e6.75ed][06:12:14.885523][conn][0x7eff880130b0] Configuration set, 0x559e8285d720 [4][75e6.75ed][06:12:14.885524][conn][0x7eff880130b0] Applying new settings [4][75e6.75ed][06:12:14.885524][conn][0x7eff880130b0] OUT: BytesSent=0 InFlight=0 InFlightMax=6100 CWnd=12200 SSThresh=4294967295 ConnFC=16777216 ISB=131072 PostedBytes=0 SRtt=0 [4][75e6.75ed][06:12:14.885524][conn][0x7eff880130b0] OUT: StreamFC=0 StreamSendWindow=0 [4][75e6.75ed][06:12:14.885525][conn][0x7eff880130b0] CUBIC: SlowStartThreshold=4294967295 K=0 WindowMax=0 WindowLastMax=0 [4][75e6.75ed][06:12:14.885525][conn][0x7eff880130b0] App configured max stream count of 1 (type=0). [4][75e6.75ed][06:12:14.885525][sett] SendBufferingEnabled = 1 [4][75e6.75ed][06:12:14.885526][sett] PacingEnabled = 1 [4][75e6.75ed][06:12:14.885526][sett] MigrationEnabled = 1 [4][75e6.75ed][06:12:14.885526][sett] DatagramReceiveEnabled = 0 [4][75e6.75ed][06:12:14.885527][sett] MaxOperationsPerDrain = 16 [4][75e6.75ed][06:12:14.885527][sett] RetryMemoryLimit = 65 [4][75e6.75ed][06:12:14.885527][sett] LoadBalancingMode = 0 [4][75e6.75ed][06:12:14.885527][sett] FixedServerID = 0 [4][75e6.75ed][06:12:14.885527][sett] MaxStatelessOperations = 16 [4][75e6.75ed][06:12:14.885528][sett] MaxWorkerQueueDelayUs = 250000 [4][75e6.75ed][06:12:14.885528][sett] InitialWindowPackets = 10 [4][75e6.75ed][06:12:14.885528][sett] SendIdleTimeoutMs = 1000 [4][75e6.75ed][06:12:14.885528][sett] InitialRttMs = 333 [4][75e6.75ed][06:12:14.885528][sett] MaxAckDelayMs = 25 [4][75e6.75ed][06:12:14.885529][sett] DisconnectTimeoutMs = 16000 [4][75e6.75ed][06:12:14.885529][sett] KeepAliveIntervalMs = 0 [4][75e6.75ed][06:12:14.885529][sett] IdleTimeoutMs = 1000 [4][75e6.75ed][06:12:14.885529][sett] HandshakeIdleTimeoutMs = 10000 [4][75e6.75ed][06:12:14.885529][sett] PeerBidiStreamCount = 1 [4][75e6.75ed][06:12:14.885530][sett] PeerUnidiStreamCount = 0 [4][75e6.75ed][06:12:14.885530][sett] TlsClientMaxSendBuffer = 8192 [4][75e6.75ed][06:12:14.885530][sett] TlsServerMaxSendBuffer = 0 [4][75e6.75ed][06:12:14.885530][sett] StreamRecvWindowDefault= 65536 [4][75e6.75ed][06:12:14.885531][sett] StreamRecvBufferDefault= 4096 [4][75e6.75ed][06:12:14.885531][sett] ConnFlowControlWindow = 16777216 [4][75e6.75ed][06:12:14.885531][sett] MaxBytesPerKey = 274877906944 [4][75e6.75ed][06:12:14.885531][sett] ServerResumptionLevel = 2 [4][75e6.75ed][06:12:14.885531][sett] Version Negotiation Ext Enabled = 0 [4][75e6.75ed][06:12:14.885532][sett] MinimumMtu = 1248 [4][75e6.75ed][06:12:14.885532][sett] MaximumMtu = 1500 [4][75e6.75ed][06:12:14.885532][sett] MtuCompleteTimeout = 600000000 [4][75e6.75ed][06:12:14.885532][sett] MtuMissingProbeCount = 3 [4][75e6.75ed][06:12:14.885533][sett] MaxBindingStatelessOper= 100 [4][75e6.75ed][06:12:14.885533][sett] StatelessOperExpirMs = 100 [4][75e6.75ed][06:12:14.885533][sett] CongestionControlAlgorithm = 0 [4][75e6.75ed][06:12:14.885534][sett] DestCidUpdateIdleTimeoutMs = 20000 [4][75e6.75ed][06:12:14.885534][sett] GreaseQuicBitEnabled = 0 [4][75e6.75ed][06:12:14.885534][sett] EcnEnabled = 0 [4][75e6.75ed][06:12:14.885535][sett] HyStartEnabled = 0 [4][75e6.75ed][06:12:14.885536][conn][0x7eff880130b0] Handshake start [4][75e6.75ed][06:12:14.885536][conn][0x7eff880130b0] Encoding Transport Parameters (Server = 1) [4][75e6.75ed][06:12:14.885538][conn][0x7eff880130b0] TP: Original Destination Connection ID (92795d86e2bb7119) [4][75e6.75ed][06:12:14.885538][conn][0x7eff880130b0] TP: Idle Timeout (1000 ms) [4][75e6.75ed][06:12:14.885539][conn][0x7eff880130b0] TP: Stateless Reset Token (671972e8a7c01ea76ae2fbccbccdfb7a) [4][75e6.75ed][06:12:14.885539][conn][0x7eff880130b0] TP: Max Udp Payload Size (1472 bytes) [4][75e6.75ed][06:12:14.885539][conn][0x7eff880130b0] TP: Max Data (16777216 bytes) [4][75e6.75ed][06:12:14.885539][conn][0x7eff880130b0] TP: Max Local Bidirectional Stream Data (65536 bytes) [4][75e6.75ed][06:12:14.885539][conn][0x7eff880130b0] TP: Max Remote Bidirectional Stream Data (65536 bytes) [4][75e6.75ed][06:12:14.885540][conn][0x7eff880130b0] TP: Max Unidirectional Stream Data (65536) [4][75e6.75ed][06:12:14.885540][conn][0x7eff880130b0] TP: Max Bidirectional Streams (1) [4][75e6.75ed][06:12:14.885540][conn][0x7eff880130b0] TP: ACK Delay Exponent (8) [4][75e6.75ed][06:12:14.885540][conn][0x7eff880130b0] TP: Max ACK Delay (26 ms) [4][75e6.75ed][06:12:14.885540][conn][0x7eff880130b0] TP: Connection ID Limit (4) [4][75e6.75ed][06:12:14.885541][conn][0x7eff880130b0] TP: Initial Source Connection ID (8433294f6c7ed8f67f) [4][75e6.75ed][06:12:14.885541][conn][0x7eff880130b0] TP: Min ACK Delay (1000 us) [4][75e6.75ed][06:12:14.885541][conn][0x7eff880130b0] Encoded 94 bytes for QUIC TP [4][75e6.75ed][06:12:14.885542][conn][0x7eff880130b0] TLS context Created [4][75e6.75ed][06:12:14.885552][conn][0x7eff880130b0] Processing 267 received bytes [4][75e6.75ed][06:12:14.885665][conn][0x7eff880130b0] Sending 90 handshake bytes (Level = 0) [4][75e6.75ed][06:12:14.885672][conn][0x7eff880130b0] New encryption secrets (Level = 2) [4][75e6.75ed][06:12:14.885679][conn][0x7eff880130b0] Sending 117 handshake bytes (Level = 2) [4][75e6.75ed][06:12:14.885680][conn][0x7eff880130b0] Writing Handshake data starts at 90 [4][75e6.75ed][06:12:14.885697][conn][0x7eff880130b0] Sending 365 handshake bytes (Level = 2) [4][75e6.75ed][06:12:14.885739][conn][0x7eff880130b0] Sending 79 handshake bytes (Level = 2) [4][75e6.75ed][06:12:14.885743][conn][0x7eff880130b0] Sending 52 handshake bytes (Level = 2) [4][75e6.75ed][06:12:14.885751][conn][0x7eff880130b0] New encryption secrets (Level = 3) [4][75e6.75ed][06:12:14.885757][conn][0x7eff880130b0] Writing 1-RTT data starts at 703 [4][75e6.75ed][06:12:14.885757][conn][0x7eff880130b0] Draining 267 crypto bytes [4][75e6.75ed][06:12:14.885758][conn][0x7eff880130b0] Write Key Updated, 3. [4][75e6.75ed][06:12:14.885758][conn][0x7eff880130b0] Queueing send flush, reason=8 [4][75e6.75ed][06:12:14.885759][conn][0x7eff880130b0] Read Key Updated, 2. [4][75e6.75ed][06:12:14.885759][conn][0x7eff880130b0] Scheduling flags 0x2 to 0x0 [4][75e6.75ed][06:12:14.885760][conn][0x7eff880130b0] QS:703 MAX:0 UNA:0 NXT:0 RECOV:0-0 [4][75e6.75ed][06:12:14.885761][conn][0x7eff880130b0] Execute: 4 [4][75e6.75ed][06:12:14.885761][conn][0x7eff880130b0] Flushing Send. Allowance=3660 bytes [4][75e6.75ed][06:12:14.885764][pack][5497558138881] Created in batch 5497558138881 [4][75e6.75ed][06:12:14.885765][conn][0x7eff880130b0] Canceling 1 [4][75e6.75ed][06:12:14.885765][time][0x559e828631b0] Updating Connection 0x7eff880130b0. [4][75e6.75ed][06:12:14.885765][time][0x559e828631b0] Next Expiration = {5960471858, 0x7eff880130b0}. [4][75e6.75ed][06:12:14.885766][conn][0x7eff880130b0] Sending 90 crypto bytes, offset=0 [4][75e6.75ed][06:12:14.885766][conn][0x7eff880130b0] QS:703 MAX:90 UNA:0 NXT:90 RECOV:0-0 [4][75e6.75ed][06:12:14.885766][conn][0x7eff880130b0] unACKed: [0, 90] [4][75e6.75ed][06:12:14.885767][S][TX][0] LH Ver:0x16777216 DestCid: SrcCid:8433294f6c7ed8f67f Type:I (Token 0 bytes) (Payload 119 bytes) [4][75e6.75ed][06:12:14.885768][S][TX][0] ACK Largest:0 Delay:1 [4][75e6.75ed][06:12:14.885768][S][TX][0] 0 [4][75e6.75ed][06:12:14.885769][S][TX][0] CRYPTO Offset:0 Len:90 [4][75e6.75ed][06:12:14.885769][pack][5497558138881] Encrypting [4][75e6.75ed][06:12:14.885770][pack][5497558138881] Finalizing [4][75e6.75ed][06:12:14.885770][conn][0x7eff880130b0][TX][0] 1 (138 bytes) [4][75e6.75ed][06:12:14.885771][conn][0x7eff880130b0] Setting 4, delay=10000000 us [4][75e6.75ed][06:12:14.885771][time][0x559e828631b0] Updating Connection 0x7eff880130b0. [4][75e6.75ed][06:12:14.885771][time][0x559e828631b0] Next Expiration = {5960472110, 0x7eff880130b0}. [4][75e6.75ed][06:12:14.885771][conn][0x7eff880130b0] OUT: BytesSent=138 InFlight=138 InFlightMax=6100 CWnd=12200 SSThresh=4294967295 ConnFC=16777216 ISB=131072 PostedBytes=0 SRtt=0 [4][75e6.75ed][06:12:14.885772][conn][0x7eff880130b0] OUT: StreamFC=0 StreamSendWindow=0 [4][75e6.75ed][06:12:14.885772][pack][5497558138882] Created in batch 5497558138881 [4][75e6.75ed][06:12:14.885772][conn][0x7eff880130b0] Sending 613 crypto bytes, offset=90 [4][75e6.75ed][06:12:14.885772][conn][0x7eff880130b0] QS:703 MAX:703 UNA:0 NXT:703 RECOV:0-0 [4][75e6.75ed][06:12:14.885773][conn][0x7eff880130b0] unACKed: [0, 703] [4][75e6.75ed][06:12:14.885773][S][TX][1] LH Ver:0x16777216 DestCid: SrcCid:8433294f6c7ed8f67f Type:HS (Payload 1064 bytes) [4][75e6.75ed][06:12:14.885774][S][TX][1] CRYPTO Offset:0 Len:613 [4][75e6.75ed][06:12:14.885774][S][TX][1] PADDING Len:427 [4][75e6.75ed][06:12:14.885774][pack][5497558138882] Encrypting [4][75e6.75ed][06:12:14.885775][pack][5497558138882] Finalizing [4][75e6.75ed][06:12:14.885775][conn][0x7eff880130b0][TX][1] 3 (1082 bytes) [4][75e6.75ed][06:12:14.885775][conn][0x7eff880130b0] OUT: BytesSent=1220 InFlight=1220 InFlightMax=6100 CWnd=12200 SSThresh=4294967295 ConnFC=16777216 ISB=131072 PostedBytes=0 SRtt=0 [4][75e6.75ed][06:12:14.885775][conn][0x7eff880130b0] OUT: StreamFC=0 StreamSendWindow=0 [4][75e6.75ed][06:12:14.885776][conn][0x7eff880130b0] Sending batch. 1 datagrams [4][75e6.75ed][06:12:14.885777][data][0x559e82879cb0] Send 1220 bytes in 1 buffers (segment=1220) Dst=127.0.0.1:56999, Src=127.0.0.1:4567 [4][75e6.75ed][06:12:14.885783][pack][5497558138881] Batch sent [4][75e6.75ed][06:12:14.885784][conn][0x7eff880130b0] Setting loss detection 0 timer for 998992 us. (ProbeCount=0) [4][75e6.75ed][06:12:14.885785][conn][0x7eff880130b0] Setting 2, delay=998992 us [4][75e6.75ed][06:12:14.885785][time][0x559e828631b0] Updating Connection 0x7eff880130b0. [4][75e6.75ed][06:12:14.885785][time][0x559e828631b0] Next Expiration = {5951471115, 0x7eff880130b0}. [4][75e6.75ed][06:12:14.885785][conn][0x7eff880130b0] Flush complete flags=0x0 [4][75e6.75ed][06:12:14.885786][conn][0x7eff880130b0] Scheduling: 0 [4][75e6.75ed][06:12:14.885787][wrkr][0x559e82874e70] IsActive = 0, Arg = 4294967295 [4][75e6.75ed][06:12:14.885787][wrkr][0x559e82874e70] QueueDelay = 0 [4][75e6.75ed][06:12:14.885787][wrkr][0x559e82863110] IsActive = 0, Arg = 1656503819 [4][75e6.75ed][06:12:14.885787][wrkr][0x559e82863110] QueueDelay = 0 [4][75ff.7606][06:12:14.885791][pack][5497558138881] Batch sent [4][75ff.7606][06:12:14.885792][conn][0x561596e27b00] Setting loss detection 0 timer for 998628 us. (ProbeCount=0) [4][75ff.7606][06:12:14.885792][conn][0x561596e27b00] Setting 2, delay=998628 us [4][75ff.7606][06:12:14.885793][time][0x561596e1b1b0] Updating Connection 0x561596e27b00. [4][75ff.7606][06:12:14.885793][time][0x561596e1b1b0] Next Expiration = {5951470759, 0x561596e27b00}. [4][75ff.7606][06:12:14.885793][conn][0x561596e27b00] Flush complete flags=0x0 [4][75ff.7606][06:12:14.885794][conn][0x561596e27b00] Scheduling: 0 [4][75ff.7606][06:12:14.885815][data][0x7ff604001960] Recv 1220 bytes (segment=0) Src=127.0.0.1:56999 Dst=127.0.0.1:4567 [4][75ff.7606][06:12:14.885815][pack][5497558138881] Received [4][75ff.7606][06:12:14.885816][conn][0x561596e27b00] Queuing 1 UDP datagrams [4][75ff.7606][06:12:14.885816][conn][0x561596e27b00] Scheduling: 1 [4][75ff.7606][06:12:14.885819][conn][0x561596e27b00] Scheduling: 2 [4][75ff.7606][06:12:14.885819][wrkr][0x561596e1b110] QueueDelay = 2 [4][75ff.7606][06:12:14.885819][conn][0x561596e27b00] Execute: 1 [4][75ff.7606][06:12:14.885820][conn][0x561596e27b00] Recv 1 UDP datagrams [4][75ff.7606][06:12:14.885820][conn][0x561596e27b00] IN: BytesRecv=1220 [4][75ff.7606][06:12:14.885820][conn][0x561596e27b00] Batch Recv 1 UDP datagrams [4][75ff.7606][06:12:14.885821][pack][5497558138881] Decrypting [4][75ff.7606][06:12:14.885822][C][RX][0] LH Ver:0x16777216 DestCid: SrcCid:8433294f6c7ed8f67f Type:I (Token 0 bytes) (Payload 119 bytes) [4][75ff.7606][06:12:14.885823][C][RX][0] ACK Largest:0 Delay:1 [4][75ff.7606][06:12:14.885823][C][RX][0] 0 [4][75ff.7606][06:12:14.885823][C][RX][0] CRYPTO Offset:0 Len:90 [4][75ff.7606][06:12:14.885824][conn][0x561596e27b00][RX][0]  (122 bytes) [4][75ff.7606][06:12:14.885824][conn][0x561596e27b00] (SeqNum=0) Removed Destination CID: 92795D86E2BB7119 [4][75ff.7606][06:12:14.885825][conn][0x561596e27b00] (SeqNum=0) New Destination CID: 8433294F6C7ED8F67F [4][75ff.7606][06:12:14.885826][C][TX][0] ACKed (0.406 ms) [4][75ff.7606][06:12:14.885826][conn][0x561596e27b00][TX][0] 1 ACKed [4][75ff.7606][06:12:14.885826][conn][0x561596e27b00] Received ack for 267 crypto bytes, offset=0 [4][75ff.7606][06:12:14.885827][conn][0x561596e27b00] QS:267 MAX:267 UNA:267 NXT:267 RECOV:0-0 [4][75ff.7606][06:12:14.885827][conn][0x561596e27b00] Path[0] Minimum MTU validated [4][75ff.7606][06:12:14.885828][conn][0x561596e27b00] Updated Rtt=0.398 ms, Var=0.199 [4][75ff.7606][06:12:14.885828][conn][0x561596e27b00] OUT: BytesSent=1220 InFlight=0 InFlightMax=6100 CWnd=12200 SSThresh=4294967295 ConnFC=0 ISB=131072 PostedBytes=0 SRtt=398 [4][75ff.7606][06:12:14.885828][conn][0x561596e27b00] OUT: StreamFC=0 StreamSendWindow=0 [4][75ff.7606][06:12:14.885829][conn][0x561596e27b00] Setting loss detection 2 timer for 785 us. (ProbeCount=0) [4][75ff.7606][06:12:14.885829][conn][0x561596e27b00] Setting 2, delay=785 us [4][75ff.7606][06:12:14.885829][time][0x561596e1b1b0] Updating Connection 0x561596e27b00. [4][75ff.7606][06:12:14.885829][time][0x561596e1b1b0] Next Expiration = {5950472953, 0x561596e27b00}. [4][75ff.7606][06:12:14.885830][conn][0x561596e27b00] Received 90 crypto bytes, offset=0 Ready=1 [4][75ff.7606][06:12:14.885830][conn][0x561596e27b00] Processing 90 received bytes [4][75ff.7606][06:12:14.885880][conn][0x561596e27b00] New encryption secrets (Level = 2) [4][75ff.7606][06:12:14.885886][conn][0x561596e27b00] Writing Handshake data starts at 267 [4][75ff.7606][06:12:14.885886][conn][0x561596e27b00] Draining 90 crypto bytes [4][75ff.7606][06:12:14.885886][conn][0x561596e27b00] Write Key Updated, 2. [4][75ff.7606][06:12:14.885887][conn][0x561596e27b00] Read Key Updated, 2. [4][75ff.7606][06:12:14.885887][C][RX][0] Marked for ACK (ECN=0) [4][75ff.7606][06:12:14.885888][conn][0x561596e27b00] Starting ACK_DELAY timer for 25 ms [4][75ff.7606][06:12:14.885888][conn][0x561596e27b00] Setting 1, delay=25000 us [4][75ff.7606][06:12:14.885888][conn][0x561596e27b00] Batch Recv 1 UDP datagrams [4][75ff.7606][06:12:14.885888][pack][5497558138881] Decrypting [4][75ff.7606][06:12:14.885890][C][RX][1] LH Ver:0x16777216 DestCid: SrcCid:8433294f6c7ed8f67f Type:HS (Payload 1064 bytes) [4][75ff.7606][06:12:14.885890][C][RX][1] CRYPTO Offset:0 Len:613 [4][75ff.7606][06:12:14.885890][C][RX][1] PADDING Len:427 [4][75ff.7606][06:12:14.885891][conn][0x561596e27b00][RX][1]  (1066 bytes) [4][75ff.7606][06:12:14.885891][conn][0x561596e27b00] Received 613 crypto bytes, offset=0 Ready=1 [4][75ff.7606][06:12:14.885891][conn][0x561596e27b00] Processing 613 received bytes [4][75ff.7606][06:12:14.885957][ tls][0x561596e27b00] ERROR, Internal certificate validation failed. [4][75ff.7606][06:12:14.885959][conn][0x561596e27b00] Send alert = 43 (Level = 0) [4][75ff.7606][06:12:14.885964][conn][0x561596e27b00] TLS handshake error: error:1416F086:SSL routines:tls_process_server_certificate:certificate verify failed, file:nssl/ssl/statem/statem_clnt.c:1927 [4][75ff.7606][06:12:14.885965][conn][0x561596e27b00] Draining 613 crypto bytes [4][75ff.7606][06:12:14.885965][conn][0x561596e27b00] ERROR, 43, Received alert from TLS. [4][75ff.7606][06:12:14.885965][conn][0x561596e27b00] Setting 5, delay=3582 us [4][75ff.7606][06:12:14.885966][conn][0x561596e27b00] Scheduling flags 0x4 to 0x0 [4][75ff.7606][06:12:14.885966][conn][0x561596e27b00] Queueing send flush, reason=0 [4][75ff.7606][06:12:14.885967][conn][0x561596e27b00] Canceling 4 [4][75ff.7606][06:12:14.885967][conn][0x561596e27b00] Indicating QUIC_CONNECTION_EVENT_SHUTDOWN_INITIATED_BY_TRANSPORT [0x200000299] [4][75ff.7606][06:12:14.885991][conn][0x561596e27b00] STATS: SRtt=398 CongestionCount=0 PersistentCongestionCount=0 SendTotalBytes=1220 RecvTotalBytes=1220 CongestionWindow=12200 Cc=Cubic EcnCongestionCount=0 [4][75ff.7606][06:12:14.885992][conn][0x561596e27b00] STATS: SendTotalPackets=1 SendSuspectedLostPackets=0 SendSpuriousLostPackets=0 RecvTotalPackets=2 RecvReorderedPackets=0 RecvDroppedPackets=0 RecvDuplicatePackets=0 RecvDecryptionFailures=0 [4][75ff.7606][06:12:14.885992][conn][0x561596e27b00] Transport Shutdown: 299 (Remote=0) (QS=0) [4][75ff.7606][06:12:14.885992][conn][0x561596e27b00] Datagram send shutdown [4][75ff.7606][06:12:14.885993][conn][0x561596e27b00] Setting 4, delay=10000000 us [4][75ff.7606][06:12:14.885994][conn][0x561596e27b00] Execute: 4 [4][75ff.7606][06:12:14.885994][conn][0x561596e27b00] Flushing Send. Allowance=12200 bytes [4][75ff.7606][06:12:14.885995][pack][5497558138882] Created in batch 5497558138882 [4][75ff.7606][06:12:14.885996][C][TX][1] LH Ver:0x16777216 DestCid:8433294f6c7ed8f67f SrcCid: Type:HS (Payload 25 bytes) [4][75ff.7606][06:12:14.885997][C][TX][1] CONN_CLOSE ErrorCode:0x299 FrameType:0 [4][75ff.7606][06:12:14.885997][pack][5497558138882] Encrypting [4][75ff.7606][06:12:14.885998][pack][5497558138882] Finalizing [4][75ff.7606][06:12:14.885998][conn][0x561596e27b00][TX][1] 3 (43 bytes) [4][75ff.7606][06:12:14.885999][conn][0x561596e27b00] Sending batch. 1 datagrams [4][75ff.7606][06:12:14.885999][data][0x7ff604001960] Send 43 bytes in 1 buffers (segment=1220) Dst=127.0.0.1:4567, Src=127.0.0.1:56999 [4][75e6.75ed][06:12:14.886012][data][0x559e82879cb0] Recv 43 bytes (segment=0) Src=127.0.0.1:4567 Dst=127.0.0.1:56999 [4][75e6.75ed][06:12:14.886013][pack][5497558138882] Received [4][75e6.75ed][06:12:14.886014][conn][0x7eff880130b0] Queuing 1 UDP datagrams [4][75e6.75ed][06:12:14.886015][conn][0x7eff880130b0] Scheduling: 1 [4][75e6.75ed][06:12:14.886016][wrkr][0x559e82863110] IsActive = 1, Arg = 1655505059 [4][75e6.75ed][06:12:14.886016][conn][0x7eff880130b0] Scheduling: 2 [4][75e6.75ed][06:12:14.886017][wrkr][0x559e82863110] QueueDelay = 0 [4][75e6.75ed][06:12:14.886017][conn][0x7eff880130b0] Execute: 1 [4][75e6.75ed][06:12:14.886017][conn][0x7eff880130b0] Recv 1 UDP datagrams [4][75e6.75ed][06:12:14.886018][conn][0x7eff880130b0] IN: BytesRecv=1263 [4][75e6.75ed][06:12:14.886019][conn][0x7eff880130b0] Batch Recv 1 UDP datagrams [4][75e6.75ed][06:12:14.886019][pack][5497558138882] Decrypting [4][75e6.75ed][06:12:14.886021][S][RX][1] LH Ver:0x16777216 DestCid:8433294f6c7ed8f67f SrcCid: Type:HS (Payload 25 bytes) [4][75e6.75ed][06:12:14.886021][S][RX][1] CONN_CLOSE ErrorCode:0x299 FrameType:0 [4][75e6.75ed][06:12:14.886022][conn][0x7eff880130b0][RX][1]  (27 bytes) [4][75e6.75ed][06:12:14.886022][conn][0x7eff880130b0] Discarding key type = 0 [4][75e6.75ed][06:12:14.886024][S][TX][0] ACKed (implicit) [4][75e6.75ed][06:12:14.886024][conn][0x7eff880130b0][TX][0] 1 ACKed [4][75e6.75ed][06:12:14.886024][conn][0x7eff880130b0] Received ack for 90 crypto bytes, offset=0 [4][75e6.75ed][06:12:14.886025][conn][0x7eff880130b0] QS:703 MAX:703 UNA:90 NXT:703 RECOV:0-0 [4][75e6.75ed][06:12:14.886025][conn][0x7eff880130b0] unACKed: [90, 703] [4][75e6.75ed][06:12:14.886026][conn][0x7eff880130b0] OUT: BytesSent=1220 InFlight=1082 InFlightMax=6100 CWnd=12200 SSThresh=4294967295 ConnFC=16777216 ISB=131072 PostedBytes=0 SRtt=0 [4][75e6.75ed][06:12:14.886026][conn][0x7eff880130b0] OUT: StreamFC=0 StreamSendWindow=0 [4][75e6.75ed][06:12:14.886027][conn][0x7eff880130b0] Path[0] Validated (Handshake Packet) [4][75e6.75ed][06:12:14.886028][conn][0x7eff880130b0] Setting 5, delay=666000 us [4][75e6.75ed][06:12:14.886028][time][0x559e828631b0] Updating Connection 0x7eff880130b0. [4][75e6.75ed][06:12:14.886028][time][0x559e828631b0] Next Expiration = {5951138366, 0x7eff880130b0}. [4][75e6.75ed][06:12:14.886028][conn][0x7eff880130b0] Scheduling flags 0x4 to 0x0 [4][75e6.75ed][06:12:14.886029][conn][0x7eff880130b0] Queueing send flush, reason=0 [4][75e6.75ed][06:12:14.886029][conn][0x7eff880130b0] Canceling 4 [4][75e6.75ed][06:12:14.886030][conn][0x7eff880130b0] Indicating QUIC_CONNECTION_EVENT_SHUTDOWN_INITIATED_BY_TRANSPORT [0x200000299] [4][75e6.75ed][06:12:14.886046][conn][0x7eff880130b0] STATS: SRtt=333000 CongestionCount=0 PersistentCongestionCount=0 SendTotalBytes=1220 RecvTotalBytes=1263 CongestionWindow=12200 Cc=Cubic EcnCongestionCount=0 [4][75e6.75ed][06:12:14.886046][conn][0x7eff880130b0] STATS: SendTotalPackets=2 SendSuspectedLostPackets=0 SendSpuriousLostPackets=0 RecvTotalPackets=2 RecvReorderedPackets=0 RecvDroppedPackets=0 RecvDuplicatePackets=0 RecvDecryptionFailures=0 [4][75e6.75ed][06:12:14.886047][conn][0x7eff880130b0] Transport Shutdown: 299 (Remote=1) (QS=0) [4][75e6.75ed][06:12:14.886047][S][RX][1] Marked for ACK (ECN=0) [4][75e6.75ed][06:12:14.886048][conn][0x7eff880130b0] First usage of SrcCid: 8433294f6c7ed8f67f [4][75e6.75ed][06:12:14.886049][conn][0x7eff880130b0] Setting 4, delay=10000000 us [4][75e6.75ed][06:12:14.886049][conn][0x7eff880130b0] Execute: 4 [4][75e6.75ed][06:12:14.886050][conn][0x7eff880130b0] Flushing Send. Allowance=11118 bytes [4][75e6.75ed][06:12:14.886050][pack][5497558138883] Created in batch 5497558138882 [4][75e6.75ed][06:12:14.886051][S][TX][2] SH DestCid: KP:0 SB:0 (Payload 8 bytes) [4][75e6.75ed][06:12:14.886051][S][TX][2] CONN_CLOSE ErrorCode:0x0 FrameType:0 [4][75e6.75ed][06:12:14.886052][pack][5497558138883] Encrypting [4][75e6.75ed][06:12:14.886052][pack][5497558138883] Finalizing [4][75e6.75ed][06:12:14.886053][conn][0x7eff880130b0][TX][2] 5 (25 bytes) [4][75e6.75ed][06:12:14.886053][conn][0x7eff880130b0] Sending batch. 1 datagrams [4][75e6.75ed][06:12:14.886054][data][0x559e82879cb0] Send 25 bytes in 1 buffers (segment=1220) Dst=127.0.0.1:56999, Src=127.0.0.1:4567 [4][75e6.75ed][06:12:14.886058][pack][5497558138882] Batch sent [4][75e6.75ed][06:12:14.886058][conn][0x7eff880130b0] Flush complete flags=0x0 [4][75e6.75ed][06:12:14.886059][conn][0x7eff880130b0] Scheduling: 0 [4][75e6.75ed][06:12:14.886060][wrkr][0x559e82863110] IsActive = 0, Arg = 1656171070 [4][75e6.75ed][06:12:14.886060][wrkr][0x559e82863110] QueueDelay = 0 [4][75ff.7606][06:12:14.886063][pack][5497558138882] Batch sent [4][75ff.7606][06:12:14.886063][conn][0x561596e27b00] Flush complete flags=0x0 [4][75ff.7606][06:12:14.886064][conn][0x561596e27b00] Scheduling: 0 [4][75ff.7606][06:12:14.886067][data][0x7ff604001960] Recv 25 bytes (segment=0) Src=127.0.0.1:56999 Dst=127.0.0.1:4567 [4][75ff.7606][06:12:14.886068][pack][5497558138882] Received [4][75ff.7606][06:12:14.886069][conn][0x561596e27b00] Queuing 1 UDP datagrams [4][75ff.7606][06:12:14.886069][conn][0x561596e27b00] Scheduling: 1 [4][75ff.7606][06:12:14.886071][conn][0x561596e27b00] Scheduling: 2 [4][75ff.7606][06:12:14.886071][wrkr][0x561596e1b110] QueueDelay = 2 [4][75ff.7606][06:12:14.886071][conn][0x561596e27b00] Execute: 1 [4][75ff.7606][06:12:14.886072][conn][0x561596e27b00] Recv 1 UDP datagrams [4][75ff.7606][06:12:14.886072][conn][0x561596e27b00] IN: BytesRecv=1245 [4][75ff.7606][06:12:14.886073][conn][0x561596e27b00] Deferring datagram (type=3) [4][75ff.7606][06:12:14.886073][conn][0x561596e27b00] Scheduling: 0 [4][75ff.7606][06:12:14.886074][wrkr][0x561596e1b110] IsActive = 0, Arg = 1655505657 [4][75ff.7606][06:12:14.886074][wrkr][0x561596e1b110] QueueDelay = 0 [4][75ff.7606][06:12:14.887226][wrkr][0x561596e1b110] IsActive = 1, Arg = 1655506267 [4][75ff.7606][06:12:14.887227][conn][0x561596e27b00] 2 expired [4][75ff.7606][06:12:14.887228][conn][0x561596e27b00] Scheduling: 1 [4][75ff.7606][06:12:14.887229][time][0x561596e1b1b0] Updating Connection 0x561596e27b00. [4][75ff.7606][06:12:14.887229][time][0x561596e1b1b0] Next Expiration = {5950475886, 0x561596e27b00}. [4][75ff.7606][06:12:14.887229][conn][0x561596e27b00] Scheduling: 2 [4][75ff.7606][06:12:14.887229][wrkr][0x561596e1b110] QueueDelay = 0 [4][75ff.7606][06:12:14.887230][conn][0x561596e27b00] Execute: 2 [4][75ff.7606][06:12:14.887232][conn][0x561596e27b00] probe round 1 [4][75ff.7606][06:12:14.887232][conn][0x561596e27b00] Scheduling: 0 [4][75ff.7606][06:12:14.887233][wrkr][0x561596e1b110] IsActive = 0, Arg = 1655508590 [4][75ff.7606][06:12:14.887233][wrkr][0x561596e1b110] QueueDelay = 0 [4][75ff.7606][06:12:14.890451][wrkr][0x561596e1b110] IsActive = 1, Arg = 1655509493 [4][75ff.7606][06:12:14.890453][conn][0x561596e27b00] 5 expired [4][75ff.7606][06:12:14.890453][conn][0x561596e27b00] Scheduling: 1 [4][75ff.7606][06:12:14.890454][time][0x561596e1b1b0] Updating Connection 0x561596e27b00. [4][75ff.7606][06:12:14.890454][time][0x561596e1b1b0] Next Expiration = {5950497227, 0x561596e27b00}. [4][75ff.7606][06:12:14.890454][conn][0x561596e27b00] Scheduling: 2 [4][75ff.7606][06:12:14.890455][wrkr][0x561596e1b110] QueueDelay = 0 [4][75ff.7606][06:12:14.890455][conn][0x561596e27b00] Execute: 5 [4][75ff.7606][06:12:14.890455][conn][0x561596e27b00] Shutdown complete, PeerFailedToAcknowledged=1. [4][75ff.7606][06:12:14.890456][conn][0x561596e27b00] Indicating QUIC_CONNECTION_EVENT_SHUTDOWN_COMPLETE [4][75ff.7606][06:12:14.890473][ api] Enter 13 (0x561596e27b00). [4][75ff.7606][06:12:14.890474][conn][0x561596e27b00] Unregistered from 0x561596e08500 [4][75ff.7606][06:12:14.890475][conn][0x561596e27b00] Handle closed [4][75ff.7606][06:12:14.890475][ api] Exit [4][75ff.7606][06:12:14.890479][conn][0x561596e27b00] Cleaning up [4][75ff.7606][06:12:14.890489][time][0x561596e1b1b0] Removing Connection 0x561596e27b00. [4][75ff.7606][06:12:14.890490][time][0x561596e1b1b0] Next Expiration = {NULL}. [4][75ff.7606][06:12:14.890490][conn][0x561596e27b00] Scheduling: 0 [4][75ff.7606][06:12:14.890491][ lib] No longer in use. [4][75ff.7606][06:12:14.890491][bind][0x7ff604001790] Cleaning up [4][75ff.7606][06:12:14.890492][data][0x7ff604001960] Destroyed [4][75ff.7606][06:12:14.890494][bind][0x7ff604001790] Destroyed [4][75ff.7606][06:12:14.890494][cnfg][0x561596e15720] Cleaning up [4][75ff.7606][06:12:14.890501][cnfg][0x561596e15720] Destroyed [4][75ff.7606][06:12:14.890512][conn][0x561596e27b00] Destroyed [4][75ff.7606][06:12:14.890520][wrkr][0x561596e1b110] IsActive = 0, Arg = 4294967295 [6][75ff.75ff][06:12:14.890521][wrkr][0x561596e19410] Cleaning up [4][75ff.7606][06:12:14.890521][wrkr][0x561596e1b110] QueueDelay = 0 [6][75ff.75ff][06:12:14.890560][wrkr][0x561596e19410] Destroyed [6][75ff.75ff][06:12:14.890561][wrkr][0x561596e19b50] Cleaning up [6][75ff.75ff][06:12:14.890613][wrkr][0x561596e19b50] Destroyed [6][75ff.75ff][06:12:14.890613][wrkr][0x561596e1a290] Cleaning up [6][75ff.75ff][06:12:14.890654][wrkr][0x561596e1a290] Destroyed [6][75ff.75ff][06:12:14.890654][wrkr][0x561596e1a9d0] Cleaning up [6][75ff.75ff][06:12:14.890698][wrkr][0x561596e1a9d0] Destroyed [6][75ff.75ff][06:12:14.890699][wrkr][0x561596e1b110] Cleaning up [6][75ff.75ff][06:12:14.890731][wrkr][0x561596e1b110] Destroyed [6][75ff.75ff][06:12:14.890731][wrkr][0x561596e1b850] Cleaning up [6][75ff.75ff][06:12:14.890764][wrkr][0x561596e1b850] Destroyed [6][75ff.75ff][06:12:14.890764][wrkr][0x561596e1bf90] Cleaning up [4][75ff.75ff][06:12:14.890783][wrkr][0x561596e1bf90] Destroyed [4][75ff.75ff][06:12:14.890784][wrkr][0x561596e1c6d0] Cleaning up [4][75ff.75ff][06:12:14.890815][wrkr][0x561596e1c6d0] Destroyed [4][75ff.75ff][06:12:14.890815][wrkr][0x561596e1ce10] Cleaning up [4][75ff.75ff][06:12:14.890845][wrkr][0x561596e1ce10] Destroyed [4][75ff.75ff][06:12:14.890846][wrkr][0x561596e1d550] Cleaning up [4][75ff.75ff][06:12:14.890877][wrkr][0x561596e1d550] Destroyed [4][75ff.75ff][06:12:14.890877][wrkr][0x561596e1dc90] Cleaning up [4][75ff.75ff][06:12:14.890910][wrkr][0x561596e1dc90] Destroyed [4][75ff.75ff][06:12:14.890910][wrkr][0x561596e1e3d0] Cleaning up [4][75ff.75ff][06:12:14.890942][wrkr][0x561596e1e3d0] Destroyed [4][75ff.75ff][06:12:14.890943][ api] Exit [4][75ff.75ff][06:12:14.890943][ api] MsQuicClose [4][75ff.75ff][06:12:14.890944][ lib] Release [4][75ff.75ff][06:12:14.890953][ lib] Uninitialized [0][75ff.7602][06:12:14.890969][ lib][0x561596e15ff0] Worker stop [1][75ff.7603][06:12:14.891016][ lib][0x561596e16060] Worker stop [2][75ff.7604][06:12:14.891084][ lib][0x561596e160d0] Worker stop [3][75ff.7605][06:12:14.891124][ lib][0x561596e16140] Worker stop [4][75ff.7606][06:12:14.891147][ lib][0x561596e161b0] Worker stop [5][75ff.7607][06:12:14.891238][ lib][0x561596e16220] Worker stop [6][75ff.7608][06:12:14.891266][ lib][0x561596e16290] Worker stop [7][75ff.7609][06:12:14.891308][ lib][0x561596e16300] Worker stop [8][75ff.760a][06:12:14.891347][ lib][0x561596e16370] Worker stop [9][75ff.760b][06:12:14.891387][ lib][0x561596e163e0] Worker stop [10][75ff.760c][06:12:14.891427][ lib][0x561596e16450] Worker stop [11][75ff.760d][06:12:14.891469][ lib][0x561596e164c0] Worker stop [4][75ff.75ff][06:12:14.891523][ dso] Uninitialized [4][75ff.75ff][06:12:14.891524][ dso] Unloaded ```

moondayyy commented 1 year ago

I installed donet-6.0 and started ./scripts/prepare-machine.ps1 -ForTest.

I try to run cmake with '-DDEBUG=1' and run make with DEBUG=1.

I try to use log_wrapper.sh and step-by-step-command.

All these attempts have failed. I am sorry that I cannot provide logs.

# ./scripts/log_wrapper.sh ./x86_64-linux-gnu/bin/Release/quicsample -server -cert_file:tls.crt -key_file:tls.key
Spawning a session daemon
Session msquic created.
Traces will be output to /root/go/src/github.com/isrc-cas/gt/dep/msquic/msquic_lttng6/data
UST event CLOG_* created in channel channel0
UST context vpid added to all channels
UST context vtid added to all channels
Tracing started for session msquic
Press Enter to exit.

[conn][0x7f0d3c012fe0] Shut down by transport, 0xbebc330
[conn][0x7f0d3c012fe0] All done

Error: Can't find valid lttng config /root/.lttngrc
Did you create a session? (lttng create <my_session>)
Error: Session name msquic not found
[error] Cannot open any trace for reading.

[error] opening trace "msquic_lttng6/data" for reading.

[error] none of the specified trace paths could be opened.
# ./scripts/log_wrapper.sh ./x86_64-linux-gnu/bin/Release/quicsample -client -target:localhost
Destroying session msquic..
Session msquic destroyed
Session msquic created.
Traces will be output to /root/go/src/github.com/isrc-cas/gt/dep/msquic/msquic_lttng7/data
UST event CLOG_* created in channel channel0
UST context vpid added to all channels
UST context vtid added to all channels
Tracing started for session msquic
[conn][0x5583517bfec0] Connecting...
[conn][0x5583517bfec0] Shut down by transport, 0xbebc330
[conn][0x5583517bfec0] All done
Waiting for data availability
Tracing stopped for session msquic
Destroying session msquic..
Session msquic destroyed
[error] Cannot open any trace for reading.

[error] opening trace "msquic_lttng7/data" for reading.

[error] none of the specified trace paths could be opened.

I have the same problem in Linux. (0xbebc330) how did you solve it?

Anveena commented 1 month ago

I installed donet-6.0 and started ./scripts/prepare-machine.ps1 -ForTest. I try to run cmake with '-DDEBUG=1' and run make with DEBUG=1. I try to use log_wrapper.sh and step-by-step-command. All these attempts have failed. I am sorry that I cannot provide logs.

# ./scripts/log_wrapper.sh ./x86_64-linux-gnu/bin/Release/quicsample -server -cert_file:tls.crt -key_file:tls.key
Spawning a session daemon
Session msquic created.
Traces will be output to /root/go/src/github.com/isrc-cas/gt/dep/msquic/msquic_lttng6/data
UST event CLOG_* created in channel channel0
UST context vpid added to all channels
UST context vtid added to all channels
Tracing started for session msquic
Press Enter to exit.

[conn][0x7f0d3c012fe0] Shut down by transport, 0xbebc330
[conn][0x7f0d3c012fe0] All done

Error: Can't find valid lttng config /root/.lttngrc
Did you create a session? (lttng create <my_session>)
Error: Session name msquic not found
[error] Cannot open any trace for reading.

[error] opening trace "msquic_lttng6/data" for reading.

[error] none of the specified trace paths could be opened.
# ./scripts/log_wrapper.sh ./x86_64-linux-gnu/bin/Release/quicsample -client -target:localhost
Destroying session msquic..
Session msquic destroyed
Session msquic created.
Traces will be output to /root/go/src/github.com/isrc-cas/gt/dep/msquic/msquic_lttng7/data
UST event CLOG_* created in channel channel0
UST context vpid added to all channels
UST context vtid added to all channels
Tracing started for session msquic
[conn][0x5583517bfec0] Connecting...
[conn][0x5583517bfec0] Shut down by transport, 0xbebc330
[conn][0x5583517bfec0] All done
Waiting for data availability
Tracing stopped for session msquic
Destroying session msquic..
Session msquic destroyed
[error] Cannot open any trace for reading.

[error] opening trace "msquic_lttng7/data" for reading.

[error] none of the specified trace paths could be opened.

I have the same problem in Linux. (0xbebc330) how did you solve it?

bro, I have the same problem in Linux. (0xbebc330) how did you solve it?

nibanks commented 1 month ago

You need to pass the -unsecure arg on the client because of the self-signed cert.