Co-Advisor
Online InterfaceSample ResultsDetails & PriceClients Factory

HTTP TEST RESULT SAMPLE

What you see below is a snapshot of an actual Web page with a Co-Advisor test result. Co-Advisor was configured to use a small subset of available test cases. We have edited Via: headers to protect the innocent. Suffice to say that the vendor has claimed HTTP/1.1 (RFC 2616) compliance and is now working on fixing the bugs.

The real Co-Advisor results page includes navigation buttons and options to customize the display. These features have been removed from this sample result.

If you have any questions or comments about this sample, please contact us.


Test configuration:

test label:sample test, take 2
device IP address:172.16.0.6
HTTP port number:8001
Co-Advisor version:head-mid29

Overall test statistics, by test case state:

success:9 cases 60%
violation:5 cases 33%
failure:1 cases 7%
 15 cases 100% 
StateTest Case Gist
successbasic HTTP connectivity test (test_case/http/basic)
successfrom-cache responses MUST include an Age header (test_case/rfc2616/ageHdr)
violationcache MUST attach Warning 113 to misses older than 86403 seconds (test_case/rfc2616/ageWarn-miss-86403)
successDUT MUST handle "chunked" response with leading zeros in chunk size sent to an HTTP/1.0 client (test_case/rfc2616/chunked-1p0-lead0s-toClt)
successDUT MUST handle "chunked" response with one 1Byte chunk sent to an HTTP/1.1 client (test_case/rfc2616/chunked-1p1-1B-toClt)
successDUT MUST handle "chunked" request with one 1Byte chunk (test_case/rfc2616/chunked-1p1-1B-toSrv)
successproxy MUST NOT forward response headers matching Connection tokens: many headers, many token lists (test_case/rfc2616/connRemoves-NxMv-toClt)
failurenew ETag field value in '200 OK' response to HEAD MUST make cache entry stale (test_case/rfc2616/headMakesStale-ETag-tellTruth)
successproxy MUST NOT forward Keep-Alive response header (test_case/rfc2616/hopHdr-Keep-Alive-toClt)
violationproxy MUST NOT forward Trailer response header (test_case/rfc2616/hopHdr-Trailer-toClt)
violationserver MUST use the strong comparison function to compare entity tags in If-Match; using weak ETag entity tag (test_case/rfc2616/ifMatch-mismatch-weak-etag)
successserver MUST NOT perform the requested method when If-None-Match matches ETag and IMS matches LMT; using strong ETag and weak If-None-Match non-empty entity tags (test_case/rfc2616/ifNoneMatch-match-imsMatch-strong-weak-full)
successproxies MUST handle 65536byte-long URIs (test_case/rfc2616/longUri-65536)
violationproxy MUST respond with 417 (Expectation Failed) if it cannot meet the "100-continueing" expectation (test_case/rfc2616/unsuppExpect-100-continueing)
violationcache MUST revalidate when stored request headers under Vary control mismatch 2nd request headers; using "Vary: X-Case-Foo" and corresponding request header values with mismatched case (test_case/rfc2616/varyMismatch-1-1-1-X-Case-Foo)

Test log follows.



Test Case: basic HTTP connectivity test

0.041| starting test case 'test_case/http/basic' from 'test_clause/http/basic' clause
0.041| IP routing suggests 172.16.0.1:8083 server address to receive requests from 172.16.0.6:8001
0.042| server listens for TCP connections at 172.16.0.1:8083
0.042| server accepting HTTP queries at 172.16.0.1:8083
0.042| client starting...
0.042| connecting to 172.16.0.6:8001; 3 attempt(s)
0.043| connected to 172.16.0.6:8001 from 172.16.0.1:15663
0.043| client will send 100 bytes
GET http://172.16.0.1:8083/nxjeyengknvy HTTP/1.1\r\n
Host: 172.16.0.1:8083\r\n
Proxy-Connection: close\r\n
\r\n

0.044| client wrote 100 bytes to 172.16.0.6:8001
0.049| server at 172.16.0.1:8083 accepted TCP connection from 172.16.0.6:42972
0.049| starting to serve connection from 172.16.0.6:42972
0.051| server read 165 bytes from 172.16.0.6:42972
0.052| server got request headers (165 bytes)
GET /nxjeyengknvy HTTP/1.1\r\n
Date: Sat, 31 Aug 2002 00:23:14 GMT\r\n
Via: 1.1 sunny:8001 (TheProxy/1.1.2)\r\n
Accept: */*\r\n
Host: 172.16.0.1:8083\r\n
User-Agent: TheProxy/1.1.2\r\n
\r\n

0.052| expecting no message body
0.052| no message body data to parse
0.053| server expects no more request data (after 165 + 0 = 165 bytes)
0.053| server will send 110 bytes
HTTP/1.1 200 OK\r\n
Date: Sat, 31 Aug 2002 00:24:08 GMT\r\n
Connection: close\r\n
Content-Length: 15\r\n
\r\n
body-vuxukyldww

0.054| server wrote 110 bytes
0.054| server closing connection to 172.16.0.6:42972
0.074| client read 127 bytes from 172.16.0.6:8001
0.074| client parsed response headers (112 bytes)
HTTP/1.1 200 OK\r\n
Date: Sat, 31 Aug 2002 00:24:08 GMT\r\n
Via: 1.1 sunny:8001 (TheProxy/1.1.2)\r\n
Content-Length: 15\r\n
\r\n

0.074| expecting 15 bytes of message body
0.075| parsed 15 bytes of message body:
body-vuxukyldww

0.075| client expects no more data (after 112 + 15 = 127 bytes)
0.075| content size: 15
0.075| client closing connection to 172.16.0.6:8001
0.075| server stops accepting HTTP queries at 172.16.0.1:8083
0.076| finished case: test_case/http/basic
0.076| result code: success
0.076| result reason: DUT proxied the response



Test Case: from-cache responses MUST include an Age header

0.076| starting test case 'test_case/rfc2616/ageHdr' from 'test_clause/rfc2616/ageHdr' clause

Subcase: cache something

0.076| starting sub-case 'test_case/rfc2616/cacheSmth'
0.076| server accepting HTTP queries at 172.16.0.1:8083
0.077| client starting...
0.077| connecting to 172.16.0.6:8001; 3 attempt(s)
0.077| connected to 172.16.0.6:8001 from 172.16.0.1:15664
0.078| client will send 100 bytes
GET http://172.16.0.1:8083/mlmebxcwkrtw HTTP/1.1\r\n
Host: 172.16.0.1:8083\r\n
Proxy-Connection: close\r\n
\r\n

0.078| client wrote 100 bytes to 172.16.0.6:8001
0.082| server at 172.16.0.1:8083 accepted TCP connection from 172.16.0.6:42973
0.082| starting to serve connection from 172.16.0.6:42973
0.084| server read 165 bytes from 172.16.0.6:42973
0.085| server got request headers (165 bytes)
GET /mlmebxcwkrtw HTTP/1.1\r\n
Date: Sat, 31 Aug 2002 00:23:14 GMT\r\n
Via: 1.1 sunny:8001 (TheProxy/1.1.2)\r\n
Accept: */*\r\n
Host: 172.16.0.1:8083\r\n
User-Agent: TheProxy/1.1.2\r\n
\r\n

0.085| expecting no message body
0.085| no message body data to parse
0.085| server expects no more request data (after 165 + 0 = 165 bytes)
0.086| server will send 219 bytes
HTTP/1.1 200 OK\r\n
Cache-Control: public\r\n
Last-Modified: Fri, 31 Aug 2001 00:24:07 GMT\r\n
Expires: Sun, 31 Aug 2003 00:24:07 GMT\r\n
Date: Sat, 31 Aug 2002 00:24:08 GMT\r\n
Connection: close\r\n
Content-Length: 15\r\n
\r\n
body-xsakamawnv

0.087| server wrote 219 bytes
0.087| server closing connection to 172.16.0.6:42973
0.102| client read 236 bytes from 172.16.0.6:8001
0.103| client parsed response headers (221 bytes)
HTTP/1.1 200 OK\r\n
Cache-Control: public\r\n
Date: Sat, 31 Aug 2002 00:24:08 GMT\r\n
Via: 1.1 sunny:8001 (TheProxy/1.1.2)\r\n
Content-Length: 15\r\n
Expires: Sun, 31 Aug 2003 00:24:07 GMT\r\n
Last-Modified: Fri, 31 Aug 2001 00:24:07 GMT\r\n
\r\n

0.103| expecting 15 bytes of message body
0.104| parsed 15 bytes of message body:
body-xsakamawnv

0.104| client expects no more data (after 221 + 15 = 236 bytes)
0.104| content size: 15
0.104| client closing connection to 172.16.0.6:8001
0.105| server stops accepting HTTP queries at 172.16.0.1:8083
0.105| finished case: test_case/rfc2616/cacheSmth
0.105| result code: success
0.105| result reason: DUT proxied the transaction and possibly cached the response

0.105| server accepting HTTP queries at 172.16.0.1:8083
0.105| client starting...
0.106| connecting to 172.16.0.6:8001; 3 attempt(s)
0.106| connected to 172.16.0.6:8001 from 172.16.0.1:15665
0.106| client will send 100 bytes
GET http://172.16.0.1:8083/mlmebxcwkrtw HTTP/1.1\r\n
Host: 172.16.0.1:8083\r\n
Proxy-Connection: close\r\n
\r\n

0.107| client wrote 100 bytes to 172.16.0.6:8001
0.113| client read 244 bytes from 172.16.0.6:8001
0.114| client parsed response headers (229 bytes)
HTTP/1.1 200 OK\r\n
Cache-Control: public\r\n
Date: Sat, 31 Aug 2002 00:24:08 GMT\r\n
Via: 1.1 sunny:8001 (TheProxy/1.1.2)\r\n
Content-Length: 15\r\n
Expires: Sun, 31 Aug 2003 00:24:07 GMT\r\n
Last-Modified: Fri, 31 Aug 2001 00:24:07 GMT\r\n
Age: 0\r\n
\r\n

0.115| expecting 15 bytes of message body
0.115| parsed 15 bytes of message body:
body-xsakamawnv

0.115| client expects no more data (after 229 + 15 = 244 bytes)
0.115| content size: 15
0.115| client closing connection to 172.16.0.6:8001
0.116| server stops accepting HTTP queries at 172.16.0.1:8083
0.116| finished case: test_case/rfc2616/ageHdr
0.116| result code: success
0.116| result reason: cache added a single Age header field



Test Case: cache MUST attach Warning 113 to misses older than 86403 seconds

0.116| starting test case 'test_case/rfc2616/ageWarn-miss-86403' from 'test_clause/rfc2616/ageWarn' clause
0.116| server accepting HTTP queries at 172.16.0.1:8083
0.117| client starting...
0.117| connecting to 172.16.0.6:8001; 3 attempt(s)
0.117| connected to 172.16.0.6:8001 from 172.16.0.1:15666
0.118| client will send 100 bytes
GET http://172.16.0.1:8083/awnevzvovtgy HTTP/1.1\r\n
Host: 172.16.0.1:8083\r\n
Proxy-Connection: close\r\n
\r\n

0.118| client wrote 100 bytes to 172.16.0.6:8001
0.122| server at 172.16.0.1:8083 accepted TCP connection from 172.16.0.6:42974
0.122| starting to serve connection from 172.16.0.6:42974
0.124| server read 165 bytes from 172.16.0.6:42974
0.124| server got request headers (165 bytes)
GET /awnevzvovtgy HTTP/1.1\r\n
Date: Sat, 31 Aug 2002 00:23:14 GMT\r\n
Via: 1.1 sunny:8001 (TheProxy/1.1.2)\r\n
Accept: */*\r\n
Host: 172.16.0.1:8083\r\n
User-Agent: TheProxy/1.1.2\r\n
\r\n

0.125| expecting no message body
0.125| no message body data to parse
0.125| server expects no more request data (after 165 + 0 = 165 bytes)
0.126| server will send 191 bytes
HTTP/1.1 200 OK\r\n
Age: 86403\r\n
Cache-Control: public\r\n
Last-Modified: Fri, 31 Aug 2001 00:24:07 GMT\r\n
Date: Sat, 31 Aug 2002 00:24:08 GMT\r\n
Connection: close\r\n
Content-Length: 15\r\n
\r\n
body-djmdfzbxns

0.127| server wrote 191 bytes
0.127| server closing connection to 172.16.0.6:42974
0.141| client read 208 bytes from 172.16.0.6:8001
0.142| client parsed response headers (193 bytes)
HTTP/1.1 200 OK\r\n
Cache-Control: public\r\n
Date: Sat, 31 Aug 2002 00:24:08 GMT\r\n
Via: 1.1 sunny:8001 (TheProxy/1.1.2)\r\n
Content-Length: 15\r\n
Last-Modified: Fri, 31 Aug 2001 00:24:07 GMT\r\n
Age: 86403\r\n
\r\n

0.142| expecting 15 bytes of message body
0.143| parsed 15 bytes of message body:
body-djmdfzbxns

0.143| client expects no more data (after 193 + 15 = 208 bytes)
0.143| content size: 15
0.143| client closing connection to 172.16.0.6:8001
0.143| server stops accepting HTTP queries at 172.16.0.1:8083
0.144| finished case: test_case/rfc2616/ageWarn-miss-86403
0.144| result code: violation
0.144| result reason: cache did not add a '113 Heuristic expiration' Warning header



Test Case: DUT MUST handle "chunked" response with leading zeros in chunk size sent to an HTTP/1.0 client

0.144| starting test case 'test_case/rfc2616/chunked-1p0-lead0s-toClt' from 'test_clause/rfc2616/chunked-1p0' clause
0.144| server accepting HTTP queries at 172.16.0.1:8083
0.145| client starting...
0.145| connecting to 172.16.0.6:8001; 3 attempt(s)
0.145| connected to 172.16.0.6:8001 from 172.16.0.1:15667
0.145| client will send 100 bytes
GET http://172.16.0.1:8083/czxkiygfogcb HTTP/1.0\r\n
Host: 172.16.0.1:8083\r\n
Proxy-Connection: close\r\n
\r\n

0.146| client wrote 100 bytes to 172.16.0.6:8001
0.150| server at 172.16.0.1:8083 accepted TCP connection from 172.16.0.6:42975
0.150| starting to serve connection from 172.16.0.6:42975
0.152| server read 165 bytes from 172.16.0.6:42975
0.153| server got request headers (165 bytes)
GET /czxkiygfogcb HTTP/1.1\r\n
Date: Sat, 31 Aug 2002 00:23:14 GMT\r\n
Via: 1.1 sunny:8001 (TheProxy/1.1.2)\r\n
Accept: */*\r\n
Host: 172.16.0.1:8083\r\n
User-Agent: TheProxy/1.1.2\r\n
\r\n

0.153| expecting no message body
0.153| no message body data to parse
0.153| server expects no more request data (after 165 + 0 = 165 bytes)
0.154| server will send 224 bytes
HTTP/1.1 200 OK\r\n
Transfer-Encoding: chunked\r\n
Date: Sat, 31 Aug 2002 00:24:08 GMT\r\n
Connection: close\r\n
\r\n
000000000064\r\n
lrymhtgkzqtqqbuqifvitkpicodgwmijdgvkcexduqtntnfbudmnnbxrrcxnpfysotfqzctwumjncoqwrcmgdlxwoxldejvsfcke\r\n
0\r\n
\r\n

0.155| server wrote 224 bytes
0.155| server closing connection to 172.16.0.6:42975
0.170| client read 192 bytes from 172.16.0.6:8001
0.170| client parsed response headers (92 bytes)
HTTP/1.1 200 OK\r\n
Date: Sat, 31 Aug 2002 00:24:08 GMT\r\n
Via: 1.1 sunny:8001 (TheProxy/1.1.2)\r\n
\r\n

0.171| warning: unknown transfer-length for a message on a persistent HTTP connection
0.171| parsed 100 bytes of message body:
lrymhtgkzqtqqbuqifvitkpicodgwmijdgvkcexduqtntnfbudmnnbxrrcxnpfysotfqzctwumjncoqwrcmgdlxwoxldejvsfcke

0.171| client read 0 bytes from 172.16.0.6:8001
0.171| no message body data to parse
0.171| client expects no more data (after 92 + 100 = 192 bytes)
0.172| content size: 100
0.172| client closing connection to 172.16.0.6:8001
0.172| server stops accepting HTTP queries at 172.16.0.1:8083
0.172| fyi: DUT decoded chunked transfer encoding on-the-fly
0.173| finished case: test_case/rfc2616/chunked-1p0-lead0s-toClt
0.173| result code: success
0.173| result reason: DUT proxied "chunked" response with leading zeros in chunk size sent to an HTTP/1.0 client



Test Case: DUT MUST handle "chunked" response with one 1Byte chunk sent to an HTTP/1.1 client

0.173| starting test case 'test_case/rfc2616/chunked-1p1-1B-toClt' from 'test_clause/rfc2616/chunked-1p1' clause
0.173| server accepting HTTP queries at 172.16.0.1:8083
0.173| client starting...
0.174| connecting to 172.16.0.6:8001; 3 attempt(s)
0.174| connected to 172.16.0.6:8001 from 172.16.0.1:15668
0.174| client will send 100 bytes
GET http://172.16.0.1:8083/mxahnycwygfk HTTP/1.1\r\n
Host: 172.16.0.1:8083\r\n
Proxy-Connection: close\r\n
\r\n

0.175| client wrote 100 bytes to 172.16.0.6:8001
0.179| server at 172.16.0.1:8083 accepted TCP connection from 172.16.0.6:42976
0.179| starting to serve connection from 172.16.0.6:42976
0.181| server read 165 bytes from 172.16.0.6:42976
0.182| server got request headers (165 bytes)
GET /mxahnycwygfk HTTP/1.1\r\n
Date: Sat, 31 Aug 2002 00:23:14 GMT\r\n
Via: 1.1 sunny:8001 (TheProxy/1.1.2)\r\n
Accept: */*\r\n
Host: 172.16.0.1:8083\r\n
User-Agent: TheProxy/1.1.2\r\n
\r\n

0.182| expecting no message body
0.182| no message body data to parse
0.182| server expects no more request data (after 165 + 0 = 165 bytes)
0.183| server will send 114 bytes
HTTP/1.1 200 OK\r\n
Transfer-Encoding: chunked\r\n
Date: Sat, 31 Aug 2002 00:24:08 GMT\r\n
Connection: close\r\n
\r\n
1\r\n
r\r\n
0\r\n
\r\n

0.184| server wrote 114 bytes
0.184| server closing connection to 172.16.0.6:42976
0.199| client read 126 bytes from 172.16.0.6:8001
0.199| client parsed response headers (120 bytes)
HTTP/1.1 200 OK\r\n
Date: Sat, 31 Aug 2002 00:24:08 GMT\r\n
Transfer-Encoding: chunked\r\n
Via: 1.1 sunny:8001 (TheProxy/1.1.2)\r\n
\r\n

0.200| expecting chunked encoding to determine message body length
0.200| parsed 6 bytes of message body:
1\r\n
r\r\n

0.200| client read 5 bytes from 172.16.0.6:8001
0.200| parsed 5 bytes of message body:
0\r\n
\r\n

0.200| client expects no more data (after 120 + 11 = 131 bytes)
0.201| fyi: successfully decoded chunked message body: 1-byte content + 0-byte trailer
0.201| content size: 1
0.201| client closing connection to 172.16.0.6:8001
0.201| server stops accepting HTTP queries at 172.16.0.1:8083
0.201| fyi: DUT preserved (did not decode) chunked transfer encoding
0.202| finished case: test_case/rfc2616/chunked-1p1-1B-toClt
0.202| result code: success
0.202| result reason: DUT proxied "chunked" response with one 1Byte chunk sent to an HTTP/1.1 client



Test Case: DUT MUST handle "chunked" request with one 1Byte chunk

0.202| starting test case 'test_case/rfc2616/chunked-1p1-1B-toSrv' from 'test_clause/rfc2616/chunked-1p1' clause
0.202| server accepting HTTP queries at 172.16.0.1:8083
0.202| client starting...
0.203| connecting to 172.16.0.6:8001; 3 attempt(s)
0.203| connected to 172.16.0.6:8001 from 172.16.0.1:15669
0.203| client will send 140 bytes
POST http://172.16.0.1:8083/lkmmjbhnbezl HTTP/1.1\r\n
Transfer-Encoding: chunked\r\n
Host: 172.16.0.1:8083\r\n
Proxy-Connection: close\r\n
\r\n
1\r\n
e\r\n
0\r\n
\r\n

0.204| client wrote 140 bytes to 172.16.0.6:8001
0.208| server at 172.16.0.1:8083 accepted TCP connection from 172.16.0.6:42977
0.208| starting to serve connection from 172.16.0.6:42977
0.210| server read 194 bytes from 172.16.0.6:42977
0.211| server got request headers (194 bytes)
POST /lkmmjbhnbezl HTTP/1.1\r\n
Date: Sat, 31 Aug 2002 00:23:14 GMT\r\n
Transfer-Encoding: chunked\r\n
Via: 1.1 sunny:8001 (TheProxy/1.1.2)\r\n
Accept: */*\r\n
Host: 172.16.0.1:8083\r\n
User-Agent: TheProxy/1.1.2\r\n
\r\n

0.212| expecting chunked encoding to determine message body length
0.212| no message body data to parse
0.212| server read 11 bytes from 172.16.0.6:42977
0.212| parsed 11 bytes of message body:
1\r\n
e\r\n
0\r\n
\r\n

0.212| server expects no more request data (after 194 + 11 = 205 bytes)
0.213| fyi: successfully decoded chunked message body: 1-byte content + 0-byte trailer
0.213| content size: 1
0.213| server will send 110 bytes
HTTP/1.1 200 OK\r\n
Date: Sat, 31 Aug 2002 00:24:08 GMT\r\n
Connection: close\r\n
Content-Length: 15\r\n
\r\n
body-dfqrnuuzru

0.214| server wrote 110 bytes
0.214| server closing connection to 172.16.0.6:42977
0.216| client read 127 bytes from 172.16.0.6:8001
0.216| client parsed response headers (112 bytes)
HTTP/1.1 200 OK\r\n
Date: Sat, 31 Aug 2002 00:24:08 GMT\r\n
Via: 1.1 sunny:8001 (TheProxy/1.1.2)\r\n
Content-Length: 15\r\n
\r\n

0.217| expecting 15 bytes of message body
0.217| parsed 15 bytes of message body:
body-dfqrnuuzru

0.217| client expects no more data (after 112 + 15 = 127 bytes)
0.217| content size: 15
0.217| client closing connection to 172.16.0.6:8001
0.218| server stops accepting HTTP queries at 172.16.0.1:8083
0.218| fyi: DUT preserved (did not decode) chunked transfer encoding
0.218| finished case: test_case/rfc2616/chunked-1p1-1B-toSrv
0.218| result code: success
0.218| result reason: DUT proxied "chunked" request with one 1Byte chunk



Test Case: proxy MUST NOT forward response headers matching Connection tokens: many headers, many token lists

0.219| starting test case 'test_case/rfc2616/connRemoves-NxMv-toClt' from 'test_clause/rfc2616/connRemoves' clause
0.219| server accepting HTTP queries at 172.16.0.1:8083
0.219| client starting...
0.220| connecting to 172.16.0.6:8001; 3 attempt(s)
0.220| connected to 172.16.0.6:8001 from 172.16.0.1:15670
0.220| client will send 100 bytes
GET http://172.16.0.1:8083/jhuldsriffur HTTP/1.1\r\n
Host: 172.16.0.1:8083\r\n
Proxy-Connection: close\r\n
\r\n

0.221| client wrote 100 bytes to 172.16.0.6:8001
0.224| server at 172.16.0.1:8083 accepted TCP connection from 172.16.0.6:42978
0.224| starting to serve connection from 172.16.0.6:42978
0.227| server read 165 bytes from 172.16.0.6:42978
0.227| server got request headers (165 bytes)
GET /jhuldsriffur HTTP/1.1\r\n
Date: Sat, 31 Aug 2002 00:23:14 GMT\r\n
Via: 1.1 sunny:8001 (TheProxy/1.1.2)\r\n
Accept: */*\r\n
Host: 172.16.0.1:8083\r\n
User-Agent: TheProxy/1.1.2\r\n
\r\n

0.228| expecting no message body
0.228| no message body data to parse
0.228| server expects no more request data (after 165 + 0 = 165 bytes)
0.232| server will send 1577 bytes
HTTP/1.1 200 OK\r\n
X-skhtidaleba: spipnysmjqgicyu-0\r\n
X-vbrdthdnto: culmsdzawhfyvja\r\n
Connection: X-skhtidaleba\r\n
X-bwkaqmwbyog: zqdjvdehfbkxffe-1\r\n
X-awiffdijmq: ktzzybxkdqwztxp\r\n
Connection: X-bwkaqmwbyog\r\n
X-xnsctexsfyv: fiyvfzocrqazvgc-2\r\n
X-upysmdubhl: grrweolysefqwko\r\n
Connection: X-xnsctexsfyv\r\n
X-ikpoovhftmv: emoirggewavuviy-3\r\n
X-dnntvhzelv: plhccpmtfaonijb\r\n
Connection: X-ikpoovhftmv\r\n
X-gsbqsqbzugp: gzujojrxkwnaqkh-4\r\n
X-nsckbstupg: rounvchwtzpwyjc\r\n
Connection: X-gsbqsqbzugp\r\n
[ ... 605 bytes, 16 lines, MD5: 2c4e481381559e5a4a8310803a7dd2c3 ]\n
X-nyfndwwlynf: wmiffzglfagsavx-13\r\n
X-sfyaldbtri: anuxdjxlzajxlhk\r\n
Connection: X-ucuobdjvdvh,X-tvsfolizygh,X-myijismrpou,X-nyfndwwlynf\r\n
X-xdwsayiuvnf: uyewjchqozwnbjq-14\r\n
X-tkvygfcpvq: echnxfqvzsthmqv\r\n
X-tdtewyidngk: ddkvxtjnoeymcgr-15\r\n
X-qakcxhwixm: gyozokeiqaizgvh\r\n
Connection: X-xdwsayiuvnf,X-tdtewyidngk\r\n
X-xardrxbiwss: bcidkjjhqchbggk-16\r\n
X-nshxwthfaz: eohtcettklrnvpf\r\n
Connection: X-xardrxbiwss\r\n
Date: Sat, 31 Aug 2002 00:24:08 GMT\r\n
Connection: close\r\n
Content-Length: 15\r\n
\r\n
body-rduatvfsgk

0.235| server wrote 1577 bytes
0.235| server closing connection to 172.16.0.6:42978
0.264| client read 654 bytes from 172.16.0.6:8001
0.266| client parsed response headers (639 bytes)
HTTP/1.1 200 OK\r\n
Date: Sat, 31 Aug 2002 00:24:08 GMT\r\n
Via: 1.1 sunny:8001 (TheProxy/1.1.2)\r\n
Content-Length: 15\r\n
X-vbrdthdnto: culmsdzawhfyvja\r\n
X-awiffdijmq: ktzzybxkdqwztxp\r\n
X-upysmdubhl: grrweolysefqwko\r\n
X-dnntvhzelv: plhccpmtfaonijb\r\n
X-nsckbstupg: rounvchwtzpwyjc\r\n
X-aevkbnrvpd: oqvwjzerqxtukiw\r\n
X-dozmeupgyt: gjbjpmaqrwfqntz\r\n
X-eoerebuftz: kqydzjvxiceqkwp\r\n
X-zrgwrrjbvo: bdhctifbthwxkvc\r\n
X-duwbnjwqtv: jvuymkwiyijsclh\r\n
X-xegeqveexo: qblnxfqrttxeocz\r\n
X-pwbshabhdj: hbhxzctujjfsifa\r\n
X-utnaocxsxx: qhiaouyzdhrpygd\r\n
X-sfyaldbtri: anuxdjxlzajxlhk\r\n
X-tkvygfcpvq: echnxfqvzsthmqv\r\n
X-qakcxhwixm: gyozokeiqaizgvh\r\n
X-nshxwthfaz: eohtcettklrnvpf\r\n
\r\n

0.267| expecting 15 bytes of message body
0.268| parsed 15 bytes of message body:
body-rduatvfsgk

0.268| client expects no more data (after 639 + 15 = 654 bytes)
0.268| content size: 15
0.268| client closing connection to 172.16.0.6:8001
0.268| server stops accepting HTTP queries at 172.16.0.1:8083
0.269| found correctly removed response header field: X-skhtidaleba
0.269| found correctly removed response header field: X-bwkaqmwbyog
0.269| found correctly removed response header field: X-xnsctexsfyv
0.270| found correctly removed response header field: X-ikpoovhftmv
0.270| found correctly removed response header field: X-gsbqsqbzugp
0.270| found correctly removed response header field: X-eiipradlpap
0.271| found correctly removed response header field: X-iozfupxpmgq
0.271| found correctly removed response header field: X-lswxeeigqxu
0.271| found correctly removed response header field: X-zcilctdbyfm
0.272| found correctly removed response header field: X-qywokzuscna
0.272| found correctly removed response header field: X-ucuobdjvdvh
0.273| found correctly removed response header field: X-tvsfolizygh
0.273| found correctly removed response header field: X-myijismrpou
0.274| found correctly removed response header field: X-nyfndwwlynf
0.274| found correctly removed response header field: X-xdwsayiuvnf
0.274| found correctly removed response header field: X-tdtewyidngk
0.275| found correctly removed response header field: X-xardrxbiwss
0.275| found correctly forwarded response header field: X-vbrdthdnto
0.276| found correctly forwarded response header field: X-awiffdijmq
0.277| found correctly forwarded response header field: X-upysmdubhl
0.277| found correctly forwarded response header field: X-dnntvhzelv
0.278| found correctly forwarded response header field: X-nsckbstupg
0.278| found correctly forwarded response header field: X-aevkbnrvpd
0.279| found correctly forwarded response header field: X-dozmeupgyt
0.280| found correctly forwarded response header field: X-eoerebuftz
0.280| found correctly forwarded response header field: X-zrgwrrjbvo
0.281| found correctly forwarded response header field: X-duwbnjwqtv
0.282| found correctly forwarded response header field: X-xegeqveexo
0.283| found correctly forwarded response header field: X-pwbshabhdj
0.284| found correctly forwarded response header field: X-utnaocxsxx
0.284| found correctly forwarded response header field: X-sfyaldbtri
0.285| found correctly forwarded response header field: X-tkvygfcpvq
0.286| found correctly forwarded response header field: X-qakcxhwixm
0.287| found correctly forwarded response header field: X-nshxwthfaz
0.287| finished case: test_case/rfc2616/connRemoves-NxMv-toClt
0.287| result code: success
0.287| result reason: DUT removed all header fields matching Connection tokens without removing non-matching header fields



Test Case: new ETag field value in '200 OK' response to HEAD MUST make cache entry stale

0.288| starting test case 'test_case/rfc2616/headMakesStale-ETag-tellTruth' from 'test_clause/rfc2616/headMakesStale' clause
0.288| step 1.1: attempting to cache something

Subcase: cache something

0.288| starting sub-case 'test_case/rfc2616/cacheSmth'
0.289| server accepting HTTP queries at 172.16.0.1:8083
0.289| client starting...
0.289| connecting to 172.16.0.6:8001; 3 attempt(s)
0.290| connected to 172.16.0.6:8001 from 172.16.0.1:15671
0.290| client will send 100 bytes
GET http://172.16.0.1:8083/uohxgbhlgbez HTTP/1.1\r\n
Host: 172.16.0.1:8083\r\n
Proxy-Connection: close\r\n
\r\n

0.290| client wrote 100 bytes to 172.16.0.6:8001
0.294| server at 172.16.0.1:8083 accepted TCP connection from 172.16.0.6:42979
0.294| starting to serve connection from 172.16.0.6:42979
0.297| server read 165 bytes from 172.16.0.6:42979
0.298| server got request headers (165 bytes)
GET /uohxgbhlgbez HTTP/1.1\r\n
Date: Sat, 31 Aug 2002 00:23:14 GMT\r\n
Via: 1.1 sunny:8001 (TheProxy/1.1.2)\r\n
Accept: */*\r\n
Host: 172.16.0.1:8083\r\n
User-Agent: TheProxy/1.1.2\r\n
\r\n

0.298| expecting no message body
0.298| no message body data to parse
0.298| server expects no more request data (after 165 + 0 = 165 bytes)
0.299| server will send 257 bytes
HTTP/1.1 200 OK\r\n
Last-Modified: Fri, 31 Aug 2001 00:24:07 GMT\r\n
Content-Length: 15\r\n
ETag: "tag-3qu2KI2IQwuSRCTGAD5OoQ=="\r\n
Expires: Sun, 31 Aug 2003 00:24:08 GMT\r\n
Cache-Control: public\r\n
Date: Sat, 31 Aug 2002 00:24:08 GMT\r\n
Connection: close\r\n
\r\n
body-yzdntxnntw

0.300| server wrote 257 bytes
0.300| server closing connection to 172.16.0.6:42979
0.316| client read 274 bytes from 172.16.0.6:8001
0.317| client parsed response headers (259 bytes)
HTTP/1.1 200 OK\r\n
Cache-Control: public\r\n
Date: Sat, 31 Aug 2002 00:24:08 GMT\r\n
Via: 1.1 sunny:8001 (TheProxy/1.1.2)\r\n
Content-Length: 15\r\n
Etag: "tag-3qu2KI2IQwuSRCTGAD5OoQ=="\r\n
Expires: Sun, 31 Aug 2003 00:24:08 GMT\r\n
Last-Modified: Fri, 31 Aug 2001 00:24:07 GMT\r\n
\r\n

0.317| expecting 15 bytes of message body
0.318| parsed 15 bytes of message body:
body-yzdntxnntw

0.318| client expects no more data (after 259 + 15 = 274 bytes)
0.318| content size: 15
0.318| client closing connection to 172.16.0.6:8001
0.318| server stops accepting HTTP queries at 172.16.0.1:8083
0.319| finished case: test_case/rfc2616/cacheSmth
0.319| result code: success
0.319| result reason: DUT proxied the transaction and possibly cached the response

0.319| step 1.2: verifying whether we cached anything

Subcase: check that a given entity instance is cached and fresh

0.319| starting sub-case 'test_case/rfc2616/checkCached-fresh'
0.319| server accepting HTTP queries at 172.16.0.1:8083
0.320| client starting...
0.320| connecting to 172.16.0.6:8001; 3 attempt(s)
0.320| connected to 172.16.0.6:8001 from 172.16.0.1:15672
0.321| client will send 100 bytes
GET http://172.16.0.1:8083/uohxgbhlgbez HTTP/1.1\r\n
Host: 172.16.0.1:8083\r\n
Proxy-Connection: close\r\n
\r\n

0.321| client wrote 100 bytes to 172.16.0.6:8001
0.328| client read 282 bytes from 172.16.0.6:8001
0.329| client parsed response headers (267 bytes)
HTTP/1.1 200 OK\r\n
Cache-Control: public\r\n
Date: Sat, 31 Aug 2002 00:24:08 GMT\r\n
Via: 1.1 sunny:8001 (TheProxy/1.1.2)\r\n
Content-Length: 15\r\n
Etag: "tag-3qu2KI2IQwuSRCTGAD5OoQ=="\r\n
Expires: Sun, 31 Aug 2003 00:24:08 GMT\r\n
Last-Modified: Fri, 31 Aug 2001 00:24:07 GMT\r\n
Age: 0\r\n
\r\n

0.329| expecting 15 bytes of message body
0.330| parsed 15 bytes of message body:
body-yzdntxnntw

0.330| client expects no more data (after 267 + 15 = 282 bytes)
0.330| content size: 15
0.330| client closing connection to 172.16.0.6:8001
0.330| server stops accepting HTTP queries at 172.16.0.1:8083
0.331| finished case: test_case/rfc2616/checkCached-fresh
0.331| result code: success
0.331| result reason: at the time of the request, entity instance appeared to be cached and fresh

0.331| step 1.3: sending an uncachable HEAD to later check whether it purges the object

Subcase: check that a max-age=0 HEAD request reaches the server

0.331| starting sub-case 'test_case/rfc2616/maxAge0-HEAD'
0.331| sleeping for 2 seconds to make "Cache-Control: max-age=0" request header work
2.332| server accepting HTTP queries at 172.16.0.1:8083
2.332| client starting...
2.333| connecting to 172.16.0.6:8001; 3 attempt(s)
2.333| connected to 172.16.0.6:8001 from 172.16.0.1:15673
2.333| client will send 127 bytes
HEAD http://172.16.0.1:8083/uohxgbhlgbez HTTP/1.1\r\n
Cache-Control: max-age=0\r\n
Host: 172.16.0.1:8083\r\n
Proxy-Connection: close\r\n
\r\n

2.334| client wrote 127 bytes to 172.16.0.6:8001
2.339| server at 172.16.0.1:8083 accepted TCP connection from 172.16.0.6:42980
2.339| starting to serve connection from 172.16.0.6:42980
2.341| server read 192 bytes from 172.16.0.6:42980
2.342| server got request headers (192 bytes)
HEAD /uohxgbhlgbez HTTP/1.1\r\n
Cache-Control: max-age=0\r\n
Date: Sat, 31 Aug 2002 00:23:16 GMT\r\n
Via: 1.1 sunny:8001 (TheProxy/1.1.2)\r\n
Accept: */*\r\n
Host: 172.16.0.1:8083\r\n
User-Agent: TheProxy/1.1.2\r\n
\r\n

2.342| expecting no message body for HEAD
2.343| no message body data to parse
2.343| server expects no more request data (after 192 + 0 = 192 bytes)
2.344| server will send 242 bytes
HTTP/1.1 200 OK\r\n
Last-Modified: Fri, 31 Aug 2001 00:24:07 GMT\r\n
Content-Length: 15\r\n
ETag: "tag-3qu2KI2IQwuSRCTGAD5OoQ=="\r\n
Expires: Sun, 31 Aug 2003 00:24:08 GMT\r\n
Cache-Control: public\r\n
Date: Sat, 31 Aug 2002 00:24:10 GMT\r\n
Connection: close\r\n
\r\n

2.344| server wrote 242 bytes
2.344| server closing connection to 172.16.0.6:42980
2.347| client read 259 bytes from 172.16.0.6:8001
2.348| client parsed response headers (259 bytes)
HTTP/1.1 200 OK\r\n
Cache-Control: public\r\n
Date: Sat, 31 Aug 2002 00:24:10 GMT\r\n
Via: 1.1 sunny:8001 (TheProxy/1.1.2)\r\n
Content-Length: 15\r\n
Etag: "tag-3qu2KI2IQwuSRCTGAD5OoQ=="\r\n
Expires: Sun, 31 Aug 2003 00:24:08 GMT\r\n
Last-Modified: Fri, 31 Aug 2001 00:24:07 GMT\r\n
\r\n

2.348| expecting no message body for HEAD
2.348| no message body data to parse
2.348| client expects no more data (after 259 + 0 = 259 bytes)
2.349| client closing connection to 172.16.0.6:8001
2.349| server stops accepting HTTP queries at 172.16.0.1:8083
2.349| finished case: test_case/rfc2616/maxAge0-HEAD
2.349| result code: success
2.349| result reason: a max-age=0 HEAD request reached the server

2.350| step 1.4: checking whether previous uncachable HEAD purged the object

Subcase: check that a given entity instance is cached and fresh

2.350| starting sub-case 'test_case/rfc2616/checkCached-fresh'
2.350| server accepting HTTP queries at 172.16.0.1:8083
2.351| client starting...
2.351| connecting to 172.16.0.6:8001; 3 attempt(s)
2.351| connected to 172.16.0.6:8001 from 172.16.0.1:15674
2.351| client will send 100 bytes
GET http://172.16.0.1:8083/uohxgbhlgbez HTTP/1.1\r\n
Host: 172.16.0.1:8083\r\n
Proxy-Connection: close\r\n
\r\n

2.352| client wrote 100 bytes to 172.16.0.6:8001
2.368| server at 172.16.0.1:8083 accepted TCP connection from 172.16.0.6:42981
2.369| starting to serve connection from 172.16.0.6:42981
2.372| server read 262 bytes from 172.16.0.6:42981
2.373| server got request headers (262 bytes)
GET /uohxgbhlgbez HTTP/1.1\r\n
Date: Sat, 31 Aug 2002 00:23:16 GMT\r\n
Via: 1.1 sunny:8001 (TheProxy/1.1.2)\r\n
Accept: */*\r\n
Host: 172.16.0.1:8083\r\n
If-Modified-Since: Fri, 31 Aug 2001 00:24:07 GMT\r\n
If-None-Match: "tag-3qu2KI2IQwuSRCTGAD5OoQ=="\r\n
User-Agent: TheProxy/1.1.2\r\n
\r\n

2.373| expecting no message body
2.374| no message body data to parse
2.374| server expects no more request data (after 262 + 0 = 262 bytes)
2.376| server will send 272 bytes
HTTP/1.1 304 Not Modified\r\n
Content-Length: 15\r\n
Last-Modified: Fri, 31 Aug 2001 00:24:07 GMT\r\n
Content-Length: 15\r\n
ETag: "tag-3qu2KI2IQwuSRCTGAD5OoQ=="\r\n
Expires: Sun, 31 Aug 2003 00:24:08 GMT\r\n
Cache-Control: public\r\n
Date: Sat, 31 Aug 2002 00:24:10 GMT\r\n
Connection: close\r\n
\r\n

2.376| server wrote 272 bytes
2.376| server closing connection to 172.16.0.6:42981
2.387| client read 282 bytes from 172.16.0.6:8001
2.388| client parsed response headers (267 bytes)
HTTP/1.1 200 OK\r\n
Cache-Control: public\r\n
Date: Sat, 31 Aug 2002 00:24:10 GMT\r\n
Via: 1.1 sunny:8001 (TheProxy/1.1.2)\r\n
Content-Length: 15\r\n
Etag: "tag-3qu2KI2IQwuSRCTGAD5OoQ=="\r\n
Expires: Sun, 31 Aug 2003 00:24:08 GMT\r\n
Last-Modified: Fri, 31 Aug 2001 00:24:07 GMT\r\n
Age: 0\r\n
\r\n

2.388| expecting 15 bytes of message body
2.389| parsed 15 bytes of message body:
body-yzdntxnntw

2.389| client expects no more data (after 267 + 15 = 282 bytes)
2.389| content size: 15
2.389| client closing connection to 172.16.0.6:8001
2.389| server stops accepting HTTP queries at 172.16.0.1:8083
2.390| finished case: test_case/rfc2616/checkCached-fresh
2.390| result code: failure
2.390| result reason: at the time of the request, entity instance appeared to be cached and stale rather than being cached and fresh

2.390| finished case: test_case/rfc2616/headMakesStale-ETag-tellTruth
2.390| result code: failure
2.390| result reason: precondition failed: failed to confirm that the object is still cached and fresh after an uncachable HEAD request



Test Case: proxy MUST NOT forward Keep-Alive response header

2.390| starting test case 'test_case/rfc2616/hopHdr-Keep-Alive-toClt' from 'test_clause/rfc2616/hopHdr' clause
2.391| server accepting HTTP queries at 172.16.0.1:8083
2.391| client starting...
2.391| connecting to 172.16.0.6:8001; 3 attempt(s)
2.392| connected to 172.16.0.6:8001 from 172.16.0.1:15675
2.392| client will send 100 bytes
GET http://172.16.0.1:8083/trjolnmrphnb HTTP/1.1\r\n
Host: 172.16.0.1:8083\r\n
Proxy-Connection: close\r\n
\r\n

2.392| client wrote 100 bytes to 172.16.0.6:8001
2.396| server at 172.16.0.1:8083 accepted TCP connection from 172.16.0.6:42982
2.396| starting to serve connection from 172.16.0.6:42982
2.399| server read 165 bytes from 172.16.0.6:42982
2.399| server got request headers (165 bytes)
GET /trjolnmrphnb HTTP/1.1\r\n
Date: Sat, 31 Aug 2002 00:23:16 GMT\r\n
Via: 1.1 sunny:8001 (TheProxy/1.1.2)\r\n
Accept: */*\r\n
Host: 172.16.0.1:8083\r\n
User-Agent: TheProxy/1.1.2\r\n
\r\n

2.400| expecting no message body
2.400| no message body data to parse
2.400| server expects no more request data (after 165 + 0 = 165 bytes)
2.401| server will send 132 bytes
HTTP/1.1 200 OK\r\n
Keep-Alive: rfc=2068\r\n
Date: Sat, 31 Aug 2002 00:24:10 GMT\r\n
Connection: close\r\n
Content-Length: 15\r\n
\r\n
body-wgkagrljgk

2.401| server wrote 132 bytes
2.401| server closing connection to 172.16.0.6:42982
2.421| client read 127 bytes from 172.16.0.6:8001
2.422| client parsed response headers (112 bytes)
HTTP/1.1 200 OK\r\n
Date: Sat, 31 Aug 2002 00:24:10 GMT\r\n
Via: 1.1 sunny:8001 (TheProxy/1.1.2)\r\n
Content-Length: 15\r\n
\r\n

2.422| expecting 15 bytes of message body
2.422| parsed 15 bytes of message body:
body-wgkagrljgk

2.422| client expects no more data (after 112 + 15 = 127 bytes)
2.422| content size: 15
2.423| client closing connection to 172.16.0.6:8001
2.423| server stops accepting HTTP queries at 172.16.0.1:8083
2.423| finished case: test_case/rfc2616/hopHdr-Keep-Alive-toClt
2.423| result code: success
2.423| result reason: DUT did not forward "Keep-Alive: rfc=2068" hop-by-hop header



Test Case: proxy MUST NOT forward Trailer response header

2.423| starting test case 'test_case/rfc2616/hopHdr-Trailer-toClt' from 'test_clause/rfc2616/hopHdr' clause
2.424| server accepting HTTP queries at 172.16.0.1:8083
2.424| client starting...
2.424| connecting to 172.16.0.6:8001; 3 attempt(s)
2.425| connected to 172.16.0.6:8001 from 172.16.0.1:15676
2.425| client will send 100 bytes
GET http://172.16.0.1:8083/uxpevvrtzmmj HTTP/1.1\r\n
Host: 172.16.0.1:8083\r\n
Proxy-Connection: close\r\n
\r\n

2.425| client wrote 100 bytes to 172.16.0.6:8001
2.429| server at 172.16.0.1:8083 accepted TCP connection from 172.16.0.6:42983
2.430| starting to serve connection from 172.16.0.6:42983
2.432| server read 165 bytes from 172.16.0.6:42983
2.433| server got request headers (165 bytes)
GET /uxpevvrtzmmj HTTP/1.1\r\n
Date: Sat, 31 Aug 2002 00:23:16 GMT\r\n
Via: 1.1 sunny:8001 (TheProxy/1.1.2)\r\n
Accept: */*\r\n
Host: 172.16.0.1:8083\r\n
User-Agent: TheProxy/1.1.2\r\n
\r\n

2.433| expecting no message body
2.433| no message body data to parse
2.433| server expects no more request data (after 165 + 0 = 165 bytes)
2.434| server will send 121 bytes
HTTP/1.1 200 OK\r\n
Trailer: \r\n
Date: Sat, 31 Aug 2002 00:24:10 GMT\r\n
Connection: close\r\n
Content-Length: 15\r\n
\r\n
body-byynrnvert

2.435| server wrote 121 bytes
2.435| server closing connection to 172.16.0.6:42983
2.455| client read 138 bytes from 172.16.0.6:8001
2.455| client parsed response headers (123 bytes)
HTTP/1.1 200 OK\r\n
Date: Sat, 31 Aug 2002 00:24:10 GMT\r\n
Via: 1.1 sunny:8001 (TheProxy/1.1.2)\r\n
Trailer: \r\n
Content-Length: 15\r\n
\r\n

2.456| expecting 15 bytes of message body
2.456| parsed 15 bytes of message body:
body-byynrnvert

2.456| client expects no more data (after 123 + 15 = 138 bytes)
2.456| content size: 15
2.457| client closing connection to 172.16.0.6:8001
2.457| server stops accepting HTTP queries at 172.16.0.1:8083
2.457| finished case: test_case/rfc2616/hopHdr-Trailer-toClt
2.457| result code: violation
2.457| result reason: DUT forwarded "Trailer: " hop-by-hop header



Test Case: server MUST use the strong comparison function to compare entity tags in If-Match; using weak ETag entity tag

2.457| starting test case 'test_case/rfc2616/ifMatch-mismatch-weak-etag' from 'test_clause/rfc2616/ifMatch-mismatch-weak' clause
2.458| step 1.1: attempting to cache an entity with a weak ETag header

Subcase: cache something

2.458| starting sub-case 'test_case/rfc2616/cacheSmth'
2.458| server accepting HTTP queries at 172.16.0.1:8083
2.459| client starting...
2.459| connecting to 172.16.0.6:8001; 3 attempt(s)
2.459| connected to 172.16.0.6:8001 from 172.16.0.1:15677
2.459| client will send 100 bytes
GET http://172.16.0.1:8083/kdtquhbcrvai HTTP/1.1\r\n
Host: 172.16.0.1:8083\r\n
Proxy-Connection: close\r\n
\r\n

2.460| client wrote 100 bytes to 172.16.0.6:8001
2.464| server at 172.16.0.1:8083 accepted TCP connection from 172.16.0.6:42984
2.464| starting to serve connection from 172.16.0.6:42984
2.472| server read 165 bytes from 172.16.0.6:42984
2.472| server got request headers (165 bytes)
GET /kdtquhbcrvai HTTP/1.1\r\n
Date: Sat, 31 Aug 2002 00:23:16 GMT\r\n
Via: 1.1 sunny:8001 (TheProxy/1.1.2)\r\n
Accept: */*\r\n
Host: 172.16.0.1:8083\r\n
User-Agent: TheProxy/1.1.2\r\n
\r\n

2.473| expecting no message body
2.473| no message body data to parse
2.473| server expects no more request data (after 165 + 0 = 165 bytes)
2.474| server will send 236 bytes
HTTP/1.1 200 OK\r\n
Cache-Control: public\r\n
Last-Modified: Fri, 31 Aug 2001 00:24:07 GMT\r\n
Expires: Sun, 31 Aug 2003 00:24:07 GMT\r\n
ETag: W/"match"\r\n
Date: Sat, 31 Aug 2002 00:24:10 GMT\r\n
Connection: close\r\n
Content-Length: 15\r\n
\r\n
body-cvftqefdpi

2.475| server wrote 236 bytes
2.475| server closing connection to 172.16.0.6:42984
2.493| client read 253 bytes from 172.16.0.6:8001
2.494| client parsed response headers (238 bytes)
HTTP/1.1 200 OK\r\n
Cache-Control: public\r\n
Date: Sat, 31 Aug 2002 00:24:10 GMT\r\n
Via: 1.1 sunny:8001 (TheProxy/1.1.2)\r\n
Content-Length: 15\r\n
Etag: W/"match"\r\n
Expires: Sun, 31 Aug 2003 00:24:07 GMT\r\n
Last-Modified: Fri, 31 Aug 2001 00:24:07 GMT\r\n
\r\n

2.494| expecting 15 bytes of message body
2.494| parsed 15 bytes of message body:
body-cvftqefdpi

2.495| client expects no more data (after 238 + 15 = 253 bytes)
2.495| content size: 15
2.495| client closing connection to 172.16.0.6:8001
2.495| server stops accepting HTTP queries at 172.16.0.1:8083
2.495| finished case: test_case/rfc2616/cacheSmth
2.495| result code: success
2.495| result reason: DUT proxied the transaction and possibly cached the response

2.496| step 1.2: verifying whether the cache cached the entity

Subcase: check that a given entity instance is cached and fresh

2.496| starting sub-case 'test_case/rfc2616/checkCached-fresh'
2.496| server accepting HTTP queries at 172.16.0.1:8083
2.497| client starting...
2.497| connecting to 172.16.0.6:8001; 3 attempt(s)
2.497| connected to 172.16.0.6:8001 from 172.16.0.1:15678
2.498| client will send 100 bytes
GET http://172.16.0.1:8083/kdtquhbcrvai HTTP/1.1\r\n
Host: 172.16.0.1:8083\r\n
Proxy-Connection: close\r\n
\r\n

2.498| client wrote 100 bytes to 172.16.0.6:8001
2.504| client read 261 bytes from 172.16.0.6:8001
2.505| client parsed response headers (246 bytes)
HTTP/1.1 200 OK\r\n
Cache-Control: public\r\n
Date: Sat, 31 Aug 2002 00:24:10 GMT\r\n
Via: 1.1 sunny:8001 (TheProxy/1.1.2)\r\n
Content-Length: 15\r\n
Etag: W/"match"\r\n
Expires: Sun, 31 Aug 2003 00:24:07 GMT\r\n
Last-Modified: Fri, 31 Aug 2001 00:24:07 GMT\r\n
Age: 0\r\n
\r\n

2.506| expecting 15 bytes of message body
2.506| parsed 15 bytes of message body:
body-cvftqefdpi

2.506| client expects no more data (after 246 + 15 = 261 bytes)
2.507| content size: 15
2.507| client closing connection to 172.16.0.6:8001
2.507| server stops accepting HTTP queries at 172.16.0.1:8083
2.507| finished case: test_case/rfc2616/checkCached-fresh
2.507| result code: success
2.507| result reason: at the time of the request, entity instance appeared to be cached and fresh

2.507| step 1.3: sending an If-Match request, expecting a 412 Precondition Failed response

Subcase: expect 412"Precondition Failed" response status

2.508| starting sub-case 'test_case/rfc2616/cacheSmth'
2.508| server accepting HTTP queries at 172.16.0.1:8083
2.508| client starting...
2.509| connecting to 172.16.0.6:8001; 3 attempt(s)
2.509| connected to 172.16.0.6:8001 from 172.16.0.1:15679
2.510| client will send 119 bytes
GET http://172.16.0.1:8083/kdtquhbcrvai HTTP/1.1\r\n
If-Match: "match"\r\n
Host: 172.16.0.1:8083\r\n
Proxy-Connection: close\r\n
\r\n

2.510| client wrote 119 bytes to 172.16.0.6:8001
2.517| client read 261 bytes from 172.16.0.6:8001
2.518| client parsed response headers (246 bytes)
HTTP/1.1 200 OK\r\n
Cache-Control: public\r\n
Date: Sat, 31 Aug 2002 00:24:10 GMT\r\n
Via: 1.1 sunny:8001 (TheProxy/1.1.2)\r\n
Content-Length: 15\r\n
Etag: W/"match"\r\n
Expires: Sun, 31 Aug 2003 00:24:07 GMT\r\n
Last-Modified: Fri, 31 Aug 2001 00:24:07 GMT\r\n
Age: 0\r\n
\r\n

2.518| expecting 15 bytes of message body
2.519| parsed 15 bytes of message body:
body-cvftqefdpi

2.519| client expects no more data (after 246 + 15 = 261 bytes)
2.519| content size: 15
2.519| client closing connection to 172.16.0.6:8001
2.519| server stops accepting HTTP queries at 172.16.0.1:8083
2.519| finished case: test_case/rfc2616/cacheSmth
2.519| result code: violation
2.519| result reason: DUT responded with '200' status code but should respond with 412"Precondition Failed"

2.520| finished case: test_case/rfc2616/ifMatch-mismatch-weak-etag
2.520| result code: violation
2.520| result reason: cache produced a hit instead of responding with 412 Precondition Failed when weak entity tags were used; using weak ETag entity tag



Test Case: server MUST NOT perform the requested method when If-None-Match matches ETag and IMS matches LMT; using strong ETag and weak If-None-Match non-empty entity tags

2.520| starting test case 'test_case/rfc2616/ifNoneMatch-match-imsMatch-strong-weak-full' from 'test_clause/rfc2616/ifNoneMatch-match-imsMatch' clause
2.521| step 1.1: attempting to cache an entity with an ETag header

Subcase: cache something

2.521| starting sub-case 'test_case/rfc2616/cacheSmth'
2.521| server accepting HTTP queries at 172.16.0.1:8083
2.521| client starting...
2.521| connecting to 172.16.0.6:8001; 3 attempt(s)
2.522| connected to 172.16.0.6:8001 from 172.16.0.1:15680
2.522| client will send 100 bytes
GET http://172.16.0.1:8083/vobdtsnuiloo HTTP/1.1\r\n
Host: 172.16.0.1:8083\r\n
Proxy-Connection: close\r\n
\r\n

2.523| client wrote 100 bytes to 172.16.0.6:8001
2.526| server at 172.16.0.1:8083 accepted TCP connection from 172.16.0.6:42985
2.527| starting to serve connection from 172.16.0.6:42985
2.528| server read 165 bytes from 172.16.0.6:42985
2.529| server got request headers (165 bytes)
GET /vobdtsnuiloo HTTP/1.1\r\n
Date: Sat, 31 Aug 2002 00:23:16 GMT\r\n
Via: 1.1 sunny:8001 (TheProxy/1.1.2)\r\n
Accept: */*\r\n
Host: 172.16.0.1:8083\r\n
User-Agent: TheProxy/1.1.2\r\n
\r\n

2.530| expecting no message body
2.530| no message body data to parse
2.530| server expects no more request data (after 165 + 0 = 165 bytes)
2.531| server will send 234 bytes
HTTP/1.1 200 OK\r\n
Cache-Control: public\r\n
Last-Modified: Fri, 31 Aug 2001 00:24:07 GMT\r\n
Expires: Sun, 31 Aug 2003 00:24:07 GMT\r\n
ETag: "xyzzy"\r\n
Date: Sat, 31 Aug 2002 00:24:10 GMT\r\n
Connection: close\r\n
Content-Length: 15\r\n
\r\n
body-gnmwymizky

2.532| server wrote 234 bytes
2.532| server closing connection to 172.16.0.6:42985
2.560| client read 251 bytes from 172.16.0.6:8001
2.561| client parsed response headers (236 bytes)
HTTP/1.1 200 OK\r\n
Cache-Control: public\r\n
Date: Sat, 31 Aug 2002 00:24:10 GMT\r\n
Via: 1.1 sunny:8001 (TheProxy/1.1.2)\r\n
Content-Length: 15\r\n
Etag: "xyzzy"\r\n
Expires: Sun, 31 Aug 2003 00:24:07 GMT\r\n
Last-Modified: Fri, 31 Aug 2001 00:24:07 GMT\r\n
\r\n

2.562| expecting 15 bytes of message body
2.562| parsed 15 bytes of message body:
body-gnmwymizky

2.562| client expects no more data (after 236 + 15 = 251 bytes)
2.563| content size: 15
2.563| client closing connection to 172.16.0.6:8001
2.563| server stops accepting HTTP queries at 172.16.0.1:8083
2.563| finished case: test_case/rfc2616/cacheSmth
2.563| result code: success
2.563| result reason: DUT proxied the transaction and possibly cached the response

2.563| step 1.2: verifying whether the cache cached the entity

Subcase: check that a given entity instance is cached and fresh

2.564| starting sub-case 'test_case/rfc2616/checkCached-fresh'
2.564| server accepting HTTP queries at 172.16.0.1:8083
2.564| client starting...
2.565| connecting to 172.16.0.6:8001; 3 attempt(s)
2.565| connected to 172.16.0.6:8001 from 172.16.0.1:15681
2.565| client will send 100 bytes
GET http://172.16.0.1:8083/vobdtsnuiloo HTTP/1.1\r\n
Host: 172.16.0.1:8083\r\n
Proxy-Connection: close\r\n
\r\n

2.566| client wrote 100 bytes to 172.16.0.6:8001
2.572| client read 259 bytes from 172.16.0.6:8001
2.573| client parsed response headers (244 bytes)
HTTP/1.1 200 OK\r\n
Cache-Control: public\r\n
Date: Sat, 31 Aug 2002 00:24:10 GMT\r\n
Via: 1.1 sunny:8001 (TheProxy/1.1.2)\r\n
Content-Length: 15\r\n
Etag: "xyzzy"\r\n
Expires: Sun, 31 Aug 2003 00:24:07 GMT\r\n
Last-Modified: Fri, 31 Aug 2001 00:24:07 GMT\r\n
Age: 0\r\n
\r\n

2.574| expecting 15 bytes of message body
2.574| parsed 15 bytes of message body:
body-gnmwymizky

2.574| client expects no more data (after 244 + 15 = 259 bytes)
2.575| content size: 15
2.575| client closing connection to 172.16.0.6:8001
2.575| server stops accepting HTTP queries at 172.16.0.1:8083
2.575| finished case: test_case/rfc2616/checkCached-fresh
2.575| result code: success
2.575| result reason: at the time of the request, entity instance appeared to be cached and fresh

2.576| step 1.3: sending an If-None-Match request where IMS matches LMT

Subcase: DUT is expected to respond to HTTP requests

2.576| starting sub-case 'test_case/http/expectResponse'
2.576| server accepting HTTP queries at 172.16.0.1:8083
2.577| client starting...
2.577| connecting to 172.16.0.6:8001; 3 attempt(s)
2.578| connected to 172.16.0.6:8001 from 172.16.0.1:15682
2.578| client will send 176 bytes
GET http://172.16.0.1:8083/vobdtsnuiloo HTTP/1.1\r\n
If-None-Match: W/"xyzzy"\r\n
If-Modified-Since: Fri, 31 Aug 2001 00:24:07 GMT\r\n
Host: 172.16.0.1:8083\r\n
Proxy-Connection: close\r\n
\r\n

2.578| client wrote 176 bytes to 172.16.0.6:8001
2.583| client read 188 bytes from 172.16.0.6:8001
2.584| client parsed response headers (188 bytes)
HTTP/1.1 304 Not Modified\r\n
Cache-Control: public\r\n
Date: Sat, 31 Aug 2002 00:24:10 GMT\r\n
Via: 1.1 sunny:8001 (TheProxy/1.1.2)\r\n
Etag: "xyzzy"\r\n
Expires: Sun, 31 Aug 2003 00:24:07 GMT\r\n
Age: 0\r\n
\r\n

2.584| expecting no message body
2.585| no message body data to parse
2.585| client expects no more data (after 188 + 0 = 188 bytes)
2.585| client closing connection to 172.16.0.6:8001
2.585| server stops accepting HTTP queries at 172.16.0.1:8083
2.585| finished case: test_case/http/expectResponse
2.585| result code: success
2.585| result reason: DUT responded to an HTTP request

2.586| finished case: test_case/rfc2616/ifNoneMatch-match-imsMatch-strong-weak-full
2.586| result code: success
2.586| result reason: cache correctly responded with 304 Not Modified or with 412 Precondition Failed when handling a transaction where If-None-Match matches cached ETag and IMS matches LMT; using strong ETag and weak If-None-Match non-empty entity tags



Test Case: proxies MUST handle 65536byte-long URIs

2.586| starting test case 'test_case/rfc2616/longUri-65536' from 'test_clause/rfc2616/longUri' clause
2.586| server accepting HTTP queries at 172.16.0.1:8083
2.587| client starting...
2.587| connecting to 172.16.0.6:8001; 3 attempt(s)
2.587| connected to 172.16.0.6:8001 from 172.16.0.1:15683
2.588| client will send 65623 bytes
GET http://172.16.0.1:8083/2097329128/[ ... 65508 bytes, MD5: 1460ea6a3a5894bff3ca7905bfd3917c ]rseqttwpqwecdzyl HTTP/1.1\r\n
Host: 172.16.0.1:8083\r\n
Proxy-Connection: close\r\n
\r\n

2.611| client wrote 17520 bytes to 172.16.0.6:8001
2.613| client wrote 2920 bytes to 172.16.0.6:8001
2.613| client wrote 2920 bytes to 172.16.0.6:8001
2.617| client wrote 4380 bytes to 172.16.0.6:8001
2.620| client wrote 5840 bytes to 172.16.0.6:8001
2.624| client wrote 7300 bytes to 172.16.0.6:8001
2.629| client wrote 8760 bytes to 172.16.0.6:8001
2.635| client wrote 10220 bytes to 172.16.0.6:8001
2.642| client wrote 5763 bytes to 172.16.0.6:8001
2.790| server at 172.16.0.1:8083 accepted TCP connection from 172.16.0.6:42986
2.790| starting to serve connection from 172.16.0.6:42986
2.798| server read 2920 bytes from 172.16.0.6:42986
2.800| server read 17520 bytes from 172.16.0.6:42986
2.812| server read 17520 bytes from 172.16.0.6:42986
2.833| server read 17520 bytes from 172.16.0.6:42986
2.863| server read 10208 bytes from 172.16.0.6:42986
2.939| server got request headers (65688 bytes)
GET /2097329128/[ ... 65508 bytes, MD5: 1460ea6a3a5894bff3ca7905bfd3917c ]rseqttwpqwecdzyl HTTP/1.1\r\n
Date: Sat, 31 Aug 2002 00:23:16 GMT\r\n
Via: 1.1 sunny:8001 (TheProxy/1.1.2)\r\n
Accept: */*\r\n
Host: 172.16.0.1:8083\r\n
User-Agent: TheProxy/1.1.2\r\n
\r\n

2.947| expecting no message body
2.950| no message body data to parse
2.950| server expects no more request data (after 65688 + 0 = 65688 bytes)
2.955| server will send 110 bytes
HTTP/1.1 200 OK\r\n
Date: Sat, 31 Aug 2002 00:24:11 GMT\r\n
Connection: close\r\n
Content-Length: 15\r\n
\r\n
body-khlkmknufi

2.956| server wrote 110 bytes
2.957| server closing connection to 172.16.0.6:42986
3.020| client read 127 bytes from 172.16.0.6:8001
3.020| client parsed response headers (112 bytes)
HTTP/1.1 200 OK\r\n
Date: Sat, 31 Aug 2002 00:24:11 GMT\r\n
Via: 1.1 sunny:8001 (TheProxy/1.1.2)\r\n
Content-Length: 15\r\n
\r\n

3.021| expecting 15 bytes of message body
3.021| parsed 15 bytes of message body:
body-khlkmknufi

3.021| client expects no more data (after 112 + 15 = 127 bytes)
3.021| content size: 15
3.022| client closing connection to 172.16.0.6:8001
3.022| server stops accepting HTTP queries at 172.16.0.1:8083
3.025| finished case: test_case/rfc2616/longUri-65536
3.025| result code: success
3.025| result reason: DUT proxied the transaction with a 65536 byte long URI path



Test Case: proxy MUST respond with 417 (Expectation Failed) if it cannot meet the "100-continueing" expectation

3.025| starting test case 'test_case/rfc2616/unsuppExpect-100-continueing' from 'test_clause/rfc2616/unsuppExpect' clause
3.026| server accepting HTTP queries at 172.16.0.1:8083
3.026| client starting...
3.026| connecting to 172.16.0.6:8001; 3 attempt(s)
3.027| connected to 172.16.0.6:8001 from 172.16.0.1:15684
3.027| client will send 125 bytes
GET http://172.16.0.1:8083/svobbiswpipj HTTP/1.1\r\n
Expect: 100-continueing\r\n
Host: 172.16.0.1:8083\r\n
Proxy-Connection: close\r\n
\r\n

3.027| client wrote 125 bytes to 172.16.0.6:8001
3.032| server at 172.16.0.1:8083 accepted TCP connection from 172.16.0.6:42987
3.032| starting to serve connection from 172.16.0.6:42987
3.034| server read 190 bytes from 172.16.0.6:42987
3.035| server got request headers (190 bytes)
GET /svobbiswpipj HTTP/1.1\r\n
Date: Sat, 31 Aug 2002 00:23:17 GMT\r\n
Via: 1.1 sunny:8001 (TheProxy/1.1.2)\r\n
Accept: */*\r\n
Expect: 100-continueing\r\n
Host: 172.16.0.1:8083\r\n
User-Agent: TheProxy/1.1.2\r\n
\r\n

3.035| expecting no message body
3.036| no message body data to parse
3.036| server expects no more request data (after 190 + 0 = 190 bytes)
3.037| server will send 110 bytes
HTTP/1.1 200 OK\r\n
Date: Sat, 31 Aug 2002 00:24:11 GMT\r\n
Connection: close\r\n
Content-Length: 15\r\n
\r\n
body-ihqhefpghb

3.037| server wrote 110 bytes
3.037| server closing connection to 172.16.0.6:42987
3.058| client read 127 bytes from 172.16.0.6:8001
3.059| client parsed response headers (112 bytes)
HTTP/1.1 200 OK\r\n
Date: Sat, 31 Aug 2002 00:24:11 GMT\r\n
Via: 1.1 sunny:8001 (TheProxy/1.1.2)\r\n
Content-Length: 15\r\n
\r\n

3.059| expecting 15 bytes of message body
3.060| parsed 15 bytes of message body:
body-ihqhefpghb

3.060| client expects no more data (after 112 + 15 = 127 bytes)
3.060| content size: 15
3.060| client closing connection to 172.16.0.6:8001
3.060| server stops accepting HTTP queries at 172.16.0.1:8083
3.061| finished case: test_case/rfc2616/unsuppExpect-100-continueing
3.061| result code: violation
3.061| result reason: DUT did not refuse to proxy a request with an unsupported expectation by responding with 417 error status code



Test Case: cache MUST revalidate when stored request headers under Vary control mismatch 2nd request headers; using "Vary: X-Case-Foo" and corresponding request header values with mismatched case

3.061| starting test case 'test_case/rfc2616/varyMismatch-1-1-1-X-Case-Foo' from 'test_clause/rfc2616/varyMismatch' clause
3.061| step 1.1: attempting to cache entity with Vary header(s)

Subcase: cache something

3.062| starting sub-case 'test_case/rfc2616/cacheSmth'
3.062| server accepting HTTP queries at 172.16.0.1:8083
3.062| client starting...
3.063| connecting to 172.16.0.6:8001; 3 attempt(s)
3.063| connected to 172.16.0.6:8001 from 172.16.0.1:15685
3.063| client will send 143 bytes
GET http://172.16.0.1:8083/quncgazmlthz HTTP/1.1\r\n
X-Case-Foo: "BaR"\r\n
X-Anebzyiej: voarajplb\r\n
Host: 172.16.0.1:8083\r\n
Proxy-Connection: close\r\n
\r\n

3.064| client wrote 143 bytes to 172.16.0.6:8001
3.068| server at 172.16.0.1:8083 accepted TCP connection from 172.16.0.6:42988
3.069| starting to serve connection from 172.16.0.6:42988
3.071| server read 208 bytes from 172.16.0.6:42988
3.072| server got request headers (208 bytes)
GET /quncgazmlthz HTTP/1.1\r\n
Date: Sat, 31 Aug 2002 00:23:17 GMT\r\n
Via: 1.1 sunny:8001 (TheProxy/1.1.2)\r\n
Accept: */*\r\n
Host: 172.16.0.1:8083\r\n
User-Agent: TheProxy/1.1.2\r\n
X-Case-Foo: "BaR"\r\n
X-Anebzyiej: voarajplb\r\n
\r\n

3.072| expecting no message body
3.072| no message body data to parse
3.073| server expects no more request data (after 208 + 0 = 208 bytes)
3.074| server will send 237 bytes
HTTP/1.1 200 OK\r\n
Cache-Control: public\r\n
Last-Modified: Fri, 31 Aug 2001 00:24:07 GMT\r\n
Expires: Sun, 31 Aug 2003 00:24:07 GMT\r\n
Vary: X-Case-Foo\r\n
Date: Sat, 31 Aug 2002 00:24:11 GMT\r\n
Connection: close\r\n
Content-Length: 15\r\n
\r\n
body-ukkogzzvkh

3.074| server wrote 237 bytes
3.075| server closing connection to 172.16.0.6:42988
3.107| client read 254 bytes from 172.16.0.6:8001
3.108| client parsed response headers (239 bytes)
HTTP/1.1 200 OK\r\n
Cache-Control: public\r\n
Date: Sat, 31 Aug 2002 00:24:11 GMT\r\n
Via: 1.1 sunny:8001 (TheProxy/1.1.2)\r\n
Content-Length: 15\r\n
Expires: Sun, 31 Aug 2003 00:24:07 GMT\r\n
Last-Modified: Fri, 31 Aug 2001 00:24:07 GMT\r\n
Vary: x-case-foo\r\n
\r\n

3.108| expecting 15 bytes of message body
3.109| parsed 15 bytes of message body:
body-ukkogzzvkh

3.109| client expects no more data (after 239 + 15 = 254 bytes)
3.109| content size: 15
3.109| client closing connection to 172.16.0.6:8001
3.109| server stops accepting HTTP queries at 172.16.0.1:8083
3.110| finished case: test_case/rfc2616/cacheSmth
3.110| result code: success
3.110| result reason: DUT proxied the transaction and possibly cached the response

3.110| step 1.2: verifying whether the cache cached an entity with Vary header(s)

Subcase: check that a given entity instance is cached and fresh

3.111| starting sub-case 'test_case/rfc2616/checkCached-fresh'
3.111| server accepting HTTP queries at 172.16.0.1:8083
3.111| client starting...
3.111| connecting to 172.16.0.6:8001; 3 attempt(s)
3.112| connected to 172.16.0.6:8001 from 172.16.0.1:15686
3.112| client will send 143 bytes
GET http://172.16.0.1:8083/quncgazmlthz HTTP/1.1\r\n
X-Case-Foo: "BaR"\r\n
X-Anebzyiej: voarajplb\r\n
Host: 172.16.0.1:8083\r\n
Proxy-Connection: close\r\n
\r\n

3.113| client wrote 143 bytes to 172.16.0.6:8001
3.120| client read 262 bytes from 172.16.0.6:8001
3.121| client parsed response headers (247 bytes)
HTTP/1.1 200 OK\r\n
Cache-Control: public\r\n
Date: Sat, 31 Aug 2002 00:24:11 GMT\r\n
Via: 1.1 sunny:8001 (TheProxy/1.1.2)\r\n
Content-Length: 15\r\n
Expires: Sun, 31 Aug 2003 00:24:07 GMT\r\n
Last-Modified: Fri, 31 Aug 2001 00:24:07 GMT\r\n
Age: 0\r\n
Vary: x-case-foo\r\n
\r\n

3.121| expecting 15 bytes of message body
3.121| parsed 15 bytes of message body:
body-ukkogzzvkh

3.122| client expects no more data (after 247 + 15 = 262 bytes)
3.122| content size: 15
3.122| client closing connection to 172.16.0.6:8001
3.122| server stops accepting HTTP queries at 172.16.0.1:8083
3.122| finished case: test_case/rfc2616/checkCached-fresh
3.122| result code: success
3.122| result reason: at the time of the request, entity instance appeared to be cached and fresh

3.123| step 1.3: sending a request with mismatched Varied headers to cause the cache to miss

Subcase: check that a given entity instance is not cached or cached but stale

3.123| starting sub-case 'test_case/rfc2616/checkCached-notORstale'
3.123| server accepting HTTP queries at 172.16.0.1:8083
3.124| client starting...
3.124| connecting to 172.16.0.6:8001; 3 attempt(s)
3.124| connected to 172.16.0.6:8001 from 172.16.0.1:15687
3.125| client will send 138 bytes
GET http://172.16.0.1:8083/quncgazmlthz HTTP/1.1\r\n
X-Case-Foo: "bAr"\r\n
X-Auhjrpfhe: vmll\r\n
Host: 172.16.0.1:8083\r\n
Proxy-Connection: close\r\n
\r\n

3.125| client wrote 138 bytes to 172.16.0.6:8001
3.132| client read 262 bytes from 172.16.0.6:8001
3.133| client parsed response headers (247 bytes)
HTTP/1.1 200 OK\r\n
Cache-Control: public\r\n
Date: Sat, 31 Aug 2002 00:24:11 GMT\r\n
Via: 1.1 sunny:8001 (TheProxy/1.1.2)\r\n
Content-Length: 15\r\n
Expires: Sun, 31 Aug 2003 00:24:07 GMT\r\n
Last-Modified: Fri, 31 Aug 2001 00:24:07 GMT\r\n
Age: 0\r\n
Vary: x-case-foo\r\n
\r\n

3.133| expecting 15 bytes of message body
3.134| parsed 15 bytes of message body:
body-ukkogzzvkh

3.134| client expects no more data (after 247 + 15 = 262 bytes)
3.134| content size: 15
3.134| client closing connection to 172.16.0.6:8001
3.135| server stops accepting HTTP queries at 172.16.0.1:8083
3.135| finished case: test_case/rfc2616/checkCached-notORstale
3.135| result code: failure
3.135| result reason: at the time of the request, entity instance appeared to be cached and fresh rather than being not cached or cached but stale

3.135| finished case: test_case/rfc2616/varyMismatch-1-1-1-X-Case-Foo
3.135| result code: violation
3.135| result reason: cache produced a hit when request headers mismatched stored headers under Vary control; using "Vary: X-Case-Foo" and corresponding request header values with mismatched case


© 2013 The Measurement Factory.