[Bug 63859] AJP cping/cpong mode failing on Tomcat 9.x

[bu...@apache.org]

https://bz.apache.org/bugzilla/show_bug.cgi?id=63859

--- Comment #8 from Aurelien Pernoud <au...@pernoud.org> ---
Hi Mark,

I have the debug but can't attach it due to limit of 1MB :-(

I managed to reproduce the issue, I have 2 httpd with the exact same
configuration, pointing to same tomcat.

In the logs of "http node 1" I see for ex :

[Mon Nov 04 09:15:03 2019] [13694:140683904751360] [info]
ajp_handle_cping_cpong::jk_ajp_common.c (976): (tom_tst01_srv09) timeout in
reply cpong after 10000 ms. Socket = 25 (event=110)
[Mon Nov 04 09:15:13 2019] [13694:140683904751360] [info]
ajp_handle_cping_cpong::jk_ajp_common.c (976): (tom_tst01_srv09) timeout in
reply cpong after 10000 ms. Socket = 14 (event=110)
[Mon Nov 04 09:15:24 2019] [13694:140683904751360] [info]
ajp_handle_cping_cpong::jk_ajp_common.c (976): (tom_tst01_srv09) timeout in
reply cpong after 10000 ms. Socket = 14 (event=110)

leading progressively to :

[Mon Nov 04 09:15:24 2019] [13694:140683904751360] [error]
ajp_send_request::jk_ajp_common.c (1725): (tom_tst01_srv09) connecting to
backend failed. Tomcat is probably not started or is listening on the wrong
port (errno=110)

(Here I only searched for the error)

In the logs of "http node 2", at the same timing, the same server replies
correctly to ping (at least that's how I interpret the below lines) :

[Mon Nov 04 09:14:52 2019] [25023:139691817195264] [debug]
ajp_maintain::jk_ajp_common.c (3441): (tom_tst01_srv09) reached pool min size
13 from 25 cache slots
[Mon Nov 04 09:14:52 2019] [25023:139691817195264] [debug]
ajp_maintain::jk_ajp_common.c (3493): (tom_tst01_srv09) pinged 1 and recycled 0
sockets in 0 seconds from 25 pool slots
[Mon Nov 04 09:15:32 2019] [25022:139691817195264] [debug]
ajp_maintain::jk_ajp_common.c (3441): (tom_tst01_srv09) reached pool min size
13 from 25 cache slots
[Mon Nov 04 09:15:32 2019] [25022:139691817195264] [debug]
ajp_maintain::jk_ajp_common.c (3493): (tom_tst01_srv09) pinged 1 and recycled 0
sockets in 0 seconds from 25 pool slots
[Mon Nov 04 09:15:32 2019] [25024:139691817195264] [debug]
ajp_maintain::jk_ajp_common.c (3441): (tom_tst01_srv09) reached pool min size
13 from 25 cache slots
[Mon Nov 04 09:15:32 2019] [25024:139691817195264] [debug]
ajp_maintain::jk_ajp_common.c (3493): (tom_tst01_srv09) pinged 2 and recycled 0
sockets in 0 seconds from 25 pool slots
[Mon Nov 04 09:15:50 2019] [26242:139691817195264] [debug]
ajp_maintain::jk_ajp_common.c (3441): (tom_tst01_srv09) reached pool min size
13 from 25 cache slots
[Mon Nov 04 09:15:50 2019] [26242:139691817195264] [debug]
ajp_maintain::jk_ajp_common.c (3493): (tom_tst01_srv09) pinged 2 and recycled 0
sockets in 0 seconds from 25 pool slots

Both server times are synecd of course.

I can send the logs, once zipped both nodes we are talking about a file of
30MB, let me know how ?

-- 
You are receiving this mail because:
You are the assignee for the bug.
---------------------------------------------------------------------
To unsubscribe, e-mail: dev-unsubscribe@tomcat.apache.org
For additional commands, e-mail: dev-help@tomcat.apache.org