0 Replies Latest reply: Apr 12, 2013 5:28 AM by 939405 RSS

    Append Extra Slash for Application Request through OHS

    939405
      Hello All,

      I am writing to request your help on the following blocking issue :

      In my attempt to configure End-End SSL between Browser->OHS Plugin->SSL Weblogic Managed Server ,
      Weblogic is giving me an incorrect response while constructing the absolute redirect.

      What i mean is :
      I am trying to excess an application deployed on an SSL Weblogic as :
      https://adc2110491.us.oracle.com:12004/Calendar

      But when i configure this through OHS, and then try to access the same application via URL :
      https://adc2110491.us.oracle.com:4443/test/Calendar - This doesnt work !

      Instead if i append an extra "Slash" in the URL, the application is accessible(This is a workaround and not an expected behavior) :
      https://adc2110491.us.oracle.com:4443/test/Calendar/ - This works !

      For such kind of configuration from OHS side, I enabled a property called "*WebLogic Plug-In Enabled*" from "WLS Managed Server -> General settings"

      Please note that, in case When above mentioned Weblogic-Server is NOT SSL, this works fine.
      This error is only in SSL case.

      Also, Pasting the log file below for one such failed request :


      +Tue Apr  9 11:47:12 2013 <1009513655332321> ================New Request: [GET /test/Calendar HTTP/1.1] =================+
      Tue Apr  9 11:47:12 2013 <1009513655332321> INFO: SSL is configured
      Tue Apr  9 11:47:12 2013 <1009513655332321> Using Uri /test/Calendar
      Tue Apr  9 11:47:12 2013 <1009513655332321> After trimming path: '/Calendar'
      Tue Apr  9 11:47:12 2013 <1009513655332321> The final request string is '/Calendar'
      Tue Apr  9 11:47:12 2013 <1009513655332321> parseServerList: Socket Address hostnames  'adc2110491.us.oracle.com:12004'
      +Tue Apr  9 11:47:12 2013 <1009513655332321> Host extracted from serverlist is [adc2110491.us.oracle.com]+
      Tue Apr  9 11:47:12 2013 <1009513655332321> parseServerList:  IP from socket Address [10.232.130.87]
      Tue Apr  9 11:47:12 2013 <1009513655332321> Initializing lastIndex=0 for a list of length=1
      Tue Apr  9 11:47:12 2013 <1009513655332321> getListNode: created a new server node: id='adc2110491.us.oracle.com:12004' server_name='adc2110491.us.oracle.com', port='4443'
      +Tue Apr  9 11:47:12 2013 <1009513655332321> getPreferred: availcookie=[s_nr=1365505876368; ADMINCONSOLESESSION=FGY9RkfPy7xrvwn4zYR0LqfTV0vGkBjPxhjxQbQGlkhjXJ22LHXJ!1111363896; _WL_AUTHCOOKIE_ADMINCONSOLESESSION=mSkOg.X9HBLf9jgkR.PJ; JSESSIONID=l2GkRkgJczv7vjf9p2dh892WhDTvHglTHdFMppJLLCxQL1pmyJJN!-1304266397]+
      Tue Apr  9 11:47:12 2013 <1009513655332321> Found cookie from cookie header: JSESSIONID=l2GkRkgJczv7vjf9p2dh892WhDTvHglTHdFMppJLLCxQL1pmyJJN!-1304266397
      Tue Apr  9 11:47:12 2013 <1009513655332321> Parsing cookie JSESSIONID=l2GkRkgJczv7vjf9p2dh892WhDTvHglTHdFMppJLLCxQL1pmyJJN!-1304266397
      +Tue Apr  9 11:47:12 2013 <1009513655332321> getpreferredServersFromCookie: [-1304266397]+
      +Tue Apr  9 11:47:12 2013 <1009513655332321> primaryJVMID: [-1304266397], secondaryJVMID: []+
      Tue Apr  9 11:47:12 2013 <1009513655332321> No of JVMIDs found in cookie: 1
      Tue Apr  9 11:47:12 2013 <1009513655332321> Number of nodes in the list: 1
      Tue Apr  9 11:47:12 2013 <1009513655332321> SrvrInfo 0 with JVMID:
      Tue Apr  9 11:47:12 2013 <1009513655332321> getPreferredFromCookie: Start Position is 0, listLen is 1
      Tue Apr  9 11:47:12 2013 <1009513655332321> getPreferredFromCookie: Either JVMIDs not set or they are stale. Will try to get JVMIDs from WLS
      Tue Apr  9 11:47:12 2013 <1009513655332321> initJVMID: Iterating SrvrList from position 0
      Tue Apr  9 11:47:12 2013 <1009513655332321> ======internal request /bea_wls_internal/WLDummyInitJVMIDs======
      initJVMID: Trying Host[10.232.130.87] Port[12004] SecurePort[12004] useSSL [1] ioTimeout [30] socketTimeout [2]
      Tue Apr  9 11:47:12 2013 <1009513655332321> Local Port of the socket is 22159
      Tue Apr  9 11:47:12 2013 <1009513655332321> Remote Host 10.232.130.87 Remote Port 12004
      Tue Apr  9 11:47:12 2013 <1009513655332321> URL::sendHeaders(): meth='HEAD' file='/bea_wls_internal/WLDummyInitJVMIDs' protocol='HTTP/1.0'
      +Tue Apr  9 11:47:12 2013 <1009513655332321> Hdrs to WLS: [Connection]=[Close]+
      +Tue Apr  9 11:47:12 2013 <1009513655332321> Hdrs to WLS: [X-WebLogic-Request-ClusterInfo]=[true]+
      +Tue Apr  9 11:47:12 2013 <1009513655332321> Hdrs to WLS: [X-WebLogic-Force-JVMID]=[unset]+
      +Tue Apr  9 11:47:12 2013 <1009513655332321> Hdrs to WLS: [X-WebLogic-KeepAliveSecs]=[30]+
      Tue Apr  9 11:47:12 2013 <1009513655332321> Need to send 167
      Tue Apr  9 11:47:12 2013 <1009513655332321> SSLWrite sent 167, Error = 0
      Tue Apr  9 11:47:12 2013 <1009513655332321> SSLWrite completed, sent 167
      Tue Apr  9 11:47:12 2013 <1009513655332321> URL::parseHeaders: Value of parsedHeaders = [0]
      Tue Apr  9 11:47:12 2013 <1009513655332321> Reader::fill(): first=0 last=0 toRead=4096
      +Tue Apr  9 11:47:12 2013 <1009513655332321> URL::parseHeaders: CompleteStatusLine set to [HTTP/1.1 404 Not Found]+
      Tue Apr  9 11:47:12 2013 <1009513655332321> URL::parseHeaders: StatusLine set to [404 Not Found]
      +Tue Apr  9 11:47:12 2013 <1009513655332321> Hdrs from WLS:[Connection]=[close]+
      +Tue Apr  9 11:47:12 2013 <1009513655332321> Hdrs from WLS:[Date]=[Tue, 09 Apr 2013 18:47:12 GMT]+
      +Tue Apr  9 11:47:12 2013 <1009513655332321> Hdrs from WLS:[Content-Length]=[1214]+
      +Tue Apr  9 11:47:12 2013 <1009513655332321> Hdrs from WLS:[Content-Type]=[text/html; charset=UTF-8]+
      +Tue Apr  9 11:47:12 2013 <1009513655332321> Hdrs from WLS:[X-WebLogic-Cluster-Hash]=[yo3Df7lxCy0/YU7vJMjHoUNLu9I]+
      +Tue Apr  9 11:47:12 2013 <1009513655332321> Hdrs from WLS:[X-Powered-By]=[Servlet/2.5 JSP/2.1]+
      +Tue Apr  9 11:47:12 2013 <1009513655332321> Hdrs from WLS:[X-WebLogic-Cluster-List]=[-1304266397!183009879!12003!12004]+
      Tue Apr  9 11:47:12 2013 <1009513655332321> parsed all headers OK
      Tue Apr  9 11:47:12 2013 <1009513655332321> Parsing cluster list: -1304266397!183009879!12003!12004
      Tue Apr  9 11:47:12 2013 <1009513655332321> parseJVMID: Parsing JVMID '-1304266397!183009879!12003!12004'
      Tue Apr  9 11:47:12 2013 <1009513655332321> parseJVMID: Actually parsing '-1304266397!183009879!12003!12004'
      Tue Apr  9 11:47:12 2013 <1009513655332321> ServerInfo struct for JVMID '-1304266397' populated, Server Details are: OrigHostInfo [10.232.130.87], isOrigHostInfoDNS [0], Host [10.232.130.87], Port [12003], SecurePort [12004]
      Tue Apr  9 11:47:12 2013 <1009513655332321> Initializing lastIndex=0 for a list of length=1
      +Tue Apr  9 11:47:12 2013 <1009513655332321> initJVMID: Trying to locate Primary or Secondary using SrvrInfo with JVMID [-1304266397]+
      Tue Apr  9 11:47:12 2013 <1009513655332321> initJVMID: Found Primary 10.232.130.87:12003:12004
      Tue Apr  9 11:47:12 2013 <1009513655332321> .....internal request /bea_wls_internal/WLDummyInitJVMIDs.....processed
      Tue Apr  9 11:47:12 2013 <1009513655332321> getPreferredFromCookie: Found 1 servers
      Tue Apr  9 11:47:12 2013 <1009513655332321> attempt #0 out of a max of 5
      Tue Apr  9 11:47:12 2013 <1009513655332321> trying connect to PRIMARY '10.232.130.87'/12003/12004
      Tue Apr  9 11:47:12 2013 <1009513655332321> getPooledConn: found a host and port/securePort match
      Tue Apr  9 11:47:12 2013 <1009513655332321> getPooledConn: No more connections in the pool for Host[10.232.130.87] Port[12003] SecurePort[12004]
      Tue Apr  9 11:47:12 2013 <1009513655332321> getConnection: Obtained a URL object from factory
      Tue Apr  9 11:47:12 2013 <1009513655332321> getConnection: Setting SSL properties on URL object
      Tue Apr  9 11:47:12 2013 <1009513655332321> Local Port of the socket is 22160
      Tue Apr  9 11:47:12 2013 <1009513655332321> Remote Host 10.232.130.87 Remote Port 12004
      Tue Apr  9 11:47:12 2013 <1009513655332321> created a new connection to preferred server '10.232.130.87/12004' for '/Calendar', Local port:0
      +Tue Apr  9 11:47:12 2013 <1009513655332321> Hdrs from clnt:[Host]=[adc2110491.us.oracle.com:4443]+
      +Tue Apr  9 11:47:12 2013 <1009513655332321> Hdrs from clnt:[User-Agent]=[Mozilla/5.0 (Windows NT 6.1; WOW64; rv:10.0.4) Gecko/20100101 Firefox/10.0.4]+
      +Tue Apr  9 11:47:12 2013 <1009513655332321> Hdrs from clnt:[Accept]=[text/html,application/xhtml+xml,application/xml;q=0.9,*/*;q=0.8]+
      +Tue Apr  9 11:47:12 2013 <1009513655332321> Hdrs from clnt:[Accept-Language]=[en-us,en;q=0.5]+
      +Tue Apr  9 11:47:12 2013 <1009513655332321> Hdrs from clnt:[Accept-Encoding]=[gzip, deflate]+
      +Tue Apr  9 11:47:12 2013 <1009513655332321> Hdrs from clnt:[Connection]=[keep-alive]+
      +Tue Apr  9 11:47:12 2013 <1009513655332321> Hdrs from clnt:[Cookie]=[s_nr=1365505876368; ADMINCONSOLESESSION=FGY9RkfPy7xrvwn4zYR0LqfTV0vGkBjPxhjxQbQGlkhjXJ22LHXJ!1111363896; _WL_AUTHCOOKIE_ADMINCONSOLESESSION=mSkOg.X9HBLf9jgkR.PJ; JSESSIONID=l2GkRkgJczv7vjf9p2dh892WhDTvHglTHdFMppJLLCxQL1pmyJJN!-1304266397]+
      +Tue Apr  9 11:47:12 2013 <1009513655332321> Hdrs from clnt:[ECID-Context]=[1.004qV7vEojhDsXY5HrG7yf0006Jj0000SH;kXjE1ZDLIPHHj4TPnKVB3HOR_UOPXIS]+
      Tue Apr  9 11:47:12 2013 <1009513655332321> parse_header is done
      Tue Apr  9 11:47:12 2013 <1009513655332321> Method is GET
      Tue Apr  9 11:47:12 2013 <1009513655332321> About to call parseHeaders
      Tue Apr  9 11:47:12 2013 <1009513655332321> URL::parseHeaders: Value of parsedHeaders = [0]
      Tue Apr  9 11:47:12 2013 <1009513655332321> URL::sendHeaders(): meth='GET' file='/Calendar' protocol='HTTP/1.1'
      +Tue Apr  9 11:47:12 2013 <1009513655332321> Hdrs to WLS: [Host]=[adc2110491.us.oracle.com:4443]+
      +Tue Apr  9 11:47:12 2013 <1009513655332321> Hdrs to WLS: [User-Agent]=[Mozilla/5.0 (Windows NT 6.1; WOW64; rv:10.0.4) Gecko/20100101 Firefox/10.0.4]+
      +Tue Apr  9 11:47:12 2013 <1009513655332321> Hdrs to WLS: [Accept]=[text/html,application/xhtml+xml,application/xml;q=0.9,*/*;q=0.8]+
      +Tue Apr  9 11:47:12 2013 <1009513655332321> Hdrs to WLS: [Accept-Language]=[en-us,en;q=0.5]+
      +Tue Apr  9 11:47:12 2013 <1009513655332321> Hdrs to WLS: [Accept-Encoding]=[gzip, deflate]+
      +Tue Apr  9 11:47:12 2013 <1009513655332321> Hdrs to WLS: [Cookie]=[s_nr=1365505876368; ADMINCONSOLESESSION=FGY9RkfPy7xrvwn4zYR0LqfTV0vGkBjPxhjxQbQGlkhjXJ22LHXJ!1111363896; _WL_AUTHCOOKIE_ADMINCONSOLESESSION=mSkOg.X9HBLf9jgkR.PJ; JSESSIONID=l2GkRkgJczv7vjf9p2dh892WhDTvHglTHdFMppJLLCxQL1pmyJJN!-1304266397]+
      +Tue Apr  9 11:47:12 2013 <1009513655332321> Hdrs to WLS: [ECID-Context]=[1.004qV7vEojhDsXY5HrG7yf0006Jj0000SH;kXjE1ZDLIPHHj4TPnKVB3HOR_UOPXIS]+
      +Tue Apr  9 11:47:12 2013 <1009513655332321> Hdrs to WLS: [Connection]=[Keep-Alive]+
      +Tue Apr  9 11:47:12 2013 <1009513655332321> Hdrs to WLS: [WL-Proxy-SSL]=[true]+
      +Tue Apr  9 11:47:12 2013 <1009513655332321> Hdrs to WLS: [WL-PATH-TRIM]=[test]+
      +Tue Apr  9 11:47:12 2013 <1009513655332321> Hdrs to WLS: [WL-Proxy-Client-IP]=[148.87.19.36]+
      +Tue Apr  9 11:47:12 2013 <1009513655332321> Hdrs to WLS: [Proxy-Client-IP]=[148.87.19.36]+
      +Tue Apr  9 11:47:12 2013 <1009513655332321> Hdrs to WLS: [X-Forwarded-For]=[148.87.19.36]+
      +Tue Apr  9 11:47:12 2013 <1009513655332321> Hdrs to WLS: [X-WebLogic-KeepAliveSecs]=[30]+
      +Tue Apr  9 11:47:12 2013 <1009513655332321> Hdrs to WLS: [X-WebLogic-Request-ClusterInfo]=[true]+
      +Tue Apr  9 11:47:12 2013 <1009513655332321> Hdrs to WLS: [x-weblogic-cluster-hash]=[yo3Df7lxCy0/YU7vJMjHoUNLu9I]+
      Tue Apr  9 11:47:12 2013 <1009513655332321> Need to send 889
      Tue Apr  9 11:47:12 2013 <1009513655332321> SSLWrite sent 889, Error = 0
      Tue Apr  9 11:47:12 2013 <1009513655332321> SSLWrite completed, sent 889
      Tue Apr  9 11:47:12 2013 <1009513655332321> Reader::fill(): first=0 last=0 toRead=4096
      +Tue Apr  9 11:47:12 2013 <1009513655332321> URL::parseHeaders: CompleteStatusLine set to [HTTP/1.1 302 Moved Temporarily]+
      Tue Apr  9 11:47:12 2013 <1009513655332321> URL::parseHeaders: StatusLine set to [302 Moved Temporarily]
      +Tue Apr  9 11:47:12 2013 <1009513655332321> Hdrs from WLS:[Date]=[Tue, 09 Apr 2013 18:47:12 GMT]+
      +Tue Apr  9 11:47:12 2013 <1009513655332321> Hdrs from WLS:[Transfer-Encoding]=[chunked]+
      +Tue Apr  9 11:47:12 2013 <1009513655332321> Hdrs from WLS:[Location]=[http://adc2110491.us.oracle.com:4443/test/Calendar/]+
      +Tue Apr  9 11:47:12 2013 <1009513655332321> Hdrs from WLS:[X-Powered-By]=[Servlet/2.5 JSP/2.1]+
      Tue Apr  9 11:47:12 2013 <1009513655332321> parsed all headers OK
      Tue Apr  9 11:47:12 2013 <1009513655332321> done with sendRequest
      Tue Apr  9 11:47:12 2013 <1009513655332321> sendResponse() : r->status = '302'
      +Tue Apr  9 11:47:12 2013 <1009513655332321> Hdrs to client (add):[Date]=[Tue, 09 Apr 2013 18:47:12 GMT]+
      +Tue Apr  9 11:47:12 2013 <1009513655332321> Hdrs to client (add):[Location]=[http://adc2110491.us.oracle.com:4443/test/Calendar/]+
      +Tue Apr  9 11:47:12 2013 <1009513655332321> Hdrs to client (add):[X-Powered-By]=[Servlet/2.5 JSP/2.1]+
      Tue Apr  9 11:47:12 2013 <1009513655332321> Reader::fill(): first=0 last=0 toRead=4096
      Tue Apr  9 11:47:12 2013 <1009513655332321> Reader::fill() Remaining data length 3 is less than buffer size
      Tue Apr  9 11:47:12 2013 <1009513655332321> Reader::fill(): first=0 last=3 toRead=4093
      Tue Apr  9 11:47:12 2013 <1009513655332321> Reader::fill() Remaining data length 2 is less than buffer size
      Tue Apr  9 11:47:12 2013 <1009513655332321> Reader::fill(): first=0 last=5 toRead=4091
      Tue Apr  9 11:47:12 2013 <1009513655332321> Reader::fill() Remaining data length 1 is less than buffer size
      Tue Apr  9 11:47:12 2013 <1009513655332321> Reader::fill(): first=0 last=0 toRead=4096
      Tue Apr  9 11:47:12 2013 <1009513655332321> Reader::fill() Remaining data length 297 is less than buffer size
      Tue Apr  9 11:47:12 2013 <1009513655332321> Reader::fill(): first=0 last=0 toRead=4096
      Tue Apr  9 11:47:12 2013 <1009513655332321> Reader::fill() Remaining data length 2 is less than buffer size
      Tue Apr  9 11:47:12 2013 <1009513655332321> Reader::fill(): first=0 last=0 toRead=4096
      Tue Apr  9 11:47:12 2013 <1009513655332321> Reader::fill() Remaining data length 3 is less than buffer size
      Tue Apr  9 11:47:12 2013 <1009513655332321> Reader::fill(): first=0 last=3 toRead=4093
      Tue Apr  9 11:47:12 2013 <1009513655332321> Reader::fill() Remaining data length 2 is less than buffer size
      Tue Apr  9 11:47:12 2013 <1009513655332321> Reader::fill(): first=0 last=5 toRead=4091
      Tue Apr  9 11:47:12 2013 <1009513655332321> Reader::fill() Remaining data length 1 is less than buffer size
      Tue Apr  9 11:47:12 2013 <1009513655332321> Reader::fill(): first=0 last=0 toRead=4096
      Tue Apr  9 11:47:12 2013 <1009513655332321> Reader::fill() Remaining data length 2 is less than buffer size
      Tue Apr  9 11:47:12 2013 <1009513655332321> calling closeConn() with non-null URL* at 3882
      Tue Apr  9 11:47:12 2013 <1009513655332321> canRecycle: conn=1 status=302 isKA=1 clen=-1 isCTE=1

      Tue Apr  9 11:47:12 2013 <1009513655332321> closeConn: pooling for '10.232.130.87/12004'
      Tue Apr  9 11:47:12 2013 <1009513655332321> closeConn: pooling '0'
      +Tue Apr  9 11:47:12 2013 <1009513655332321> request [Calendar] processed successfully..................+