Return-Path: Delivered-To: apmail-camel-dev-archive@www.apache.org Received: (qmail 95255 invoked from network); 10 Feb 2011 14:36:27 -0000 Received: from hermes.apache.org (HELO mail.apache.org) (140.211.11.3) by minotaur.apache.org with SMTP; 10 Feb 2011 14:36:27 -0000 Received: (qmail 20488 invoked by uid 500); 10 Feb 2011 14:36:27 -0000 Delivered-To: apmail-camel-dev-archive@camel.apache.org Received: (qmail 19444 invoked by uid 500); 10 Feb 2011 14:36:25 -0000 Mailing-List: contact dev-help@camel.apache.org; run by ezmlm Precedence: bulk List-Help: List-Unsubscribe: List-Post: List-Id: Reply-To: dev@camel.apache.org Delivered-To: mailing list dev@camel.apache.org Received: (qmail 19139 invoked by uid 99); 10 Feb 2011 14:36:20 -0000 Received: from athena.apache.org (HELO athena.apache.org) (140.211.11.136) by apache.org (qpsmtpd/0.29) with ESMTP; Thu, 10 Feb 2011 14:36:20 +0000 X-ASF-Spam-Status: No, hits=-2000.0 required=5.0 tests=ALL_TRUSTED,T_RP_MATCHES_RCVD X-Spam-Check-By: apache.org Received: from [140.211.11.116] (HELO hel.zones.apache.org) (140.211.11.116) by apache.org (qpsmtpd/0.29) with ESMTP; Thu, 10 Feb 2011 14:36:18 +0000 Received: from hel.zones.apache.org (hel.zones.apache.org [140.211.11.116]) by hel.zones.apache.org (Postfix) with ESMTP id 0870919D2E0 for ; Thu, 10 Feb 2011 14:35:58 +0000 (UTC) Date: Thu, 10 Feb 2011 14:35:58 +0000 (UTC) From: =?utf-8?Q?Christian_M=C3=BCller_=28JIRA=29?= To: dev@camel.apache.org Message-ID: <1089161746.8007.1297348558031.JavaMail.tomcat@hel.zones.apache.org> In-Reply-To: <1201707923.7655.1297334997970.JavaMail.tomcat@hel.zones.apache.org> Subject: [jira] Commented: (CAMEL-3650) SMSC initiated unbind spawns exponential amounts of reconnect threads MIME-Version: 1.0 Content-Type: text/plain; charset=utf-8 Content-Transfer-Encoding: quoted-printable X-JIRA-FingerPrint: 30527f35849b9dde25b450d4833f0394 [ https://issues.apache.org/jira/browse/CAMEL-3650?page=3Dcom.atlassian= .jira.plugin.system.issuetabpanels:comment-tabpanel&focusedCommentId=3D1299= 3068#comment-12993068 ]=20 Christian M=C3=BCller commented on CAMEL-3650: ----------------------------------------- Will have a look on it later today... > SMSC initiated unbind spawns exponential amounts of reconnect threads > --------------------------------------------------------------------- > > Key: CAMEL-3650 > URL: https://issues.apache.org/jira/browse/CAMEL-3650 > Project: Camel > Issue Type: Bug > Components: camel-smpp > Affects Versions: 2.6.0 > Reporter: Jaco van Tonder > Assignee: Christian M=C3=BCller > Priority: Critical > Labels: smpp > Fix For: 2.7.0 > > > It took me a bit of time to put my finger on this problem. > When an unbind PDU is sent from the SMSC side to a camel-smpp endpoint, a= cascading amount reconnect threads are spawned by the reconnect code. I ca= n reliably re-produce this problem having a simple smpp consumer to a SMSC = / simulator and issuing an unbind from the SMSC / simulator. > Here are some logs (I have added additional debug messages to the SmppCon= sumer to show the object references and state of the SmppSession objects): > {code} > 2011-02-10 12:23:05,931 [ing.Main.main()] INFO MainSupport - Ap= ache Camel 2.6.0 starting > 2011-02-10 12:23:06,338 [ing.Main.main()] INFO amelNamespaceHandler - OS= Gi environment not detected. > 2011-02-10 12:23:06,338 [ing.Main.main()] DEBUG amelNamespaceHandler - Us= ing org.apache.camel.spring.CamelContextFactoryBean as CamelContextBeanDefi= nitionParser > 2011-02-10 12:23:06,764 [ing.Main.main()] DEBUG amelNamespaceHandler - Re= gistered default: org.apache.camel.spring.CamelProducerTemplateFactoryBean = with id: template on camel context: camelTestRouteBuilderContext > 2011-02-10 12:23:06,764 [ing.Main.main()] DEBUG amelNamespaceHandler - Re= gistered default: org.apache.camel.spring.CamelConsumerTemplateFactoryBean = with id: consumerTemplate on camel context: camelTestRouteBuilderContext > 2011-02-10 12:23:06,965 [ing.Main.main()] DEBUG SpringCamelContext - Se= t the application context classloader to: java.net.URLClassLoader@16e7eec9 > 2011-02-10 12:23:06,984 [ing.Main.main()] DEBUG elContextFactoryBean - Fo= und JAXB created routes: [] > 2011-02-10 12:23:06,990 [ing.Main.main()] DEBUG SpringCamelContext - Ad= ding routes from builder: Routes: [] > 2011-02-10 12:23:07,059 [ing.Main.main()] DEBUG SpringCamelContext - on= ApplicationEvent: org.springframework.context.event.ContextRefreshedEvent[s= ource=3Dorg.springframework.context.support.ClassPathXmlApplicationContext@= 4c9549af: startup date [Thu Feb 10 12:23:05 SAST 2011]; root of context hie= rarchy] > 2011-02-10 12:23:07,059 [ing.Main.main()] INFO SpringCamelContext - Ap= ache Camel 2.6.0 (CamelContext: camelTestRouteBuilderContext) is starting > 2011-02-10 12:23:07,059 [ing.Main.main()] INFO SpringCamelContext - JM= X enabled. Using ManagedManagementStrategy. > 2011-02-10 12:23:07,106 [ing.Main.main()] DEBUG faultManagementAgent - St= arting JMX agent on server: com.sun.jmx.mbeanserver.JmxMBeanServer@7e3b014c > 2011-02-10 12:23:07,168 [ing.Main.main()] DEBUG faultManagementAgent - Re= gistered MBean with objectname: org.apache.camel:context=3Djacovt-notebook/= camelTestRouteBuilderContext,type=3Dcontext,name=3D"camelTestRouteBuilderCo= ntext" > 2011-02-10 12:23:07,194 [ing.Main.main()] DEBUG faultManagementAgent - Re= gistered MBean with objectname: org.apache.camel:context=3Djacovt-notebook/= camelTestRouteBuilderContext,type=3Dservices,name=3DDefaultTypeConverter(0x= 61e481c1) > 2011-02-10 12:23:07,194 [ing.Main.main()] DEBUG DefaultTypeConverter - Lo= ading type converters ... > 2011-02-10 12:23:07,194 [ing.Main.main()] INFO nTypeConverterLoader - Lo= ading file META-INF/services/org/apache/camel/TypeConverter to retrieve lis= t of packages, from url: jar:file:/home/jacovt/.m2/repository/org/apache/ca= mel/camel-core/2.6.0/camel-core-2.6.0.jar!/META-INF/services/org/apache/cam= el/TypeConverter > 2011-02-10 12:23:07,195 [ing.Main.main()] INFO nTypeConverterLoader - Lo= ading file META-INF/services/org/apache/camel/TypeConverter to retrieve lis= t of packages, from url: jar:file:/home/jacovt/.m2/repository/org/apache/ac= tivemq/activemq-camel/5.3.1-fuse-02-00/activemq-camel-5.3.1-fuse-02-00.jar!= /META-INF/services/org/apache/camel/TypeConverter > 2011-02-10 12:23:07,196 [ing.Main.main()] DEBUG ageScanClassResolver - Se= arching for annotations of org.apache.camel.Converter in packages: [org.apa= che.camel.component.file, org.apache.camel.component.bean, org.apache.camel= .converter, org.apache.activemq.camel.converter] > 2011-02-10 12:23:07,474 [ing.Main.main()] DEBUG ageScanClassResolver - Fo= und: [class org.apache.camel.component.file.GenericFileConverter, class org= .apache.camel.component.bean.BeanConverter, class org.apache.camel.converte= r.CamelConverter, class org.apache.camel.converter.CollectionConverter, cla= ss org.apache.camel.converter.IOConverter, class org.apache.camel.converter= .NIOConverter, class org.apache.camel.converter.ObjectConverter, class org.= apache.camel.converter.TimePatternConverter, class org.apache.camel.convert= er.jaxp.DomConverter, class org.apache.camel.converter.jaxp.StaxConverter, = class org.apache.camel.converter.jaxp.StreamSourceConverter, class org.apac= he.camel.converter.jaxp.XmlConverter, class org.apache.camel.converter.stre= am.StreamCacheConverter, class org.apache.activemq.camel.converter.ActiveMQ= Converter, class org.apache.activemq.camel.converter.ActiveMQMessageConvert= er] > 2011-02-10 12:23:07,474 [ing.Main.main()] INFO nTypeConverterLoader - Fo= und 4 packages with 15 @Converter classes to load > 2011-02-10 12:23:07,474 [ing.Main.main()] DEBUG nTypeConverterLoader - Lo= ading converter class: org.apache.camel.component.file.GenericFileConverter > 2011-02-10 12:23:07,479 [ing.Main.main()] DEBUG nTypeConverterLoader - Lo= ading converter class: org.apache.camel.component.bean.BeanConverter > 2011-02-10 12:23:07,479 [ing.Main.main()] DEBUG nTypeConverterLoader - Lo= ading converter class: org.apache.camel.converter.CamelConverter > 2011-02-10 12:23:07,482 [ing.Main.main()] DEBUG nTypeConverterLoader - Lo= ading converter class: org.apache.camel.converter.CollectionConverter > 2011-02-10 12:23:07,483 [ing.Main.main()] DEBUG nTypeConverterLoader - Lo= ading converter class: org.apache.camel.converter.IOConverter > 2011-02-10 12:23:07,488 [ing.Main.main()] DEBUG nTypeConverterLoader - Lo= ading converter class: org.apache.camel.converter.NIOConverter > 2011-02-10 12:23:07,490 [ing.Main.main()] DEBUG nTypeConverterLoader - Lo= ading converter class: org.apache.camel.converter.ObjectConverter > 2011-02-10 12:23:07,491 [ing.Main.main()] DEBUG nTypeConverterLoader - Lo= ading converter class: org.apache.camel.converter.TimePatternConverter > 2011-02-10 12:23:07,491 [ing.Main.main()] DEBUG nTypeConverterLoader - Lo= ading converter class: org.apache.camel.converter.jaxp.DomConverter > 2011-02-10 12:23:07,492 [ing.Main.main()] DEBUG nTypeConverterLoader - Lo= ading converter class: org.apache.camel.converter.jaxp.StaxConverter > 2011-02-10 12:23:07,494 [ing.Main.main()] DEBUG nTypeConverterLoader - Lo= ading converter class: org.apache.camel.converter.jaxp.StreamSourceConverte= r > 2011-02-10 12:23:07,494 [ing.Main.main()] DEBUG nTypeConverterLoader - Lo= ading converter class: org.apache.camel.converter.jaxp.XmlConverter > 2011-02-10 12:23:07,499 [ing.Main.main()] DEBUG nTypeConverterLoader - Lo= ading converter class: org.apache.camel.converter.stream.StreamCacheConvert= er > 2011-02-10 12:23:07,499 [ing.Main.main()] DEBUG nTypeConverterLoader - Lo= ading converter class: org.apache.activemq.camel.converter.ActiveMQConverte= r > 2011-02-10 12:23:07,501 [ing.Main.main()] DEBUG nTypeConverterLoader - Lo= ading converter class: org.apache.activemq.camel.converter.ActiveMQMessageC= onverter > 2011-02-10 12:23:07,507 [ing.Main.main()] DEBUG DefaultTypeConverter - Lo= ading type converters done > 2011-02-10 12:23:07,508 [ing.Main.main()] INFO DefaultTypeConverter - Lo= aded 150 type converters in 0.313 seconds > 2011-02-10 12:23:07,516 [ing.Main.main()] DEBUG faultManagementAgent - Re= gistered MBean with objectname: org.apache.camel:context=3Djacovt-notebook/= camelTestRouteBuilderContext,type=3Dservices,name=3DDefaultExecutorServiceS= trategy(0x4d12ee4f) > 2011-02-10 12:23:07,525 [ing.Main.main()] DEBUG faultManagementAgent - Re= gistered MBean with objectname: org.apache.camel:context=3Djacovt-notebook/= camelTestRouteBuilderContext,type=3Dservices,name=3DSharedProducerServicePo= ol(0x15dbac11) > 2011-02-10 12:23:07,525 [ing.Main.main()] DEBUG dProducerServicePool - St= arting service pool: org.apache.camel.impl.SharedProducerServicePool@15dbac= 11 > 2011-02-10 12:23:07,534 [ing.Main.main()] DEBUG faultManagementAgent - Re= gistered MBean with objectname: org.apache.camel:context=3Djacovt-notebook/= camelTestRouteBuilderContext,type=3Dservices,name=3DDefaultInflightReposito= ry(0xf7b650a) > 2011-02-10 12:23:07,542 [ing.Main.main()] DEBUG faultManagementAgent - Re= gistered MBean with objectname: org.apache.camel:context=3Djacovt-notebook/= camelTestRouteBuilderContext,type=3Dservices,name=3DDefaultShutdownStrategy= (0x6e267b76) > 2011-02-10 12:23:07,555 [ing.Main.main()] DEBUG ultComponentResolver - Fo= und component: smpp via type: org.apache.camel.component.smpp.SmppComponent= via: META-INF/services/org/apache/camel/component/smpp > 2011-02-10 12:23:07,590 [ing.Main.main()] DEBUG faultManagementAgent - Re= gistered MBean with objectname: org.apache.camel:context=3Djacovt-notebook/= camelTestRouteBuilderContext,type=3Dcomponents,name=3D"smpp" > 2011-02-10 12:23:07,591 [ing.Main.main()] DEBUG DefaultComponent - Cr= eating endpoint uri=3D[smpp://ubankmobile01@127.0.0.1:2775?amp%3BenquireLin= kTimer=3D55000&%3BsystemType=3Dubankmobile01&%3BtransactionTimer=3D15= 000&password=3Du%24%40nk01], path=3D[ubankmobile01@127.0.0.1:2775], paramet= ers=3D[{amp;enquireLinkTimer=3D55000, amp;systemType=3Dubankmobile01, amp;t= ransactionTimer=3D15000, password=3Du$@nk01}] > 2011-02-10 12:23:07,605 [ing.Main.main()] DEBUG SpringCamelContext - sm= pp://ubankmobile01@127.0.0.1:2775?amp%3BenquireLinkTimer=3D55000&%3Bsyst= emType=3Dubankmobile01&%3BtransactionTimer=3D15000&password=3Du%24%40nk0= 1 converted to endpoint: Endpoint[smpp://ubankmobile01@127.0.0.1:2775?amp%3= BenquireLinkTimer=3D55000&%3BsystemType=3Dubankmobile01&%3Btransactio= nTimer=3D15000&password=3D******] by component: org.apache.camel.component.= smpp.SmppComponent@517c804b > 2011-02-10 12:23:07,615 [ing.Main.main()] DEBUG faultManagementAgent - Re= gistered MBean with objectname: org.apache.camel:context=3Djacovt-notebook/= camelTestRouteBuilderContext,type=3Dendpoints,name=3D"smpp://ubankmobile01@= 127.0.0.1:2775" > 2011-02-10 12:23:07,655 [ing.Main.main()] DEBUG ultComponentResolver - Fo= und component: log via type: org.apache.camel.component.log.LogComponent vi= a: META-INF/services/org/apache/camel/component/log > 2011-02-10 12:23:07,661 [ing.Main.main()] DEBUG faultManagementAgent - Re= gistered MBean with objectname: org.apache.camel:context=3Djacovt-notebook/= camelTestRouteBuilderContext,type=3Dcomponents,name=3D"log" > 2011-02-10 12:23:07,661 [ing.Main.main()] DEBUG DefaultComponent - Cr= eating endpoint uri=3D[log://SmppIn], path=3D[SmppIn], parameters=3D[{}] > 2011-02-10 12:23:07,666 [ing.Main.main()] DEBUG SpringCamelContext - lo= g://SmppIn converted to endpoint: Endpoint[log://SmppIn] by component: org.= apache.camel.component.log.LogComponent@4633c1aa > 2011-02-10 12:23:07,669 [ing.Main.main()] DEBUG faultManagementAgent - Re= gistered MBean with objectname: org.apache.camel:context=3Djacovt-notebook/= camelTestRouteBuilderContext,type=3Dendpoints,name=3D"log://SmppIn" > 2011-02-10 12:23:07,712 [ing.Main.main()] DEBUG faultManagementAgent - Re= gistered MBean with objectname: org.apache.camel:context=3Djacovt-notebook/= camelTestRouteBuilderContext,type=3Dtracer,name=3DTracer(0x3761f888) > 2011-02-10 12:23:07,747 [ing.Main.main()] DEBUG faultManagementAgent - Re= gistered MBean with objectname: org.apache.camel:context=3Djacovt-notebook/= camelTestRouteBuilderContext,type=3Derrorhandlers,name=3D"DefaultErrorHandl= erBuilder(ref:CamelDefaultErrorHandlerBuilder)" > 2011-02-10 12:23:07,754 [ing.Main.main()] DEBUG SpringCamelContext - Wa= rming up route id: SmppConsumerRoute having autoStartup=3Dtrue > 2011-02-10 12:23:07,772 [ing.Main.main()] DEBUG faultManagementAgent - Re= gistered MBean with objectname: org.apache.camel:context=3Djacovt-notebook/= camelTestRouteBuilderContext,type=3Dservices,name=3DProducerCache(0x3a67ad7= 9) > 2011-02-10 12:23:07,774 [ing.Main.main()] DEBUG ProcessorEndpoint$1 - St= arting producer: Producer[log://SmppIn] > 2011-02-10 12:23:07,775 [ing.Main.main()] DEBUG ProducerCache - Ad= ding to producer cache with key: Endpoint[log://SmppIn] for producer: Produ= cer[log://SmppIn] > 2011-02-10 12:23:07,797 [ing.Main.main()] DEBUG faultManagementAgent - Re= gistered MBean with objectname: org.apache.camel:context=3Djacovt-notebook/= camelTestRouteBuilderContext,type=3Dthreadpools,name=3DDefaultErrorHandler(= 0xa62b39f) > 2011-02-10 12:23:07,797 [ing.Main.main()] DEBUG cutorServiceStrategy - Cr= eated new scheduled thread pool for source: DefaultErrorHandler[TraceInterc= eptor[To[log:SmppIn]]] with name: ErrorHandlerRedeliveryTask. [poolSize=3D1= 0]. -> java.util.concurrent.ScheduledThreadPoolExecutor@55fe910c > 2011-02-10 12:23:07,844 [ing.Main.main()] DEBUG faultManagementAgent - Re= gistered MBean with objectname: org.apache.camel:context=3Djacovt-notebook/= camelTestRouteBuilderContext,type=3Dprocessors,name=3D"to1" > 2011-02-10 12:23:07,873 [ing.Main.main()] DEBUG faultManagementAgent - Re= gistered MBean with objectname: org.apache.camel:context=3Djacovt-notebook/= camelTestRouteBuilderContext,type=3Droutes,name=3D"SmppConsumerRoute" > 2011-02-10 12:23:07,873 [ing.Main.main()] DEBUG SpringCamelContext - Ro= ute: SmppConsumerRoute >>> EventDrivenConsumerRoute[Endpoint[smpp://ubankmo= bile01@127.0.0.1:2775?amp%3BenquireLinkTimer=3D55000&%3BsystemType=3Duba= nkmobile01&%3BtransactionTimer=3D15000&password=3D******] -> Instrumenta= tion:route[UnitOfWork(Channel[sendTo(Endpoint[log://SmppIn])])]] > 2011-02-10 12:23:07,873 [ing.Main.main()] DEBUG SpringCamelContext - St= arting consumer (order: 1000) on route: SmppConsumerRoute > 2011-02-10 12:23:07,884 [ing.Main.main()] DEBUG faultManagementAgent - Re= gistered MBean with objectname: org.apache.camel:context=3Djacovt-notebook/= camelTestRouteBuilderContext,type=3Dconsumers,name=3DSmppConsumer(0x59c2740= 2) > 2011-02-10 12:23:07,884 [ing.Main.main()] DEBUG SmppConsumer - Co= nnecting to: smpp://ubankmobile01@127.0.0.1:2775... > 2011-02-10 12:23:07,884 [ing.Main.main()] DEBUG SmppConsumer - St= arting consumer: SmppConsumer[smpp://ubankmobile01@127.0.0.1:2775] > 2011-02-10 12:23:07,922 [ing.Main.main()] DEBUG SMPPSession - Co= nnect and bind to 127.0.0.1 port 2775 > 2011-02-10 12:23:07,923 [ing.Main.main()] INFO SMPPSession - Co= nnected > 2011-02-10 12:23:07,923 [ing.Main.main()] DEBUG SmppConsumer - >>= >>>>>>>>>>>>>>>>> STATE CHANGE -- CLOSED --> OPEN[org.jsmpp.session.SMPPSes= sion@501b2cb9] > 2011-02-10 12:23:07,925 [Thread-6 ] INFO SMPPSession - St= arting PDUReaderWorker with processor degree:3 ... > 2011-02-10 12:23:10,913 [pool-1-thread-1] DEBUG stractSessionContext - Ac= tivity notified > 2011-02-10 12:23:10,913 [pool-1-thread-1] DEBUG SMPPSessionOpen - Bi= nd Response header (30, 80000001, 00000000, 1) > 2011-02-10 12:23:10,915 [ing.Main.main()] DEBUG AbstractSession - bi= nd response received > 2011-02-10 12:23:10,915 [ing.Main.main()] DEBUG SmppConsumer - >>= >>>>>>>>>>>>>>>>> STATE CHANGE -- OPEN --> BOUND_RX[org.jsmpp.session.SMPPS= ession@501b2cb9] > 2011-02-10 12:23:10,916 [ing.Main.main()] DEBUG SmppConsumer - Se= ssion info: [org.jsmpp.session.SMPPSession@501b2cb9], session state: BOUND_= RX > 2011-02-10 12:23:10,916 [Thread-7 ] INFO SMPPSession - St= arting EnquireLinkSender > 2011-02-10 12:23:10,916 [ing.Main.main()] INFO SmppConsumer - Co= nnected to: smpp://ubankmobile01@127.0.0.1:2775 > 2011-02-10 12:23:10,917 [ing.Main.main()] INFO SpringCamelContext - Ro= ute: SmppConsumerRoute started and consuming from: Endpoint[smpp://ubankmob= ile01@127.0.0.1:2775?amp%3BenquireLinkTimer=3D55000&%3BsystemType=3Duban= kmobile01&%3BtransactionTimer=3D15000&password=3D******] > 2011-02-10 12:23:10,918 [ing.Main.main()] DEBUG DefaultComponent - Cr= eating endpoint uri=3D[spring-event://default], path=3D[default], parameter= s=3D[{}] > 2011-02-10 12:23:10,919 [ing.Main.main()] DEBUG SpringCamelContext - sp= ring-event://default converted to endpoint: Endpoint[spring-event://default= ] by component: org.apache.camel.component.event.EventComponent@774e1f2b > 2011-02-10 12:23:10,925 [ing.Main.main()] DEBUG faultManagementAgent - Re= gistered MBean with objectname: org.apache.camel:context=3Djacovt-notebook/= camelTestRouteBuilderContext,type=3Dendpoints,name=3D"spring-event://defaul= t" > 2011-02-10 12:23:10,926 [ing.Main.main()] INFO SpringCamelContext - To= tal 1 routes, of which 1 is started. > 2011-02-10 12:23:10,926 [ing.Main.main()] INFO SpringCamelContext - Ap= ache Camel 2.6.0 (CamelContext: camelTestRouteBuilderContext) started in 3.= 866 seconds > 2011-02-10 12:23:10,935 [ing.Main.main()] DEBUG MainSupport - St= arting Spring ApplicationContext: org.springframework.context.support.Class= PathXmlApplicationContext@4c9549af > 2011-02-10 12:23:10,936 [ing.Main.main()] DEBUG SpringCamelContext - on= ApplicationEvent: org.springframework.context.event.ContextStartedEvent[sou= rce=3Dorg.springframework.context.support.ClassPathXmlApplicationContext@4c= 9549af: startup date [Thu Feb 10 12:23:05 SAST 2011]; root of context hiera= rchy] > 2011-02-10 12:23:11,408 [pool-1-thread-2] DEBUG stractSessionContext - Ac= tivity notified > 2011-02-10 12:23:11,408 [pool-1-thread-2] DEBUG SMPPSession - Se= nding enquire_link_resp > 2011-02-10 12:23:16,413 [Thread-6 ] DEBUG SMPPSession - No= activity notified > 2011-02-10 12:23:16,413 [Thread-6 ] DEBUG SMPPSession - Se= nding enquire link notify > 2011-02-10 12:23:18,851 [pool-1-thread-3] DEBUG stractSessionContext - Ac= tivity notified > 2011-02-10 12:23:18,852 [Thread-7 ] DEBUG AbstractSession - en= quire_link response received > 2011-02-10 12:23:23,853 [Thread-6 ] DEBUG SMPPSession - No= activity notified > 2011-02-10 12:23:23,853 [Thread-6 ] DEBUG SMPPSession - Se= nding enquire link notify > 2011-02-10 12:23:24,854 [pool-1-thread-1] DEBUG stractSessionContext - Ac= tivity notified > 2011-02-10 12:23:24,854 [Thread-7 ] DEBUG AbstractSession - en= quire_link response received > 2011-02-10 12:23:29,859 [Thread-6 ] DEBUG SMPPSession - No= activity notified > 2011-02-10 12:23:29,859 [Thread-6 ] DEBUG SMPPSession - Se= nding enquire link notify > 2011-02-10 12:23:30,938 [pool-1-thread-2] DEBUG stractSessionContext - Ac= tivity notified > 2011-02-10 12:23:30,938 [Thread-7 ] DEBUG AbstractSession - en= quire_link response received > 2011-02-10 12:23:35,943 [Thread-6 ] DEBUG SMPPSession - No= activity notified > 2011-02-10 12:23:35,943 [Thread-6 ] DEBUG SMPPSession - Se= nding enquire link notify > 2011-02-10 12:23:37,901 [pool-1-thread-3] DEBUG stractSessionContext - Ac= tivity notified > 2011-02-10 12:23:37,901 [Thread-7 ] DEBUG AbstractSession - en= quire_link response received > 2011-02-10 12:23:42,906 [Thread-6 ] DEBUG SMPPSession - No= activity notified > 2011-02-10 12:23:42,906 [Thread-6 ] DEBUG SMPPSession - Se= nding enquire link notify > 2011-02-10 12:23:45,256 [pool-1-thread-1] DEBUG stractSessionContext - Ac= tivity notified > 2011-02-10 12:23:45,256 [Thread-7 ] DEBUG AbstractSession - en= quire_link response received > 2011-02-10 12:23:50,261 [Thread-6 ] DEBUG SMPPSession - No= activity notified > 2011-02-10 12:23:50,261 [Thread-6 ] DEBUG SMPPSession - Se= nding enquire link notify > 2011-02-10 12:23:51,232 [pool-1-thread-2] DEBUG stractSessionContext - Ac= tivity notified > 2011-02-10 12:23:51,232 [Thread-7 ] DEBUG AbstractSession - en= quire_link response received > 2011-02-10 12:23:56,237 [Thread-6 ] DEBUG SMPPSession - No= activity notified > 2011-02-10 12:23:56,237 [Thread-6 ] DEBUG SMPPSession - Se= nding enquire link notify > 2011-02-10 12:23:57,208 [pool-1-thread-3] DEBUG stractSessionContext - Ac= tivity notified > 2011-02-10 12:23:57,208 [Thread-7 ] DEBUG AbstractSession - en= quire_link response received > 2011-02-10 12:24:02,211 [Thread-6 ] DEBUG SMPPSession - No= activity notified > 2011-02-10 12:24:02,211 [Thread-6 ] DEBUG SMPPSession - Se= nding enquire link notify > 2011-02-10 12:24:03,277 [pool-1-thread-1] DEBUG stractSessionContext - Ac= tivity notified > 2011-02-10 12:24:03,277 [Thread-7 ] DEBUG AbstractSession - en= quire_link response received > 2011-02-10 12:24:08,281 [Thread-6 ] DEBUG SMPPSession - No= activity notified > 2011-02-10 12:24:08,281 [Thread-6 ] DEBUG SMPPSession - Se= nding enquire link notify > 2011-02-10 12:24:09,436 [pool-1-thread-2] DEBUG stractSessionContext - Ac= tivity notified > 2011-02-10 12:24:09,437 [Thread-7 ] DEBUG AbstractSession - en= quire_link response received > 2011-02-10 12:24:09,821 [pool-1-thread-3] DEBUG stractSessionContext - Ac= tivity notified > 2011-02-10 12:24:09,821 [pool-1-thread-3] INFO ericSMPPSessionBound - Re= ceving unbind request > 2011-02-10 12:24:09,822 [pool-1-thread-3] DEBUG SmppConsumer - >>= >>>>>>>>>>>>>>>>> STATE CHANGE -- BOUND_RX --> UNBOUND[org.jsmpp.session.SM= PPSession@501b2cb9] > {code} > Everything is perfectly fine up to this point. It is here that the SMSC /= simulator issues an unbind request towards the camel-smpp consumer. > {code} > 2011-02-10 12:24:09,937 [Thread-7 ] INFO SMPPSession - En= quireLinkSender stop > 2011-02-10 12:24:14,826 [Thread-6 ] DEBUG SMPPSession - No= activity notified > 2011-02-10 12:24:14,827 [Thread-6 ] DEBUG SmppConsumer - >>= >>>>>>>>>>>>>>>>> STATE CHANGE -- UNBOUND --> CLOSED[org.jsmpp.session.SMPP= Session@501b2cb9] > 2011-02-10 12:24:14,827 [Thread-6 ] WARN SmppConsumer - Lo= ost connection to: smpp://ubankmobile01@127.0.0.1:2775 - trying to reconnec= t... > 2011-02-10 12:24:14,827 [Thread-6 ] DEBUG SmppConsumer - >>= >>>>>>>> Closing SMPP session [org.jsmpp.session.SMPPSession@501b2cb9] > 2011-02-10 12:24:14,829 [Thread-8 ] INFO SmppConsumer - Sc= hedule reconnect after 5000 millis > 2011-02-10 12:24:19,829 [Thread-8 ] INFO SmppConsumer - Tr= ying to reconnect to smpp://ubankmobile01@127.0.0.1:2775 - attempt #1... > 2011-02-10 12:24:19,830 [Thread-8 ] DEBUG SmppConsumer - Se= ssion info: [org.jsmpp.session.SMPPSession@501b2cb9], session state: CLOSED > 2011-02-10 12:24:19,830 [Thread-8 ] DEBUG SMPPSession - Co= nnect and bind to 127.0.0.1 port 2775 > 2011-02-10 12:24:19,830 [Thread-8 ] INFO SMPPSession - Co= nnected > 2011-02-10 12:24:19,830 [Thread-8 ] DEBUG SmppConsumer - >>= >>>>>>>>>>>>>>>>> STATE CHANGE -- CLOSED --> OPEN[org.jsmpp.session.SMPPSes= sion@1b6b7f83] > 2011-02-10 12:24:19,831 [Thread-9 ] INFO SMPPSession - St= arting PDUReaderWorker with processor degree:3 ... > 2011-02-10 12:24:22,834 [pool-2-thread-1] DEBUG stractSessionContext - Ac= tivity notified > 2011-02-10 12:24:22,834 [pool-2-thread-1] DEBUG SMPPSessionOpen - Bi= nd Response header (30, 80000001, 0000000d, 1) > 2011-02-10 12:24:22,835 [Thread-8 ] DEBUG AbstractSession - bi= nd response received > 2011-02-10 12:24:22,837 [Thread-8 ] ERROR SMPPSession - Re= ceive negative bind response > org.jsmpp.extra.NegativeResponseException: Negative response 0000000d fou= nd > =09at org.jsmpp.session.AbstractSession.validateResponse(AbstractSession.= java:215) > =09at org.jsmpp.session.AbstractSession.executeSendCommand(AbstractSessio= n.java:271) > =09at org.jsmpp.session.SMPPSession.sendBind(SMPPSession.java:294) > =09at org.jsmpp.session.SMPPSession.connectAndBind(SMPPSession.java:232) > =09at org.jsmpp.session.SMPPSession.connectAndBind(SMPPSession.java:200) > =09at org.apache.camel.component.smpp.SmppConsumer.createSession(SmppCons= umer.java:161) > =09at org.apache.camel.component.smpp.SmppConsumer.access$500(SmppConsume= r.java:54) > =09at org.apache.camel.component.smpp.SmppConsumer$3.run(SmppConsumer.jav= a:223) > 2011-02-10 12:24:22,838 [Thread-8 ] DEBUG SmppConsumer - >>= >>>>>>>>>>>>>>>>> STATE CHANGE -- OPEN --> CLOSED[org.jsmpp.session.SMPPSes= sion@1b6b7f83] > {code} > The above negative bind responseis due to the fact that only a single smp= p RX bind is allowed to the SMSC / simulator. I suspect the underlying prob= lem is how the exception is handled here, but I might be wrong. > {code} > 2011-02-10 12:24:22,838 [Thread-8 ] WARN SmppConsumer - Lo= ost connection to: smpp://ubankmobile01@127.0.0.1:2775 - trying to reconnec= t... > 2011-02-10 12:24:22,838 [Thread-8 ] DEBUG SmppConsumer - >>= >>>>>>>> Closing SMPP session [org.jsmpp.session.SMPPSession@501b2cb9] > 2011-02-10 12:24:22,838 [Thread-10 ] INFO SmppConsumer - Sc= hedule reconnect after 5000 millis > 2011-02-10 12:24:22,838 [Thread-8 ] DEBUG SmppConsumer - Se= ssion info: [org.jsmpp.session.SMPPSession@501b2cb9], session state: CLOSED > 2011-02-10 12:24:22,838 [Thread-8 ] INFO SmppConsumer - Fa= iled to reconnect to smpp://ubankmobile01@127.0.0.1:2775, sessionState =3D= =3D CLOSED > 2011-02-10 12:24:22,838 [Thread-9 ] INFO SMPPSession - PD= UReaderWorker stop > 2011-02-10 12:24:22,838 [Thread-8 ] DEBUG SmppConsumer - >>= >>>>>>>> Closing SMPP session [org.jsmpp.session.SMPPSession@501b2cb9] > 2011-02-10 12:24:27,838 [Thread-10 ] INFO SmppConsumer - Tr= ying to reconnect to smpp://ubankmobile01@127.0.0.1:2775 - attempt #1... > 2011-02-10 12:24:27,839 [Thread-10 ] DEBUG SmppConsumer - Se= ssion info: [org.jsmpp.session.SMPPSession@501b2cb9], session state: CLOSED > 2011-02-10 12:24:27,839 [Thread-10 ] DEBUG SMPPSession - Co= nnect and bind to 127.0.0.1 port 2775 > 2011-02-10 12:24:27,839 [Thread-8 ] INFO SmppConsumer - Tr= ying to reconnect to smpp://ubankmobile01@127.0.0.1:2775 - attempt #2... > 2011-02-10 12:24:27,839 [Thread-8 ] DEBUG SmppConsumer - Se= ssion info: [org.jsmpp.session.SMPPSession@501b2cb9], session state: CLOSED > 2011-02-10 12:24:27,839 [Thread-10 ] INFO SMPPSession - Co= nnected > 2011-02-10 12:24:27,839 [Thread-8 ] DEBUG SMPPSession - Co= nnect and bind to 127.0.0.1 port 2775 > 2011-02-10 12:24:27,839 [Thread-10 ] DEBUG SmppConsumer - >>= >>>>>>>>>>>>>>>>> STATE CHANGE -- CLOSED --> OPEN[org.jsmpp.session.SMPPSes= sion@62bc184] > 2011-02-10 12:24:27,840 [Thread-11 ] INFO SMPPSession - St= arting PDUReaderWorker with processor degree:3 ... > 2011-02-10 12:24:27,840 [Thread-8 ] INFO SMPPSession - Co= nnected > 2011-02-10 12:24:27,840 [Thread-8 ] DEBUG SmppConsumer - >>= >>>>>>>>>>>>>>>>> STATE CHANGE -- CLOSED --> OPEN[org.jsmpp.session.SMPPSes= sion@3e30e173] > {code} > OK, wait, we have 2 SMPPSession object references now? Seems like we have= 2 Threads running that tries to re-connect... > {code} > 2011-02-10 12:24:27,841 [Thread-12 ] INFO SMPPSession - St= arting PDUReaderWorker with processor degree:3 ... > 2011-02-10 12:24:31,035 [pool-3-thread-1] DEBUG stractSessionContext - Ac= tivity notified > 2011-02-10 12:24:31,035 [pool-3-thread-1] DEBUG SMPPSessionOpen - Bi= nd Response header (30, 80000001, 0000000d, 1) > 2011-02-10 12:24:31,035 [Thread-10 ] DEBUG AbstractSession - bi= nd response received > 2011-02-10 12:24:31,035 [Thread-10 ] ERROR SMPPSession - Re= ceive negative bind response > org.jsmpp.extra.NegativeResponseException: Negative response 0000000d fou= nd > =09at org.jsmpp.session.AbstractSession.validateResponse(AbstractSession.= java:215) > =09at org.jsmpp.session.AbstractSession.executeSendCommand(AbstractSessio= n.java:271) > =09at org.jsmpp.session.SMPPSession.sendBind(SMPPSession.java:294) > =09at org.jsmpp.session.SMPPSession.connectAndBind(SMPPSession.java:232) > =09at org.jsmpp.session.SMPPSession.connectAndBind(SMPPSession.java:200) > =09at org.apache.camel.component.smpp.SmppConsumer.createSession(SmppCons= umer.java:161) > =09at org.apache.camel.component.smpp.SmppConsumer.access$500(SmppConsume= r.java:54) > =09at org.apache.camel.component.smpp.SmppConsumer$3.run(SmppConsumer.jav= a:223) > 2011-02-10 12:24:31,036 [Thread-10 ] DEBUG SmppConsumer - >>= >>>>>>>>>>>>>>>>> STATE CHANGE -- OPEN --> CLOSED[org.jsmpp.session.SMPPSes= sion@62bc184] > 2011-02-10 12:24:31,036 [Thread-10 ] WARN SmppConsumer - Lo= ost connection to: smpp://ubankmobile01@127.0.0.1:2775 - trying to reconnec= t... > 2011-02-10 12:24:31,036 [Thread-10 ] DEBUG SmppConsumer - >>= >>>>>>>> Closing SMPP session [org.jsmpp.session.SMPPSession@501b2cb9] > 2011-02-10 12:24:31,036 [Thread-10 ] DEBUG SmppConsumer - Se= ssion info: [org.jsmpp.session.SMPPSession@501b2cb9], session state: CLOSED > 2011-02-10 12:24:31,036 [Thread-10 ] INFO SmppConsumer - Fa= iled to reconnect to smpp://ubankmobile01@127.0.0.1:2775, sessionState =3D= =3D CLOSED > 2011-02-10 12:24:31,036 [Thread-11 ] INFO SMPPSession - PD= UReaderWorker stop > 2011-02-10 12:24:31,036 [Thread-10 ] DEBUG SmppConsumer - >>= >>>>>>>> Closing SMPP session [org.jsmpp.session.SMPPSession@501b2cb9] > 2011-02-10 12:24:31,037 [Thread-13 ] INFO SmppConsumer - Sc= hedule reconnect after 5000 millis > 2011-02-10 12:24:32,846 [Thread-12 ] DEBUG SMPPSession - No= activity notified > 2011-02-10 12:24:32,982 [pool-4-thread-1] DEBUG stractSessionContext - Ac= tivity notified > 2011-02-10 12:24:32,982 [pool-4-thread-1] DEBUG SMPPSessionOpen - Bi= nd Response header (30, 80000001, 0000000d, 1) > 2011-02-10 12:24:32,982 [Thread-8 ] DEBUG AbstractSession - bi= nd response received > 2011-02-10 12:24:32,982 [Thread-8 ] ERROR SMPPSession - Re= ceive negative bind response > org.jsmpp.extra.NegativeResponseException: Negative response 0000000d fou= nd > =09at org.jsmpp.session.AbstractSession.validateResponse(AbstractSession.= java:215) > =09at org.jsmpp.session.AbstractSession.executeSendCommand(AbstractSessio= n.java:271) > =09at org.jsmpp.session.SMPPSession.sendBind(SMPPSession.java:294) > =09at org.jsmpp.session.SMPPSession.connectAndBind(SMPPSession.java:232) > =09at org.jsmpp.session.SMPPSession.connectAndBind(SMPPSession.java:200) > =09at org.apache.camel.component.smpp.SmppConsumer.createSession(SmppCons= umer.java:161) > =09at org.apache.camel.component.smpp.SmppConsumer.access$500(SmppConsume= r.java:54) > =09at org.apache.camel.component.smpp.SmppConsumer$3.run(SmppConsumer.jav= a:223) > 2011-02-10 12:24:32,982 [Thread-8 ] DEBUG SmppConsumer - >>= >>>>>>>>>>>>>>>>> STATE CHANGE -- OPEN --> CLOSED[org.jsmpp.session.SMPPSes= sion@3e30e173] > 2011-02-10 12:24:32,982 [Thread-8 ] WARN SmppConsumer - Lo= ost connection to: smpp://ubankmobile01@127.0.0.1:2775 - trying to reconnec= t... > 2011-02-10 12:24:32,982 [Thread-8 ] DEBUG SmppConsumer - >>= >>>>>>>> Closing SMPP session [org.jsmpp.session.SMPPSession@501b2cb9] > 2011-02-10 12:24:32,983 [Thread-8 ] DEBUG SmppConsumer - Se= ssion info: [org.jsmpp.session.SMPPSession@501b2cb9], session state: CLOSED > 2011-02-10 12:24:32,983 [Thread-12 ] INFO SMPPSession - PD= UReaderWorker stop > 2011-02-10 12:24:32,983 [Thread-14 ] INFO SmppConsumer - Sc= hedule reconnect after 5000 millis > 2011-02-10 12:24:32,983 [Thread-8 ] INFO SmppConsumer - Fa= iled to reconnect to smpp://ubankmobile01@127.0.0.1:2775, sessionState =3D= =3D CLOSED > 2011-02-10 12:24:32,983 [Thread-8 ] DEBUG SmppConsumer - >>= >>>>>>>> Closing SMPP session [org.jsmpp.session.SMPPSession@501b2cb9] > 2011-02-10 12:24:36,037 [Thread-10 ] INFO SmppConsumer - Tr= ying to reconnect to smpp://ubankmobile01@127.0.0.1:2775 - attempt #2... > 2011-02-10 12:24:36,037 [Thread-13 ] INFO SmppConsumer - Tr= ying to reconnect to smpp://ubankmobile01@127.0.0.1:2775 - attempt #1... > {code} > Some more re-connect threads... > {code} > 2011-02-10 12:24:36,038 [Thread-10 ] DEBUG SmppConsumer - Se= ssion info: [org.jsmpp.session.SMPPSession@501b2cb9], session state: CLOSED > 2011-02-10 12:24:36,038 [Thread-13 ] DEBUG SmppConsumer - Se= ssion info: [org.jsmpp.session.SMPPSession@501b2cb9], session state: CLOSED > 2011-02-10 12:24:36,038 [Thread-10 ] DEBUG SMPPSession - Co= nnect and bind to 127.0.0.1 port 2775 > 2011-02-10 12:24:36,038 [Thread-13 ] DEBUG SMPPSession - Co= nnect and bind to 127.0.0.1 port 2775 > 2011-02-10 12:24:36,038 [Thread-10 ] INFO SMPPSession - Co= nnected > 2011-02-10 12:24:36,038 [Thread-13 ] INFO SMPPSession - Co= nnected > 2011-02-10 12:24:36,038 [Thread-10 ] DEBUG SmppConsumer - >>= >>>>>>>>>>>>>>>>> STATE CHANGE -- CLOSED --> OPEN[org.jsmpp.session.SMPPSes= sion@782bbb7b] > 2011-02-10 12:24:36,039 [Thread-13 ] DEBUG SmppConsumer - >>= >>>>>>>>>>>>>>>>> STATE CHANGE -- CLOSED --> OPEN[org.jsmpp.session.SMPPSes= sion@7f21c5df] > 2011-02-10 12:24:36,039 [Thread-15 ] INFO SMPPSession - St= arting PDUReaderWorker with processor degree:3 ... > 2011-02-10 12:24:36,039 [Thread-16 ] INFO SMPPSession - St= arting PDUReaderWorker with processor degree:3 ... > 2011-02-10 12:24:37,983 [Thread-14 ] INFO SmppConsumer - Tr= ying to reconnect to smpp://ubankmobile01@127.0.0.1:2775 - attempt #1... > 2011-02-10 12:24:37,984 [Thread-14 ] DEBUG SmppConsumer - Se= ssion info: [org.jsmpp.session.SMPPSession@501b2cb9], session state: CLOSED > 2011-02-10 12:24:37,984 [Thread-8 ] INFO SmppConsumer - Tr= ying to reconnect to smpp://ubankmobile01@127.0.0.1:2775 - attempt #3... > 2011-02-10 12:24:37,984 [Thread-8 ] DEBUG SmppConsumer - Se= ssion info: [org.jsmpp.session.SMPPSession@501b2cb9], session state: CLOSED > 2011-02-10 12:24:37,984 [Thread-14 ] DEBUG SMPPSession - Co= nnect and bind to 127.0.0.1 port 2775 > 2011-02-10 12:24:37,984 [Thread-8 ] DEBUG SMPPSession - Co= nnect and bind to 127.0.0.1 port 2775 > 2011-02-10 12:24:37,984 [Thread-14 ] INFO SMPPSession - Co= nnected > 2011-02-10 12:24:37,984 [Thread-8 ] INFO SMPPSession - Co= nnected > 2011-02-10 12:24:37,985 [Thread-14 ] DEBUG SmppConsumer - >>= >>>>>>>>>>>>>>>>> STATE CHANGE -- CLOSED --> OPEN[org.jsmpp.session.SMPPSes= sion@6a754384] > 2011-02-10 12:24:37,985 [Thread-8 ] DEBUG SmppConsumer - >>= >>>>>>>>>>>>>>>>> STATE CHANGE -- CLOSED --> OPEN[org.jsmpp.session.SMPPSes= sion@38a3c5b6] > 2011-02-10 12:24:37,985 [Thread-17 ] INFO SMPPSession - St= arting PDUReaderWorker with processor degree:3 ... > 2011-02-10 12:24:37,987 [Thread-18 ] INFO SMPPSession - St= arting PDUReaderWorker with processor degree:3 ... > 2011-02-10 12:24:38,831 [pool-6-thread-1] DEBUG stractSessionContext - Ac= tivity notified > 2011-02-10 12:24:38,831 [pool-6-thread-1] DEBUG SMPPSessionOpen - Bi= nd Response header (30, 80000001, 0000000d, 1) > 2011-02-10 12:24:38,831 [Thread-13 ] DEBUG AbstractSession - bi= nd response received > 2011-02-10 12:24:38,831 [Thread-13 ] ERROR SMPPSession - Re= ceive negative bind response > org.jsmpp.extra.NegativeResponseException: Negative response 0000000d fou= nd > =09at org.jsmpp.session.AbstractSession.validateResponse(AbstractSession.= java:215) > =09at org.jsmpp.session.AbstractSession.executeSendCommand(AbstractSessio= n.java:271) > =09at org.jsmpp.session.SMPPSession.sendBind(SMPPSession.java:294) > =09at org.jsmpp.session.SMPPSession.connectAndBind(SMPPSession.java:232) > =09at org.jsmpp.session.SMPPSession.connectAndBind(SMPPSession.java:200) > =09at org.apache.camel.component.smpp.SmppConsumer.createSession(SmppCons= umer.java:161) > =09at org.apache.camel.component.smpp.SmppConsumer.access$500(SmppConsume= r.java:54) > =09at org.apache.camel.component.smpp.SmppConsumer$3.run(SmppConsumer.jav= a:223) > 2011-02-10 12:24:38,832 [Thread-13 ] DEBUG SmppConsumer - >>= >>>>>>>>>>>>>>>>> STATE CHANGE -- OPEN --> CLOSED[org.jsmpp.session.SMPPSes= sion@7f21c5df] > 2011-02-10 12:24:38,832 [Thread-13 ] WARN SmppConsumer - Lo= ost connection to: smpp://ubankmobile01@127.0.0.1:2775 - trying to reconnec= t... > 2011-02-10 12:24:38,832 [Thread-13 ] DEBUG SmppConsumer - >>= >>>>>>>> Closing SMPP session [org.jsmpp.session.SMPPSession@501b2cb9] > 2011-02-10 12:24:38,832 [Thread-13 ] DEBUG SmppConsumer - Se= ssion info: [org.jsmpp.session.SMPPSession@501b2cb9], session state: CLOSED > 2011-02-10 12:24:38,832 [Thread-19 ] INFO SmppConsumer - Sc= hedule reconnect after 5000 millis > 2011-02-10 12:24:38,832 [Thread-13 ] INFO SmppConsumer - Fa= iled to reconnect to smpp://ubankmobile01@127.0.0.1:2775, sessionState =3D= =3D CLOSED > 2011-02-10 12:24:38,832 [Thread-16 ] INFO SMPPSession - PD= UReaderWorker stop > 2011-02-10 12:24:38,833 [Thread-13 ] DEBUG SmppConsumer - >>= >>>>>>>> Closing SMPP session [org.jsmpp.session.SMPPSession@501b2cb9] > 2011-02-10 12:24:40,957 [pool-5-thread-1] DEBUG stractSessionContext - Ac= tivity notified > 2011-02-10 12:24:40,957 [pool-5-thread-1] DEBUG SMPPSessionOpen - Bi= nd Response header (30, 80000001, 0000000d, 1) > 2011-02-10 12:24:40,957 [Thread-10 ] DEBUG AbstractSession - bi= nd response received > 2011-02-10 12:24:40,957 [Thread-10 ] ERROR SMPPSession - Re= ceive negative bind response > org.jsmpp.extra.NegativeResponseException: Negative response 0000000d fou= nd > =09at org.jsmpp.session.AbstractSession.validateResponse(AbstractSession.= java:215) > =09at org.jsmpp.session.AbstractSession.executeSendCommand(AbstractSessio= n.java:271) > =09at org.jsmpp.session.SMPPSession.sendBind(SMPPSession.java:294) > =09at org.jsmpp.session.SMPPSession.connectAndBind(SMPPSession.java:232) > =09at org.jsmpp.session.SMPPSession.connectAndBind(SMPPSession.java:200) > =09at org.apache.camel.component.smpp.SmppConsumer.createSession(SmppCons= umer.java:161) > =09at org.apache.camel.component.smpp.SmppConsumer.access$500(SmppConsume= r.java:54) > =09at org.apache.camel.component.smpp.SmppConsumer$3.run(SmppConsumer.jav= a:223) > 2011-02-10 12:24:40,958 [Thread-10 ] DEBUG SmppConsumer - >>= >>>>>>>>>>>>>>>>> STATE CHANGE -- OPEN --> CLOSED[org.jsmpp.session.SMPPSes= sion@782bbb7b] > 2011-02-10 12:24:40,958 [Thread-10 ] WARN SmppConsumer - Lo= ost connection to: smpp://ubankmobile01@127.0.0.1:2775 - trying to reconnec= t... > 2011-02-10 12:24:40,958 [Thread-10 ] DEBUG SmppConsumer - >>= >>>>>>>> Closing SMPP session [org.jsmpp.session.SMPPSession@501b2cb9] > 2011-02-10 12:24:40,958 [Thread-20 ] INFO SmppConsumer - Sc= hedule reconnect after 5000 millis > 2011-02-10 12:24:40,958 [Thread-15 ] INFO SMPPSession - PD= UReaderWorker stop > 2011-02-10 12:24:40,958 [Thread-10 ] DEBUG SmppConsumer - Se= ssion info: [org.jsmpp.session.SMPPSession@501b2cb9], session state: CLOSED > 2011-02-10 12:24:40,958 [Thread-10 ] INFO SmppConsumer - Fa= iled to reconnect to smpp://ubankmobile01@127.0.0.1:2775, sessionState =3D= =3D CLOSED > 2011-02-10 12:24:40,959 [Thread-10 ] DEBUG SmppConsumer - >>= >>>>>>>> Closing SMPP session [org.jsmpp.session.SMPPSession@501b2cb9] > 2011-02-10 12:24:42,821 [pool-8-thread-1] DEBUG stractSessionContext - Ac= tivity notified > 2011-02-10 12:24:42,821 [pool-8-thread-1] DEBUG SMPPSessionOpen - Bi= nd Response header (30, 80000001, 0000000d, 1) > 2011-02-10 12:24:42,821 [Thread-8 ] DEBUG AbstractSession - bi= nd response received > 2011-02-10 12:24:42,822 [Thread-8 ] ERROR SMPPSession - Re= ceive negative bind response > org.jsmpp.extra.NegativeResponseException: Negative response 0000000d fou= nd > =09at org.jsmpp.session.AbstractSession.validateResponse(AbstractSession.= java:215) > =09at org.jsmpp.session.AbstractSession.executeSendCommand(AbstractSessio= n.java:271) > =09at org.jsmpp.session.SMPPSession.sendBind(SMPPSession.java:294) > =09at org.jsmpp.session.SMPPSession.connectAndBind(SMPPSession.java:232) > =09at org.jsmpp.session.SMPPSession.connectAndBind(SMPPSession.java:200) > =09at org.apache.camel.component.smpp.SmppConsumer.createSession(SmppCons= umer.java:161) > =09at org.apache.camel.component.smpp.SmppConsumer.access$500(SmppConsume= r.java:54) > =09at org.apache.camel.component.smpp.SmppConsumer$3.run(SmppConsumer.jav= a:223) > 2011-02-10 12:24:42,822 [Thread-8 ] DEBUG SmppConsumer - >>= >>>>>>>>>>>>>>>>> STATE CHANGE -- OPEN --> CLOSED[org.jsmpp.session.SMPPSes= sion@38a3c5b6] > 2011-02-10 12:24:42,822 [Thread-8 ] WARN SmppConsumer - Lo= ost connection to: smpp://ubankmobile01@127.0.0.1:2775 - trying to reconnec= t... > 2011-02-10 12:24:42,822 [Thread-8 ] DEBUG SmppConsumer - >>= >>>>>>>> Closing SMPP session [org.jsmpp.session.SMPPSession@501b2cb9] > 2011-02-10 12:24:42,822 [Thread-21 ] INFO SmppConsumer - Sc= hedule reconnect after 5000 millis > 2011-02-10 12:24:42,822 [Thread-8 ] DEBUG SmppConsumer - Se= ssion info: [org.jsmpp.session.SMPPSession@501b2cb9], session state: CLOSED > 2011-02-10 12:24:42,822 [Thread-8 ] INFO SmppConsumer - Fa= iled to reconnect to smpp://ubankmobile01@127.0.0.1:2775, sessionState =3D= =3D CLOSED > 2011-02-10 12:24:42,822 [Thread-18 ] INFO SMPPSession - PD= UReaderWorker stop > 2011-02-10 12:24:42,823 [Thread-8 ] DEBUG SmppConsumer - >>= >>>>>>>> Closing SMPP session [org.jsmpp.session.SMPPSession@501b2cb9] > 2011-02-10 12:24:42,988 [Thread-17 ] DEBUG SMPPSession - No= activity notified > 2011-02-10 12:24:43,832 [Thread-19 ] INFO SmppConsumer - Tr= ying to reconnect to smpp://ubankmobile01@127.0.0.1:2775 - attempt #1... > 2011-02-10 12:24:43,833 [Thread-19 ] DEBUG SmppConsumer - Se= ssion info: [org.jsmpp.session.SMPPSession@501b2cb9], session state: CLOSED > 2011-02-10 12:24:43,833 [Thread-19 ] DEBUG SMPPSession - Co= nnect and bind to 127.0.0.1 port 2775 > 2011-02-10 12:24:43,833 [Thread-13 ] INFO SmppConsumer - Tr= ying to reconnect to smpp://ubankmobile01@127.0.0.1:2775 - attempt #2... > 2011-02-10 12:24:43,833 [Thread-13 ] DEBUG SmppConsumer - Se= ssion info: [org.jsmpp.session.SMPPSession@501b2cb9], session state: CLOSED > 2011-02-10 12:24:43,833 [Thread-19 ] INFO SMPPSession - Co= nnected > 2011-02-10 12:24:43,833 [Thread-13 ] DEBUG SMPPSession - Co= nnect and bind to 127.0.0.1 port 2775 > 2011-02-10 12:24:43,833 [Thread-19 ] DEBUG SmppConsumer - >>= >>>>>>>>>>>>>>>>> STATE CHANGE -- CLOSED --> OPEN[org.jsmpp.session.SMPPSes= sion@18dd7404] > 2011-02-10 12:24:43,834 [Thread-13 ] INFO SMPPSession - Co= nnected > 2011-02-10 12:24:43,834 [Thread-22 ] INFO SMPPSession - St= arting PDUReaderWorker with processor degree:3 ... > 2011-02-10 12:24:43,834 [Thread-13 ] DEBUG SmppConsumer - >>= >>>>>>>>>>>>>>>>> STATE CHANGE -- CLOSED --> OPEN[org.jsmpp.session.SMPPSes= sion@53606bf5] > 2011-02-10 12:24:43,835 [Thread-23 ] INFO SMPPSession - St= arting PDUReaderWorker with processor degree:3 ... > 2011-02-10 12:24:44,972 [pool-7-thread-1] DEBUG stractSessionContext - Ac= tivity notified > 2011-02-10 12:24:44,972 [pool-7-thread-1] DEBUG SMPPSessionOpen - Bi= nd Response header (30, 80000001, 0000000d, 1) > 2011-02-10 12:24:44,972 [Thread-14 ] DEBUG AbstractSession - bi= nd response received > 2011-02-10 12:24:44,972 [Thread-14 ] ERROR SMPPSession - Re= ceive negative bind response > org.jsmpp.extra.NegativeResponseException: Negative response 0000000d fou= nd > =09at org.jsmpp.session.AbstractSession.validateResponse(AbstractSession.= java:215) > =09at org.jsmpp.session.AbstractSession.executeSendCommand(AbstractSessio= n.java:271) > =09at org.jsmpp.session.SMPPSession.sendBind(SMPPSession.java:294) > =09at org.jsmpp.session.SMPPSession.connectAndBind(SMPPSession.java:232) > =09at org.jsmpp.session.SMPPSession.connectAndBind(SMPPSession.java:200) > =09at org.apache.camel.component.smpp.SmppConsumer.createSession(SmppCons= umer.java:161) > =09at org.apache.camel.component.smpp.SmppConsumer.access$500(SmppConsume= r.java:54) > =09at org.apache.camel.component.smpp.SmppConsumer$3.run(SmppConsumer.jav= a:223) > 2011-02-10 12:24:44,972 [Thread-14 ] DEBUG SmppConsumer - >>= >>>>>>>>>>>>>>>>> STATE CHANGE -- OPEN --> CLOSED[org.jsmpp.session.SMPPSes= sion@6a754384] > 2011-02-10 12:24:44,973 [Thread-14 ] WARN SmppConsumer - Lo= ost connection to: smpp://ubankmobile01@127.0.0.1:2775 - trying to reconnec= t... > 2011-02-10 12:24:44,973 [Thread-14 ] DEBUG SmppConsumer - >>= >>>>>>>> Closing SMPP session [org.jsmpp.session.SMPPSession@501b2cb9] > 2011-02-10 12:24:44,973 [Thread-14 ] DEBUG SmppConsumer - Se= ssion info: [org.jsmpp.session.SMPPSession@501b2cb9], session state: CLOSED > 2011-02-10 12:24:44,973 [Thread-24 ] INFO SmppConsumer - Sc= hedule reconnect after 5000 millis > 2011-02-10 12:24:44,973 [Thread-17 ] INFO SMPPSession - PD= UReaderWorker stop > 2011-02-10 12:24:44,973 [Thread-14 ] INFO SmppConsumer - Fa= iled to reconnect to smpp://ubankmobile01@127.0.0.1:2775, sessionState =3D= =3D CLOSED > 2011-02-10 12:24:44,974 [Thread-14 ] DEBUG SmppConsumer - >>= >>>>>>>> Closing SMPP session [org.jsmpp.session.SMPPSession@501b2cb9] > 2011-02-10 12:24:45,958 [Thread-20 ] INFO SmppConsumer - Tr= ying to reconnect to smpp://ubankmobile01@127.0.0.1:2775 - attempt #1... > 2011-02-10 12:24:45,958 [Thread-20 ] DEBUG SmppConsumer - Se= ssion info: [org.jsmpp.session.SMPPSession@501b2cb9], session state: CLOSED > 2011-02-10 12:24:45,959 [Thread-20 ] DEBUG SMPPSession - Co= nnect and bind to 127.0.0.1 port 2775 > 2011-02-10 12:24:45,959 [Thread-10 ] INFO SmppConsumer - Tr= ying to reconnect to smpp://ubankmobile01@127.0.0.1:2775 - attempt #3... > 2011-02-10 12:24:45,959 [Thread-10 ] DEBUG SmppConsumer - Se= ssion info: [org.jsmpp.session.SMPPSession@501b2cb9], session state: CLOSED > 2011-02-10 12:24:45,959 [Thread-20 ] INFO SMPPSession - Co= nnected > 2011-02-10 12:24:45,959 [Thread-10 ] DEBUG SMPPSession - Co= nnect and bind to 127.0.0.1 port 2775 > 2011-02-10 12:24:45,959 [Thread-20 ] DEBUG SmppConsumer - >>= >>>>>>>>>>>>>>>>> STATE CHANGE -- CLOSED --> OPEN[org.jsmpp.session.SMPPSes= sion@684be8b8] > 2011-02-10 12:24:45,960 [Thread-10 ] INFO SMPPSession - Co= nnected > 2011-02-10 12:24:45,960 [Thread-25 ] INFO SMPPSession - St= arting PDUReaderWorker with processor degree:3 ... > 2011-02-10 12:24:45,960 [Thread-10 ] DEBUG SmppConsumer - >>= >>>>>>>>>>>>>>>>> STATE CHANGE -- CLOSED --> OPEN[org.jsmpp.session.SMPPSes= sion@79b7d13e] > 2011-02-10 12:24:45,960 [Thread-26 ] INFO SMPPSession - St= arting PDUReaderWorker with processor degree:3 ... > 2011-02-10 12:24:47,018 [pool-9-thread-1] DEBUG stractSessionContext - Ac= tivity notified > 2011-02-10 12:24:47,018 [pool-9-thread-1] DEBUG SMPPSessionOpen - Bi= nd Response header (30, 80000001, 0000000d, 1) > 2011-02-10 12:24:47,018 [Thread-19 ] DEBUG AbstractSession - bi= nd response received > 2011-02-10 12:24:47,018 [Thread-19 ] ERROR SMPPSession - Re= ceive negative bind response > org.jsmpp.extra.NegativeResponseException: Negative response 0000000d fou= nd > =09at org.jsmpp.session.AbstractSession.validateResponse(AbstractSession.= java:215) > =09at org.jsmpp.session.AbstractSession.executeSendCommand(AbstractSessio= n.java:271) > =09at org.jsmpp.session.SMPPSession.sendBind(SMPPSession.java:294) > =09at org.jsmpp.session.SMPPSession.connectAndBind(SMPPSession.java:232) > =09at org.jsmpp.session.SMPPSession.connectAndBind(SMPPSession.java:200) > =09at org.apache.camel.component.smpp.SmppConsumer.createSession(SmppCons= umer.java:161) > =09at org.apache.camel.component.smpp.SmppConsumer.access$500(SmppConsume= r.java:54) > =09at org.apache.camel.component.smpp.SmppConsumer$3.run(SmppConsumer.jav= a:223) > 2011-02-10 12:24:47,018 [Thread-19 ] DEBUG SmppConsumer - >>= >>>>>>>>>>>>>>>>> STATE CHANGE -- OPEN --> CLOSED[org.jsmpp.session.SMPPSes= sion@18dd7404] > 2011-02-10 12:24:47,019 [Thread-19 ] WARN SmppConsumer - Lo= ost connection to: smpp://ubankmobile01@127.0.0.1:2775 - trying to reconnec= t... > 2011-02-10 12:24:47,019 [Thread-19 ] DEBUG SmppConsumer - >>= >>>>>>>> Closing SMPP session [org.jsmpp.session.SMPPSession@501b2cb9] > 2011-02-10 12:24:47,019 [Thread-19 ] DEBUG SmppConsumer - Se= ssion info: [org.jsmpp.session.SMPPSession@501b2cb9], session state: CLOSED > 2011-02-10 12:24:47,019 [Thread-19 ] INFO SmppConsumer - Fa= iled to reconnect to smpp://ubankmobile01@127.0.0.1:2775, sessionState =3D= =3D CLOSED > 2011-02-10 12:24:47,019 [Thread-22 ] INFO SMPPSession - PD= UReaderWorker stop > 2011-02-10 12:24:47,019 [Thread-19 ] DEBUG SmppConsumer - >>= >>>>>>>> Closing SMPP session [org.jsmpp.session.SMPPSession@501b2cb9] > 2011-02-10 12:24:47,020 [Thread-27 ] INFO SmppConsumer - Sc= hedule reconnect after 5000 millis > {code} > Even more re-connect threads being spawned... > {code} > 2011-02-10 12:24:47,823 [Thread-21 ] INFO SmppConsumer - Tr= ying to reconnect to smpp://ubankmobile01@127.0.0.1:2775 - attempt #1... > 2011-02-10 12:24:47,823 [Thread-21 ] DEBUG SmppConsumer - Se= ssion info: [org.jsmpp.session.SMPPSession@501b2cb9], session state: CLOSED > 2011-02-10 12:24:47,823 [Thread-21 ] DEBUG SMPPSession - Co= nnect and bind to 127.0.0.1 port 2775 > 2011-02-10 12:24:47,823 [Thread-8 ] INFO SmppConsumer - Tr= ying to reconnect to smpp://ubankmobile01@127.0.0.1:2775 - attempt #4... > 2011-02-10 12:24:47,823 [Thread-8 ] DEBUG SmppConsumer - Se= ssion info: [org.jsmpp.session.SMPPSession@501b2cb9], session state: CLOSED > 2011-02-10 12:24:47,823 [Thread-21 ] INFO SMPPSession - Co= nnected > 2011-02-10 12:24:47,823 [Thread-8 ] DEBUG SMPPSession - Co= nnect and bind to 127.0.0.1 port 2775 > 2011-02-10 12:24:47,823 [Thread-21 ] DEBUG SmppConsumer - >>= >>>>>>>>>>>>>>>>> STATE CHANGE -- CLOSED --> OPEN[org.jsmpp.session.SMPPSes= sion@490eb6ae] > 2011-02-10 12:24:47,824 [Thread-8 ] INFO SMPPSession - Co= nnected > 2011-02-10 12:24:47,824 [Thread-28 ] INFO SMPPSession - St= arting PDUReaderWorker with processor degree:3 ... > 2011-02-10 12:24:47,824 [Thread-8 ] DEBUG SmppConsumer - >>= >>>>>>>>>>>>>>>>> STATE CHANGE -- CLOSED --> OPEN[org.jsmpp.session.SMPPSes= sion@3b4d82e1] > 2011-02-10 12:24:47,825 [Thread-29 ] INFO SMPPSession - St= arting PDUReaderWorker with processor degree:3 ... > 2011-02-10 12:24:48,838 [Thread-23 ] DEBUG SMPPSession - No= activity notified > 2011-02-10 12:24:48,962 [ool-10-thread-1] DEBUG stractSessionContext - Ac= tivity notified > 2011-02-10 12:24:48,962 [ool-10-thread-1] DEBUG SMPPSessionOpen - Bi= nd Response header (30, 80000001, 0000000d, 1) > 2011-02-10 12:24:48,962 [Thread-13 ] DEBUG AbstractSession - bi= nd response received > 2011-02-10 12:24:48,962 [Thread-13 ] ERROR SMPPSession - Re= ceive negative bind response > org.jsmpp.extra.NegativeResponseException: Negative response 0000000d fou= nd > =09at org.jsmpp.session.AbstractSession.validateResponse(AbstractSession.= java:215) > =09at org.jsmpp.session.AbstractSession.executeSendCommand(AbstractSessio= n.java:271) > =09at org.jsmpp.session.SMPPSession.sendBind(SMPPSession.java:294) > =09at org.jsmpp.session.SMPPSession.connectAndBind(SMPPSession.java:232) > =09at org.jsmpp.session.SMPPSession.connectAndBind(SMPPSession.java:200) > =09at org.apache.camel.component.smpp.SmppConsumer.createSession(SmppCons= umer.java:161) > =09at org.apache.camel.component.smpp.SmppConsumer.access$500(SmppConsume= r.java:54) > =09at org.apache.camel.component.smpp.SmppConsumer$3.run(SmppConsumer.jav= a:223) > 2011-02-10 12:24:48,963 [Thread-13 ] DEBUG SmppConsumer - >>= >>>>>>>>>>>>>>>>> STATE CHANGE -- OPEN --> CLOSED[org.jsmpp.session.SMPPSes= sion@53606bf5] > 2011-02-10 12:24:48,963 [Thread-13 ] WARN SmppConsumer - Lo= ost connection to: smpp://ubankmobile01@127.0.0.1:2775 - trying to reconnec= t... > 2011-02-10 12:24:48,963 [Thread-13 ] DEBUG SmppConsumer - >>= >>>>>>>> Closing SMPP session [org.jsmpp.session.SMPPSession@501b2cb9] > 2011-02-10 12:24:48,963 [Thread-13 ] DEBUG SmppConsumer - Se= ssion info: [org.jsmpp.session.SMPPSession@501b2cb9], session state: CLOSED > 2011-02-10 12:24:48,963 [Thread-13 ] INFO SmppConsumer - Fa= iled to reconnect to smpp://ubankmobile01@127.0.0.1:2775, sessionState =3D= =3D CLOSED > 2011-02-10 12:24:48,963 [Thread-23 ] INFO SMPPSession - PD= UReaderWorker stop > 2011-02-10 12:24:48,963 [Thread-13 ] DEBUG SmppConsumer - >>= >>>>>>>> Closing SMPP session [org.jsmpp.session.SMPPSession@501b2cb9] > 2011-02-10 12:24:48,964 [Thread-30 ] INFO SmppConsumer - Sc= hedule reconnect after 5000 millis > ^C2011-02-10 12:24:49,726 [Thread-5 ] INFO rt$HangupInterceptor - = Received hang up - stopping the main instance. > 2011-02-10 12:24:49,726 [Thread-5 ] INFO MainSupport - Ap= ache Camel 2.6.0 stopping > 2011-02-10 12:24:49,727 [Thread-5 ] DEBUG MainSupport - St= opping Spring ApplicationContext: org.springframework.context.support.Class= PathXmlApplicationContext@4c9549af > 2011-02-10 12:24:49,733 [Thread-5 ] DEBUG SpringCamelContext - on= ApplicationEvent: org.springframework.context.event.ContextClosedEvent[sour= ce=3Dorg.springframework.context.support.ClassPathXmlApplicationContext@4c9= 549af: startup date [Thu Feb 10 12:23:05 SAST 2011]; root of context hierar= chy] > 2011-02-10 12:24:49,735 [Thread-5 ] DEBUG ledConnectionFactory - St= op the PooledConnectionFactory, number of connections in cache: 0 > 2011-02-10 12:24:49,736 [Thread-5 ] INFO SpringCamelContext - Ap= ache Camel 2.6.0 (CamelContext:camelTestRouteBuilderContext) is shutting do= wn > 2011-02-10 12:24:49,737 [Thread-5 ] INFO aultShutdownStrategy - St= arting to graceful shutdown 1 routes (timeout 300 seconds) > 2011-02-10 12:24:49,738 [Thread-5 ] DEBUG cutorServiceStrategy - Cr= eated new single thread pool for source: org.apache.camel.impl.DefaultShutd= ownStrategy@6e267b76 with name: ShutdownTask. -> java.util.concurrent.Execu= tors$FinalizableDelegatedExecutorService@6ea53502 > 2011-02-10 12:24:49,742 [ - ShutdownTask] DEBUG aultShutdownStrategy - Th= ere are 1 routes to shutdown > 2011-02-10 12:24:49,742 [ - ShutdownTask] DEBUG SmppConsumer - Di= sconnecting from: smpp://ubankmobile01@127.0.0.1:2775... > 2011-02-10 12:24:49,742 [ - ShutdownTask] DEBUG SmppConsumer - Cl= osing session: org.jsmpp.session.SMPPSession@501b2cb9 > 2011-02-10 12:24:49,742 [ - ShutdownTask] DEBUG SmppConsumer - >>= >>>>>>>> Closing SMPP session [org.jsmpp.session.SMPPSession@501b2cb9] > 2011-02-10 12:24:49,742 [ - ShutdownTask] DEBUG SmppConsumer - ca= lling super.doStop() > 2011-02-10 12:24:49,742 [ - ShutdownTask] DEBUG SmppConsumer - St= opping consumer: SmppConsumer[smpp://ubankmobile01@127.0.0.1:2775] > 2011-02-10 12:24:49,742 [ - ShutdownTask] DEBUG ProcessorEndpoint$1 - St= opping producer: Producer[log://SmppIn] > 2011-02-10 12:24:49,743 [ - ShutdownTask] INFO SmppConsumer - Di= sconnected from: smpp://ubankmobile01@127.0.0.1:2775 > 2011-02-10 12:24:49,743 [ - ShutdownTask] DEBUG aultShutdownStrategy - Sh= utdown complete for: SmppConsumer[smpp://ubankmobile01@127.0.0.1:2775] > 2011-02-10 12:24:49,743 [ - ShutdownTask] INFO aultShutdownStrategy - Ro= ute: SmppConsumerRoute shutdown complete, was consuming from: Endpoint[smpp= ://ubankmobile01@127.0.0.1:2775?amp%3BenquireLinkTimer=3D55000&%3Bsystem= Type=3Dubankmobile01&%3BtransactionTimer=3D15000&password=3D******] > 2011-02-10 12:24:49,744 [Thread-5 ] INFO aultShutdownStrategy - Gr= aceful shutdown of 1 routes completed in 0 seconds > 2011-02-10 12:24:49,745 [Thread-5 ] DEBUG faultManagementAgent - Un= registered MBean with objectname: org.apache.camel:context=3Djacovt-noteboo= k/camelTestRouteBuilderContext,type=3Droutes,name=3D"SmppConsumerRoute" > 2011-02-10 12:24:49,745 [Thread-5 ] DEBUG faultManagementAgent - Un= registered MBean with objectname: org.apache.camel:context=3Djacovt-noteboo= k/camelTestRouteBuilderContext,type=3Dconsumers,name=3DSmppConsumer(0x59c27= 402) > 2011-02-10 12:24:49,745 [Thread-5 ] DEBUG faultManagementAgent - Un= registered MBean with objectname: org.apache.camel:context=3Djacovt-noteboo= k/camelTestRouteBuilderContext,type=3Dprocessors,name=3D"to1" > 2011-02-10 12:24:49,747 [Thread-5 ] DEBUG faultManagementAgent - Un= registered MBean with objectname: org.apache.camel:context=3Djacovt-noteboo= k/camelTestRouteBuilderContext,type=3Dcontext,name=3D"camelTestRouteBuilder= Context" > 2011-02-10 12:24:49,747 [Thread-5 ] DEBUG cutorServiceStrategy - Sh= utdownNow ExecutorService: java.util.concurrent.Executors$FinalizableDelega= tedExecutorService@6ea53502 > 2011-02-10 12:24:49,747 [Thread-5 ] INFO ltInflightRepository - Sh= utting down with no inflight exchanges. > 2011-02-10 12:24:49,747 [Thread-5 ] DEBUG dProducerServicePool - St= opping service pool: org.apache.camel.impl.SharedProducerServicePool@15dbac= 11 > 2011-02-10 12:24:49,748 [Thread-5 ] DEBUG cutorServiceStrategy - Sh= utdownNow ExecutorService: java.util.concurrent.ScheduledThreadPoolExecutor= @55fe910c > 2011-02-10 12:24:49,748 [Thread-5 ] DEBUG faultManagementAgent - Un= registered MBean with objectname: org.apache.camel:context=3Djacovt-noteboo= k/camelTestRouteBuilderContext,type=3Dthreadpools,name=3DDefaultErrorHandle= r(0xa62b39f) > 2011-02-10 12:24:49,749 [Thread-5 ] DEBUG faultManagementAgent - Un= registered MBean with objectname: org.apache.camel:context=3Djacovt-noteboo= k/camelTestRouteBuilderContext,type=3Dcomponents,name=3D"smpp" > 2011-02-10 12:24:49,749 [Thread-5 ] DEBUG faultManagementAgent - Un= registered MBean with objectname: org.apache.camel:context=3Djacovt-noteboo= k/camelTestRouteBuilderContext,type=3Dendpoints,name=3D"spring-event://defa= ult" > 2011-02-10 12:24:49,749 [Thread-5 ] DEBUG faultManagementAgent - Un= registered MBean with objectname: org.apache.camel:context=3Djacovt-noteboo= k/camelTestRouteBuilderContext,type=3Dservices,name=3DDefaultExecutorServic= eStrategy(0x4d12ee4f) > 2011-02-10 12:24:49,749 [Thread-5 ] DEBUG faultManagementAgent - Un= registered MBean with objectname: org.apache.camel:context=3Djacovt-noteboo= k/camelTestRouteBuilderContext,type=3Dendpoints,name=3D"log://SmppIn" > 2011-02-10 12:24:49,749 [Thread-5 ] DEBUG faultManagementAgent - Un= registered MBean with objectname: org.apache.camel:context=3Djacovt-noteboo= k/camelTestRouteBuilderContext,type=3Dservices,name=3DProducerCache(0x3a67a= d79) > 2011-02-10 12:24:49,749 [Thread-5 ] DEBUG faultManagementAgent - Un= registered MBean with objectname: org.apache.camel:context=3Djacovt-noteboo= k/camelTestRouteBuilderContext,type=3Dservices,name=3DDefaultTypeConverter(= 0x61e481c1) > 2011-02-10 12:24:49,749 [Thread-5 ] DEBUG faultManagementAgent - Un= registered MBean with objectname: org.apache.camel:context=3Djacovt-noteboo= k/camelTestRouteBuilderContext,type=3Dservices,name=3DSharedProducerService= Pool(0x15dbac11) > 2011-02-10 12:24:49,749 [Thread-5 ] DEBUG faultManagementAgent - Un= registered MBean with objectname: org.apache.camel:context=3Djacovt-noteboo= k/camelTestRouteBuilderContext,type=3Dtracer,name=3DTracer(0x3761f888) > 2011-02-10 12:24:49,749 [Thread-5 ] DEBUG faultManagementAgent - Un= registered MBean with objectname: org.apache.camel:context=3Djacovt-noteboo= k/camelTestRouteBuilderContext,type=3Dservices,name=3DDefaultShutdownStrate= gy(0x6e267b76) > 2011-02-10 12:24:49,749 [Thread-5 ] DEBUG faultManagementAgent - Un= registered MBean with objectname: org.apache.camel:context=3Djacovt-noteboo= k/camelTestRouteBuilderContext,type=3Dcomponents,name=3D"log" > 2011-02-10 12:24:49,750 [Thread-5 ] DEBUG faultManagementAgent - Un= registered MBean with objectname: org.apache.camel:context=3Djacovt-noteboo= k/camelTestRouteBuilderContext,type=3Dservices,name=3DDefaultInflightReposi= tory(0xf7b650a) > 2011-02-10 12:24:49,750 [Thread-5 ] DEBUG faultManagementAgent - Un= registered MBean with objectname: org.apache.camel:context=3Djacovt-noteboo= k/camelTestRouteBuilderContext,type=3Dendpoints,name=3D"smpp://ubankmobile0= 1@127.0.0.1:2775" > 2011-02-10 12:24:49,750 [Thread-5 ] DEBUG faultManagementAgent - Un= registered MBean with objectname: org.apache.camel:context=3Djacovt-noteboo= k/camelTestRouteBuilderContext,type=3Derrorhandlers,name=3D"DefaultErrorHan= dlerBuilder(ref:CamelDefaultErrorHandlerBuilder)" > 2011-02-10 12:24:49,750 [Thread-5 ] INFO SpringCamelContext - Up= time: 1 minute > 2011-02-10 12:24:49,750 [Thread-5 ] INFO SpringCamelContext - Ap= ache Camel 2.6.0 (CamelContext: camelTestRouteBuilderContext) is shutdown i= n 0.014 seconds > {code} > The above thread-spawning quickly gets out of hand with thousands being c= reated. > Note however that this problem does not seem to happen when the smsc goes= down (session is closed and a connection refused pops up), but only when a= n unbind happens and then a negative bind. --=20 This message is automatically generated by JIRA. - For more information on JIRA, see: http://www.atlassian.com/software/jira