Mailing List Archive: Apache 502 server error intermitten

Hello, I am getting intermitten 502 errors from calling a tomcat service uri path. Can someone help me out with the errors in the trace logs below?

Sun Nov 14 18:09:26.219600 2021] [core:trace6] [pid 29761] core_filters.c(525): [client 169.22.5.105:16430] core_output_filter: flushing because of FLUSH bucket
[Sun Nov 14 18:09:26.219622 2021] [ssl:trace3] [pid 29761] ssl_engine_kernel.c(1780): [client 169.22.5.105:16430] OpenSSL: Loop: SSLv3 flush data
[Sun Nov 14 18:09:26.219629 2021] [ssl:trace3] [pid 29761] ssl_engine_kernel.c(1775): [client 169.22.5.105:16430] OpenSSL: Handshake: done
[Sun Nov 14 18:09:26.219636 2021] [ssl:debug] [pid 29761] ssl_engine_kernel.c(1824): [client 169.22.5.105:16430] AH02041: Protocol: TLSv1.2, Cipher: AES128-SHA (128/128 bits)
[Sun Nov 14 18:09:26.219641 2021] [core:trace6] [pid 29761] core_filters.c(525): [client 169.22.5.105:16430] core_output_filter: flushing because of FLUSH bucket
[Sun Nov 14 18:09:26.219932 2021] [ssl:trace4] [pid 29761] ssl_engine_io.c(2070): [client 169.22.5.105:16430] OpenSSL: read 5/5 bytes from BIO#563f94b8d1e0 [mem: 563f94cb9393] (BIO dump follows)
[Sun Nov 14 18:09:26.219936 2021] [core:trace6] [pid 29761] core_filters.c(525): [client 169.22.5.105:16430] core_output_filter: flushing because of FLUSH bucket
[Sun Nov 14 18:09:26.219938 2021] [ssl:trace4] [pid 29761] ssl_engine_io.c(2070): [client 169.22.5.105:16430] OpenSSL: read 48/48 bytes from BIO#563f94b8d1e0 [mem: 563f94cb9398] (BIO dump follows)
[Sun Nov 14 18:09:26.219946 2021] [core:trace5] [pid 29761] protocol.c(656): [client 169.22.5.105:16430] Request received from client: GET /
[Sun Nov 14 18:09:26.219967 2021] [ssl:debug] [pid 29761] ssl_engine_kernel.c(225): [client 169.22.5.105:16430] AH02034: Initial (No.1) HTTPS request received for child 1 (server testserver07.eng.rr.com:443)
[Sun Nov 14 18:09:26.219971 2021] [http:trace4] [pid 29761] http_request.c(323): [client 169.22.5.105:16430] Headers received from client:
[Sun Nov 14 18:09:26.219995 2021] [authz_core:debug] [pid 29761] mod_authz_core.c(835): [client 169.22.5.105:16430] AH01628: authorization result: granted (no directives)
[Sun Nov 14 18:09:26.219998 2021] [core:trace3] [pid 29761] request.c(312): [client 169.22.5.105:16430] request authorized without authentication by access_checker_ex hook: /
[Sun Nov 14 18:09:26.220010 2021] [proxy_http:trace1] [pid 29761] mod_proxy_http.c(60): [client 169.22.5.105:16430] HTTP: canonicalising URL //localhost:8080/
[Sun Nov 14 18:09:26.220043 2021] [proxy:trace2] [pid 29761] proxy_util.c(1991): [client 169.22.5.105:16430] http: found worker http://localhost:8080/ for http://localhost:8080/
[Sun Nov 14 18:09:26.220046 2021] [proxy:debug] [pid 29761] mod_proxy.c(1123): [client 169.22.5.105:16430] AH01143: Running scheme http handler (attempt 0)
[Sun Nov 14 18:09:26.220050 2021] [proxy_ajp:debug] [pid 29761] mod_proxy_ajp.c(722): [client 169.22.5.105:16430] AH00894: declining URL http://localhost:8080/
[Sun Nov 14 18:09:26.220053 2021] [proxy_fcgi:debug] [pid 29761] mod_proxy_fcgi.c(972): [client 169.22.5.105:16430] AH01076: url: http://localhost:8080/ proxyname: (null) proxyport: 0
[Sun Nov 14 18:09:26.220055 2021] [proxy_fcgi:debug] [pid 29761] mod_proxy_fcgi.c(975): [client 169.22.5.105:16430] AH01077: declining URL http://localhost:8080/
[Sun Nov 14 18:09:26.220059 2021] [proxy_http:trace1] [pid 29761] mod_proxy_http.c(1939): [client 169.22.5.105:16430] HTTP: serving URL http://localhost:8080/
[Sun Nov 14 18:09:26.220062 2021] [proxy:debug] [pid 29761] proxy_util.c(2210): AH00942: HTTP: has acquired connection for (localhost)
[Sun Nov 14 18:09:26.220066 2021] [proxy:debug] [pid 29761] proxy_util.c(2263): [client 169.22.5.105:16430] AH00944: connecting http://localhost:8080/ to localhost:8080
[Sun Nov 14 18:09:26.220069 2021] [proxy:debug] [pid 29761] proxy_util.c(2443): [client 169.22.5.105:16430] AH00947: connected / to localhost:8080
[Sun Nov 14 18:09:26.220095 2021] [proxy:warn] [pid 29761] [client 169.22.5.105:16430] AH01092: no HTTP 0.9 request (with no host line) on incoming request and preserve host set forcing hostname to be testserver06.eng.rr.com: for uri /
[Sun Nov 14 18:09:26.220105 2021] [core:trace6] [pid 29761] core_filters.c(525): [remote 24.33.37.114:8080] core_output_filter: flushing because of FLUSH bucket
[Sun Nov 14 18:09:26.221055 2021] [proxy_http:trace3] [pid 29761] mod_proxy_http.c(1414): [client 169.22.5.105:16430] Status from backend: 200
[Sun Nov 14 18:09:26.221063 2021] [proxy_http:trace4] [pid 29761] mod_proxy_http.c(1089): [client 169.22.5.105:16430] Headers received from backend:
[Sun Nov 14 18:09:26.221066 2021] [proxy_http:trace4] [pid 29761] mod_proxy_http.c(1091): [client 169.22.5.105:16430] Set-Cookie: JSESSIONID=7C2A1D70220C40CD4D030C94AE2FBDC4; Path=/; HttpOnly
[Sun Nov 14 18:09:26.221073 2021] [proxy_http:trace4] [pid 29761] mod_proxy_http.c(1091): [client 169.22.5.105:16430] vary: accept-encoding
[Sun Nov 14 18:09:26.221076 2021] [proxy_http:trace4] [pid 29761] mod_proxy_http.c(1091): [client 169.22.5.105:16430] Content-Type: text/html;charset=UTF-8
[Sun Nov 14 18:09:26.221078 2021] [proxy_http:trace4] [pid 29761] mod_proxy_http.c(1091): [client 169.22.5.105:16430] Content-Language: en-US
[Sun Nov 14 18:09:26.221081 2021] [proxy_http:trace4] [pid 29761] mod_proxy_http.c(1091): [client 169.22.5.105:16430] Transfer-Encoding: chunked
[Sun Nov 14 18:09:26.221084 2021] [proxy_http:trace4] [pid 29761] mod_proxy_http.c(1091): [client 169.22.5.105:16430] Date: Sun, 14 Nov 2021 18:09:26 GMT
[Sun Nov 14 18:09:26.221088 2021] [proxy_http:trace4] [pid 29761] mod_proxy_http.c(1091): [client 169.22.5.105:16430] Keep-Alive: timeout=60
[Sun Nov 14 18:09:26.221090 2021] [proxy_http:trace4] [pid 29761] mod_proxy_http.c(1091): [client 169.22.5.105:16430] Connection: keep-alive
[Sun Nov 14 18:09:26.221098 2021] [proxy_http:trace3] [pid 29761] mod_proxy_http.c(1682): [client 169.22.5.105:16430] start body send
[Sun Nov 14 18:09:26.221124 2021] [proxy:debug] [pid 29761] proxy_util.c(2225): AH00943: http: has released connection for (localhost)
[Sun Nov 14 18:09:26.221127 2021] [core:trace6] [pid 29761] core_filters.c(525): [client 169.22.5.105:16430] core_output_filter: flushing because of FLUSH bucket
[Sun Nov 14 18:09:26.221144 2021] [proxy_http:trace2] [pid 29761] mod_proxy_http.c(1827): [client 169.22.5.105:16430] end body send
[Sun Nov 14 18:09:26.221182 2021] [core:trace6] [pid 29761] core_filters.c(525): [client 169.22.5.105:16430] core_output_filter: flushing because of FLUSH bucket
[Sun Nov 14 18:09:26.221189 2021] [ssl:trace3] [pid 29761] ssl_engine_kernel.c(1790): [client 169.22.5.105:16430] OpenSSL: Write: SSL negotiation finished successfully
[Sun Nov 14 18:09:26.221192 2021] [core:trace6] [pid 29761] core_filters.c(525): [client 169.22.5.105:16430] core_output_filter: flushing because of FLUSH bucket
[Sun Nov 14 18:09:26.221204 2021] [ssl:debug] [pid 29761] ssl_engine_io.c(993): [client 169.22.5.105:16430] AH02001: Connection closed to child 1 with standard shutdown (server testserver07.eng.rr.com:443)
[Sun Nov 14 18:09:26.555072 2021] [ssl:info] [pid 30788] [client 169.22.5.106:15520] AH01964: Connection to child 0 established (server testserver07.eng.rr.com:443)
[Sun Nov 14 18:09:26.555112 2021] [ssl:trace2] [pid 30788] ssl_engine_rand.c(124): Seeding PRNG with 0 bytes of entropy
[Sun Nov 14 18:09:26.555176 2021] [ssl:trace3] [pid 30788] ssl_engine_kernel.c(1771): [client 169.22.5.106:15520] OpenSSL: Handshake: start
[Sun Nov 14 18:09:26.555192 2021] [ssl:trace3] [pid 30788] ssl_engine_kernel.c(1780): [client 169.22.5.106:15520] OpenSSL: Loop: before/accept initialization
[Sun Nov 14 18:09:26.555199 2021] [core:trace6] [pid 30788] core_filters.c(525): [client 169.22.5.106:15520] core_output_filter: flushing because of FLUSH bucket
[Sun Nov 14 18:09:26.555213 2021] [ssl:trace4] [pid 30788] ssl_engine_io.c(2070): [client 169.22.5.106:15520] OpenSSL: read 5/5 bytes from BIO#563f94cccad0 [mem: 563f94cb9393] (BIO dump follows)
[Sun Nov 14 18:09:26.555225 2021] [core:trace6] [pid 30788] core_filters.c(525): [client 169.22.5.106:15520] core_output_filter: flushing because of FLUSH bucket

I don't see any errors. Which line do you think is an error?

Nick

On Wed, Nov 17, 2021 at 3:24 PM wendellhatcher1074 <
wendellhatcher1074@gmail.com> wrote:

> Hello, I am getting intermitten 502 errors from calling a tomcat service
> uri path. Can someone help me out with the errors in the trace logs below?
>
>
>
>
>
> Sun Nov 14 18:09:26.219600 2021] [core:trace6] [pid 29761]
> core_filters.c(525): [client 169.22.5.105:16430] core_output_filter:
> flushing because of FLUSH bucket
>
> [Sun Nov 14 18:09:26.219622 2021] [ssl:trace3] [pid 29761]
> ssl_engine_kernel.c(1780): [client 169.22.5.105:16430] OpenSSL: Loop:
> SSLv3 flush data
>
> [Sun Nov 14 18:09:26.219629 2021] [ssl:trace3] [pid 29761]
> ssl_engine_kernel.c(1775): [client 169.22.5.105:16430] OpenSSL:
> Handshake: done
>
> [Sun Nov 14 18:09:26.219636 2021] [ssl:debug] [pid 29761]
> ssl_engine_kernel.c(1824): [client 169.22.5.105:16430] AH02041: Protocol:
> TLSv1.2, Cipher: AES128-SHA (128/128 bits)
>
> [Sun Nov 14 18:09:26.219641 2021] [core:trace6] [pid 29761]
> core_filters.c(525): [client 169.22.5.105:16430] core_output_filter:
> flushing because of FLUSH bucket
>
> [Sun Nov 14 18:09:26.219932 2021] [ssl:trace4] [pid 29761]
> ssl_engine_io.c(2070): [client 169.22.5.105:16430] OpenSSL: read 5/5
> bytes from BIO#563f94b8d1e0 [mem: 563f94cb9393] (BIO dump follows)
>
> [Sun Nov 14 18:09:26.219936 2021] [core:trace6] [pid 29761]
> core_filters.c(525): [client 169.22.5.105:16430] core_output_filter:
> flushing because of FLUSH bucket
>
> [Sun Nov 14 18:09:26.219938 2021] [ssl:trace4] [pid 29761]
> ssl_engine_io.c(2070): [client 169.22.5.105:16430] OpenSSL: read 48/48
> bytes from BIO#563f94b8d1e0 [mem: 563f94cb9398] (BIO dump follows)
>
> [Sun Nov 14 18:09:26.219946 2021] [core:trace5] [pid 29761]
> protocol.c(656): [client 169.22.5.105:16430] Request received from
> client: GET /
>
> [Sun Nov 14 18:09:26.219967 2021] [ssl:debug] [pid 29761]
> ssl_engine_kernel.c(225): [client 169.22.5.105:16430] AH02034: Initial
> (No.1) HTTPS request received for child 1 (server
> testserver07.eng.rr.com:443)
>
> [Sun Nov 14 18:09:26.219971 2021] [http:trace4] [pid 29761]
> http_request.c(323): [client 169.22.5.105:16430] Headers received from
> client:
>
> [Sun Nov 14 18:09:26.219995 2021] [authz_core:debug] [pid 29761]
> mod_authz_core.c(835): [client 169.22.5.105:16430] AH01628: authorization
> result: granted (no directives)
>
> [Sun Nov 14 18:09:26.219998 2021] [core:trace3] [pid 29761]
> request.c(312): [client 169.22.5.105:16430] request authorized without
> authentication by access_checker_ex hook: /
>
> [Sun Nov 14 18:09:26.220010 2021] [proxy_http:trace1] [pid 29761]
> mod_proxy_http.c(60): [client 169.22.5.105:16430] HTTP: canonicalising
> URL //localhost:8080/
>
> [Sun Nov 14 18:09:26.220043 2021] [proxy:trace2] [pid 29761]
> proxy_util.c(1991): [client 169.22.5.105:16430] http: found worker
> http://localhost:8080/ for http://localhost:8080/
>
> [Sun Nov 14 18:09:26.220046 2021] [proxy:debug] [pid 29761]
> mod_proxy.c(1123): [client 169.22.5.105:16430] AH01143: Running scheme
> http handler (attempt 0)
>
> [Sun Nov 14 18:09:26.220050 2021] [proxy_ajp:debug] [pid 29761]
> mod_proxy_ajp.c(722): [client 169.22.5.105:16430] AH00894: declining URL
> http://localhost:8080/
>
> [Sun Nov 14 18:09:26.220053 2021] [proxy_fcgi:debug] [pid 29761]
> mod_proxy_fcgi.c(972): [client 169.22.5.105:16430] AH01076: url:
> http://localhost:8080/ proxyname: (null) proxyport: 0
>
> [Sun Nov 14 18:09:26.220055 2021] [proxy_fcgi:debug] [pid 29761]
> mod_proxy_fcgi.c(975): [client 169.22.5.105:16430] AH01077: declining URL
> http://localhost:8080/
>
> [Sun Nov 14 18:09:26.220059 2021] [proxy_http:trace1] [pid 29761]
> mod_proxy_http.c(1939): [client 169.22.5.105:16430] HTTP: serving URL
> http://localhost:8080/
>
> [Sun Nov 14 18:09:26.220062 2021] [proxy:debug] [pid 29761]
> proxy_util.c(2210): AH00942: HTTP: has acquired connection for (localhost)
>
> [Sun Nov 14 18:09:26.220066 2021] [proxy:debug] [pid 29761]
> proxy_util.c(2263): [client 169.22.5.105:16430] AH00944: connecting
> http://localhost:8080/ to localhost:8080
>
> [Sun Nov 14 18:09:26.220069 2021] [proxy:debug] [pid 29761]
> proxy_util.c(2443): [client 169.22.5.105:16430] AH00947: connected / to
> localhost:8080
>
> [Sun Nov 14 18:09:26.220095 2021] [proxy:warn] [pid 29761] [client
> 169.22.5.105:16430] AH01092: no HTTP 0.9 request (with no host line) on
> incoming request and preserve host set forcing hostname to be
> testserver06.eng.rr.com: for uri /
>
> [Sun Nov 14 18:09:26.220105 2021] [core:trace6] [pid 29761]
> core_filters.c(525): [remote 24.33.37.114:8080] core_output_filter:
> flushing because of FLUSH bucket
>
> [Sun Nov 14 18:09:26.221055 2021] [proxy_http:trace3] [pid 29761]
> mod_proxy_http.c(1414): [client 169.22.5.105:16430] Status from backend:
> 200
>
> [Sun Nov 14 18:09:26.221063 2021] [proxy_http:trace4] [pid 29761]
> mod_proxy_http.c(1089): [client 169.22.5.105:16430] Headers received from
> backend:
>
> [Sun Nov 14 18:09:26.221066 2021] [proxy_http:trace4] [pid 29761]
> mod_proxy_http.c(1091): [client 169.22.5.105:16430] Set-Cookie:
> JSESSIONID=7C2A1D70220C40CD4D030C94AE2FBDC4; Path=/; HttpOnly
>
> [Sun Nov 14 18:09:26.221073 2021] [proxy_http:trace4] [pid 29761]
> mod_proxy_http.c(1091): [client 169.22.5.105:16430] vary: accept-encoding
>
> [Sun Nov 14 18:09:26.221076 2021] [proxy_http:trace4] [pid 29761]
> mod_proxy_http.c(1091): [client 169.22.5.105:16430] Content-Type:
> text/html;charset=UTF-8
>
> [Sun Nov 14 18:09:26.221078 2021] [proxy_http:trace4] [pid 29761]
> mod_proxy_http.c(1091): [client 169.22.5.105:16430] Content-Language:
> en-US
>
> [Sun Nov 14 18:09:26.221081 2021] [proxy_http:trace4] [pid 29761]
> mod_proxy_http.c(1091): [client 169.22.5.105:16430] Transfer-Encoding:
> chunked
>
> [Sun Nov 14 18:09:26.221084 2021] [proxy_http:trace4] [pid 29761]
> mod_proxy_http.c(1091): [client 169.22.5.105:16430] Date: Sun, 14 Nov
> 2021 18:09:26 GMT
>
> [Sun Nov 14 18:09:26.221088 2021] [proxy_http:trace4] [pid 29761]
> mod_proxy_http.c(1091): [client 169.22.5.105:16430] Keep-Alive: timeout=60
>
> [Sun Nov 14 18:09:26.221090 2021] [proxy_http:trace4] [pid 29761]
> mod_proxy_http.c(1091): [client 169.22.5.105:16430] Connection: keep-alive
>
> [Sun Nov 14 18:09:26.221098 2021] [proxy_http:trace3] [pid 29761]
> mod_proxy_http.c(1682): [client 169.22.5.105:16430] start body send
>
> [Sun Nov 14 18:09:26.221124 2021] [proxy:debug] [pid 29761]
> proxy_util.c(2225): AH00943: http: has released connection for (localhost)
>
> [Sun Nov 14 18:09:26.221127 2021] [core:trace6] [pid 29761]
> core_filters.c(525): [client 169.22.5.105:16430] core_output_filter:
> flushing because of FLUSH bucket
>
> [Sun Nov 14 18:09:26.221144 2021] [proxy_http:trace2] [pid 29761]
> mod_proxy_http.c(1827): [client 169.22.5.105:16430] end body send
>
> [Sun Nov 14 18:09:26.221182 2021] [core:trace6] [pid 29761]
> core_filters.c(525): [client 169.22.5.105:16430] core_output_filter:
> flushing because of FLUSH bucket
>
> [Sun Nov 14 18:09:26.221189 2021] [ssl:trace3] [pid 29761]
> ssl_engine_kernel.c(1790): [client 169.22.5.105:16430] OpenSSL: Write:
> SSL negotiation finished successfully
>
> [Sun Nov 14 18:09:26.221192 2021] [core:trace6] [pid 29761]
> core_filters.c(525): [client 169.22.5.105:16430] core_output_filter:
> flushing because of FLUSH bucket
>
> [Sun Nov 14 18:09:26.221204 2021] [ssl:debug] [pid 29761]
> ssl_engine_io.c(993): [client 169.22.5.105:16430] AH02001: Connection
> closed to child 1 with standard shutdown (server
> testserver07.eng.rr.com:443)
>
> [Sun Nov 14 18:09:26.555072 2021] [ssl:info] [pid 30788] [client
> 169.22.5.106:15520] AH01964: Connection to child 0 established (server
> testserver07.eng.rr.com:443)
>
> [Sun Nov 14 18:09:26.555112 2021] [ssl:trace2] [pid 30788]
> ssl_engine_rand.c(124): Seeding PRNG with 0 bytes of entropy
>
> [Sun Nov 14 18:09:26.555176 2021] [ssl:trace3] [pid 30788]
> ssl_engine_kernel.c(1771): [client 169.22.5.106:15520] OpenSSL:
> Handshake: start
>
> [Sun Nov 14 18:09:26.555192 2021] [ssl:trace3] [pid 30788]
> ssl_engine_kernel.c(1780): [client 169.22.5.106:15520] OpenSSL: Loop:
> before/accept initialization
>
> [Sun Nov 14 18:09:26.555199 2021] [core:trace6] [pid 30788]
> core_filters.c(525): [client 169.22.5.106:15520] core_output_filter:
> flushing because of FLUSH bucket
>
> [Sun Nov 14 18:09:26.555213 2021] [ssl:trace4] [pid 30788]
> ssl_engine_io.c(2070): [client 169.22.5.106:15520] OpenSSL: read 5/5
> bytes from BIO#563f94cccad0 [mem: 563f94cb9393] (BIO dump follows)
>
> [Sun Nov 14 18:09:26.555225 2021] [core:trace6] [pid 30788]
> core_filters.c(525): [client 169.22.5.106:15520] core_output_filter:
> flushing because of FLUSH bucket
>
>
>