12:21:01.045607 [0-x] == Info: [0-x] [MULTI] [INIT] added to multi, mid=1, running=1, total=2
12:21:01.045643 [0-x] == Info: [0-x] [MULTI] [INIT] -> [SETUP]
12:21:01.045646 [0-x] == Info: [0-x] [MULTI] [SETUP] -> [CONNECT]
12:21:01.045648 [0-x] == Info: [0-x] [READ] client_reset, clear readers
12:21:01.045665 [0-0] == Info: [0-0] [MULTI] [CONNECT] [CPOOL] added connection 0. The cache now contains 1 members
12:21:01.045673 [0-0] == Info: [0-0] [SETUP] added
12:21:01.045676 [0-0] == Info: [0-0] [MULTI] [CONNECT] -> [CONNECTING]
12:21:01.045817 [0-0] == Info: [0-0] [HAPPY-EYEBALLS] created ipv4 (timeout 300000ms)
12:21:01.045821 [0-0] == Info: [0-0] [HAPPY-EYEBALLS] ipv4 starting (timeout=300000ms)
12:21:01.045841 [0-0] == Info: [0-0]   Trying 127.0.0.1:34329...
12:21:01.045853 [0-0] == Info: [0-0] [TCP] cf_socket_open() -> 0, fd=6
12:21:01.045926 [0-0] == Info: [0-0] [TCP] local address 127.0.0.1 port 50454...
12:21:01.045930 [0-0] == Info: [0-0] [HAPPY-EYEBALLS] ipv4 connect -> 0, connected=0
12:21:01.045932 [0-0] == Info: [0-0] [SETUP] Curl_conn_connect(block=0) -> 0, done=0
12:21:01.045946 [0-0] == Info: [0-0] [TCP] adjust_pollset, !connected, POLLOUT fd=6
12:21:01.045947 [0-0] == Info: [0-0] [HAPPY-EYEBALLS] adjust_pollset -> 1 socks
12:21:01.045950 [0-0] == Info: [0-0] [MULTI] [CONNECTING] multi_wait pollset[fd=6 OUT], timeouts=0
12:21:01.045958 [0-0] == Info: [0-0] [TCP] connected
12:21:01.045960 [0-0] == Info: [0-0] [HAPPY-EYEBALLS] ipv4 connect -> 0, connected=1
12:21:01.045962 [0-0] == Info: [0-0] [HAPPY-EYEBALLS] Connected to 127.0.0.1 (127.0.0.1) port 34329
12:21:01.045964 [0-0] == Info: [0-0] [SETUP] Curl_conn_connect(block=0) -> 0, done=1
12:21:01.045966 [0-0] == Info: [0-0] Connected to 127.0.0.1 (127.0.0.1) port 34329
12:21:01.045968 [0-0] == Info: [0-0] using HTTP/1.x
12:21:01.045969 [0-0] == Info: [0-0] [MULTI] [CONNECTING] -> [PROTOCONNECT]
12:21:01.045971 [0-0] == Info: [0-0] [MULTI] [PROTOCONNECT] -> [DO]
12:21:01.046006 [0-0] == Info: [0-0] [TCP] send(len=120) -> 120, err=0
12:21:01.046008 [0-0] => Send header, 126 bytes (0x7e)
0000: [0-0] GET /3204 HTTP/1.1
001a: Host: 127.0.0.1:34329
0031: User-Agent: curl/8.14.1
004a: Accept: */*
0057: If-None-Match: "21025-dc7-39462498"
007c: 
12:21:01.046020 [0-0] == Info: [0-0] [MULTI] [DO] -> [DID]
12:21:01.046022 [0-0] == Info: [0-0] [MULTI] [DID] -> [PERFORMING]
12:21:01.046029 [0-0] == Info: [0-0] [TCP] recv(len=102400) -> -1, err=81
12:21:01.046032 [0-0] == Info: [0-0] Request completely sent off
12:21:01.046036 [0-0] == Info: [0-0] [MULTI] [PERFORMING] multi_wait pollset[fd=6 IN], timeouts=0
12:21:01.046144 [0-0] == Info: [0-0] [TCP] recv(len=102400) -> 80, err=0
12:21:01.046148 [0-0] <= Recv header, 32 bytes (0x20)
0000: [0-0] HTTP/1.1 304 Not Modified.
12:21:01.046182 [0-0] == Info: [0-0] [WRITE] [OUT] wrote 26 header bytes -> 26
12:21:01.046184 [0-0] == Info: [0-0] [WRITE] [PAUSE] writing 26/26 bytes of type c -> 0
12:21:01.046186 [0-0] == Info: [0-0] [WRITE] download_write header(type=c, blen=26) -> 0
12:21:01.046187 [0-0] == Info: [0-0] [WRITE] client_write(type=c, len=26) -> 0
12:21:01.046189 [0-0] <= Recv header, 42 bytes (0x2a)
0000: [0-0] Date: Tue, 09 Nov 2010 14:49:00 GMT.
12:21:01.046196 [0-0] == Info: [0-0] [WRITE] header_collect pushed(type=1, len=36) -> 0
12:21:01.046198 [0-0] == Info: [0-0] [WRITE] [OUT] wrote 36 header bytes -> 36
12:21:01.046199 [0-0] == Info: [0-0] [WRITE] [PAUSE] writing 36/36 bytes of type 4 -> 0
12:21:01.046201 [0-0] == Info: [0-0] [WRITE] download_write header(type=4, blen=36) -> 0
12:21:01.046202 [0-0] == Info: [0-0] [WRITE] client_write(type=4, len=36) -> 0
12:21:01.046204 [0-0] == Info: [0-0] [WRITE] xfer_write_resp(len=80, eos=0) -> 0
12:21:01.046209 [0-0] == Info: [0-0] [TCP] recv(len=102400) -> 35, err=0
12:21:01.046210 [0-0] <= Recv header, 31 bytes (0x1f)
0000: [0-0] Server: test-server/fake.
12:21:01.046215 [0-0] == Info: [0-0] [WRITE] header_collect pushed(type=1, len=25) -> 0
12:21:01.046216 [0-0] == Info: [0-0] [WRITE] [OUT] wrote 25 header bytes -> 25
12:21:01.046218 [0-0] == Info: [0-0] [WRITE] [PAUSE] writing 25/25 bytes of type 4 -> 0
12:21:01.046219 [0-0] == Info: [0-0] [WRITE] download_write header(type=4, blen=25) -> 0
12:21:01.046221 [0-0] == Info: [0-0] [WRITE] client_write(type=4, len=25) -> 0
12:21:01.046222 [0-0] <= Recv header, 33 bytes (0x21)
0000: [0-0] ETag: "21025-dc7-39462498".
12:21:01.046230 [0-0] == Info: [0-0] [WRITE] header_collect pushed(type=1, len=27) -> 0
12:21:01.046258 [0-0] == Info: [0-0] [WRITE] [OUT] wrote 27 header bytes -> 27
12:21:01.046260 [0-0] == Info: [0-0] [WRITE] [PAUSE] writing 27/27 bytes of type 4 -> 0
12:21:01.046261 [0-0] == Info: [0-0] [WRITE] download_write header(type=4, blen=27) -> 0
12:21:01.046263 [0-0] == Info: [0-0] [WRITE] client_write(type=4, len=27) -> 0
12:21:01.046265 [0-0] <= Recv header, 7 bytes (0x7)
0000: [0-0] .
12:21:01.046269 [0-0] == Info: [0-0] [WRITE] header_collect pushed(type=1, len=1) -> 0
12:21:01.046271 [0-0] == Info: [0-0] [WRITE] [OUT] wrote 1 header bytes -> 1
12:21:01.046272 [0-0] == Info: [0-0] [WRITE] [PAUSE] writing 1/1 bytes of type 4 -> 0
12:21:01.046274 [0-0] == Info: [0-0] [WRITE] download_write header(type=4, blen=1) -> 0
12:21:01.046275 [0-0] == Info: [0-0] [WRITE] client_write(type=4, len=1) -> 0
12:21:01.046276 [0-0] == Info: [0-0] [WRITE] xfer_write_resp(len=35, eos=0) -> 0
12:21:01.046280 [0-0] == Info: [0-0] [MULTI] [PERFORMING] -> [DONE]
12:21:01.046282 [0-0] == Info: [0-0] [MULTI] [DONE] multi_done: status: 0 prem: 0 done: 0
12:21:01.046321 [0-0] == Info: [0-0] [WRITE] [OUT] done
12:21:01.046323 [0-0] == Info: [0-0] [READ] client_reset, clear readers
12:21:01.046326 [0-x] == Info: [0-x] [MULTI] [DONE] multi_done_locked, in use=0
12:21:01.046329 [0-0] == Info: [0-0] Connection #0 to host 127.0.0.1 left intact
12:21:01.046331 [0-0] == Info: [0-0] [MULTI] [DONE] -> [COMPLETED]
12:21:01.046332 [0-0] == Info: [0-0] [MULTI] [COMPLETED] -> [MSGSENT]
12:21:01.046339 [0-0] == Info: [0-0] [MULTI] [COMPLETED] removed from multi, mid=1, running=0, total=1
