Description of problem: The call xmlRpc.org.listSystemEntitlements(token) throws a server-side exception: --- [2010-09-21 17:27:48,585] INFO - REQUESTED FROM: *callerIp* CALL: org.listSystemEntitlements(25x90145a29d47244754365942ef668ee79) CALLER: (admin) TIME: 0.019 seconds [2010-09-21 17:27:48,585] ERROR - postProcess error java.lang.IllegalStateException: Stopwatch is not running. at org.apache.commons.lang.time.StopWatch.stop(StopWatch.java:115) at com.redhat.rhn.frontend.xmlrpc.LoggingInvocationProcessor.onException(LoggingInvocationProcessor.java:133) at redstone.xmlrpc.XmlRpcDispatcher.processException(XmlRpcDispatcher.java:268) at redstone.xmlrpc.XmlRpcDispatcher.dispatch(XmlRpcDispatcher.java:137) at com.redhat.rhn.frontend.xmlrpc.RhnXmlRpcServer.execute(RhnXmlRpcServer.java:53) at com.redhat.rhn.frontend.xmlrpc.XmlRpcServlet.doPost(XmlRpcServlet.java:162) at javax.servlet.http.HttpServlet.service(HttpServlet.java:710) at javax.servlet.http.HttpServlet.service(HttpServlet.java:803) at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:269) at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:188) at com.opensymphony.module.sitemesh.filter.PageFilter.parsePage(PageFilter.java:142) at com.opensymphony.module.sitemesh.filter.PageFilter.doFilter(PageFilter.java:58) at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:215) at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:188) at com.redhat.rhn.frontend.servlets.LocalizedEnvironmentFilter.doFilter(LocalizedEnvironmentFilter.java:67) at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:215) at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:188) at com.redhat.rhn.frontend.servlets.EnvironmentFilter.doFilter(EnvironmentFilter.java:108) at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:215) at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:188) at com.redhat.rhn.frontend.servlets.SessionFilter.doFilter(SessionFilter.java:55) at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:215) at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:188) at com.redhat.rhn.frontend.servlets.SetCharacterEncodingFilter.doFilter(SetCharacterEncodingFilter.java:97) at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:215) at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:188) at org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:210) at org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:172) at org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:127) at org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:117) at org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:108) at org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:151) at org.apache.jk.server.JkCoyoteHandler.invoke(JkCoyoteHandler.java:200) at org.apache.jk.common.HandlerRequest.invoke(HandlerRequest.java:291) at org.apache.jk.common.ChannelSocket.invoke(ChannelSocket.java:775) at org.apache.jk.common.ChannelSocket.processConnection(ChannelSocket.java:704) at org.apache.jk.common.ChannelSocket$SocketConnection.runIt(ChannelSocket.java:897) at org.apache.tomcat.util.threads.ThreadPool$ControlRunnable.run(ThreadPool.java:685) at java.lang.Thread.run(Thread.java:736) --- Version-Release number of selected component (if applicable): current iso: Satellite-5.4.0-RHEL5-re20100920.0 How reproducible: always on that call. the other calls (like: xmlRpc.satellite.listEntitlements(self.token) is ok) Steps to Reproduce: 1.initiate xmlrpc python object, make login ad do the call. 2. 3. Actual results: exception in /var/log/rhn/rhn_web_api.log Expected results: no exception, should work as it was expected on sat530 Additional info:
Taking the bug and preparing RHTS test for it.
VERIFIED ----- verification Python script: ----- import xmlrpclib USER='<user>' PASS='<pass>' SERVER='<server_hostname>' client = xmlrpclib.Server('http://%s/rpc/api' % SERVER, verbose=0) key = client.auth.login(USER, PASS) result = client.org.listSystemEntitlements(key) print(result) --------------------------------------- Output is: [{'allocated': 2, 'allocated_flex': 0, 'free': 0, 'free_flex': 0, 'label': 'enterprise_entitled', 'name': 'Management (Base)', 'unallocated': 39998, 'used': 2}, {'allocated': 0, 'allocated_flex': 0, 'free': 0, 'free_flex': 0, 'label': 'monitoring_entitled', 'name': 'Monitoring (Add-On)', 'unallocated': 140000, 'used': 0}, {'allocated': 2, 'allocated_flex': 0, 'free': 0, 'free_flex': 0, 'label': 'provisioning_entitled', 'name': 'Provisioning (Add-On)', 'unallocated': 139998, 'used': 2}, {'allocated': 0, 'allocated_flex': 0, 'free': 0, 'free_flex': 0, 'label': 'virtualization_host', 'name': 'Virtualization (Add-On)', 'unallocated': 100000, 'used': 0}, {'allocated': 0, 'allocated_flex': 0, 'free': 0, 'free_flex': 0, 'label': 'virtualization_host_platform', 'name': 'Virtualization Platform (Add-On)', 'unallocated': 100000, 'used': 0}]
The 5.4.0 RHN Satellite and RHN Proxy release has occurred. This issue has been resolved with this release. RHEA-2010:0801 - RHN Satellite Server 5.4.0 Upgrade https://rhn.redhat.com/rhn/errata/details/Details.do?eid=10332 RHEA-2010:0803 - RHN Tools enhancement update https://rhn.redhat.com/rhn/errata/details/Details.do?eid=10333 RHEA-2010:0802 - RHN Proxy Server 5.4.0 bug fix update https://rhn.redhat.com/rhn/errata/details/Details.do?eid=10334 RHEA-2010:0800 - RHN Satellite Server 5.4.0 https://rhn.redhat.com/rhn/errata/details/Details.do?eid=10335 Docs are available: http://docs.redhat.com/docs/en-US/Red_Hat_Network_Satellite/index.html Regards, Clifford