12:21:00.965791 [0-x] == Info: [0-x] [MULTI] [INIT] added to multi, mid=1, running=1, total=2
12:21:00.965816 [0-x] == Info: [0-x] [MULTI] [INIT] -> [SETUP]
12:21:00.965818 [0-x] == Info: [0-x] [MULTI] [SETUP] -> [CONNECT]
12:21:00.965819 [0-x] == Info: [0-x] [READ] client_reset, clear readers
12:21:00.965834 [0-0] == Info: [0-0] [MULTI] [CONNECT] [CPOOL] added connection 0. The cache now contains 1 members
12:21:00.965840 [0-0] == Info: [0-0] [SETUP] added
12:21:00.965842 [0-0] == Info: [0-0] [MULTI] [CONNECT] -> [CONNECTING]
12:21:00.965939 [0-0] == Info: [0-0] [HAPPY-EYEBALLS] created ipv4 (timeout 300000ms)
12:21:00.965941 [0-0] == Info: [0-0] [HAPPY-EYEBALLS] ipv4 starting (timeout=300000ms)
12:21:00.965950 [0-0] == Info: [0-0]   Trying 127.0.0.1:41219...
12:21:00.965953 [0-0] == Info: [0-0] [TCP] cf_socket_open() -> 0, fd=5
12:21:00.966055 [0-0] == Info: [0-0] [TCP] local address 127.0.0.1 port 49556...
12:21:00.966057 [0-0] == Info: [0-0] [HAPPY-EYEBALLS] ipv4 connect -> 0, connected=0
12:21:00.966059 [0-0] == Info: [0-0] [SETUP] Curl_conn_connect(block=0) -> 0, done=0
12:21:00.966067 [0-0] == Info: [0-0] [TCP] adjust_pollset, !connected, POLLOUT fd=5
12:21:00.966069 [0-0] == Info: [0-0] [HAPPY-EYEBALLS] adjust_pollset -> 1 socks
12:21:00.966071 [0-0] == Info: [0-0] [MULTI] [CONNECTING] multi_wait pollset[fd=5 OUT], timeouts=0
12:21:00.966075 [0-0] == Info: [0-0] [TCP] connected
12:21:00.966077 [0-0] == Info: [0-0] [HAPPY-EYEBALLS] ipv4 connect -> 0, connected=1
12:21:00.966078 [0-0] == Info: [0-0] [HAPPY-EYEBALLS] Connected to 127.0.0.1 (127.0.0.1) port 41219
12:21:00.966081 [0-0] == Info: [0-0] [HTTP-PROXY] connect
12:21:00.966082 [0-0] == Info: [0-0] [HTTP-PROXY] installing subfilter for HTTP/1.1
12:21:00.966083 [0-0] == Info: [0-0] CONNECT tunnel: HTTP/1.1 negotiated
12:21:00.966085 [0-0] == Info: [0-0] [H1-PROXY] connect
12:21:00.966086 [0-0] == Info: [0-0] allocate connect buffer
12:21:00.966087 [0-0] == Info: [0-0] [H1-PROXY] CONNECT start
12:21:00.966091 [0-0] == Info: [0-0] Establish HTTP proxy tunnel to 127.0.0.1:38241
12:21:00.966098 [0-0] == Info: [0-0] [H1-PROXY] new tunnel state 'connect'
12:21:00.966099 [0-0] == Info: [0-0] [H1-PROXY] CONNECT send
12:21:00.966219 [0-0] == Info: [0-0] [TCP] send(len=114) -> 114, err=0
12:21:00.966221 [0-0] => Send header, 120 bytes (0x78)
0000: [0-0] CONNECT 127.0.0.1:38241 HTTP/1.1
0028: Host: 127.0.0.1:38241
003f: User-Agent: curl/8.14.1
0058: Proxy-Connection: Keep-Alive
0076: 
12:21:00.966230 [0-0] == Info: [0-0] [H1-PROXY] new tunnel state 'receive'
12:21:00.966231 [0-0] == Info: [0-0] [H1-PROXY] CONNECT receive
12:21:00.966234 [0-0] == Info: [0-0] [TCP] recv(len=1) -> 1, err=0
12:21:00.966236 [0-0] == Info: [0-0] [TCP] recv(len=1) -> 1, err=0
12:21:00.966238 [0-0] == Info: [0-0] [TCP] recv(len=1) -> 1, err=0
12:21:00.966239 [0-0] == Info: [0-0] [TCP] recv(len=1) -> 1, err=0
12:21:00.966240 [0-0] == Info: [0-0] [TCP] recv(len=1) -> 1, err=0
12:21:00.966242 [0-0] == Info: [0-0] [TCP] recv(len=1) -> 1, err=0
12:21:00.966243 [0-0] == Info: [0-0] [TCP] recv(len=1) -> 1, err=0
12:21:00.966245 [0-0] == Info: [0-0] [TCP] recv(len=1) -> 1, err=0
12:21:00.966246 [0-0] == Info: [0-0] [TCP] recv(len=1) -> 1, err=0
12:21:00.966247 [0-0] == Info: [0-0] [TCP] recv(len=1) -> 1, err=0
12:21:00.966249 [0-0] == Info: [0-0] [TCP] recv(len=1) -> 1, err=0
12:21:00.966250 [0-0] == Info: [0-0] [TCP] recv(len=1) -> 1, err=0
12:21:00.966252 [0-0] == Info: [0-0] [TCP] recv(len=1) -> 1, err=0
12:21:00.966253 [0-0] == Info: [0-0] [TCP] recv(len=1) -> 1, err=0
12:21:00.966256 [0-0] == Info: [0-0] [TCP] recv(len=1) -> 1, err=0
12:21:00.966257 [0-0] == Info: [0-0] [TCP] recv(len=1) -> 1, err=0
12:21:00.966259 [0-0] == Info: [0-0] [TCP] recv(len=1) -> 1, err=0
12:21:00.966260 [0-0] == Info: [0-0] [TCP] recv(len=1) -> 1, err=0
12:21:00.966262 [0-0] == Info: [0-0] [TCP] recv(len=1) -> 1, err=0
12:21:00.966263 [0-0] == Info: [0-0] [TCP] recv(len=1) -> 1, err=0
12:21:00.966264 [0-0] == Info: [0-0] [TCP] recv(len=1) -> 1, err=0
12:21:00.966266 [0-0] == Info: [0-0] [TCP] recv(len=1) -> 1, err=0
12:21:00.966267 [0-0] == Info: [0-0] [TCP] recv(len=1) -> 1, err=0
12:21:00.966269 [0-0] == Info: [0-0] [TCP] recv(len=1) -> 1, err=0
12:21:00.966270 [0-0] == Info: [0-0] [TCP] recv(len=1) -> 1, err=0
12:21:00.966271 [0-0] == Info: [0-0] [TCP] recv(len=1) -> 1, err=0
12:21:00.966273 [0-0] == Info: [0-0] [TCP] recv(len=1) -> 1, err=0
12:21:00.966274 [0-0] == Info: [0-0] [TCP] recv(len=1) -> 1, err=0
12:21:00.966276 [0-0] == Info: [0-0] [TCP] recv(len=1) -> 1, err=0
12:21:00.966280 [0-0] == Info: [0-0] [TCP] recv(len=1) -> 1, err=0
12:21:00.966282 [0-0] == Info: [0-0] [TCP] recv(len=1) -> 1, err=0
12:21:00.966284 [0-0] == Info: [0-0] [TCP] recv(len=1) -> 1, err=0
12:21:00.966285 [0-0] <= Recv header, 38 bytes (0x26)
0000: [0-0] HTTP/1.1 200 Mighty fine indeed.
12:21:00.966300 [0-0] == Info: [0-0] [WRITE] [OUT] wrote 32 header bytes -> 32
12:21:00.966302 [0-0] == Info: [0-0] [WRITE] [PAUSE] writing 32/32 bytes of type 1c -> 0
12:21:00.966304 [0-0] == Info: [0-0] [WRITE] download_write header(type=1c, blen=32) -> 0
12:21:00.966305 [0-0] == Info: [0-0] [WRITE] client_write(type=1c, len=32) -> 0
12:21:00.966308 [0-0] == Info: [0-0] [TCP] recv(len=1) -> 1, err=0
12:21:00.966309 [0-0] <= Recv header, 7 bytes (0x7)
0000: [0-0] .
12:21:00.966312 [0-0] == Info: [0-0] [WRITE] header_collect pushed(type=4, len=1) -> 0
12:21:00.966314 [0-0] == Info: [0-0] [WRITE] [OUT] wrote 1 header bytes -> 1
12:21:00.966316 [0-0] == Info: [0-0] [WRITE] [PAUSE] writing 1/1 bytes of type 14 -> 0
12:21:00.966317 [0-0] == Info: [0-0] [WRITE] download_write header(type=14, blen=1) -> 0
12:21:00.966318 [0-0] == Info: [0-0] [WRITE] client_write(type=14, len=1) -> 0
12:21:00.966320 [0-0] == Info: [0-0] [H1-PROXY] new tunnel state 'response'
12:21:00.966321 [0-0] == Info: [0-0] [H1-PROXY] CONNECT response
12:21:00.966322 [0-0] == Info: [0-0] [H1-PROXY] new tunnel state 'established'
12:21:00.966323 [0-0] == Info: [0-0] CONNECT phase completed
12:21:00.966325 [0-0] == Info: [0-0] CONNECT tunnel established, response 200
12:21:00.966326 [0-0] == Info: [0-0] [READ] client_reset, clear readers
12:21:00.966328 [0-0] == Info: [0-0] [H1-PROXY] new tunnel state 'failed'
12:21:00.966334 [0-0] == Info: [0-0] [TCP] send(len=44) -> 44, err=0
12:21:00.966335 [0-0] == Info: [0-0] [SETUP] Curl_conn_connect(block=0) -> 0, done=1
12:21:00.966340 [0-0] == Info: [0-0] Connected to 127.0.0.1 (127.0.0.1) port 41219
12:21:00.966341 [0-0] == Info: [0-0] using HTTP/1.x
12:21:00.966343 [0-0] == Info: [0-0] [MULTI] [CONNECTING] -> [PROTOCONNECT]
12:21:00.966344 [0-0] == Info: [0-0] [MULTI] [PROTOCONNECT] -> [DO]
12:21:00.966353 [0-0] == Info: [0-0] [TCP] send(len=83) -> 83, err=0
12:21:00.966354 [0-0] => Send header, 89 bytes (0x59)
0000: [0-0] GET /page HTTP/1.1
001a: Host: 127.0.0.1:38241
0031: User-Agent: curl/8.14.1
004a: Accept: */*
0057: 
12:21:00.966360 [0-0] == Info: [0-0] [MULTI] [DO] -> [DID]
12:21:00.966361 [0-0] == Info: [0-0] [MULTI] [DID] -> [PERFORMING]
12:21:00.966366 [0-0] == Info: [0-0] [TCP] recv(len=102400) -> -1, err=81
12:21:00.966368 [0-0] == Info: [0-0] Request completely sent off
12:21:00.966372 [0-0] == Info: [0-0] [MULTI] [PERFORMING] multi_wait pollset[fd=5 IN], timeouts=0
12:21:01.467395 [0-0] == Info: [0-0] [TCP] recv(len=102400) -> 199, err=0
12:21:01.467413 [0-0] <= Recv header, 29 bytes (0x1d)
0000: [0-0] HTTP/1.1 404 Not Found.
12:21:01.467435 [0-0] == Info: [0-0] [WRITE] [OUT] wrote 23 header bytes -> 23
12:21:01.467437 [0-0] == Info: [0-0] [WRITE] [PAUSE] writing 23/23 bytes of type c -> 0
12:21:01.467439 [0-0] == Info: [0-0] [WRITE] download_write header(type=c, blen=23) -> 0
12:21:01.467441 [0-0] == Info: [0-0] [WRITE] client_write(type=c, len=23) -> 0
12:21:01.467443 [0-0] <= Recv header, 27 bytes (0x1b)
0000: [0-0] Server: nginx/1.23.2.
12:21:01.467448 [0-0] == Info: [0-0] [WRITE] header_collect pushed(type=1, len=21) -> 0
12:21:01.467450 [0-0] == Info: [0-0] [WRITE] [OUT] wrote 21 header bytes -> 21
12:21:01.467452 [0-0] == Info: [0-0] [WRITE] [PAUSE] writing 21/21 bytes of type 4 -> 0
12:21:01.467453 [0-0] == Info: [0-0] [WRITE] download_write header(type=4, blen=21) -> 0
12:21:01.467455 [0-0] == Info: [0-0] [WRITE] client_write(type=4, len=21) -> 0
12:21:01.467456 [0-0] <= Recv header, 42 bytes (0x2a)
0000: [0-0] Date: Wed, 09 Nov 2022 09:44:58 GMT.
12:21:01.467465 [0-0] == Info: [0-0] [WRITE] header_collect pushed(type=1, len=36) -> 0
12:21:01.467466 [0-0] == Info: [0-0] [WRITE] [OUT] wrote 36 header bytes -> 36
12:21:01.467468 [0-0] == Info: [0-0] [WRITE] [PAUSE] writing 36/36 bytes of type 4 -> 0
12:21:01.467469 [0-0] == Info: [0-0] [WRITE] download_write header(type=4, blen=36) -> 0
12:21:01.467470 [0-0] == Info: [0-0] [WRITE] client_write(type=4, len=36) -> 0
12:21:01.467473 [0-0] <= Recv header, 46 bytes (0x2e)
0000: [0-0] Content-Type: text/plain; charset=utf-8.
12:21:01.467477 [0-0] == Info: [0-0] [WRITE] header_collect pushed(type=1, len=40) -> 0
12:21:01.467479 [0-0] == Info: [0-0] [WRITE] [OUT] wrote 40 header bytes -> 40
12:21:01.467480 [0-0] == Info: [0-0] [WRITE] [PAUSE] writing 40/40 bytes of type 4 -> 0
12:21:01.467481 [0-0] == Info: [0-0] [WRITE] download_write header(type=4, blen=40) -> 0
12:21:01.467482 [0-0] == Info: [0-0] [WRITE] client_write(type=4, len=40) -> 0
12:21:01.467483 [0-0] <= Recv header, 24 bytes (0x18)
0000: [0-0] Content-Length: 4.
12:21:01.467487 [0-0] == Info: [0-0] [WRITE] header_collect pushed(type=1, len=18) -> 0
12:21:01.467488 [0-0] == Info: [0-0] [WRITE] [OUT] wrote 18 header bytes -> 18
12:21:01.467490 [0-0] == Info: [0-0] [WRITE] [PAUSE] writing 18/18 bytes of type 4 -> 0
12:21:01.467491 [0-0] == Info: [0-0] [WRITE] download_write header(type=4, blen=18) -> 0
12:21:01.467492 [0-0] == Info: [0-0] [WRITE] client_write(type=4, len=18) -> 0
12:21:01.467494 [0-0] <= Recv header, 29 bytes (0x1d)
0000: [0-0] Connection: keep-alive.
12:21:01.467498 [0-0] == Info: [0-0] [WRITE] header_collect pushed(type=1, len=23) -> 0
12:21:01.467499 [0-0] == Info: [0-0] [WRITE] [OUT] wrote 23 header bytes -> 23
12:21:01.467500 [0-0] == Info: [0-0] [WRITE] [PAUSE] writing 23/23 bytes of type 4 -> 0
12:21:01.467501 [0-0] == Info: [0-0] [WRITE] download_write header(type=4, blen=23) -> 0
12:21:01.467503 [0-0] == Info: [0-0] [WRITE] client_write(type=4, len=23) -> 0
12:21:01.467504 [0-0] <= Recv header, 38 bytes (0x26)
0000: [0-0] X-Content-Type-Options: nosniff.
12:21:01.467508 [0-0] == Info: [0-0] [WRITE] header_collect pushed(type=1, len=32) -> 0
12:21:01.467509 [0-0] == Info: [0-0] [WRITE] [OUT] wrote 32 header bytes -> 32
12:21:01.467510 [0-0] == Info: [0-0] [WRITE] [PAUSE] writing 32/32 bytes of type 4 -> 0
12:21:01.467512 [0-0] == Info: [0-0] [WRITE] download_write header(type=4, blen=32) -> 0
12:21:01.467513 [0-0] == Info: [0-0] [WRITE] client_write(type=4, len=32) -> 0
12:21:01.467514 [0-0] <= Recv header, 7 bytes (0x7)
0000: [0-0] .
12:21:01.467517 [0-0] == Info: [0-0] [WRITE] header_collect pushed(type=1, len=1) -> 0
12:21:01.467518 [0-0] == Info: [0-0] [WRITE] [OUT] wrote 1 header bytes -> 1
12:21:01.467520 [0-0] == Info: [0-0] [WRITE] [PAUSE] writing 1/1 bytes of type 4 -> 0
12:21:01.467521 [0-0] == Info: [0-0] [WRITE] download_write header(type=4, blen=1) -> 0
12:21:01.467523 [0-0] == Info: [0-0] [WRITE] client_write(type=4, len=1) -> 0
12:21:01.467524 [0-0] <= Recv data, 11 bytes (0xb)
0000: [0-0] haha.
12:21:01.467528 [0-0] == Info: [0-0] [WRITE] [OUT] wrote 4 body bytes -> 4
12:21:01.467529 [0-0] == Info: [0-0] [WRITE] [PAUSE] writing 4/4 bytes of type 1 -> 0
12:21:01.467531 [0-0] == Info: [0-0] [WRITE] download_write body(type=1, blen=5) -> 0
12:21:01.467533 [0-0] == Info: [0-0] Excess found writing body: excess = 1, size = 4, maxdownload = 4, bytecount = 4
12:21:01.467535 [0-0] == Info: [0-0] [WRITE] client_write(type=1, len=5) -> 0
12:21:01.467536 [0-0] == Info: [0-0] [WRITE] xfer_write_resp(len=199, eos=0) -> 0
12:21:01.467577 [0-0] == Info: [0-0] [MULTI] [PERFORMING] -> [DONE]
12:21:01.467579 [0-0] == Info: [0-0] [MULTI] [DONE] multi_done: status: 0 prem: 0 done: 0
12:21:01.467671 [0-0] == Info: [0-0] [WRITE] [OUT] done
12:21:01.467674 [0-0] == Info: [0-0] [READ] client_reset, clear readers
12:21:01.467677 [0-x] == Info: [0-x] [MULTI] [DONE] multi_done_locked, in use=0
12:21:01.467681 [0-0] == Info: [0-0] [MULTI] [DONE] multi_done, not reusing connection=0, forbid=0, close=1, premature=0, conn_multiplex=0
12:21:01.467688 [0-0] == Info: [0-0] shutting down connection #0
12:21:01.467715 [0-0] == Info: [0-0] [MULTI] [DONE] -> [COMPLETED]
12:21:01.467718 [0-0] == Info: [0-0] [MULTI] [COMPLETED] -> [MSGSENT]
12:21:01.467722 [0-0] == Info: [0-0] [MULTI] [COMPLETED] removed from multi, mid=1, running=0, total=1
