Re: Apache SSI breaks with tomcat-connectors-1.2.43 or newer

Mon, 30 Sep 2019 12:34:07 -0700

Thanks, eactly what we need. I will look into it, but it might take two or three days. Anyone who can do it more quickly is welcome. I suspect these log lines point to the root cause: 

> [Mon Sep 30 15:04:03 2019][3906:140148297873536] [debug]
> jk_servlet_normalize::jk_util.c (2184): URI on entering
> jk_servlet_normalize: []
> [Mon Sep 30 15:04:03 2019][3906:140148297873536] [emerg]
> jk_servlet_normalize::jk_util.c (2188): [] does not start with '/'.
> [Mon Sep 30 15:04:03 2019][3906:140148297873536] [debug]
but we'll need to investigate whether it can be fixed by configuration or needs a code change. Our configs are pretty flexible but we need to understand a bit better how we can let mod_jk keep out of the SSI handling of the internal requests.
As far as I have understoff, you do not actually want these SSI requests to be forwarded via mod_jk/AJP and instead want all of them being handled directly by Apache? 

regards,

Rainer

Am 30.09.2019 um 17:17 schrieb Ezsra McDonald:
Apologies as this is a long e-mail. I tried to include all the information to help troubleshoot the issue.
I have a lab setup with  a vagrant box to test.: CentOS Linux release 7.7.1908 (Core) 

httpd-2.4.6-90.el7
tomcat-connectors-1.2.44-src

The only changes I made to the config from default install are as follows

#*************************************************
FILE: /etc/httpd/conf/httpd.conf

#test-ssi
<Location /test-ssi/ >
    Options +Includes
    AddHandler server-parsed .shtml
</Location>

#*************************************************
FILE: /etc/httpd/conf/workers.properties

This file is currently blank.

#*************************************************
FILE: /etc/httpd/conf.d/mod_jk.conf

# Load mod_jk module
# Specify the filename of the mod_jk lib
#LoadModule jk_module modules/mod_jk.so

<IfModule jk_module>

     # Where to find workers.properties
     JkWorkersFile conf/workers.properties

     # Where to put jk logs
     JkLogFile logs/mod_jk.log

     # Set the jk log level [debug/error/info]
     #JkLogLevel info
     JkLogLevel trace

     # Select the log format
     JkLogStampFormat  "[%a %b %d %H:%M:%S %Y]"

     # JkOptions indicates to send SSK KEY SIZE
     JkOptions +ForwardKeySize +ForwardURICompat -ForwardDirectories

     # JkRequestLogFormat
     JkRequestLogFormat "%w %V %T"

     # Mount your applications
     # The default setting only sends Java application data to mod_jk.
     # Use the commented-out line to send all URLs through mod_jk.
     # JkMount /* loadbalancer
     # JkMount /application/* loadbalancer
     #JkMountFile conf.d/uriworkermap.properties

     # Add shared memory.
     # This directive is present with 1.2.10 and
     # later versions of mod_jk, and is needed for
     # for load balancing to work properly
     JkShmFile logs/jk.shm

     # Should mod_jk send SSL information (default is On)
     JkExtractSSL On
     # What is the indicator for SSL (default is HTTPS)
     JkHTTPSIndicator HTTPS
     # What is the indicator for SSL session (default is SSL_SESSION_ID)
     JkSESSIONIndicator SSL_SESSION_ID
    # What is the indicator for client SSL cipher suit (default is SSL_CIPHER) 
     JkCIPHERIndicator SSL_CIPHER
    # What is the indicator for the client SSL certificated? (default is SSL_CLIENT_CERT) 
     JkCERTSIndicator SSL_CLIENT_CERT

     # Add jkstatus for managing runtime data
     <Location /jkmanager>
         Order deny,allow
         Deny from all
         Allow from localhost 192.168.19.54 192.168.19.52
     </Location>

</IfModule>

#*************************************************
FILE: /etc/httpd/conf.modules.d/02-mod_jk.conf

# This file configures the mod_jk modules:
LoadModule jk_module modules/mod_jk.so

#*************************************************
SSI Structure modeled after a previously working customer site.

[vagrant@WEBSRV test-ssi]$ tree /var/www/html/test-ssi
/var/www/html/test-ssi
├── includes
│   ├── footer.html
│   └── header.html
└── index.shtml

#*************************************************
FILE: /var/www/html/test-ssi/index.shtml

<html>
   <head>
       <title>SSI Test stuff</title>
   </head>
   <body>
       <!--#include file="includes/header.html" -->
         <div>
       <!-- Page content goes here   -->
         </div>
       <!--#include file="includes/footer.html" -->
   </body>
</html>

#*************************************************
When I load index.shtml in the browser I get the following page:

[an error occurred while processing this directive]
[an error occurred while processing this directive]

#*************************************************
error_log
[Mon Sep 30 15:04:03.634861 2019] [include:error] [pid 3906] [client 10.211.55.42:37042 <http://10.211.55.42:37042>] unable to include "includes/header.html" in parsed file /var/www/html/test-ssi/index.shtml, subrequest setup returned 404 [Mon Sep 30 15:04:03.634907 2019] [include:error] [pid 3906] [client 10.211.55.42:37042 <http://10.211.55.42:37042>] unable to include "includes/footer.html" in parsed file /var/www/html/test-ssi/index.shtml, subrequest setup returned 404 

#*************************************************
If I reconfigure the SSI structure as follows the SSI works:

/var/www/html/test-ssi
├── footer.html
├── header.html
└── index.shtml

FILE: /var/www/html/test-ssi/index.shtml

<html>
   <head>
       <title>SSI Test stuff</title>
   </head>
   <body>
       <!--#include file="header.html" -->
         <div>
       <!-- Page content goes here   -->
         </div>
       <!--#include file="footer.html" -->
   </body>
</html>

It seems like mod_include can not find resources in sub directories


#*************************************************
FILE: /var/log/httpd/mod_jk.log. (257 lines. happy reading)
[Mon Sep 30 15:01:43 2019][3905:140148297873536] [debug] jk_set_time_fmt::jk_util.c (487): Pre-processed log time stamp format is '[%a %b %d %H:%M:%S %Y]' [Mon Sep 30 15:01:43 2019][3905:140148297873536] [debug] dump_options::mod_jk.c (665): JkOption 'ForwardURICompat' set in server '127.0.0.1' [Mon Sep 30 15:01:43 2019][3905:140148297873536] [debug] dump_options::mod_jk.c (673): JkOption 'ForwardURIEscaped' set in server '127.0.0.1' [Mon Sep 30 15:01:43 2019][3905:140148297873536] [debug] dump_options::mod_jk.c (697): JkOption 'ForwardKeySize' set in server '127.0.0.1' (default) [Mon Sep 30 15:01:43 2019][3905:140148297873536] [trace] uri_worker_map_alloc::jk_uri_worker_map.c (216): enter [Mon Sep 30 15:01:43 2019][3905:140148297873536] [trace] uri_worker_map_open::jk_uri_worker_map.c (892): enter [Mon Sep 30 15:01:43 2019][3905:140148297873536] [debug] uri_worker_map_open::jk_uri_worker_map.c (898): rule map size is 0 [Mon Sep 30 15:01:43 2019][3905:140148297873536] [trace] uri_worker_map_dump::jk_uri_worker_map.c (174): enter [Mon Sep 30 15:01:43 2019][3905:140148297873536] [debug] uri_worker_map_dump::jk_uri_worker_map.c (178): uri map dump after map open: id=0, index=0 file='(null)' reject_unsafe=0 reload=60 modified=0 checked=0 [Mon Sep 30 15:01:43 2019][3905:140148297873536] [debug] uri_worker_map_dump::jk_uri_worker_map.c (184): generation 0: size=0 nosize=0 capacity=0 [Mon Sep 30 15:01:43 2019][3905:140148297873536] [debug] uri_worker_map_dump::jk_uri_worker_map.c (184): generation 1: size=0 nosize=0 capacity=0 [Mon Sep 30 15:01:43 2019][3905:140148297873536] [trace] uri_worker_map_dump::jk_uri_worker_map.c (207): exit [Mon Sep 30 15:01:43 2019][3905:140148297873536] [trace] uri_worker_map_open::jk_uri_worker_map.c (958): exit [Mon Sep 30 15:01:43 2019][3905:140148297873536] [trace] uri_worker_map_alloc::jk_uri_worker_map.c (255): exit [Mon Sep 30 15:01:43 2019][3905:140148297873536] [debug] jk_set_time_fmt::jk_util.c (487): Pre-processed log time stamp format is '[%a %b %d %H:%M:%S %Y]' [Mon Sep 30 15:01:43 2019][3905:140148297873536] [debug] dump_options::mod_jk.c (665): JkOption 'ForwardURICompat' set in server '127.0.0.1 [_default_:443]' [Mon Sep 30 15:01:43 2019][3905:140148297873536] [debug] dump_options::mod_jk.c (673): JkOption 'ForwardURIEscaped' set in server '127.0.0.1 [_default_:443]' [Mon Sep 30 15:01:43 2019][3905:140148297873536] [debug] dump_options::mod_jk.c (697): JkOption 'ForwardKeySize' set in server '127.0.0.1 [_default_:443]' (default) [Mon Sep 30 15:01:43 2019][3905:140148297873536] [debug] init_jk::mod_jk.c (3518): Setting default connection pool max size to 1 [Mon Sep 30 15:01:43 2019][3905:140148297873536] [trace] jk_map_resolve_references::jk_map.c (775): enter [Mon Sep 30 15:01:43 2019][3905:140148297873536] [debug] jk_map_resolve_references::jk_map.c (783): Checking for references with prefix worker. with wildcard (recursion 1) [Mon Sep 30 15:01:43 2019][3905:140148297873536] [trace] jk_map_resolve_references::jk_map.c (839): exit [Mon Sep 30 15:01:43 2019][3905:140148297873536] [trace] jk_shm_calculate_size::jk_shm.c (132): enter [Mon Sep 30 15:01:43 2019][3905:140148297873536] [debug] jk_shm_calculate_size::jk_shm.c (143): JK_SHM_SLOT_SIZE defined as 384, need at least 384 [Mon Sep 30 15:01:43 2019][3905:140148297873536] [debug] jk_shm_calculate_size::jk_shm.c (177): shared memory will contain 1 ajp workers and 0 lb workers with 0 members [Mon Sep 30 15:01:43 2019][3905:140148297873536] [trace] jk_shm_calculate_size::jk_shm.c (184): exit [Mon Sep 30 15:01:43 2019][3905:140148297873536] [trace] do_shm_open::jk_shm.c (548): enter [Mon Sep 30 15:01:43 2019][3905:140148297873536] [debug] do_shm_open::jk_shm.c (622): Truncated shared memory to 1024 [Mon Sep 30 15:01:43 2019][3905:140148297873536] [debug] do_shm_open::jk_shm.c (669): Initialized shared memory /etc/httpd/logs/jk.shm.3905 size=1024 free=384 addr=0x7f76d1839000 [Mon Sep 30 15:01:43 2019][3905:140148297873536] [trace] do_shm_open_lock::jk_shm.c (459): enter [Mon Sep 30 15:01:43 2019][3905:140148297873536] [debug] do_shm_open_lock::jk_shm.c (532): Opened shared memory lock /etc/httpd/logs/jk.shm.3905.lock [Mon Sep 30 15:01:43 2019][3905:140148297873536] [trace] do_shm_open_lock::jk_shm.c (534): exit [Mon Sep 30 15:01:43 2019][3905:140148297873536] [trace] do_shm_open::jk_shm.c (723): exit [Mon Sep 30 15:01:43 2019][3905:140148297873536] [trace] wc_open::jk_worker.c (55): enter [Mon Sep 30 15:01:43 2019][3905:140148297873536] [debug] jk_map_dump::jk_map.c (599): Dump of map 2: 'ServerRoot' -> '/etc/httpd' [Mon Sep 30 15:01:43 2019][3905:140148297873536] [trace] build_worker_map::jk_worker.c (244): enter [Mon Sep 30 15:01:43 2019][3905:140148297873536] [debug] build_worker_map::jk_worker.c (250): creating worker ajp13 [Mon Sep 30 15:01:43 2019][3905:140148297873536] [trace] wc_create_worker::jk_worker.c (130): enter [Mon Sep 30 15:01:43 2019][3905:140148297873536] [debug] wc_create_worker::jk_worker.c (154): about to create instance ajp13 of ajp13 [Mon Sep 30 15:01:43 2019][3905:140148297873536] [trace] ajp13_worker_factory::jk_ajp13_worker.c (80): enter [Mon Sep 30 15:01:43 2019][3905:140148297873536] [trace] ajp_worker_factory::jk_ajp_common.c (3165): enter [Mon Sep 30 15:01:43 2019][3905:140148297873536] [debug] ajp_worker_factory::jk_ajp_common.c (3219): ajp worker 'ajp13' type=2 created [Mon Sep 30 15:01:43 2019][3905:140148297873536] [trace] ajp_worker_factory::jk_ajp_common.c (3222): exit [Mon Sep 30 15:01:43 2019][3905:140148297873536] [trace] ajp13_worker_factory::jk_ajp13_worker.c (92): exit [Mon Sep 30 15:01:43 2019][3905:140148297873536] [debug] wc_create_worker::jk_worker.c (167): about to validate and init ajp13 [Mon Sep 30 15:01:43 2019][3905:140148297873536] [trace] validate::jk_ajp13_worker.c (35): enter [Mon Sep 30 15:01:43 2019][3905:140148297873536] [trace] ajp_validate::jk_ajp_common.c (2814): enter [Mon Sep 30 15:01:43 2019][3905:140148297873536] [debug] ajp_validate::jk_ajp_common.c (2863): worker ajp13 target is 'localhost:8009' [Mon Sep 30 15:01:43 2019][3905:140148297873536] [trace] jk_resolve::jk_connect.c (406): enter [Mon Sep 30 15:01:43 2019][3905:140148297873536] [trace] jk_resolve::jk_connect.c (599): exit [Mon Sep 30 15:01:43 2019][3905:140148297873536] [trace] jk_ajp_push::jk_ajp_common.c (1188): enter [Mon Sep 30 15:01:43 2019][3905:140148297873536] [debug] jk_ajp_push::jk_ajp_common.c (1191): syncing shm for ajp worker 'ajp13' from mem (0->0) [0->0] [Mon Sep 30 15:01:43 2019][3905:140148297873536] [trace] jk_ajp_push::jk_ajp_common.c (1239): exit [Mon Sep 30 15:01:43 2019][3905:140148297873536] [trace] ajp_validate::jk_ajp_common.c (2908): exit [Mon Sep 30 15:01:43 2019][3905:140148297873536] [trace] validate::jk_ajp13_worker.c (37): exit [Mon Sep 30 15:01:43 2019][3905:140148297873536] [trace] init::jk_ajp13_worker.c (47): enter [Mon Sep 30 15:01:43 2019][3905:140148297873536] [trace] ajp_init::jk_ajp_common.c (2974): enter [Mon Sep 30 15:01:43 2019][3905:140148297873536] [debug] ajp_init::jk_ajp_common.c (3077): (ajp13) setting endpoint options: [Mon Sep 30 15:01:43 2019][3905:140148297873536] [debug] ajp_init::jk_ajp_common.c (3081): keepalive:              0 [Mon Sep 30 15:01:43 2019][3905:140148297873536] [debug] ajp_init::jk_ajp_common.c (3085): socket timeout:         0 [Mon Sep 30 15:01:43 2019][3905:140148297873536] [debug] ajp_init::jk_ajp_common.c (3089): socket connect timeout: 0 [Mon Sep 30 15:01:43 2019][3905:140148297873536] [debug] ajp_init::jk_ajp_common.c (3093): buffer size:            0 [Mon Sep 30 15:01:43 2019][3905:140148297873536] [debug] ajp_init::jk_ajp_common.c (3097): pool timeout:           0 [Mon Sep 30 15:01:43 2019][3905:140148297873536] [debug] ajp_init::jk_ajp_common.c (3101): ping timeout:           10000 [Mon Sep 30 15:01:43 2019][3905:140148297873536] [debug] ajp_init::jk_ajp_common.c (3105): connect timeout:        0 [Mon Sep 30 15:01:43 2019][3905:140148297873536] [debug] ajp_init::jk_ajp_common.c (3109): reply timeout:          0 [Mon Sep 30 15:01:43 2019][3905:140148297873536] [debug] ajp_init::jk_ajp_common.c (3113): prepost timeout:        0 [Mon Sep 30 15:01:43 2019][3905:140148297873536] [debug] ajp_init::jk_ajp_common.c (3117): recovery options:       0 [Mon Sep 30 15:01:43 2019][3905:140148297873536] [debug] ajp_init::jk_ajp_common.c (3121): retries:                2 [Mon Sep 30 15:01:43 2019][3905:140148297873536] [debug] ajp_init::jk_ajp_common.c (3125): max packet size:        8192 [Mon Sep 30 15:01:43 2019][3905:140148297873536] [debug] ajp_init::jk_ajp_common.c (3129): retry interval:         100 [Mon Sep 30 15:01:43 2019][3905:140148297873536] [debug] ajp_init::jk_ajp_common.c (3133): busy limit:         0 [Mon Sep 30 15:01:43 2019][3905:140148297873536] [trace] ajp_create_endpoint_cache::jk_ajp_common.c (2924): enter [Mon Sep 30 15:01:43 2019][3905:140148297873536] [debug] ajp_create_endpoint_cache::jk_ajp_common.c (2933): (ajp13) setting connection pool size to 1 with min 1 and acquire timeout 200 [Mon Sep 30 15:01:43 2019][3905:140148297873536] [trace] ajp_create_endpoint_cache::jk_ajp_common.c (2962): exit [Mon Sep 30 15:01:43 2019][3905:140148297873536] [trace] ajp_init::jk_ajp_common.c (3155): exit [Mon Sep 30 15:01:43 2019][3905:140148297873536] [trace] init::jk_ajp13_worker.c (50): exit [Mon Sep 30 15:01:43 2019][3905:140148297873536] [trace] wc_create_worker::jk_worker.c (206): exit [Mon Sep 30 15:01:43 2019][3905:140148297873536] [trace] build_worker_map::jk_worker.c (281): exit [Mon Sep 30 15:01:43 2019][3905:140148297873536] [trace] wc_open::jk_worker.c (93): exit [Mon Sep 30 15:01:43 2019][3905:140148297873536] [info] init_jk::mod_jk.c (3580): mod_jk/1.2.44 initialized [Mon Sep 30 15:01:43 2019][3905:140148297873536] [trace] uri_worker_map_ext::jk_uri_worker_map.c (617): enter [Mon Sep 30 15:01:43 2019][3905:140148297873536] [trace] uri_worker_map_dump::jk_uri_worker_map.c (174): enter [Mon Sep 30 15:01:43 2019][3905:140148297873536] [debug] uri_worker_map_dump::jk_uri_worker_map.c (178): uri map dump after extension stripping: id=1, index=0 file='(null)' reject_unsafe=0 reload=60 modified=0 checked=0 [Mon Sep 30 15:01:43 2019][3905:140148297873536] [debug] uri_worker_map_dump::jk_uri_worker_map.c (184): generation 0: size=0 nosize=0 capacity=0 [Mon Sep 30 15:01:43 2019][3905:140148297873536] [debug] uri_worker_map_dump::jk_uri_worker_map.c (184): generation 1: size=0 nosize=0 capacity=0 [Mon Sep 30 15:01:43 2019][3905:140148297873536] [trace] uri_worker_map_dump::jk_uri_worker_map.c (207): exit [Mon Sep 30 15:01:43 2019][3905:140148297873536] [trace] uri_worker_map_ext::jk_uri_worker_map.c (633): exit [Mon Sep 30 15:01:43 2019][3905:140148297873536] [trace] uri_worker_map_switch::jk_uri_worker_map.c (597): enter [Mon Sep 30 15:01:43 2019][3905:140148297873536] [debug] uri_worker_map_switch::jk_uri_worker_map.c (602): Switching uri worker map from index 0 to index 1 [Mon Sep 30 15:01:43 2019][3905:140148297873536] [trace] uri_worker_map_switch::jk_uri_worker_map.c (609): exit [Mon Sep 30 15:01:43 2019][3905:140148297873536] [debug] jk_shm_close::jk_shm.c (745): Closed shared memory /etc/httpd/logs/jk.shm.3905 childs=1 [Mon Sep 30 15:01:43 2019][3905:140148297873536] [debug] jk_set_time_fmt::jk_util.c (487): Pre-processed log time stamp format is '[%a %b %d %H:%M:%S %Y]' [Mon Sep 30 15:01:43 2019][3905:140148297873536] [debug] dump_options::mod_jk.c (665): JkOption 'ForwardURICompat' set in server '127.0.0.1' [Mon Sep 30 15:01:43 2019][3905:140148297873536] [debug] dump_options::mod_jk.c (673): JkOption 'ForwardURIEscaped' set in server '127.0.0.1' [Mon Sep 30 15:01:43 2019][3905:140148297873536] [debug] dump_options::mod_jk.c (697): JkOption 'ForwardKeySize' set in server '127.0.0.1' (default) [Mon Sep 30 15:01:43 2019][3905:140148297873536] [trace] uri_worker_map_alloc::jk_uri_worker_map.c (216): enter [Mon Sep 30 15:01:43 2019][3905:140148297873536] [trace] uri_worker_map_open::jk_uri_worker_map.c (892): enter [Mon Sep 30 15:01:43 2019][3905:140148297873536] [debug] uri_worker_map_open::jk_uri_worker_map.c (898): rule map size is 0 [Mon Sep 30 15:01:43 2019][3905:140148297873536] [trace] uri_worker_map_dump::jk_uri_worker_map.c (174): enter [Mon Sep 30 15:01:43 2019][3905:140148297873536] [debug] uri_worker_map_dump::jk_uri_worker_map.c (178): uri map dump after map open: id=0, index=0 file='(null)' reject_unsafe=0 reload=60 modified=0 checked=0 [Mon Sep 30 15:01:43 2019][3905:140148297873536] [debug] uri_worker_map_dump::jk_uri_worker_map.c (184): generation 0: size=0 nosize=0 capacity=0 [Mon Sep 30 15:01:43 2019][3905:140148297873536] [debug] uri_worker_map_dump::jk_uri_worker_map.c (184): generation 1: size=0 nosize=0 capacity=0 [Mon Sep 30 15:01:43 2019][3905:140148297873536] [trace] uri_worker_map_dump::jk_uri_worker_map.c (207): exit [Mon Sep 30 15:01:43 2019][3905:140148297873536] [trace] uri_worker_map_open::jk_uri_worker_map.c (958): exit [Mon Sep 30 15:01:43 2019][3905:140148297873536] [trace] uri_worker_map_alloc::jk_uri_worker_map.c (255): exit [Mon Sep 30 15:01:43 2019][3905:140148297873536] [debug] jk_set_time_fmt::jk_util.c (487): Pre-processed log time stamp format is '[%a %b %d %H:%M:%S %Y]' [Mon Sep 30 15:01:43 2019][3905:140148297873536] [debug] dump_options::mod_jk.c (665): JkOption 'ForwardURICompat' set in server '127.0.0.1 [_default_:443]' [Mon Sep 30 15:01:43 2019][3905:140148297873536] [debug] dump_options::mod_jk.c (673): JkOption 'ForwardURIEscaped' set in server '127.0.0.1 [_default_:443]' [Mon Sep 30 15:01:43 2019][3905:140148297873536] [debug] dump_options::mod_jk.c (697): JkOption 'ForwardKeySize' set in server '127.0.0.1 [_default_:443]' (default) [Mon Sep 30 15:01:43 2019][3905:140148297873536] [debug] init_jk::mod_jk.c (3518): Setting default connection pool max size to 1 [Mon Sep 30 15:01:43 2019][3905:140148297873536] [trace] jk_map_resolve_references::jk_map.c (775): enter [Mon Sep 30 15:01:43 2019][3905:140148297873536] [debug] jk_map_resolve_references::jk_map.c (783): Checking for references with prefix worker. with wildcard (recursion 1) [Mon Sep 30 15:01:43 2019][3905:140148297873536] [trace] jk_map_resolve_references::jk_map.c (839): exit [Mon Sep 30 15:01:43 2019][3905:140148297873536] [trace] jk_shm_calculate_size::jk_shm.c (132): enter [Mon Sep 30 15:01:43 2019][3905:140148297873536] [debug] jk_shm_calculate_size::jk_shm.c (143): JK_SHM_SLOT_SIZE defined as 384, need at least 384 [Mon Sep 30 15:01:43 2019][3905:140148297873536] [debug] jk_shm_calculate_size::jk_shm.c (177): shared memory will contain 1 ajp workers and 0 lb workers with 0 members [Mon Sep 30 15:01:43 2019][3905:140148297873536] [trace] jk_shm_calculate_size::jk_shm.c (184): exit [Mon Sep 30 15:01:43 2019][3905:140148297873536] [trace] do_shm_open::jk_shm.c (548): enter [Mon Sep 30 15:01:43 2019][3905:140148297873536] [debug] do_shm_open::jk_shm.c (622): Truncated shared memory to 1024 [Mon Sep 30 15:01:43 2019][3905:140148297873536] [debug] do_shm_open::jk_shm.c (669): Initialized shared memory /etc/httpd/logs/jk.shm.3905 size=1024 free=384 addr=0x7f76d1838000 [Mon Sep 30 15:01:43 2019][3905:140148297873536] [trace] do_shm_open_lock::jk_shm.c (459): enter [Mon Sep 30 15:01:43 2019][3905:140148297873536] [debug] do_shm_open_lock::jk_shm.c (532): Opened shared memory lock /etc/httpd/logs/jk.shm.3905.lock [Mon Sep 30 15:01:43 2019][3905:140148297873536] [trace] do_shm_open_lock::jk_shm.c (534): exit [Mon Sep 30 15:01:43 2019][3905:140148297873536] [trace] do_shm_open::jk_shm.c (723): exit [Mon Sep 30 15:01:43 2019][3905:140148297873536] [trace] wc_open::jk_worker.c (55): enter [Mon Sep 30 15:01:43 2019][3905:140148297873536] [debug] jk_map_dump::jk_map.c (599): Dump of map 2: 'ServerRoot' -> '/etc/httpd' [Mon Sep 30 15:01:43 2019][3905:140148297873536] [trace] build_worker_map::jk_worker.c (244): enter [Mon Sep 30 15:01:43 2019][3905:140148297873536] [debug] build_worker_map::jk_worker.c (250): creating worker ajp13 [Mon Sep 30 15:01:43 2019][3905:140148297873536] [trace] wc_create_worker::jk_worker.c (130): enter [Mon Sep 30 15:01:43 2019][3905:140148297873536] [debug] wc_create_worker::jk_worker.c (154): about to create instance ajp13 of ajp13 [Mon Sep 30 15:01:43 2019][3905:140148297873536] [trace] ajp13_worker_factory::jk_ajp13_worker.c (80): enter [Mon Sep 30 15:01:43 2019][3905:140148297873536] [trace] ajp_worker_factory::jk_ajp_common.c (3165): enter [Mon Sep 30 15:01:43 2019][3905:140148297873536] [debug] ajp_worker_factory::jk_ajp_common.c (3219): ajp worker 'ajp13' type=2 created [Mon Sep 30 15:01:43 2019][3905:140148297873536] [trace] ajp_worker_factory::jk_ajp_common.c (3222): exit [Mon Sep 30 15:01:43 2019][3905:140148297873536] [trace] ajp13_worker_factory::jk_ajp13_worker.c (92): exit [Mon Sep 30 15:01:43 2019][3905:140148297873536] [debug] wc_create_worker::jk_worker.c (167): about to validate and init ajp13 [Mon Sep 30 15:01:43 2019][3905:140148297873536] [trace] validate::jk_ajp13_worker.c (35): enter [Mon Sep 30 15:01:43 2019][3905:140148297873536] [trace] ajp_validate::jk_ajp_common.c (2814): enter [Mon Sep 30 15:01:43 2019][3905:140148297873536] [debug] ajp_validate::jk_ajp_common.c (2863): worker ajp13 target is 'localhost:8009' [Mon Sep 30 15:01:43 2019][3905:140148297873536] [trace] jk_resolve::jk_connect.c (406): enter [Mon Sep 30 15:01:43 2019][3905:140148297873536] [trace] jk_resolve::jk_connect.c (599): exit [Mon Sep 30 15:01:43 2019][3905:140148297873536] [trace] jk_ajp_push::jk_ajp_common.c (1188): enter [Mon Sep 30 15:01:43 2019][3905:140148297873536] [debug] jk_ajp_push::jk_ajp_common.c (1191): syncing shm for ajp worker 'ajp13' from mem (0->0) [0->0] [Mon Sep 30 15:01:43 2019][3905:140148297873536] [trace] jk_ajp_push::jk_ajp_common.c (1239): exit [Mon Sep 30 15:01:43 2019][3905:140148297873536] [trace] ajp_validate::jk_ajp_common.c (2908): exit [Mon Sep 30 15:01:43 2019][3905:140148297873536] [trace] validate::jk_ajp13_worker.c (37): exit [Mon Sep 30 15:01:43 2019][3905:140148297873536] [trace] init::jk_ajp13_worker.c (47): enter [Mon Sep 30 15:01:43 2019][3905:140148297873536] [trace] ajp_init::jk_ajp_common.c (2974): enter [Mon Sep 30 15:01:43 2019][3905:140148297873536] [debug] ajp_init::jk_ajp_common.c (3077): (ajp13) setting endpoint options: [Mon Sep 30 15:01:43 2019][3905:140148297873536] [debug] ajp_init::jk_ajp_common.c (3081): keepalive:              0 [Mon Sep 30 15:01:43 2019][3905:140148297873536] [debug] ajp_init::jk_ajp_common.c (3085): socket timeout:         0 [Mon Sep 30 15:01:43 2019][3905:140148297873536] [debug] ajp_init::jk_ajp_common.c (3089): socket connect timeout: 0 [Mon Sep 30 15:01:43 2019][3905:140148297873536] [debug] ajp_init::jk_ajp_common.c (3093): buffer size:            0 [Mon Sep 30 15:01:43 2019][3905:140148297873536] [debug] ajp_init::jk_ajp_common.c (3097): pool timeout:           0 [Mon Sep 30 15:01:43 2019][3905:140148297873536] [debug] ajp_init::jk_ajp_common.c (3101): ping timeout:           10000 [Mon Sep 30 15:01:43 2019][3905:140148297873536] [debug] ajp_init::jk_ajp_common.c (3105): connect timeout:        0 [Mon Sep 30 15:01:43 2019][3905:140148297873536] [debug] ajp_init::jk_ajp_common.c (3109): reply timeout:          0 [Mon Sep 30 15:01:43 2019][3905:140148297873536] [debug] ajp_init::jk_ajp_common.c (3113): prepost timeout:        0 [Mon Sep 30 15:01:43 2019][3905:140148297873536] [debug] ajp_init::jk_ajp_common.c (3117): recovery options:       0 [Mon Sep 30 15:01:43 2019][3905:140148297873536] [debug] ajp_init::jk_ajp_common.c (3121): retries:                2 [Mon Sep 30 15:01:43 2019][3905:140148297873536] [debug] ajp_init::jk_ajp_common.c (3125): max packet size:        8192 [Mon Sep 30 15:01:43 2019][3905:140148297873536] [debug] ajp_init::jk_ajp_common.c (3129): retry interval:         100 [Mon Sep 30 15:01:43 2019][3905:140148297873536] [debug] ajp_init::jk_ajp_common.c (3133): busy limit:         0 [Mon Sep 30 15:01:43 2019][3905:140148297873536] [trace] ajp_create_endpoint_cache::jk_ajp_common.c (2924): enter [Mon Sep 30 15:01:43 2019][3905:140148297873536] [debug] ajp_create_endpoint_cache::jk_ajp_common.c (2933): (ajp13) setting connection pool size to 1 with min 1 and acquire timeout 200 [Mon Sep 30 15:01:43 2019][3905:140148297873536] [trace] ajp_create_endpoint_cache::jk_ajp_common.c (2962): exit [Mon Sep 30 15:01:43 2019][3905:140148297873536] [trace] ajp_init::jk_ajp_common.c (3155): exit [Mon Sep 30 15:01:43 2019][3905:140148297873536] [trace] init::jk_ajp13_worker.c (50): exit [Mon Sep 30 15:01:43 2019][3905:140148297873536] [trace] wc_create_worker::jk_worker.c (206): exit [Mon Sep 30 15:01:43 2019][3905:140148297873536] [trace] build_worker_map::jk_worker.c (281): exit [Mon Sep 30 15:01:43 2019][3905:140148297873536] [trace] wc_open::jk_worker.c (93): exit [Mon Sep 30 15:01:43 2019][3905:140148297873536] [info] init_jk::mod_jk.c (3580): mod_jk/1.2.44 initialized [Mon Sep 30 15:01:43 2019][3905:140148297873536] [trace] uri_worker_map_ext::jk_uri_worker_map.c (617): enter [Mon Sep 30 15:01:43 2019][3905:140148297873536] [trace] uri_worker_map_dump::jk_uri_worker_map.c (174): enter [Mon Sep 30 15:01:43 2019][3905:140148297873536] [debug] uri_worker_map_dump::jk_uri_worker_map.c (178): uri map dump after extension stripping: id=1, index=0 file='(null)' reject_unsafe=0 reload=60 modified=0 checked=0 [Mon Sep 30 15:01:43 2019][3905:140148297873536] [debug] uri_worker_map_dump::jk_uri_worker_map.c (184): generation 0: size=0 nosize=0 capacity=0 [Mon Sep 30 15:01:43 2019][3905:140148297873536] [debug] uri_worker_map_dump::jk_uri_worker_map.c (184): generation 1: size=0 nosize=0 capacity=0 [Mon Sep 30 15:01:43 2019][3905:140148297873536] [trace] uri_worker_map_dump::jk_uri_worker_map.c (207): exit [Mon Sep 30 15:01:43 2019][3905:140148297873536] [trace] uri_worker_map_ext::jk_uri_worker_map.c (633): exit [Mon Sep 30 15:01:43 2019][3905:140148297873536] [trace] uri_worker_map_switch::jk_uri_worker_map.c (597): enter [Mon Sep 30 15:01:43 2019][3905:140148297873536] [debug] uri_worker_map_switch::jk_uri_worker_map.c (602): Switching uri worker map from index 0 to index 1 [Mon Sep 30 15:01:43 2019][3905:140148297873536] [trace] uri_worker_map_switch::jk_uri_worker_map.c (609): exit [Mon Sep 30 15:01:43 2019][3906:140148297873536] [trace] jk_child_init::mod_jk.c (3440): enter [Mon Sep 30 15:01:43 2019][3906:140148297873536] [trace] do_shm_open::jk_shm.c (548): enter [Mon Sep 30 15:01:43 2019][3906:140148297873536] [debug] do_shm_open::jk_shm.c (679): Attached shared memory /etc/httpd/logs/jk.shm.3905 [2] size=1024 workers=0 free=0 addr=0x7f76d1838000 [Mon Sep 30 15:01:43 2019][3906:140148297873536] [trace] do_shm_open_lock::jk_shm.c (459): enter [Mon Sep 30 15:01:43 2019][3906:140148297873536] [debug] do_shm_open_lock::jk_shm.c (473): Duplicated shared memory lock /etc/httpd/logs/jk.shm.3905.lock [Mon Sep 30 15:01:43 2019][3906:140148297873536] [trace] do_shm_open_lock::jk_shm.c (475): exit [Mon Sep 30 15:01:43 2019][3906:140148297873536] [trace] do_shm_open::jk_shm.c (723): exit [Mon Sep 30 15:01:43 2019][3906:140148297873536] [debug] jk_child_init::mod_jk.c (3463): Initialized mod_jk/1.2.44 [Mon Sep 30 15:01:43 2019][3906:140148297873536] [trace] jk_child_init::mod_jk.c (3464): exit [Mon Sep 30 15:01:43 2019][3907:140148297873536] [trace] jk_child_init::mod_jk.c (3440): enter [Mon Sep 30 15:01:43 2019][3907:140148297873536] [trace] do_shm_open::jk_shm.c (548): enter [Mon Sep 30 15:01:43 2019][3907:140148297873536] [debug] do_shm_open::jk_shm.c (679): Attached shared memory /etc/httpd/logs/jk.shm.3905 [3] size=1024 workers=0 free=0 addr=0x7f76d1838000 [Mon Sep 30 15:01:43 2019][3907:140148297873536] [trace] do_shm_open_lock::jk_shm.c (459): enter [Mon Sep 30 15:01:43 2019][3907:140148297873536] [debug] do_shm_open_lock::jk_shm.c (473): Duplicated shared memory lock /etc/httpd/logs/jk.shm.3905.lock [Mon Sep 30 15:01:43 2019][3907:140148297873536] [trace] do_shm_open_lock::jk_shm.c (475): exit [Mon Sep 30 15:01:43 2019][3907:140148297873536] [trace] do_shm_open::jk_shm.c (723): exit [Mon Sep 30 15:01:43 2019][3907:140148297873536] [debug] jk_child_init::mod_jk.c (3463): Initialized mod_jk/1.2.44 [Mon Sep 30 15:01:43 2019][3907:140148297873536] [trace] jk_child_init::mod_jk.c (3464): exit [Mon Sep 30 15:01:43 2019][3910:140148297873536] [trace] jk_child_init::mod_jk.c (3440): enter [Mon Sep 30 15:01:43 2019][3910:140148297873536] [trace] do_shm_open::jk_shm.c (548): enter [Mon Sep 30 15:01:43 2019][3910:140148297873536] [debug] do_shm_open::jk_shm.c (679): Attached shared memory /etc/httpd/logs/jk.shm.3905 [4] size=1024 workers=0 free=0 addr=0x7f76d1838000 [Mon Sep 30 15:01:43 2019][3910:140148297873536] [trace] do_shm_open_lock::jk_shm.c (459): enter [Mon Sep 30 15:01:43 2019][3910:140148297873536] [debug] do_shm_open_lock::jk_shm.c (473): Duplicated shared memory lock /etc/httpd/logs/jk.shm.3905.lock [Mon Sep 30 15:01:43 2019][3910:140148297873536] [trace] do_shm_open_lock::jk_shm.c (475): exit [Mon Sep 30 15:01:43 2019][3910:140148297873536] [trace] do_shm_open::jk_shm.c (723): exit [Mon Sep 30 15:01:43 2019][3910:140148297873536] [debug] jk_child_init::mod_jk.c (3463): Initialized mod_jk/1.2.44 [Mon Sep 30 15:01:43 2019][3910:140148297873536] [trace] jk_child_init::mod_jk.c (3464): exit [Mon Sep 30 15:01:43 2019][3908:140148297873536] [trace] jk_child_init::mod_jk.c (3440): enter [Mon Sep 30 15:01:43 2019][3908:140148297873536] [trace] do_shm_open::jk_shm.c (548): enter [Mon Sep 30 15:01:43 2019][3908:140148297873536] [debug] do_shm_open::jk_shm.c (679): Attached shared memory /etc/httpd/logs/jk.shm.3905 [5] size=1024 workers=0 free=0 addr=0x7f76d1838000 [Mon Sep 30 15:01:43 2019][3908:140148297873536] [trace] do_shm_open_lock::jk_shm.c (459): enter [Mon Sep 30 15:01:43 2019][3908:140148297873536] [debug] do_shm_open_lock::jk_shm.c (473): Duplicated shared memory lock /etc/httpd/logs/jk.shm.3905.lock [Mon Sep 30 15:01:43 2019][3908:140148297873536] [trace] do_shm_open_lock::jk_shm.c (475): exit [Mon Sep 30 15:01:43 2019][3908:140148297873536] [trace] do_shm_open::jk_shm.c (723): exit [Mon Sep 30 15:01:43 2019][3908:140148297873536] [debug] jk_child_init::mod_jk.c (3463): Initialized mod_jk/1.2.44 [Mon Sep 30 15:01:43 2019][3908:140148297873536] [trace] jk_child_init::mod_jk.c (3464): exit [Mon Sep 30 15:01:43 2019][3909:140148297873536] [trace] jk_child_init::mod_jk.c (3440): enter [Mon Sep 30 15:01:43 2019][3909:140148297873536] [trace] do_shm_open::jk_shm.c (548): enter [Mon Sep 30 15:01:43 2019][3909:140148297873536] [debug] do_shm_open::jk_shm.c (679): Attached shared memory /etc/httpd/logs/jk.shm.3905 [6] size=1024 workers=0 free=0 addr=0x7f76d1838000 [Mon Sep 30 15:01:43 2019][3909:140148297873536] [trace] do_shm_open_lock::jk_shm.c (459): enter [Mon Sep 30 15:01:43 2019][3909:140148297873536] [debug] do_shm_open_lock::jk_shm.c (473): Duplicated shared memory lock /etc/httpd/logs/jk.shm.3905.lock [Mon Sep 30 15:01:43 2019][3909:140148297873536] [trace] do_shm_open_lock::jk_shm.c (475): exit [Mon Sep 30 15:01:43 2019][3909:140148297873536] [trace] do_shm_open::jk_shm.c (723): exit [Mon Sep 30 15:01:43 2019][3909:140148297873536] [debug] jk_child_init::mod_jk.c (3463): Initialized mod_jk/1.2.44 [Mon Sep 30 15:01:43 2019][3909:140148297873536] [trace] jk_child_init::mod_jk.c (3464): exit [Mon Sep 30 15:04:03 2019][3906:140148297873536] [debug] jk_servlet_normalize::jk_util.c (2184): URI on entering jk_servlet_normalize: [/test-ssi/index.shtml] [Mon Sep 30 15:04:03 2019][3906:140148297873536] [debug] jk_servlet_normalize::jk_util.c (2265): URI on exiting jk_servlet_normalize: [/test-ssi/index.shtml] [Mon Sep 30 15:04:03 2019][3906:140148297873536] [trace] map_uri_to_worker_ext::jk_uri_worker_map.c (1080): enter [Mon Sep 30 15:04:03 2019][3906:140148297873536] [debug] map_uri_to_worker_ext::jk_uri_worker_map.c (1175): Attempting to map URI '/test-ssi/index.shtml' from 0 maps [Mon Sep 30 15:04:03 2019][3906:140148297873536] [trace] find_match::jk_uri_worker_map.c (967): enter [Mon Sep 30 15:04:03 2019][3906:140148297873536] [trace] find_match::jk_uri_worker_map.c (1010): exit [Mon Sep 30 15:04:03 2019][3906:140148297873536] [trace] map_uri_to_worker_ext::jk_uri_worker_map.c (1208): exit [Mon Sep 30 15:04:03 2019][3906:140148297873536] [debug] jk_translate::mod_jk.c (3954): no match for /test-ssi/index.shtml found [Mon Sep 30 15:04:03 2019][3906:140148297873536] [debug] jk_servlet_normalize::jk_util.c (2184): URI on entering jk_servlet_normalize: [/test-ssi/index.shtml] [Mon Sep 30 15:04:03 2019][3906:140148297873536] [debug] jk_servlet_normalize::jk_util.c (2265): URI on exiting jk_servlet_normalize: [/test-ssi/index.shtml] [Mon Sep 30 15:04:03 2019][3906:140148297873536] [trace] map_uri_to_worker_ext::jk_uri_worker_map.c (1080): enter [Mon Sep 30 15:04:03 2019][3906:140148297873536] [debug] map_uri_to_worker_ext::jk_uri_worker_map.c (1175): Attempting to map URI '/test-ssi/index.shtml' from 0 maps [Mon Sep 30 15:04:03 2019][3906:140148297873536] [trace] find_match::jk_uri_worker_map.c (967): enter [Mon Sep 30 15:04:03 2019][3906:140148297873536] [trace] find_match::jk_uri_worker_map.c (1010): exit [Mon Sep 30 15:04:03 2019][3906:140148297873536] [trace] map_uri_to_worker_ext::jk_uri_worker_map.c (1208): exit [Mon Sep 30 15:04:03 2019][3906:140148297873536] [debug] jk_map_to_storage::mod_jk.c (4039): no match for /test-ssi/index.shtml found [Mon Sep 30 15:04:03 2019][3906:140148297873536] [debug] jk_servlet_normalize::jk_util.c (2184): URI on entering jk_servlet_normalize: [] [Mon Sep 30 15:04:03 2019][3906:140148297873536] [emerg] jk_servlet_normalize::jk_util.c (2188): [] does not start with '/'. [Mon Sep 30 15:04:03 2019][3906:140148297873536] [debug] jk_servlet_normalize::jk_util.c (2184): URI on entering jk_servlet_normalize: [] [Mon Sep 30 15:04:03 2019][3906:140148297873536] [emerg] jk_servlet_normalize::jk_util.c (2188): [] does not start with '/'. [Mon Sep 30 15:04:04 2019][3926:140148297873536] [trace] jk_child_init::mod_jk.c (3440): enter [Mon Sep 30 15:04:04 2019][3926:140148297873536] [trace] do_shm_open::jk_shm.c (548): enter [Mon Sep 30 15:04:04 2019][3926:140148297873536] [debug] do_shm_open::jk_shm.c (679): Attached shared memory /etc/httpd/logs/jk.shm.3905 [7] size=1024 workers=0 free=0 addr=0x7f76d1838000 [Mon Sep 30 15:04:04 2019][3926:140148297873536] [trace] do_shm_open_lock::jk_shm.c (459): enter [Mon Sep 30 15:04:04 2019][3926:140148297873536] [debug] do_shm_open_lock::jk_shm.c (473): Duplicated shared memory lock /etc/httpd/logs/jk.shm.3905.lock [Mon Sep 30 15:04:04 2019][3926:140148297873536] [trace] do_shm_open_lock::jk_shm.c (475): exit [Mon Sep 30 15:04:04 2019][3926:140148297873536] [trace] do_shm_open::jk_shm.c (723): exit [Mon Sep 30 15:04:04 2019][3926:140148297873536] [debug] jk_child_init::mod_jk.c (3463): Initialized mod_jk/1.2.44 [Mon Sep 30 15:04:04 2019][3926:140148297873536] [trace] jk_child_init::mod_jk.c (3464): exit
On Wed, Sep 18, 2019 at 7:24 AM Rainer Jung <rainer.j...@kippdata.de <mailto:rainer.j...@kippdata.de>> wrote: 

    Am 17.09.2019 um 17:44 schrieb Ezsra McDonald:
     > Hello,
     >
     > Environment:
     > OS: CentOS 7
     > Apache: Apache/2.4.6 (CentOS)
     > Working Connector: tomcat-connectors-1.2.42
     >
     > When we installed the latest version of the connector, 1.2.46 at
    this time,
     > some of our customer Server Side Includes stopped working. SSIs using
     > the <!--#include
     > file="file-name" --> directives produce the following error in
    the log
     > files:
     >
     > unable to include "layout/includes/headernav.html" in parsed file
     > /var/www/html/horses/index.shtml, subrequest setup returned 400,
    referer:
     > http://10.211.55.44/horses/
     >
     > When we roll back to tomcat-connectors-1.2.42 the SSI works.
     >
     > We do not know why tomcat-connectors impact mod_include.
     >
     > Any assistance would be appreciated.

    It seems you can easily reproduce. Can you set JkLogLevel to trace and
    run one request that shows the failure? Then provide the resulting
    mod_jk log file plus your mod_jk configuration (all Jk... diractives
    plus workers.properties plus uriworkermap.properties or similar if
    in use).

    Thanks and regards,

    Rainer



---------------------------------------------------------------------
To unsubscribe, e-mail: users-unsubscr...@tomcat.apache.org
For additional commands, e-mail: users-h...@tomcat.apache.org

Reply via email to