tomcat-users mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From "Leandro Dardini" <l.dard...@comune.prato.it>
Subject R: R: Newer version of mod_jk much slower than 1.2.13
Date Fri, 22 Aug 2008 16:26:07 GMT



-----Messaggio originale-----
Da: Rainer Jung [mailto:rainer.jung@kippdata.de]
Inviato: ven 22/08/2008 14.21
A: Tomcat Users List
Oggetto: Re: R: Newer version of mod_jk much slower than 1.2.13
 
> I get rid of the balancer and set in apache configuration file:
> 
>         JkMount /eltbinj/* ajp13w_01_01
> 
> With the 1.2.13 the result is obviously the same high speed (I unset the lang for not
italian (lucky) people):
...
> 13:13:00 (630.19 KB/s) - `/dev/null' saved [53048]
> 
> With the 1.2.26 the result is the same:
...
> 13:14:53 (7.35 KB/s) - `/dev/null' saved [53048]
> 
> So in conclusion, it is not a matter of load balancer.

Thanks for helping narrowing it down, also the information about it only 
happening for dynamic content is interesting (maybe it has to do with 
chunked encoding).

I could analyze the packet flow between Apache and Tomcat. If you set 
JkLogLevel to trace (and if trace doesn't exist for 1.2.13 to debug 
there), you get very long log output. If you do the wget test once with 
each version and with the increased log level, I could have a look at 
those log files. They will contain a full packet dump of the 
communication and at least for 1.2.26 millisecond timestamps.

Nevertheless I'm still curious about your JK directives for Apache. I 
guess JkMount is not everything ;)

Regards,

Rainer

---------------------------------------------------------------------
To start a new topic, e-mail: users@tomcat.apache.org
To unsubscribe, e-mail: users-unsubscribe@tomcat.apache.org
For additional commands, e-mail: users-help@tomcat.apache.org


Sorry for the ugly mailer, but I am using a webmail now from home. About the jk directives
for apache, here they are:

LoadModule jk_module modules/mod_jk.so

JkWorkersFile /etc/httpd/conf/workers.properties
JkLogFile /etc/httpd/logs/mod_jk.log
JkLogLevel info

About the trace level, full it is over 700Kbyte (gzipped), I send it directly to you. Thank
you very much if you can look at it.

172.16.1.49 is the host making the wget

193.43.107.13 is the apache server 

193.43.107.16 is the tomcat server

A piece following:

[Fri Aug 22 18:10:57.827 2008] [23127:2537062720] [debug] init_ws_service::mod_jk.c (888):
Service protocol=HTTP/1.0 method=GET host=(null) a
ddr=172.16.1.49 name=elenco1.comune.prato.it port=80 auth=(null) user=(null) laddr=193.43.107.14
raddr=172.16.1.49 uri=/eltbinj/servlet/ricer
caelenco
[Fri Aug 22 18:10:57.827 2008] [23127:2537062720] [trace] get_endpoint::jk_ajp13_worker.c
(73): enter
[Fri Aug 22 18:10:57.827 2008] [23127:2537062720] [trace] ajp_get_endpoint::jk_ajp_common.c
(2552): enter
[Fri Aug 22 18:10:57.827 2008] [23127:2537062720] [debug] ajp_get_endpoint::jk_ajp_common.c
(2579): acquired connection pool slot=0
[Fri Aug 22 18:10:57.827 2008] [23127:2537062720] [trace] ajp_get_endpoint::jk_ajp_common.c
(2582): exit
[Fri Aug 22 18:10:57.827 2008] [23127:2537062720] [trace] get_endpoint::jk_ajp13_worker.c
(75): exit
[Fri Aug 22 18:10:57.827 2008] [23127:2537062720] [trace] ajp_service::jk_ajp_common.c (1966):
enter
[Fri Aug 22 18:10:57.827 2008] [23127:2537062720] [trace] ajp_marshal_into_msgb::jk_ajp_common.c
(373): enter
[Fri Aug 22 18:10:57.827 2008] [23127:2537062720] [debug] ajp_marshal_into_msgb::jk_ajp_common.c
(553): ajp marshaling done
[Fri Aug 22 18:10:57.827 2008] [23127:2537062720] [trace] ajp_marshal_into_msgb::jk_ajp_common.c
(554): exit
[Fri Aug 22 18:10:57.827 2008] [23127:2537062720] [debug] ajp_service::jk_ajp_common.c (2050):
processing ajp13w_01_01 with 2 retries
[Fri Aug 22 18:10:57.827 2008] [23127:2537062720] [trace] ajp_send_request::jk_ajp_common.c
(1263): enter
[Fri Aug 22 18:10:57.827 2008] [23127:2537062720] [trace] ajp_next_connection::jk_ajp_common.c
(737): enter
[Fri Aug 22 18:10:57.827 2008] [23127:2537062720] [trace] ajp_next_connection::jk_ajp_common.c
(764): exit
[Fri Aug 22 18:10:57.828 2008] [23127:2537062720] [debug] ajp_send_request::jk_ajp_common.c
(1352): (ajp13w_01_01) all endpoints are disconne
cted, detected by connect check (0), cping (0), send (0)
[Fri Aug 22 18:10:57.828 2008] [23127:2537062720] [trace] ajp_connect_to_endpoint::jk_ajp_common.c
(859): enter
[Fri Aug 22 18:10:57.828 2008] [23127:2537062720] [trace] jk_open_socket::jk_connect.c (426):
enter
[Fri Aug 22 18:10:57.828 2008] [23127:2537062720] [debug] jk_open_socket::jk_connect.c (448):
socket TCP_NODELAY set to On
[Fri Aug 22 18:10:57.828 2008] [23127:2537062720] [debug] jk_open_socket::jk_connect.c (462):
socket SO_KEEPALIVE set to On
[Fri Aug 22 18:10:57.828 2008] [23127:2537062720] [debug] jk_open_socket::jk_connect.c (513):
timeout 1 set for socket=17
[Fri Aug 22 18:10:57.828 2008] [23127:2537062720] [debug] jk_open_socket::jk_connect.c (548):
trying to connect socket 17 to 193.43.107.16:8009
[Fri Aug 22 18:10:57.828 2008] [23127:2537062720] [trace] nb_connect::jk_connect.c (218):
enter
[Fri Aug 22 18:10:57.828 2008] [23127:2537062720] [trace] nb_connect::jk_connect.c (264):
exit
[Fri Aug 22 18:10:57.828 2008] [23127:2537062720] [debug] jk_open_socket::jk_connect.c (574):
socket 17 connected to 193.43.107.16:8009
[Fri Aug 22 18:10:57.828 2008] [23127:2537062720] [trace] jk_open_socket::jk_connect.c (577):
exit
[Fri Aug 22 18:10:57.828 2008] [23127:2537062720] [debug] ajp_connect_to_endpoint::jk_ajp_common.c
(878): Connected socket 17 to (193.43.107.16:8009)
[Fri Aug 22 18:10:57.828 2008] [23127:2537062720] [trace] ajp_connect_to_endpoint::jk_ajp_common.c
(908): exit
[Fri Aug 22 18:10:57.828 2008] [23127:2537062720] [trace] ajp_connection_tcp_send_message::jk_ajp_common.c
(928): enter
[Fri Aug 22 18:10:57.828 2008] [23127:2537062720] [debug] ajp_connection_tcp_send_message::jk_ajp_common.c
(934): sending to ajp13 pos=4 len=
190 max=8192
[Fri Aug 22 18:10:57.828 2008] [23127:2537062720] [debug] ajp_connection_tcp_send_message::jk_ajp_common.c
(934): 0000    12 34 00 BA 02 02 0
0 08 48 54 54 50 2F 31 2E 30  - .4......HTTP/1.0
[Fri Aug 22 18:10:57.828 2008] [23127:2537062720] [debug] ajp_connection_tcp_send_message::jk_ajp_common.c
(934): 0010    00 00 1E 2F 65 6C 7
4 62 69 6E 6A 2F 73 65 72 76  - .../eltbinj/serv
[Fri Aug 22 18:10:57.828 2008] [23127:2537062720] [debug] ajp_connection_tcp_send_message::jk_ajp_common.c
(934): 0020    6C 65 74 2F 72 69 6
3 65 72 63 61 65 6C 65 6E 63  - let/ricercaelenc
[Fri Aug 22 18:10:57.828 2008] [23127:2537062720] [debug] ajp_connection_tcp_send_message::jk_ajp_common.c
(934): 0030    6F 00 00 0B 31 37 3
2 2E 31 36 2E 31 2E 34 39 00  - o...172.16.1.49.
[Fri Aug 22 18:10:57.829 2008] [23127:2537062720] [debug] ajp_connection_tcp_send_message::jk_ajp_common.c
(934): 0040    FF FF 00 17 65 6C 6
5 6E 63 6F 31 2E 63 6F 6D 75  - ....elenco1.comu
[Fri Aug 22 18:10:57.829 2008] [23127:2537062720] [debug] ajp_connection_tcp_send_message::jk_ajp_common.c
(934): 0050    6E 65 2E 70 72 61 7
4 6F 2E 69 74 00 00 50 00 00  - ne.prato.it..P..
[Fri Aug 22 18:10:57.829 2008] [23127:2537062720] [debug] ajp_connection_tcp_send_message::jk_ajp_common.c
(934): 0060    05 A0 0E 00 1E 57 6
7 65 74 2F 31 2E 31 30 2E 32  - .....Wget/1.10.2
[Fri Aug 22 18:10:57.829 2008] [23127:2537062720] [debug] ajp_connection_tcp_send_message::jk_ajp_common.c
(934): 0070    20 28 52 65 64 20 4
8 61 74 20 6D 6F 64 69 66 69  - .(Red.Hat.modifi
[Fri Aug 22 18:10:57.829 2008] [23127:2537062720] [debug] ajp_connection_tcp_send_message::jk_ajp_common.c
(934): 0080    65 64 29 00 A0 01 0
0 03 2A 2F 2A 00 A0 0B 00 17  - ed).....*/*.....
[Fri Aug 22 18:10:57.829 2008] [23127:2537062720] [debug] ajp_connection_tcp_send_message::jk_ajp_common.c
(934): 0090    65 6C 65 6E 63 6F 3
1 2E 63 6F 6D 75 6E 65 2E 70  - elenco1.comune.p
[Fri Aug 22 18:10:57.829 2008] [23127:2537062720] [debug] ajp_connection_tcp_send_message::jk_ajp_common.c
(934): 00a0    72 61 74 6F 2E 69 7
4 00 A0 06 00 0A 4B 65 65 70  - rato.it.....Keep
[Fri Aug 22 18:10:57.829 2008] [23127:2537062720] [debug] ajp_connection_tcp_send_message::jk_ajp_common.c
(934): 00b0    2D 41 6C 69 76 65 0
0 A0 08 00 01 30 00 FF 00 00  - -Alive.....0....
[Fri Aug 22 18:10:57.829 2008] [23127:2537062720] [trace] jk_tcp_socket_sendfull::jk_connect.c
(719): enter
[Fri Aug 22 18:10:57.829 2008] [23127:2537062720] [trace] jk_tcp_socket_sendfull::jk_connect.c
(747): exit
[Fri Aug 22 18:10:57.829 2008] [23127:2537062720] [trace] ajp_connection_tcp_send_message::jk_ajp_common.c
(961): exit
[Fri Aug 22 18:10:57.829 2008] [23127:2537062720] [debug] ajp_send_request::jk_ajp_common.c
(1395): (ajp13w_01_01) request body to send 0 - r
equest body to resend 0
[Fri Aug 22 18:10:57.829 2008] [23127:2537062720] [trace] ajp_send_request::jk_ajp_common.c
(1506): exit
[Fri Aug 22 18:10:57.829 2008] [23127:2537062720] [trace] ajp_get_reply::jk_ajp_common.c (1718):
enter
[Fri Aug 22 18:10:57.829 2008] [23127:2537062720] [trace] ajp_connection_tcp_get_message::jk_ajp_common.c
(992): enter
[Fri Aug 22 18:10:57.829 2008] [23127:2537062720] [trace] jk_tcp_socket_recvfull::jk_connect.c
(768): enter
[Fri Aug 22 18:10:58.021 2008] [23127:2537062720] [trace] jk_tcp_socket_recvfull::jk_connect.c
(796): exit
[Fri Aug 22 18:10:58.021 2008] [23127:2537062720] [trace] jk_tcp_socket_recvfull::jk_connect.c
(768): enter
[Fri Aug 22 18:10:58.021 2008] [23127:2537062720] [trace] jk_tcp_socket_recvfull::jk_connect.c
(796): exit
[Fri Aug 22 18:10:58.021 2008] [23127:2537062720] [debug] ajp_connection_tcp_get_message::jk_ajp_common.c
(1117): received from ajp13 pos=0 l
en=56 max=8192
[Fri Aug 22 18:10:58.021 2008] [23127:2537062720] [debug] ajp_connection_tcp_get_message::jk_ajp_common.c
(1117): 0000    04 00 C8 00 02 4F 4
B 00 00 01 00 0C 43 6F 6E 74  - .....OK.....Cont
[Fri Aug 22 18:10:58.021 2008] [23127:2537062720] [debug] ajp_connection_tcp_get_message::jk_ajp_common.c
(1117): 0010    65 6E 74 2D 54 79 7
0 65 00 00 1C 74 65 78 74 2F  - ent-Type...text/
[Fri Aug 22 18:10:58.021 2008] [23127:2537062720] [debug] ajp_connection_tcp_get_message::jk_ajp_common.c
(1117): 0020    68 74 6D 6C 3B 63 6
8 61 72 73 65 74 3D 49 53 4F  - html;charset=ISO
[Fri Aug 22 18:10:58.022 2008] [23127:2537062720] [debug] ajp_connection_tcp_get_message::jk_ajp_common.c
(1117): 0030    2D 38 38 35 39 2D 3
1 00 00 00 00 00 00 00 00 00  - -8859-1.........
[Fri Aug 22 18:10:58.022 2008] [23127:2537062720] [trace] ajp_connection_tcp_get_message::jk_ajp_common.c
(1123): exit
[Fri Aug 22 18:10:58.022 2008] [23127:2537062720] [trace] ajp_process_callback::jk_ajp_common.c
(1536): enter
[Fri Aug 22 18:10:58.022 2008] [23127:2537062720] [trace] ajp_unmarshal_response::jk_ajp_common.c
(590): enter
[Fri Aug 22 18:10:58.022 2008] [23127:2537062720] [debug] ajp_unmarshal_response::jk_ajp_common.c
(608): status = 200
[Fri Aug 22 18:10:58.022 2008] [23127:2537062720] [debug] ajp_unmarshal_response::jk_ajp_common.c
(615): Number of headers is = 1
[Fri Aug 22 18:10:58.022 2008] [23127:2537062720] [debug] ajp_unmarshal_response::jk_ajp_common.c
(671): Header[0] [Content-Type] = [text/htm
l;charset=ISO-8859-1]
[Fri Aug 22 18:10:58.022 2008] [23127:2537062720] [trace] ajp_unmarshal_response::jk_ajp_common.c
(678): exit
[Fri Aug 22 18:10:58.022 2008] [23127:2537062720] [trace] ajp_connection_tcp_get_message::jk_ajp_common.c
(992): enter
[Fri Aug 22 18:10:58.022 2008] [23127:2537062720] [trace] jk_tcp_socket_recvfull::jk_connect.c
(768): enter
[Fri Aug 22 18:10:58.022 2008] [23127:2537062720] [trace] jk_tcp_socket_recvfull::jk_connect.c
(796): exit
[Fri Aug 22 18:10:58.022 2008] [23127:2537062720] [trace] jk_tcp_socket_recvfull::jk_connect.c
(768): enter
[Fri Aug 22 18:10:58.022 2008] [23127:2537062720] [trace] jk_tcp_socket_recvfull::jk_connect.c
(796): exit
[Fri Aug 22 18:10:58.022 2008] [23127:2537062720] [debug] ajp_connection_tcp_get_message::jk_ajp_common.c
(1117): received from ajp13 pos=0 l
en=4 max=8192
[Fri Aug 22 18:10:58.022 2008] [23127:2537062720] [debug] ajp_connection_tcp_get_message::jk_ajp_common.c
(1117): 0000    03 00 00 00 00 00 0
0 00 00 00 00 00 00 00 00 00  - ................
[Fri Aug 22 18:10:58.022 2008] [23127:2537062720] [trace] ajp_connection_tcp_get_message::jk_ajp_common.c
(1123): exit
[Fri Aug 22 18:10:58.022 2008] [23127:2537062720] [trace] ajp_process_callback::jk_ajp_common.c
(1536): enter
[Fri Aug 22 18:10:58.025 2008] [23127:2537062720] [trace] ajp_process_callback::jk_ajp_common.c
(1680): exit
[Fri Aug 22 18:10:58.025 2008] [23127:2537062720] [trace] ajp_connection_tcp_get_message::jk_ajp_common.c
(992): enter
[Fri Aug 22 18:10:58.025 2008] [23127:2537062720] [trace] jk_tcp_socket_recvfull::jk_connect.c
(768): enter
[Fri Aug 22 18:10:58.025 2008] [23127:2537062720] [trace] jk_tcp_socket_recvfull::jk_connect.c
(796): exit
[Fri Aug 22 18:10:58.025 2008] [23127:2537062720] [trace] jk_tcp_socket_recvfull::jk_connect.c
(768): enter
[Fri Aug 22 18:10:58.025 2008] [23127:2537062720] [trace] jk_tcp_socket_recvfull::jk_connect.c
(796): exit
[Fri Aug 22 18:10:58.025 2008] [23127:2537062720] [debug] ajp_connection_tcp_get_message::jk_ajp_common.c
(1117): received from ajp13 pos=0 len=73 max=8192
[Fri Aug 22 18:10:58.025 2008] [23127:2537062720] [debug] ajp_connection_tcp_get_message::jk_ajp_common.c
(1117): 0000    03 00 45 3C 21 44 4
F 43 54 59 50 45 20 48 54 4D  - ..E<!DOCTYPE.HTM
[Fri Aug 22 18:10:58.025 2008] [23127:2537062720] [debug] ajp_connection_tcp_get_message::jk_ajp_common.c
(1117): 0010    4C 20 50 55 42 4C 4
9 43 20 22 2D 2F 2F 57 33 43  - L.PUBLIC."-//W3C
[Fri Aug 22 18:10:58.025 2008] [23127:2537062720] [debug] ajp_connection_tcp_get_message::jk_ajp_common.c
(1117): 0020    2F 2F 44 54 44 20 4
8 54 4D 4C 20 34 2E 30 31 20  - //DTD.HTML.4.01.
[Fri Aug 22 18:10:58.025 2008] [23127:2537062720] [debug] ajp_connection_tcp_get_message::jk_ajp_common.c
(1117): 0030    54 72 61 6E 73 69 7
4 69 6F 6E 61 6C 2F 2F 45 4E  - Transitional//EN
[Fri Aug 22 18:10:58.025 2008] [23127:2537062720] [debug] ajp_connection_tcp_get_message::jk_ajp_common.c
(1117): 0040    22 3E 0A 3C 68 74 6
D 6C 00 00 00 00 00 00 00 00  - ">.<html........
[Fri Aug 22 18:10:58.025 2008] [23127:2537062720] [trace] ajp_connection_tcp_get_message::jk_ajp_common.c
(1123): exit
[Fri Aug 22 18:10:58.025 2008] [23127:2537062720] [trace] ajp_process_callback::jk_ajp_common.c
(1536): enter
[Fri Aug 22 18:10:58.025 2008] [23127:2537062720] [debug] ws_write::mod_jk.c (455): written
69 out of 69
[Fri Aug 22 18:10:58.025 2008] [23127:2537062720] [trace] ajp_process_callback::jk_ajp_common.c
(1680): exit
[Fri Aug 22 18:10:58.025 2008] [23127:2537062720] [trace] ajp_connection_tcp_get_message::jk_ajp_common.c
(992): enter
[Fri Aug 22 18:10:58.025 2008] [23127:2537062720] [trace] jk_tcp_socket_recvfull::jk_connect.c
(768): enter
[Fri Aug 22 18:10:58.025 2008] [23127:2537062720] [trace] jk_tcp_socket_recvfull::jk_connect.c
(796): exit
[Fri Aug 22 18:10:58.025 2008] [23127:2537062720] [trace] jk_tcp_socket_recvfull::jk_connect.c
(768): enter
[Fri Aug 22 18:10:58.025 2008] [23127:2537062720] [trace] jk_tcp_socket_recvfull::jk_connect.c
(796): exit
[Fri Aug 22 18:10:58.026 2008] [23127:2537062720] [debug] ajp_connection_tcp_get_message::jk_ajp_common.c
(1117): received from ajp13 pos=0 l
en=4 max=8192
[Fri Aug 22 18:10:58.026 2008] [23127:2537062720] [debug] ajp_connection_tcp_get_message::jk_ajp_common.c
(1117): 0000    03 00 00 00 00 00 0
0 00 00 00 00 00 00 00 00 00  - ................
[Fri Aug 22 18:10:58.026 2008] [23127:2537062720] [trace] ajp_connection_tcp_get_message::jk_ajp_common.c
(1123): exit
[Fri Aug 22 18:10:58.026 2008] [23127:2537062720] [trace] ajp_process_callback::jk_ajp_common.c
(1536): enter
[Fri Aug 22 18:10:58.027 2008] [23127:2537062720] [trace] ajp_process_callback::jk_ajp_common.c
(1680): exit
[Fri Aug 22 18:10:58.027 2008] [23127:2537062720] [trace] ajp_connection_tcp_get_message::jk_ajp_common.c
(992): enter
[Fri Aug 22 18:10:58.027 2008] [23127:2537062720] [trace] jk_tcp_socket_recvfull::jk_connect.c
(768): enter
[Fri Aug 22 18:10:58.027 2008] [23127:2537062720] [trace] jk_tcp_socket_recvfull::jk_connect.c
(796): exit
[Fri Aug 22 18:10:58.027 2008] [23127:2537062720] [trace] jk_tcp_socket_recvfull::jk_connect.c
(768): enter
[Fri Aug 22 18:10:58.027 2008] [23127:2537062720] [trace] jk_tcp_socket_recvfull::jk_connect.c
(796): exit
[Fri Aug 22 18:10:58.027 2008] [23127:2537062720] [debug] ajp_connection_tcp_get_message::jk_ajp_common.c
(1117): received from ajp13 pos=0 l
en=13 max=8192
[Fri Aug 22 18:10:58.027 2008] [23127:2537062720] [debug] ajp_connection_tcp_get_message::jk_ajp_common.c
(1117): 0000    03 00 09 3E 0A 20 2
0 3C 68 65 61 64 00 00 00 00  - ...>...<head....
[Fri Aug 22 18:10:58.027 2008] [23127:2537062720] [trace] ajp_connection_tcp_get_message::jk_ajp_common.c
(1123): exit
[Fri Aug 22 18:10:58.027 2008] [23127:2537062720] [trace] ajp_process_callback::jk_ajp_common.c
(1536): enter
[Fri Aug 22 18:10:58.027 2008] [23127:2537062720] [debug] ws_write::mod_jk.c (455): written
9 out of 9
[Fri Aug 22 18:10:58.027 2008] [23127:2537062720] [trace] ajp_process_callback::jk_ajp_common.c
(1680): exit
[Fri Aug 22 18:10:58.027 2008] [23127:2537062720] [trace] ajp_connection_tcp_get_message::jk_ajp_common.c
(992): enter
[Fri Aug 22 18:10:58.027 2008] [23127:2537062720] [trace] jk_tcp_socket_recvfull::jk_connect.c
(768): enter
[Fri Aug 22 18:10:58.027 2008] [23127:2537062720] [trace] jk_tcp_socket_recvfull::jk_connect.c
(796): exit
[Fri Aug 22 18:10:58.027 2008] [23127:2537062720] [trace] jk_tcp_socket_recvfull::jk_connect.c
(768): enter
[Fri Aug 22 18:10:58.027 2008] [23127:2537062720] [trace] jk_tcp_socket_recvfull::jk_connect.c
(796): exit
[Fri Aug 22 18:10:58.027 2008] [23127:2537062720] [debug] ajp_connection_tcp_get_message::jk_ajp_common.c
(1117): received from ajp13 pos=0 l
en=4 max=8192
[Fri Aug 22 18:10:58.027 2008] [23127:2537062720] [debug] ajp_connection_tcp_get_message::jk_ajp_common.c
(1117): 0000    03 00 00 00 00 00 0
0 00 00 00 00 00 00 00 00 00  - ................
[Fri Aug 22 18:10:58.027 2008] [23127:2537062720] [trace] ajp_connection_tcp_get_message::jk_ajp_common.c
(1123): exit
[Fri Aug 22 18:10:58.027 2008] [23127:2537062720] [trace] ajp_process_callback::jk_ajp_common.c
(1536): enter
[Fri Aug 22 18:10:58.029 2008] [23127:2537062720] [trace] ajp_process_callback::jk_ajp_common.c
(1680): exit
[Fri Aug 22 18:10:58.029 2008] [23127:2537062720] [trace] ajp_connection_tcp_get_message::jk_ajp_common.c
(992): enter
[Fri Aug 22 18:10:58.029 2008] [23127:2537062720] [trace] jk_tcp_socket_recvfull::jk_connect.c
(768): enter
[Fri Aug 22 18:10:58.029 2008] [23127:2537062720] [trace] jk_tcp_socket_recvfull::jk_connect.c
(796): exit
[Fri Aug 22 18:10:58.029 2008] [23127:2537062720] [trace] jk_tcp_socket_recvfull::jk_connect.c
(768): enter
[Fri Aug 22 18:10:58.029 2008] [23127:2537062720] [trace] jk_tcp_socket_recvfull::jk_connect.c
(796): exit
[Fri Aug 22 18:10:58.029 2008] [23127:2537062720] [debug] ajp_connection_tcp_get_message::jk_ajp_common.c
(1117): received from ajp13 pos=0 l
en=93 max=8192
[Fri Aug 22 18:10:58.029 2008] [23127:2537062720] [debug] ajp_connection_tcp_get_message::jk_ajp_common.c
(1117): 0000    03 00 59 3E 0A 20 2
0 20 20 3C 4D 45 54 41 20 68  - ..Y>.....<META.h
[Fri Aug 22 18:10:58.029 2008] [23127:2537062720] [debug] ajp_connection_tcp_get_message::jk_ajp_common.c
(1117): 0010    74 74 70 2D 65 71 7
5 69 76 3D 22 43 6F 6E 74 65  - ttp-equiv="Conte
[Fri Aug 22 18:10:58.029 2008] [23127:2537062720] [debug] ajp_connection_tcp_get_message::jk_ajp_common.c
(1117): 0020    6E 74 2D 54 79 70 6
5 22 20 63 6F 6E 74 65 6E 74  - nt-Type".content
[Fri Aug 22 18:10:58.029 2008] [23127:2537062720] [debug] ajp_connection_tcp_get_message::jk_ajp_common.c
(1117): 0030    3D 22 74 65 78 74 2
F 68 74 6D 6C 3B 20 63 68 61  - ="text/html;.cha
[Fri Aug 22 18:10:58.029 2008] [23127:2537062720] [debug] ajp_connection_tcp_get_message::jk_ajp_common.c
(1117): 0040    72 73 65 74 3D 49 5
3 4F 2D 38 38 35 39 2D 31 22  - rset=ISO-8859-1"
[Fri Aug 22 18:10:58.029 2008] [23127:2537062720] [debug] ajp_connection_tcp_get_message::jk_ajp_common.c
(1117): 0050    3E 0A 20 20 20 20 3
C 74 69 74 6C 65 00 00 00 00  - >.....<title....
[Fri Aug 22 18:10:58.029 2008] [23127:2537062720] [trace] ajp_connection_tcp_get_message::jk_ajp_common.c
(1123): exit
[Fri Aug 22 18:10:58.029 2008] [23127:2537062720] [trace] ajp_process_callback::jk_ajp_common.c
(1536): enter
[Fri Aug 22 18:10:58.029 2008] [23127:2537062720] [debug] ws_write::mod_jk.c (455): written
89 out of 89
[Fri Aug 22 18:10:58.029 2008] [23127:2537062720] [trace] ajp_process_callback::jk_ajp_common.c
(1680): exit
[Fri Aug 22 18:10:58.029 2008] [23127:2537062720] [trace] ajp_connection_tcp_get_message::jk_ajp_common.c
(992): enter
[Fri Aug 22 18:10:58.029 2008] [23127:2537062720] [trace] jk_tcp_socket_recvfull::jk_connect.c
(768): enter
[Fri Aug 22 18:10:58.029 2008] [23127:2537062720] [trace] jk_tcp_socket_recvfull::jk_connect.c
(796): exit
[Fri Aug 22 18:10:58.029 2008] [23127:2537062720] [trace] jk_tcp_socket_recvfull::jk_connect.c
(768): enter
[Fri Aug 22 18:10:58.029 2008] [23127:2537062720] [trace] jk_tcp_socket_recvfull::jk_connect.c
(796): exit
[Fri Aug 22 18:10:58.029 2008] [23127:2537062720] [debug] ajp_connection_tcp_get_message::jk_ajp_common.c
(1117): received from ajp13 pos=0 l
en=4 max=8192
[Fri Aug 22 18:10:58.029 2008] [23127:2537062720] [debug] ajp_connection_tcp_get_message::jk_ajp_common.c
(1117): 0000    03 00 00 00 00 00 0
0 00 00 00 00 00 00 00 00 00  - ................
[Fri Aug 22 18:10:58.029 2008] [23127:2537062720] [trace] ajp_connection_tcp_get_message::jk_ajp_common.c
(1123): exit
[Fri Aug 22 18:10:58.029 2008] [23127:2537062720] [trace] ajp_process_callback::jk_ajp_common.c
(1536): enter
[Fri Aug 22 18:10:58.031 2008] [23127:2537062720] [trace] ajp_process_callback::jk_ajp_common.c
(1680): exit
[Fri Aug 22 18:10:58.031 2008] [23127:2537062720] [trace] ajp_connection_tcp_get_message::jk_ajp_common.c
(992): enter
[Fri Aug 22 18:10:58.031 2008] [23127:2537062720] [trace] jk_tcp_socket_recvfull::jk_connect.c
(768): enter
[Fri Aug 22 18:10:58.031 2008] [23127:2537062720] [trace] jk_tcp_socket_recvfull::jk_connect.c
(796): exit
[Fri Aug 22 18:10:58.031 2008] [23127:2537062720] [trace] jk_tcp_socket_recvfull::jk_connect.c
(768): enter
[Fri Aug 22 18:10:58.031 2008] [23127:2537062720] [trace] jk_tcp_socket_recvfull::jk_connect.c
(796): exit
[Fri Aug 22 18:10:58.031 2008] [23127:2537062720] [debug] ajp_connection_tcp_get_message::jk_ajp_common.c
(1117): received from ajp13 pos=0 l
en=13 max=8192
[Fri Aug 22 18:10:58.031 2008] [23127:2537062720] [debug] ajp_connection_tcp_get_message::jk_ajp_common.c
(1117): 0000    03 00 09 3E 52 69 6
3 65 72 63 61 20 00 00 00 00  - ...>Ricerca.....
[Fri Aug 22 18:10:58.031 2008] [23127:2537062720] [trace] ajp_connection_tcp_get_message::jk_ajp_common.c
(1123): exit
[Fri Aug 22 18:10:58.031 2008] [23127:2537062720] [trace] ajp_process_callback::jk_ajp_common.c
(1536): enter
[Fri Aug 22 18:10:58.031 2008] [23127:2537062720] [debug] ws_write::mod_jk.c (455): written
9 out of 9
[Fri Aug 22 18:10:58.031 2008] [23127:2537062720] [trace] ajp_process_callback::jk_ajp_common.c
(1680): exit
[Fri Aug 22 18:10:58.031 2008] [23127:2537062720] [trace] ajp_connection_tcp_get_message::jk_ajp_common.c
(992): enter
[Fri Aug 22 18:10:58.031 2008] [23127:2537062720] [trace] jk_tcp_socket_recvfull::jk_connect.c
(768): enter
[Fri Aug 22 18:10:58.031 2008] [23127:2537062720] [trace] jk_tcp_socket_recvfull::jk_connect.c
(796): exit
[Fri Aug 22 18:10:58.031 2008] [23127:2537062720] [trace] jk_tcp_socket_recvfull::jk_connect.c
(768): enter
[Fri Aug 22 18:10:58.031 2008] [23127:2537062720] [trace] jk_tcp_socket_recvfull::jk_connect.c
(796): exit
[Fri Aug 22 18:10:58.032 2008] [23127:2537062720] [debug] ajp_connection_tcp_get_message::jk_ajp_common.c
(1117): received from ajp13 pos=0 l
en=4 max=8192
[Fri Aug 22 18:10:58.032 2008] [23127:2537062720] [debug] ajp_connection_tcp_get_message::jk_ajp_common.c
(1117): 0000    03 00 00 00 00 00 0
0 00 00 00 00 00 00 00 00 00  - ................
[Fri Aug 22 18:10:58.032 2008] [23127:2537062720] [trace] ajp_connection_tcp_get_message::jk_ajp_common.c
(1123): exit
[Fri Aug 22 18:10:58.032 2008] [23127:2537062720] [trace] ajp_process_callback::jk_ajp_common.c
(1536): enter
[Fri Aug 22 18:10:58.033 2008] [23127:2537062720] [trace] ajp_process_callback::jk_ajp_common.c
(1680): exit
[Fri Aug 22 18:10:58.033 2008] [23127:2537062720] [trace] ajp_connection_tcp_get_message::jk_ajp_common.c
(992): enter
[Fri Aug 22 18:10:58.033 2008] [23127:2537062720] [trace] jk_tcp_socket_recvfull::jk_connect.c
(768): enter
[Fri Aug 22 18:10:58.033 2008] [23127:2537062720] [trace] jk_tcp_socket_recvfull::jk_connect.c
(796): exit
[Fri Aug 22 18:10:58.033 2008] [23127:2537062720] [trace] jk_tcp_socket_recvfull::jk_connect.c
(768): enter
[Fri Aug 22 18:10:58.033 2008] [23127:2537062720] [trace] jk_tcp_socket_recvfull::jk_connect.c
(796): exit
[Fri Aug 22 18:10:58.033 2008] [23127:2537062720] [debug] ajp_connection_tcp_get_message::jk_ajp_common.c
(1117): received from ajp13 pos=0 l
en=12 max=8192

... and so on

Looking to tcpdump flow of packets between client and apache web server, I notice a lots of
small packets. With older version of mod_jk, there are instead fewer packets of bigger size.

Leandro

Mime
  • Unnamed multipart/alternative (inline, None, 0 bytes)
View raw message