Thanks Udara for the update. I will look into this. It seems like an issue in the AlgorithmContextCache we introduced recently.
Thanks On Tue, Feb 25, 2014 at 11:25 AM, Udara Liyanage <[email protected]> wrote: > Hi Imesh Me too get the same error when accessing via LB. It works for > some time and throws this error. When I restart the LB, it works fine again > > [2014-02-25 21:50:01,065] DEBUG - LoadBalancerStatisticsNotifier In-flight > request count published to cep: [cluster-id] pak.php.domain > [network-partition] ec2 [value] 0 > [2014-02-25 21:50:16,065] DEBUG - LoadBalancerStatisticsNotifier > Publishing load balancer statistics > [2014-02-25 21:50:16,066] DEBUG - LoadBalancerStatisticsNotifier In-flight > request count published to cep: [cluster-id] pak.php.domain > [network-partition] ec2 [value] 0 > [2014-02-25 21:50:31,066] DEBUG - LoadBalancerStatisticsNotifier > Publishing load balancer statistics > [2014-02-25 21:50:31,066] DEBUG - LoadBalancerStatisticsNotifier In-flight > request count published to cep: [cluster-id] pak.php.domain > [network-partition] ec2 [value] 0 > [2014-02-25 21:50:46,066] DEBUG - LoadBalancerStatisticsNotifier > Publishing load balancer statistics > [2014-02-25 21:50:46,067] DEBUG - LoadBalancerStatisticsNotifier In-flight > request count published to cep: [cluster-id] pak.php.domain > [network-partition] ec2 [value] 0 > [2014-02-25 21:51:01,067] DEBUG - LoadBalancerStatisticsNotifier > Publishing load balancer statistics > [2014-02-25 21:51:01,067] DEBUG - LoadBalancerStatisticsNotifier In-flight > request count published to cep: [cluster-id] pak.php.domain > [network-partition] ec2 [value] 0 > [2014-02-25 21:51:16,067] DEBUG - LoadBalancerStatisticsNotifier > Publishing load balancer statistics > [2014-02-25 21:51:16,067] DEBUG - LoadBalancerStatisticsNotifier In-flight > request count published to cep: [cluster-id] pak.php.domain > [network-partition] ec2 [value] 0 > [2014-02-25 21:51:31,068] DEBUG - LoadBalancerStatisticsNotifier > Publishing load balancer statistics > [2014-02-25 21:51:31,068] DEBUG - LoadBalancerStatisticsNotifier In-flight > request count published to cep: [cluster-id] pak.php.domain > [network-partition] ec2 [value] 0 > [2014-02-25 21:51:46,068] DEBUG - LoadBalancerStatisticsNotifier > Publishing load balancer statistics > [2014-02-25 21:51:46,068] DEBUG - LoadBalancerStatisticsNotifier In-flight > request count published to cep: [cluster-id] pak.php.domain > [network-partition] ec2 [value] 0 > [2014-02-25 21:52:01,069] DEBUG - LoadBalancerStatisticsNotifier > Publishing load balancer statistics > [2014-02-25 21:52:01,069] DEBUG - LoadBalancerStatisticsNotifier In-flight > request count published to cep: [cluster-id] pak.php.domain > [network-partition] ec2 [value] 0 > [2014-02-25 21:52:16,069] DEBUG - LoadBalancerStatisticsNotifier > Publishing load balancer statistics > [2014-02-25 21:52:16,069] DEBUG - LoadBalancerStatisticsNotifier In-flight > request count published to cep: [cluster-id] pak.php.domain > [network-partition] ec2 [value] 0 > [2014-02-25 21:52:31,070] DEBUG - LoadBalancerStatisticsNotifier > Publishing load balancer statistics > [2014-02-25 21:52:31,070] DEBUG - LoadBalancerStatisticsNotifier In-flight > request count published to cep: [cluster-id] pak.php.domain > [network-partition] ec2 [value] 0 > [2014-02-25 21:52:38,239] DEBUG - SynapseMessageReceiver Synapse received > a new message for message mediation... > [2014-02-25 21:52:38,239] DEBUG - SynapseMessageReceiver Received To: > /www/test2.php > [2014-02-25 21:52:38,239] DEBUG - SynapseMessageReceiver SOAPAction: null > [2014-02-25 21:52:38,239] DEBUG - SynapseMessageReceiver WSA-Action: null > [2014-02-25 21:52:38,239] DEBUG - Axis2SynapseEnvironment Injecting > MessageContext > [2014-02-25 21:52:38,239] DEBUG - Axis2SynapseEnvironment Using Main > Sequence for injected message > [2014-02-25 21:52:38,239] DEBUG - SequenceMediator Start : Sequence <main> > [2014-02-25 21:52:38,239] DEBUG - SequenceMediator Setting the onError > handler : fault for the sequence : main > [2014-02-25 21:52:38,239] DEBUG - SequenceMediator Sequence > <SequenceMediator> :: mediate() > [2014-02-25 21:52:38,239] DEBUG - SequenceMediator Mediation started from > mediator position : 0 > [2014-02-25 21:52:38,239] DEBUG - InMediator Start : In mediator > [2014-02-25 21:52:38,239] DEBUG - InMediator Current message is incoming - > executing child mediators > [2014-02-25 21:52:38,239] DEBUG - InMediator Sequence <InMediator> :: > mediate() > [2014-02-25 21:52:38,239] DEBUG - InMediator Mediation started from > mediator position : 0 > [2014-02-25 21:52:38,239] DEBUG - PropertyMediator Start : Property > mediator > [2014-02-25 21:52:38,239] DEBUG - PropertyMediator Setting property : > SERVICE_PREFIX at scope : default to : http://10.142.214.25:8280/ (i.e. > result of expression : $axis2:SERVICE_PREFIX) > [2014-02-25 21:52:38,239] DEBUG - PropertyMediator End : Property mediator > [2014-02-25 21:52:38,239] DEBUG - SendMediator Start : Send mediator > [2014-02-25 21:52:38,239] DEBUG - ClassEndpoint Start sending message > [2014-02-25 21:52:38,239] DEBUG - HttpSessionDispatcher Extracted Host > Name : pak.isuruh.lk > [2014-02-25 21:52:38,239] DEBUG - HttpSessionDispatcher Endpoint Address : > /www/test2.php > [2014-02-25 21:52:38,239] DEBUG - HttpSessionDispatcher Content Type : null > [2014-02-25 21:52:38,239] DEBUG - HttpSessionDispatcher A request received > with the Host Name : pak.isuruh.lk:8280 > [2014-02-25 21:52:38,240] DEBUG - HttpSessionDispatcher Couldn't find the > Cookie header to find the session > [2014-02-25 21:52:38,240] DEBUG - HttpSessionDispatcher Did not find a > session info obj. > [2014-02-25 21:52:38,240] DEBUG - TenantAwareLoadBalanceEndpoint > Multi-tenancy enabled, scanning URL for tenant... > [2014-02-25 21:52:38,240] DEBUG - TenantAwareLoadBalanceEndpoint Request > URL: /www/test2.php > [2014-02-25 21:52:38,240] DEBUG - TenantAwareLoadBalanceEndpoint Tenant > identifier regex: t/([^/]*)/ > [2014-02-25 21:52:38,240] DEBUG - TenantAwareLoadBalanceEndpoint Tenant > identifier not found in URL > [2014-02-25 21:52:38,240] WARN - SynapseMessageReceiver Executing fault > handler due to exception encountered > [2014-02-25 21:52:38,242] WARN - FaultHandler ERROR_CODE : 0 > [2014-02-25 21:52:38,242] WARN - FaultHandler ERROR_MESSAGE : Error > occured when execute the class endpoint > [2014-02-25 21:52:38,242] WARN - FaultHandler ERROR_DETAIL : > org.apache.synapse.SynapseException: Error occured when execute the class > endpoint > at org.apache.synapse.endpoints.ClassEndpoint.send(ClassEndpoint.java:89) > at > org.apache.synapse.mediators.builtin.SendMediator.mediate(SendMediator.java:97) > at > org.apache.synapse.mediators.AbstractListMediator.mediate(AbstractListMediator.java:77) > at > org.apache.synapse.mediators.AbstractListMediator.mediate(AbstractListMediator.java:47) > at > org.apache.synapse.mediators.filters.InMediator.mediate(InMediator.java:61) > at > org.apache.synapse.mediators.AbstractListMediator.mediate(AbstractListMediator.java:77) > at > org.apache.synapse.mediators.AbstractListMediator.mediate(AbstractListMediator.java:47) > at > org.apache.synapse.mediators.base.SequenceMediator.mediate(SequenceMediator.java:131) > at > org.apache.synapse.core.axis2.Axis2SynapseEnvironment.injectMessage(Axis2SynapseEnvironment.java:228) > at > org.apache.synapse.core.axis2.SynapseMessageReceiver.receive(SynapseMessageReceiver.java:83) > at org.apache.axis2.engine.AxisEngine.receive(AxisEngine.java:180) > at > org.apache.synapse.transport.passthru.ServerWorker.processNonEntityEnclosingRESTHandler(ServerWorker.java:336) > at > org.apache.synapse.transport.passthru.ServerWorker.run(ServerWorker.java:168) > at > org.apache.axis2.transport.base.threads.NativeWorkerPool$1.run(NativeWorkerPool.java:172) > at > java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1110) > at > java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:603) > at java.lang.Thread.run(Thread.java:722) > Caused by: java.lang.NullPointerException > at > org.apache.stratos.load.balancer.cache.LoadBalancerCache.getInteger(LoadBalancerCache.java:121) > at > org.apache.stratos.load.balancer.cache.AlgorithmContextCache.getCurrentMemberIndex(AlgorithmContextCache.java:43) > at > org.apache.stratos.load.balancer.algorithm.AlgorithmContext.getCurrentMemberIndex(AlgorithmContext.java:47) > at > org.apache.stratos.load.balancer.algorithm.RoundRobin.getNextMember(RoundRobin.java:53) > at > org.apache.stratos.load.balancer.RequestDelegator.findNextMemberInCluster(RequestDelegator.java:99) > at > org.apache.stratos.load.balancer.RequestDelegator.findNextMemberFromHostName(RequestDelegator.java:54) > at > org.apache.stratos.load.balancer.endpoint.TenantAwareLoadBalanceEndpoint.findNextMember(TenantAwareLoadBalanceEndpoint.java:216) > at > org.apache.stratos.load.balancer.endpoint.TenantAwareLoadBalanceEndpoint.send(TenantAwareLoadBalanceEndpoint.java:124) > at org.apache.synapse.endpoints.ClassEndpoint.send(ClassEndpoint.java:87) > ... 16 more > > [2014-02-25 21:52:38,242] WARN - FaultHandler ERROR_EXCEPTION : > org.apache.synapse.SynapseException: Error occured when execute the class > endpoint > [2014-02-25 21:52:38,242] WARN - FaultHandler FaultHandler : > org.apache.synapse.mediators.MediatorFaultHandler@59f91f38 > [2014-02-25 21:52:38,242] WARN - MediatorFaultHandler Executing fault > handler mediator : fault > [2014-02-25 21:52:38,242] DEBUG - SequenceMediator Start : Sequence <fault> > [2014-02-25 21:52:38,242] DEBUG - SequenceMediator Sequence > <SequenceMediator> :: mediate() > [2014-02-25 21:52:38,242] DEBUG - SequenceMediator Mediation started from > mediator position : 0 > [2014-02-25 21:52:38,242] DEBUG - ScriptMediator Start : Script mediator > [2014-02-25 21:52:38,242] DEBUG - ScriptMediator Scripting language : js > source : specified inline function : mediate > [2014-02-25 21:52:38,246] DEBUG - ScriptMediator End : Script mediator > return value : true > [2014-02-25 21:52:38,246] DEBUG - SwitchMediator Start : Switch mediator > [2014-02-25 21:52:38,246] DEBUG - SwitchMediator XPath : > get-property('ERROR_CODE') evaluates to : 0 > [2014-02-25 21:52:38,247] DEBUG - SwitchMediator None of the switch cases > matched - executing default > [2014-02-25 21:52:38,247] DEBUG - AnonymousListMediator Sequence > <AnonymousListMediator> :: mediate() > [2014-02-25 21:52:38,247] DEBUG - AnonymousListMediator Mediation started > from mediator position : 0 > [2014-02-25 21:52:38,247] DEBUG - PropertyMediator Start : Property > mediator > [2014-02-25 21:52:38,247] DEBUG - PropertyMediator Setting property : > HTTP_SC at scope : axis2 to : 500 (i.e. constant : 500) > [2014-02-25 21:52:38,247] DEBUG - PropertyMediator End : Property mediator > [2014-02-25 21:52:38,247] DEBUG - PropertyMediator Start : Property > mediator > [2014-02-25 21:52:38,247] DEBUG - PropertyMediator Removing property : > NO_ENTITY_BODY (scope:axis2) > [2014-02-25 21:52:38,247] DEBUG - PropertyMediator End : Property mediator > [2014-02-25 21:52:38,248] DEBUG - HeaderMediator Start : Header mediator > [2014-02-25 21:52:38,248] DEBUG - HeaderMediator Removing SOAP Header : To > [2014-02-25 21:52:38,248] DEBUG - HeaderMediator End : Header mediator > [2014-02-25 21:52:38,248] DEBUG - PropertyMediator Start : Property > mediator > [2014-02-25 21:52:38,248] DEBUG - PropertyMediator Setting property : > RESPONSE at scope : default to : true (i.e. constant : true) > [2014-02-25 21:52:38,248] DEBUG - PropertyMediator End : Property mediator > [2014-02-25 21:52:38,248] DEBUG - PropertyMediator Start : Property > mediator > [2014-02-25 21:52:38,248] DEBUG - PropertyMediator Setting property : > messageType at scope : axis2 to : text/html (i.e. constant : text/html) > [2014-02-25 21:52:38,249] DEBUG - PropertyMediator End : Property mediator > [2014-02-25 21:52:38,249] DEBUG - PropertyMediator Start : Property > mediator > [2014-02-25 21:52:38,249] DEBUG - PropertyMediator Setting property : > contentType at scope : axis2 to : text/html (i.e. constant : text/html) > [2014-02-25 21:52:38,249] DEBUG - PropertyMediator End : Property mediator > [2014-02-25 21:52:38,249] DEBUG - SendMediator Start : Send mediator > [2014-02-25 21:52:38,249] DEBUG - SendMediator Sending response message > using implicit message properties.. > Sending To: null > SOAPAction: null > [2014-02-25 21:52:38,250] DEBUG - SendMediator End : Send mediator > [2014-02-25 21:52:38,250] DEBUG - SequenceMediator End : Sequence <fault> > [2014-02-25 21:52:46,070] DEBUG - LoadBalancerStatisticsNotifier > Publishing load balancer statistics > [2014-02-25 21:52:46,072] DEBUG - LoadBalancerStatisticsNotifier In-flight > request count published to cep: [cluster-id] pak.php.domain > [network-partition] ec2 [value] 0 > [2014-02-25 21:53:01,072] DEBUG - LoadBalancerStatisticsNotifier > Publishing load balancer statistics > [2014-02-25 21:53:01,072] DEBUG - LoadBalancerStatisticsNotifier In-flight > request count published to cep: [cluster-id] pak.php.domain > [network-partition] ec2 [value] 0 > > > > On Mon, Feb 24, 2014 at 9:51 AM, Imesh Gunaratne <[email protected]> wrote: > >> Hi Manula, >> >> For me it looks like a problem with the synapse load balancing endpoint. >> Will it be possible for you to re-create this issue by enabling debug >> logs in log4j.properties file in load balancer and post the trace file? >> >> log4j.logger.org.apache.stratos.load.balancer=DEBUG >> log4j.logger.org.apache.synapse=DEBUG >> >> Thanks >> >> >> On Wed, Feb 19, 2014 at 6:29 AM, Manula Chathurika Thantriwatte < >> [email protected]> wrote: >> >>> Hi, >>> >>> $subject. >>> >>> I have subscribed to php cartridge and can access via lb from the >>> beginning. But after some time it want. No errors in lb or any other >>> product logs. In browser it shows "Error occured when execute the class >>> endpoint". Any one know about this issue ? >>> >>> -- >>> Regards, >>> Manula Chathurika Thantriwatte >>> Software Engineer >>> WSO2 Inc. : http://wso2.com >>> lean . enterprise . middleware >>> >>> email : [email protected] / [email protected] >>> phone : +94 772492511 >>> blog : http://manulachathurika.blogspot.com/ >>> >>> >>> >>> >> > > > -- > Udara Liyanage > Software Engineer > WSO2, Inc.: http://wso2.com > lean. enterprise. middleware > > web: http://udaraliyanage.wordpress.com > phone: +94 71 443 6897 >
