DO NOT REPLY TO THIS EMAIL, BUT PLEASE POST YOUR BUG· RELATED COMMENTS THROUGH THE WEB INTERFACE AVAILABLE AT <http://issues.apache.org/bugzilla/show_bug.cgi?id=41244>. ANY REPLY MADE TO THIS MESSAGE WILL NOT BE COLLECTED AND· INSERTED IN THE BUG DATABASE.
http://issues.apache.org/bugzilla/show_bug.cgi?id=41244 Summary: webservice :no response using connector AJP with tomcat >=5.5.10 Product: Tomcat 5 Version: 5.5.10 Platform: Other OS/Version: other Status: NEW Severity: normal Priority: P2 Component: Connector:AJP AssignedTo: tomcat-dev@jakarta.apache.org ReportedBy: [EMAIL PROTECTED] Hello, I am using webservice (made by commons-httpclient3.0rc3) with an apache-httpd link to tomcat with AJP1.3 connector. When I send the request directly to tomcat, there is no problem whereas when I send it to apache-httpd, there is no answer receive by the client. I can reproduce this problem with a tomcat on linux or on windows. I have tried various versions of tomcat and finally I have this result : I have no response with tomcat >=5.5.10 but if I use an older (<=5.5.9) there is no problem and my client get a response. So I think that the modification made into the connector have made a regression. more precisely, I have tried various request for my webservice (POST message) : If I use HTTP1.0, there is no problem. If I use HTTP1.1 whith a content-lenght, there is no problem, but if I used HTTP1.1 with a chunk mode transfert, there is no response. With tomcat launch by eclipse, I can see that the authentification is made (an authentification is requiered for the use of my webservice), but nothing is done after. Moreover, in the log of mod_jk, I have this information : Fri Dec 22 11:22:22 2006] [29700:1728] [debug] ajp_send_request::jk_ajp_common.c (1261): request body to send 0 - request body to resend 0. Here is my request : [EMAIL PROTECTED] ~]# /tmp/ngrep -W byline port 80 interface: eth0 (172.26.224.0/255.255.248.0) filter: (ip) and ( port 80 ) #### T 10.67.188.74:4806 -> 172.26.227.231:80 [AP] POST /pac-server/services/contact HTTP/1.1. Content-Type: text/xml; charset=utf-8. SOAPAction: "". Authorization: Basic cGVyZjA6cGVyZjA=. User-Agent: Jakarta Commons-HttpClient/3.0-rc3. Host: paplx2. Transfer-Encoding: chunked. . 46b. <?xml version="1.0" encoding="UTF-8"?><soapenv:Envelope xmlns:soapenv="http://schemas.xmlsoap.org/soap/envelope/"; xmlns:xsd="http://www.w3.org/2001/XMLSchema"; xmlns:xsi="http://www.w3.org/2001/XMLSchema- instance"><soapenv:Body><ns1:changementTarif soapenv:encodingStyle="http://schemas.xmlsoap.org/soap/encoding/"; xmlns:ns1="urn:francetelecom.com/pac"><in0 href="#id0"/><in1 href="#id1"/></ns1:changementTarif><multiRef id="id0" soapenc:root="0" soapenv:encodingStyle="http://schemas.xmlsoap.org/soap/encoding/"; xsi:type="ns2:ReferenceAppel" xmlns:soapenc="http://schemas.xmlsoap.org/soap/encoding/"; xmlns:ns2="http://domain.business.server.pac.capgemini.com";><idBepPcs xsi:type="xsd:string">1</idBepPcs><identifiantAppel xsi:type="xsd:string">23456</identifiantAppel></multiRef><multiRef id="id1" soapenc:root="0" soapenv:encodingStyle="http://schemas.xmlsoap.org/soap/encoding/"; xsi:type="ns3:PalierTarifaire" xmlns:ns3="http://domain.business.server.pac.capgemini.com"; xmlns:soapenc="http://schemas.xmlsoap.org/soap/encoding/";><codeTarifaire xsi:type="xsd:string">085</codeTarifaire></multiRef></soapenv:Body></soapenv:En velope>. 0. . #exit 7 received, 0 dropped [EMAIL PROTECTED] ~]# The log with the 1.2.19 mod_jk : [Fri Dec 22 11:22:19 2006] [29696:1728] [debug] do_shm_open::jk_shm.c (295): Truncated shared memory to 24704 [Fri Dec 22 11:22:19 2006] [29696:1728] [debug] do_shm_open::jk_shm.c (327): Initialized shared memory size=24704 free=24576 addr=0xb7f39000 [Fri Dec 22 11:22:19 2006] [29696:1728] [debug] do_shm_open_lock::jk_shm.c (234): Opened shared memory lock /etc/httpd/logs/jk-runtime-status.lock [Fri Dec 22 11:22:19 2006] [29696:1728] [debug] init_jk::mod_jk.c (2444): Initialized shm:/etc/httpd/logs/jk-runtime-status [Fri Dec 22 11:22:19 2006] [29696:1728] [debug] uri_worker_map_open::jk_uri_worker_map.c (361): rule map size is 0 [Fri Dec 22 11:22:19 2006] [29696:1728] [debug] jk_map_resolve_references::jk_map.c (638): Checking for references with prefix worker. with wildcard (recursion 1) [Fri Dec 22 11:22:19 2006] [29696:1728] [debug] build_worker_map::jk_worker.c (236): creating worker 45hpac [Fri Dec 22 11:22:19 2006] [29696:1728] [debug] wc_create_worker::jk_worker.c (141): about to create instance 45hpac of ajp13 [Fri Dec 22 11:22:19 2006] [29696:1728] [debug] wc_create_worker::jk_worker.c (154): about to validate and init 45hpac [Fri Dec 22 11:22:19 2006] [29696:1728] [debug] ajp_validate::jk_ajp_common.c (1922): worker 45hpac contact is 'paplx2:9049' [Fri Dec 22 11:22:19 2006] [29696:1728] [debug] ajp_init::jk_ajp_common.c (2047): setting endpoint options: [Fri Dec 22 11:22:19 2006] [29696:1728] [debug] ajp_init::jk_ajp_common.c (2050): keepalive: 0 [Fri Dec 22 11:22:19 2006] [29696:1728] [debug] ajp_init::jk_ajp_common.c (2054): timeout: -1 [Fri Dec 22 11:22:19 2006] [29696:1728] [debug] ajp_init::jk_ajp_common.c (2058): buffer size: 0 [Fri Dec 22 11:22:19 2006] [29696:1728] [debug] ajp_init::jk_ajp_common.c (2062): pool timeout: 0 [Fri Dec 22 11:22:19 2006] [29696:1728] [debug] ajp_init::jk_ajp_common.c (2066): connect timeout: 0 [Fri Dec 22 11:22:19 2006] [29696:1728] [debug] ajp_init::jk_ajp_common.c (2070): reply timeout: 0 [Fri Dec 22 11:22:19 2006] [29696:1728] [debug] ajp_init::jk_ajp_common.c (2074): prepost timeout: 0 [Fri Dec 22 11:22:19 2006] [29696:1728] [debug] ajp_init::jk_ajp_common.c (2078): recovery options: 0 [Fri Dec 22 11:22:19 2006] [29696:1728] [debug] ajp_init::jk_ajp_common.c (2082): retries: 2 [Fri Dec 22 11:22:19 2006] [29696:1728] [debug] ajp_init::jk_ajp_common.c (2086): max packet size: 8192 [Fri Dec 22 11:22:19 2006] [29696:1728] [debug] ajp_create_endpoint_cache::jk_ajp_common.c (1959): setting connection pool size to 1 with min 0 [Fri Dec 22 11:22:20 2006] [29699:1728] [debug] do_shm_open::jk_shm.c (295): Truncated shared memory to 24704 [Fri Dec 22 11:22:20 2006] [29699:1728] [debug] do_shm_open::jk_shm.c (327): Initialized shared memory size=24704 free=24576 addr=0xb7ea2000 [Fri Dec 22 11:22:20 2006] [29699:1728] [debug] do_shm_open_lock::jk_shm.c (234): Opened shared memory lock /etc/httpd/logs/jk-runtime-status.lock [Fri Dec 22 11:22:20 2006] [29699:1728] [debug] init_jk::mod_jk.c (2444): Initialized shm:/etc/httpd/logs/jk-runtime-status [Fri Dec 22 11:22:20 2006] [29699:1728] [debug] uri_worker_map_open::jk_uri_worker_map.c (361): rule map size is 0 [Fri Dec 22 11:22:20 2006] [29699:1728] [debug] jk_map_resolve_references::jk_map.c (638): Checking for references with prefix worker. with wildcard (recursion 1) [Fri Dec 22 11:22:20 2006] [29699:1728] [debug] build_worker_map::jk_worker.c (236): creating worker 45hpac [Fri Dec 22 11:22:20 2006] [29699:1728] [debug] wc_create_worker::jk_worker.c (141): about to create instance 45hpac of ajp13 [Fri Dec 22 11:22:20 2006] [29699:1728] [debug] wc_create_worker::jk_worker.c (154): about to validate and init 45hpac [Fri Dec 22 11:22:20 2006] [29699:1728] [debug] ajp_validate::jk_ajp_common.c (1922): worker 45hpac contact is 'paplx2:9049' [Fri Dec 22 11:22:20 2006] [29699:1728] [debug] ajp_init::jk_ajp_common.c (2047): setting endpoint options: [Fri Dec 22 11:22:20 2006] [29699:1728] [debug] ajp_init::jk_ajp_common.c (2050): keepalive: 0 [Fri Dec 22 11:22:20 2006] [29699:1728] [debug] ajp_init::jk_ajp_common.c (2054): timeout: -1 [Fri Dec 22 11:22:20 2006] [29699:1728] [debug] ajp_init::jk_ajp_common.c (2058): buffer size: 0 [Fri Dec 22 11:22:20 2006] [29699:1728] [debug] ajp_init::jk_ajp_common.c (2062): pool timeout: 0 [Fri Dec 22 11:22:20 2006] [29699:1728] [debug] ajp_init::jk_ajp_common.c (2066): connect timeout: 0 [Fri Dec 22 11:22:20 2006] [29699:1728] [debug] ajp_init::jk_ajp_common.c (2070): reply timeout: 0 [Fri Dec 22 11:22:20 2006] [29699:1728] [debug] ajp_init::jk_ajp_common.c (2074): prepost timeout: 0 [Fri Dec 22 11:22:20 2006] [29699:1728] [debug] ajp_init::jk_ajp_common.c (2078): recovery options: 0 [Fri Dec 22 11:22:20 2006] [29699:1728] [debug] ajp_init::jk_ajp_common.c (2082): retries: 2 [Fri Dec 22 11:22:20 2006] [29699:1728] [debug] ajp_init::jk_ajp_common.c (2086): max packet size: 8192 [Fri Dec 22 11:22:20 2006] [29699:1728] [debug] ajp_create_endpoint_cache::jk_ajp_common.c (1959): setting connection pool size to 1 with min 0 [Fri Dec 22 11:22:20 2006] [29700:1728] [debug] do_shm_open::jk_shm.c (252): Shared memory is already open [Fri Dec 22 11:22:20 2006] [29700:1728] [debug] jk_child_init::mod_jk.c (2400): Attached shm:/etc/httpd/logs/jk-runtime-status [Fri Dec 22 11:22:20 2006] [29700:1728] [debug] jk_child_init::mod_jk.c (2410): Initialized mod_jk/1.2.19 [Fri Dec 22 11:22:20 2006] [29701:1728] [debug] do_shm_open::jk_shm.c (252): Shared memory is already open [Fri Dec 22 11:22:20 2006] [29701:1728] [debug] jk_child_init::mod_jk.c (2400): Attached shm:/etc/httpd/logs/jk-runtime-status [Fri Dec 22 11:22:20 2006] [29701:1728] [debug] jk_child_init::mod_jk.c (2410): Initialized mod_jk/1.2.19 [Fri Dec 22 11:22:20 2006] [29702:1728] [debug] do_shm_open::jk_shm.c (252): Shared memory is already open [Fri Dec 22 11:22:20 2006] [29702:1728] [debug] jk_child_init::mod_jk.c (2400): Attached shm:/etc/httpd/logs/jk-runtime-status [Fri Dec 22 11:22:20 2006] [29702:1728] [debug] jk_child_init::mod_jk.c (2410): Initialized mod_jk/1.2.19 [Fri Dec 22 11:22:20 2006] [29703:1728] [debug] do_shm_open::jk_shm.c (252): Shared memory is already open [Fri Dec 22 11:22:20 2006] [29703:1728] [debug] jk_child_init::mod_jk.c (2400): Attached shm:/etc/httpd/logs/jk-runtime-status [Fri Dec 22 11:22:20 2006] [29703:1728] [debug] jk_child_init::mod_jk.c (2410): Initialized mod_jk/1.2.19 [Fri Dec 22 11:22:20 2006] [29704:1728] [debug] do_shm_open::jk_shm.c (252): Shared memory is already open [Fri Dec 22 11:22:20 2006] [29704:1728] [debug] jk_child_init::mod_jk.c (2400): Attached shm:/etc/httpd/logs/jk-runtime-status [Fri Dec 22 11:22:20 2006] [29704:1728] [debug] jk_child_init::mod_jk.c (2410): Initialized mod_jk/1.2.19 [Fri Dec 22 11:22:20 2006] [29705:1728] [debug] do_shm_open::jk_shm.c (252): Shared memory is already open [Fri Dec 22 11:22:20 2006] [29705:1728] [debug] jk_child_init::mod_jk.c (2400): Attached shm:/etc/httpd/logs/jk-runtime-status [Fri Dec 22 11:22:20 2006] [29705:1728] [debug] jk_child_init::mod_jk.c (2410): Initialized mod_jk/1.2.19 [Fri Dec 22 11:22:20 2006] [29706:1728] [debug] do_shm_open::jk_shm.c (252): Shared memory is already open [Fri Dec 22 11:22:20 2006] [29706:1728] [debug] jk_child_init::mod_jk.c (2400): Attached shm:/etc/httpd/logs/jk-runtime-status [Fri Dec 22 11:22:20 2006] [29706:1728] [debug] jk_child_init::mod_jk.c (2410): Initialized mod_jk/1.2.19 [Fri Dec 22 11:22:20 2006] [29707:1728] [debug] do_shm_open::jk_shm.c (252): Shared memory is already open [Fri Dec 22 11:22:20 2006] [29707:1728] [debug] jk_child_init::mod_jk.c (2400): Attached shm:/etc/httpd/logs/jk-runtime-status [Fri Dec 22 11:22:20 2006] [29707:1728] [debug] jk_child_init::mod_jk.c (2410): Initialized mod_jk/1.2.19 [Fri Dec 22 11:22:20 2006] [29708:1728] [debug] do_shm_open::jk_shm.c (252): Shared memory is already open [Fri Dec 22 11:22:20 2006] [29708:1728] [debug] jk_child_init::mod_jk.c (2400): Attached shm:/etc/httpd/logs/jk-runtime-status [Fri Dec 22 11:22:20 2006] [29708:1728] [debug] jk_child_init::mod_jk.c (2410): Initialized mod_jk/1.2.19 [Fri Dec 22 11:22:20 2006] [29709:1728] [debug] do_shm_open::jk_shm.c (252): Shared memory is already open [Fri Dec 22 11:22:20 2006] [29709:1728] [debug] jk_child_init::mod_jk.c (2400): Attached shm:/etc/httpd/logs/jk-runtime-status [Fri Dec 22 11:22:20 2006] [29709:1728] [debug] jk_child_init::mod_jk.c (2410): Initialized mod_jk/1.2.19 [Fri Dec 22 11:22:22 2006] [29700:1728] [debug] map_uri_to_worker::jk_uri_worker_map.c (513): Attempting to map URI '/pac- server/services/contact' from 10 maps [Fri Dec 22 11:22:22 2006] [29700:1728] [debug] map_uri_to_worker::jk_uri_worker_map.c (525): Attempting to map context URI '/jonas-javadoc/*' [Fri Dec 22 11:22:22 2006] [29700:1728] [debug] map_uri_to_worker::jk_uri_worker_map.c (525): Attempting to map context URI '/jonas-doc-en/*' [Fri Dec 22 11:22:22 2006] [29700:1728] [debug] map_uri_to_worker::jk_uri_worker_map.c (525): Attempting to map context URI '/jrockit-docs/*' [Fri Dec 22 11:22:22 2006] [29700:1728] [debug] map_uri_to_worker::jk_uri_worker_map.c (525): Attempting to map context URI '/tomcat-docs/*' [Fri Dec 22 11:22:22 2006] [29700:1728] [debug] map_uri_to_worker::jk_uri_worker_map.c (525): Attempting to map context URI '/jonasAdmin/*' [Fri Dec 22 11:22:22 2006] [29700:1728] [debug] map_uri_to_worker::jk_uri_worker_map.c (525): Attempting to map context URI '/pac-server/*' [Fri Dec 22 11:22:22 2006] [29700:1728] [debug] map_uri_to_worker::jk_uri_worker_map.c (539): Found a wildchar match 45hpac - > /pac-server/* [Fri Dec 22 11:22:22 2006] [29700:1728] [debug] jk_handler::mod_jk.c (1920): Into handler jakarta-servlet worker=45hpac r->proxyreq=0 [Fri Dec 22 11:22:22 2006] [29700:1728] [debug] wc_get_worker_for_name::jk_worker.c (111): found a worker 45hpac [Fri Dec 22 11:22:22 2006] [29700:1728] [debug] wc_maintain::jk_worker.c (318): Maintaining worker 45hpac [Fri Dec 22 11:22:22 2006] [29700:1728] [debug] wc_get_name_for_type::jk_worker.c (287): Found worker type 'ajp13' [Fri Dec 22 11:22:22 2006] [29700:1728] [debug] init_ws_service::mod_jk.c (568): Service protocol=HTTP/1.1 method=POST host=(null) addr=10.67.188.74 name=paplx2 port=80 auth=(null) user=(null) laddr=172.26.227.231 raddr=10.67.188.74 [Fri Dec 22 11:22:22 2006] [29700:1728] [debug] ajp_get_endpoint::jk_ajp_common.c (2251): acquired connection pool slot=0 [Fri Dec 22 11:22:22 2006] [29700:1728] [debug] ajp_marshal_into_msgb::jk_ajp_common.c (547): ajp marshaling done [Fri Dec 22 11:22:22 2006] [29700:1728] [debug] ajp_service::jk_ajp_common.c (1734): processing 45hpac with 2 retries [Fri Dec 22 11:22:22 2006] [29700:1728] [debug] jk_open_socket::jk_connect.c (334): socket TCP_NODELAY set to On [Fri Dec 22 11:22:22 2006] [29700:1728] [debug] jk_open_socket::jk_connect.c (432): trying to connect socket 17 to 172.26.227.231:9049 [Fri Dec 22 11:22:22 2006] [29700:1728] [debug] jk_open_socket::jk_connect.c (458): socket 17 connected to 172.26.227.231:9049 [Fri Dec 22 11:22:22 2006] [29700:1728] [debug] ajp_connect_to_endpoint::jk_ajp_common.c (846): Connected socket 17 to (172.26.227.231:9049) [Fri Dec 22 11:22:22 2006] [29700:1728] [debug] ajp_connection_tcp_send_message::jk_ajp_common.c (892): sending to ajp13 pos=4 len=233 max=8192 [Fri Dec 22 11:22:22 2006] [29700:1728] [debug] ajp_connection_tcp_send_message::jk_ajp_common.c (892): 0000 12 34 00 E5 02 04 00 08 48 54 54 50 2F 31 2E 31 - .4......HTTP/1.1 [Fri Dec 22 11:22:22 2006] [29700:1728] [debug] ajp_connection_tcp_send_message::jk_ajp_common.c (892): 0010 00 00 1C 2F 70 61 63 2D 73 65 72 76 65 72 2F 73 - .../pac-server/s [Fri Dec 22 11:22:22 2006] [29700:1728] [debug] ajp_connection_tcp_send_message::jk_ajp_common.c (892): 0020 65 72 76 69 63 65 73 2F 63 6F 6E 74 61 63 74 00 - ervices/contact. [Fri Dec 22 11:22:22 2006] [29700:1728] [debug] ajp_connection_tcp_send_message::jk_ajp_common.c (892): 0030 00 0C 31 30 2E 36 37 2E 31 38 38 2E 37 34 00 FF - ..10.67.188.74.. [Fri Dec 22 11:22:22 2006] [29700:1728] [debug] ajp_connection_tcp_send_message::jk_ajp_common.c (892): 0040 FF 00 06 70 61 70 6C 78 32 00 00 50 00 00 06 A0 - ...paplx2..P.... [Fri Dec 22 11:22:22 2006] [29700:1728] [debug] ajp_connection_tcp_send_message::jk_ajp_common.c (892): 0050 07 00 17 74 65 78 74 2F 78 6D 6C 3B 20 63 68 61 - ...text/xml;.cha [Fri Dec 22 11:22:22 2006] [29700:1728] [debug] ajp_connection_tcp_send_message::jk_ajp_common.c (892): 0060 72 73 65 74 3D 75 74 66 2D 38 00 00 0A 53 4F 41 - rset=utf-8...SOA [Fri Dec 22 11:22:22 2006] [29700:1728] [debug] ajp_connection_tcp_send_message::jk_ajp_common.c (892): 0070 50 41 63 74 69 6F 6E 00 00 02 22 22 00 A0 05 00 - PAction..."".... [Fri Dec 22 11:22:22 2006] [29700:1728] [debug] ajp_connection_tcp_send_message::jk_ajp_common.c (892): 0080 16 42 61 73 69 63 20 63 47 56 79 5A 6A 41 36 63 - .Basic.cGVyZjA6c [Fri Dec 22 11:22:22 2006] [29700:1728] [debug] ajp_connection_tcp_send_message::jk_ajp_common.c (892): 0090 47 56 79 5A 6A 41 3D 00 A0 0E 00 22 4A 61 6B 61 - GVyZjA=...."Jaka [Fri Dec 22 11:22:22 2006] [29700:1728] [debug] ajp_connection_tcp_send_message::jk_ajp_common.c (892): 00a0 72 74 61 20 43 6F 6D 6D 6F 6E 73 2D 48 74 74 70 - rta.Commons-Http [Fri Dec 22 11:22:22 2006] [29700:1728] [debug] ajp_connection_tcp_send_message::jk_ajp_common.c (892): 00b0 43 6C 69 65 6E 74 2F 33 2E 30 2D 72 63 33 00 A0 - Client/3.0-rc3.. [Fri Dec 22 11:22:22 2006] [29700:1728] [debug] ajp_connection_tcp_send_message::jk_ajp_common.c (892): 00c0 0B 00 06 70 61 70 6C 78 32 00 00 11 54 72 61 6E - ...paplx2...Tran [Fri Dec 22 11:22:22 2006] [29700:1728] [debug] ajp_connection_tcp_send_message::jk_ajp_common.c (892): 00d0 73 66 65 72 2D 45 6E 63 6F 64 69 6E 67 00 00 07 - sfer-Encoding... [Fri Dec 22 11:22:22 2006] [29700:1728] [debug] ajp_connection_tcp_send_message::jk_ajp_common.c (892): 00e0 63 68 75 6E 6B 65 64 00 FF 00 00 00 00 00 00 00 - chunked......... [Fri Dec 22 11:22:22 2006] [29700:1728] [debug] ajp_send_request::jk_ajp_common.c (1261): request body to send 0 - request body to resend 0 [Fri Dec 22 11:22:23 2006] [29720:1728] [debug] do_shm_open::jk_shm.c (252): Shared memory is already open [Fri Dec 22 11:22:23 2006] [29720:1728] [debug] jk_child_init::mod_jk.c (2400): Attached shm:/etc/httpd/logs/jk-runtime-status [Fri Dec 22 11:22:23 2006] [29720:1728] [debug] jk_child_init::mod_jk.c (2410): Initialized mod_jk/1.2.19 Can I have some log from the connector AJP ? how ? Do you have an idea for this problem ? Thank's for your help. Gael -- Configure bugmail: http://issues.apache.org/bugzilla/userprefs.cgi?tab=email ------- You are receiving this mail because: ------- You are the assignee for the bug, or are watching the assignee. --------------------------------------------------------------------- To unsubscribe, e-mail: [EMAIL PROTECTED] For additional commands, e-mail: [EMAIL PROTECTED]
