geronimo-dev mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From "Kevan Miller (JIRA)" <j...@apache.org>
Subject [jira] Closed: (GERONIMO-3709) multiple wadi WARN messages during server start
Date Tue, 29 Jan 2008 03:39:36 GMT

     [ https://issues.apache.org/jira/browse/GERONIMO-3709?page=com.atlassian.jira.plugin.system.issuetabpanels:all-tabpanel
]

Kevan Miller closed GERONIMO-3709.
----------------------------------

    Resolution: Invalid

Problem has been fixed. Not sure when or by whom

> multiple wadi WARN messages during server start
> -----------------------------------------------
>
>                 Key: GERONIMO-3709
>                 URL: https://issues.apache.org/jira/browse/GERONIMO-3709
>             Project: Geronimo
>          Issue Type: Bug
>      Security Level: public(Regular issues) 
>          Components: Clustering
>    Affects Versions: 2.1
>            Reporter: Kevan Miller
>             Fix For: 2.1
>
>
> I'm seeing lots of WARNING messages from WADI during server start. The messages really
look like info messages to me. I've also seen warning messages and exception stack traces
during shutdown. We need to get things quieted down...
> Here's a sample:
> bash-3.2$ ./geronimo.sh run --long
> Using GERONIMO_BASE:   /Users/kevan/geronimo/server/trunk/target/geronimo-jetty6-javaee5-2.1-SNAPSHOT
> Using GERONIMO_HOME:   /Users/kevan/geronimo/server/trunk/target/geronimo-jetty6-javaee5-2.1-SNAPSHOT
> Using GERONIMO_TMPDIR: var/temp
> Using JRE_HOME:        /System/Library/Frameworks/JavaVM.framework/Versions/CurrentJDK/Home
> 09:27:57,977 WARN  [AbstractGBeanReference] GBean references are not using proxies
> Booting Geronimo Kernel (in Java 1.5.0_13)...
> Module  1/59 org.apache.geronimo.configs/ca-helper-jetty/2.1-SNAPSHOT/car           
    started in  1.715s
> Module  2/59 org.apache.geronimo.configs/jasper/2.1-SNAPSHOT/car                    
    started in   .000s
> Module  3/59 org.apache.geronimo.configs/j2ee-server/2.1-SNAPSHOT/car               
    started in   .000s
> Module  4/59 org.apache.geronimo.configs/rmi-naming/2.1-SNAPSHOT/car                
    started in   .000s
> Module  5/59 org.apache.geronimo.configs/j2ee-system/2.1-SNAPSHOT/car               
    started in   .000s
> Module  6/59 org.apache.geronimo.configs/jee-specs/2.1-SNAPSHOT/car                 
    started in   .000s
> Module  7/59 org.apache.geronimo.configs/server-security-config/2.1-SNAPSHOT/car    
    started in   .000s
> Module  8/59 org.apache.geronimo.configs/j2ee-security/2.1-SNAPSHOT/car             
    started in   .000s
> Module  9/59 org.apache.geronimo.configs/transaction/2.1-SNAPSHOT/car               
    started in   .000s
> Module 10/59 org.apache.geronimo.configs/myfaces-deployer/2.1-SNAPSHOT/car          
    started in   .627s
> Module 11/59 org.apache.geronimo.configs/myfaces/2.1-SNAPSHOT/car                   
    started in   .007s
> Module 12/59 org.apache.geronimo.configs/j2ee-deployer/2.1-SNAPSHOT/car             
    started in   .000s
> Module 13/59 org.apache.geronimo.configs/geronimo-gbean-deployer/2.1-SNAPSHOT/car   
    started in   .000s
> Module 14/59 org.apache.geronimo.configs/xmlbeans/2.1-SNAPSHOT/car                  
    started in   .000s
> Module 15/59 org.apache.geronimo.configs/activemq-ra/2.1-SNAPSHOT/car               
    started in  3.145s
> Module 16/59 org.apache.geronimo.configs/activemq-broker/2.1-SNAPSHOT/car           
    started in   .001s
> Module 17/59 org.apache.geronimo.configs/system-database/2.1-SNAPSHOT/car           
    started in   .000s
> Module 18/59 org.apache.geronimo.configs/connector-deployer/2.1-SNAPSHOT/car        
    started in   .090s
> Module 19/59 org.apache.geronimo.configs/jasper-deployer/2.1-SNAPSHOT/car           
    started in   .016s
> Module 20/59 org.apache.geronimo.configs/jetty6-deployer/2.1-SNAPSHOT/car           
    started in   .101s
> Module 21/59 org.apache.geronimo.configs/jetty6/2.1-SNAPSHOT/car                    
    started in   .000s
> Module 22/59 org.apache.geronimo.configs/clustering/2.1-SNAPSHOT/car                
    started in   .074s
> Module 23/59 org.apache.geronimo.configs/webservices-common/2.1-SNAPSHOT/car        
    started in   .000s
> Module 24/59 org.apache.geronimo.configs/jaxws-ejb-deployer/2.1-SNAPSHOT/car        
    started in   .090s
> Module 25/59 org.apache.geronimo.configs/jaxws-deployer/2.1-SNAPSHOT/car            
    started in   .000s
> Module 26/59 org.apache.geronimo.configs/openejb-deployer/2.1-SNAPSHOT/car          
    started in   .000s
> Module 27/59 org.apache.geronimo.configs/openejb/2.1-SNAPSHOT/car                   
    started in   .575s
> Module 28/59 org.apache.geronimo.configs/openjpa/2.1-SNAPSHOT/car                   
    started in   .000s
> Module 29/59 org.apache.geronimo.configs/axis2/2.1-SNAPSHOT/car                     
    started in   .000s
> Module 30/59 org.apache.geronimo.configs/axis2-ejb/2.1-SNAPSHOT/car                 
    started in   .000s
> Module 31/59 org.apache.geronimo.plugins/system-database-jetty/2.1-SNAPSHOT/car     
    started in  4.422s
> Module 32/59 org.apache.geronimo.plugins/console-jetty/2.1-SNAPSHOT/car             
    started in   .000s
> Module 33/59 org.apache.geronimo.plugins/pluto-support/2.1-SNAPSHOT/car             
    started in   .000s
> Module 34/59 org.apache.geronimo.configs/spring/2.1-SNAPSHOT/car                    
    started in   .000s
> Module 35/59 org.apache.geronimo.configs/transformer-agent/2.1-SNAPSHOT/car         
    started in   .001s
> Module 36/59 org.apache.geronimo.configs/remote-deploy-jetty/2.1-SNAPSHOT/car       
    started in   .082s
> Module 37/59 org.apache.geronimo.configs/cxf-ejb/2.1-SNAPSHOT/car                   
    started in   .000s
> Module 38/59 org.apache.geronimo.configs/cxf/2.1-SNAPSHOT/car                       
    started in   .000s
> Module 39/59 org.apache.geronimo.plugins/debugviews-jetty/2.1-SNAPSHOT/car          
    started in   .449s
> Module 40/59 org.apache.geronimo.configs/dojo-jetty6/2.1-SNAPSHOT/car               
    started in   .000s
> Module 41/59 org.apache.geronimo.configs/persistence-jpa10-deployer/2.1-SNAPSHOT/car
    started in   .073s
> Module 42/59 org.apache.geronimo.plugins/activemq-jetty/2.1-SNAPSHOT/car            
    started in   .648s
> Module 43/59 org.apache.geronimo.configs/jetty6-clustering-wadi/2.1-SNAPSHOT/car    
   Dec 16, 2007 9:28:14 AM org.apache.catalina.tribes.transport.ReceiverBase bind
> INFO: Receiver Server Socket bound to:/10.0.1.200:4000
> Dec 16, 2007 9:28:14 AM org.codehaus.wadi.tribes.WadiMemberInterceptor start
> INFO: memberStart local:org.apache.catalina.tribes.membership.MemberImpl[tcp://10.0.1.200:4000,10.0.1.200,4000,
alive=0,id={-31 -103 29 40 72 82 66 -11 -122 116 -49 -123 -114 -110 -11 121 }, payload={-84
-19 0 5 115 114 0 50 111 ...(421)}, command={}, domain={68 69 70 65 85 76 84 95 67 ...(15)},
] notify:false peer:NODE
> Dec 16, 2007 9:28:14 AM org.apache.catalina.tribes.membership.McastServiceImpl setupSocket
> INFO: Setting cluster mcast soTimeout to 500
> Dec 16, 2007 9:28:14 AM org.apache.catalina.tribes.membership.McastServiceImpl waitForMembers
> INFO: Sleeping for 1000 milliseconds to establish cluster membership, start level:4
> Dec 16, 2007 9:28:15 AM org.apache.catalina.tribes.membership.McastServiceImpl waitForMembers
> INFO: Done sleeping, membership established, start level:4
> Dec 16, 2007 9:28:15 AM org.apache.catalina.tribes.membership.McastServiceImpl waitForMembers
> INFO: Sleeping for 1000 milliseconds to establish cluster membership, start level:8
> Dec 16, 2007 9:28:16 AM org.apache.catalina.tribes.membership.McastServiceImpl waitForMembers
> INFO: Done sleeping, membership established, start level:8
> Dec 16, 2007 9:28:16 AM org.codehaus.wadi.tribes.WadiMemberInterceptor start
> INFO: memberStart local:org.apache.catalina.tribes.membership.MemberImpl[tcp://10.0.1.200:4000,10.0.1.200,4000,
alive=100,id={-31 -103 29 40 72 82 66 -11 -122 116 -49 -123 -114 -110 -11 121 }, payload={-84
-19 0 5 115 114 0 50 111 ...(421)}, command={}, domain={68 69 70 65 85 76 84 95 67 ...(15)},
] notify:true peer:NODE
> Dec 16, 2007 9:28:16 AM org.codehaus.wadi.tribes.WadiMemberInterceptor memberAdded
> INFO: memberAdded:tcp://10.0.1.200:4000
> Dec 16, 2007 9:28:16 AM org.apache.catalina.tribes.io.BufferPool getBufferPool
> INFO: Created a buffer pool with max size:104857600 bytes of type:org.apache.catalina.tribes.io.BufferPool15Impl
>  started in  2.373s
> Module 44/59 org.apache.geronimo.configs/wadi-clustering/2.1-SNAPSHOT/car           
    started in   .000s
> Module 45/59 org.apache.geronimo.configs/uddi-jetty6/2.1-SNAPSHOT/car               
    started in   .537s
> Module 46/59 org.apache.geronimo.configs/axis/2.1-SNAPSHOT/car                      
    started in   .000s
> Module 47/59 org.apache.geronimo.configs/axis-deployer/2.1-SNAPSHOT/car             
    started in   .088s
> Module 48/59 org.apache.geronimo.configs/j2ee-corba-yoko/2.1-SNAPSHOT/car           
    started in   .932s
> Module 49/59 org.apache.geronimo.configs/mejb/2.1-SNAPSHOT/car                      
    started in   .218s
> Module 50/59 org.apache.geronimo.configs/jetty6-clustering-builder-wadi/2.1-SNAPSHOT/car
started in   .037s
> Module 51/59 org.apache.geronimo.configs/hot-deployer/2.1-SNAPSHOT/car              
    started in   .645s
> Module 52/59 org.apache.geronimo.configs/openejb-corba-deployer/2.1-SNAPSHOT/car    
    started in   .135s
> Module 53/59 org.apache.geronimo.configs/javamail/2.1-SNAPSHOT/car                  
    started in   .067s
> Module 54/59 org.apache.geronimo.configs/cxf-deployer/2.1-SNAPSHOT/car              
    started in   .045s
> Module 55/59 org.apache.geronimo.configs/welcome-jetty/2.1-SNAPSHOT/car             
    started in   .109s
> Module 56/59 org.apache.geronimo.configs/sharedlib/2.1-SNAPSHOT/car                 
    started in   .013s
> Module 57/59 org.apache.geronimo.configs/client-deployer/2.1-SNAPSHOT/car           
    started in   .099s
> Module 58/59 org.apache.geronimo.configs/cxf-ejb-deployer/2.1-SNAPSHOT/car          
    started in   .066s
> Module 59/59 org.apache.geronimo.plugins/plancreator-jetty/2.1-SNAPSHOT/car         
    started in   .289s
> Startup completed in 20.590s seconds
>   Listening on Ports:
>        0 0.0.0.0   Derby Connector
>     1050 127.0.0.1 CORBA Naming Service
>     1099 0.0.0.0   RMI Naming
>     2001 127.0.0.1 OpenEJB ORB Adapter
>     4201 0.0.0.0   OpenEJB Daemon
>     6882 127.0.0.1 OpenEJB ORB Adapter
>     8009 0.0.0.0   Jetty Connector AJP13
>     8080 0.0.0.0   Jetty SelectChannel Connector HTTP
>     8443 0.0.0.0   Jetty SelectChannel Connector HTTPS
>     9999 0.0.0.0   JMX Remoting Connector
>    61613 0.0.0.0   ActiveMQ Transport Connector
>    61616 0.0.0.0   ActiveMQ Transport Connector
>   Started Application Modules:
>     EAR: org.apache.geronimo.configs/uddi-jetty6/2.1-SNAPSHOT/car
>     EAR: org.apache.geronimo.plugins/console-jetty/2.1-SNAPSHOT/car
>     JAR: org.apache.geronimo.configs/mejb/2.1-SNAPSHOT/car
>     RAR: org.apache.geronimo.configs/activemq-ra/2.1-SNAPSHOT/car
>     RAR: org.apache.geronimo.configs/system-database/2.1-SNAPSHOT/car
>     WAR: org.apache.geronimo.configs/ca-helper-jetty/2.1-SNAPSHOT/car
>     WAR: org.apache.geronimo.configs/dojo-jetty6/2.1-SNAPSHOT/car
>     WAR: org.apache.geronimo.configs/remote-deploy-jetty/2.1-SNAPSHOT/car
>     WAR: org.apache.geronimo.configs/welcome-jetty/2.1-SNAPSHOT/car
>     WAR: org.apache.geronimo.plugins/activemq-jetty/2.1-SNAPSHOT/car
>     WAR: org.apache.geronimo.plugins/debugviews-jetty/2.1-SNAPSHOT/car
>     WAR: org.apache.geronimo.plugins/plancreator-jetty/2.1-SNAPSHOT/car
>     WAR: org.apache.geronimo.plugins/system-database-jetty/2.1-SNAPSHOT/car
>   Web Applications:
>     /
>     /CAHelper
>     /activemq
>     /console
>     /console-base
>     /debug-views
>     /dojo
>     /juddi
>     /plan-creator
>     /remote-deploy
>     /system-database
> Geronimo Application Server started
>   C-c C-c[] received stop signal
> 09:28:26,400 WARN  [BasicEnvelopeDispatcherManager] spurious message received: Message:
to [TribesPeer [NODE; tcp://10.0.1.200:4000]]; replyTo [TribesPeer [NODE; tcp://10.0.1.200:4000]];
payload [Service [CommandEndPoint] in space [WADI/Admin] hosted by [TribesPeer [NODE; tcp://10.0.1.200:4000]]
is [STOPPING]]
> 09:28:26,400 WARN  [BasicEnvelopeDispatcherManager] spurious message received: Message:
to [TribesPeer [NODE; tcp://10.0.1.200:4000]]; replyTo [TribesPeer [NODE; tcp://10.0.1.200:4000]];
payload [Service [CommandEndPoint] in space [WADI/Admin] hosted by [TribesPeer [NODE; tcp://10.0.1.200:4000]]
is [STOPPED]]
> Here's the exception stack trace I saw once on shutdown:
> 09:20:09,101 ERROR [TribesDispatcher] Interrupted when a TribesMessage received, unable
to hand it off to the thread pool.
> java.lang.InterruptedException
> 	at EDU.oswego.cs.dl.util.concurrent.SynchronousChannel.offer(Unknown Source)
> 	at EDU.oswego.cs.dl.util.concurrent.PooledExecutor.execute(Unknown Source)
> 	at org.codehaus.wadi.group.impl.PooledExecutorAdapter.execute(PooledExecutorAdapter.java:40)
> 	at org.codehaus.wadi.tribes.TribesDispatcher.messageReceived(TribesDispatcher.java:102)
> 	at org.apache.catalina.tribes.group.GroupChannel.messageReceived(GroupChannel.java:264)
> 	at org.apache.catalina.tribes.group.ChannelInterceptorBase.messageReceived(ChannelInterceptorBase.java:79)
> 	at org.codehaus.wadi.tribes.WadiMemberInterceptor.messageReceived(WadiMemberInterceptor.java:58)
> 	at org.apache.catalina.tribes.group.ChannelInterceptorBase.messageReceived(ChannelInterceptorBase.java:79)
> 	at org.apache.catalina.tribes.group.ChannelInterceptorBase.messageReceived(ChannelInterceptorBase.java:79)
> 	at org.apache.catalina.tribes.group.interceptors.DomainFilterInterceptor.messageReceived(DomainFilterInterceptor.java:44)
> 	at org.apache.catalina.tribes.group.ChannelInterceptorBase.messageReceived(ChannelInterceptorBase.java:79)
> 	at org.apache.catalina.tribes.group.interceptors.TcpFailureDetector.messageReceived(TcpFailureDetector.java:110)
> 	at org.apache.catalina.tribes.group.ChannelInterceptorBase.messageReceived(ChannelInterceptorBase.java:79)
> 	at org.apache.catalina.tribes.group.ChannelCoordinator.messageReceived(ChannelCoordinator.java:241)
> 	at org.apache.catalina.tribes.transport.ReceiverBase.messageDataReceived(ReceiverBase.java:225)
> 	at org.apache.catalina.tribes.transport.nio.NioReplicationTask.drainChannel(NioReplicationTask.java:188)
> 	at org.apache.catalina.tribes.transport.nio.NioReplicationTask.run(NioReplicationTask.java:91)
> 	at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:650)
> 	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:675)
> 	at java.lang.Thread.run(Thread.java:613)
>  

-- 
This message is automatically generated by JIRA.
-
You can reply to this email to add a comment to the issue online.


Mime
View raw message