Return-Path: Delivered-To: apmail-geronimo-dev-archive@www.apache.org Received: (qmail 80607 invoked from network); 16 Dec 2007 14:32:14 -0000 Received: from hermes.apache.org (HELO mail.apache.org) (140.211.11.2) by minotaur.apache.org with SMTP; 16 Dec 2007 14:32:14 -0000 Received: (qmail 85290 invoked by uid 500); 16 Dec 2007 14:32:02 -0000 Delivered-To: apmail-geronimo-dev-archive@geronimo.apache.org Received: (qmail 85246 invoked by uid 500); 16 Dec 2007 14:32:02 -0000 Mailing-List: contact dev-help@geronimo.apache.org; run by ezmlm Precedence: bulk list-help: list-unsubscribe: List-Post: Reply-To: dev@geronimo.apache.org List-Id: Delivered-To: mailing list dev@geronimo.apache.org Received: (qmail 85235 invoked by uid 99); 16 Dec 2007 14:32:01 -0000 Received: from nike.apache.org (HELO nike.apache.org) (192.87.106.230) by apache.org (qpsmtpd/0.29) with ESMTP; Sun, 16 Dec 2007 06:32:01 -0800 X-ASF-Spam-Status: No, hits=-100.0 required=10.0 tests=ALL_TRUSTED X-Spam-Check-By: apache.org Received: from [140.211.11.4] (HELO brutus.apache.org) (140.211.11.4) by apache.org (qpsmtpd/0.29) with ESMTP; Sun, 16 Dec 2007 14:31:54 +0000 Received: from brutus (localhost [127.0.0.1]) by brutus.apache.org (Postfix) with ESMTP id F2C2071424C for ; Sun, 16 Dec 2007 06:31:44 -0800 (PST) Message-ID: <8642428.1197815504991.JavaMail.jira@brutus> Date: Sun, 16 Dec 2007 06:31:44 -0800 (PST) From: "Kevan Miller (JIRA)" To: dev@geronimo.apache.org Subject: [jira] Created: (GERONIMO-3709) multiple wadi WARN messages during server start MIME-Version: 1.0 Content-Type: text/plain; charset=utf-8 Content-Transfer-Encoding: 7bit X-Virus-Checked: Checked by ClamAV on apache.org 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.