Re: 1.2b20-1: Reworked client body processing [patch] from Duane Wessels on 1998-04-27 (squid-dev)

From: Duane Wessels <wessels@dont-contact.us>
Date: Mon, 27 Apr 1998 15:06:33 -0600

Henrik Nordstrom writes:

>This is a multi-part message in MIME format.
>
>--------------CD94657B23F41D293E1882
>Content-Type: text/plain; charset=us-ascii
>Content-Transfer-Encoding: 7bit
>
>Here is a updated vesion of my client body processing.
>
>The idea behind this patch is that all client side request processing
>should be driven by clientReadRequest, and handed down to each protocol
>in a well-defined way.
>
>How it works:
>When a request with a body is received, the connection changes state to
>"body processing", and the request marked with a linked to the client
>connection.
>
>The protocols then use clientReadBody to get the request-body content,
>in a way similar to read_file or comm_write. End-of-body is indicated by
>read length 0, aborts/failure with length -1.

Here's my thoughts:

I do agree that the pump.c code is not very pretty, BUT I think it does
work with HTTP POST/PUT at least. I'm not sure how it interacts with
FTP PUT. FTP PUT is a high priority, so we need to get it working for
squid-1.2. We seem to have finally eliminated all coredumps from
aborted POST requests in the beta19/beta20 versions.

I'm not strictly opposed to replacing pump.c with something cleaner,
but we have unfortunately invested a lot of time in pump.c code and we
want to make a real release soon. I'm worried that it will take a long
time to work out all the little gotchas from this new body processsing
code.

I applied this patch and ran my simple POST test script, and
it didn't seem to work very well:

1998/04/27 14:27:01| clientReadBody: start fd=23 body_size=512 in.offset=95 cb=456294 req=10479000
1998/04/27 14:27:01| clientReadBodyHandler: start fd=23 body_size=512 in.offset=95 cb=456294 req=10479000
1998/04/27 14:27:01| clientReadBodyHandler: end fd=23 size=95 body_size=417 in.offset=0 cb=456294 req=10479000
1998/04/27 14:27:01| parseHttpRequest: Unsupported method '>of}ytlf'
1998/04/27 14:27:01| clientReadRequest: FD 23 Invalid Request
1998/04/27 14:27:01| clientSendMoreData: Deferring error:unsupported-request-method
1998/04/27 14:27:01| clientReadBody: start fd=23 body_size=417 in.offset=0 cb=456294 req=10479000
1998/04/27 14:27:01| clientReadBody: fd 23 wait for clientReadRequest

I'll attach a full debug log below.

My suggestion is for you to keep working on this patch, but I
don't think its ready to be commited yet.

Duane W.

1998/04/27 14:26:50| Starting Squid Cache version 1.2.beta20 for mips-sgi-irix5.3...
1998/04/27 14:26:50| Process ID 24844
1998/04/27 14:26:50| With 2500 file descriptors available
1998/04/27 14:26:50| Performing DNS Tests...
1998/04/27 14:26:50| Successful DNS name lookup tests...
1998/04/27 14:26:50| Started 16 'dnsserver' processes
1998/04/27 14:26:50| Unlinkd pipe opened on FD 24
1998/04/27 14:26:50| Swap maxSize 46080 KB, estimated 3544 objects
1998/04/27 14:26:50| Target number of buckets: 70
1998/04/27 14:26:50| Using 8192 Store buckets, replacement runs every 10 seconds
1998/04/27 14:26:50| Max Mem size: 512 KB
1998/04/27 14:26:50| Max Swap size: 46080 KB
1998/04/27 14:26:50| Local cache digest enabled; rebuild/rewrite every 3600/3600 sec
1998/04/27 14:26:50| Rebuilding storage in Cache Dir #0 (CLEAN)
1998/04/27 14:26:50| Rebuilding storage in Cache Dir #1 (CLEAN)
1998/04/27 14:26:50| Rebuilding storage in Cache Dir #2 (CLEAN)
1998/04/27 14:26:50| Loaded Icons.
1998/04/27 14:26:50| Accepting HTTP connections on port 3128, FD 46.
1998/04/27 14:26:50| Accepting ICP messages on port 4130, FD 47.
1998/04/27 14:26:52| pinger: ICMP socket opened
1998/04/27 14:26:50| Pinger socket opened on FD 49
1998/04/27 14:26:52| NETDB state reloaded; 79 entries, 1419 msec
1998/04/27 14:26:52| Ready to serve requests.
1998/04/27 14:26:53| Done reading Cache Dir #1 swaplog (49 entries)
1998/04/27 14:26:53| Done reading Cache Dir #2 swaplog (616 entries)
1998/04/27 14:26:53| Done reading Cache Dir #0 swaplog (2125 entries)
1998/04/27 14:26:53| Finished rebuilding storage disk.
1998/04/27 14:26:53| 2790 Entries read from previous logfile.
1998/04/27 14:26:53| 0 Entries scanned from swap files.
1998/04/27 14:26:53| 0 Invalid entries.
1998/04/27 14:26:53| 0 With invalid flags.
1998/04/27 14:26:53| 2790 Objects loaded.
1998/04/27 14:26:53| 0 Objects expired.
1998/04/27 14:26:53| 0 Objects cancelled.
1998/04/27 14:26:53| 0 Duplicate URLs purged.
1998/04/27 14:26:53| 0 Swapfile clashes avoided.
1998/04/27 14:26:53| Took 3 seconds ( 930.0 objects/sec).
1998/04/27 14:26:53| Beginning Validation Procedure
1998/04/27 14:26:54| Completed Validation Procedure
1998/04/27 14:26:54| Validated 2790 Entries
1998/04/27 14:26:54| store_swap_size = 41841k
1998/04/27 14:26:55| comm_select: 0 sockets ready at 893708815
1998/04/27 14:26:56| comm_select: 0 sockets ready at 893708816
1998/04/27 14:26:56| comm_select: time out: 893708816
1998/04/27 14:26:56| eventRun: Running 'storeMaintainSwapSpace'
1998/04/27 14:26:56| eventAdd: Adding 'storeMaintainSwapSpace', in 1 seconds
1998/04/27 14:26:56| storeMaintainSwapSpace
1998/04/27 14:26:56| storeMaintainSwapSpace stats:
1998/04/27 14:26:56| 2807 objects
1998/04/27 14:26:56| 101 were scanned
1998/04/27 14:26:56| 0 were locked
1998/04/27 14:26:56| 0 were expired
1998/04/27 14:26:57| comm_select: 0 sockets ready at 893708817
1998/04/27 14:26:57| comm_select: time out: 893708817
1998/04/27 14:26:57| eventRun: Running 'storeMaintainSwapSpace'
1998/04/27 14:26:57| eventAdd: Adding 'storeMaintainSwapSpace', in 1 seconds
1998/04/27 14:26:57| storeMaintainSwapSpace
1998/04/27 14:26:57| storeMaintainSwapSpace stats:
1998/04/27 14:26:57| 2807 objects
1998/04/27 14:26:57| 101 were scanned
1998/04/27 14:26:57| 0 were locked
1998/04/27 14:26:57| 0 were expired
1998/04/27 14:26:58| comm_select: 1 sockets ready at 893708818
1998/04/27 14:26:58| comm_select: FD 49 ready for reading
1998/04/27 14:26:58| commSetSelect: FD 49 type 1
1998/04/27 14:26:58| eventRun: Running 'storeMaintainSwapSpace'
1998/04/27 14:26:58| eventAdd: Adding 'storeMaintainSwapSpace', in 1 seconds
1998/04/27 14:26:58| storeMaintainSwapSpace
1998/04/27 14:26:58| storeMaintainSwapSpace stats:
1998/04/27 14:26:58| 2807 objects
1998/04/27 14:26:58| 101 were scanned
1998/04/27 14:26:58| 0 were locked
1998/04/27 14:26:58| 0 were expired
1998/04/27 14:26:58| comm_select: 0 sockets ready at 893708818
1998/04/27 14:26:59| comm_select: 0 sockets ready at 893708819
1998/04/27 14:26:59| comm_select: time out: 893708819
1998/04/27 14:26:59| eventRun: Running 'storeMaintainSwapSpace'
1998/04/27 14:26:59| eventAdd: Adding 'storeMaintainSwapSpace', in 1 seconds
1998/04/27 14:26:59| storeMaintainSwapSpace
1998/04/27 14:26:59| storeMaintainSwapSpace stats:
1998/04/27 14:26:59| 2807 objects
1998/04/27 14:26:59| 101 were scanned
1998/04/27 14:26:59| 0 were locked
1998/04/27 14:26:59| 0 were expired
1998/04/27 14:27:00| comm_select: 1 sockets ready at 893708820
1998/04/27 14:27:00| eventRun: Running 'storeMaintainSwapSpace'
1998/04/27 14:27:00| eventAdd: Adding 'storeMaintainSwapSpace', in 1 seconds
1998/04/27 14:27:00| storeMaintainSwapSpace
1998/04/27 14:27:00| storeMaintainSwapSpace stats:
1998/04/27 14:27:00| 2807 objects
1998/04/27 14:27:00| 101 were scanned
1998/04/27 14:27:00| 0 were locked
1998/04/27 14:27:00| 0 were expired
1998/04/27 14:27:01| commSetSelect: FD 46 type 1
1998/04/27 14:27:01| fd_open FD 23 HTTP Request
1998/04/27 14:27:01| httpAccept: FD 23: accepted
1998/04/27 14:27:01| cbdataAdd: 10464200
1998/04/27 14:27:01| comm_add_close_handler: FD 23, handler=42b7cc, data=10464200
1998/04/27 14:27:01| cbdataLock: 10464200
1998/04/27 14:27:01| commSetTimeout: FD 23 timeout 30
1998/04/27 14:27:01| commSetSelect: FD 23 type 1
1998/04/27 14:27:01| comm_select: 1 sockets ready at 893708821
1998/04/27 14:27:01| comm_select: FD 23 ready for reading
1998/04/27 14:27:01| clientReadRequest: FD 23: reading request...
1998/04/27 14:27:01| commSetSelect: FD 23 type 1
1998/04/27 14:27:01| parseHttpRequest: Method is 'POST'
1998/04/27 14:27:01| parseHttpRequest: Request is 'http://squid.nlanr.net/cgi-bin/md5-post.cgi'
1998/04/27 14:27:01| parseHttpRequest: req_hdr = {Content-length: 512
Accept: */*

}
1998/04/27 14:27:01| parseHttpRequest: end = {}
1998/04/27 14:27:01| parseHttpRequest: req_sz = 95
1998/04/27 14:27:01| cbdataAdd: 10169a00
1998/04/27 14:27:01| parseHttpRequest: Request Header is
Content-length: 512
Accept: */*

1998/04/27 14:27:01| parseHttpRequest: Complete request received
1998/04/27 14:27:01| commSetTimeout: FD 23 timeout 7200
1998/04/27 14:27:01| mime_get_header: looking for 'Content-Length'
1998/04/27 14:27:01| mime_get_header: checking 'Content-length: 512'
1998/04/27 14:27:01| mime_get_header: returning '512'
1998/04/27 14:27:01| mime_get_header: looking for 'Content-Length'
1998/04/27 14:27:01| mime_get_header: checking 'Content-length: 512'
1998/04/27 14:27:01| mime_get_header: returning '512'
1998/04/27 14:27:01| mime_get_header: looking for 'User-Agent'
1998/04/27 14:27:01| cbdataAdd: 10169800
1998/04/27 14:27:01| cbdataLock: 1013ce70
1998/04/27 14:27:01| cbdataLock: 10169a00
1998/04/27 14:27:01| cbdataValid: 1013ce70
1998/04/27 14:27:01| aclCheck: checking 'http_access allow manager localhost'
1998/04/27 14:27:01| aclMatchAclList: checking manager
1998/04/27 14:27:01| aclMatchAcl: checking 'acl manager proto cache_object'
1998/04/27 14:27:01| aclMatchAclList: returning 0
1998/04/27 14:27:01| cbdataUnlock: 1013ce70
1998/04/27 14:27:01| cbdataLock: 1013ce40
1998/04/27 14:27:01| cbdataValid: 1013ce40
1998/04/27 14:27:01| aclCheck: checking 'http_access deny manager'
1998/04/27 14:27:01| aclMatchAclList: checking manager
1998/04/27 14:27:01| aclMatchAcl: checking 'acl manager proto cache_object'
1998/04/27 14:27:01| aclMatchAclList: returning 0
1998/04/27 14:27:01| cbdataUnlock: 1013ce40
1998/04/27 14:27:01| cbdataLock: 1013cdf0
1998/04/27 14:27:01| cbdataValid: 1013cdf0
1998/04/27 14:27:01| aclCheck: checking 'http_access deny adverts'
1998/04/27 14:27:01| aclMatchAclList: checking adverts
1998/04/27 14:27:01| aclMatchAcl: checking 'acl adverts url_regex ad.doubleclick.net banner-net.com ad.linkexchange.com /ads/ click_through clickthrough'
1998/04/27 14:27:01| aclMatchRegex: checking 'http://squid.nlanr.net/cgi-bin/md5-post.cgi'
1998/04/27 14:27:01| aclMatchRegex: looking for 'ad.doubleclick.net'
1998/04/27 14:27:01| aclMatchRegex: looking for 'banner-net.com'
1998/04/27 14:27:01| aclMatchRegex: looking for 'ad.linkexchange.com'
1998/04/27 14:27:01| aclMatchRegex: looking for '/ads/'
1998/04/27 14:27:01| aclMatchRegex: looking for 'click_through'
1998/04/27 14:27:01| aclMatchRegex: looking for 'clickthrough'
1998/04/27 14:27:01| aclMatchAclList: returning 0
1998/04/27 14:27:01| cbdataUnlock: 1013cdf0
1998/04/27 14:27:01| cbdataLock: 1013cdd0
1998/04/27 14:27:01| cbdataValid: 1013cdd0
1998/04/27 14:27:01| aclCheck: checking 'http_access deny counters'
1998/04/27 14:27:01| aclMatchAclList: checking counters
1998/04/27 14:27:01| aclMatchAcl: checking 'acl counters url_regex /count/ /Count/ count\. Count\. counter\.digits\.com'
1998/04/27 14:27:01| aclMatchRegex: checking 'http://squid.nlanr.net/cgi-bin/md5-post.cgi'
1998/04/27 14:27:01| aclMatchRegex: looking for '/count/'
1998/04/27 14:27:01| aclMatchRegex: looking for '/Count/'
1998/04/27 14:27:01| aclMatchRegex: looking for 'count\.'
1998/04/27 14:27:01| aclMatchRegex: looking for 'Count\.'
1998/04/27 14:27:01| aclMatchRegex: looking for 'counter\.digits\.com'
1998/04/27 14:27:01| aclMatchAclList: returning 0
1998/04/27 14:27:01| cbdataUnlock: 1013cdd0
1998/04/27 14:27:01| cbdataLock: 1013cdb0
1998/04/27 14:27:01| cbdataValid: 1013cdb0
1998/04/27 14:27:01| aclCheck: checking 'http_access allow localhost'
1998/04/27 14:27:01| aclMatchAclList: checking localhost
1998/04/27 14:27:01| aclMatchAcl: checking 'acl localhost src 127.0.0.1/255.255.255.255'
1998/04/27 14:27:01| aclMatchIp: '127.0.0.1' found
1998/04/27 14:27:01| aclMatchAclList: returning 1
1998/04/27 14:27:01| cbdataUnlock: 1013cdb0
1998/04/27 14:27:01| aclCheck: match found, returning 1
1998/04/27 14:27:01| aclCheckCallback: answer=1
1998/04/27 14:27:01| cbdataValid: 10169a00
1998/04/27 14:27:01| clientAccessCheckDone: 'http://squid.nlanr.net/cgi-bin/md5-post.cgi' answer=1
1998/04/27 14:27:01| redirectStart: 'http://squid.nlanr.net/cgi-bin/md5-post.cgi'
1998/04/27 14:27:01| clientRedirectDone: 'http://squid.nlanr.net/cgi-bin/md5-post.cgi' result=NULL
1998/04/27 14:27:01| mime_get_header: looking for 'If-Modified-Since'
1998/04/27 14:27:01| mime_get_header: looking for 'Pragma'
1998/04/27 14:27:01| mime_get_header: looking for 'Range'
1998/04/27 14:27:01| mime_get_header: looking for 'Request-Range'
1998/04/27 14:27:01| mime_get_header: looking for 'Authorization'
1998/04/27 14:27:01| mime_get_header: looking for 'Proxy-Connection'
1998/04/27 14:27:01| mime_get_header: looking for 'Via'
1998/04/27 14:27:01| mime_get_header: looking for 'X-Forwarded-For'
1998/04/27 14:27:01| mime_get_header: looking for 'Cache-control'
1998/04/27 14:27:01| mime_get_header: looking for 'Cache-control'
1998/04/27 14:27:01| aclCheckFast: list: 1013cee0
1998/04/27 14:27:01| aclMatchAclList: checking QUERY
1998/04/27 14:27:01| aclMatchAcl: checking 'acl QUERY urlpath_regex cgi-bin \?'
1998/04/27 14:27:01| aclMatchRegex: checking '/cgi-bin/md5-post.cgi'
1998/04/27 14:27:01| aclMatchRegex: looking for 'cgi-bin'
1998/04/27 14:27:01| aclMatchAclList: returning 1
1998/04/27 14:27:01| clientParseRequestHeaders: REQ_NOCACHE = NOT SET
1998/04/27 14:27:01| clientParseRequestHeaders: REQ_CACHABLE = NOT SET
1998/04/27 14:27:01| clientParseRequestHeaders: REQ_HIERARCHICAL = NOT SET
1998/04/27 14:27:01| clientProcessRequest: POST 'http://squid.nlanr.net/cgi-bin/md5-post.cgi'
1998/04/27 14:27:01| storeGet: looking up B4E19BFE5D223D976225AF54E15DCA9F
1998/04/27 14:27:01| clientProcessRequest: TCP_MISS for 'http://squid.nlanr.net/cgi-bin/md5-post.cgi'
1998/04/27 14:27:01| clientProcessMiss: 'POST http://squid.nlanr.net/cgi-bin/md5-post.cgi'
1998/04/27 14:27:01| clientProcessMiss: request_hdr:
Content-length: 512
Accept: */*

1998/04/27 14:27:01| aclCheckFast: list: 0
1998/04/27 14:27:01| aclCheckFast: no matches, returning: 1
1998/04/27 14:27:01| storeCreateEntry: 'http://squid.nlanr.net/cgi-bin/md5-post.cgi' icp flags=0
1998/04/27 14:27:01| creating rep: 1013a580
1998/04/27 14:27:01| init-ing hdr: 1013a5c0
1998/04/27 14:27:01| finding entry 9 in hdr 1013a5c0
1998/04/27 14:27:01| finding entry 13 in hdr 1013a5c0
1998/04/27 14:27:01| finding entry 18 in hdr 1013a5c0
1998/04/27 14:27:01| finding entry 15 in hdr 1013a5c0
1998/04/27 14:27:01| finding entry 12 in hdr 1013a5c0
1998/04/27 14:27:01| finding entry 11 in hdr 1013a5c0
1998/04/27 14:27:01| finding entry 33 in hdr 1013a5c0
1998/04/27 14:27:01| finding entry 7 in hdr 1013a5c0
1998/04/27 14:27:01| 1013a5c0 lookup for 15
1998/04/27 14:27:01| new_MemObject: returning 1013a080
1998/04/27 14:27:01| new_StoreEntry: returning 104b86c0
1998/04/27 14:27:01| storeKeyPrivate: POST http://squid.nlanr.net/cgi-bin/md5-post.cgi
1998/04/27 14:27:01| storeHashInsert: Inserting Entry 104b86c0 key 'A4FF5C1C811462AAC662E4B8553F657A'
1998/04/27 14:27:01| storeReleaseRequest: 'A4FF5C1C811462AAC662E4B8553F657A'
1998/04/27 14:27:01| cbdataAdd: 104b8d40
1998/04/27 14:27:01| storeClientCopy: A4FF5C1C811462AAC662E4B8553F657A, seen 0, want 0, size 4096, cb 42d254, cbdata 10169a00
1998/04/27 14:27:01| storeClientCopy2: A4FF5C1C811462AAC662E4B8553F657A
1998/04/27 14:27:01| storeClientCopy2: Waiting for more
1998/04/27 14:27:01| protoDispatch: 'http://squid.nlanr.net/cgi-bin/md5-post.cgi'
1998/04/27 14:27:01| cbdataAdd: 104bbda0
1998/04/27 14:27:01| storeLockObject: key 'A4FF5C1C811462AAC662E4B8553F657A' count=2
1998/04/27 14:27:01| peerSelect: http://squid.nlanr.net/cgi-bin/md5-post.cgi
1998/04/27 14:27:01| cbdataAdd: 1013a300
1998/04/27 14:27:01| cbdataLock: 104bbda0
1998/04/27 14:27:01| peerSelectFoo: 'POST squid.nlanr.net'
1998/04/27 14:27:01| cbdataAdd: 10169600
1998/04/27 14:27:01| cbdataLock: 1013cfa0
1998/04/27 14:27:01| cbdataLock: 1013a300
1998/04/27 14:27:01| cbdataValid: 1013cfa0
1998/04/27 14:27:01| aclCheck: checking 'always_direct allow ftp'
1998/04/27 14:27:01| aclMatchAclList: checking ftp
1998/04/27 14:27:01| aclMatchAcl: checking 'acl ftp proto FTP'
1998/04/27 14:27:01| aclMatchAclList: returning 0
1998/04/27 14:27:01| cbdataUnlock: 1013cfa0
1998/04/27 14:27:01| cbdataLock: 1013cf00
1998/04/27 14:27:01| cbdataValid: 1013cf00
1998/04/27 14:27:01| aclCheck: checking 'always_direct allow SURF'
1998/04/27 14:27:01| aclMatchAclList: checking SURF
1998/04/27 14:27:01| aclMatchAcl: checking 'acl SURF dstdomain surf.scd.ucar.edu'
1998/04/27 14:27:01| aclMatchDomainList: checking 'squid.nlanr.net'
1998/04/27 14:27:01| aclMatchDomainList: 'squid.nlanr.net' NOT found
1998/04/27 14:27:01| aclMatchAclList: returning 0
1998/04/27 14:27:01| cbdataUnlock: 1013cf00
1998/04/27 14:27:01| aclCheck: NO match found, returning 0
1998/04/27 14:27:01| aclCheckCallback: answer=0
1998/04/27 14:27:01| cbdataValid: 1013a300
1998/04/27 14:27:01| peerCheckAlwaysDirectDone: 0
1998/04/27 14:27:01| peerSelectFoo: 'POST squid.nlanr.net'
1998/04/27 14:27:01| peerSelectFoo: direct = DIRECT_MAYBE
1998/04/27 14:27:01| getSingleParent: returning NULL
1998/04/27 14:27:01| peerSelectIcpPing: http://squid.nlanr.net/cgi-bin/md5-post.cgi
1998/04/27 14:27:01| peerSelectFoo: After peerSelectIcpPing.
1998/04/27 14:27:01| peerSelect: DIRECT/squid.nlanr.net
1998/04/27 14:27:01| peerSelectCallback: http://squid.nlanr.net/cgi-bin/md5-post.cgi
1998/04/27 14:27:01| cbdataValid: 104bbda0
1998/04/27 14:27:01| storeUnlockObject: key 'A4FF5C1C811462AAC662E4B8553F657A' count=1
1998/04/27 14:27:01| protoStart: FD 23: Fetching 'POST http://squid.nlanr.net/cgi-bin/md5-post.cgi' from source
1998/04/27 14:27:01| cbdataAdd: 104bbdb0
1998/04/27 14:27:01| cbdataLock: 104bbdb0
1998/04/27 14:27:01| ipcache_nbgethostbyname: Name 'squid.nlanr.net'.
1998/04/27 14:27:01| ipcache_nbgethostbyname: MISS for 'squid.nlanr.net'
1998/04/27 14:27:01| ipcacheAddNew: Adding 'squid.nlanr.net', status=P
1998/04/27 14:27:01| cbdataLock: 104bbdb0
1998/04/27 14:27:01| cbdataLock: 10144000
1998/04/27 14:27:01| comm_write: FD 5: sz 16: hndl 0: data 0.
1998/04/27 14:27:01| commSetSelect: FD 5 type 2
1998/04/27 14:27:01| commSetSelect: FD 5 type 1
1998/04/27 14:27:01| ipcache_dnsDispatch: Request sent to DNS server #1.
1998/04/27 14:27:01| httpStart: "POST http://squid.nlanr.net/cgi-bin/md5-post.cgi"
1998/04/27 14:27:01| comm_open: FD 27 is a new socket
1998/04/27 14:27:01| fd_open FD 27 http://squid.nlanr.net/cgi-bin/md5-post.cgi
1998/04/27 14:27:01| storeLockObject: key 'A4FF5C1C811462AAC662E4B8553F657A' count=2
1998/04/27 14:27:01| cbdataAdd: 104b8b40
1998/04/27 14:27:01| comm_add_close_handler: FD 27, handler=452244, data=104b8b40
1998/04/27 14:27:01| cbdataLock: 104b8b40
1998/04/27 14:27:01| commSetTimeout: FD 27 timeout 120
1998/04/27 14:27:01| cbdataAdd: 104b8a40
1998/04/27 14:27:01| cbdataLock: 104b8b40
1998/04/27 14:27:01| comm_add_close_handler: FD 27, handler=434afc, data=104b8a40
1998/04/27 14:27:01| cbdataLock: 104b8a40
1998/04/27 14:27:01| ipcache_nbgethostbyname: Name 'squid.nlanr.net'.
1998/04/27 14:27:01| ipcache_nbgethostbyname: PENDING for 'squid.nlanr.net'
1998/04/27 14:27:01| cbdataLock: 104b8a40
1998/04/27 14:27:01| cbdataFree: 104bbda0
1998/04/27 14:27:01| cbdataFree: 104bbda0 has 1 locks, not freeing
1998/04/27 14:27:01| cbdataUnlock: 104bbda0
1998/04/27 14:27:01| cbdataReallyFree: Freeing 104bbda0
1998/04/27 14:27:01| cbdataFree: 1013a300
1998/04/27 14:27:01| cbdataFree: 1013a300 has 1 locks, not freeing
1998/04/27 14:27:01| cbdataUnlock: 1013a300
1998/04/27 14:27:01| cbdataReallyFree: Freeing 1013a300
1998/04/27 14:27:01| cbdataFree: 10169600
1998/04/27 14:27:01| cbdataReallyFree: Freeing 10169600
1998/04/27 14:27:01| cbdataUnlock: 10169a00
1998/04/27 14:27:01| cbdataFree: 10169800
1998/04/27 14:27:01| cbdataReallyFree: Freeing 10169800
1998/04/27 14:27:01| Incomplete request line, waiting for more data
1998/04/27 14:27:01| eventRun: Running 'storeMaintainSwapSpace'
1998/04/27 14:27:01| eventAdd: Adding 'storeMaintainSwapSpace', in 1 seconds
1998/04/27 14:27:01| storeMaintainSwapSpace
1998/04/27 14:27:01| storeMaintainSwapSpace stats:
1998/04/27 14:27:01| 2808 objects
1998/04/27 14:27:01| 101 were scanned
1998/04/27 14:27:01| 0 were locked
1998/04/27 14:27:01| 0 were expired
1998/04/27 14:27:01| comm_select: 1 sockets ready at 893708821
1998/04/27 14:27:01| comm_select: FD 5 ready for writing
1998/04/27 14:27:01| commHandleWrite: FD 5: off 0, sz 16.
1998/04/27 14:27:01| comm_select: 1 sockets ready at 893708821
1998/04/27 14:27:01| comm_select: FD 5 ready for reading
1998/04/27 14:27:01| ipcache_dnsHandleRead: Result from DNS ID 1 (25 bytes)
1998/04/27 14:27:01| ipcacheParse: parsing:
$addr 43087 198.17.46.59
1998/04/27 14:27:01| cbdataValid: 104bbdb0
1998/04/27 14:27:01| cbdataUnlock: 104bbdb0
1998/04/27 14:27:01| netdbSendPing: pinging squid.nlanr.net
1998/04/27 14:27:01| icmpDomainPing: 'squid.nlanr.net'
1998/04/27 14:27:01| cbdataFree: 104bbdb0
1998/04/27 14:27:01| cbdataFree: 104bbdb0 has 1 locks, not freeing
1998/04/27 14:27:01| cbdataUnlock: 104bbdb0
1998/04/27 14:27:01| cbdataReallyFree: Freeing 104bbdb0
1998/04/27 14:27:01| cbdataValid: 104b8a40
1998/04/27 14:27:01| ipcacheCycleAddr: squid.nlanr.net now at 198.17.46.59
1998/04/27 14:27:01| connect FD 27: (150) Operation now in progress
1998/04/27 14:27:01| comm_connect_addr: FD 27 connection pending
1998/04/27 14:27:01| commConnectHandle: FD 27: COMM_INPROGRESS
1998/04/27 14:27:01| commSetSelect: FD 27 type 2
1998/04/27 14:27:01| cbdataUnlock: 104b8a40
1998/04/27 14:27:01| ipcache_call_pending: Called 2 handlers.
1998/04/27 14:27:01| cbdataUnlock: 10144000
1998/04/27 14:27:01| comm_select: 1 sockets ready at 893708821
1998/04/27 14:27:01| comm_select: FD 27 ready for writing
1998/04/27 14:27:01| connect FD 27: (133) Transport endpoint is already connected
1998/04/27 14:27:01| comm_connect_addr: FD 27 connected to 198.17.46.59:80
1998/04/27 14:27:01| comm_remove_close_handler: FD 27, handler=434afc, data=104b8a40
1998/04/27 14:27:01| cbdataUnlock: 104b8a40
1998/04/27 14:27:01| commSetTimeout: FD 27 timeout -1
1998/04/27 14:27:01| commConnectFree: FD 27
1998/04/27 14:27:01| cbdataFree: 104b8a40
1998/04/27 14:27:01| cbdataReallyFree: Freeing 104b8a40
1998/04/27 14:27:01| cbdataValid: 104b8b40
1998/04/27 14:27:01| commSetSelect: FD 27 type 2
1998/04/27 14:27:01| commSetTimeout: FD 27 timeout 3600
1998/04/27 14:27:01| cbdataUnlock: 104b8b40
1998/04/27 14:27:01| comm_select: 1 sockets ready at 893708821
1998/04/27 14:27:01| comm_select: FD 27 ready for writing
1998/04/27 14:27:01| httpSendRequest: FD 27: httpState 104b8b40.
1998/04/27 14:27:01| httpBuildRequestHeader: INPUT:
Content-length: 512
Accept: */*

1998/04/27 14:27:01| httpAppendRequestHeader: POST /cgi-bin/md5-post.cgi HTTP/1.0
1998/04/27 14:27:01| httpBuildRequestHeader: Content-length: 512
1998/04/27 14:27:01| httpAppendRequestHeader: Content-length: 512
1998/04/27 14:27:01| httpBuildRequestHeader: Accept: */*
1998/04/27 14:27:01| httpAppendRequestHeader: Accept: */*
1998/04/27 14:27:01| httpAppendRequestHeader: User-Agent: Nutscrape/1.0 (CP/M; 8-bit)
1998/04/27 14:27:01| httpAppendRequestHeader: Host: squid.nlanr.net
1998/04/27 14:27:01| getMaxAge: 'http://squid.nlanr.net/cgi-bin/md5-post.cgi'
1998/04/27 14:27:01| httpAppendRequestHeader: Cache-control: Max-age=604800
1998/04/27 14:27:01| httpAppendRequestHeader: Connection: Keep-Alive
1998/04/27 14:27:01| httpAppendRequestHeader:
1998/04/27 14:27:01| httpBuildRequestHeader: OUTPUT:
POST /cgi-bin/md5-post.cgi HTTP/1.0
Content-length: 512
Accept: */*
User-Agent: Nutscrape/1.0 (CP/M; 8-bit)
Host: squid.nlanr.net
Cache-control: Max-age=604800
Connection: Keep-Alive

1998/04/27 14:27:01| httpSendRequest: FD 27:
POST /cgi-bin/md5-post.cgi HTTP/1.0
Content-length: 512
Accept: */*
User-Agent: Nutscrape/1.0 (CP/M; 8-bit)
Host: squid.nlanr.net
Cache-control: Max-age=604800
Connection: Keep-Alive

1998/04/27 14:27:01| comm_write: FD 27: sz 192: hndl 456388: data 104b8b40.
1998/04/27 14:27:01| cbdataLock: 104b8b40
1998/04/27 14:27:01| commSetSelect: FD 27 type 2
1998/04/27 14:27:01| comm_select: 1 sockets ready at 893708821
1998/04/27 14:27:01| comm_select: FD 27 ready for writing
1998/04/27 14:27:01| commHandleWrite: FD 27: off 0, sz 192.
1998/04/27 14:27:01| cbdataValid: 104b8b40
1998/04/27 14:27:01| httpSendRequestEntry: FD 27: size 192: errflag 0.
1998/04/27 14:27:01| clientReadBody: start fd=23 body_size=512 in.offset=95 cb=456294 req=10479000
1998/04/27 14:27:01| clientReadBodyHandler: start fd=23 body_size=512 in.offset=95 cb=456294 req=10479000
1998/04/27 14:27:01| comm_write: FD 27: sz 95: hndl 456388: data 104b8b40.
1998/04/27 14:27:01| cbdataLock: 104b8b40
1998/04/27 14:27:01| commSetSelect: FD 27 type 2
1998/04/27 14:27:01| clientReadBodyHandler: end fd=23 size=95 body_size=417 in.offset=0 cb=456294 req=10479000
1998/04/27 14:27:01| cbdataUnlock: 104b8b40
1998/04/27 14:27:01| comm_select: 2 sockets ready at 893708821
1998/04/27 14:27:01| comm_select: FD 23 ready for reading
1998/04/27 14:27:01| clientReadRequest: FD 23: reading request...
1998/04/27 14:27:01| commSetSelect: FD 23 type 1
1998/04/27 14:27:01| parseHttpRequest: Unsupported method '¾ïæ
ýùôìæ'
1998/04/27 14:27:01| cbdataAdd: 10169400
1998/04/27 14:27:01| commSetTimeout: FD 23 timeout 7200
1998/04/27 14:27:01| clientReadRequest: FD 23 Invalid Request
1998/04/27 14:27:01| storeCreateEntry: 'error:unsupported-request-method' icp flags=0
1998/04/27 14:27:01| creating rep: 10471780
1998/04/27 14:27:01| init-ing hdr: 104717c0
1998/04/27 14:27:01| finding entry 9 in hdr 104717c0
1998/04/27 14:27:01| finding entry 13 in hdr 104717c0
1998/04/27 14:27:01| finding entry 18 in hdr 104717c0
1998/04/27 14:27:01| finding entry 15 in hdr 104717c0
1998/04/27 14:27:01| finding entry 12 in hdr 104717c0
1998/04/27 14:27:01| finding entry 11 in hdr 104717c0
1998/04/27 14:27:01| finding entry 33 in hdr 104717c0
1998/04/27 14:27:01| finding entry 7 in hdr 104717c0
1998/04/27 14:27:01| 104717c0 lookup for 15
1998/04/27 14:27:01| new_MemObject: returning 10471200
1998/04/27 14:27:01| new_StoreEntry: returning 104b8840
1998/04/27 14:27:01| storeKeyPrivate: NONE error:unsupported-request-method
1998/04/27 14:27:01| storeHashInsert: Inserting Entry 104b8840 key 'AE4C0FC0F689A3454A15AD3D29A510C3'
1998/04/27 14:27:01| storeReleaseRequest: 'AE4C0FC0F689A3454A15AD3D29A510C3'
1998/04/27 14:27:01| cbdataAdd: 104b8fc0
1998/04/27 14:27:01| storeClientCopy: AE4C0FC0F689A3454A15AD3D29A510C3, seen 0, want 0, size 4096, cb 42d254, cbdata 10169400
1998/04/27 14:27:01| storeClientCopy2: AE4C0FC0F689A3454A15AD3D29A510C3
1998/04/27 14:27:01| storeClientCopy2: Waiting for more
1998/04/27 14:27:01| creating rep: 10471a80
1998/04/27 14:27:01| init-ing hdr: 10471ac0
1998/04/27 14:27:01| finding entry 9 in hdr 10471ac0
1998/04/27 14:27:01| finding entry 13 in hdr 10471ac0
1998/04/27 14:27:01| finding entry 18 in hdr 10471ac0
1998/04/27 14:27:01| finding entry 15 in hdr 10471ac0
1998/04/27 14:27:01| finding entry 12 in hdr 10471ac0
1998/04/27 14:27:01| finding entry 11 in hdr 10471ac0
1998/04/27 14:27:01| finding entry 33 in hdr 10471ac0
1998/04/27 14:27:01| finding entry 7 in hdr 10471ac0
1998/04/27 14:27:01| 10471ac0 lookup for 15
1998/04/27 14:27:01| errorConvert: %R --> '¾ïæ
ýùôìæ'
1998/04/27 14:27:01| errorConvert: %T --> 'Mon, 27 Apr 1998 20:27:01 GMT'
1998/04/27 14:27:01| errorConvert: %h --> 'surf.scd.ucar.edu'
1998/04/27 14:27:01| errorConvert: %s --> 'Squid/1.2.beta20'
1998/04/27 14:27:01| errorConvert: %S --> '
<br clear="all">
<hr noshade size=1>
Generated Mon, 27 Apr 1998 20:27:01 GMT by surf.scd.ucar.edu (Squid/1.2.beta20)
</BODY></HTML>
'
1998/04/27 14:27:01| created entry 1045a9a0: 'Server: Squid/1.2.beta20'
1998/04/27 14:27:01| 10471ac0 adding entry: 26 at 0
1998/04/27 14:27:01| created entry 1045af00: 'Mime-Version: 1.0'
1998/04/27 14:27:01| 10471ac0 adding entry: 21 at 1
1998/04/27 14:27:01| created entry 1045abe0: 'Date: Mon, 27 Apr 1998 20:27:01 GMT'
1998/04/27 14:27:01| 10471ac0 adding entry: 13 at 2
1998/04/27 14:27:01| created entry 1045ad00: 'Content-Type: text/html'
1998/04/27 14:27:01| 10471ac0 adding entry: 12 at 3
1998/04/27 14:27:01| created entry 1045a1c0: 'Content-Length: 827'
1998/04/27 14:27:01| 10471ac0 adding entry: 9 at 4
1998/04/27 14:27:01| created entry 1045a520: 'Expires: Mon, 27 Apr 1998 20:27:01 GMT'
1998/04/27 14:27:01| 10471ac0 adding entry: 15 at 5
1998/04/27 14:27:01| packing sline 10471ab0 using 7fffaae8:
1998/04/27 14:27:01| HTTP/1.0 400 Bad Request
1998/04/27 14:27:01| storeAppend: appending 26 bytes for 'AE4C0FC0F689A3454A15AD3D29A510C3'
1998/04/27 14:27:01| memAppend: len 26
1998/04/27 14:27:01| InvokeHandlers: AE4C0FC0F689A3454A15AD3D29A510C3
1998/04/27 14:27:01| InvokeHandlers: checking client #0
1998/04/27 14:27:01| storeClientCopy2: AE4C0FC0F689A3454A15AD3D29A510C3
1998/04/27 14:27:01| storeClientCopy2: Copying from memory
1998/04/27 14:27:01| memCopy: offset 0: size 4096
1998/04/27 14:27:01| clientSendMoreData: error:unsupported-request-method, 26 bytes
1998/04/27 14:27:01| clientSendMoreData: FD 23 'error:unsupported-request-method', out.offset=0
1998/04/27 14:27:01| clientSendMoreData: Deferring error:unsupported-request-method
1998/04/27 14:27:01| storeCheckSwapOut: error:unsupported-request-method
1998/04/27 14:27:01| storeCheckSwapOut: store_status = STORE_PENDING
1998/04/27 14:27:01| storeCheckSwapOut: mem->inmem_lo = 0
1998/04/27 14:27:01| storeCheckSwapOut: mem->inmem_hi = 26
1998/04/27 14:27:01| storeCheckSwapOut: swapout.queue_offset = 0
1998/04/27 14:27:01| storeCheckSwapOut: swapout.done_offset = 0
1998/04/27 14:27:01| storeCheckSwapOut: lowest_offset = 0
1998/04/27 14:27:01| packing hdr: (10471ac0)
1998/04/27 14:27:01| searching for next e in hdr 10471ac0 from -1
1998/04/27 14:27:01| storeAppend: appending 6 bytes for 'AE4C0FC0F689A3454A15AD3D29A510C3'
1998/04/27 14:27:01| memAppend: len 6
1998/04/27 14:27:01| InvokeHandlers: AE4C0FC0F689A3454A15AD3D29A510C3
1998/04/27 14:27:01| InvokeHandlers: checking client #0
1998/04/27 14:27:01| storeCheckSwapOut: error:unsupported-request-method
1998/04/27 14:27:01| storeCheckSwapOut: store_status = STORE_PENDING
1998/04/27 14:27:01| storeCheckSwapOut: mem->inmem_lo = 0
1998/04/27 14:27:01| storeCheckSwapOut: mem->inmem_hi = 32
1998/04/27 14:27:01| storeCheckSwapOut: swapout.queue_offset = 0
1998/04/27 14:27:01| storeCheckSwapOut: swapout.done_offset = 0
1998/04/27 14:27:01| storeCheckSwapOut: lowest_offset = 0
1998/04/27 14:27:01| storeAppend: appending 2 bytes for 'AE4C0FC0F689A3454A15AD3D29A510C3'
1998/04/27 14:27:01| memAppend: len 2
1998/04/27 14:27:01| InvokeHandlers: AE4C0FC0F689A3454A15AD3D29A510C3
1998/04/27 14:27:01| InvokeHandlers: checking client #0
1998/04/27 14:27:01| storeCheckSwapOut: error:unsupported-request-method
1998/04/27 14:27:01| storeCheckSwapOut: store_status = STORE_PENDING
1998/04/27 14:27:01| storeCheckSwapOut: mem->inmem_lo = 0
1998/04/27 14:27:01| storeCheckSwapOut: mem->inmem_hi = 34
1998/04/27 14:27:01| storeCheckSwapOut: swapout.queue_offset = 0
1998/04/27 14:27:01| storeCheckSwapOut: swapout.done_offset = 0
1998/04/27 14:27:01| storeCheckSwapOut: lowest_offset = 0
1998/04/27 14:27:01| storeAppend: appending 16 bytes for 'AE4C0FC0F689A3454A15AD3D29A510C3'
1998/04/27 14:27:01| memAppend: len 16
1998/04/27 14:27:01| InvokeHandlers: AE4C0FC0F689A3454A15AD3D29A510C3
1998/04/27 14:27:01| InvokeHandlers: checking client #0
1998/04/27 14:27:01| storeCheckSwapOut: error:unsupported-request-method
1998/04/27 14:27:01| storeCheckSwapOut: store_status = STORE_PENDING
1998/04/27 14:27:01| storeCheckSwapOut: mem->inmem_lo = 0
1998/04/27 14:27:01| storeCheckSwapOut: mem->inmem_hi = 50
1998/04/27 14:27:01| storeCheckSwapOut: swapout.queue_offset = 0
1998/04/27 14:27:01| storeCheckSwapOut: swapout.done_offset = 0
1998/04/27 14:27:01| storeCheckSwapOut: lowest_offset = 0
1998/04/27 14:27:01| storeAppend: appending 2 bytes for 'AE4C0FC0F689A3454A15AD3D29A510C3'
1998/04/27 14:27:01| memAppend: len 2
1998/04/27 14:27:01| InvokeHandlers: AE4C0FC0F689A3454A15AD3D29A510C3
1998/04/27 14:27:01| InvokeHandlers: checking client #0
1998/04/27 14:27:01| storeCheckSwapOut: error:unsupported-request-method
1998/04/27 14:27:01| storeCheckSwapOut: store_status = STORE_PENDING
1998/04/27 14:27:01| storeCheckSwapOut: mem->inmem_lo = 0
1998/04/27 14:27:01| storeCheckSwapOut: mem->inmem_hi = 52
1998/04/27 14:27:01| storeCheckSwapOut: swapout.queue_offset = 0
1998/04/27 14:27:01| storeCheckSwapOut: swapout.done_offset = 0
1998/04/27 14:27:01| storeCheckSwapOut: lowest_offset = 0
1998/04/27 14:27:01| searching for next e in hdr 10471ac0 from 0
1998/04/27 14:27:01| storeAppend: appending 12 bytes for 'AE4C0FC0F689A3454A15AD3D29A510C3'
1998/04/27 14:27:01| memAppend: len 12
1998/04/27 14:27:01| InvokeHandlers: AE4C0FC0F689A3454A15AD3D29A510C3
1998/04/27 14:27:01| InvokeHandlers: checking client #0
1998/04/27 14:27:01| storeCheckSwapOut: error:unsupported-request-method
1998/04/27 14:27:01| storeCheckSwapOut: store_status = STORE_PENDING
1998/04/27 14:27:01| storeCheckSwapOut: mem->inmem_lo = 0
1998/04/27 14:27:01| storeCheckSwapOut: mem->inmem_hi = 64
1998/04/27 14:27:01| storeCheckSwapOut: swapout.queue_offset = 0
1998/04/27 14:27:01| storeCheckSwapOut: swapout.done_offset = 0
1998/04/27 14:27:01| storeCheckSwapOut: lowest_offset = 0
1998/04/27 14:27:01| storeAppend: appending 2 bytes for 'AE4C0FC0F689A3454A15AD3D29A510C3'
1998/04/27 14:27:01| memAppend: len 2
1998/04/27 14:27:01| InvokeHandlers: AE4C0FC0F689A3454A15AD3D29A510C3
1998/04/27 14:27:01| InvokeHandlers: checking client #0
1998/04/27 14:27:01| storeCheckSwapOut: error:unsupported-request-method
1998/04/27 14:27:01| storeCheckSwapOut: store_status = STORE_PENDING
1998/04/27 14:27:01| storeCheckSwapOut: mem->inmem_lo = 0
1998/04/27 14:27:01| storeCheckSwapOut: mem->inmem_hi = 66
1998/04/27 14:27:01| storeCheckSwapOut: swapout.queue_offset = 0
1998/04/27 14:27:01| storeCheckSwapOut: swapout.done_offset = 0
1998/04/27 14:27:01| storeCheckSwapOut: lowest_offset = 0
1998/04/27 14:27:01| storeAppend: appending 3 bytes for 'AE4C0FC0F689A3454A15AD3D29A510C3'
1998/04/27 14:27:01| memAppend: len 3
1998/04/27 14:27:01| InvokeHandlers: AE4C0FC0F689A3454A15AD3D29A510C3
1998/04/27 14:27:01| InvokeHandlers: checking client #0
1998/04/27 14:27:01| storeCheckSwapOut: error:unsupported-request-method
1998/04/27 14:27:01| storeCheckSwapOut: store_status = STORE_PENDING
1998/04/27 14:27:01| storeCheckSwapOut: mem->inmem_lo = 0
1998/04/27 14:27:01| storeCheckSwapOut: mem->inmem_hi = 69
1998/04/27 14:27:01| storeCheckSwapOut: swapout.queue_offset = 0
1998/04/27 14:27:01| storeCheckSwapOut: swapout.done_offset = 0
1998/04/27 14:27:01| storeCheckSwapOut: lowest_offset = 0
1998/04/27 14:27:01| storeAppend: appending 2 bytes for 'AE4C0FC0F689A3454A15AD3D29A510C3'
1998/04/27 14:27:01| memAppend: len 2
1998/04/27 14:27:01| InvokeHandlers: AE4C0FC0F689A3454A15AD3D29A510C3
1998/04/27 14:27:01| InvokeHandlers: checking client #0
1998/04/27 14:27:01| storeCheckSwapOut: error:unsupported-request-method
1998/04/27 14:27:01| storeCheckSwapOut: store_status = STORE_PENDING
1998/04/27 14:27:01| storeCheckSwapOut: mem->inmem_lo = 0
1998/04/27 14:27:01| storeCheckSwapOut: mem->inmem_hi = 71
1998/04/27 14:27:01| storeCheckSwapOut: swapout.queue_offset = 0
1998/04/27 14:27:01| storeCheckSwapOut: swapout.done_offset = 0
1998/04/27 14:27:01| storeCheckSwapOut: lowest_offset = 0
1998/04/27 14:27:01| searching for next e in hdr 10471ac0 from 1
1998/04/27 14:27:01| storeAppend: appending 4 bytes for 'AE4C0FC0F689A3454A15AD3D29A510C3'
1998/04/27 14:27:01| memAppend: len 4
1998/04/27 14:27:01| InvokeHandlers: AE4C0FC0F689A3454A15AD3D29A510C3
1998/04/27 14:27:01| InvokeHandlers: checking client #0
1998/04/27 14:27:01| storeCheckSwapOut: error:unsupported-request-method
1998/04/27 14:27:01| storeCheckSwapOut: store_status = STORE_PENDING
1998/04/27 14:27:01| storeCheckSwapOut: mem->inmem_lo = 0
1998/04/27 14:27:01| storeCheckSwapOut: mem->inmem_hi = 75
1998/04/27 14:27:01| storeCheckSwapOut: swapout.queue_offset = 0
1998/04/27 14:27:01| storeCheckSwapOut: swapout.done_offset = 0
1998/04/27 14:27:01| storeCheckSwapOut: lowest_offset = 0
1998/04/27 14:27:01| storeAppend: appending 2 bytes for 'AE4C0FC0F689A3454A15AD3D29A510C3'
1998/04/27 14:27:01| memAppend: len 2
1998/04/27 14:27:01| InvokeHandlers: AE4C0FC0F689A3454A15AD3D29A510C3
1998/04/27 14:27:01| InvokeHandlers: checking client #0
1998/04/27 14:27:01| storeCheckSwapOut: error:unsupported-request-method
1998/04/27 14:27:01| storeCheckSwapOut: store_status = STORE_PENDING
1998/04/27 14:27:01| storeCheckSwapOut: mem->inmem_lo = 0
1998/04/27 14:27:01| storeCheckSwapOut: mem->inmem_hi = 77
1998/04/27 14:27:01| storeCheckSwapOut: swapout.queue_offset = 0
1998/04/27 14:27:01| storeCheckSwapOut: swapout.done_offset = 0
1998/04/27 14:27:01| storeCheckSwapOut: lowest_offset = 0
1998/04/27 14:27:01| storeAppend: appending 29 bytes for 'AE4C0FC0F689A3454A15AD3D29A510C3'
1998/04/27 14:27:01| memAppend: len 29
1998/04/27 14:27:01| InvokeHandlers: AE4C0FC0F689A3454A15AD3D29A510C3
1998/04/27 14:27:01| InvokeHandlers: checking client #0
1998/04/27 14:27:01| storeCheckSwapOut: error:unsupported-request-method
1998/04/27 14:27:01| storeCheckSwapOut: store_status = STORE_PENDING
1998/04/27 14:27:01| storeCheckSwapOut: mem->inmem_lo = 0
1998/04/27 14:27:01| storeCheckSwapOut: mem->inmem_hi = 106
1998/04/27 14:27:01| storeCheckSwapOut: swapout.queue_offset = 0
1998/04/27 14:27:01| storeCheckSwapOut: swapout.done_offset = 0
1998/04/27 14:27:01| storeCheckSwapOut: lowest_offset = 0
1998/04/27 14:27:01| storeAppend: appending 2 bytes for 'AE4C0FC0F689A3454A15AD3D29A510C3'
1998/04/27 14:27:01| memAppend: len 2
1998/04/27 14:27:01| InvokeHandlers: AE4C0FC0F689A3454A15AD3D29A510C3
1998/04/27 14:27:01| InvokeHandlers: checking client #0
1998/04/27 14:27:01| storeCheckSwapOut: error:unsupported-request-method
1998/04/27 14:27:01| storeCheckSwapOut: store_status = STORE_PENDING
1998/04/27 14:27:01| storeCheckSwapOut: mem->inmem_lo = 0
1998/04/27 14:27:01| storeCheckSwapOut: mem->inmem_hi = 108
1998/04/27 14:27:01| storeCheckSwapOut: swapout.queue_offset = 0
1998/04/27 14:27:01| storeCheckSwapOut: swapout.done_offset = 0
1998/04/27 14:27:01| storeCheckSwapOut: lowest_offset = 0
1998/04/27 14:27:01| searching for next e in hdr 10471ac0 from 2
1998/04/27 14:27:01| storeAppend: appending 12 bytes for 'AE4C0FC0F689A3454A15AD3D29A510C3'
1998/04/27 14:27:01| memAppend: len 12
1998/04/27 14:27:01| InvokeHandlers: AE4C0FC0F689A3454A15AD3D29A510C3
1998/04/27 14:27:01| InvokeHandlers: checking client #0
1998/04/27 14:27:01| storeCheckSwapOut: error:unsupported-request-method
1998/04/27 14:27:01| storeCheckSwapOut: store_status = STORE_PENDING
1998/04/27 14:27:01| storeCheckSwapOut: mem->inmem_lo = 0
1998/04/27 14:27:01| storeCheckSwapOut: mem->inmem_hi = 120
1998/04/27 14:27:01| storeCheckSwapOut: swapout.queue_offset = 0
1998/04/27 14:27:01| storeCheckSwapOut: swapout.done_offset = 0
1998/04/27 14:27:01| storeCheckSwapOut: lowest_offset = 0
1998/04/27 14:27:01| storeAppend: appending 2 bytes for 'AE4C0FC0F689A3454A15AD3D29A510C3'
1998/04/27 14:27:01| memAppend: len 2
1998/04/27 14:27:01| InvokeHandlers: AE4C0FC0F689A3454A15AD3D29A510C3
1998/04/27 14:27:01| InvokeHandlers: checking client #0
1998/04/27 14:27:01| storeCheckSwapOut: error:unsupported-request-method
1998/04/27 14:27:01| storeCheckSwapOut: store_status = STORE_PENDING
1998/04/27 14:27:01| storeCheckSwapOut: mem->inmem_lo = 0
1998/04/27 14:27:01| storeCheckSwapOut: mem->inmem_hi = 122
1998/04/27 14:27:01| storeCheckSwapOut: swapout.queue_offset = 0
1998/04/27 14:27:01| storeCheckSwapOut: swapout.done_offset = 0
1998/04/27 14:27:01| storeCheckSwapOut: lowest_offset = 0
1998/04/27 14:27:01| storeAppend: appending 9 bytes for 'AE4C0FC0F689A3454A15AD3D29A510C3'
1998/04/27 14:27:01| memAppend: len 9
1998/04/27 14:27:01| InvokeHandlers: AE4C0FC0F689A3454A15AD3D29A510C3
1998/04/27 14:27:01| InvokeHandlers: checking client #0
1998/04/27 14:27:01| storeCheckSwapOut: error:unsupported-request-method
1998/04/27 14:27:01| storeCheckSwapOut: store_status = STORE_PENDING
1998/04/27 14:27:01| storeCheckSwapOut: mem->inmem_lo = 0
1998/04/27 14:27:01| storeCheckSwapOut: mem->inmem_hi = 131
1998/04/27 14:27:01| storeCheckSwapOut: swapout.queue_offset = 0
1998/04/27 14:27:01| storeCheckSwapOut: swapout.done_offset = 0
1998/04/27 14:27:01| storeCheckSwapOut: lowest_offset = 0
1998/04/27 14:27:01| storeAppend: appending 2 bytes for 'AE4C0FC0F689A3454A15AD3D29A510C3'
1998/04/27 14:27:01| memAppend: len 2
1998/04/27 14:27:01| InvokeHandlers: AE4C0FC0F689A3454A15AD3D29A510C3
1998/04/27 14:27:01| InvokeHandlers: checking client #0
1998/04/27 14:27:01| storeCheckSwapOut: error:unsupported-request-method
1998/04/27 14:27:01| storeCheckSwapOut: store_status = STORE_PENDING
1998/04/27 14:27:01| storeCheckSwapOut: mem->inmem_lo = 0
1998/04/27 14:27:01| storeCheckSwapOut: mem->inmem_hi = 133
1998/04/27 14:27:01| storeCheckSwapOut: swapout.queue_offset = 0
1998/04/27 14:27:01| storeCheckSwapOut: swapout.done_offset = 0
1998/04/27 14:27:01| storeCheckSwapOut: lowest_offset = 0
1998/04/27 14:27:01| searching for next e in hdr 10471ac0 from 3
1998/04/27 14:27:01| storeAppend: appending 14 bytes for 'AE4C0FC0F689A3454A15AD3D29A510C3'
1998/04/27 14:27:01| memAppend: len 14
1998/04/27 14:27:01| InvokeHandlers: AE4C0FC0F689A3454A15AD3D29A510C3
1998/04/27 14:27:01| InvokeHandlers: checking client #0
1998/04/27 14:27:01| storeCheckSwapOut: error:unsupported-request-method
1998/04/27 14:27:01| storeCheckSwapOut: store_status = STORE_PENDING
1998/04/27 14:27:01| storeCheckSwapOut: mem->inmem_lo = 0
1998/04/27 14:27:01| storeCheckSwapOut: mem->inmem_hi = 147
1998/04/27 14:27:01| storeCheckSwapOut: swapout.queue_offset = 0
1998/04/27 14:27:01| storeCheckSwapOut: swapout.done_offset = 0
1998/04/27 14:27:01| storeCheckSwapOut: lowest_offset = 0
1998/04/27 14:27:01| storeAppend: appending 2 bytes for 'AE4C0FC0F689A3454A15AD3D29A510C3'
1998/04/27 14:27:01| memAppend: len 2
1998/04/27 14:27:01| InvokeHandlers: AE4C0FC0F689A3454A15AD3D29A510C3
1998/04/27 14:27:01| InvokeHandlers: checking client #0
1998/04/27 14:27:01| storeCheckSwapOut: error:unsupported-request-method
1998/04/27 14:27:01| storeCheckSwapOut: store_status = STORE_PENDING
1998/04/27 14:27:01| storeCheckSwapOut: mem->inmem_lo = 0
1998/04/27 14:27:01| storeCheckSwapOut: mem->inmem_hi = 149
1998/04/27 14:27:01| storeCheckSwapOut: swapout.queue_offset = 0
1998/04/27 14:27:01| storeCheckSwapOut: swapout.done_offset = 0
1998/04/27 14:27:01| storeCheckSwapOut: lowest_offset = 0
1998/04/27 14:27:01| storeAppend: appending 3 bytes for 'AE4C0FC0F689A3454A15AD3D29A510C3'
1998/04/27 14:27:01| memAppend: len 3
1998/04/27 14:27:01| InvokeHandlers: AE4C0FC0F689A3454A15AD3D29A510C3
1998/04/27 14:27:01| InvokeHandlers: checking client #0
1998/04/27 14:27:01| storeCheckSwapOut: error:unsupported-request-method
1998/04/27 14:27:01| storeCheckSwapOut: store_status = STORE_PENDING
1998/04/27 14:27:01| storeCheckSwapOut: mem->inmem_lo = 0
1998/04/27 14:27:01| storeCheckSwapOut: mem->inmem_hi = 152
1998/04/27 14:27:01| storeCheckSwapOut: swapout.queue_offset = 0
1998/04/27 14:27:01| storeCheckSwapOut: swapout.done_offset = 0
1998/04/27 14:27:01| storeCheckSwapOut: lowest_offset = 0
1998/04/27 14:27:01| storeAppend: appending 2 bytes for 'AE4C0FC0F689A3454A15AD3D29A510C3'
1998/04/27 14:27:01| memAppend: len 2
1998/04/27 14:27:01| InvokeHandlers: AE4C0FC0F689A3454A15AD3D29A510C3
1998/04/27 14:27:01| InvokeHandlers: checking client #0
1998/04/27 14:27:01| storeCheckSwapOut: error:unsupported-request-method
1998/04/27 14:27:01| storeCheckSwapOut: store_status = STORE_PENDING
1998/04/27 14:27:01| storeCheckSwapOut: mem->inmem_lo = 0
1998/04/27 14:27:01| storeCheckSwapOut: mem->inmem_hi = 154
1998/04/27 14:27:01| storeCheckSwapOut: swapout.queue_offset = 0
1998/04/27 14:27:01| storeCheckSwapOut: swapout.done_offset = 0
1998/04/27 14:27:01| storeCheckSwapOut: lowest_offset = 0
1998/04/27 14:27:01| searching for next e in hdr 10471ac0 from 4
1998/04/27 14:27:01| storeAppend: appending 7 bytes for 'AE4C0FC0F689A3454A15AD3D29A510C3'
1998/04/27 14:27:01| memAppend: len 7
1998/04/27 14:27:01| InvokeHandlers: AE4C0FC0F689A3454A15AD3D29A510C3
1998/04/27 14:27:01| InvokeHandlers: checking client #0
1998/04/27 14:27:01| storeCheckSwapOut: error:unsupported-request-method
1998/04/27 14:27:01| storeCheckSwapOut: store_status = STORE_PENDING
1998/04/27 14:27:01| storeCheckSwapOut: mem->inmem_lo = 0
1998/04/27 14:27:01| storeCheckSwapOut: mem->inmem_hi = 161
1998/04/27 14:27:01| storeCheckSwapOut: swapout.queue_offset = 0
1998/04/27 14:27:01| storeCheckSwapOut: swapout.done_offset = 0
1998/04/27 14:27:01| storeCheckSwapOut: lowest_offset = 0
1998/04/27 14:27:01| storeAppend: appending 2 bytes for 'AE4C0FC0F689A3454A15AD3D29A510C3'
1998/04/27 14:27:01| memAppend: len 2
1998/04/27 14:27:01| InvokeHandlers: AE4C0FC0F689A3454A15AD3D29A510C3
1998/04/27 14:27:01| InvokeHandlers: checking client #0
1998/04/27 14:27:01| storeCheckSwapOut: error:unsupported-request-method
1998/04/27 14:27:01| storeCheckSwapOut: store_status = STORE_PENDING
1998/04/27 14:27:01| storeCheckSwapOut: mem->inmem_lo = 0
1998/04/27 14:27:01| storeCheckSwapOut: mem->inmem_hi = 163
1998/04/27 14:27:01| storeCheckSwapOut: swapout.queue_offset = 0
1998/04/27 14:27:01| storeCheckSwapOut: swapout.done_offset = 0
1998/04/27 14:27:01| storeCheckSwapOut: lowest_offset = 0
1998/04/27 14:27:01| storeAppend: appending 29 bytes for 'AE4C0FC0F689A3454A15AD3D29A510C3'
1998/04/27 14:27:01| memAppend: len 29
1998/04/27 14:27:01| InvokeHandlers: AE4C0FC0F689A3454A15AD3D29A510C3
1998/04/27 14:27:01| InvokeHandlers: checking client #0
1998/04/27 14:27:01| storeCheckSwapOut: error:unsupported-request-method
1998/04/27 14:27:01| storeCheckSwapOut: store_status = STORE_PENDING
1998/04/27 14:27:01| storeCheckSwapOut: mem->inmem_lo = 0
1998/04/27 14:27:01| storeCheckSwapOut: mem->inmem_hi = 192
1998/04/27 14:27:01| storeCheckSwapOut: swapout.queue_offset = 0
1998/04/27 14:27:01| storeCheckSwapOut: swapout.done_offset = 0
1998/04/27 14:27:01| storeCheckSwapOut: lowest_offset = 0
1998/04/27 14:27:01| storeAppend: appending 2 bytes for 'AE4C0FC0F689A3454A15AD3D29A510C3'
1998/04/27 14:27:01| memAppend: len 2
1998/04/27 14:27:01| InvokeHandlers: AE4C0FC0F689A3454A15AD3D29A510C3
1998/04/27 14:27:01| InvokeHandlers: checking client #0
1998/04/27 14:27:01| storeCheckSwapOut: error:unsupported-request-method
1998/04/27 14:27:01| storeCheckSwapOut: store_status = STORE_PENDING
1998/04/27 14:27:01| storeCheckSwapOut: mem->inmem_lo = 0
1998/04/27 14:27:01| storeCheckSwapOut: mem->inmem_hi = 194
1998/04/27 14:27:01| storeCheckSwapOut: swapout.queue_offset = 0
1998/04/27 14:27:01| storeCheckSwapOut: swapout.done_offset = 0
1998/04/27 14:27:01| storeCheckSwapOut: lowest_offset = 0
1998/04/27 14:27:01| searching for next e in hdr 10471ac0 from 5
1998/04/27 14:27:01| no more entries in hdr 10471ac0
1998/04/27 14:27:01| storeAppend: appending 2 bytes for 'AE4C0FC0F689A3454A15AD3D29A510C3'
1998/04/27 14:27:01| memAppend: len 2
1998/04/27 14:27:01| InvokeHandlers: AE4C0FC0F689A3454A15AD3D29A510C3
1998/04/27 14:27:01| InvokeHandlers: checking client #0
1998/04/27 14:27:01| storeCheckSwapOut: error:unsupported-request-method
1998/04/27 14:27:01| storeCheckSwapOut: store_status = STORE_PENDING
1998/04/27 14:27:01| storeCheckSwapOut: mem->inmem_lo = 0
1998/04/27 14:27:01| storeCheckSwapOut: mem->inmem_hi = 196
1998/04/27 14:27:01| storeCheckSwapOut: swapout.queue_offset = 0
1998/04/27 14:27:01| storeCheckSwapOut: swapout.done_offset = 0
1998/04/27 14:27:01| storeCheckSwapOut: lowest_offset = 0
1998/04/27 14:27:01| storeAppend: appending 827 bytes for 'AE4C0FC0F689A3454A15AD3D29A510C3'
1998/04/27 14:27:01| memAppend: len 827
1998/04/27 14:27:01| InvokeHandlers: AE4C0FC0F689A3454A15AD3D29A510C3
1998/04/27 14:27:01| InvokeHandlers: checking client #0
1998/04/27 14:27:01| storeCheckSwapOut: error:unsupported-request-method
1998/04/27 14:27:01| storeCheckSwapOut: store_status = STORE_PENDING
1998/04/27 14:27:01| storeCheckSwapOut: mem->inmem_lo = 0
1998/04/27 14:27:01| storeCheckSwapOut: mem->inmem_hi = 1023
1998/04/27 14:27:01| storeCheckSwapOut: swapout.queue_offset = 0
1998/04/27 14:27:01| storeCheckSwapOut: swapout.done_offset = 0
1998/04/27 14:27:01| storeCheckSwapOut: lowest_offset = 0
1998/04/27 14:27:01| destroying rep: 10471a80
1998/04/27 14:27:01| cleaning hdr: 10471ac0
1998/04/27 14:27:01| searching for next e in hdr 10471ac0 from -1
1998/04/27 14:27:01| destroying entry 1045a9a0: 'Server: Squid/1.2.beta20'
1998/04/27 14:27:01| searching for next e in hdr 10471ac0 from 0
1998/04/27 14:27:01| destroying entry 1045af00: 'Mime-Version: 1.0'
1998/04/27 14:27:01| searching for next e in hdr 10471ac0 from 1
1998/04/27 14:27:01| destroying entry 1045abe0: 'Date: Mon, 27 Apr 1998 20:27:01 GMT'
1998/04/27 14:27:01| searching for next e in hdr 10471ac0 from 2
1998/04/27 14:27:01| destroying entry 1045ad00: 'Content-Type: text/html'
1998/04/27 14:27:01| searching for next e in hdr 10471ac0 from 3
1998/04/27 14:27:01| destroying entry 1045a1c0: 'Content-Length: 827'
1998/04/27 14:27:01| searching for next e in hdr 10471ac0 from 4
1998/04/27 14:27:01| destroying entry 1045a520: 'Expires: Mon, 27 Apr 1998 20:27:01 GMT'
1998/04/27 14:27:01| searching for next e in hdr 10471ac0 from 5
1998/04/27 14:27:01| no more entries in hdr 10471ac0
1998/04/27 14:27:01| storeComplete: 'AE4C0FC0F689A3454A15AD3D29A510C3'
1998/04/27 14:27:01| storeEntryValidLength: Checking 'AE4C0FC0F689A3454A15AD3D29A510C3'
1998/04/27 14:27:01| storeEntryValidLength: object_len = 1023
1998/04/27 14:27:01| storeEntryValidLength: hdr_sz = 0
1998/04/27 14:27:01| storeEntryValidLength: content_length = -1
1998/04/27 14:27:01| storeEntryValidLength: Unspecified content length: AE4C0FC0F689A3454A15AD3D29A510C3
1998/04/27 14:27:01| InvokeHandlers: AE4C0FC0F689A3454A15AD3D29A510C3
1998/04/27 14:27:01| InvokeHandlers: checking client #0
1998/04/27 14:27:01| storeCheckSwapOut: error:unsupported-request-method
1998/04/27 14:27:01| storeCheckSwapOut: store_status = STORE_OK
1998/04/27 14:27:01| storeCheckSwapOut: mem->inmem_lo = 0
1998/04/27 14:27:01| storeCheckSwapOut: mem->inmem_hi = 1023
1998/04/27 14:27:01| storeCheckSwapOut: swapout.queue_offset = 0
1998/04/27 14:27:01| storeCheckSwapOut: swapout.done_offset = 0
1998/04/27 14:27:01| storeCheckSwapOut: lowest_offset = 0
1998/04/27 14:27:01| comm_select: FD 27 ready for writing
1998/04/27 14:27:01| commHandleWrite: FD 27: off 0, sz 95.
1998/04/27 14:27:01| cbdataValid: 104b8b40
1998/04/27 14:27:01| httpSendRequestEntry: FD 27: size 95: errflag 0.
1998/04/27 14:27:01| clientReadBody: start fd=23 body_size=417 in.offset=0 cb=456294 req=10479000
1998/04/27 14:27:01| clientReadBody: fd 23 wait for clientReadRequest
1998/04/27 14:27:01| cbdataUnlock: 104b8b40
1998/04/27 14:27:01| comm_select: 1 sockets ready at 893708821
1998/04/27 14:27:01| comm_select: FD 49 ready for reading
1998/04/27 14:27:01| commSetSelect: FD 49 type 1
1998/04/27 14:27:01| netdbHandlePingReply: from 198.17.46.59
1998/04/27 14:27:01| netdbHandlePingReply: 198.17.46.0; rtt= 66.2 hops= 6.0
1998/04/27 14:27:01| comm_select: 0 sockets ready at 893708821
1998/04/27 14:27:02| comm_select: 0 sockets ready at 893708822
1998/04/27 14:27:02| comm_select: time out: 893708822
Received on Tue Jul 29 2003 - 13:15:48 MDT

This archive was generated by hypermail pre-2.1.9 : Tue Dec 09 2003 - 16:11:45 MST