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=43572>.
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=43572

           Summary: http POST is refused by Tomcat through mod_jk
           Product: Tomcat 5
           Version: 5.5.23
          Platform: Other
        OS/Version: Windows Server 2003
            Status: NEW
          Severity: major
          Priority: P2
         Component: Connector:AJP
        AssignedTo: [EMAIL PROTECTED]
        ReportedBy: [EMAIL PROTECTED]


A (for me) completely normal http POST is refused by Tomcat (returns 503) when
served through mod_jk. The request works fine when served directly (through
Catalina).

It seems that mod_jk receives the request, and that Tomcat thinks it is a bad
request (responds with 400). Then a retry is initiated, but the second time
Tomcat just refuses with 503.

Here is the request:

---------
POST /servlet/FlushServer HTTP/1.1
Connection: close
HOST_SERVICE: FutureTenseContentServer:6.3.0
User-Agent: Jakarta Commons-HttpClient/3.0-rc3
Host: content-test2.website.no:7500
Content-Length: 6450
Content-Type: multipart/form-data; boundary=n9NyrUUS0FIvX9Fqn-iBCN6bhqfLRHoVM

--n9NyrUUS0FIvX9Fqn-iBCN6bhqfLRHoVM
Content-Disposition: form-data; name="password"
Content-Type: text/plain; charset=ISO-8859-1
Content-Transfer-Encoding: 8bit

(topsecret)
--n9NyrUUS0FIvX9Fqn-iBCN6bhqfLRHoVM
Content-Disposition: form-data; name="tags"
Content-Type: text/plain; charset=ISO-8859-1
Content-Transfer-Encoding: 8bit

<com.fatwire.satellite.page pagename="WebSite/RenderAssets"
cachecontrol="default" cid="1181049931138" rendermode="live"
attributeType="Vedlegg_flex" attrid="1098976538607"
attributedef="Informasjonsattributt" site="WebSite" attributename="Vedlegg"
c="Informasjonstekst" /com.fatwire.satellite.page>
<com.fatwire.satellite.page pagename="WebSite/RenderAssets"
cachecontrol="default" cid="1181049931138"
rendermode="preview-frankw-1138643678332" attributeType="Person"
attrid="1137742915171" attributedef="Informasjonsattributt" site="WebSite"
attributename="InterneKontaktpersoner" c="Informasjonstekst"
/com.fatwire.satellite.page>
<com.fatwire.satellite.page pagename="WebSite/RenderText" cachecontrol="default"
cid="1181049931138" rendermode="live" attributedef="Informasjonsattributt"
site="WebSite" attributename="Ingress" c="Informasjonstekst"
/com.fatwire.satellite.page>
<com.fatwire.satellite.page pagename="WebSite/RenderAssets"
cachecontrol="default" cid="1181049931138" rendermode="live"
attributeType="Person" attrid="1137742915171"
attributedef="Informasjonsattributt" site="WebSite"
attributename="InterneKontaktpersoner" c="Informasjonstekst"
/com.fatwire.satellite.page>
<com.fatwire.satellite.page pagename="WebSite/RenderText" cachecontrol="default"
cid="1181049931138" rendermode="preview-frankw-1138643678332"
attributedef="Informasjonsattributt" site="WebSite" attributename="Ingress"
c="Informasjonstekst" /com.fatwire.satellite.page>
<com.fatwire.satellite.page pagename="WebSite/RenderEksternLinkList"
cachecontrol="default" cid="1181049931138" rendermode="live" listtype="updown"
attributetype="Informasjonsattributt" site="WebSite"
attributename="EksterneLinker" c="Informasjonstekst" 
/com.fatwire.satellite.page>
<com.fatwire.satellite.page pagename="WebSite/Informasjonstekst/Detaljvisning"
cachecontrol="default" cid="1181049931138" rendermode="live" p="1181049931162"
site="WebSite" c="Informasjonstekst" /com.fatwire.satellite.page>
<com.fatwire.satellite.page pagename="WebSite/RenderInternLinkList"
cachecontrol="default" cid="1181049931138"
rendermode="preview-frankw-1138643678332" listtype="updown"
attributetype="Informasjonsattributt" site="WebSite"
attributename="InterneLinker" c="Informasjonstekst" /com.fatwire.satellite.page>
<com.fatwire.satellite.page pagename="WebSite/Informasjonstekst/Detaljvisning"
cachecontrol="default" cid="1181049931138"
rendermode="preview-frankw-1138643678332" p="1181049931162" site="WebSite"
c="Informasjonstekst" /com.fatwire.satellite.page>
<com.fatwire.satellite.page pagename="WebSite/RenderAssets"
cachecontrol="default" cid="1181049931138"
rendermode="preview-frankw-1138643678332" attributeType="Person"
attrid="1137742915553" attributedef="Informasjonsattributt" site="WebSite"
attributename="InterneKontaktpersoner" c="Informasjonstekst"
/com.fatwire.satellite.page>
<com.fatwire.satellite.page pagename="WebSite/RenderAssets"
cachecontrol="default" cid="1181049931138" rendermode="live"
attributeType="Vedlegg_flex" attrid="1144676186079"
attributedef="Informasjonsattributt" site="WebSite" attributename="Vedlegg"
c="Informasjonstekst" /com.fatwire.satellite.page>
<com.fatwire.satellite.page pagename="WebSite/RenderAssets"
cachecontrol="default" cid="1181049931138" rendermode="live"
attributeType="Person" attrid="1137742915553"
attributedef="Informasjonsattributt" site="WebSite"
attributename="InterneKontaktpersoner" c="Informasjonstekst"
/com.fatwire.satellite.page>
<com.fatwire.satellite.page pagename="WebSite/RenderEksternLinkList"
cachecontrol="default" cid="1181049931138"
rendermode="preview-frankw-1138643678332" listtype="updown"
attributetype="Informasjonsattributt" site="WebSite"
attributename="EksterneLinker" c="Informasjonstekst" 
/com.fatwire.satellite.page>
<com.fatwire.satellite.page pagename="WebSite/RenderAssets"
cachecontrol="default" cid="1181049931138"
rendermode="preview-frankw-1138643678332" attributeType="Vedlegg_flex"
attrid="1098976538607" attributedef="Informasjonsattributt" site="WebSite"
attributename="Vedlegg" c="Informasjonstekst" /com.fatwire.satellite.page>
<com.fatwire.satellite.page pagename="WebSite/RenderRightColumn"
cachecontrol="default" cid="1181049931138"
rendermode="preview-frankw-1138643678332" attributedef="Informasjonsattributt"
site="WebSite" c="Informasjonstekst" /com.fatwire.satellite.page>
<com.fatwire.satellite.page pagename="WebSite/Informasjonstekst/Detaljvisning"
cachecontrol="default" cid="1181049931138" rendermode="live" p="1173185591012"
site="WebSite" c="Informasjonstekst" /com.fatwire.satellite.page>
<com.fatwire.satellite.page pagename="WebSite/RenderRightColumn"
cachecontrol="default" cid="1181049931138" rendermode="live"
attributedef="Informasjonsattributt" site="WebSite" c="Informasjonstekst"
/com.fatwire.satellite.page>
<com.fatwire.satellite.page pagename="WebSite/RenderAssets"
cachecontrol="default" cid="1181049931138"
rendermode="preview-frankw-1138643678332" attributeType="Vedlegg_flex"
attrid="1144676186079" attributedef="Informasjonsattributt" site="WebSite"
attributename="Vedlegg" c="Informasjonstekst" /com.fatwire.satellite.page>
<com.fatwire.satellite.page pagename="WebSite/RenderInternLinkList"
cachecontrol="default" cid="1181049931138" rendermode="live" listtype="updown"
attributetype="Informasjonsattributt" site="WebSite"
attributename="InterneLinker" c="Informasjonstekst" /com.fatwire.satellite.page>
--n9NyrUUS0FIvX9Fqn-iBCN6bhqfLRHoVM
Content-Disposition: form-data; name="username"
Content-Type: text/plain; charset=ISO-8859-1
Content-Transfer-Encoding: 8bit

admin
--n9NyrUUS0FIvX9Fqn-iBCN6bhqfLRHoVM--

---------

Here is the log output from my mod_jk log:

[Mon Oct 08 14:00:07.156 2007] [632:5336] [debug] jk_uri_worker_map.c (597):
Attempting to map URI '/servlet/FlushServer' from 1 maps
[Mon Oct 08 14:00:07.156 2007] [632:5336] [debug] jk_uri_worker_map.c (609):
Attempting to map context URI '/servlet/*=ajp13' source 'JkMount'
[Mon Oct 08 14:00:07.156 2007] [632:5336] [debug] jk_uri_worker_map.c (624):
Found a wildchar match '/servlet/*=ajp13'
[Mon Oct 08 14:00:07.156 2007] [632:5336] [debug] mod_jk.c (2131): Into handler
jakarta-servlet worker=ajp13 r->proxyreq=0
[Mon Oct 08 14:00:07.156 2007] [632:5336] [debug] jk_worker.c (115): found a
worker ajp13
[Mon Oct 08 14:00:07.156 2007] [632:5336] [debug] jk_worker.c (292): Found
worker type 'ajp13'
[Mon Oct 08 14:00:07.156 2007] [632:5336] [debug] mod_jk.c (811): Service
protocol=HTTP/1.1 method=POST host=(null) addr=158.36.112.211
name=esak-webtest.website.no port=80 auth=(null) user=(null) laddr=158.36.125.14
raddr=158.36.112.211 uri=/servlet/FlushServer
[Mon Oct 08 14:00:07.156 2007] [632:5336] [debug] jk_ajp_common.c (2498):
acquired connection pool slot=0
[Mon Oct 08 14:00:07.156 2007] [632:5336] [debug] jk_ajp_common.c (551): ajp
marshaling done
[Mon Oct 08 14:00:07.156 2007] [632:5336] [debug] jk_ajp_common.c (1935):
processing ajp13 with 2 retries
[Mon Oct 08 14:00:07.156 2007] [632:5336] [debug] jk_ajp_common.c (911): sending
to ajp13 pos=4 len=293 max=8192
[Mon Oct 08 14:00:07.156 2007] [632:5336] [debug] jk_ajp_common.c (911): 0000  
 12 34 01 21 02 04 00 08 48 54 54 50 2F 31 2E 31  - .4.!....HTTP/1.1
[Mon Oct 08 14:00:07.156 2007] [632:5336] [debug] jk_ajp_common.c (911): 0010  
 00 00 14 2F 73 65 72 76 6C 65 74 2F 46 6C 75 73  - .../servlet/Flus
[Mon Oct 08 14:00:07.156 2007] [632:5336] [debug] jk_ajp_common.c (911): 0020  
 68 53 65 72 76 65 72 00 00 0E 31 35 38 2E 33 36  - hServer...158.36
(.....)
[Mon Oct 08 14:00:07.156 2007] [632:5336] [debug] jk_ajp_common.c (911): 0110  
 5A 78 41 6B 65 64 6D 37 2D 44 58 69 42 4A 70 75  - ZxAkedm7-DXiBJpu
[Mon Oct 08 14:00:07.156 2007] [632:5336] [debug] jk_ajp_common.c (911): 0120  
 35 76 6C 00 FF 00 00 00 00 00 00 00 00 00 00 00  - 5vl.............
[Mon Oct 08 14:00:07.156 2007] [632:5336] [debug] jk_ajp_common.c (1325):
(ajp13) request body to send 1690 - request body to resend 0
[Mon Oct 08 14:00:07.156 2007] [632:5336] [debug] jk_ajp_common.c (911): sending
to ajp13 pos=4 len=1696 max=8192
[Mon Oct 08 14:00:07.156 2007] [632:5336] [debug] jk_ajp_common.c (911): 0000  
 12 34 06 9C 06 9A 2D 2D 4B 46 77 34 49 32 51 65  - .4....--KFw4I2Qe
[Mon Oct 08 14:00:07.156 2007] [632:5336] [debug] jk_ajp_common.c (911): 0010  
 71 36 70 5A 78 41 6B 65 64 6D 37 2D 44 58 69 42  - q6pZxAkedm7-DXiB
[Mon Oct 08 14:00:07.156 2007] [632:5336] [debug] jk_ajp_common.c (911): 0020  
 4A 70 75 35 76 6C 0D 0A 43 6F 6E 74 65 6E 74 2D  - Jpu5vl..Content-
[Mon Oct 08 14:00:07.156 2007] [632:5336] [debug] jk_ajp_common.c (911): 0030  
 44 69 73 70 6F 73 69 74 69 6F 6E 3A 20 66 6F 72  - Disposition:.for
(.....)
[Mon Oct 08 14:00:07.156 2007] [632:5336] [debug] jk_ajp_common.c (911): 03f0  
 74 65 6C 6C 69 74 65 2E 70 61 67 65 20 70 61 67  - tellite.page.pag
[Mon Oct 08 14:00:07.156 2007] [632:5336] [debug] jk_ajp_common.c (1058):
received from ajp13 pos=0 len=19 max=8192
[Mon Oct 08 14:00:07.156 2007] [632:5336] [debug] jk_ajp_common.c (1058): 0000 
  04 01 90 00 0B 42 61 64 20 52 65 71 75 65 73 74  - .....Bad.Request
[Mon Oct 08 14:00:07.156 2007] [632:5336] [debug] jk_ajp_common.c (1058): 0010 
  00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00  - ................
[Mon Oct 08 14:00:07.156 2007] [632:5336] [debug] jk_ajp_common.c (606): status
= 400
[Mon Oct 08 14:00:07.156 2007] [632:5336] [debug] jk_ajp_common.c (613): Number
of headers is = 0
[Mon Oct 08 14:00:07.156 2007] [632:5336] [error] jk_ajp_common.c (966): (ajp13)
can't receive the response message from tomcat, network problems or tomcat
(127.0.0.1:8009) is down (errno=53)
[Mon Oct 08 14:00:07.156 2007] [632:5336] [error] jk_ajp_common.c (1699):
(ajp13) Tomcat is down or network problems. Part of the response has already
been sent to the client
[Mon Oct 08 14:00:07.156 2007] [632:5336] [info] jk_ajp_common.c (2046): (ajp13)
receiving from tomcat failed, recoverable operation attempt=0
[Mon Oct 08 14:00:07.156 2007] [632:5336] [info] jk_ajp_common.c (2085): (ajp13)
sending request to tomcat failed,  recoverable operation attempt=1
[Mon Oct 08 14:00:07.156 2007] [632:5336] [debug] jk_ajp_common.c (911): sending
to ajp13 pos=4 len=293 max=8192
[Mon Oct 08 14:00:07.156 2007] [632:5336] [debug] jk_ajp_common.c (911): 0000  
 12 34 01 21 02 04 00 08 48 54 54 50 2F 31 2E 31  - .4.!....HTTP/1.1
[Mon Oct 08 14:00:07.156 2007] [632:5336] [debug] jk_ajp_common.c (911): 0010  
 00 00 14 2F 73 65 72 76 6C 65 74 2F 46 6C 75 73  - .../servlet/Flus
[Mon Oct 08 14:00:07.156 2007] [632:5336] [debug] jk_ajp_common.c (911): 0020  
 68 53 65 72 76 65 72 00 00 0E 31 35 38 2E 33 36  - hServer...158.36
(.....)
[Mon Oct 08 14:00:07.156 2007] [632:5336] [debug] jk_ajp_common.c (911): 0110  
 5A 78 41 6B 65 64 6D 37 2D 44 58 69 42 4A 70 75  - ZxAkedm7-DXiBJpu
[Mon Oct 08 14:00:07.156 2007] [632:5336] [debug] jk_ajp_common.c (911): 0120  
 35 76 6C 00 FF 00 00 00 00 00 00 00 00 00 00 00  - 5vl.............
[Mon Oct 08 14:00:07.156 2007] [632:5336] [debug] jk_ajp_common.c (1325):
(ajp13) request body to send 0 - request body to resend 15
[Mon Oct 08 14:00:07.156 2007] [632:5336] [debug] jk_ajp_common.c (911): sending
to ajp13 pos=4 len=1696 max=8192
[Mon Oct 08 14:00:07.156 2007] [632:5336] [debug] jk_ajp_common.c (911): 0000  
 12 34 06 9C 06 9A 2D 2D 4B 46 77 34 49 32 51 65  - .4....--KFw4I2Qe
[Mon Oct 08 14:00:07.156 2007] [632:5336] [debug] jk_ajp_common.c (911): 0010  
 71 36 70 5A 78 41 6B 65 64 6D 37 2D 44 58 69 42  - q6pZxAkedm7-DXiB
[Mon Oct 08 14:00:07.156 2007] [632:5336] [debug] jk_ajp_common.c (911): 0020  
 4A 70 75 35 76 6C 0D 0A 43 6F 6E 74 65 6E 74 2D  - Jpu5vl..Content-
[Mon Oct 08 14:00:07.156 2007] [632:5336] [debug] jk_ajp_common.c (911): 0030  
 44 69 73 70 6F 73 69 74 69 6F 6E 3A 20 66 6F 72  - Disposition:.for
(.....)
[Mon Oct 08 14:00:07.156 2007] [632:5336] [debug] jk_ajp_common.c (911): 03f0  
 74 65 6C 6C 69 74 65 2E 70 61 67 65 20 70 61 67  - tellite.page.pag
[Mon Oct 08 14:00:07.156 2007] [632:5336] [debug] jk_ajp_common.c (1352): Resent
the request body (1696)
[Mon Oct 08 14:00:07.156 2007] [632:5336] [error] jk_ajp_common.c (966): (ajp13)
can't receive the response message from tomcat, network problems or tomcat
(127.0.0.1:8009) is down (errno=53)
[Mon Oct 08 14:00:07.156 2007] [632:5336] [error] jk_ajp_common.c (1658):
(ajp13) Tomcat is down or refused connection. No response has been sent to the
client (yet)
[Mon Oct 08 14:00:07.156 2007] [632:5336] [info] jk_ajp_common.c (2046): (ajp13)
receiving from tomcat failed, recoverable operation attempt=1
[Mon Oct 08 14:00:07.156 2007] [632:5336] [info] jk_ajp_common.c (2085): (ajp13)
sending request to tomcat failed,  recoverable operation attempt=2
[Mon Oct 08 14:00:07.156 2007] [632:5336] [error] jk_ajp_common.c (2097):
(ajp13) Connecting to tomcat failed. Tomcat is probably not started or is
listening on the wrong port
[Mon Oct 08 14:00:07.156 2007] [632:5336] [debug] jk_ajp_common.c (2441):
recycling connection pool slot=0 for worker ajp13
[Mon Oct 08 14:00:07.156 2007] [632:5336] [info] mod_jk.c (2277): Service
error=0 for worker=ajp13

-- 
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]

Reply via email to