You are viewing a plain text version of this content. The canonical link for it is here.
Posted to users@tomcat.apache.org by Jost Richstein <jr...@softdecc.com> on 2010/10/27 15:24:23 UTC

ISAPI-Problem

Hi,

I have the following problem on a Windows Server 2003 64Bit (AMD).

Configuration:
IIS 6, isapi_redirect.dll AMD64 Version 1.2.30, Tomcat 5.5.27, JDk 1.6.0_12 64Bit.

I am using the following small worker.properties:

  ps=\
  worker.list=ajp13
  worker.ajp13.port=8010
  worker.ajp13.host=localhost
  worker.ajp13.type=ajp13
  worker.ajp13.connection_pool_size=500

My isapi_redirect.properties contains only (no other parameters set)

extension_uri
log_file
log_level (error)
worker_file
worker_mount_file

My Tomcat site works fine in general, there are up to 1.400 users active and the site
answers requests very fast. However I have the following log entries every few minutes
(sometimes every few seconds) in jk.log. Every time that pair of entries:

  [Wed Oct 27 12:15:37.764 2010] [3156:3284] [error] ajp_service::jk_ajp_common.c (2559): (ajp13) connecting to tomcat failed.
  [Wed Oct 27 12:15:37.811 2010] [3156:3284] [error] HttpExtensionProc::jk_isapi_plugin.c (2195): service() failed with http error 500

And I do not have any other log entries in Tomcat- or Application Log. The client receives
an incomplete HTTP-Response, the response has no "Content-Length" header and no content,
for example.

Any hints or ideas?

Regards,
Jost




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


Re: ISAPI-Problem

Posted by Jost Richstein <jr...@softdecc.com>.
And here is whole response I receive, if I run into that error:

HTTP/1.1 500 Internal Server Error
Date: Thu, 04 Nov 2010 16:26:36 GMT
Server: Microsoft-IIS/6.0
X-Powered-By: ASP.NET
Content-Type: text/html

<!DOCTYPE HTML PUBLIC "-//W3C//DTD HTML 4.0 Transitional//EN" "http://www.w3c.org/TR/REC-html40/loose.dtd">
<html><head>
   ...deleted some Comments and style information...
<title>Internal Server Error!</title>
</head><body>
<h1>Internal Server Error!</h1>
<p>
The server encountered an internal error and was unable to complete your request.
</p>
<p>
<br>&nbsp;<br>&nbsp;<br>&nbsp;<br>&nbsp;
Jakarta/ISAPI/isapi_redirector/1.2.30
<br>&nbsp;
</p><hr>
<p id="footer">
Copyright © 1999-2010 Apache Software Foundation<br>
All Rights Reserved
</p>
HTTP/1.1 200 OK
Date: Thu, 04 Nov 2010 16:21:59 GMT
Server: Microsoft-IIS/6.0
X-Powered-By: ASP.NET
Content-Type: text/plain;charset=ISO-8859-1
Content-Length: 4

true</body></html>

The html-body contains the response I would expect. Because there is no "Content-Length"-Header or
"chunked encoding" or "Connection: close" or what ever, a browser is not able to determine when
the response has to be closed. I looks like the browser hangs - until a timeout is triggered.
Like I said before: the request before was a request with a "Content-Length: 0"-Header (and no content)
and I suppose it has to do with the error afterwards.


-------- Original-Nachricht --------
Betreff: Re: ISAPI-Problem
Von: Jost Richstein <jr...@softdecc.com>
An: Tomcat Users List <us...@tomcat.apache.org>
Datum: 03.11.2010 12:34

>
>>> On 29.10.2010 09:18, Jost Richstein wrote:
>>>>> On 27.10.2010 15:24, Jost Richstein wrote:
>>>> that is the log output with log level "info":
>>>
>>> Double check: no other message betwen about 09:00 and the following line which is marked with "3076:4308]"?
>>
>> No, I copied it directly from the log file. I have always these 5 lines of output grouped together.
>> However I have the same lines in that case at 09:04:05.
>
> Rainer,
>
> I checked the complete log file. There are no other messages for hours, except these group of entries.
> IIS was restarted at 01:30. However it sometimes says "closing socket -1". Looks strange, because I
> think "-1" is not a valid socket number:
>
> jk_ajp_common.c (1490): (ajp13_internet) did not receive END_RESPONSE, closing socket -1
>
> Could the problem be related to http://issues.apache.org/bugzilla/show_bug.cgi?id=49413 that
> was fixed in version 1.2.31? Or other AJP fixes in Tomcat since version 5.5.27?
>
> I see some responses to earlier requests just before the error occurs with "content-length=0",
> what is correct in that situation, but could that cause the error afterwards?
>
>
>>>> [Fri Oct 29 09:04:15.312 2010] [3076:4308] [info]
>>>> ajp_service::jk_ajp_common.c (2540): (ajp13_internet) sending request to
>>>> tomcat failed (recoverable), because of server error (attempt=1)
>>>> [Fri Oct 29 09:04:15.483 2010] [3076:4308] [info]
>>>> ajp_send_request::jk_ajp_common.c (1490): (ajp13_internet) did not
>>>> receive END_RESPONSE, closing socket 1628
>>>> [Fri Oct 29 09:04:15.546 2010] [3076:4308] [info]
>>>> ajp_service::jk_ajp_common.c (2540): (ajp13_internet) sending request to
>>>> tomcat failed (recoverable), because of server error (attempt=2)
>>>> [Fri Oct 29 09:04:15.593 2010] [3076:4308] [error]
>>>> ajp_service::jk_ajp_common.c (2559): (ajp13_internet) connecting to
>>>> tomcat failed.
>>>> [Fri Oct 29 09:04:15.655 2010] [3076:4308] [error]
>>>> HttpExtensionProc::jk_isapi_plugin.c (2195): service() failed with http
>>>> error 500
>>>>
>>>> I still have no other info, in particular no log entry in Tomcat or my
>>>> applications indicating an error in Tomcat or my apps.
>>>> The problem came in after we switched from Version 1.2.14 to 1.2.30 of
>>>> the redirector dll.
>>>
>>> Regards,
>>>
>>> Rainer

-- 
Jost Richstein
SoftDeCC Software GmbH
Email: jrichstein@softdecc.com
Tel.: +49 89 89 06 78 47
Fax.: +49 89 89 06 78 33

SoftDeCC Software GmbH; Gesellschaft mit beschränkter Haftung; Sitz der Gesellschaft: München; Registergericht: München, HRB 123667; 
Geschäftsführer: Ralf Malis, Georg Nüssel, Jost Richstein, Gerd Wilts


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


Re: ISAPI-Problem

Posted by Jost Richstein <jr...@softdecc.com>.
>> On 29.10.2010 09:18, Jost Richstein wrote:
>>>> On 27.10.2010 15:24, Jost Richstein wrote:
>>> that is the log output with log level "info":
>>
>> Double check: no other message betwen about 09:00 and the following line which is marked with "3076:4308]"?
>
> No, I copied it directly from the log file. I have always these 5 lines of output grouped together.
> However I have the same lines in that case at 09:04:05.

Rainer,

I checked the complete log file. There are no other messages for hours, except these group of entries.
IIS was restarted at 01:30. However it sometimes says "closing socket -1". Looks strange, because I
think "-1" is not a valid socket number:

   jk_ajp_common.c (1490): (ajp13_internet) did not receive END_RESPONSE, closing socket -1

Could the problem be related to http://issues.apache.org/bugzilla/show_bug.cgi?id=49413 that
was fixed in version 1.2.31? Or other AJP fixes in Tomcat since version 5.5.27?

I see some responses to earlier requests just before the error occurs with "content-length=0",
what is correct in that situation, but could that cause the error afterwards?


>>> [Fri Oct 29 09:04:15.312 2010] [3076:4308] [info]
>>> ajp_service::jk_ajp_common.c (2540): (ajp13_internet) sending request to
>>> tomcat failed (recoverable), because of server error (attempt=1)
>>> [Fri Oct 29 09:04:15.483 2010] [3076:4308] [info]
>>> ajp_send_request::jk_ajp_common.c (1490): (ajp13_internet) did not
>>> receive END_RESPONSE, closing socket 1628
>>> [Fri Oct 29 09:04:15.546 2010] [3076:4308] [info]
>>> ajp_service::jk_ajp_common.c (2540): (ajp13_internet) sending request to
>>> tomcat failed (recoverable), because of server error (attempt=2)
>>> [Fri Oct 29 09:04:15.593 2010] [3076:4308] [error]
>>> ajp_service::jk_ajp_common.c (2559): (ajp13_internet) connecting to
>>> tomcat failed.
>>> [Fri Oct 29 09:04:15.655 2010] [3076:4308] [error]
>>> HttpExtensionProc::jk_isapi_plugin.c (2195): service() failed with http
>>> error 500
>>>
>>> I still have no other info, in particular no log entry in Tomcat or my
>>> applications indicating an error in Tomcat or my apps.
>>> The problem came in after we switched from Version 1.2.14 to 1.2.30 of
>>> the redirector dll.
>>
>> Regards,
>>
>> Rainer


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


Re: ISAPI-Problem

Posted by Jost Richstein <jr...@softdecc.com>.
> On 29.10.2010 09:18, Jost Richstein wrote:
>>> On 27.10.2010 15:24, Jost Richstein wrote:
>> that is the log output with log level "info":
>
> Double check: no other message betwen about 09:00 and the following line which is marked with "3076:4308]"?

No, I copied it directly from the log file. I have always these 5 lines of output grouped together.
However I have the same lines in that case at 09:04:05.

>
>> [Fri Oct 29 09:04:15.312 2010] [3076:4308] [info]
>> ajp_service::jk_ajp_common.c (2540): (ajp13_internet) sending request to
>> tomcat failed (recoverable), because of server error (attempt=1)
>> [Fri Oct 29 09:04:15.483 2010] [3076:4308] [info]
>> ajp_send_request::jk_ajp_common.c (1490): (ajp13_internet) did not
>> receive END_RESPONSE, closing socket 1628
>> [Fri Oct 29 09:04:15.546 2010] [3076:4308] [info]
>> ajp_service::jk_ajp_common.c (2540): (ajp13_internet) sending request to
>> tomcat failed (recoverable), because of server error (attempt=2)
>> [Fri Oct 29 09:04:15.593 2010] [3076:4308] [error]
>> ajp_service::jk_ajp_common.c (2559): (ajp13_internet) connecting to
>> tomcat failed.
>> [Fri Oct 29 09:04:15.655 2010] [3076:4308] [error]
>> HttpExtensionProc::jk_isapi_plugin.c (2195): service() failed with http
>> error 500
>>
>> I still have no other info, in particular no log entry in Tomcat or my
>> applications indicating an error in Tomcat or my apps.
>> The problem came in after we switched from Version 1.2.14 to 1.2.30 of
>> the redirector dll.
>
> Regards,
>
> Rainer
>
> ---------------------------------------------------------------------
> To unsubscribe, e-mail: users-unsubscribe@tomcat.apache.org
> For additional commands, e-mail: users-help@tomcat.apache.org


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


Re: ISAPI-Problem

Posted by Rainer Jung <ra...@kippdata.de>.
On 29.10.2010 09:18, Jost Richstein wrote:
>> On 27.10.2010 15:24, Jost Richstein wrote:
> that is the log output with log level "info":

Double check: no other message betwen about 09:00 and the following line 
which is marked with "3076:4308]"?

> [Fri Oct 29 09:04:15.312 2010] [3076:4308] [info]
> ajp_service::jk_ajp_common.c (2540): (ajp13_internet) sending request to
> tomcat failed (recoverable), because of server error (attempt=1)
> [Fri Oct 29 09:04:15.483 2010] [3076:4308] [info]
> ajp_send_request::jk_ajp_common.c (1490): (ajp13_internet) did not
> receive END_RESPONSE, closing socket 1628
> [Fri Oct 29 09:04:15.546 2010] [3076:4308] [info]
> ajp_service::jk_ajp_common.c (2540): (ajp13_internet) sending request to
> tomcat failed (recoverable), because of server error (attempt=2)
> [Fri Oct 29 09:04:15.593 2010] [3076:4308] [error]
> ajp_service::jk_ajp_common.c (2559): (ajp13_internet) connecting to
> tomcat failed.
> [Fri Oct 29 09:04:15.655 2010] [3076:4308] [error]
> HttpExtensionProc::jk_isapi_plugin.c (2195): service() failed with http
> error 500
>
> I still have no other info, in particular no log entry in Tomcat or my
> applications indicating an error in Tomcat or my apps.
> The problem came in after we switched from Version 1.2.14 to 1.2.30 of
> the redirector dll.

Regards,

Rainer

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


Re: ISAPI-Problem

Posted by Jost Richstein <jr...@softdecc.com>.
> On 27.10.2010 15:24, Jost Richstein wrote:
>> Hi,
>>
>> I have the following problem on a Windows Server 2003 64Bit (AMD).
>>
>> Configuration:
>> IIS 6, isapi_redirect.dll AMD64 Version 1.2.30, Tomcat 5.5.27, JDk
>> 1.6.0_12 64Bit.
>>
>> I am using the following small worker.properties:
>>
>> ps=\
>> worker.list=ajp13
>> worker.ajp13.port=8010
>> worker.ajp13.host=localhost
>> worker.ajp13.type=ajp13
>> worker.ajp13.connection_pool_size=500
>>
>> My isapi_redirect.properties contains only (no other parameters set)
>>
>> extension_uri
>> log_file
>> log_level (error)
>> worker_file
>> worker_mount_file
>>
>> My Tomcat site works fine in general, there are up to 1.400 users active
>> and the site
>> answers requests very fast. However I have the following log entries
>> every few minutes
>> (sometimes every few seconds) in jk.log. Every time that pair of entries:
>>
>> [Wed Oct 27 12:15:37.764 2010] [3156:3284] [error]
>> ajp_service::jk_ajp_common.c (2559): (ajp13) connecting to tomcat failed.
>> [Wed Oct 27 12:15:37.811 2010] [3156:3284] [error]
>> HttpExtensionProc::jk_isapi_plugin.c (2195): service() failed with http
>> error 500
>
> Increase your log_level to "info". This will provide additional log lines directly before the ones you cited which contain more detailed
> information about the root cause.
>
> Regards,
>
> Rainer
>
Rainer,

that is the log output with log level "info":

[Fri Oct 29 09:04:15.312 2010] [3076:4308] [info] ajp_service::jk_ajp_common.c (2540): (ajp13_internet) sending request to tomcat failed 
(recoverable), because of server error (attempt=1)
[Fri Oct 29 09:04:15.483 2010] [3076:4308] [info] ajp_send_request::jk_ajp_common.c (1490): (ajp13_internet) did not receive END_RESPONSE, 
closing socket 1628
[Fri Oct 29 09:04:15.546 2010] [3076:4308] [info] ajp_service::jk_ajp_common.c (2540): (ajp13_internet) sending request to tomcat failed 
(recoverable), because of server error (attempt=2)
[Fri Oct 29 09:04:15.593 2010] [3076:4308] [error] ajp_service::jk_ajp_common.c (2559): (ajp13_internet) connecting to tomcat failed.
[Fri Oct 29 09:04:15.655 2010] [3076:4308] [error] HttpExtensionProc::jk_isapi_plugin.c (2195): service() failed with http error 500

I still have no other info, in particular no log entry in Tomcat or my applications indicating an error in Tomcat or my apps.
The problem came in after we switched from Version 1.2.14 to 1.2.30 of the redirector dll.

Regards,
Jost



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


Re: ISAPI-Problem

Posted by Rainer Jung <ra...@kippdata.de>.
On 27.10.2010 15:24, Jost Richstein wrote:
> Hi,
>
> I have the following problem on a Windows Server 2003 64Bit (AMD).
>
> Configuration:
> IIS 6, isapi_redirect.dll AMD64 Version 1.2.30, Tomcat 5.5.27, JDk
> 1.6.0_12 64Bit.
>
> I am using the following small worker.properties:
>
> ps=\
> worker.list=ajp13
> worker.ajp13.port=8010
> worker.ajp13.host=localhost
> worker.ajp13.type=ajp13
> worker.ajp13.connection_pool_size=500
>
> My isapi_redirect.properties contains only (no other parameters set)
>
> extension_uri
> log_file
> log_level (error)
> worker_file
> worker_mount_file
>
> My Tomcat site works fine in general, there are up to 1.400 users active
> and the site
> answers requests very fast. However I have the following log entries
> every few minutes
> (sometimes every few seconds) in jk.log. Every time that pair of entries:
>
> [Wed Oct 27 12:15:37.764 2010] [3156:3284] [error]
> ajp_service::jk_ajp_common.c (2559): (ajp13) connecting to tomcat failed.
> [Wed Oct 27 12:15:37.811 2010] [3156:3284] [error]
> HttpExtensionProc::jk_isapi_plugin.c (2195): service() failed with http
> error 500

Increase your log_level to "info". This will provide additional log 
lines directly before the ones you cited which contain more detailed 
information about the root cause.

Regards,

Rainer

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


Re: ISAPI-Problem

Posted by Jost Richstein <jr...@softdecc.com>.
I did not say "concurrent users". Just active sessions. There are,
under normal circumstances, not more than 50 conccurent requests.

-------- Original-Nachricht --------
Betreff: Re: ISAPI-Problem
Von: Mladen Turk <mt...@apache.org>
An: users@tomcat.apache.org
Datum: 27.10.2010 15:30

> On 10/27/2010 03:24 PM, Jost Richstein wrote:
>>
>> I am using the following small worker.properties:
>>
>> worker.ajp13.connection_pool_size=500
>
> ...
>
>> My Tomcat site works fine in general, there are up to 1.400 users active and the site
>
> So how do you expect 500 connections will serve up to 1400 concurrent users?
>
>
> Regards

-- 
Jost Richstein
SoftDeCC Software GmbH
Email: jrichstein@softdecc.com
Tel.: +49 89 89 06 78 47
Fax.: +49 89 89 06 78 33

SoftDeCC Software GmbH; Gesellschaft mit beschränkter Haftung; Sitz der Gesellschaft: München; Registergericht: München, HRB 123667; 
Geschäftsführer: Ralf Malis, Georg Nüssel, Jost Richstein, Gerd Wilts


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


Re: ISAPI-Problem

Posted by Mladen Turk <mt...@apache.org>.
On 10/27/2010 03:24 PM, Jost Richstein wrote:
>
> I am using the following small worker.properties:
>
> worker.ajp13.connection_pool_size=500

...

> My Tomcat site works fine in general, there are up to 1.400 users active and the site

So how do you expect 500 connections will serve up to 1400 concurrent users?


Regards
-- 
^TM

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