The log statement at WARN level that occur while booting ServiceMix are not
the cause of the problem.
One log line shows
2007-12-17 10:01:22,399 [main ] INFO JBIContainer - ServiceMix
JBI Container (ServiceMix) started
which clearly indicates that the container was started correctly.
I will investigate the issue but I suspect a regression in the maven jbi
plugin caused by the startup / shutdown method changed.
On Dec 17, 2007 4:16 PM, Derek Lu <[EMAIL PROTECTED]> wrote:
>
> This problem happens when starting the example. It is due to "Cound not
> start
> ClientFactory: javax.naming.NoInitialContextException", the JBI is shuting
> down.
>
> Please see the entire log as follows
>
> C:\java\apache-servicemix-3.2.1\examples\basic>mvn jbi:embeddedServicemix
> [INFO] Scanning for projects...
> [INFO] Searching repository for plugin with prefix: 'jbi'.
> [INFO] org.apache.maven.plugins : checking for updates from
> apache.incubating
> [INFO] org.apache.maven.plugins: checking for updates from apache-releases
> [INFO] org.apache.maven.plugins: checking for updates from
> servicemix-m2-repo
> [INFO] org.apache.maven.plugins : checking for updates from
> apache-incubating
> [INFO] org.codehaus.mojo: checking for updates from apache.incubating
> [INFO] org.codehaus.mojo: checking for updates from apache-releases
> [INFO] org.codehaus.mojo: checking for updates from servicemix-m2-repo
> [INFO] org.codehaus.mojo: checking for updates from apache-incubating
> [INFO] artifact org.apache.maven.plugins:maven-checkstyle-plugin: checking
> for u
> pdates from apache.incubating
> [INFO] artifact org.apache.maven.plugins:maven-checkstyle-plugin: checking
> for u
> pdates from apache-releases
> [INFO] artifact org.apache.maven.plugins:maven-checkstyle-plugin: checking
> for u
> pdates from servicemix-m2-repo
> [INFO] artifact org.apache.maven.plugins:maven-checkstyle-plugin: checking
> for u
> pdates from apache-incubating
> [INFO]
> -------------------------------------------------------------------------
> ---
> [INFO] Building ServiceMix :: Samples :: Basic
> [INFO] task-segment: [jbi:embeddedServicemix]
> [INFO]
> -------------------------------------------------------------------------
> ---
> [INFO] Preparing jbi:embeddedServicemix
> [INFO] artifact org.apache.maven.plugins:maven-resources-plugin: checking
> for up
> dates from apache.incubating
> [INFO] artifact org.apache.maven.plugins:maven-resources-plugin: checking
> for up
> dates from apache-releases
> [INFO] artifact org.apache.maven.plugins:maven-resources-plugin: checking
> for up
> dates from servicemix-m2-repo
> [INFO] artifact org.apache.maven.plugins:maven-resources-plugin: checking
> for up
> dates from apache-incubating
> [INFO] artifact org.apache.maven.plugins:maven-compiler-plugin: checking
> for
> upd
> ates from apache.incubating
> [INFO] artifact org.apache.maven.plugins:maven-compiler-plugin: checking
> for
> upd
> ates from apache-releases
> [INFO] artifact org.apache.maven.plugins:maven-compiler-plugin: checking
> for
> upd
> ates from servicemix-m2-repo
> [INFO] artifact org.apache.maven.plugins:maven-compiler-plugin: checking
> for
> upd
> ates from apache-incubating
> [INFO] [build:copy {execution: default}]
> [INFO] [resources:resources]
> [INFO] Using default encoding to copy filtered resources.
> Downloading:
> http://people.apache.org/repo/m2-incubating-repository/wss4j/wss4j/
> 1.5.1/wss4j-1.5.1.pom
> Downloading:
> http://svn.apache.org/repos/asf/servicemix/m2-repo/wss4j/wss4j/1.5.
> 1/wss4j-1.5.1.pom<http://svn.apache.org/repos/asf/servicemix/m2-repo/wss4j/wss4j/1.5.1/wss4j-1.5.1.pom>
> Downloading:
> http://people.apache.org/repo/m2-incubating-repository/wss4j/wss4j/
> 1.5.1/wss4j- 1.5.1.pom
> Downloading:
> http://repository.codehaus.org/wss4j/wss4j/1.5.1/wss4j-1.5.1.pom
> Downloading:
> http://repo1.maven.org/maven2/wss4j/wss4j/1.5.1/wss4j-1.5.1.pom
> Downloading:
> http://people.apache.org/repo/m2-incubating-repository/xml-security
> /xmlsec/1.3.0/xmlsec-1.3.0.pom<http://people.apache.org/repo/m2-incubating-repository/xml-security/xmlsec/1.3.0/xmlsec-1.3.0.pom>
> Downloading:
> http://svn.apache.org/repos/asf/servicemix/m2-repo/xml-security/xml
> sec/1.3.0/xmlsec-1.3.0.pom<http://svn.apache.org/repos/asf/servicemix/m2-repo/xml-security/xmlsec/1.3.0/xmlsec-1.3.0.pom>
> Downloading:
> http://people.apache.org/repo/m2-incubating-repository/xml-security
> /xmlsec/1.3.0/xmlsec-1.3.0.pom<http://people.apache.org/repo/m2-incubating-repository/xml-security/xmlsec/1.3.0/xmlsec-1.3.0.pom>
> Downloading:
> http://repository.codehaus.org/xml-security/xmlsec/1.3.0/xmlsec-1.3
> .0.pom
> Downloading:
> http://repo1.maven.org/maven2/xml-security/xmlsec/1.3.0/xmlsec-1.3.
> 0.pom
> [INFO] [compiler:compile]
> [INFO] No sources to compile
> [INFO] [jbi:embeddedServicemix]
> 2007-12-17 10:01:17,321 [main ] INFO
> ileSystemXmlApplicationContext -
> Refreshing
> [EMAIL PROTECTED]
> 8d: display name
> [org.apache.xbean.spring.context.FileSystemXmlApplicationContex
> [EMAIL PROTECTED]; startup date [Mon Dec 17 10:01:17 EST 2007]; root of
> context
> hierarch
> y
> 2007-12-17 10:01:17,586 [main ] INFO
> XBeanXmlBeanDefinitionReader
> -
> Loading XML bean definitions from URL
> [file:/C:/java/apache-servicemix-3.2.1/ex
> amples/basic/src/main/resources/servicemix.xml]
> 2007-12-17 10:01:19,336 [main ] INFO
> ileSystemXmlApplicationContext -
> Bean factory for application context
> [org.apache.xbean.spring.context.FileSyste
> [EMAIL PROTECTED]:
> org.springframework.beans.factory.support.Defaul
> [EMAIL PROTECTED]
> 2007-12-17 10:01:19,352 [main ] INFO DefaultListableBeanFactory
>
> -
> Pre-instantiating singletons in
> org.springframework.beans.factory.support.Defau
> [EMAIL PROTECTED]: defining beans
> [jbi,transactionManager,workManager
> ,bootstrapContext,activeMQResourceAdapter,broker,jmsFactory]; root of
> factory hi
> erarchy
> 2007-12-17 10:01:19,446 [main ] WARN EndpointRegistry
> -
> Disabled endpoint processor
> 'org.apache.servicemix.jbi.framework.support.WSDL2P
> rocessor': java.lang.NoClassDefFoundError : org/apache/woden/WSDLReader
> 2007-12-17 10:01:20,102 [main ] INFO BrokerService
> -
> ActiveMQ 4.1.1 JMS Message Broker (localhost) is starting
> 2007-12-17 10:01:20,102 [main ] INFO BrokerService
> -
> For help or more information please see:
> http://incubator.apache.org/activemq/
> 2007-12-17 10:01:20,805 [JMX connector ] INFO ManagementContext
> -
> JMX consoles can connect to
> service:jmx:rmi:///jndi/rmi://localhost:1099/jmxrmi
>
> 2007-12-17 10:01:20,993 [main ] INFO
> TransportServerThreadSupport
> -
> Listening for connections at: tcp://DLU-E6100:61616
> 2007-12-17 10:01:21,008 [main ] INFO TransportConnector
> -
> Connector tcp://localhost:61616 Started
> 2007-12-17 10:01:21,024 [main ] INFO BrokerService
> -
> ActiveMQ JMS Message Broker (localhost,
> ID:DLU-E6100-2423-1197903680555-0:0) st
> arted
> 2007-12-17 10:01:21,086 [main ] INFO JBIContainer
> -
> ServiceMix 3.2.1 JBI Container (ServiceMix) is starting
> 2007-12-17 10:01:21,086 [main ] INFO JBIContainer
> -
> For help or more informations please see:
> http://incubator.apache.org/servicemi
> x/
> 2007-12-17 10:01:21,118 [main ] WARN ManagementContext
> -
> Failed to start rmi registry: internal error: ObjID already in use
> 2007-12-17 10:01:21,149 [main ] WARN ManagementContext
> -
> Failed to start jmx connector: Cannot bind to URL
> [rmi://localhost:1099/jmxrmi]
> : javax.naming.NameAlreadyBoundException: jmxrmi [Root exception is
> java.rmi.Alr
> eadyBoundException: jmxrmi]
> 2007-12-17 10:01:21,305 [main ] INFO ComponentMBeanImpl
> -
> Initializing component: #SubscriptionManager#
> 2007-12-17 10:01:21,321 [main ] INFO DeploymentService
> -
> Restoring service assemblies
> 2007-12-17 10:01:21,336 [main ] INFO ComponentMBeanImpl
> -
> Initializing component: inputSender
> 2007-12-17 10:01:21,352 [main ] INFO ComponentMBeanImpl
> -
> Initializing component: timer
> 2007-12-17 10:01:21,446 [main ] INFO SimpleThreadPool
> -
> Job execution threads will use class loader of thread: main
> 2007-12-17 10:01:21,493 [main ] INFO QuartzScheduler
> -
> Quartz Scheduler v.1.5.2 created.
> 2007-12-17 10:01:21,493 [main ] INFO RAMJobStore
> -
> RAMJobStore initialized.
> 2007-12-17 10:01:21,493 [main ] INFO StdSchedulerFactory
> -
> Quartz scheduler 'DefaultQuartzScheduler' initialized from default
> resource
> fil
> e in Quartz package: 'quartz.properties'
> 2007-12-17 10:01:21,493 [main ] INFO StdSchedulerFactory
> -
> Quartz scheduler version: 1.5.2
> 2007-12-17 10:01:21,508 [main ] INFO ComponentMBeanImpl
> -
> Initializing component: inputReceiver
> 2007-12-17 10:01:21,524 [main ] INFO ComponentMBeanImpl
> -
> Initializing component: outputSender
> 2007-12-17 10:01:21,524 [main ] INFO ComponentMBeanImpl
> -
> Initializing component: jmsTrace
> 2007-12-17 10:01:21,524 [main ] INFO ComponentMBeanImpl
> -
> Initializing component: trace
> 2007-12-17 10:01:21,524 [main ] WARN ClientFactory
> -
> 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
> 2007-12-17 10:01:22,290 [main ] INFO QuartzScheduler
> -
> Scheduler DefaultQuartzScheduler_$_NON_CLUSTERED started.
> 2007-12-17 10:01:22,399 [main ] INFO JBIContainer
> -
> ServiceMix JBI Container (ServiceMix) started
> [INFO]
> ------------------------------------------------------------------------
> [INFO] BUILD SUCCESSFUL
> [INFO]
> ------------------------------------------------------------------------
> [INFO] Total time: 30 seconds
> [INFO] Finished at: Mon Dec 17 10:01:22 EST 2007
> [INFO] Final Memory: 16M/29M
> [INFO]
> ------------------------------------------------------------------------
> 2007-12-17 10:01:22,586 [MQ ShutdownHook] INFO BrokerService
> -
> ActiveMQ Message Broker (localhost, ID:DLU-E6100-2423-1197903680555-0:0)
> is
> shu
> tting down
> 2007-12-17 10:01:22,586 [ix ShutdownHook] INFO
> ileSystemXmlApplicationContext -
> Closing
> [EMAIL PROTECTED]:
> display name
> [EMAIL PROTECTED]
> 7828d]; startup date [Mon Dec 17 10:01:17 EST 2007]; root of context
> hierarchy
> 2007-12-17 10:01:22,586 [ix ShutdownHook] INFO DefaultListableBeanFactory
> -
> Destroying singletons in
> org.springframework.beans.factory.support.DefaultLista
> [EMAIL PROTECTED]: defining beans
> [jbi,transactionManager,workManager,bootst
> rapContext,activeMQResourceAdapter,broker,jmsFactory]; root of factory
> hierarchy
>
> 2007-12-17 10:01:22,586 [ix ShutdownHook] INFO JBIContainer
> -
> Shutting down ServiceMix JBI Container (ServiceMix) stopped
> 2007-12-17 10:01:22,618 [ix ShutdownHook] INFO JBIContainer
> -
> Deactivating component #SubscriptionManager#
> 2007-12-17 10:01:22,649 [ix ShutdownHook] INFO QuartzScheduler
> -
> Scheduler DefaultQuartzScheduler_$_NON_CLUSTERED paused.
> 2007-12-17 10:01:22,649 [ix ShutdownHook] INFO QuartzScheduler
> -
> Scheduler DefaultQuartzScheduler_$_NON_CLUSTERED shutting down.
> 2007-12-17 10:01:22,649 [ix ShutdownHook] INFO QuartzScheduler
> -
> Scheduler DefaultQuartzScheduler_$_NON_CLUSTERED paused.
> 2007-12-17 10:01:22,649 [ix ShutdownHook] INFO QuartzScheduler
> -
> Scheduler DefaultQuartzScheduler_$_NON_CLUSTERED shutdown complete.
> 2007-12-17 10:01:22,665 [ix ShutdownHook] INFO ConnectorServerFactoryBean
>
> -
> Stopping JMX connector server:
> [EMAIL PROTECTED]
> 283826
> 2007-12-17 10:01:22,680 [ix ShutdownHook] INFO JBIContainer
> -
> ServiceMix JBI Container (ServiceMix) stopped
> 2007-12-17 10:01:22,868 [Sender-thread-1] ERROR JmsComponent
> -
> Error processing exchange InOnly[
> id: ID:10.1.6.118-116e89f9970-7:0
> status: Active
> role: provider
> service:
> {http://servicemix.apache.org/demo/}inputSender<http://servicemix.apache.org/demo/%7DinputSender>
> endpoint: endpoint
> in: <?xml version=" 1.0" encoding="UTF-8"?><timer><name>My Example
> Job</name><g
> roup>ServiceMix</group><fullname>ServiceMix.My Example
> Job</fullname><descriptio
> n/><fireTime>Mon Dec 17 10:01:22 EST 2007</fireTime></timer>
> ]
> java.lang.NullPointerException
> at
> org.apache.servicemix.jms.multiplexing.MultiplexingProviderProcessor .
> process(MultiplexingProviderProcessor.java:139)
> at
> org.apache.servicemix.common.AsyncBaseLifeCycle.doProcess(AsyncBaseLi
> feCycle.java:538)
> at
> org.apache.servicemix.common.AsyncBaseLifeCycle.processExchange (Async
> BaseLifeCycle.java:490)
> at
> org.apache.servicemix.common.BaseLifeCycle.onMessageExchange(BaseLife
> Cycle.java:46)
> at
> org.apache.servicemix.jbi.messaging.DeliveryChannelImpl.processInBoun
> d(DeliveryChannelImpl.java:610)
> at
> org.apache.servicemix.jbi.nmr.flow.AbstractFlow.doRouting(AbstractFlo
> w.java:170)
> at
> org.apache.servicemix.jbi.nmr.flow.seda.SedaFlow.doRouting(SedaFlow.j
> ava:167)
> at
> org.apache.servicemix.jbi.nmr.flow.seda.SedaQueue$1.run(SedaQueue.jav
> a:134)
> at
> java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExec
> utor.java:885)
> at
> java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor
> .java:907)
> at java.lang.Thread.run(Thread.java:619)
> 2007-12-17 10:01:22,868 [Sender-thread-1] ERROR JmsComponent
> -
> Error setting exchange status to ERROR
> javax.jbi.messaging.MessagingException: DeliveryChannel{inputSender} has
> been cl
> osed.
> at
> org.apache.servicemix.jbi.messaging.DeliveryChannelImpl.checkNotClose
> d(DeliveryChannelImpl.java:147)
> at
> org.apache.servicemix.jbi.messaging.DeliveryChannelImpl.send(Delivery
> ChannelImpl.java:422)
> at
> org.apache.servicemix.common.BaseLifeCycle.onMessageExchange (BaseLife
> Cycle.java:58)
> at
> org.apache.servicemix.jbi.messaging.DeliveryChannelImpl.processInBoun
> d(DeliveryChannelImpl.java:610)
> at
> org.apache.servicemix.jbi.nmr.flow.AbstractFlow.doRouting (AbstractFlo
> w.java:170)
> at
> org.apache.servicemix.jbi.nmr.flow.seda.SedaFlow.doRouting(SedaFlow.j
> ava:167)
> at
> org.apache.servicemix.jbi.nmr.flow.seda.SedaQueue$1.run(SedaQueue.jav
> a:134)
> at
> java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExec
> utor.java:885)
> at
> java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor
> .java:907)
> at java.lang.Thread.run(Thread.java:619)
> 2007-12-17 10:01:26,024 [127.0.0.1:61616] WARN ActiveMQConnection
> -
> Async exception with no exception listener: java.io.EOFException
> java.io.EOFException
> at java.io.DataInputStream.readInt(DataInputStream.java:375)
> at
> org.apache.activemq.openwire.OpenWireFormat.unmarshal(OpenWireFormat.
> java:267)
> at
> org.apache.activemq.transport.tcp.TcpTransport.readCommand (TcpTranspo
> rt.java:156)
> at
> org.apache.activemq.transport.tcp.TcpTransport.run(TcpTransport.java:
> 136)
> at java.lang.Thread.run(Thread.java:619)
> 2007-12-17 10:01:26,024 [MQ ShutdownHook] INFO TransportConnector
> -
> Connector tcp://localhost:61616 Stopped
> 2007-12-17 10:01:26,055 [MQ ShutdownHook] INFO BrokerService
> -
> ActiveMQ JMS Message Broker (localhost,
> ID:DLU-E6100-2423-1197903680555-0:0) st
> opped
>
> C:\java\apache-servicemix-3.2.1\examples\basic>
>
>
>
>
>
>
> gnodet wrote:
> >
> > Thanks for reporting this error. It occurs when shutting down the JBI
> > container, so I would not say that the example is not working.
> > I'm quite sure it is timing dependant so it may not happen each time.
> > I'll
> > have a look at it and thanks for raising a JIRA.
> >
> > On Dec 16, 2007 8:13 PM, Derek Lu < [EMAIL PROTECTED]> wrote:
> >
> >>
> >> I have been using Mule for a long time. Now for a new project, I am
> >> evaluating ServiceMix. However the basic example from the latest
> release
> >> 3.2.1 does not run, as there is a NullPointException. I am kind
> >> disappointed
> >> about it. Then I tried the same example in previous release 3.2 and it
> >> worked fine. Also a friend of mine was evaluating ServiceMix for their
> >> company also, but he said how come the first basic example could not
> run.
> >> It
> >> seems that there are no good documents about the examples for
> ServiceMix.
> >> In order to attribute more user or customer, I think a little more work
>
> >> need
> >> to be done for the examples and documents. Following is the null point
> >> exception for the basic example in 3.2.1
> >>
> >> 2007-12-16 14:00:44,175 [ix ShutdownHook] INFO
> >> ConnectorServerFactoryBean
> >> -
> >> Stopping JMX connector server:
> >> [EMAIL PROTECTED]
> >> 685be9
> >> 2007-12-16 14:00:44,178 [ix ShutdownHook] INFO JBIContainer
> >> -
> >> ServiceMix JBI Container (ServiceMix) stopped
> >> 2007-12-16 14:00:44,364 [Sender-thread-1] ERROR JmsComponent
> >> -
> >> Error processing exchange InOnly[
> >> id: ID: 192.168.1.103-116e454999a-7:0
> >> status: Active
> >> role: provider
> >> service:
> >> {http://servicemix.apache.org/demo/}inputSender
> <http://servicemix.apache.org/demo/%7DinputSender><
> http://servicemix.apache.org/demo/%7DinputSender>
> >> endpoint: endpoint
> >> in: <?xml version=" 1.0" encoding="UTF-8"?><timer><name>My Example
> >> Job</name><g
> >> roup>ServiceMix</group><fullname>ServiceMix.My Example
> >> Job</fullname><descriptio
> >> n/><fireTime>Sun Dec 16 14:00:43 EST 2007</fireTime></timer>
> >> ]
> >> java.lang.NullPointerException
> >> at
> >> org.apache.servicemix.jms.multiplexing.MultiplexingProviderProcessor .
> >> process(MultiplexingProviderProcessor.java:139)
> >> at
> >> org.apache.servicemix.common.AsyncBaseLifeCycle.doProcess(AsyncBaseLi
> >> feCycle.java:538)
> >> at
> >> org.apache.servicemix.common.AsyncBaseLifeCycle.processExchange(Async
> >> BaseLifeCycle.java:490)
> >> at
> >> org.apache.servicemix.common.BaseLifeCycle.onMessageExchange(BaseLife
> >> Cycle.java:46)
> >> at
> >> org.apache.servicemix.jbi.messaging.DeliveryChannelImpl.processInBoun
> >> d(DeliveryChannelImpl.java:610)
> >> at
> >> org.apache.servicemix.jbi.nmr.flow.AbstractFlow.doRouting (AbstractFlo
> >> w.java:170)
> >> at
> >> org.apache.servicemix.jbi.nmr.flow.seda.SedaFlow.doRouting(SedaFlow.j
> >> ava:167)
> >> at
> >> org.apache.servicemix.jbi.nmr.flow.seda.SedaQueue$1.run (SedaQueue.jav
> >> a:134)
> >> at
> >> java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExec
> >> utor.java:885)
> >> at
> >> java.util.concurrent.ThreadPoolExecutor$Worker.run (ThreadPoolExecutor
> >> .java:907)
> >> at java.lang.Thread.run(Thread.java:619)
> >> 2007-12-16 14:00:44,396 [Sender-thread-1] ERROR JmsComponent
> >> -
> >> Error setting exchange status to ERROR
> >>
> >> --
> >> View this message in context:
> >>
> http://www.nabble.com/NullpointException-of-basic-example-in-SM-3.2.1-%28what%27s-going-on----%29-tp14364907s12049p14364907.html
> >> Sent from the ServiceMix - User mailing list archive at Nabble.com.
> >>
> >>
> >
> >
> > --
> > Cheers,
> > Guillaume Nodet
> > ------------------------
> > Blog: http://gnodet.blogspot.com/
> >
> >
>
> --
> View this message in context:
> http://www.nabble.com/NullpointException-of-basic-example-in-SM-3.2.1-%28what%27s-going-on----%29-tp14364907s12049p14372436.html
> Sent from the ServiceMix - User mailing list archive at Nabble.com.
>
>
--
Cheers,
Guillaume Nodet
------------------------
Blog: http://gnodet.blogspot.com/
