Article delegate-en/3320 of [1-4224] on the server localhost:7119
  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] CFI with windows : taking time ...
21 Jun 2006 15:33:25 GMT "Xavier Cheney" <p2mgabdyi-mxhgu45cf33w.ml@delegate.org>


Dear Yutaka,

  I use DeleGate 9.2.3-pre1 under Windows like a proxy to filter web pages.
  I made test on localhost, with a small sample (1 CSS and 3 pictures).
  When I add the option FTOCL=filter.cfi, the page takes time to appear in 
the browser, specialy when there's many links (pictures, ...). In the 
filter.cfi I only do text/html replacement.

  My first analyse let me show that when there's filtering option :
  - there's many more delegated processes running
  - the processes between "REQUEST =  [http://domain] GET /img.gif 
HTTP/1.1" and "#HT11 ..." takes long time (300-400ms)
  - there's errors "### ERROR: select() applied to non-SOCKET handle" on 
child processes

  Is it possible to have "shorter time display" :
  - by a delegate patch ?
  - by delegate options ?

  Note that I test CMAP conditional filtering, which can alow to specify 
filtered / not filtered domains. But the aim is to display as quicker as 
possible web filtered pages ...

  Thanks,

    Xavier

PS : The corresponding log ...

06/21 17:30:19.57 [2176] 0+0: ... testing resolver[SYS] 
with 'WWW.DeleGate.ORG'
06/21 17:30:19.57 [2176] 0+0: ... gethostname(C54158)
06/21 17:30:19.57 [2176] 0+0: configuring default RESOLV ...
06/21 17:30:19.57 [2176] 0+0: ... gethostname()='C54158'
06/21 17:30:19.57 [2176] 0+0: ... SYS: C54158 -> 89.0.20.230
06/21 17:30:19.59 [2176] 0+0: ... DNS: 89.0.20.230 -> c54008
06/21 17:30:19.59 [2176] 0+0: ... DNS available
06/21 17:30:19.59 [2176] 0+0: ... NIS not available (no default domain)
06/21 17:30:19.59 [2176] 0+0: ... export RES_ORDER=CFD
06/21 17:30:19.59 [2176] 0+0: export RESOLV=cache,file,dns (set by default)
06/21 17:30:19.59 [2176] 0+0: --INITIALIZATION START: 9.2.3-pre1 on 
WindowsNT--
06/21 17:30:19.59 [2176] 0+0: BINSHELL=/bin/sh
(WIN) 30:19.593 [2176] #### send_file (2176,1)[1920,7] -> 2176[1868,0] 
(0,Err=87)
(WIN) 30:19.593 [2176] #### file to be sent fd=1 -> 0 8800000 142606336

06/21 17:30:19.60 [2176] 0+0: DGAuth: ERROR getCkey(), ekeyFd=-1
06/21 17:30:19.60 [2176] 0+0: #### start a service...
06/21 17:30:21.46 [1876] 0+0: ## RES_ORDER=CFD
06/21 17:30:21.48 [1876] 0+0: ... testing resolver[CFD] 
with 'WWW.DeleGate.ORG'
06/21 17:30:21.50 [1876] 0+0: ... gethostname(C54158)
06/21 17:30:21.51 [1876] 0+0: --INITIALIZATION START: 9.2.3-pre1 on 
WindowsNT--
06/21 17:30:21.51 [1876] 0+0: BINSHELL=/bin/sh
06/21 17:30:21.51 [1876] 0+0: server_open(delegate,:8080,listen=20)
06/21 17:30:21.51 [1876] 0+0: server_open(delegate,:8080) BOUND
06/21 17:30:21.51 [1876] 0+0: DGROOT=C:\delegate^M
06/21 17:30:21.51 [1876] 0+0: <DeleGate/9.2.3-pre1> [1876] -P8080 READY^M
06/21 17:30:21.51 [1876] 0+0: PORT= 8080/11 (31,144)
06/21 17:30:21.51 [1876] 0+0: OWNER=nobody => OWNER=?/?(?/?)
06/21 17:30:21.53 [1876] 0+0: default netmask 89.0.20.230/. = FFFFFF00
06/21 17:30:21.53 [1876] 0+0: REMITTABLE = http,https/
{80,443},gopher,ftp,wais
06/21 17:30:21.53 [1876] 0+0: LIBPATH: test.cfi -> C:\delegate/etc\test.cfi
06/21 17:30:21.53 [1876] 0+0: --- [z] 0 z
06/21 17:30:21.53 [1876] 0+0: ## cannot load z
06/21 17:30:21.54 [1876] 0+0: #### gzip nor gunzip not found in LIBPATH
06/21 17:30:21.54 [1876] 0+0: ADMIN=delegate-admin@xxx..fr protocol=http
(specialist)
06/21 17:30:21.54 [1876] 0+0: WORKDIR=C:\delegate/work/8080
06/21 17:30:21.54 [1876] 0+0: MOUNT[0]X[3] /-/builtin/mssgs/302-moved.dhtml 
http://localhost/cms_fe/index.php?service=proxyerror&errnb=302 
06/21 17:30:21.54 [1876] 0+0: MOUNT[1]X[4] /-/builtin/mssgs/305-
userproxy.dhtml http://localhost/cms_fe/index.php?
service=proxyerror&errnb=305 
06/21 17:30:21.54 [1876] 0+0: MOUNT[2]X[5] /-/builtin/mssgs/401-unauth.dhtml 
http://localhost/cms_fe/index.php?service=proxyerror&errnb=401 
06/21 17:30:21.54 [1876] 0+0: MOUNT[3]X[6] /-/builtin/mssgs/403-
forbidden.dhtml http://localhost/cms_fe/index.php?
service=proxyerror&errnb=403 
06/21 17:30:21.54 [1876] 0+0: MOUNT[4]X[7] /-/builtin/mssgs/404-
notfound.dhtml http://localhost/cms_fe/index.php?
service=proxyerror&errnb=404 
06/21 17:30:21.54 [1876] 0+0: MOUNT[5]X[8] /-/builtin/mssgs/407-
unauthproxy.dhtml http://localhost/cms_fe/index.php?
service=proxyerror&errnb=407 
06/21 17:30:21.54 [1876] 0+0: MOUNT[6]X[9] /-/builtin/mssgs/426-
upgrade.dhtml http://localhost/cms_fe/index.php?service=proxyerror&errnb=426 
06/21 17:30:21.54 [1876] 0+0: MOUNT[7]X[10] /-/builtin/mssgs/502-
cantconnect.dhtml http://localhost/cms_fe/index.php?
service=proxyerror&errnb=502 
06/21 17:30:21.54 [1876] 0+0: MOUNT[8]X[11] /-/builtin/mssgs/502-
offline.dhtml http://localhost/cms_fe/index.php?service=proxyerror&errnb=502 
06/21 17:30:21.54 [1876] 0+0: MOUNT[9]X[12] /-/builtin/icons/* = default
06/21 17:30:21.54 [1876] 0+0: MOUNT[10]X[13] /-/* = 
forbidden,from=!.RELIABLE,default
06/21 17:30:21.54 [1876] 0+0: MOUNT[11]X[0] /-* = default
06/21 17:30:21.54 [1876] 0+0: MOUNT[12]X[1] /=* = default
06/21 17:30:21.54 [1876] 0+0: MOUNT[13]X[2] http://https.* https://* 
FSV=sslway
06/21 17:30:21.54 [1876] 0+0: MOUNT[14]=[14] /favicon.ico 
builtin:icons/ysato/default.ico default,direction=fo,onerror=404,expires=15m
06/21 17:30:21.54 [1876] 0+0: ## MOUNT vURL* [http://https.*] rem=1 nc=2
06/21 17:30:21.54 [1876] 0+0: ## MOUNT FULL-URL-SRC [http]://[https.:80]/[]
06/21 17:30:21.54 [1876] 0+0: #### stack size limit = FFFFFFFF (-1)
06/21 17:30:21.54 [1876] 0+0: Stay open PIDFILE for accept() lock[fd=15]
06/21 17:30:21.54 [1876] 0+0: env[25] 
LIBPATH=.;C:\WINDOWS\system32;C:\delegate/lib;C:\delegate;C:\delegate/etc
06/21 17:30:21.54 [1876] 0+0: ext[0] SERVER=http
06/21 17:30:21.54 [1876] 0+0: ext[1] ADMIN=delegate-admin@xxx..fr
06/21 17:30:21.54 [1876] 0+0: ext[2] PROXY=89.0.15.2:8080:!*localhost*
06/21 17:30:21.54 [1876] 0+0: ext[3] FTOCL=test.cfi
06/21 17:30:21.54 [1876] 0+0: ext[4] MOUNT=http://https.* https://* 
FSV=sslway
06/21 17:30:21.54 [1876] 0+0: ext[5] DELAY=reject:0
06/21 17:30:21.54 [1876] 0+0: ext[6] CACHE=no
06/21 17:30:21.54 [1876] 0+0: ext[7] PERMIT=*:*localhost*:*
06/21 17:30:21.54 [1876] 0+0: ext[8] PERMIT=*:*eprocedures*:*
06/21 17:30:21.54 [1876] 0+0: ext[9] PERMIT=*:*google*:*
06/21 17:30:21.54 [1876] 0+0: ext[10] PERMIT=*:*valdoise*:*
06/21 17:30:21.54 [1876] 0+0: ext[11] MOUNT=/-/builtin/mssgs/302-moved.dhtml 
http://localhost/cms_fe/index.php?service=proxyerror&errnb=302
06/21 17:30:21.54 [1876] 0+0: ext[12] MOUNT=/-/builtin/mssgs/305-
userproxy.dhtml http://localhost/cms_fe/index.php?
service=proxyerror&errnb=305
06/21 17:30:21.54 [1876] 0+0: ext[13] MOUNT=/-/builtin/mssgs/401-
unauth.dhtml http://localhost/cms_fe/index.php?service=proxyerror&errnb=401
06/21 17:30:21.54 [1876] 0+0: ext[14] MOUNT=/-/builtin/mssgs/403-
forbidden.dhtml http://localhost/cms_fe/index.php?
service=proxyerror&errnb=403
06/21 17:30:21.54 [1876] 0+0: ext[15] MOUNT=/-/builtin/mssgs/404-
notfound.dhtml http://localhost/cms_fe/index.php?service=proxyerror&errnb=404
06/21 17:30:21.54 [1876] 0+0: ext[16] MOUNT=/-/builtin/mssgs/407-
unauthproxy.dhtml http://localhost/cms_fe/index.php?
service=proxyerror&errnb=407
06/21 17:30:21.54 [1876] 0+0: ext[17] MOUNT=/-/builtin/mssgs/426-
upgrade.dhtml http://localhost/cms_fe/index.php?service=proxyerror&errnb=426
06/21 17:30:21.54 [1876] 0+0: ext[18] MOUNT=/-/builtin/mssgs/502-
cantconnect.dhtml http://localhost/cms_fe/index.php?
service=proxyerror&errnb=502
06/21 17:30:21.54 [1876] 0+0: ext[19] MOUNT=/-/builtin/mssgs/502-
offline.dhtml http://localhost/cms_fe/index.php?service=proxyerror&errnb=502
06/21 17:30:21.54 [1876] 0+0: arg[1] 
LIBPATH=.;C:\delegate;C:\delegate/lib;.;C:\delegate/etc
06/21 17:30:21.54 [1876] 0+0: arg[2] RESOLV=cache,file,dns
06/21 17:30:21.54 [1876] 0+0: arg[3] DGROOT=C:\delegate
06/21 17:30:21.54 [1876] 0+0: arg[10] ADMIN=delegate-admin@xxx..fr
06/21 17:30:21.56 [1876] 0+0: DELEGATE_Modified[0]: 44994a1d 1150896669
06/21 17:30:21.56 [1876] 0+0: --INITIALIZATION DONE: 9.2.3-pre1 on WindowsNT-
-
(WIN) 30:21.562 [1876] setNonblockingSocket(20,1)=0
(WIN) 30:29.359 [1876] spawn() = 376 [2456], children(alive=1,total=1) 0.063s
06/21 17:30:29.37 [2456] 1+0: Stay open PIDFILE for accept() lock[fd=25]
06/21 17:30:29.39 [2456] 1+1: default netmask 89.0.20.230/. = FFFFFF00
06/21 17:30:29.40 [2456] 1+1: (0) accepted [5] -@[127.0.0.1]localhost:3828 
(0.047s)(1)
06/21 17:30:29.40 [2456] 1+1: IGNORE request: Keep-Alive: 300^M
06/21 17:30:29.40 [2456] 1+1: Proxy: host=localhost; User-Agent: Mozilla/5.0 
(Windows; U; Windows NT 5.1; fr-FR; rv:1.7.10) Gecko/20050717 Firefox/1.0.6; 
DIRECT
06/21 17:30:29.40 [2456] 1+1: HCKA:[0] keep-alive; host=localhost; (User-
Agent: Mozilla/5.0 (Windows; U; Windows NT 5.1; fr-FR; rv:1.7.10) 
Gecko/20050717 Firefox/1.0.6)
06/21 17:30:29.40 [2456] 1+1: REQUEST - GET http://localhost/test.html 
HTTP/1.1^M
06/21 17:30:29.40 [2456] 1+1: PATH> http://localhost:80!localhost:8080!
localhost:3828!anonymous@localhost;1150903829
06/21 17:30:29.40 [2456] 1+1: REQUEST = [http://localhost:80/] 
GET /test.html HTTP/1.1^M
(WIN) 30:29.453 [2456] spawn() = 1828 [3776], children(alive=1,total=1) 
0.047s
06/21 17:30:29.45 [2456] 1+1: ## wait the filter finish reading enviroment
06/21 17:30:29.45 [3776] 0+0: #### clearConnPTR: clearing D_REQUEST
06/21 17:30:29.45 [3776] 0+0: simple_relayf [29 -> 31] = 0
06/21 17:30:29.56 [2456] 1+1: #### pre-filter inserted: 1828
(WIN) 30:29.593 [2456] spawn() = 1844 [3688], children(alive=2,total=2) 
0.031s
06/21 17:30:29.60 [2456] 1+1: ## wait the filter finish reading enviroment
06/21 17:30:29.60 [3688] 0+0: #### clearConnPTR: clearing D_REQUEST
06/21 17:30:29.60 [3688] 0+0: #### execFilter[FTOCL] CFI
06/21 17:30:29.60 [3688] 0+0: ## CFI/http ##
06/21 17:30:29.71 [2456] 1+1: connectTO: assume in non-blocking mode
(WIN) 30:29.718 [2456] setNonblockingSocket(29,1)=0
(WIN) 30:29.718 [2456] setNonblockingSocket(29,0)=0
06/21 17:30:29.71 [2456] 1+1: ConnectToServer connected [29] {127.0.0.1:80 <-
 127.0.0.1:3835} [0.000s]
06/21 17:30:29.71 [2456] 1+1: willSTLS_SV: ServerFlags=10
06/21 17:30:29.71 [2456] 1+1: HTTP => (localhost:80) GET /test.html 
HTTP/1.1^M
06/21 17:30:29.71 [2456] 1+1: #HT11 SERVER ver[HTTP/1.1] conn[]
06/21 17:30:29.71 [2456] 1+1: #HT11 server KEEP-ALIVE
06/21 17:30:29.71 [2456] 1+1: HTTP/1.1 200 Content-{Type:text/html Encoding:
[/] Leng:341} Server:Apache/1.3.33 (Win32) PHP/5.0.3
06/21 17:30:29.71 [2456] 1+1: Content-Length: 341 -> 341 (618 - 277)
06/21 17:30:29.71 [2456] 1+1: HTTP transmitted: 
235head+341/341body=>0txt+0bin->341/341, 9i/2o/0f/0.0
06/21 17:30:29.71 [2456] 1+1: #HT11 putServ(30/32) localhost:80
06/21 17:30:29.71 [2456] 1+1: HCKA:[0] closed -- x:external filter
06/21 17:30:29.71 [3688] 0+0: systemFilter[29,0]: C:\delegate\ssed\ssed -e 
s/fai.fr/titi/g
06/21 17:30:29.71 [2456] 1+1: disconnected [5] -@[127.0.0.1]localhost:3828 
(0.359s)(0)
06/21 17:30:29.81 [3688] 0+0: systemFilter[0,4]: C:\delegate\ssed\ssed -e 
s/teleprocedures/toto/g
06/21 17:30:29.89 [3688] 0+0: Content-Length: 341 -> 341 (671 - 330)
(WIN) 30:29.890 [3776] select() = -1 [errno=(22 / 10038)]
(WIN) 30:29.890 [3776] #### ERROR: select() applied to non-SOCKET handle
(WIN) 30:29.937 [2456] wait3(N) = 1844 [3688] 0, children(alive=1,total=2) 
0.00s
06/21 17:30:29.93 [2456] 1+1: CFI process [1844] done (1/2 AFT-2)
(WIN) 30:29.968 [1876] spawn() = 440 [3556], children(alive=2,total=2) 0.031s
06/21 17:30:30.00 [3556] 2+0: Stay open PIDFILE for accept() lock[fd=24]
06/21 17:30:30.03 [3556] 2+1: default netmask 89.0.20.230/. = FFFFFF00
(WIN) 30:30.062 [1876] spawn() = 424 [4056], children(alive=3,total=3) 0.078s
06/21 17:30:30.07 [3556] 2+1: (1) accepted [5] -@[127.0.0.1]localhost:3836 
(0.094s)(1)
06/21 17:30:30.09 [3776] 0+0: simple_relay [29 -> 31] = (671 -> 671) / 1
06/21 17:30:30.09 [3556] 2+1: IGNORE request: Keep-Alive: 300^M
06/21 17:30:30.09 [3556] 2+1: Proxy: host=localhost; User-Agent: Mozilla/5.0 
(Windows; U; Windows NT 5.1; fr-FR; rv:1.7.10) Gecko/20050717 Firefox/1.0.6; 
DIRECT
06/21 17:30:30.09 [3556] 2+1: HCKA:[0] keep-alive; host=localhost; (User-
Agent: Mozilla/5.0 (Windows; U; Windows NT 5.1; fr-FR; rv:1.7.10) 
Gecko/20050717 Firefox/1.0.6)
06/21 17:30:30.09 [3556] 2+1: REQUEST - GET 
http://localhost/veuillezpatienter.gif HTTP/1.1^M
06/21 17:30:30.09 [3556] 2+1: PATH> http://localhost:80!localhost:8080!
localhost:3836!anonymous@localhost;1150903830
06/21 17:30:30.09 [3556] 2+1: REQUEST = [http://localhost:80/] 
GET /veuillezpatienter.gif HTTP/1.1^M
06/21 17:30:30.10 [4056] 3+0: Stay open PIDFILE for accept() lock[fd=25]
(WIN) 30:30.140 [2456] wait3(N) = 1828 [3776] 0, children(alive=0,total=2) 
0.00s
06/21 17:30:30.14 [2456] 1+1: CFI process [1828] done (2/2 AFT-4)
06/21 17:30:30.14 [4056] 3+1: default netmask 89.0.20.230/. = FFFFFF00
(WIN) 30:30.203 [1876] spawn() = 508 [3676], children(alive=4,total=4) 0.141s
(WIN) 30:30.203 [1876] spawn() = 508 [4]0.125[8]0.016
(WIN) 30:30.250 [3556] spawn() = 1828 [3244], children(alive=1,total=1) 
0.141s
(WIN) 30:30.250 [3556] spawn() = 1828 [4]0.125[8]0.016
06/21 17:30:30.25 [3676] 4+0: Stay open PIDFILE for accept() lock[fd=24]
06/21 17:30:30.28 [3676] 4+1: default netmask 89.0.20.230/. = FFFFFF00
06/21 17:30:30.28 [3244] 0+0: #### clearConnPTR: clearing D_REQUEST
06/21 17:30:30.28 [3244] 0+0: simple_relayf [28 -> 30] = 0
06/21 17:30:30.28 [3556] 2+1: #### pre-filter inserted: 1828
06/21 17:30:30.29 [4056] 3+1: (2) accepted [5] -@[127.0.0.1]localhost:3837 
(0.218s)(2)
06/21 17:30:30.31 [4056] 3+1: IGNORE request: Keep-Alive: 300^M
06/21 17:30:30.31 [4056] 3+1: Proxy: host=localhost; User-Agent: Mozilla/5.0 
(Windows; U; Windows NT 5.1; fr-FR; rv:1.7.10) Gecko/20050717 Firefox/1.0.6; 
DIRECT
06/21 17:30:30.31 [4056] 3+1: HCKA:[0] keep-alive; host=localhost; (User-
Agent: Mozilla/5.0 (Windows; U; Windows NT 5.1; fr-FR; rv:1.7.10) 
Gecko/20050717 Firefox/1.0.6)
06/21 17:30:30.31 [4056] 3+1: REQUEST - GET 
http://localhost/Sablier_anime.gif HTTP/1.1^M
06/21 17:30:30.31 [4056] 3+1: PATH> http://localhost:80!localhost:8080!
localhost:3837!anonymous@localhost;1150903830
06/21 17:30:30.31 [4056] 3+1: REQUEST = [http://localhost:80/] 
GET /Sablier_anime.gif HTTP/1.1^M
(WIN) 30:30.390 [3556] spawn() = 1844 [3324], children(alive=2,total=2) 
0.109s
(WIN) 30:30.390 [3556] spawn() = 1844 [2]0.015[4]0.094
06/21 17:30:30.39 [3676] 4+1: (3) accepted [5] -@[127.0.0.1]localhost:3838 
(0.156s)(3)
(WIN) 30:30.406 [4056] spawn() = 1828 [3148], children(alive=1,total=1) 
0.094s
06/21 17:30:30.40 [3676] 4+1: IGNORE request: Keep-Alive: 300^M
06/21 17:30:30.40 [3676] 4+1: Proxy: host=localhost; User-Agent: Mozilla/5.0 
(Windows; U; Windows NT 5.1; fr-FR; rv:1.7.10) Gecko/20050717 Firefox/1.0.6; 
DIRECT
06/21 17:30:30.40 [3676] 4+1: HCKA:[0] keep-alive; host=localhost; (User-
Agent: Mozilla/5.0 (Windows; U; Windows NT 5.1; fr-FR; rv:1.7.10) 
Gecko/20050717 Firefox/1.0.6)
06/21 17:30:30.40 [3676] 4+1: REQUEST - GET http://localhost/fond.jpg 
HTTP/1.1^M
06/21 17:30:30.40 [3676] 4+1: PATH> http://localhost:80!localhost:8080!
localhost:3838!anonymous@localhost;1150903830
06/21 17:30:30.40 [3676] 4+1: REQUEST = [http://localhost:80/] GET /fond.jpg 
HTTP/1.1^M
06/21 17:30:30.42 [3556] 2+1: ## wait the filter finish reading enviroment
06/21 17:30:30.42 [3324] 0+0: #### clearConnPTR: clearing D_REQUEST
06/21 17:30:30.42 [3324] 0+0: #### execFilter[FTOCL] CFI
06/21 17:30:30.42 [3324] 0+0: ## CFI/http ##
06/21 17:30:30.43 [4056] 3+1: ## wait the filter finish reading enviroment
06/21 17:30:30.43 [3148] 0+0: #### clearConnPTR: clearing D_REQUEST
06/21 17:30:30.43 [3148] 0+0: simple_relayf [29 -> 31] = 0
(WIN) 30:30.468 [3676] spawn() = 1828 [2916], children(alive=1,total=1) 
0.062s
06/21 17:30:30.46 [3676] 4+1: ## wait the filter finish reading enviroment
06/21 17:30:30.46 [2916] 0+0: #### clearConnPTR: clearing D_REQUEST
06/21 17:30:30.46 [2916] 0+0: simple_relayf [28 -> 30] = 0
06/21 17:30:30.53 [3556] 2+1: connectTO: assume in non-blocking mode
(WIN) 30:30.531 [3556] setNonblockingSocket(28,1)=0
(WIN) 30:30.531 [3556] setNonblockingSocket(28,0)=0
06/21 17:30:30.53 [3556] 2+1: ConnectToServer connected [28] {127.0.0.1:80 <-
 127.0.0.1:3853} [0.000s]
06/21 17:30:30.53 [3556] 2+1: willSTLS_SV: ServerFlags=10
06/21 17:30:30.53 [3556] 2+1: HTTP => (localhost:80) 
GET /veuillezpatienter.gif HTTP/1.1^M
06/21 17:30:30.53 [3556] 2+1: #HT11 SERVER ver[HTTP/1.1] conn[]
06/21 17:30:30.53 [3556] 2+1: #HT11 server KEEP-ALIVE
06/21 17:30:30.53 [3556] 2+1: HTTP/1.1 200 Content-{Type:image/gif Encoding:
[/] Leng:1774} Server:Apache/1.3.33 (Win32) PHP/5.0.3
06/21 17:30:30.53 [3556] 2+1: detach respBuff: non-text data, non keep-alive
06/21 17:30:30.53 [3556] 2+1: HTTP transmitted: 
236head+1774/1774body=>0txt+0bin->1774/1774, 9i/2o/0f/0.0
06/21 17:30:30.53 [3324] 0+0: simple_relayf [28 -> 29] = 0
(WIN) 30:30.531 [3324] select() = -1 [errno=(9 / 10038)]
(WIN) 30:30.531 [3324] #### ERROR: select() applied to non-SOCKET handle
06/21 17:30:30.54 [3556] 2+1: #HT11 putServ(29/31) localhost:80
06/21 17:30:30.54 [3556] 2+1: HCKA:[0] closed -- x:external filter
06/21 17:30:30.54 [4056] 3+1: #### pre-filter inserted: 1828
06/21 17:30:30.54 [3556] 2+1: disconnected [5] -@[127.0.0.1]localhost:3836 
(0.562s)(2)
06/21 17:30:30.57 [3676] 4+1: #### pre-filter inserted: 1828
(WIN) 30:30.593 [4056] spawn() = 1844 [3708], children(alive=2,total=2) 
0.047s
06/21 17:30:30.64 [3708] 0+0: #### clearConnPTR: clearing D_REQUEST
06/21 17:30:30.64 [3708] 0+0: #### execFilter[FTOCL] CFI
06/21 17:30:30.64 [3708] 0+0: ## CFI/http ##
06/21 17:30:30.64 [4056] 3+1: connectTO: assume in non-blocking mode
(WIN) 30:30.640 [4056] setNonblockingSocket(29,1)=0
(WIN) 30:30.640 [4056] setNonblockingSocket(29,0)=0
06/21 17:30:30.64 [4056] 3+1: ConnectToServer connected [29] {127.0.0.1:80 <-
 127.0.0.1:3857} [0.000s]
06/21 17:30:30.64 [4056] 3+1: willSTLS_SV: ServerFlags=10
06/21 17:30:30.64 [4056] 3+1: HTTP => (localhost:80) GET /Sablier_anime.gif 
HTTP/1.1^M
06/21 17:30:30.64 [4056] 3+1: #HT11 SERVER ver[HTTP/1.1] conn[]
06/21 17:30:30.64 [4056] 3+1: #HT11 server KEEP-ALIVE
06/21 17:30:30.64 [4056] 3+1: HTTP/1.1 200 Content-{Type:image/gif Encoding:
[/] Leng:9924} Server:Apache/1.3.33 (Win32) PHP/5.0.3
06/21 17:30:30.64 [4056] 3+1: detach respBuff: non-text data, non keep-alive
06/21 17:30:30.65 [3708] 0+0: simple_relayf [29 -> 30] = 0
06/21 17:30:30.65 [4056] 3+1: HTTP transmitted: 
237head+9924/9924body=>0txt+0bin->9924/9924, 10i/3o/0f/0.0
(WIN) 30:30.656 [3676] spawn() = 1844 [2928], children(alive=2,total=2) 
0.078s
(WIN) 30:30.656 [3708] select() = -1 [errno=(9 / 10038)]
(WIN) 30:30.656 [3708] #### ERROR: select() applied to non-SOCKET handle
06/21 17:30:30.65 [4056] 3+1: #HT11 putServ(30/32) localhost:80
06/21 17:30:30.65 [4056] 3+1: HCKA:[0] closed -- x:external filter
06/21 17:30:30.65 [4056] 3+1: disconnected [5] -@[127.0.0.1]localhost:3837 
(0.578s)(1)
06/21 17:30:30.67 [2928] 0+0: #### clearConnPTR: clearing D_REQUEST
06/21 17:30:30.67 [2928] 0+0: #### execFilter[FTOCL] CFI
06/21 17:30:30.67 [2928] 0+0: ## CFI/http ##
06/21 17:30:30.67 [3676] 4+1: connectTO: assume in non-blocking mode
(WIN) 30:30.671 [3676] setNonblockingSocket(28,1)=0
(WIN) 30:30.671 [3676] setNonblockingSocket(28,0)=0
06/21 17:30:30.67 [3676] 4+1: ConnectToServer connected [28] {127.0.0.1:80 <-
 127.0.0.1:3859} [0.000s]
06/21 17:30:30.67 [3676] 4+1: willSTLS_SV: ServerFlags=10
06/21 17:30:30.67 [3676] 4+1: HTTP => (localhost:80) GET /fond.jpg HTTP/1.1^M
06/21 17:30:30.68 [3676] 4+1: #HT11 SERVER ver[HTTP/1.1] conn[]
06/21 17:30:30.68 [3676] 4+1: #HT11 server KEEP-ALIVE
06/21 17:30:30.68 [3676] 4+1: HTTP/1.1 200 Content-{Type:image/jpeg Encoding:
[/] Leng:55825} Server:Apache/1.3.33 (Win32) PHP/5.0.3
06/21 17:30:30.68 [3676] 4+1: detach respBuff: non-text data, non keep-alive
06/21 17:30:30.68 [2928] 0+0: simple_relayf [28 -> 29] = 0
06/21 17:30:30.71 [3676] 4+1: HTTP transmitted: 
239head+00000/0000fbody=>0txt+0bin->55825/55825, 13i/6o/0f/0.0
06/21 17:30:30.73 [3676] 4+1: #HT11 putServ(29/31) localhost:80
06/21 17:30:30.73 [3676] 4+1: HCKA:[0] closed -- x:external filter
(WIN) 30:30.750 [2928] select() = -1 [errno=(9 / 10038)]
(WIN) 30:30.750 [2928] #### ERROR: select() applied to non-SOCKET handle
06/21 17:30:30.78 [1876] 4+0: AcceptByMain: locked out*1/0 by Sticky*4 0/0
06/21 17:30:30.79 [3676] 4+1: disconnected [5] -@[127.0.0.1]localhost:3838 
(0.562s)(0)
06/21 17:30:30.79 [3324] 0+0: simple_relay [28 -> 29] = (1774 -> 1774) / 1
(WIN) 30:30.796 [3556] wait3(N) = 1844 [3324] 0, children(alive=1,total=2) 
0.00s
06/21 17:30:30.79 [3556] 2+1: CFI process [1844] done (1/2 AFT-2)
(WIN) 30:30.796 [3244] select() = -1 [errno=(22 / 10038)]
(WIN) 30:30.796 [3244] #### ERROR: select() applied to non-SOCKET handle
06/21 17:30:30.79 [2456] 1+2: (0) accepted [41] -@[127.0.0.1]localhost:3860 
(0.000s)(1)
06/21 17:30:30.81 [2456] 1+2: IGNORE request: Keep-Alive: 300^M
06/21 17:30:30.81 [2456] 1+2: Proxy: host=localhost; User-Agent: Mozilla/5.0 
(Windows; U; Windows NT 5.1; fr-FR; rv:1.7.10) Gecko/20050717 Firefox/1.0.6; 
DIRECT
06/21 17:30:30.81 [2456] 1+2: HCKA:[0] keep-alive; host=localhost; (User-
Agent: Mozilla/5.0 (Windows; U; Windows NT 5.1; fr-FR; rv:1.7.10) 
Gecko/20050717 Firefox/1.0.6)
06/21 17:30:30.81 [2456] 1+2: REQUEST - GET http://localhost/favicon.ico 
HTTP/1.1^M
06/21 17:30:30.81 [2456] 1+2: PATH> http://localhost:80!localhost:8080!
localhost:3860!anonymous@localhost;1150903830
06/21 17:30:30.81 [2456] 1+2: REQUEST = [http://localhost:80/] 
GET /favicon.ico HTTP/1.1^M
(WIN) 30:30.843 [2456] spawn() = 336 [2528], children(alive=1,total=3) 0.031s
06/21 17:30:30.85 [3708] 0+0: simple_relay [29 -> 30] = (9924 -> 9924) / 2
(WIN) 30:30.859 [3148] select() = -1 [errno=(22 / 10038)]
(WIN) 30:30.859 [3148] #### ERROR: select() applied to non-SOCKET handle
06/21 17:30:30.85 [2528] 0+0: #### clearConnPTR: clearing D_REQUEST
06/21 17:30:30.85 [2528] 0+0: simple_relayf [25 -> 31] = 0
06/21 17:30:30.85 [2456] 1+2: #### pre-filter inserted: 336
(WIN) 30:30.890 [4056] wait3(N) = 1844 [3708] 0, children(alive=1,total=2) 
0.00s
06/21 17:30:30.89 [4056] 3+1: CFI process [1844] done (1/2 AFT-2)
(WIN) 30:30.906 [2456] spawn() = 1976 [1980], children(alive=2,total=4) 
0.047s
06/21 17:30:30.90 [1980] 0+0: #### clearConnPTR: clearing D_REQUEST
06/21 17:30:30.90 [1980] 0+0: #### execFilter[FTOCL] CFI
06/21 17:30:30.90 [1980] 0+0: ## CFI/http ##
06/21 17:30:30.90 [2456] 1+2/0/1: #HT11 getServ  1.19s*1 SERVER REUSE 
(30/32) [localhost:80] localhost
06/21 17:30:30.92 [2456] 1+2/0/1: #HT11 SERVER ver[HTTP/1.1] conn[]
06/21 17:30:30.92 [2456] 1+2/0/1: #HT11 server KEEP-ALIVE
06/21 17:30:30.92 [2456] 1+2/0/1: HTTP/1.1 200 Content-{Type:image/x-icon 
Encoding:[/] Leng:797} Server:Apache/1.3.33 (Win32) PHP/5.0.3
06/21 17:30:30.92 [2456] 1+2/0/1: detach respBuff: non-text data, non keep-
alive
06/21 17:30:30.92 [2456] 1+2/0/1: HTTP transmitted: 
238head+797/797body=>0txt+0bin->797/797, 9i/2o/0f/0.0
06/21 17:30:30.92 [1980] 0+0: simple_relayf [25 -> 29] = 0
(WIN) 30:30.921 [1980] select() = -1 [errno=(9 / 10038)]
(WIN) 30:30.921 [1980] #### ERROR: select() applied to non-SOCKET handle
06/21 17:30:30.92 [2456] 1+2/0/1: #HT11 putServ(25/29) localhost:80
06/21 17:30:30.92 [2456] 1+2/0/1: HCKA:[0] closed -- x:external filter
06/21 17:30:30.92 [2456] 1+2/0/1: disconnected [41] -@[127.0.0.1]
localhost:3860 (0.125s)(0)
06/21 17:30:30.93 [2928] 0+0: simple_relay [28 -> 29] = (55825 -> 55825) / 5
(WIN) 30:30.953 [2916] select() = -1 [errno=(22 / 10038)]
(WIN) 30:30.953 [2916] #### ERROR: select() applied to non-SOCKET handle
06/21 17:30:31.00 [3244] 0+0: simple_relay [28 -> 30] = (2105 -> 2105) / 1
(WIN) 30:31.000 [3676] wait3(N) = 1844 [2928] 0, children(alive=1,total=2) 
0.00s
06/21 17:30:31.00 [3676] 4+1: CFI process [1844] done (1/2 AFT-2)
(WIN) 30:31.000 [3556] wait3(N) = 1828 [3244] 0, children(alive=0,total=2) 
0.00s
06/21 17:30:31.00 [3556] 2+1: CFI process [1828] done (2/2 AFT-4)
06/21 17:30:31.04 [3148] 0+0: simple_relay [29 -> 31] = (10256 -> 10256) / 2
(WIN) 30:31.109 [4056] wait3(N) = 1828 [3148] 0, children(alive=0,total=2) 
0.00s
06/21 17:30:31.10 [4056] 3+1: CFI process [1828] done (2/2 AFT-4)
06/21 17:30:31.12 [1980] 0+0: simple_relay [25 -> 29] = (797 -> 797) / 1
(WIN) 30:31.125 [2528] select() = -1 [errno=(22 / 10038)]
(WIN) 30:31.125 [2528] #### ERROR: select() applied to non-SOCKET handle
(WIN) 30:31.125 [2456] wait3(N) = 1976 [1980] 0, children(alive=1,total=4) 
0.00s
06/21 17:30:31.12 [2456] 1+2/0/1: CFI process [1976] done (1/2 AFT-2)
06/21 17:30:31.15 [2916] 0+0: simple_relay [28 -> 30] = (56159 -> 56159) / 6
(WIN) 30:31.203 [3676] wait3(N) = 1828 [2916] 0, children(alive=0,total=2) 
0.00s
06/21 17:30:31.20 [3676] 4+1: CFI process [1828] done (2/2 AFT-4)
06/21 17:30:31.32 [2528] 0+0: simple_relay [25 -> 31] = (1130 -> 1130) / 1
(WIN) 30:31.343 [2456] wait3(N) = 336 [2528] 0, children(alive=0,total=4) 
0.00s
06/21 17:30:31.34 [2456] 1+2/0/1: CFI process [336] done (2/2 AFT-4)
06/21 17:30:36.59 [1876] 4+0: TERMINATE...
06/21 17:30:36.59 [1876] 4+0: DGAuth: ERROR getCkey(), ekeyFd=-1
06/21 17:30:36.59 [1876] 4+0: Kill(376,15)
06/21 17:30:36.59 [1876] 4+0: Kill(440,15)
06/21 17:30:36.59 [1876] 4+0: Kill(424,15)
06/21 17:30:36.59 [1876] 4+0: Kill(508,15)
06/21 17:30:36.59 [1876] 4+0: StickyKill(15): 4/4 killed
06/21 17:30:36.60 [1876] 4+0: unlinked C:\delegate/work/8080/1876
06/21 17:30:36.60 [1876] 4+0: remove failed, errno=13, C:\delegate/work/8080
(WIN) 30:36.609 [1876] killchildren#1 Terminate 376 [2456] = 1
(WIN) 30:36.609 [1876] killchildren#2 Terminate 424 [4056] = 1
(WIN) 30:36.609 [1876] killchildren#3 Terminate 440 [3556] = 1
(WIN) 30:36.609 [1876] killchildren#4 Terminate 508 [3676] = 1
06/21 17:30:36.60 [1876] 4+0: TERMINATED.
06/21 17:30:36.60 [1876] 4+0: SetStatus: STOP


--
Open WebMail Project (http://openwebmail.org)


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