kieker-monitoring / kieker

Kieker's main repository
Apache License 2.0
70 stars 41 forks source link

[KIEKER-324] JMS Reader dumps log.info messages for Unknown types #1535

Closed rju closed 11 hours ago

rju commented 2 days ago

JIRA Issue: KIEKER-324 JMS Reader dumps log.info messages for Unknown types Original Reporter: Andre van Hoorn


It seems that for each monitoring record another server-specific message is sent.
As the JMSReader currently dumps a log.info message for each unknown record, this leads to a number of log messages.

This happens for the JMS Example with ActiveMQ, HornetQ, and OpenJMS.

avanhoornavanhoorn-thinkpad:130:~/tmp/kieker-1.4-RC/examples/userguide/appendix-JMS $ ant run-analysis-activemq 

...

run-analysis-activemq:
     [java] jms-connection-factory:org.apache.activemq.jndi.ActiveMQInitialContextFactory
     [java] jms-provider-url:      tcp://127.0.0.1:61616/
     [java] jms-queue:             queue1
     [java] 
     [java] 0    WARN  [main] org.apache.activemq.transport.tcp.TcpTransportFactory     - path isn't a valid local location for TcpTransport to use
     [java] 264  WARN  [main]  kieker.analysis.reader.jms.JMSReader     - Failed to lookup queue 'queue1' via JNDI: queue1
     [java] 264  INFO  [main]  kieker.analysis.reader.jms.JMSReader     - Attempting to create queue ...
     [java] 265  INFO  [main]  kieker.analysis.reader.jms.JMSReader     - Listening to destination:queue://queue1 at tcp://127.0.0.1:61616/ !
     [java] ***
     [java] 
     [java] 
     [java] 284  INFO  [main]  kieker.analysis.reader.jms.JMSReader     - JMSReader started and waits for incomming monitoring events!
     [java] maximum response time exceeded by 2799006 ns: bookstoreApplication.Catalog.getBook(..)
     [java] 14871 INFO  [ActiveMQ Session Task-1]  kieker.analysis.reader.jms.JMSReader     - Unknown type of message ActiveMQObjectMessage {commandId = 5, responseRequired = false, messageId = ID:avanhoorn-thinkpad-47411-1318520439901-0:1:1:1:1, originalDestination = null, originalTransactionId = null, producerId = ID:avanhoorn-thinkpad-47411-1318520439901-0:1:1:1, destination = queue://queue1, transactionId = null, expiration = 1318520450145, timestamp = 1318520440145, arrival = 0, brokerInTime = 1318520440148, brokerOutTime = 1318520440148, correlationId = null, replyTo = null, persistent = false, type = null, priority = 4, groupID = null, groupSequence = 0, targetConsumerId = null, compressed = false, userID = null, content = org.apache.activemq.util.ByteSequenceecb281, marshalledProperties = null, dataStructure = null, redeliveryCounter = 0, size = 0, properties = null, readOnlyProperties = true, readOnlyBody = true, droppable = false}
     [java] maximum response time exceeded by 2746904 ns: bookstoreApplication.Catalog.getBook()
     [java] 14875 INFO  [ActiveMQ Session Task-1]  kieker.analysis.reader.jms.JMSReader     - Unknown type of message ActiveMQObjectMessage {commandId = 6, responseRequired = false, messageId = ID:avanhoorn-thinkpad-47411-1318520439901-0:1:1:1:2, originalDestination = null, originalTransactionId = null, producerId = ID:avanhoorn-thinkpad-47411-1318520439901-0:1:1:1, destination = queue://queue1, transactionId = null, expiration = 1318520450158, timestamp = 1318520440158, arrival = 0, brokerInTime = 1318520440159, brokerOutTime = 1318520440159, correlationId = null, replyTo = null, persistent = false, type = null, priority = 4, groupID = null, groupSequence = 0, targetConsumerId = null, compressed = false, userID = null, content = org.apache.activemq.util.ByteSequencee2cb55, marshalledProperties = null, dataStructure = null, redeliveryCounter = 0, size = 0, properties = null, readOnlyProperties = true, readOnlyBody = true, droppable = false}

...
avanhoornavanhoorn-thinkpad:130:~/tmp/kieker-1.4-RC/examples/userguide/appendix-JMS $ ant run-analysis-hornetq 

...

run-analysis-hornetq:
     [java] jms-connection-factory:org.jnp.interfaces.NamingContextFactory
     [java] jms-provider-url:      jnp://localhost:1099
     [java] jms-queue:             /queue/queue1
     [java] 
     [java] 0    INFO  [main]  kieker.analysis.reader.jms.JMSReader     - Listening to destination:HornetQQueue[queue1] at jnp://localhost:1099 !
     [java] ***
     [java] 
     [java] 
     [java] 43   INFO  [main]  kieker.analysis.reader.jms.JMSReader     - JMSReader started and waits for incomming monitoring events!
     [java] maximum response time exceeded by 2126713 ns: bookstoreApplication.Catalog.getBook(..)
     [java] 8187 INFO  [Thread-1 (group:HornetQ-client-global-threads-28606871)]  kieker.analysis.reader.jms.JMSReader     - Unknown type of message HornetQMessage[ID:e330451d-f5b2-11e0-9598-cd82de8da938]:NON-PERSISTENT
     [java] maximum response time exceeded by 3700866 ns: bookstoreApplication.Catalog.getBook()
     [java] 8191 INFO  [Thread-1 (group:HornetQ-client-global-threads-28606871)]  kieker.analysis.reader.jms.JMSReader     - Unknown type of message HornetQMessage[ID:e331086e-f5b2-11e0-9598-cd82de8da938]:NON-PERSISTENT

...
avanhoornavanhoorn-thinkpad:0:~/tmp/kieker-1.4-RC/examples/userguide/appendix-JMS $ ant run-analysis-openjms 

...

run-analysis-openjms:
     [java] jms-connection-factory:org.exolab.jms.jndi.InitialContextFactory
     [java] jms-provider-url:      tcp://127.0.0.1:3035/
     [java] jms-queue:             queue1
     [java] 
     [java] 0    INFO  [main]  kieker.analysis.reader.jms.JMSReader     - Listening to destination:queue1-true at tcp://127.0.0.1:3035/ !
     [java] ***
     [java] 
     [java] 
     [java] 16   INFO  [main]  kieker.analysis.reader.jms.JMSReader     - JMSReader started and waits for incomming monitoring events!
     [java] maximum response time exceeded by 2113304 ns: bookstoreApplication.Catalog.getBook(..)
     [java] 14679 INFO  [ORB-Worker-1]  kieker.analysis.reader.jms.JMSReader     - Unknown type of message org.exolab.jms.message.ObjectMessageImpl1e78fc6
     [java] maximum response time exceeded by 2190268 ns: bookstoreApplication.Catalog.getBook()

...
rju commented 2 days ago

author André van Hoorn -- Thu, 13 Oct 2011 19:00:23 +0200

Nils Christian Ehmke: Did you also observe these messages?

rju commented 2 days ago

author Jan Waller -- Sat, 5 Nov 2011 11:37:52 +0100

is this fixed?

rju commented 2 days ago

author André van Hoorn -- Sun, 6 Nov 2011 14:29:08 +0100

Replying to [jwa|comment:3]:
> is this fixed?

Yes, the log.info has been removed.