Return-Path: X-Original-To: apmail-activemq-users-archive@www.apache.org Delivered-To: apmail-activemq-users-archive@www.apache.org Received: from mail.apache.org (hermes.apache.org [140.211.11.3]) by minotaur.apache.org (Postfix) with SMTP id 8B673D41D for ; Tue, 30 Oct 2012 15:30:47 +0000 (UTC) Received: (qmail 40793 invoked by uid 500); 30 Oct 2012 15:30:47 -0000 Delivered-To: apmail-activemq-users-archive@activemq.apache.org Received: (qmail 40758 invoked by uid 500); 30 Oct 2012 15:30:47 -0000 Mailing-List: contact users-help@activemq.apache.org; run by ezmlm Precedence: bulk List-Help: List-Unsubscribe: List-Post: List-Id: Reply-To: users@activemq.apache.org Delivered-To: mailing list users@activemq.apache.org Received: (qmail 40734 invoked by uid 99); 30 Oct 2012 15:30:46 -0000 Received: from nike.apache.org (HELO nike.apache.org) (192.87.106.230) by apache.org (qpsmtpd/0.29) with ESMTP; Tue, 30 Oct 2012 15:30:46 +0000 X-ASF-Spam-Status: No, hits=2.5 required=5.0 tests=FREEMAIL_REPLY,HTML_MESSAGE,RCVD_IN_DNSWL_LOW,SPF_PASS X-Spam-Check-By: apache.org Received-SPF: pass (nike.apache.org: domain of christian.posta@gmail.com designates 209.85.212.43 as permitted sender) Received: from [209.85.212.43] (HELO mail-vb0-f43.google.com) (209.85.212.43) by apache.org (qpsmtpd/0.29) with ESMTP; Tue, 30 Oct 2012 15:30:39 +0000 Received: by mail-vb0-f43.google.com with SMTP id fq11so440706vbb.2 for ; Tue, 30 Oct 2012 08:30:18 -0700 (PDT) DKIM-Signature: v=1; a=rsa-sha256; c=relaxed/relaxed; d=gmail.com; s=20120113; h=mime-version:in-reply-to:references:date:message-id:subject:from:to :content-type; bh=bYkOJ+uYmd/wLbAAA36QhIh6mZh/k6i/5Cyymyo2aXM=; b=ScSozWRG7QL7CRnmjoFfdhe034sYbc+egPzH2ZtKjq16rN3pCQHv04BwRyecFM479m FM+pIKGd6TajbL7whZQYCCiz3lLJ9zRr5BUGn8r5OduUbZrEqKnKjHr8zIea7PHyDT2V Vh8mQ6WZBiIMMPjFtPNYYTrY3/ywaqBZs0UMlMr/yTpi43GmfVOaQOOaA78D5XHlIKRp ojGuBbjELIdWli2rntOsooX/HlzuzhPk7Yfj4pvp6SLNBXFHvWA0ErhxvjMHnzOISaCI dm92Tm5IiBKVR4A6lQx3Zts3/5W/txU9N5cIo99e+pqqNe/s1yPQHs1KIqOKwCiiN836 YXVg== MIME-Version: 1.0 Received: by 10.220.148.7 with SMTP id n7mr13677741vcv.30.1351611018146; Tue, 30 Oct 2012 08:30:18 -0700 (PDT) Received: by 10.220.38.196 with HTTP; Tue, 30 Oct 2012 08:30:18 -0700 (PDT) In-Reply-To: References: Date: Tue, 30 Oct 2012 08:30:18 -0700 Message-ID: Subject: Re: AMQ 5.7.0: Stomp trace does not log frames From: Christian Posta To: users@activemq.apache.org Content-Type: multipart/alternative; boundary=bcaec54eeafa9c757e04cd487655 X-Virus-Checked: Checked by ClamAV on apache.org --bcaec54eeafa9c757e04cd487655 Content-Type: text/plain; charset=ISO-8859-1 James, so using ?transport.trace=true should work for your case here. There is a bug with our introspection utils class that sets trace on the transport when configured using ?trace=true I opened this JIRA (https://issues.apache.org/jira/browse/AMQ-4145) and we resolve asap. Thanks, Christian On Tue, Oct 30, 2012 at 6:57 AM, Christian Posta wrote: > James, > > I was wrong. The correct log4j logger would be this... > > log4j.logger.org.apache.activemq.transport.stomp.StompIO=TRACE > > (therefore, your original setting would have been correct) > > but even so, there does seem to be an issue with "trace" being set on the > Stomp transport filter. I'm digging to see why... > > > > On Tue, Oct 30, 2012 at 2:26 AM, James Green wrote: > >> That made no difference :( >> >> The entire log4j.properties file is below. If it helps the JMX console >> says >> that the stompURL property of the broker is >> stomp://localhost:61612?trace=true >> >> log4j.rootLogger=INFO, console, logfile >> log4j.logger.org.apache.activemq.spring=WARN >> log4j.logger.org.apache.activemq.web.handler=WARN >> log4j.logger.org.springframework=WARN >> log4j.logger.org.apache.xbean=WARN >> log4j.logger.org.apache.camel=ERROR >> log4j.logger.org.apache.activemq.transport.stomp=TRACE, stomp >> log4j.logger.org.apache.activemq.transport.TransportLogger=TRACE, stomp >> >> # When debugging or reporting problems to the ActiveMQ team, >> # comment out the above lines and uncomment the next. >> >> #log4j.rootLogger=DEBUG, logfile, console >> >> # Or for more fine grained debug logging uncomment one of these >> log4j.logger.org.apache.activemq=DEBUG >> #log4j.logger.org.apache.camel=DEBUG >> >> # Console appender >> log4j.appender.console=org.apache.log4j.ConsoleAppender >> log4j.appender.console.layout=org.apache.log4j.PatternLayout >> log4j.appender.console.layout.ConversionPattern=%5p | %m%n >> log4j.appender.console.threshold=INFO >> >> # File appender >> log4j.appender.logfile=org.apache.log4j.RollingFileAppender >> log4j.appender.logfile.file=${activemq.base}/data/activemq.log >> log4j.appender.logfile.maxFileSize=20MB >> log4j.appender.logfile.maxBackupIndex=20 >> log4j.appender.logfile.append=true >> log4j.appender.logfile.layout=org.apache.log4j.PatternLayout >> log4j.appender.logfile.layout.ConversionPattern=%d | %-5p | %m | %c | %t%n >> >> #log4j.appender.kahadb=org.apache.log4j.RollingFileAppender >> #log4j.appender.kahadb.file=${activemq.base}/data/kahadb.log >> #log4j.appender.kahadb.maxFileSize=1024KB >> #log4j.appender.kahadb.maxBackupIndex=5 >> #log4j.appender.kahadb.append=true >> #log4j.appender.kahadb.layout=org.apache.log4j.PatternLayout >> #log4j.appender.kahadb.layout.ConversionPattern=%d [%-15.15t] %-5p >> %-30.30c{1} - %m%n >> #log4j.logger.org.apache.activemq.store.kahadb.MessageDatabase=TRACE, >> kahadb >> >> log4j.appender.stomp=org.apache.log4j.RollingFileAppender >> log4j.appender.stomp.file=${activemq.base}/data/stomp.log >> log4j.appender.stomp.maxFileSize=1024KB >> log4j.appender.stomp.maxBackupIndex=5 >> log4j.appender.stomp.append=true >> log4j.appender.stomp.layout=org.apache.log4j.PatternLayout >> log4j.appender.stomp.layout.ConversionPattern=%d [%-15.15t] %-5p >> %-30.30c{1} - %m%n >> >> # enable this line and disable the above if you just want the frame IO. >> #log4j.logger.org.apache.activemq.transport.stomp.StompIO=TRACE, stomp >> log4j.additivity.org.apache.activemq.transport.stomp=false >> >> Thanks again, >> >> James >> >> On 29 October 2012 23:07, Christian Posta >> wrote: >> >> > So setting ?trace=true should add a transport filter to the transports >> that >> > is in this class: >> > org.apache.activemq.transport.TransportLogger >> > >> > Can you try with the following settings >> > >> > log4j.logger.org.apache.activemq.transport.TransportLogger=TRACE, stomp >> > >> > >> > to get the logging you want? >> > >> > On Fri, Oct 26, 2012 at 8:34 AM, James Green > > >wrote: >> > >> > > log4j.properties: >> > > >> > > log4j.rootLogger=INFO, console, logfile >> > > log4j.logger.org.apache.activemq.spring=WARN >> > > log4j.logger.org.apache.activemq.web.handler=WARN >> > > log4j.logger.org.springframework=WARN >> > > log4j.logger.org.apache.xbean=WARN >> > > log4j.logger.org.apache.camel=ERROR >> > > log4j.logger.org.apache.activemq.transport.stomp=TRACE, stomp >> > > >> > > # Or for more fine grained debug logging uncomment one of these >> > > log4j.logger.org.apache.activemq=DEBUG >> > > #log4j.logger.org.apache.camel=DEBUG >> > > >> > > # Console appender >> > > log4j.appender.console=org.apache.log4j.ConsoleAppender >> > > log4j.appender.console.layout=org.apache.log4j.PatternLayout >> > > log4j.appender.console.layout.ConversionPattern=%5p | %m%n >> > > log4j.appender.console.threshold=INFO >> > > >> > > # File appender >> > > log4j.appender.logfile=org.apache.log4j.RollingFileAppender >> > > log4j.appender.logfile.file=${activemq.base}/data/activemq.log >> > > log4j.appender.logfile.maxFileSize=1024KB >> > > log4j.appender.logfile.maxBackupIndex=5 >> > > log4j.appender.logfile.append=true >> > > log4j.appender.logfile.layout=org.apache.log4j.PatternLayout >> > > log4j.appender.logfile.layout.ConversionPattern=%d | %-5p | %m | %c | >> > %t%n >> > > >> > > #log4j.appender.kahadb=org.apache.log4j.RollingFileAppender >> > > #log4j.appender.kahadb.file=${activemq.base}/data/kahadb.log >> > > #log4j.appender.kahadb.maxFileSize=1024KB >> > > #log4j.appender.kahadb.maxBackupIndex=5 >> > > #log4j.appender.kahadb.append=true >> > > #log4j.appender.kahadb.layout=org.apache.log4j.PatternLayout >> > > #log4j.appender.kahadb.layout.ConversionPattern=%d [%-15.15t] %-5p >> > > %-30.30c{1} - %m%n >> > > #log4j.logger.org.apache.activemq.store.kahadb.MessageDatabase=TRACE, >> > > kahadb >> > > >> > > log4j.appender.stomp=org.apache.log4j.RollingFileAppender >> > > log4j.appender.stomp.file=${activemq.base}/data/stomp.log >> > > log4j.appender.stomp.maxFileSize=1024KB >> > > log4j.appender.stomp.maxBackupIndex=5 >> > > log4j.appender.stomp.append=true >> > > log4j.appender.stomp.layout=org.apache.log4j.PatternLayout >> > > log4j.appender.stomp.layout.ConversionPattern=%d [%-15.15t] %-5p >> > > %-30.30c{1} - %m%n >> > > >> > > # enable this line and disable the above if you just want the frame >> IO. >> > > #log4j.logger.org.apache.activemq.transport.stomp.StompIO=TRACE, stomp >> > > log4j.additivity.org.apache.activemq.transport.stomp=false >> > > >> > > >> > > TransportConnector: >> > > > > uri="stomp://localhost:61612?trace=true"/> >> > > >> > > The stomp.log holds only: >> > > 2012-10-26 16:22:41,443 [0.1:57638@61612] DEBUG >> > > StompInactivityMonitor - Stomp Inactivity Monitor read check: >> 0, >> > > write check: 0 >> > > 2012-10-26 16:22:41,443 [0.1:57638@61612] DEBUG >> > > ProtocolConverter - Stomp Connect heartbeat conf RW[0,0] >> > > 2012-10-26 16:22:52,812 [0.1:57640@61612] DEBUG >> > > StompInactivityMonitor - Stomp Inactivity Monitor read check: >> 0, >> > > write check: 0 >> > > 2012-10-26 16:22:52,812 [0.1:57640@61612] DEBUG >> > > ProtocolConverter - Stomp Connect heartbeat conf RW[0,0] >> > > 2012-10-26 16:22:52,842 [0.1:57641@61612] DEBUG >> > > StompInactivityMonitor - Stomp Inactivity Monitor read check: >> 0, >> > > write check: 0 >> > > 2012-10-26 16:22:52,842 [0.1:57641@61612] DEBUG >> > > ProtocolConverter - Stomp Connect heartbeat conf RW[0,0] >> > > 2012-10-26 16:23:04,054 [0.1:57643@61612] DEBUG >> > > StompInactivityMonitor - Stomp Inactivity Monitor read check: >> 0, >> > > write check: 0 >> > > 2012-10-26 16:23:04,054 [0.1:57643@61612] DEBUG >> > > ProtocolConverter - Stomp Connect heartbeat conf RW[0,0] >> > > 2012-10-26 16:23:15,386 [0.1:57645@61612] DEBUG >> > > StompInactivityMonitor - Stomp Inactivity Monitor read check: >> 0, >> > > write check: 0 >> > > 2012-10-26 16:23:15,386 [0.1:57645@61612] DEBUG >> > > ProtocolConverter - Stomp Connect heartbeat conf RW[0,0] >> > > >> > > Where are the frames? >> > > >> > > I need to understand why a consumer is being starved of messages >> after a >> > > while. >> > > >> > > Thanks, >> > > >> > > James >> > > >> > >> > >> > >> > -- >> > *Christian Posta* >> > http://www.christianposta.com/blog >> > twitter: @christianposta >> > >> > > > > -- > *Christian Posta* > http://www.christianposta.com/blog > twitter: @christianposta > > -- *Christian Posta* http://www.christianposta.com/blog twitter: @christianposta --bcaec54eeafa9c757e04cd487655--