Article delegate-en/3966 of [1-5169] on the server localhost:119
  upper oldest olders older1 this newer1 newers latest
search
[Top/Up] [oldest] - [Older+chunk] - [Newer+chunk] - [newest + Check]

Newsgroups: mail-lists.delegate-en

[DeleGate-En] takeover issue?
08 May 2008 12:49:42 GMT Dmitry Donskih <ppyhqbdyi-5vjbuj474ilr.ml@ml.delegate.org>


Hi,

I've come into one unwanted situation, and trying to find out how this 
could happen.

I use DeleGate 9.2.1 as HTTP proxy on Fedora box. Most its settings 
(timeouts etc) are default.
A client requests a big file (http GET, >700 Mbytes). DeleGate starts 
retrieving it from server. Then this client (by HIS words) disconnects.
Somehow proxy does not terminate, and downloads entire file.

(We pay for internet traffic, and this file is being disputed)

Please help me to find out if this client really has disconnected at the 
very beginning of download, or not.
If so, then why did DeleGate continue downloading that file. Is this a 
timeout/takeover issue, or what?

See attached file, part of DeleGate log.

Thanks in advance,

Dmitry Donskih,
donskih@isbank..ru

05/07 04:03:16.99 [5201] 0+0: ## RES_ORDER=FDS
05/07 04:03:16.99 [5201] 0+0: --INITIALIZATION START: 9.2.1 on Linux/2.6.18-1.2200.fc5--
05/07 04:03:16.99 [5201] 0+0: BINSHELL=/bin/sh
05/07 04:03:16.99 [5201] 0+0: DGROOT=/var/spool/delegate-nobody^M
05/07 04:03:16.99 [5201] 0+0: <DeleGate/9.2.1> [5201] -P8080 READY^M
05/07 04:03:16.99 [5201] 0+0: PORT= 8080/10 (31,144)
05/07 04:03:17.00 [5201] 0+0: OWNER=nobody => OWNER=nobody/nobody(nobody/nobody)
05/07 04:03:17.14 [5201] 0+0: default netmask 127.0.0.1/. = FFFFFF00
05/07 04:03:17.15 [5201] 0+0: REMITTABLE = http,https/{80,443},gopher,ftp,wais,http,ftp,https,ssltunnel
05/07 04:03:24.35 [5201] 0+0: #### gzip/gunzip = dynamically linked
05/07 04:03:24.35 [5201] 0+0: ADMIN=noc@isbank..ru protocol=delegate
05/07 04:03:24.35 [5201] 0+0: WORKDIR=/var/spool/delegate-nobody/work/8080
05/07 04:03:24.35 [5201] 0+0: MOUNT[0]X[2] /-/builtin/mssgs/403-forbidden.dhtml file:///proxy/403.dhtml
05/07 04:03:24.35 [5201] 0+0: MOUNT[1]X[0] /-* = default
05/07 04:03:24.35 [5201] 0+0: MOUNT[2]X[1] /=* = default
05/07 04:03:26.62 [5201] 0+0: Stay open PIDFILE for accept() lock[fd=26]
05/07 04:03:26.62 [5201] 0+0: env[26] LIBPATH=.;/proxy;/var/spool/delegate-nobody/lib;/proxy;/var/spool/delegate-nobody/etc
05/07 04:03:26.62 [5201] 0+0: ext[0] RESOLV=file,dns,sys
05/07 04:03:26.62 [5201] 0+0: ext[1] ADMIN=noc@isbank..ru
05/07 04:03:26.62 [5201] 0+0: ext[2] MOUNT=/-/builtin/mssgs/403-forbidden.dhtml /proxy/403.dhtml
05/07 04:03:26.62 [5201] 0+0: ext[3] REMITTABLE=+,http,ftp,https,ssltunnel
....
05/07 04:03:26.62 [5201] 0+0: ext[109] PERMIT=*:*:192.168.0.29
....
05/07 04:03:26.62 [5201] 0+0: ext[184] FTOCL=/proxy/relay/cfi/8080.cfi
05/07 04:03:26.62 [5201] 0+0: ext[185] TIMEOUT=io:6000,con:30,dns:30,dnsinv:30
05/07 04:03:26.62 [5201] 0+0: arg[1] DGROOT=/var/spool/delegate-nobody
05/07 04:03:26.65 [5201] 0+0: DELEGATE_Modified[0]: 481d5992 000000000X
05/07 04:03:26.65 [5201] 0+0: NUM_HUPS=4
05/07 04:03:26.65 [5201] 0+0: --INITIALIZATION DONE: 9.2.1 on Linux/2.6.18-1.2200.fc5--



05/07 17:46:26.25 [9974] 2345+8: (10) accepted [57] -@[192.168.0.89]mars-sek.isbank.ru:3114 (0.001s)(1)
05/07 17:46:26.25 [9974] 2345+8: Proxy: host=mars-sek.isbank.ru; User-Agent: Mozilla/4.0 (compatible; MSIE 6.0; Windows NT 5.1; SV1; .NET CLR 1.1.4322; .NET CLR 2.0.50727; .NET CLR 3.0.04506.30; InfoPath.1); DIRECT
05/07 17:46:26.25 [9974] 2345+8: HCKA:[0] Keep-Alive; host=mars-sek.isbank.ru; (User-Agent: Mozilla/4.0 (compatible; MSIE 6.0; Windows NT 5.1; SV1; .NET CLR 1.1.4322; .NET CLR 2.0.50727; .NET CLR 3.0.04506.30; InfoPath.1))
05/07 17:46:26.25 [9974] 2345+8: REQUEST = GET http://krugmebeli.ru/index.php?catalog=1032 HTTP/1.0^M
05/07 17:46:26.29 [9974] 2345+8: PATH> http://krugmebeli.ru:80!uno.isbank.ru:8080!mars-sek.isbank.ru:3114!anonymous@mars-sek.isbank.ru;1210167986
05/07 17:46:26.29 [9974] 2345+8: REQUEST = [http://krugmebeli.ru:80/] GET /index.php?catalog=1032 HTTP/1.0^M
05/07 17:46:26.30 [9974] 2345+8: [0.00,-1][HTTP cache-NONE] /var/spool/delegate-nobody/cache/http/krugmebeli.ru/index.php?catalog=1032
05/07 17:46:26.30 [9974] 2345+8: #HT11 getServ  0.1s*0 SERVER SWITCH[www.rambler.ru:80]->[krugmebeli.ru:80]
05/07 17:46:26.41 [9974] 2345+8: ConnectToServer connected [34] {194.54.90.170:80 <- 192.168.6.200:46719} [0.110s]
05/07 17:46:26.41 [9974] 2345+8: willSTLS_SV: ServerFlags=10
05/07 17:46:26.41 [9974] 2345+8: HTTP => (krugmebeli.ru:80) GET /index.php?catalog=1032 HTTP/1.0^M
05/07 17:46:26.41 [9974] 2345+8: #HT11 FORCE HTTP/1.1 or Connection:keep-alive
05/07 17:46:26.66 [9974] 2345+8: HCKA:[R] Keep-Alive: timeout=15, max=300^M
05/07 17:46:26.66 [9974] 2345+8: #HT11 --getChunk-Header: Transfer-Encoding: chunked^M
05/07 17:46:26.66 [9974] 2345+8: #HT11 SERVER ver[HTTP/1.1] conn[Keep-Alive]
05/07 17:46:26.66 [9974] 2345+8: #HT11 server KEEP-ALIVE
05/07 17:46:26.66 [9974] 2345+8: HTTP/1.1 200 Content-{Type:text/html Encoding:[/chunked] Leng:0} Server:Apache/1.3.37 (Unix) mod_ssl/2.8.28 OpenSSL/0.9.7e-p1 FrontPage/5.0.2.2510
05/07 17:46:32.13 [9974] 2345+8: detach respBuff: huge data
05/07 17:46:32.27 [9974] 2345+8: discard cache: leng=1049280/0 /var/spool/delegate-nobody/cache/http/krugmebeli.ru/index.php?catalog=1032
05/07 17:46:56.00 [9974] 2345+8: ClosedOnTimeout(1): time=1210168016/1210168016 ppid=5201/5201 pid=9974/9974
05/07 18:49:42.23 [9974] 2345+8: ## premature client close: flush_body (cant_getpeername)
05/07 18:49:42.23 [9974] 2345+8: ClientEOF: flush_body
05/07 18:49:42.23 [9974] 2345+8: HTTP transmitted: 275head+000000000/fbody=>1049280txt+0bin->734250566/0, 807026i/47843o/0f/3795.6
05/07 18:49:42.23 [9974] 2345+8: rcode=-10005 unlink /var/spool/delegate-nobody/cache/http/krugmebeli.ru/index.php?catalog=1032 (0)
05/07 18:49:42.23 [9974] 2345+8: #HT11 EOF from the server
05/07 18:49:42.23 [9974] 2345+8: #HT11 close svsokcs[35,38]
05/07 18:49:42.36 [9974] 2345+8: unlink empty cache: /var/spool/delegate-nobody/cache/http/krugmebeli.ru/index.php?catalog=1032
05/07 18:49:42.39 [9974] 2345+8: HCKA:[0] closed -- p:premature client EOF (flush_body)
05/07 18:49:43.65 [9974] 2345+8: disconnected [57] -@[192.168.0.89]mars-sek.isbank.ru:3114 (3797.400s)(0)
05/07 18:49:43.65 [9974] 2345+8: CFI process [10012] done (1/1 AFT-0)
05/07 18:49:43.65 [9974] 2345+8: StickyServer done [serverSocketClosed] 8 req / 8 conn / 3801 sec


  admin search upper oldest olders older1 this newer1 newers latest
[Top/Up] [oldest] - [Older+chunk] - [Newer+chunk] - [newest + Check]
@_@V