免费注册 查看新帖 |

Chinaunix

  平台 论坛 博客 文库
› 论坛 › IT运维 › 中间件技术 › weblogic高手请进，排错问题
最近访问板块 发新帖
查看: 8356 | 回复: 2
打印 上一主题 下一主题

[WebLogic] weblogic高手请进，排错问题 [复制链接]

lizisor

论坛徽章:
0
跳转到指定楼层
1楼 [收藏(0)] [报告]
发表于 2010-06-30 12:51 |只看该作者 |倒序浏览
系统环境：linux redhat as4
apache2.0   weblogic10

两台weblogic做的集群，apache做转发，最近几天apache日志突然不停的记录好多日志，类似如下：

[Wed Jun 30 10:21:48 2010] [error] [client 219.142.175.162] ap_proxy: trying POST /spxml/u501_100701.xml at backend host '192.168.3.30/7701; got exception 'PROTOCOL_ERROR [line 872 of ../nsapi/URL.cpp]: malformed status 1', referer: http://www.happypool.net/happypool/bjdc/dc_spf.jsp
[Wed Jun 30 10:21:48 2010] [error] [client 219.142.175.162] ap_proxy: trying POST /spxml/u501_100701.xml at backend host '192.168.3.32/7701; got exception 'PROTOCOL_ERROR [line 872 of ../nsapi/URL.cpp]: malformed status 1', referer: http://www.happypool.net/happypool/bjdc/dc_spf.jsp
[Wed Jun 30 10:21:48 2010] [error] [client 219.142.175.162] ap_proxy: trying POST /spxml/u501_100701.xml at backend host '192.168.3.32/7701; got exception 'PROTOCOL_ERROR [line 872 of ../nsapi/URL.cpp]: malformed status 1', referer: http://www.happypool.net/happypool/bjdc/dc_spf.jsp
[Wed Jun 30 10:21:48 2010] [error] [client 219.142.175.162] ap_proxy: trying POST /spxml/u501_100701.xml at backend host '192.168.3.30/7701; got exception 'PROTOCOL_ERROR [line 872 of ../nsapi/URL.cpp]: malformed status 1', referer: http://www.happypool.net/happypool/bjdc/dc_spf.jsp
[Wed Jun 30 10:21:48 2010] [error] [client 219.142.175.162] ap_proxy: trying POST /spxml/u501_100701.xml at backend host '192.168.3.32/7701; got exception 'PROTOCOL_ERROR [line 872 of ../nsapi/URL.cpp]: malformed status 1', referer: http://www.happypool.net/happypool/bjdc/dc_spf.jsp
[Wed Jun 30 10:21:57 2010] [error] [client 123.175.227.103] ap_proxy: trying POST /spxml/u503_100701.xml at backend host '192.168.3.30/7701; got exception 'PROTOCOL_ERROR [line 872 of ../nsapi/URL.cpp]: malformed status 1', referer: http://www.happypoolonline.com/h ... opId=0&inHzId=0



在apache配置文件httpd.conf中我开启debug all功能做排错：

<IfModule mod_weblogic.c>
  WebLogicCluster 192.168.3.30:7701,192.168.3.32:7701
  Debug       ALL
  WLLogFile             /tmp/global_proxy.log
  #WLTempDir             "c:/myTemp"
  DebugConfigInfo       OFF
  KeepAliveEnabled ON
  KeepAliveSecs  15
</IfModule>


之后在/tmp/global_proxy.log日志文件中找到以上日志出现问题的时间点，[Wed Jun 30 10:21:48 2010]，如下：

Wed Jun 30 10:21:48 2010 <1760512778645087>
Wed Jun 30 10:21:48 2010 <1760512778645087> INFO: SSL is not configured
Wed Jun 30 10:21:48 2010 <1760512778645087> Using Uri /spxml/u501_100701.xml
Wed Jun 30 10:21:48 2010 <1760512778645087> After trimming path: '/spxml/u501_100701.xml'
Wed Jun 30 10:21:48 2010 <1760512778645087> The final request string is '/spxml/u501_100701.xml?1277864483171'
Wed Jun 30 10:21:48 2010 <1760512778645087> SEARCHING id=[192.168.3.30:7701,192.168.3.32:7701] from current ID=[192.168.3.30:7701,192.168.3.32:7701]
Wed Jun 30 10:21:48 2010 <1760512778645087> The two ids matched
Wed Jun 30 10:21:48 2010 <1760512778645087> @@@FOUND...id=[192.168.3.30:7701,192.168.3.32:7701], server_name=[www.happypool.net], server_port=[80]
Wed Jun 30 10:21:48 2010 <1760512778645087> getPreferred: availcookie=[__utma=4314538.1308419643.1276963531.1277796157.1277864345.18; __utmz=4314538.1276963531.1.1.utmcsr=(direct)|utmccn=(direct)|utmcmd=(none); __utmb=4314538.1.10.1277864345; __utmc=4314538; rtime=8; ltime=1277864345562; cnzz_eid=28551267-1276953211-http%3A//www.happypool.net/; kf51_userid=1277083838734_5585; kf51_referrer=; cnzz_a1502998=28; sin1502998=none; cck_lasttime=1277802019718; cck_count=0; JSESSIONID=7dJmMqcMQpy3KGkWy2rTkmSvFdHjZpRs2gW1hsrg5Xy1dtjdNCDH!404037475]
Wed Jun 30 10:21:48 2010 <1760512778645087> Found cookie from cookie header: JSESSIONID=7dJmMqcMQpy3KGkWy2rTkmSvFdHjZpRs2gW1hsrg5Xy1dtjdNCDH!404037475
Wed Jun 30 10:21:48 2010 <1760512778645087> Parsing cookie JSESSIONID=7dJmMqcMQpy3KGkWy2rTkmSvFdHjZpRs2gW1hsrg5Xy1dtjdNCDH!404037475
Wed Jun 30 10:21:48 2010 <1760512778645087> getpreferredServersFromCookie: [404037475]
Wed Jun 30 10:21:48 2010 <1760512778645087> primaryJVMID: [404037475]
Wed Jun 30 10:21:48 2010 <1760512778645087> No of JVMIDs found in cookie: 1
Wed Jun 30 10:21:48 2010 <1760512778645087> Trying to locate Primary or Secondary using SrvrInfo with JVMID: 404037475
Wed Jun 30 10:21:48 2010 <1760512778645087> getPreferredFromCookie: Found Primary 192.168.3.30:7701:7702
Wed Jun 30 10:21:48 2010 <1760512778645087> getPreferredFromCookie: Found 1 servers
Wed Jun 30 10:21:48 2010 <1760512778645087> Going to get the post data of size=0 clength=0
Wed Jun 30 10:21:48 2010 <1760512778645087> attempt #0 out of a max of 5
Wed Jun 30 10:21:48 2010 <1760512778645087> trying connect to PRIMARY '192.168.3.30'/7701/7702
Wed Jun 30 10:21:48 2010 <1760512778645087> getPooledConn: No more connections in the pool for Host[192.168.3.30] Port[7701] SecurePort[7702]
Wed Jun 30 10:21:48 2010 <1760512778645087> INFO: New NON-SSL URL
Wed Jun 30 10:21:48 2010 <1760512778645087> Connect returns -1, and error no set to 115, msg 'Operation now in progress'
Wed Jun 30 10:21:48 2010 <1760512778645087> EINPROGRESS in connect() - selecting
Wed Jun 30 10:21:48 2010 <1760512778645087> Local Port of the socket is 33595
Wed Jun 30 10:21:48 2010 <1760512778645087> Remote Host 192.168.3.30 Remote Port 7701
Wed Jun 30 10:21:48 2010 <1760512778645087> created a new connection to preferred server '192.168.3.30/7701' for '/spxml/u501_100701.xml?1277864483171', Local port:33595
Wed Jun 30 10:21:48 2010 <1760512778645087> Hdrs from clnt:[Accept]=[*/*]
Wed Jun 30 10:21:48 2010 <1760512778645087> Hdrs from clnt:[Accept-Language]=[zh-cn]
Wed Jun 30 10:21:48 2010 <1760512778645087> Hdrs from clnt:[Referer]=[http://www.happypool.net/happypool/bjdc/dc_spf.jsp]
Wed Jun 30 10:21:48 2010 <1760512778645087> Hdrs from clnt:[Accept-Encoding]=[gzip, deflate]
Wed Jun 30 10:21:48 2010 <1760512778645087> Hdrs from clnt:[User-Agent]=[Mozilla/4.0 (compatible; MSIE 6.0; Windows NT 5.1; SV1; .NET CLR 2.0.50727)]
Wed Jun 30 10:21:48 2010 <1760512778645087> Hdrs from clnt:[Host]=[www.happypool.net]
Wed Jun 30 10:21:48 2010 <1760512778645087> Hdrs from clnt:[Content-Length]=[0]
Wed Jun 30 10:21:48 2010 <1760512778645087> Hdrs from clnt:[Connection]=[Keep-Alive]
Wed Jun 30 10:21:48 2010 <1760512778645087> Hdrs from clnt:[Cache-Control]=[no-cache]
Wed Jun 30 10:21:48 2010 <1760512778645087> Hdrs from clnt:[Cookie]=[__utma=4314538.1308419643.1276963531.1277796157.1277864345.18; __utmz=4314538.1276963531.1.1.utmcsr=(direct)|utmccn=(direct)|utmcmd=(none); __utmb=4314538.1.10.1277864345; __utmc=4314538; rtime=8; ltime=1277864345562; cnzz_eid=28551267-1276953211-http%3A//www.happypool.net/; kf51_userid=1277083838734_5585; kf51_referrer=; cnzz_a1502998=28; sin1502998=none; cck_lasttime=1277802019718; cck_count=0; JSESSIONID=7dJmMqcMQpy3KGkWy2rTkmSvFdHjZpRs2gW1hsrg5Xy1dtjdNCDH!404037475]
Wed Jun 30 10:21:48 2010 <1760512778645087> URL::sendHeaders(): meth='POST' file='/spxml/u501_100701.xml?1277864483171' protocol='HTTP/1.1'
Wed Jun 30 10:21:48 2010 <1760512778645087> Hdrs to WLS:[Accept]=[*/*]
Wed Jun 30 10:21:48 2010 <1760512778645087> Hdrs to WLS:[Accept-Language]=[zh-cn]
Wed Jun 30 10:21:48 2010 <1760512778645087> Hdrs to WLS:[Referer]=[http://www.happypool.net/happypool/bjdc/dc_spf.jsp]
Wed Jun 30 10:21:48 2010 <1760512778645087> Hdrs to WLS:[Accept-Encoding]=[gzip, deflate]
Wed Jun 30 10:21:48 2010 <1760512778645087> Hdrs to WLS:[User-Agent]=[Mozilla/4.0 (compatible; MSIE 6.0; Windows NT 5.1; SV1; .NET CLR 2.0.50727)]
Wed Jun 30 10:21:48 2010 <1760512778645087> Hdrs to WLS:[Host]=[www.happypool.net]
Wed Jun 30 10:21:48 2010 <1760512778645087> Hdrs to WLS:[Content-Length]=[0]
Wed Jun 30 10:21:48 2010 <1760512778645087> Hdrs to WLS:[Cache-Control]=[no-cache]
Wed Jun 30 10:21:48 2010 <1760512778645087> Hdrs to WLS:[Cookie]=[__utma=4314538.1308419643.1276963531.1277796157.1277864345.18; __utmz=4314538.1276963531.1.1.utmcsr=(direct)|utmccn=(direct)|utmcmd=(none); __utmb=4314538.1.10.1277864345; __utmc=4314538; rtime=8; ltime=1277864345562; cnzz_eid=28551267-1276953211-http%3A//www.happypool.net/; kf51_userid=1277083838734_5585; kf51_referrer=; cnzz_a1502998=28; sin1502998=none; cck_lasttime=1277802019718; cck_count=0; JSESSIONID=7dJmMqcMQpy3KGkWy2rTkmSvFdHjZpRs2gW1hsrg5Xy1dtjdNCDH!404037475]
Wed Jun 30 10:21:48 2010 <1760512778645087> Hdrs to WLS:[Connection]=[Keep-Alive]
Wed Jun 30 10:21:48 2010 <1760512778645087> Hdrs to WLS:[WL-Proxy-SSL]=[false]
Wed Jun 30 10:21:48 2010 <1760512778645087> Hdrs to WLS:[WL-Proxy-Client-IP]=[219.142.175.162]
Wed Jun 30 10:21:48 2010 <1760512778645087> Hdrs to WLS:[Proxy-Client-IP]=[219.142.175.162]
Wed Jun 30 10:21:48 2010 <1760512778645087> Hdrs to WLS:[X-Forwarded-For]=[219.142.175.162]
Wed Jun 30 10:21:48 2010 <1760512778645087> Hdrs to WLS:[X-WebLogic-KeepAliveSecs]=[25]
Wed Jun 30 10:21:48 2010 <1760512778645087> Hdrs to WLS:[X-WebLogic-Request-ClusterInfo]=[true]
Wed Jun 30 10:21:48 2010 <1760512778645087> Hdrs to WLS:[x-weblogic-cluster-hash]=[1j2ElYWMNzbno054hZOCSu9ebv4]
Wed Jun 30 10:21:48 2010 <1760512778645087> *******Exception type [PROTOCOL_ERROR] (malformed status 1) raised at line 872 of ../nsapi/URL.cpp
Wed Jun 30 10:21:48 2010 <1760512778645087> Marking 192.168.3.30:7701 as bad
Wed Jun 30 10:21:48 2010 <1760512778645087> got exception in sendRequest phase: PROTOCOL_ERROR [line 872 of ../nsapi/URL.cpp]: malformed status 1 at line 3059
Wed Jun 30 10:21:48 2010 <1760512778645087> Failing over after sendRequest() exception: PROTOCOL_ERROR as Idempotent is set to ON
Wed Jun 30 10:21:48 2010 <1760512778645087> attempt #1 out of a max of 5
Wed Jun 30 10:21:48 2010 <1760512778645087> general list: trying connect to '192.168.3.32'/7701/7702 at line 2632 for '/spxml/u501_100701.xml?1277864483171'
Wed Jun 30 10:21:48 2010 <1760512778645087> INFO: New NON-SSL URL
Wed Jun 30 10:21:48 2010 <1760512778645087> Connect returns -1, and error no set to 115, msg 'Operation now in progress'
Wed Jun 30 10:21:48 2010 <1760512778645087> EINPROGRESS in connect() - selecting
Wed Jun 30 10:21:48 2010 <1760512778645087> Local Port of the socket is 33596
Wed Jun 30 10:21:48 2010 <1760512778645087> Remote Host 192.168.3.32 Remote Port 7701
Wed Jun 30 10:21:48 2010 <1760512778645087> general list: created a new connection to '192.168.3.32'/7701 for '/spxml/u501_100701.xml?1277864483171', Local port:33596
。。。
。。。
剩下的信息基本都类似，

我自己分心到问题的原因应该出现在这里：
Wed Jun 30 10:21:48 2010 <1760512778645087> Hdrs to WLS:[x-weblogic-cluster-hash]=[1j2ElYWMNzbno054hZOCSu9ebv4]
Wed Jun 30 10:21:48 2010 <1760512778645087> *******Exception type [PROTOCOL_ERROR] (malformed status 1) raised at line 872 of ../nsapi/URL.cpp
Wed Jun 30 10:21:48 2010 <1760512778645087> Marking 192.168.3.30:7701 as bad
Wed Jun 30 10:21:48 2010 <1760512778645087> got exception in sendRequest phase: PROTOCOL_ERROR [line 872 of ../nsapi/URL.cpp]: malformed status 1 at line 3059
Wed Jun 30 10:21:48 2010 <1760512778645087> Failing over after sendRequest() exception: PROTOCOL_ERROR as Idempotent is set to ON
但是不知道这是什么错误信息？在网上也搜索不到相关的问题解决方法

请高手帮忙分析！多谢~
lizisor

论坛徽章:
0
2楼 [报告]
发表于 2010-06-30 15:23 |只看该作者
没人回答？
实战分享：从技术角度谈机器学习入门| 【大话IT】RadonDB低门槛向MySQL集群下战书 | ChinaUnix打赏功能已上线！ | 新一代分布式关系型数据库RadonDB知多少？
huyangg

论坛徽章:
0
3楼 [报告]
发表于 2012-01-18 15:48 |只看该作者
lizisor 发表于 2010-06-30 12:51
系统环境：linux redhat as4
apache2.0   weblogic10




大侠我也遇到这样的问题， 最后是怎么解决的 ？
实战分享：从技术角度谈机器学习入门| 【大话IT】RadonDB低门槛向MySQL集群下战书 | ChinaUnix打赏功能已上线！ | 新一代分布式关系型数据库RadonDB知多少？
返回列表 发新帖
高级模式
B Color Image Link Quote Code Smilies
您需要登录后才可以回帖 登录 | 注册

本版积分规则 发表回复

  

北京盛拓优讯信息技术有限公司. 版权所有 京ICP备16024965号-6 北京市公安局海淀分局网监中心备案编号：11010802020122 niuxiaotong@pcpop.com 17352615567
未成年举报专区
中国互联网协会会员  联系我们：huangweiwei@itpub.net
感谢所有关心和支持过ChinaUnix的朋友们 转载本站内容请注明原作者名及出处

清除 Cookies - ChinaUnix - Archiver - WAP - TOP