[SMXCOMP-11] servicemix-wsn2005 WSNSpringComponentTest stalling

• Type: Bug
• Status: Open
• Priority: Major
• Resolution: Unresolved
• Affects Version/s: servicemix-wsn2005-2008.01
• Fix Version/s: 2013.02
• Component/s: servicemix-wsn2005
• Assignee: Gert Vanthienen
• Reporter: Gert Vanthienen
• Created: 03/Feb/09 14:49
• Updated: 10/Dec/12 10:12

Description

Just noticed this test stalling the entire build from time to time, with this info in the log:

[05:01:15]: Running org.apache.servicemix.wsn.component.WSNSpringComponentTest
[05:01:15]: Feb 3, 2009 5:01:15 AM org.springframework.context.support.AbstractApplicationContext prepareRefresh
[05:01:15]: INFO: Refreshing org.apache.xbean.spring.context.ClassPathXmlApplicationContext@2dc719bd: display name [org.apache.xbean.spring.context.ClassPathXmlApplicationContext@2dc719bd]; startup date [Tue Feb 03 05:01:15 EST 2009]; root of context hierarchy
[05:01:15]: Feb 3, 2009 5:01:15 AM org.springframework.beans.factory.xml.XmlBeanDefinitionReader loadBeanDefinitions
[05:01:15]: INFO: Loading XML bean definitions from class path resource [org/apache/servicemix/wsn/spring.xml]
[05:01:15]: Feb 3, 2009 5:01:15 AM org.springframework.context.support.AbstractApplicationContext obtainFreshBeanFactory
[05:01:15]: INFO: Bean factory for application context [org.apache.xbean.spring.context.ClassPathXmlApplicationContext@2dc719bd]: org.springframework.beans.factory.support.DefaultListableBeanFactory@75ce994b
[05:01:15]: Feb 3, 2009 5:01:15 AM org.springframework.beans.factory.support.DefaultListableBeanFactory preInstantiateSingletons
[05:01:15]: INFO: Pre-instantiating singletons in org.springframework.beans.factory.support.DefaultListableBeanFactory@75ce994b: defining beans [jbi,connectionFactory,broker]; root of factory hierarchy
[05:01:16]: Feb 3, 2009 5:01:16 AM org.apache.activemq.broker.BrokerService start
[05:01:16]: INFO: Using Persistence Adapter: MemoryPersistenceAdapter
[05:01:16]: Feb 3, 2009 5:01:16 AM org.apache.activemq.broker.BrokerService getBroker
[05:01:16]: INFO: ActiveMQ 5.2.0-fuse-SNAPSHOT JMS Message Broker (localhost) is starting
[05:01:16]: Feb 3, 2009 5:01:16 AM org.apache.activemq.broker.BrokerService getBroker
[05:01:16]: INFO: For help or more information please see: http://activemq.apache.org/
[05:01:16]: Feb 3, 2009 5:01:16 AM org.apache.activemq.transport.TransportServerThreadSupport doStart
[05:01:16]: INFO: Listening for connections at: tcp://earl:61216
[05:01:16]: Feb 3, 2009 5:01:16 AM org.apache.activemq.broker.TransportConnector start
[05:01:16]: INFO: Connector tcp://localhost:61216 Started
[05:01:16]: Feb 3, 2009 5:01:16 AM org.apache.activemq.broker.BrokerService start
[05:01:16]: INFO: ActiveMQ JMS Message Broker (localhost, ID:earl-34386-1233655215158-0:13) started
[05:01:16]: Feb 3, 2009 5:01:16 AM org.apache.activemq.broker.jmx.ManagementContext$1 run
[05:01:16]: INFO: JMX consoles can connect to service:jmx:rmi:///jndi/rmi://localhost:1099/jmxrmi
[05:01:16]: Feb 3, 2009 5:01:16 AM org.apache.servicemix.jbi.container.JBIContainer init
[05:01:16]: INFO: ServiceMix 3.3 JBI Container (ServiceMix) is starting
[05:01:16]: Feb 3, 2009 5:01:16 AM org.apache.servicemix.jbi.container.JBIContainer init
[05:01:16]: INFO: For help or more information please see: http://servicemix.apache.org/
[05:01:16]: Feb 3, 2009 5:01:16 AM org.apache.servicemix.jbi.framework.ComponentMBeanImpl init
[05:01:16]: INFO: Initializing component: #SubscriptionManager#
[05:01:16]: Feb 3, 2009 5:01:16 AM org.apache.servicemix.jbi.framework.DeploymentService buildState
[05:01:16]: INFO: Restoring service assemblies
[05:01:16]: Feb 3, 2009 5:01:16 AM org.apache.servicemix.jbi.framework.ComponentMBeanImpl init
[05:01:16]: INFO: Initializing component: receiver
[05:01:16]: Feb 3, 2009 5:01:16 AM org.apache.servicemix.jbi.framework.ComponentMBeanImpl init
[05:01:16]: INFO: Initializing component: ID:10.65.4.78-11f3b943311-86:0
[05:01:17]: Feb 3, 2009 5:01:17 AM org.apache.servicemix.jbi.framework.ComponentMBeanImpl init
[05:01:17]: INFO: Initializing component: ID:10.65.4.78-11f3b943311-86:1
[05:01:17]: Feb 3, 2009 5:01:17 AM org.apache.servicemix.jbi.framework.ComponentMBeanImpl init
[05:01:17]: INFO: Initializing component: ID:10.65.4.78-11f3b943311-86:2
[05:01:17]: Feb 3, 2009 5:01:17 AM org.quartz.simpl.SimpleThreadPool initialize
[05:01:17]: INFO: Job execution threads will use class loader of thread: main
[05:01:17]: Feb 3, 2009 5:01:17 AM org.quartz.core.QuartzScheduler <init>
[05:01:17]: INFO: Quartz Scheduler v.1.5.2 created.
[05:01:17]: Feb 3, 2009 5:01:17 AM org.quartz.simpl.RAMJobStore initialize
[05:01:17]: INFO: RAMJobStore initialized.
[05:01:17]: Feb 3, 2009 5:01:17 AM org.quartz.impl.StdSchedulerFactory instantiate
[05:01:17]: INFO: Quartz scheduler 'DefaultQuartzScheduler' initialized from default resource file in Quartz package: 'quartz.properties'
[05:01:17]: Feb 3, 2009 5:01:17 AM org.quartz.impl.StdSchedulerFactory instantiate
[05:01:17]: INFO: Quartz scheduler version: 1.5.2
[05:01:17]: Feb 3, 2009 5:01:17 AM org.apache.servicemix.jbi.framework.ClientFactory start
[05:01:17]: WARNING: Cound not start ClientFactory: javax.naming.NoInitialContextException: Need to specify class name in environment or system property, or as an applet parameter, or in an application resource file:  java.naming.factory.initial
[05:01:17]: Feb 3, 2009 5:01:17 AM org.quartz.core.QuartzScheduler start
[05:01:17]: INFO: Scheduler DefaultQuartzScheduler_$_NON_CLUSTERED started.
[05:01:17]: Feb 3, 2009 5:01:17 AM org.apache.servicemix.jbi.container.JBIContainer start
[05:01:17]: INFO: ServiceMix JBI Container (ServiceMix) started
[05:01:17]: Feb 3, 2009 5:01:17 AM org.apache.servicemix.jbi.messaging.DeliveryChannelImpl createExchangeFactory
[05:01:17]: INFO: Set default destination endpoint for ID:10.65.4.78-11f3b943311-86:2 to ServiceEndpoint[service={http://servicemix.org/test}publisher,endpoint=endpoint]
[05:01:18]: Feb 3, 2009 5:01:18 AM org.apache.servicemix.tck.MessageList waitForMessagesToArrive
[05:01:18]: INFO: Waiting for message to arrive
[05:01:18]: Feb 3, 2009 5:01:18 AM org.apache.servicemix.tck.MessageList waitForMessagesToArrive
[05:01:18]: INFO: End of wait for 2 millis
[05:01:18]: Feb 3, 2009 5:01:18 AM org.apache.servicemix.tck.SpringTestSupport tearDown
[05:01:18]: INFO: Closing down the spring context
[05:01:18]: Feb 3, 2009 5:01:18 AM org.springframework.context.support.AbstractApplicationContext doClose
[05:01:18]: INFO: Closing org.apache.xbean.spring.context.ClassPathXmlApplicationContext@2dc719bd: display name [org.apache.xbean.spring.context.ClassPathXmlApplicationContext@2dc719bd]; startup date [Tue Feb 03 05:01:15 EST 2009]; root of context hierarchy
[05:01:18]: Feb 3, 2009 5:01:18 AM org.springframework.beans.factory.support.DefaultSingletonBeanRegistry destroySingletons
[05:01:18]: INFO: Destroying singletons in org.springframework.beans.factory.support.DefaultListableBeanFactory@75ce994b: defining beans [jbi,connectionFactory,broker]; root of factory hierarchy
[05:01:18]: Feb 3, 2009 5:01:18 AM org.apache.servicemix.jbi.container.JBIContainer shutDown
[05:01:18]: INFO: Shutting down ServiceMix JBI Container (ServiceMix) stopped
[05:01:18]: Feb 3, 2009 5:01:18 AM org.apache.servicemix.jbi.container.JBIContainer shutdownRegistry
[05:01:18]: INFO: Waiting for complete shutdown of the components and service assemblies
[05:01:18]: Feb 3, 2009 5:01:18 AM org.quartz.core.QuartzScheduler standby
[05:01:18]: INFO: Scheduler DefaultQuartzScheduler_$_NON_CLUSTERED paused.
[05:01:18]: Feb 3, 2009 5:01:18 AM org.quartz.core.QuartzScheduler shutdown
[05:01:18]: INFO: Scheduler DefaultQuartzScheduler_$_NON_CLUSTERED shutting down.
[05:01:18]: Feb 3, 2009 5:01:18 AM org.quartz.core.QuartzScheduler standby
[05:01:18]: INFO: Scheduler DefaultQuartzScheduler_$_NON_CLUSTERED paused.
[05:01:18]: Feb 3, 2009 5:01:18 AM org.quartz.core.QuartzScheduler shutdown
[05:01:18]: INFO: Scheduler DefaultQuartzScheduler_$_NON_CLUSTERED shutdown complete.
[05:01:18]: Feb 3, 2009 5:01:18 AM org.apache.servicemix.wsn.spring.PublisherComponent$1 run
[05:01:18]: SEVERE: Could not create wsn client
[05:01:18]: javax.jbi.JBIException: javax.jbi.messaging.MessagingException: java.lang.InterruptedException
[05:01:18]: 	at org.apache.servicemix.wsn.client.AbstractWSAClient.request(AbstractWSAClient.java:174)
[05:01:18]: 	at org.apache.servicemix.wsn.client.AbstractWSAClient.request(AbstractWSAClient.java:133)
[05:01:18]: 	at org.apache.servicemix.wsn.client.NotificationBroker.registerPublisher(NotificationBroker.java:137)
[05:01:18]: 	at org.apache.servicemix.wsn.spring.PublisherComponent$1.run(PublisherComponent.java:123)
[05:01:18]: Caused by: javax.jbi.messaging.MessagingException: java.lang.InterruptedException
[05:01:18]: 	at org.apache.servicemix.jbi.messaging.DeliveryChannelImpl.sendSync(DeliveryChannelImpl.java:508)
[05:01:18]: 	at org.apache.servicemix.jbi.messaging.DeliveryChannelImpl.sendSync(DeliveryChannelImpl.java:442)
[05:01:18]: 	at org.apache.servicemix.wsn.client.AbstractWSAClient.request(AbstractWSAClient.java:151)
[05:01:18]: 	... 3 more
[05:01:18]: Caused by: java.lang.InterruptedException
[05:01:18]: 	at java.lang.Object.wait(Native Method)
[05:01:18]: 	at org.apache.servicemix.jbi.messaging.DeliveryChannelImpl.waitForExchange(DeliveryChannelImpl.java:709)
[05:01:18]: 	at org.apache.servicemix.jbi.messaging.DeliveryChannelImpl.sendSync(DeliveryChannelImpl.java:472)
[05:01:18]: 	... 5 more