My Oracle Support Banner

"Error reading POST data from client" Error Occurs Intermittently (Doc ID 2189967.1)

Last updated on OCTOBER 10, 2018

Applies to:

Oracle HTTP Server - Version 11.1.1.0.0 to 12.2.1.1.0 [Release Oracle11g to 12c]
Information in this document applies to any platform.

Symptoms

OHS 12.2.1.0 and wlsplugin 12.2.1.0 and WLS 10.3.6 in Linux x86-64 are being used.

During stress testing a POST request (which normally takes 3 seconds), "Error reading POST data from client" error occurs.

This error occurs intermittently.

"error_log" file
----------------
...
[Thu Sep 22 13:12:58.818550 2016] [authz_core:debug] [pid 29504:tid 140063553840896] mod_authz_core.c(870): [client 123.123.123.123:45907] AH01628: authorization result: granted (no directives)
[Thu Sep 22 13:12:58.818634 2016] [weblogic:debug] [pid 29504:tid 140063553840896] ApacheProxy.cpp(876): [client 123.123.123.123:45907] <005FH3WV^TvBp2d5xft1iW0007Go00001F> ================New Request: [POST /TestPostRequest.http HTTP/1.1] =================
[Thu Sep 22 13:12:58.818663 2016] [weblogic:debug] [pid 29504:tid 140063553840896] ApacheProxy.cpp(1032): [client 123.123.123.123:45907] <005FH3WV^TvBp2d5xft1iW0007Go00001F> Using Uri /TestPostRequest.http
[Thu Sep 22 13:12:58.818675 2016] [weblogic:debug] [pid 29504:tid 140063553840896] ApacheProxy.cpp(1052): [client 123.123.123.123:45907] <005FH3WV^TvBp2d5xft1iW0007Go00001F> After trimming path: '/TestPostRequest.http'
[Thu Sep 22 13:12:58.818680 2016] [weblogic:debug] [pid 29504:tid 140063553840896] ApacheProxy.cpp(1128): [client 123.123.123.123:45907] <005FH3WV^TvBp2d5xft1iW0007Go00001F> The final request string is '/TestPostRequest.http'
[Thu Sep 22 13:12:58.818693 2016] [weblogic:debug] [pid 29504:tid 140063553840896] BaseProxy.cpp(429): [client 123.123.123.123:45907] <005FH3WV^TvBp2d5xft1iW0007Go00001F> SEARCHING id=[123.123.123.123:23201] from current ID=[123.123.123.123:23201]
[Thu Sep 22 13:12:58.818705 2016] [weblogic:debug] [pid 29504:tid 140063553840896] BaseProxy.cpp(438): [client 123.123.123.123:45907] <005FH3WV^TvBp2d5xft1iW0007Go00001F> The two ids matched
[Thu Sep 22 13:12:58.818709 2016] [weblogic:debug] [pid 29504:tid 140063553840896] BaseProxy.cpp(455): [client 123.123.123.123:45907] <005FH3WV^TvBp2d5xft1iW0007Go00001F> @@@FOUND...id=[123.123.123.123:23201], server_name=[host01.localhost], server_port=[23290]
[Thu Sep 22 13:12:58.818717 2016] [weblogic:debug] [pid 29504:tid 140063553840896] ap_proxy.cpp(460): [client 123.123.123.123:45907] <005FH3WV^TvBp2d5xft1iW0007Go00001F> Going to get the post data of size=1449 clength=0
[Thu Sep 22 13:13:28.079462 2016] [authz_core:debug] [pid 989:tid 140063532861184] mod_authz_core.c(842): [client 127.0.0.1:52329] AH01626: authorization result of Require all granted: granted
[Thu Sep 22 13:13:28.079522 2016] [authz_core:debug] [pid 989:tid 140063532861184] mod_authz_core.c(842): [client 127.0.0.1:52329] AH01626: authorization result of <RequireAny>: granted
[Thu Sep 22 13:13:58.149761 2016] [authz_core:debug] [pid 989:tid 140063522371328] mod_authz_core.c(842): [client 127.0.0.1:52337] AH01626: authorization result of Require all granted: granted
[Thu Sep 22 13:13:58.149818 2016] [authz_core:debug] [pid 989:tid 140063522371328] mod_authz_core.c(842): [client 127.0.0.1:52337] AH01626: authorization result of <RequireAny>: granted
[Thu Sep 22 13:13:58.863907 2016] [weblogic:error] [pid 29504:tid 140063553840896] [client 123.123.123.123:45907] <005FH3WV^TvBp2d5xft1iW0007Go00001F> Error reading POST data from client \n
[Thu Sep 22 13:14:28.222719 2016] [authz_core:debug] [pid 989:tid 140063511881472] mod_authz_core.c(842): [client 127.0.0.1:52345] AH01626: authorization result of Require all granted: granted
[Thu Sep 22 13:14:28.222760 2016] [authz_core:debug] [pid 989:tid 140063511881472] mod_authz_core.c(842): [client 127.0.0.1:52345] AH01626: authorization result of <RequireAny>: granted
[Thu Sep 22 13:14:28.785788 2016] [weblogic:debug] [pid 29504:tid 140063417472768] ap_proxy.cpp(839): BEFORE acquire_lock
[Thu Sep 22 13:14:28.785821 2016] [weblogic:debug] [pid 29504:tid 140063417472768] ap_proxy.cpp(849): AFTER acquire_lock
[Thu Sep 22 13:14:28.785825 2016] [weblogic:debug] [pid 29504:tid 140063417472768] ap_proxy.cpp(852): List size is 1
[Thu Sep 22 13:14:28.785829 2016] [weblogic:debug] [pid 29504:tid 140063417472768] ap_proxy.cpp(859): Cleaning up the list node '123.123.123.123:23201'list Length '1''
[Thu Sep 22 13:14:28.785834 2016] [weblogic:debug] [pid 29504:tid 140063417472768] ap_proxy.cpp(879): stale connections: KA = 20, delta = 100'
[Thu Sep 22 13:14:28.785837 2016] [weblogic:debug] [pid 29504:tid 140063417472768] ap_proxy.cpp(889): Cleaning up stale connections at host '123.123.123.123/23201/23201''

 

Changes

No changes.

Cause

To view full details, sign in with your My Oracle Support account.

Don't have a My Oracle Support account? Click to get started!


In this Document
Symptoms
Changes
Cause
Solution

My Oracle Support provides customers with access to over a million knowledge articles and a vibrant support community of peers and Oracle experts.