You are viewing a plain text version of this content. The canonical link for it is here.
Posted to bugs@httpd.apache.org by bu...@apache.org on 2003/05/28 15:25:01 UTC

DO NOT REPLY [Bug 20301] New: - mod_jserv crashes under load (apache 1.3.27,mod_jserv 1.1.2

DO NOT REPLY TO THIS EMAIL, BUT PLEASE POST YOUR BUG 
RELATED COMMENTS THROUGH THE WEB INTERFACE AVAILABLE AT
<http://nagoya.apache.org/bugzilla/show_bug.cgi?id=20301>.
ANY REPLY MADE TO THIS MESSAGE WILL NOT BE COLLECTED AND 
INSERTED IN THE BUG DATABASE.

http://nagoya.apache.org/bugzilla/show_bug.cgi?id=20301

mod_jserv crashes under load (apache 1.3.27,mod_jserv 1.1.2

           Summary: mod_jserv crashes under load (apache 1.3.27,mod_jserv
                    1.1.2
           Product: Apache httpd-1.3
           Version: 1.3.27
          Platform: PC
        OS/Version: Windows NT/2K
            Status: NEW
          Severity: Major
          Priority: Other
         Component: Other mods
        AssignedTo: bugs@httpd.apache.org
        ReportedBy: nick.laqua@newtron.net


After running fine the  whole time, the jserv_module is shut down by the apache 
server without any reason. Problem could be reproduced using three parallel 
threads requesting a simple servlet (IsItWorking).

mod_jserv.log:

27/05/2003 00:57:49:218] (INFO) wrapper: controller got stop event (PH=660)
[27/05/2003 00:57:49:234] (INFO) wrapper: Sending signal to JServ
[27/05/2003 00:57:49:265] (INFO) ajp12: sending shutdown signal
[27/05/2003 00:57:53:546] (ERROR) wrapper: Java Virtual Machine did not exit, 
terminated
[27/05/2003 00:57:53:578] (INFO) wrapper: Java Virtual Machine stopped
[27/05/2003 00:57:53:625] (INFO) wrapper: controller thread stopped
[27/05/2003 00:57:53:656] (INFO) wrapper: Shutdown done (PID=448)
[27/05/2003 00:57:53:656] (INFO) Apache Module was cleaned-up
[27/05/2003 00:58:26:265] (EMERGENCY) ajp12: can not connect to host 
127.0.0.1:8007
[27/05/2003 00:58:26:296] (EMERGENCY) ajp12: connection fail
[27/05/2003 00:58:26:296] (ERROR) an error returned handling request via 
protocol "ajpv12"
[27/05/2003 00:58:27:796] (EMERGENCY) ajp12: can not connect to host 
127.0.0.1:8007
[27/05/2003 00:58:27:796] (EMERGENCY) ajp12: connection fail
[27/05/2003 00:58:27:796] (ERROR) an error returned handling request via 
protocol "ajpv12"
[27/05/2003 00:58:29:328] (EMERGENCY) ajp12: can not connect to host 
127.0.0.1:8007
[27/05/2003 00:58:29:328] (EMERGENCY) ajp12: connection fail


access.log (time looks 2 hours different, just wrong configuration):

193.158.227.12 - - [27/May/2003:02:57:15 +0200] "POST /servlets/HTTPController 
HTTP/1.1" 200 157
193.158.227.12 - - [27/May/2003:02:57:17 +0200] "POST /servlets/HTTPController 
HTTP/1.1" 200 157
193.158.227.12 - - [27/May/2003:02:57:19 +0200] "POST /servlets/HTTPController 
HTTP/1.1" 200 157
193.158.227.12 - - [27/May/2003:02:57:20 +0200] "POST /servlets/HTTPController 
HTTP/1.1" 200 157
193.158.227.12 - - [27/May/2003:02:57:22 +0200] "POST /servlets/HTTPController 
HTTP/1.1" 200 157
193.158.227.12 - - [27/May/2003:02:57:24 +0200] "POST /servlets/HTTPController 
HTTP/1.1" 200 157
193.158.227.12 - - [27/May/2003:02:57:25 +0200] "POST /servlets/HTTPController 
HTTP/1.1" 200 157
193.158.227.12 - - [27/May/2003:02:57:26 +0200] "POST /servlets/HTTPController 
HTTP/1.1" 200 157
193.158.227.12 - - [27/May/2003:02:57:28 +0200] "POST /servlets/HTTPController 
HTTP/1.1" 200 157
193.158.227.12 - - [27/May/2003:02:58:26 +0200] "POST /servlets/HTTPController 
HTTP/1.1" 500 593
193.158.227.12 - - [27/May/2003:02:58:27 +0200] "POST /servlets/HTTPController 
HTTP/1.1" 500 593
193.158.227.12 - - [27/May/2003:02:58:29 +0200] "POST /servlets/HTTPController 
HTTP/1.1" 500 593
193.158.227.12 - - [27/May/2003:02:58:30 +0200] "POST /servlets/HTTPController 
HTTP/1.1" 500 593
193.158.227.12 - - [27/May/2003:02:58:32 +0200] "POST /servlets/HTTPController 
HTTP/1.1" 500 593
193.158.227.12 - - [27/May/2003:02:58:33 +0200] "POST /servlets/HTTPController 
HTTP/1.1" 500 593
193.158.227.12 - - [27/May/2003:02:58:35 +0200] "POST /servlets/HTTPController 
HTTP/1.1" 500 593
193.158.227.12 - - [27/May/2003:02:58:36 +0200] "POST /servlets/HTTPController 
HTTP/1.1" 500 593

retcode 500 (behind server url) = Internal Server error(mod_jserv not available)

jserv.log

27/05/2003 08:55:53:203 CEST] <info> ApacheJServ/1.1.2 is starting...
[27/05/2003 08:55:58:406 CEST] <info> Log queue age limit exceeded
[27/05/2003 08:55:53:203 CEST] <info> Connection authentication is disabled
[27/05/2003 08:55:53:468 CEST] <info> Connection allowed from 
127.0.0.1/127.0.0.1
[27/05/2003 08:55:53:500 CEST] <info> Listening on port 8007 accepting 50 
maximum connections
[27/05/2003 08:55:53:562 CEST] <info> Creating Servlet Zones
[27/05/2003 08:55:53:562 CEST] <info> Servlet Zone root initializing...
[27/05/2003 08:55:53:609 CEST] <info> Initialisation timeout: 10 seconds
[27/05/2003 08:55:53:609 CEST] <info> Destroy timeout: 10 seconds
[27/05/2003 08:55:53:609 CEST] <info> Session timeout: 1800 seconds
[27/05/2003 08:55:53:609 CEST] <info> New session timeout: 1800 seconds
[27/05/2003 08:55:53:609 CEST] <info> Session check frequency: 30 seconds
[27/05/2003 08:55:53:609 CEST] <info> Autoreload on zone file changes: true
[27/05/2003 08:55:53:609 CEST] <info> Autoreload on classfile changes: true
[27/05/2003 08:55:53:609 CEST] <info> Default initArgs: {}
[27/05/2003 08:55:53:656 CEST] <info> Servlet name: IsItWorking
[27/05/2003 08:55:53:656 CEST] <info> Servlet name: HTTPController
[27/05/2003 08:55:58:593 CEST] <servletLog> 
HTTPController/com.seeburger.http.HTTPController: init
[27/05/2003 08:55:58:593 CEST] <servletLog> HTTPController/main 
[HTTPHandlerServlet::init] # ### ServletContext is loaded 
[27/05/2003 08:55:58:593 CEST] <servletLog> HTTPController/main 
[HTTPHandlerServlet::init] # ### in init method of HTTPHandlerServlet, Version  
1.0.2-0703a
[27/05/2003 08:55:58:593 CEST] <servletLog> HTTPController/main 
[HTTPHandlerServlet::init] # ### check the derived flag 
[27/05/2003 08:55:58:593 CEST] <servletLog> HTTPController/main 
[HTTPHandlerServlet::init] # ### check if this method called from derived class 
method
[27/05/2003 08:55:58:671 CEST] <servletLog> HTTPController/main 
[HTTPHandlerServlet::init] # ### seems if this method called from derived class 
method
[27/05/2003 08:55:58:671 CEST] <servletLog> HTTPController/main 
[HTTPHandlerServlet::init] # ### thus return !
[27/05/2003 08:55:58:671 CEST] <servletLog> HTTPController/ ok, going on with 
own init ... 
[27/05/2003 08:55:58:671 CEST] <servletLog> 
HTTPController/HTTPController.init : ### ServletContext is loaded 
[27/05/2003 08:55:58:671 CEST] <servletLog> 
HTTPController/HTTPController.init : ### in init method of HTTPController, 
Version  0.9.4-1106a
[27/05/2003 08:55:58:671 CEST] <servletLog> HTTPController/HTTPController.init 
### Ok, found LOGPROPERTYFILE 'c:\BIS\sys\SHC\SHCtrace.properties'
[27/05/2003 08:56:03:437 CEST] <servletLog> HTTPController/HTTPController.init 
### Ok, found PROPERTYFILE 'c:\BIS\sys\SHC\SHC.properties'
[27/05/2003 08:56:04:015 CEST] <servletLog> HTTPController/Initializing is 
finished !
[27/05/2003 08:56:20:328 CEST] <servletLog> check_servlet: init
[27/05/2003 08:56:26:718 CEST] <servletLog> IsItWorking/IsItWorking: init
[27/05/2003 09:01:19:500 CEST] <info> Terminating ApacheJServ/1.1.2...

at 8:55,Apache was restarted (jserv.log), at 9:01, the jserv module was shut 
down again (same trace in mod_jserv.log)

---------------------------------------------------------------------
To unsubscribe, e-mail: bugs-unsubscribe@httpd.apache.org
For additional commands, e-mail: bugs-help@httpd.apache.org