Re: [squid-users] SSL Site Problem...

From: Henrik Nordstrom <henrik_at_henriknordstrom.net>
Date: Wed, 05 Nov 2008 21:04:00 +0100

Most likely a window scaling issue. There is still very many broken
firewalls out there..

Squid FAQ System Wierdness - Linux - Some sites load extremely slowly or not at all:
http://wiki.squid-cache.org/SquidFaq/SystemWeirdnesses#head-4920199b311ce7d20b9a0d85723fd5d0dfc9bc84

Regards
Henrik

On ons, 2008-11-05 at 15:07 +0000, Andy McCall wrote:
> Hi Folks,
>
> I have a problem accessing an SSL site through my Squid setup, IE just spins its blue circle forever, and doesn't seem to ever actually time out. The same site works when going direct. I have tried multiple browsers to eliminate the browser as the issue.
>
> Any help is appreciated, as I am really stuck now...
>
> The site is:
>
> https://secure.crtsolutions.co.uk
>
> I am using:
>
> Squid Cache: Version 2.6.STABLE18
> configure options: '--prefix=/usr' '--exec_prefix=/usr' '--bindir=/usr/sbin' '--sbindir=/usr/sbin' '--libexecdir=/usr/lib/squid' '--sysconfdir=/etc/squid' '--localstatedir=/var/spool/squid' '--datadir=/usr/share/squid' '--enable-async-io' '--with-pthreads' '--enable-storeio=ufs,aufs,coss,diskd,null' '--enable-linux-netfilter' '--enable-arp-acl' '--enable-epoll' '--enable-removal-policies=lru,heap' '--enable-snmp' '--enable-delay-pools' '--enable-htcp' '--enable-cache-digests' '--enable-underscores' '--enable-referer-log' '--enable-useragent-log' '--enable-auth=basic,digest,ntlm' '--enable-carp' '--enable-follow-x-forwarded-for' '--with-large-files' '--with-maxfd=65536' 'i386-debian-linux' 'build_alias=i386-debian-linux' 'host_alias=i386-debian-linux' 'target_alias=i386-debian-linux' 'CFLAGS=-Wall -g -O2' 'LDFLAGS=-Wl,-Bsymbolic-functions' 'CPPFLAGS='
>
> The entry in access.log is:
>
> 1225894644.785 3106 10.XX.XX.XX TCP_MISS/200 174 CONNECT secure.crtsolutions.co.uk:443 - DIRECT/195.114.102.18 -
>
> The cache.log entry is (if there is too much here, I apologise, I am not sure how much to post!):
>
> 2008/11/05 14:17:25| parseHttpRequest: Client HTTP version 1.0.
> 2008/11/05 14:17:25| parseHttpRequest: Method is 'CONNECT'
> 2008/11/05 14:17:25| parseHttpRequest: URI is 'secure.crtsolutions.co.uk:443'
> 2008/11/05 14:17:25| parseHttpRequest: req_hdr = {User-Agent: Mozilla/4.0 (compatible; MSIE 7.0; Windows NT 6.0; SLCC1; .NET CLR 2.0.50727; Media Center PC 5.0; .NET CLR 3.0.04506; InfoPath.2)^M
> Proxy-Connection: Keep-Alive^M
> Content-Length: 0^M
> Host: secure.crtsolutions.co.uk^M
> Pragma: no-cache^M
> ^M
> }
> 2008/11/05 14:17:25| parseHttpRequest: end = {}
> 2008/11/05 14:17:25| parseHttpRequest: prefix_sz = 294, req_line_sz = 48
> 2008/11/05 14:17:25| parseHttpRequest: Request Header is
> User-Agent: Mozilla/4.0 (compatible; MSIE 7.0; Windows NT 6.0; SLCC1; .NET CLR 2.0.50727; Media Center PC 5.0; .NET CLR 3.0.04506; InfoPath.2)^M
> Proxy-Connection: Keep-Alive^M
> Content-Length: 0^M
> Host: secure.crtsolutions.co.uk^M
> Pragma: no-cache^M
> ^M
>
> 2008/11/05 14:17:25| parseHttpRequest: Complete request received
> 2008/11/05 14:17:25| conn->in.offset = 0
> 2008/11/05 14:17:25| commSetTimeout: FD 44 timeout 86400
> 2008/11/05 14:17:25| init-ing hdr: 0x191b82c8 owner: 2
> 2008/11/05 14:17:25| parsing hdr: (0x191b82c8)
> User-Agent: Mozilla/4.0 (compatible; MSIE 7.0; Windows NT 6.0; SLCC1; .NET CLR 2.0.50727; Media Center PC 5.0; .NET CLR 3.0.04506; InfoPath.2)^M
> Proxy-Connection: Keep-Alive^M
> Content-Length: 0^M
> Host: secure.crtsolutions.co.uk^M
> Pragma: no-cache^M
>
> 2008/11/05 14:17:25| creating entry 0x1a1f39a0: near 'User-Agent: Mozilla/4.0 (compatible; MSIE 7.0; Windows NT 6.0; SLCC1; .NET CLR 2.0.50727; Media Center PC 5.0; .NET CLR 3.0.04506; InfoPath.2)'
> 2008/11/05 14:17:25| created entry 0x1a1f39a0: 'User-Agent: Mozilla/4.0 (compatible; MSIE 7.0; Windows NT 6.0; SLCC1; .NET CLR 2.0.50727; Media Center PC 5.0; .NET CLR 3.0.04506; InfoPath.2)'
> 2008/11/05 14:17:25| 0x191b82c8 adding entry: 50 at 0
> 2008/11/05 14:17:25| creating entry 0x1a1ea180: near 'Proxy-Connection: Keep-Alive'
> 2008/11/05 14:17:25| created entry 0x1a1ea180: 'Proxy-Connection: Keep-Alive'
> 2008/11/05 14:17:25| 0x191b82c8 adding entry: 41 at 1
> 2008/11/05 14:17:25| creating entry 0x82b4a88: near 'Content-Length: 0'
> 2008/11/05 14:17:25| created entry 0x82b4a88: 'Content-Length: 0'
> 2008/11/05 14:17:25| 0x191b82c8 adding entry: 14 at 2
> 2008/11/05 14:17:25| creating entry 0x1a1f38d0: near 'Host: secure.crtsolutions.co.uk'
> 2008/11/05 14:17:25| created entry 0x1a1f38d0: 'Host: secure.crtsolutions.co.uk'
> 2008/11/05 14:17:25| 0x191b82c8 adding entry: 27 at 3
> 2008/11/05 14:17:25| creating entry 0x1a1f3910: near 'Pragma: no-cache'
> 2008/11/05 14:17:25| created entry 0x1a1f3910: 'Pragma: no-cache'
> 2008/11/05 14:17:25| 0x191b82c8 adding entry: 37 at 4
> 2008/11/05 14:17:25| 0x191b82c8 lookup for 20
> 2008/11/05 14:17:25| clientSetKeepaliveFlag: http_ver = 1.0
> 2008/11/05 14:17:25| clientSetKeepaliveFlag: method = CONNECT
> 2008/11/05 14:17:25| 0x191b82c8 lookup for 41
> 2008/11/05 14:17:25| 0x191b82c8: joining for id 41
> 2008/11/05 14:17:25| 0x191b82c8: joined for id 41: Keep-Alive
> 2008/11/05 14:17:25| 0x191b82c8 lookup for 52
> 2008/11/05 14:17:25| 0x191b82c8 lookup for 41
> 2008/11/05 14:17:25| 0x191b82c8: joining for id 41
> 2008/11/05 14:17:25| 0x191b82c8: joined for id 41: Keep-Alive
> 2008/11/05 14:17:25| commSetSelect: FD 44 type 1
> 2008/11/05 14:17:25| commSetEvents(fd=44)
> 2008/11/05 14:17:25| 0x191b82c8 lookup for 59
> 2008/11/05 14:17:25| cbdataLock: 0x82a9c78
> 2008/11/05 14:17:25| cbdataLock: 0x191f33c8
> 2008/11/05 14:17:25| cbdataLock: 0x191c41c8
> 2008/11/05 14:17:25| cbdataValid: 0x82a9c78
> 2008/11/05 14:17:25| aclCheck: checking 'http_access allow manager localhost'
> 2008/11/05 14:17:25| aclMatchAclList: checking manager
> 2008/11/05 14:17:25| aclMatchAcl: checking 'acl manager proto cache_object'
> 2008/11/05 14:17:25| aclMatchAclList: no match, returning 0
> 2008/11/05 14:17:25| cbdataLock: 0x82a97f8
> 2008/11/05 14:17:25| cbdataUnlock: 0x82a9c78
> 2008/11/05 14:17:25| cbdataValid: 0x82a97f8
> 2008/11/05 14:17:25| aclCheck: checking 'http_access deny manager'
> 2008/11/05 14:17:25| aclMatchAclList: checking manager
> 2008/11/05 14:17:25| aclMatchAcl: checking 'acl manager proto cache_object'
> 2008/11/05 14:17:25| aclMatchAclList: no match, returning 0
> 2008/11/05 14:17:25| cbdataLock: 0x82a9d40
> 2008/11/05 14:17:25| cbdataUnlock: 0x82a97f8
> 2008/11/05 14:17:25| cbdataValid: 0x82a9d40
> 2008/11/05 14:17:25| aclCheck: checking 'http_access allow purge localhost'
> 2008/11/05 14:17:25| aclMatchAclList: checking purge
> 2008/11/05 14:17:25| aclMatchAcl: checking 'acl purge method PURGE'
> 2008/11/05 14:17:25| aclMatchAclList: no match, returning 0
> 2008/11/05 14:17:25| cbdataLock: 0x82a9d18
> 2008/11/05 14:17:25| cbdataUnlock: 0x82a9d40
> 2008/11/05 14:17:25| cbdataValid: 0x82a9d18
> 2008/11/05 14:17:25| aclCheck: checking 'http_access deny purge'
> 2008/11/05 14:17:25| aclMatchAclList: checking purge
> 2008/11/05 14:17:25| aclMatchAcl: checking 'acl purge method PURGE'
> 2008/11/05 14:17:25| aclMatchAclList: no match, returning 0
> 2008/11/05 14:17:25| cbdataLock: 0x82a9e08
> 2008/11/05 14:17:25| cbdataUnlock: 0x82a9d18
> 2008/11/05 14:17:25| cbdataValid: 0x82a9e08
> 2008/11/05 14:17:25| aclCheck: checking 'http_access deny !Safe_ports'
> 2008/11/05 14:17:25| aclMatchAclList: checking !Safe_ports
> 2008/11/05 14:17:25| aclMatchAcl: checking 'acl Safe_ports port 80 # http'
> 2008/11/05 14:17:25| aclMatchAclList: no match, returning 0
> 2008/11/05 14:17:25| cbdataLock: 0x82a9e68
> 2008/11/05 14:17:25| cbdataUnlock: 0x82a9e08
> 2008/11/05 14:17:25| cbdataValid: 0x82a9e68
> 2008/11/05 14:17:25| aclCheck: checking 'http_access deny CONNECT !SSL_ports'
> 2008/11/05 14:17:25| aclMatchAclList: checking CONNECT
> 2008/11/05 14:17:25| aclMatchAcl: checking 'acl CONNECT method CONNECT'
> 2008/11/05 14:17:25| aclMatchAclList: checking !SSL_ports
> 2008/11/05 14:17:25| aclMatchAcl: checking 'acl SSL_ports port 443 # https'
> 2008/11/05 14:17:25| aclMatchAclList: no match, returning 0
> 2008/11/05 14:17:25| cbdataLock: 0x82aa020
> 2008/11/05 14:17:25| cbdataUnlock: 0x82a9e68
> 2008/11/05 14:17:25| cbdataValid: 0x82aa020
> 2008/11/05 14:17:25| aclCheck: checking 'http_access allow school_networks'
> 2008/11/05 14:17:25| aclMatchAclList: checking school_networks
> 2008/11/05 14:17:25| aclMatchAcl: checking 'acl school_networks src 172.X.X.X/8 192.XXX.X.X/16'
> 2008/11/05 14:17:25| aclMatchIp: '10.XX.XX.XX' NOT found
> 2008/11/05 14:17:25| aclMatchAclList: no match, returning 0
> 2008/11/05 14:17:25| cbdataLock: 0x82aa080
> 2008/11/05 14:17:25| cbdataUnlock: 0x82aa020
> 2008/11/05 14:17:25| cbdataValid: 0x82aa080
> 2008/11/05 14:17:25| aclCheck: checking 'http_access allow council_net'
> 2008/11/05 14:17:25| aclMatchAclList: checking council_net
> 2008/11/05 14:17:25| aclMatchAcl: checking 'acl council_net src 10.0.0.0/8'
> 2008/11/05 14:17:25| aclMatchIp: '10.XX.XX.XX' found
> 2008/11/05 14:17:25| aclMatchAclList: returning 1
> 2008/11/05 14:17:25| aclCheck: match found, returning 1
> 2008/11/05 14:17:25| cbdataUnlock: 0x82aa080
> 2008/11/05 14:17:25| aclCheckCallback: answer=1
> 2008/11/05 14:17:25| cbdataValid: 0x191c41c8
> 2008/11/05 14:17:25| The request CONNECT secure.crtsolutions.co.uk:443 is ALLOWED, because it matched 'council_net'
> 2008/11/05 14:17:25| clientRedirectStart: 'secure.crtsolutions.co.uk:443'
> 2008/11/05 14:17:25| redirectStart: 'secure.crtsolutions.co.uk:443'
> 2008/11/05 14:17:25| cbdataLock: 0x191c41c8
> 2008/11/05 14:17:25| cbdataLock: 0x196d6138
> 2008/11/05 14:17:25| cbdataValid: 0x196d6138
> 2008/11/05 14:17:25| comm_write: FD 7: sz 57: hndl 0x8088b10: data 0x82b5fd8.
> 2008/11/05 14:17:25| cbdataLock: 0x82b5fd8
> 2008/11/05 14:17:25| commSetSelect: FD 7 type 2
> 2008/11/05 14:17:25| commSetEvents(fd=7)
> 2008/11/05 14:17:25| helperDispatch: Request sent to url_rewriter #1[0], 57 bytes
> 2008/11/05 14:17:25| helperSubmit: secure.crtsolutions.co.uk:443 10.XX.XX.XX/- - CONNECT -
>
> 2008/11/05 14:17:25| cbdataUnlock: 0x191c41c8
> 2008/11/05 14:17:25| cbdataUnlock: 0x191f33c8
> 2008/11/05 14:17:25| cbdataFree: 0x191b4aa8
> 2008/11/05 14:17:25| cbdataFree: Freeing 0x191b4aa8
> 2008/11/05 14:17:25| cbdataValid: 0x191f33c8
> 2008/11/05 14:17:25| cbdataUnlock: 0x191f33c8
> 2008/11/05 14:17:25| commSetSelect: FD 44 type 1
> 2008/11/05 14:17:25| commSetEvents(fd=44)
> 2008/11/05 14:17:25| comm_select: timeout 997
> 2008/11/05 14:17:25| comm_call_handlers(): got fd=7 read_event=0 write_event=4 F->read_handler=0x8089ad0 F->write_handler=0x806ef80
> 2008/11/05 14:17:25| commHandleWrite: FD 7: off 0, sz 57.
> 2008/11/05 14:17:25| commHandleWrite: write() returns 57
> 2008/11/05 14:17:25| cbdataValid: 0x82b5fd8
> 2008/11/05 14:17:25| cbdataUnlock: 0x82b5fd8
> 2008/11/05 14:17:25| commSetEvents(fd=7)
> 2008/11/05 14:17:25| comm_select: timeout 997
> 2008/11/05 14:17:25| comm_call_handlers(): got fd=7 read_event=1 write_event=0 F->read_handler=0x8089ad0 F->write_handler=(nil)
> 2008/11/05 14:17:25| comm_call_handlers(): Calling read handler on fd=7
> 2008/11/05 14:17:25| cbdataValid: 0x82b5fd8
> 2008/11/05 14:17:25| helperHandleRead: 1 bytes from url_rewriter #1.
> 2008/11/05 14:17:25| commSetSelect: FD 7 type 1
> 2008/11/05 14:17:25| commSetEvents(fd=7)
> 2008/11/05 14:17:25| helperHandleRead: '
> '
> 2008/11/05 14:17:25| helperHandleRead: end of reply found:
>
> 2008/11/05 14:17:25| cbdataValid: 0x196d6138
> 2008/11/05 14:17:25| redirectHandleRead: {}
> 2008/11/05 14:17:25| cbdataValid: 0x191c41c8
> 2008/11/05 14:17:25| cbdataUnlock: 0x191c41c8
> 2008/11/05 14:17:25| clientRedirectDone: 'secure.crtsolutions.co.uk:443' result=NULL
> 2008/11/05 14:17:25| 0x191b82c8 lookup for 37
> 2008/11/05 14:17:25| 0x191b82c8: joining for id 37
> 2008/11/05 14:17:25| 0x191b82c8: joined for id 37: no-cache
> 2008/11/05 14:17:25| 0x191b82c8 lookup for 7
> 2008/11/05 14:17:25| 0x191b82c8 lookup for 7
> 2008/11/05 14:17:25| 0x191b82c8 lookup for 40
> 2008/11/05 14:17:25| 0x191b82c8 lookup for 52
> 2008/11/05 14:17:25| clientInterpretRequestHeaders: REQ_NOCACHE = SET
> 2008/11/05 14:17:25| clientInterpretRequestHeaders: REQ_CACHABLE = NOT SET
> 2008/11/05 14:17:25| clientInterpretRequestHeaders: REQ_HIERARCHICAL = NOT SET
> 2008/11/05 14:17:25| clientProcessRequest: CONNECT 'secure.crtsolutions.co.uk:443'
> 2008/11/05 14:17:25| aclCheckFast: list: (nil)
> 2008/11/05 14:17:25| aclCheckFast: no matches, returning: 1
> 2008/11/05 14:17:25| sslStart: 'CONNECT secure.crtsolutions.co.uk:443'
> 2008/11/05 14:17:25| comm_open: FD 48 is a new socket
> 2008/11/05 14:17:25| fd_open FD 48 secure.crtsolutions.co.uk:443
> 2008/11/05 14:17:25| comm_add_close_handler: FD 48, handler=0x80b5a20, data=0x196e0db0
> 2008/11/05 14:17:25| cbdataLock: 0x196e0db0
> 2008/11/05 14:17:25| comm_add_close_handler: FD 44, handler=0x80b5980, data=0x196e0db0
> 2008/11/05 14:17:25| cbdataLock: 0x196e0db0
> 2008/11/05 14:17:25| commSetTimeout: FD 44 timeout 86400
> 2008/11/05 14:17:25| commSetSelect: FD 44 type 1
> 2008/11/05 14:17:25| commSetEvents(fd=44)
> 2008/11/05 14:17:25| peerSelect: CONNECT
> 2008/11/05 14:17:25| cbdataLock: 0x196e0db0
> 2008/11/05 14:17:25| peerSelectFoo: 'CONNECT secure.crtsolutions.co.uk'
> 2008/11/05 14:17:25| peerCheckNetdbDirect: MY RTT = 0 msec
> 2008/11/05 14:17:25| peerCheckNetdbDirect: minimum_direct_rtt = 400 msec
> 2008/11/05 14:17:25| peerCheckNetdbDirect: MY hops = 0
> 2008/11/05 14:17:25| peerCheckNetdbDirect: minimum_direct_hops = 4
> 2008/11/05 14:17:25| whichPeer: from 0.0.0.0 port 0
> 2008/11/05 14:17:25| peerSelectFoo: direct = DIRECT_MAYBE
> 2008/11/05 14:17:25| peerAddFwdServer: adding DIRECT DIRECT
> 2008/11/05 14:17:25| cbdataValid: 0x196e0db0
> 2008/11/05 14:17:25| commSetTimeout: FD 48 timeout 60
> 2008/11/05 14:17:25| commConnectStart: FD 48, secure.crtsolutions.co.uk:443
> 2008/11/05 14:17:25| cbdataLock: 0x196e0db0
> 2008/11/05 14:17:25| comm_add_close_handler: FD 48, handler=0x8070220, data=0x191f6b68
> 2008/11/05 14:17:25| cbdataLock: 0x191f6b68
> 2008/11/05 14:17:25| ipcache_nbgethostbyname: Name 'secure.crtsolutions.co.uk'.
> 2008/11/05 14:17:25| ipcache_nbgethostbyname: HIT for 'secure.crtsolutions.co.uk'
> 2008/11/05 14:17:25| cbdataLock: 0x191f6b68
> 2008/11/05 14:17:25| cbdataValid: 0x191f6b68
> 2008/11/05 14:17:25| ipcacheCycleAddr: secure.crtsolutions.co.uk now at 195.114.102.18
> 2008/11/05 14:17:25| connect FD 48: (115) Operation now in progress
> 2008/11/05 14:17:25| comm_connect_addr: FD 48 connection pending
> 2008/11/05 14:17:25| commConnectHandle: FD 48: COMM_INPROGRESS
> 2008/11/05 14:17:25| commSetSelect: FD 48 type 2
> 2008/11/05 14:17:25| commSetEvents(fd=48)
> 2008/11/05 14:17:25| cbdataUnlock: 0x191f6b68
> 2008/11/05 14:17:25| cbdataUnlock: 0x196e0db0
> 2008/11/05 14:17:25| cbdataFree: 0x191d6a88
> 2008/11/05 14:17:25| cbdataFree: Freeing 0x191d6a88
> 2008/11/05 14:17:25| cbdataFree: 0x196d6138
> 2008/11/05 14:17:25| cbdataFree: 0x196d6138 has 1 locks, not freeing
> 2008/11/05 14:17:25| cbdataUnlock: 0x196d6138
> 2008/11/05 14:17:25| cbdataUnlock: Freeing 0x196d6138
> 2008/11/05 14:17:25| comm_select: timeout 996
> 2008/11/05 14:17:25| comm_call_handlers(): got fd=48 read_event=0 write_event=4 F->read_handler=(nil) F->write_handler=0x8070330
> 2008/11/05 14:17:25| comm_connect_addr: FD 48 connected to 195.114.102.18:443
> 2008/11/05 14:17:25| comm_remove_close_handler: FD 48, handler=0x8070220, data=0x191f6b68
> 2008/11/05 14:17:25| cbdataUnlock: 0x191f6b68
> 2008/11/05 14:17:25| commSetTimeout: FD 48 timeout -1
> 2008/11/05 14:17:25| commConnectFree: FD 48
> 2008/11/05 14:17:25| commConnectFree: FD 48
> 2008/11/05 14:17:25| cbdataFree: 0x191f6b68
> 2008/11/05 14:17:25| cbdataFree: Freeing 0x191f6b68
> 2008/11/05 14:17:25| cbdataValid: 0x196e0db0
> 2008/11/05 14:17:25| sslConnected: FD 48 sslState=0x196e0db0
> 2008/11/05 14:17:25| commSetSelect: FD 44 type 2
> 2008/11/05 14:17:25| commSetEvents(fd=44)
> 2008/11/05 14:17:25| commSetSelect: FD 44 type 1
> 2008/11/05 14:17:25| commSetEvents(fd=44)
> 2008/11/05 14:17:25| commSetSelect: FD 48 type 1
> 2008/11/05 14:17:25| commSetEvents(fd=48)
> 2008/11/05 14:17:25| commSetTimeout: FD 48 timeout 900
> 2008/11/05 14:17:25| cbdataUnlock: 0x196e0db0
> 2008/11/05 14:17:25| commSetEvents(fd=48)
> 2008/11/05 14:17:25| comm_select: timeout 980
> 2008/11/05 14:17:25| comm_call_handlers(): got fd=44 read_event=0 write_event=4 F->read_handler=0x80b4c70 F->write_handler=0x80b4e30
> 2008/11/05 14:17:25| sslWriteClient: FD 44, 39 bytes to write
> 2008/11/05 14:17:25| sslWriteClient: FD 44, 39 bytes written
> 2008/11/05 14:17:25| cbdataLock: 0x196e0db0
> 2008/11/05 14:17:25| cbdataValid: 0x196e0db0
> 2008/11/05 14:17:25| commSetSelect: FD 44 type 1
> 2008/11/05 14:17:25| commSetEvents(fd=44)
> 2008/11/05 14:17:25| commSetSelect: FD 48 type 1
> 2008/11/05 14:17:25| commSetEvents(fd=48)
> 2008/11/05 14:17:25| cbdataUnlock: 0x196e0db0
> 2008/11/05 14:17:25| commSetEvents(fd=44)
> 2008/11/05 14:17:25| comm_select: timeout 980
> 2008/11/05 14:17:25| comm_call_handlers(): got fd=44 read_event=1 write_event=0 F->read_handler=0x80b4c70 F->write_handler=(nil)
> 2008/11/05 14:17:25| comm_call_handlers(): Calling read handler on fd=44
> 2008/11/05 14:17:25| sslReadClient: FD 44, reading 65535 bytes at offset 0
> 2008/11/05 14:17:25| sslReadClient: FD 44, read 135 bytes
> 2008/11/05 14:17:25| cbdataLock: 0x196e0db0
> 2008/11/05 14:17:25| cbdataValid: 0x196e0db0
> 2008/11/05 14:17:25| commSetSelect: FD 44 type 1
> 2008/11/05 14:17:25| commSetEvents(fd=44)
> 2008/11/05 14:17:25| commSetSelect: FD 48 type 2
> 2008/11/05 14:17:25| commSetEvents(fd=48)
> 2008/11/05 14:17:25| commSetSelect: FD 48 type 1
> 2008/11/05 14:17:25| commSetEvents(fd=48)
> 2008/11/05 14:17:25| cbdataUnlock: 0x196e0db0
> 2008/11/05 14:17:25| comm_select: timeout 978
> 2008/11/05 14:17:25| comm_call_handlers(): got fd=48 read_event=0 write_event=4 F->read_handler=0x80b49b0 F->write_handler=0x80b5030
> 2008/11/05 14:17:25| sslWriteServer: FD 48, 135 bytes to write
> 2008/11/05 14:17:25| sslWriteServer: FD 48, 135 bytes written
> 2008/11/05 14:17:25| cbdataLock: 0x196e0db0
> 2008/11/05 14:17:25| cbdataValid: 0x196e0db0
> 2008/11/05 14:17:25| commSetSelect: FD 44 type 1
> 2008/11/05 14:17:25| commSetEvents(fd=44)
> 2008/11/05 14:17:25| commSetSelect: FD 48 type 1
> 2008/11/05 14:17:25| commSetEvents(fd=48)
> 2008/11/05 14:17:25| cbdataUnlock: 0x196e0db0
> 2008/11/05 14:17:25| commSetEvents(fd=48)
> 2008/11/05 14:17:25| comm_select: timeout 978
> 2008/11/05 14:17:25| comm_select: time out
> 2008/11/05 14:17:25| comm_select: timeout 722
> 2008/11/05 14:17:26| comm_select: time out
> 2008/11/05 14:17:26| eventRun: RUN ID 3033134
> 2008/11/05 14:17:26| eventRun: Running 'MaintainSwapSpace', id 3033133
> 2008/11/05 14:17:26| storeMaintainSwapSpace: f=0.000000, max_scan=100, max_remove=10
> 2008/11/05 14:17:26| cbdataFree: 0x8471098
> 2008/11/05 14:17:26| cbdataFree: Freeing 0x8471098
> 2008/11/05 14:17:26| storeUfsDirMaintain: /cache1 removed 0/10 f=0.000 max_scan=100
> 2008/11/05 14:17:26| storeMaintainSwapSpace: f=0.000000, max_scan=100, max_remove=10
> 2008/11/05 14:17:26| cbdataFree: 0x8471098
> 2008/11/05 14:17:26| cbdataFree: Freeing 0x8471098
> 2008/11/05 14:17:26| storeUfsDirMaintain: /cache2 removed 0/10 f=0.000 max_scan=100
> 2008/11/05 14:17:26| eventAdd: Adding 'MaintainSwapSpace', in 1.000000 seconds
> 2008/11/05 14:17:26| comm_select: timeout 1000
> 2008/11/05 14:17:26| comm_select: time out
> 2008/11/05 14:17:26| comm_select: timeout 721
> 2008/11/05 14:17:27| comm_select: time out
> 2008/11/05 14:17:27| eventRun: RUN ID 3033135
> 2008/11/05 14:17:27| eventRun: Running 'MaintainSwapSpace', id 3033134
> 2008/11/05 14:17:27| storeMaintainSwapSpace: f=0.000000, max_scan=100, max_remove=10
> 2008/11/05 14:17:27| cbdataFree: 0x8471098
> 2008/11/05 14:17:27| cbdataFree: Freeing 0x8471098
> 2008/11/05 14:17:27| storeUfsDirMaintain: /cache1 removed 0/10 f=0.000 max_scan=100
> 2008/11/05 14:17:27| storeMaintainSwapSpace: f=0.000000, max_scan=100, max_remove=10
> 2008/11/05 14:17:27| cbdataFree: 0x8471098
> 2008/11/05 14:17:27| cbdataFree: Freeing 0x8471098
> 2008/11/05 14:17:27| storeUfsDirMaintain: /cache2 removed 0/10 f=0.000 max_scan=100
> 2008/11/05 14:17:27| eventAdd: Adding 'MaintainSwapSpace', in 1.000000 seconds
> 2008/11/05 14:17:27| comm_select: timeout 560
> 2008/11/05 14:17:27| comm_select: time out
> 2008/11/05 14:17:27| comm_select: timeout 290
> 2008/11/05 14:17:28| comm_select: time out
> 2008/11/05 14:17:28| eventRun: RUN ID 3033136
> 2008/11/05 14:17:28| eventRun: Running 'ipcache_purgelru', id 3033123
> 2008/11/05 14:17:28| eventAdd: Adding 'ipcache_purgelru', in 10.000000 seconds
> 2008/11/05 14:17:28| ipcache_purgelru: removed 0 entries
> 2008/11/05 14:17:28| comm_select: timeout 441
> 2008/11/05 14:17:28| comm_select: time out
> 2008/11/05 14:17:28| eventRun: RUN ID 3033137
> 2008/11/05 14:17:28| eventRun: Running 'MaintainSwapSpace', id 3033135
> 2008/11/05 14:17:28| storeMaintainSwapSpace: f=0.000000, max_scan=100, max_remove=10
> 2008/11/05 14:17:28| cbdataFree: 0x8471098
> 2008/11/05 14:17:28| cbdataFree: Freeing 0x8471098
> 2008/11/05 14:17:28| storeUfsDirMaintain: /cache1 removed 0/10 f=0.000 max_scan=100
> 2008/11/05 14:17:28| storeMaintainSwapSpace: f=0.000000, max_scan=100, max_remove=10
> 2008/11/05 14:17:28| cbdataFree: 0x8471098
> 2008/11/05 14:17:28| cbdataFree: Freeing 0x8471098
> 2008/11/05 14:17:28| storeUfsDirMaintain: /cache2 removed 0/10 f=0.000 max_scan=100
> 2008/11/05 14:17:28| eventAdd: Adding 'MaintainSwapSpace', in 1.000000 seconds
> 2008/11/05 14:17:28| comm_select: timeout 1000
> 2008/11/05 14:17:28| comm_select: time out
> 2008/11/05 14:17:28| comm_select: timeout 740
> 2008/11/05 14:17:29| comm_select: time out
> 2008/11/05 14:17:29| eventRun: RUN ID 3033138
> 2008/11/05 14:17:29| eventRun: Running 'MaintainSwapSpace', id 3033137
> 2008/11/05 14:17:29| storeMaintainSwapSpace: f=0.000000, max_scan=100, max_remove=10
> 2008/11/05 14:17:29| cbdataFree: 0x8471098
> 2008/11/05 14:17:29| cbdataFree: Freeing 0x8471098
> 2008/11/05 14:17:29| storeUfsDirMaintain: /cache1 removed 0/10 f=0.000 max_scan=100
> 2008/11/05 14:17:29| storeMaintainSwapSpace: f=0.000000, max_scan=100, max_remove=10
> 2008/11/05 14:17:29| cbdataFree: 0x8471098
> 2008/11/05 14:17:29| cbdataFree: Freeing 0x8471098
> 2008/11/05 14:17:29| storeUfsDirMaintain: /cache2 removed 0/10 f=0.000 max_scan=100
> 2008/11/05 14:17:29| eventAdd: Adding 'MaintainSwapSpace', in 1.000000 seconds
>
>
> **********************************************************************
> The information in this e-mail is confidential and may be legally privileged.
> It is intended solely for the addressee. Access to this email by anyone else
> is unauthorised. If you have received it in error, please notify us immediately
> by replying to this e-mail and then delete it from your system.
>
> This note confirms that this email message has been swept for the presence of
> computer viruses, however we advise that in keeping with good IT practice the
> recipient should ensure that the e-mail together with any attachments are virus
> free by running a virus scan themselves. We cannot accept any responsibility for
> any damage or loss caused by software viruses.
>
> The Unity Partnership Ltd, registered in England at West Hall, Parvis Road, West Byfleet, Surrey UK KT14 6EZ.
> Registered No : 5916336. VAT No : 903761336.
> **********************************************************************
Received on Wed Nov 05 2008 - 20:04:12 MST

This archive was generated by hypermail 2.2.0 : Thu Nov 06 2008 - 12:00:03 MST