Re[2]: Cluster & HTTPU

Hello Dmitry,

Friday, April 18, 2008, 6:52:07 PM, you wrote:

>> После апгрейда с 5.1.14 до 5.2.2 возникла проблема - когда
>> пользователи логинятся через веб-интерфейс на Frontend, они
>> перебрасываются на frameset.wssp и тут же получают ответ "You have
>> been disconnected from the CommuniGate Pro server.".

> Помогает ли отключение проверки IP адреса (это можно сделать прячмо на > страничке логина)?

Не помогает.

>> Если этот же пользователь логинится прямо на Backend - все нормально.
>> POP/IMAP через Frontend работают нормально.
>>
>> Подскажите где копать или где поднять уровень лога чтобы увидеть в
>> чем проблема?

> На бэкендах можно поднять уровень логов для session и потом посмотреть,
> по какой причине закрываются сессии. На ненагруженном кластере -
> временно поднять уровень логов HTTPU и посмотреть, как отрабатываются
> запросы.

Со стороны бэкэнда сессия не прерывается. В Monitors -> Access -> Sessions она продолжают висеть пока не отваливаются по таймауту. Похоже что она обрывается по инициативе frontend без объяснения причин.

=== Логи со стороны frontend: ===

15:45:35.114 5 HTTPU [0.0.0.0]:8080 <- [user.ip]:4909 connection request. socket=16
15:45:35.114 4 HTTPU-000106([user.ip]) got connection on [frontend.ip]:8080(frontend.domain) from [user.ip]:4909
15:45:35.118 5 HTTPU-000106([user.ip]) inp: POST / HTTP/1.1
15:45:35.118 5 HTTPU-000106([user.ip]) inp: Accept: image/gif, image/x-xbitmap, image/jpeg, image/pjpeg, application/x-shockwave-flash, application/vnd.ms-excel, application/vnd.ms-powerpoint, application/msword, application/xaml+xml, application/vnd.ms-xpsdocument, application/x-ms-xbap, appli
15:45:35.118 5 HTTPU-000106([user.ip]) inp: Referer: http://frontend.domain:8080/
15:45:35.118 5 HTTPU-000106([user.ip]) inp: Accept-Language: en-US
15:45:35.118 5 HTTPU-000106([user.ip]) inp: Content-Type: multipart/form-data; boundary=---------------------------7d82c4221704aa
15:45:35.118 5 HTTPU-000106([user.ip]) inp: UA-CPU: x86
15:45:35.118 5 HTTPU-000106([user.ip]) inp: Accept-Encoding: gzip, deflate
15:45:35.118 5 HTTPU-000106([user.ip]) inp: User-Agent: Mozilla/4.0 (compatible; MSIE 7.0; Windows NT 5.1; WebMoney Advisor; .NET CLR 1.1.4322; .NET CLR 2.0.50727; InfoPath.2; .NET CLR 3.0.04506.30; MEGAUPLOAD 1.0)
15:45:35.118 5 HTTPU-000106([user.ip]) inp: Host: frontend.domain:8080
15:45:35.118 5 HTTPU-000106([user.ip]) inp: Content-Length: 993
15:45:35.118 5 HTTPU-000106([user.ip]) inp: Connection: Keep-Alive
15:45:35.118 5 HTTPU-000106([user.ip]) inp: Cache-Control: no-cache
15:45:35.118 5 HTTPU-000106([user.ip]) inp: 
15:45:35.119 4 HTTPU-000106([user.ip]) req(993): POST /
15:45:35.234 4 HTTPC-000106 connecting to [backend.local.ip]:8100
15:45:35.235 4 HTTPC-000106 sending request(328): POST /ProxyLogin/user_at_domain.com/kmbcnmw.html HTTP/1.1\r\nReferer: http://frontend.domain:8080/\r\nHost: frontend.domain:8080\r\nX-ORIGINAL-ADDRESS: [user.ip]:4909\r\nUser-Agent: Mozilla/4.0 (compatible; MSIE 7.0; Windows NT 5.1; WebMoney Advisor; .NET 
15:45:35.235 5 HTTPC-000106 out: POST /ProxyLogin/user_at_domain.com/kmbcnmw.html HTTP/1.1\r\nReferer: http://frontend.domain:8080/\r\nHost: frontend.domain:8080\r\nX-ORIGINAL-ADDRESS: [user.ip]:4909\r\nUser-Agent: Mozilla/4.0 (compatible; MSIE 7.0; Windows NT 5.1; WebMoney Advisor; .NET CLR 1.1.4322; .NE
15:45:35.235 4 HTTPC-000106 sending body(1024): Accept: image/gif, image/x-xbitmap, image/jpeg, image/pjpeg, application/x-shockwave-flash, application/vnd.ms-excel, application/vnd.ms-powerpoint, application/msword, application/xaml+xml, application/vnd.ms-xpsdocument, application/x-ms-xbap, applic
15:45:35.235 5 HTTPC-000106 out: Accept: image/gif, image/x-xbitmap, image/jpeg, image/pjpeg, application/x-shockwave-flash, application/vnd.ms-excel, application/vnd.ms-powerpoint, application/msword, application/xaml+xml, application/vnd.ms-xpsdocument, application/x-ms-xbap, application/x-ms-appl
15:45:35.235 4 HTTPC-000106 sending body(481): ="DisableIPWatch"\r\n\r\non\r\n-----------------------------7d82c4221704aa\r\nContent-Disposition: form-data; name="DisableUseCookie"\r\n\r\non\r\n-----------------------------7d82c4221704aa\r\nContent-Disposition: form-data; name="login"\r\n\r\nEnter\r
15:45:35.235 5 HTTPC-000106 out: ="DisableIPWatch"\r\n\r\non\r\n-----------------------------7d82c4221704aa\r\nContent-Disposition: form-data; name="DisableUseCookie"\r\n\r\non\r\n-----------------------------7d82c4221704aa\r\nContent-Disposition: form-data; name="login"\r\n\r\nEnter\r\n------------
15:45:35.235 4 HTTPC-000106 proxy started
15:45:35.301 5 HTTPU-000106([user.ip]) out: HTTP/1.1 301 Moved\r\nContent-Length: 455\r\nConnection: close\r\nDate: Sat, 19 Apr 2008 12:45:35 GMT\r\nContent-Type: text/html;charset=iso-8859-1\r\nServer: CommuniGatePro/5.2.2\r\nLocation: http://frontend.domain:8080/Session/14-xlOrH3EL0hFvmaMG2Ual/fra
15:45:35.302 4 HTTPU-000106([user.ip]) server has closed the connection
15:45:35.302 4 HTTPC-000106 closing connection
15:45:35.302 4 HTTPU-000106([user.ip]) closing connection
15:45:35.302 4 HTTPU-000106([user.ip]) releasing stream
15:45:35.317 5 HTTPU [0.0.0.0]:8080 <- [user.ip]:4911 connection request. socket=32
15:45:35.317 4 HTTPU-000107([user.ip]) got connection on [frontend.ip]:8080(frontend.domain) from [user.ip]:4911
15:45:35.320 5 HTTPU-000107([user.ip]) inp: GET /Session/14-xlOrH3EL0hFvmaMG2Ual/frameset.wssp? HTTP/1.1
15:45:35.321 5 HTTPU-000107([user.ip]) inp: Accept: image/gif, image/x-xbitmap, image/jpeg, image/pjpeg, application/x-shockwave-flash, application/vnd.ms-excel, application/vnd.ms-powerpoint, application/msword, application/xaml+xml, application/vnd.ms-xpsdocument, application/x-ms-xbap, appli
15:45:35.321 5 HTTPU-000107([user.ip]) inp: Referer: http://frontend.domain:8080/
15:45:35.321 5 HTTPU-000107([user.ip]) inp: Accept-Language: en-US
15:45:35.321 5 HTTPU-000107([user.ip]) inp: UA-CPU: x86
15:45:35.321 5 HTTPU-000107([user.ip]) inp: Accept-Encoding: gzip, deflate
15:45:35.321 5 HTTPU-000107([user.ip]) inp: User-Agent: Mozilla/4.0 (compatible; MSIE 7.0; Windows NT 5.1; WebMoney Advisor; .NET CLR 1.1.4322; .NET CLR 2.0.50727; InfoPath.2; .NET CLR 3.0.04506.30; MEGAUPLOAD 1.0)
15:45:35.321 5 HTTPU-000107([user.ip]) inp: Host: frontend.domain:8080
15:45:35.321 5 HTTPU-000107([user.ip]) inp: Connection: Keep-Alive
15:45:35.321 5 HTTPU-000107([user.ip]) inp: Cache-Control: no-cache
15:45:35.321 5 HTTPU-000107([user.ip]) inp: 
15:45:35.321 4 HTTPU-000107([user.ip]) req(0): GET /Session/14-xlOrH3EL0hFvmaMG2Ual/frameset.wssp
15:45:35.321 2 HTTPU-000107([user.ip]) login.wssp(frontend.domain) retrieved
15:45:35.321 5 HTTPU-000107([user.ip]) hdr: HTTP/1.1 200 OK
15:45:35.321 5 HTTPU-000107([user.ip]) hdr: Content-Length: 6125
15:45:35.321 5 HTTPU-000107([user.ip]) hdr: Connection: close
15:45:35.321 5 HTTPU-000107([user.ip]) hdr: Date: Sat, 19 Apr 2008 12:45:35 GMT
15:45:35.321 5 HTTPU-000107([user.ip]) hdr: Content-Type: text/html;charset=iso-8859-1
15:45:35.322 5 HTTPU-000107([user.ip]) hdr: Server: CommuniGatePro/5.2.2
15:45:35.322 5 HTTPU-000107([user.ip]) hdr: 
15:45:35.322 4 HTTPU-000107([user.ip]) rsp(6125): 200 OK
15:45:35.322 5 HTTPU-000107([user.ip]) out: HTTP/1.1 200 OK\r\nContent-Length: 6125\r\nConnection: close\r\nDate: Sat, 19 Apr 2008 12:45:35 GMT\r\nContent-Type: text/html;charset=iso-8859-1\r\nServer: CommuniGatePro/5.2.2\r\n\r\n<!DOCTYPE HTML PUBLIC "-//W3C//DTD HTML 4.01 Transitional//EN" "ht
15:45:35.322 4 HTTPU-000107([user.ip]) closing connection
15:45:35.322 4 HTTPU-000107([user.ip]) releasing stream
15:45:50.001 5 HTTPU stream thread finished

=======================================

=== Логи со стороны backend: ===

15:45:35.235 5 HTTPU [0.0.0.0]:8100 <- [frontend.local.ip]:64982 connection request. socket=12
15:45:35.235 4 HTTPU-002243([frontend.local.ip]) got connection on [backend.local.ip]:8100(dniprogroup.com) from [frontend.local.ip]:64982
15:45:35.235 5 HTTPU-002243([frontend.local.ip]) inp: POST /ProxyLogin/user@domain.com/kmbcnmw.html HTTP/1.1
15:45:35.236 5 HTTPU-002243([frontend.local.ip]) inp: Referer: http://frontend.domain:8080/
15:45:35.236 5 HTTPU-002243([frontend.local.ip]) inp: Host: frontend.domain:8080
15:45:35.236 5 HTTPU-002243([frontend.local.ip]) inp: X-ORIGINAL-ADDRESS: [user.ip]:4909
15:45:35.236 5 HTTPU-002243([frontend.local.ip]) inp: User-Agent: Mozilla/4.0 (compatible; MSIE 7.0; Windows NT 5.1; WebMoney Advisor; .NET CLR 1.1.4322; .NET CLR 2.0.50727; InfoPath.2; .NET CLR 3.0.04506.30; MEGAUPLOAD 1.0)
15:45:35.236 5 HTTPU-002243([frontend.local.ip]) inp: Accept: image/gif, image/x-xbitmap, image/jpeg, image/pjpeg, application/x-shockwave-flash, application/vnd.ms-excel, application/vnd.ms-powerpoint, application/msword, application/xaml+xml, application/vnd.ms-xpsdocument, application/x-ms-xbap, applic
15:45:35.236 5 HTTPU-002243([frontend.local.ip]) inp: Accept-Language: en-US
15:45:35.236 5 HTTPU-002243([frontend.local.ip]) inp: Content-Type: multipart/form-data; boundary=---------------------------7d82c4221704aa
15:45:35.236 5 HTTPU-002243([frontend.local.ip]) inp: UA-CPU: x86
15:45:35.236 5 HTTPU-002243([frontend.local.ip]) inp: Accept-Encoding: gzip, deflate
15:45:35.236 5 HTTPU-002243([frontend.local.ip]) inp: Content-Length: 993
15:45:35.236 5 HTTPU-002243([frontend.local.ip]) inp: Cache-Control: no-cache
15:45:35.236 5 HTTPU-002243([frontend.local.ip]) inp: 
15:45:35.237 4 HTTPU-002243([frontend.local.ip]) req(993): POST /ProxyLogin/user@domain.com/kmbcnmw.html
15:45:35.237 2 WEBUSER-000014(user@domain.com) logged in(HTTP) from [user.ip]
15:45:35.301 2 HTTPU-002243([frontend.local.ip]) Moved.wssp(domain.com) retrieved
15:45:35.301 5 HTTPU-002243([frontend.local.ip]) hdr: HTTP/1.1 301 Moved
15:45:35.301 5 HTTPU-002243([frontend.local.ip]) hdr: Content-Length: 455
15:45:35.301 5 HTTPU-002243([frontend.local.ip]) hdr: Connection: close
15:45:35.301 5 HTTPU-002243([frontend.local.ip]) hdr: Date: Sat, 19 Apr 2008 12:45:35 GMT
15:45:35.301 5 HTTPU-002243([frontend.local.ip]) hdr: Content-Type: text/html;charset=iso-8859-1
15:45:35.301 5 HTTPU-002243([frontend.local.ip]) hdr: Server: CommuniGatePro/5.2.2
15:45:35.301 5 HTTPU-002243([frontend.local.ip]) hdr: Location: http://frontend.domain:8080/Session/14-xlOrH3EL0hFvmaMG2Ual/frameset.wssp?
15:45:35.301 5 HTTPU-002243([frontend.local.ip]) hdr: 
15:45:35.301 4 HTTPU-002243([frontend.local.ip]) rsp(455): 301 Moved
15:45:35.301 5 HTTPU-002243([frontend.local.ip]) out: HTTP/1.1 301 Moved\r\nContent-Length: 455\r\nConnection: close\r\nDate: Sat, 19 Apr 2008 12:45:35 GMT\r\nContent-Type: text/html;charset=iso-8859-1\r\nServer: CommuniGatePro/5.2.2\r\nLocation: http://frontend.domain:8080/Session/14-xlOrH3EL0hFvmaMG2Ual/fram
15:45:35.302 4 HTTPU-002243([frontend.local.ip]) closing connection
15:45:35.302 4 HTTPU-002243([frontend.local.ip]) releasing stream

=======================================

-- 
Best regards,
 Sergey