2025-04-30 11:35:35,432 Generated random file: yakfrnqvld of size: 10240 2025-04-30 11:35:35,432 Requests: https://server4:443/yakfrnqvld 2025-04-30 11:35:35,489 2025-04-30 11:35:35,489 Command: WAITFORSERVER=server:443 CERTS=/tmp/certs_1jtlx8dh/ TESTCASE_SERVER=retry TESTCASE_CLIENT=retry WWW=/tmp/www_q3alo109/ DOWNLOADS=/tmp/download_9kir5s3r/ SERVER_LOGS=/tmp/logs_server_2zpcf576 CLIENT_LOGS=/tmp/logs_client_9co2kkg6 SCENARIO="simple-p2p --delay=15ms --bandwidth=10Mbps --queue=25" CLIENT=peterdoornbosch/kwik_n_flupke-interop SERVER=cloudflare/quiche-qns:latest REQUESTS="https://server4:443/yakfrnqvld" docker compose --env-file empty.env up --abort-on-container-exit --timeout 1 sim client server 2025-04-30 11:35:49,374 Container sim Created Container server Recreate Container client Recreate Container client Recreated Container server Recreated Attaching to client, server, sim sim | waiting 10s for server:443 client | Setting up routes... client | Actual changes: client | tx-checksum-ip-generic: off client | tx-tcp-segmentation: off [not requested] client | tx-tcp-ecn-segmentation: off [not requested] client | tx-tcp-mangleid-segmentation: off [not requested] client | tx-tcp6-segmentation: off [not requested] client | tx-udp-segmentation: off [not requested] client | tx-checksum-sctp: off client | Endpoint's IPv4 address is 193.167.0.100 client | Endpoint's IPv6 address is fd00:cafe:cafe::100 server | Setting up routes... server | Actual changes: server | tx-checksum-ip-generic: off server | tx-tcp-segmentation: off [not requested] server | tx-tcp-ecn-segmentation: off [not requested] server | tx-tcp-mangleid-segmentation: off [not requested] server | tx-tcp6-segmentation: off [not requested] server | tx-udp-segmentation: off [not requested] server | tx-checksum-sctp: off server | Endpoint's IPv4 address is 193.167.100.100 server | Endpoint's IPv6 address is fd00:cafe:cafe:100::100 server | supported server | ## Starting quiche server... server | ## Server params: server | ## Test case: retry client | Certificate was added to keystore client | wait-for-it.sh: waiting 30 seconds for sim:57832 sim | server:443 is available after 1.047281449s sim | Using scenario: simple-p2p --delay=15ms --bandwidth=10Mbps --queue=25 sim | tcpdump: listening on eth0, link-type EN10MB (Ethernet), snapshot length 262144 bytes sim | tcpdump: listening on eth1, link-type EN10MB (Ethernet), snapshot length 262144 bytes client | wait-for-it.sh: sim:57832 is available after 1 seconds client | 35:37.602 Creating connection with server4:443 with v1 client | 35:37.686 Original destination connection id: 566ad63b1821f260 (scid: 1ab7165d2a8e5552) client | >- CryptoStream[I|ClientHello] client | 35:37.807 -> Packet I|0|L|1200|2 Token=[] CryptoFrame[0,257] Padding(896) client | 35:37.845 <- (1) Packet I|-|R|81| Retry Token (30): 71756963686500000000000000000000ffffc1a70064566ad63b1821f260 client | 35:37.869 -> Packet I|1|L|1200|2 Token=71756963686500000000000000000000ffffc1a70064566ad63b1821f260 CryptoFrame[0,257] Padding(854) client | 35:37.902 <- (2) Packet I|0|L|187|2 Token=[] AckFrame[1|?0] CryptoFrame[0,123] client | -< CryptoStream[I|ServerHello] client | 35:37.930 -> Packet I|2|L|1201|2 Token=71756963686500000000000000000000ffffc1a70064566ad63b1821f260 AckFrame[0|?0] Padding(1111) client | 35:37.902 <- (2) Packet H|1|L|776|1 CryptoFrame[0,718] client | >- CryptoStream[H|Finished] client | 35:37.959 -> Packet H|0|L|92|1 CryptoFrame[0,36] client | -< CryptoStream[H|EncryptedExtensions,Certificate,CertificateVerify,Finished] client | 35:37.962 -> Packet H|1|L|58|1 AckFrame[1|?0] client | 35:37.967 -> Packet A|0|S0|3535860ef769973155bf3ce225b0ae312952a9b9|59|1 StreamFrame[0(CIB),0,17,fin] client | 35:37.992 <- (3) Packet A|2|S0|1ab7165d2a8e5552|467|3 NewConnectionIdFrame[1,<0|792aca24604f8fa1ab065d1abe6695621e193c5b|753d26f5166e437f3b167bd2c77dfa80] HandshakeDoneFrame[] CryptoFrame[0,396] client | -< CryptoStream[A|NewSessionTicket,NewSessionTicket] client | 35:38.000 <- (4) Packet A|3|S0|1ab7165d2a8e5552|1350|2 AckFrame[0|?0] StreamFrame[0(CIB),0,1314] client | 35:38.001 <- (5) Packet A|4|S0|1ab7165d2a8e5552|1350|1 StreamFrame[0(CIB),1314,1318] client | 35:38.002 <- (6) Packet A|5|S0|1ab7165d2a8e5552|1350|1 StreamFrame[0(CIB),2632,1318] client | 35:38.005 <- (7) Packet A|6|S0|1ab7165d2a8e5552|1350|1 StreamFrame[0(CIB),3950,1318] client | 35:38.002 -> Packet A|1|S0|3535860ef769973155bf3ce225b0ae312952a9b9|44|1 AckFrame[3-2|?3] client | 35:38.006 -> Packet A|2|S0|3535860ef769973155bf3ce225b0ae312952a9b9|44|1 AckFrame[5-2|?3] client | 35:38.005 <- (8) Packet A|7|S0|1ab7165d2a8e5552|1350|1 StreamFrame[0(CIB),5268,1318] client | 35:38.005 <- (9) Packet A|8|S0|1ab7165d2a8e5552|1350|1 StreamFrame[0(CIB),6586,1318] client | 35:38.007 <- (10) Packet A|9|S0|1ab7165d2a8e5552|1350|1 StreamFrame[0(CIB),7904,1318] client | 35:38.008 -> Packet A|3|S0|3535860ef769973155bf3ce225b0ae312952a9b9|44|1 AckFrame[7-2|?1] client | 35:38.011 -> Packet A|4|S0|3535860ef769973155bf3ce225b0ae312952a9b9|44|1 AckFrame[9-2|?1] client | 35:38.007 <- (11) Packet A|10|S0|1ab7165d2a8e5552|1050|1 StreamFrame[0(CIB),9222,1018,fin] client | Get requested finished in 0.33 sec (0.03 MB/s) : tech.kwik.h09.client.HttpResponseImpl@2ddc8ecb client | 35:38.028 Closing ClientConnection[566ad63b1821f260/1ab7165d2a8e5552(V1) with server4/193.167.100.100:443] (reason: ImmediateClose) client | 35:38.031 -> Packet A|5|S0|3535860ef769973155bf3ce225b0ae312952a9b9|48|2 ConnectionCloseFrame[0|0|] AckFrame[10-2|?17] client | Terminating Kwik client exited with code 0 Aborting on container exit... Container server Stopping Container client Stopping Container client Stopped Container server Stopped Container sim Stopping Container sim Stopped 2025-04-30 11:35:49,466 Using the server's key log file. 2025-04-30 11:35:49,474 2025-04-30 11:35:49,475 Using the server's key log file. 2025-04-30 11:35:49,475 Using the server's key log file. 2025-04-30 11:35:49,482 2025-04-30 11:35:49,483 Using the server's key log file. 2025-04-30 11:35:50,039 Check of downloaded files succeeded. 2025-04-30 11:35:50,585 Check of Retry succeeded. Token used: 71756963686500000000000000000000ffffc1a70064566ad63b1821f260