[ https://issues.apache.org/jira/browse/STRATOS-481?page=com.atlassian.jira.plugin.system.issuetabpanels:comment-tabpanel&focusedCommentId=13939054#comment-13939054 ]
Udara Liyanage commented on STRATOS-481: ---------------------------------------- I got fallowing logs in wso2carbon.log file TID: [0] [LB] [2014-03-18 16:28:32,823] DEBUG {org.apache.stratos.load.balancer.endpoint.TenantAwareLoadBalanceEndpoint} - Multi-tenancy enabled, scanning URL for tenant... TID: [0] [LB] [2014-03-18 16:28:32,823] DEBUG {org.apache.stratos.load.balancer.endpoint.TenantAwareLoadBalanceEndpoint} - Request URL: /t/abc.com/webapps/example/jsp/jsp2/tagfiles/hello.jsp TID: [0] [LB] [2014-03-18 16:28:32,824] DEBUG {org.apache.stratos.load.balancer.endpoint.TenantAwareLoadBalanceEndpoint} - Tenant identifier regex: t/([^/]*)/ TID: [0] [LB] [2014-03-18 16:28:32,824] DEBUG {org.apache.stratos.load.balancer.endpoint.TenantAwareLoadBalanceEndpoint} - Identifying tenant using tenant domain... TID: [0] [LB] [2014-03-18 16:28:32,824] DEBUG {org.apache.stratos.load.balancer.endpoint.TenantAwareLoadBalanceEndpoint} - Tenant identifier found: [tenant-domain] abc.com [tenant-id] -1 And in wso2-elb.log 2014-03-18 16:28:12,192 [-] [PassThroughMessageProcessor-226] WARN __SynapseService Executing fault sequence mediator : fault 2014-03-18 16:28:32,825 [-] [PassThroughMessageProcessor-229] WARN __SynapseService Executing fault handler due to exception encountered 2014-03-18 16:28:32,825 [-] [PassThroughMessageProcessor-229] WARN __SynapseService ERROR_CODE : 0 ERROR_MESSAGE : Error occured when execute the class endpoint 2014-03-18 16:28:32,825 [-] [PassThroughMessageProcessor-229] WARN __SynapseService Executing fault sequence mediator : fault > class endpoint Exception after a load test > ------------------------------------------ > > Key: STRATOS-481 > URL: https://issues.apache.org/jira/browse/STRATOS-481 > Project: Stratos > Issue Type: Bug > Components: Load Balancer > Reporter: Udara Liyanage > Assignee: Imesh Gunaratne > Fix For: 4.0.0 Alpha > > > [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 -- This message was sent by Atlassian JIRA (v6.2#6252)