WAS5 & Windows 2003 IIS Problem

Whenever an Agent tries to dedirect browser to a URL ie. print “[http://server/names.nsf]”, the browser reports a DNS error and I get the following in the WAS log.

http://msdomino/test.nsf/test?openagent

the agent contains a simple print “[http://msdomino/names.nsf]”

Here’s an extract from the was log

[Fri Apr 29 10:05:13 2005] 00001284 00000dbc - TRACE: ESI: esiHandleRequest

[Fri Apr 29 10:05:13 2005] 00001284 00000dbc - TRACE: ESI: esiRequestCreate

[Fri Apr 29 10:05:13 2005] 00001284 00000dbc - TRACE: ESI: esiUrlCreate: URL=‘/test.nsf/test’; query string=‘openagent’

[Fri Apr 29 10:05:13 2005] 00001284 00000dbc - TRACE: ESI: esiUrlCreate: success

[Fri Apr 29 10:05:13 2005] 00001284 00000dbc - TRACE: ESI: esiRequestPushUrl: ‘/test.nsf/test’

[Fri Apr 29 10:05:13 2005] 00001284 00000dbc - TRACE: ESI: esiRequestCreate: success

[Fri Apr 29 10:05:13 2005] 00001284 00000dbc - TRACE: ESI: esiResponseGet

[Fri Apr 29 10:05:13 2005] 00001284 00000dbc - TRACE: ESI: esiRulesGetCacheId: getting cache id for ‘/test.nsf/test’

[Fri Apr 29 10:05:13 2005] 00001284 00000dbc - TRACE: ESI: esiFindEntry: key = ‘/test.nsf/test’; hash = 99bf7a13

[Fri Apr 29 10:05:13 2005] 00001284 00000dbc - TRACE: ESI: esiRulesGetCacheId: cache miss … no rules found

[Fri Apr 29 10:05:13 2005] 00001284 00000dbc - TRACE: ESI: esiResponseCreate: sending request and waiting for response

[Fri Apr 29 10:05:13 2005] 00001284 00000dbc - TRACE: lib_htrequest: htrequestSetURL: Setting the url |/test.nsf/test|

[Fri Apr 29 10:05:13 2005] 00001284 00000dbc - TRACE: ESI: esiRequestGetQueryString: one: ‘openagent’

[Fri Apr 29 10:05:13 2005] 00001284 00000dbc - TRACE: lib_htrequest: htrequestSetURL: Setting the query string |openagent|

[Fri Apr 29 10:05:13 2005] 00001284 00000dbc - TRACE: ESI: esiResponseCreate: adding Surrogate-Capability header

[Fri Apr 29 10:05:13 2005] 00001284 00000dbc - TRACE: lib_htrequest: htrequestSetHeader: Setting the header name |Surrogate-Capability| to value |WS-ESI=“ESI/1.0+”|

[Fri Apr 29 10:05:13 2005] 00001284 00000dbc - TRACE: ws_common: websphereWriteRequestReadResponse: Enter

[Fri Apr 29 10:05:13 2005] 00001284 00000dbc - TRACE: ws_server_group: serverGroupNextRoundRobinServer: Round Robin load balancing

[Fri Apr 29 10:05:13 2005] 00001284 00000dbc - TRACE: ws_server_group: serverGroupNextRoundRobinServer: numPrimaryServers is 1

[Fri Apr 29 10:05:13 2005] 00001284 00000dbc - TRACE: ws_server_group: assureWeightsValid: group default_group

[Fri Apr 29 10:05:13 2005] 00001284 00000dbc - TRACE: ws_server_group: serverGroupGetFirstServer: getting the first server

[Fri Apr 29 10:05:13 2005] 00001284 00000dbc - TRACE: ws_server_group: sum_wlb_weights: default_server: 1 max, 0 cur.

[Fri Apr 29 10:05:13 2005] 00001284 00000dbc - TRACE: ws_server_group: serverGroupGetNextServer: getting the next server

[Fri Apr 29 10:05:13 2005] 00001284 00000dbc - TRACE: ws_server_group: sum_wlb_weights := 0

[Fri Apr 29 10:05:13 2005] 00001284 00000dbc - TRACE: ws_server_group: serverGroupGetFirstServer: getting the first server

[Fri Apr 29 10:05:13 2005] 00001284 00000dbc - TRACE: ws_server_group: serverGroupGetNextServer: getting the next server

[Fri Apr 29 10:05:13 2005] 00001284 00000dbc - TRACE: ws_server_group: serverGroupGetNextServer: getting the next server

[Fri Apr 29 10:05:13 2005] 00001284 00000dbc - TRACE: ws_server_group: serverGroupGetFirstServer: getting the first server

[Fri Apr 29 10:05:13 2005] 00001284 00000dbc - TRACE: ws_server_group: serverGroupCheckServerStatus: Checking status of default_server, ignoreWeights 0, markedDown 0, retryNow 0, wlbAllows 1

[Fri Apr 29 10:05:13 2005] 00001284 00000dbc - TRACE: ws_server_group: lockedServerGroupUseServer: Server default_server picked, weight 0.

[Fri Apr 29 10:05:13 2005] 00001284 00000dbc - TRACE: ws_common: websphereFindTransport: Finding the transport

[Fri Apr 29 10:05:13 2005] 00001284 00000dbc - TRACE: ws_common: websphereFindTransport: Setting the transport(case 2): msdomino.virtual.local on port 9080

[Fri Apr 29 10:05:13 2005] 00001284 00000dbc - TRACE: ws_common: websphereExecute: Executing the transaction with the app server

[Fri Apr 29 10:05:13 2005] 00001284 00000dbc - TRACE: ws_common: websphereGetStream: Getting the stream to the app server

[Fri Apr 29 10:05:13 2005] 00001284 00000dbc - TRACE: ws_transport: transportStreamDequeue: Checking for existing stream from the queue

[Fri Apr 29 10:05:13 2005] 00001284 00000dbc - TRACE: lib_stream: openStream: Opening the stream

[Fri Apr 29 10:05:13 2005] 00001284 00000dbc - TRACE: ws_common: websphereGetStream: Created a new stream; queue was empty

[Fri Apr 29 10:05:13 2005] 00001284 00000dbc - TRACE: lib_htrequest: htrequestWrite: Writing the request:

[Fri Apr 29 10:05:13 2005] 00001284 00000dbc - TRACE: GET /test.nsf/test?openagent HTTP/1.1

[Fri Apr 29 10:05:13 2005] 00001284 00000dbc - TRACE: Connection: Keep-Alive

[Fri Apr 29 10:05:13 2005] 00001284 00000dbc - TRACE: Accept: image/gif, image/x-xbitmap, image/jpeg, image/pjpeg, /

[Fri Apr 29 10:05:13 2005] 00001284 00000dbc - TRACE: Accept-Encoding: gzip, deflate

[Fri Apr 29 10:05:13 2005] 00001284 00000dbc - TRACE: Accept-Language: en-gb

[Fri Apr 29 10:05:13 2005] 00001284 00000dbc - TRACE: Authorization: Basic YWRtaW5pc3RyYXRvcjpkM2wxY0B0ZQ==

[Fri Apr 29 10:05:13 2005] 00001284 00000dbc - TRACE: Host: msdomino

[Fri Apr 29 10:05:13 2005] 00001284 00000dbc - TRACE: User-Agent: Mozilla/4.0 (compatible; MSIE 6.0; Windows NT 5.2; SV1; .NET CLR 1.1.4322)

[Fri Apr 29 10:05:13 2005] 00001284 00000dbc - TRACE: UA-CPU: x86

[Fri Apr 29 10:05:13 2005] 00001284 00000dbc - TRACE: x-ibm-incoming-enc-url: /test.nsf/test?openagent

[Fri Apr 29 10:05:13 2005] 00001284 00000dbc - TRACE: x-ibm-incoming-host: msdomino

[Fri Apr 29 10:05:13 2005] 00001284 00000dbc - TRACE: x-ibm-incoming-port: 80

[Fri Apr 29 10:05:13 2005] 00001284 00000dbc - TRACE: $WSAT: Basic

[Fri Apr 29 10:05:13 2005] 00001284 00000dbc - TRACE: $WSIS: false

[Fri Apr 29 10:05:13 2005] 00001284 00000dbc - TRACE: $WSSC: http

[Fri Apr 29 10:05:13 2005] 00001284 00000dbc - TRACE: $WSPR: HTTP/1.1

[Fri Apr 29 10:05:13 2005] 00001284 00000dbc - TRACE: $WSRA: 10.30.5.31

[Fri Apr 29 10:05:13 2005] 00001284 00000dbc - TRACE: $WSRU: administrator

[Fri Apr 29 10:05:13 2005] 00001284 00000dbc - TRACE: $WSSN: msdomino

[Fri Apr 29 10:05:13 2005] 00001284 00000dbc - TRACE: $WSSP: 80

[Fri Apr 29 10:05:13 2005] 00001284 00000dbc - TRACE: Surrogate-Capability: WS-ESI=“ESI/1.0+”

[Fri Apr 29 10:05:13 2005] 00001284 00000dbc - TRACE: lib_htrequest: htrequestWrite: Writing the request content

[Fri Apr 29 10:05:13 2005] 00001284 00000dbc - TRACE: lib_stream: flushStream: Flushing the stream

[Fri Apr 29 10:05:13 2005] 00001284 00000dbc - TRACE: ws_common: websphereExecute: Wrote the request; reading the response

[Fri Apr 29 10:05:13 2005] 00001284 00000dbc - TRACE: lib_htresponse: htresponseRead: Reading the response:

[Fri Apr 29 10:05:14 2005] 00001284 00000dbc - TRACE: HTTP/1.1 302 Found

[Fri Apr 29 10:05:14 2005] 00001284 00000dbc - TRACE: Date: Fri, 29 Apr 2005 09:04:36 GMT

[Fri Apr 29 10:05:14 2005] 00001284 00000dbc - TRACE: Connection: close

[Fri Apr 29 10:05:14 2005] 00001284 00000dbc - TRACE: Location: http://about:blank

[Fri Apr 29 10:05:14 2005] 00001284 00000dbc - TRACE: ws_common: websphereExecute: Read the response; breaking out of loop

[Fri Apr 29 10:05:14 2005] 00001284 00000dbc - TRACE: ws_common: websphereExecute: Done with Request to app server processing

[Fri Apr 29 10:05:14 2005] 00001284 00000dbc - TRACE: ESI: esiControlCreate: no Surrogate-Control header found

[Fri Apr 29 10:05:14 2005] 00001284 00000dbc - TRACE: ESI: esiResponseGet: bypassing ESI

[Fri Apr 29 10:05:14 2005] 00001284 00000dbc - TRACE: ESI: esiResponsePassThru

[Fri Apr 29 10:05:14 2005] 00001284 00000dbc - TRACE: iis_plugin: cb_write_headers: In the write headers callback

[Fri Apr 29 10:05:14 2005] 00001284 00000dbc - TRACE: iis_plugin: getServerVariable: Getting server variable: SERVER_PROTOCOL

[Fri Apr 29 10:05:14 2005] 00001284 00000dbc - TRACE: lib_htresponse: htresponseGetContentBlock: Getting the next content block

[Fri Apr 29 10:05:14 2005] 00001284 00000dbc - TRACE: lib_htresponse: htresponseGetContentBlock: Read -1; not expected length of 32

[Fri Apr 29 10:05:14 2005] 00001284 00000dbc - TRACE: iis_plugin: cb_write_body: In the write body callback

[Fri Apr 29 10:05:14 2005] 00001284 00000dbc - TRACE: iis_plugin: cb_write_body: Writing chunk

[Fri Apr 29 10:05:14 2005] 00001284 00000dbc - WARNING: iis_plugin: cb_write_body: Response write failed, OS err: 10054

[Fri Apr 29 10:05:14 2005] 00001284 00000dbc - ERROR: ESI: esiResponsePassThru: failed to write body: rc = 7

[Fri Apr 29 10:05:14 2005] 00001284 00000dbc - ERROR: ESI: esiHandleRequest: failed to write response

[Fri Apr 29 10:05:14 2005] 00001284 00000dbc - TRACE: ESI: esiUrlDestroy: ‘/test.nsf/test’

[Fri Apr 29 10:05:14 2005] 00001284 00000dbc - TRACE: ESI: esiResponseRelease

[Fri Apr 29 10:05:14 2005] 00001284 00000dbc - TRACE: ESI: esiResponseDestroy

[Fri Apr 29 10:05:14 2005] 00001284 00000dbc - ERROR: ws_common: websphereHandleRequest: Failed to handle request

[Fri Apr 29 10:05:14 2005] 00001284 00000dbc - TRACE: ws_common: websphereEndRequest: Ending the request

[Fri Apr 29 10:05:14 2005] 00001284 00000dbc - TRACE: lib_stream: destroyStream: Destroying the stream

[Fri Apr 29 10:05:14 2005] 00001284 00000dbc - TRACE: iis_plugin: HttpFilterProc: In HttpFilterProc for SF_NOTIFY_LOG

Subject: WAS5 & Windows 2003 IIS Problem

IBM Lotus ship a very old version of the websfear plugin for IIS, even with the latest domino server code. This is the cause of my problems.

http://www-1.ibm.com/support/docview.wss?uid=swg24007265