ConsumerUnitTestCase fails when TRACE enabled on org.jboss.ejb3

Enabling TRACE logging on org.jboss.ejb3 and running org.jboss.ejb3.test.consumer.unit.ConsumerUnitTestCase
fails:

testQueue Failure expected:<null> but was:<method1>

junit.framework.ComparisonFailure: expected:<null> but was:<method1>
at org.jboss.ejb3.test.consumer.unit.ConsumerUnitTestCase.testQueue(ConsumerUnitTestCase.java:70)
at junit.extensions.TestDecorator.basicRun(TestDecorator.java:22)
at junit.extensions.TestSetup$1.protect(TestSetup.java:19)
at junit.extensions.TestSetup.run(TestSetup.java:23)

1.693
testQueueXA Failure expected:<null> but was:<method2>

junit.framework.ComparisonFailure: expected:<null> but was:<method2>
at org.jboss.ejb3.test.consumer.unit.ConsumerUnitTestCase.testQueueXA(ConsumerUnitTestCase.java:102)
at junit.extensions.TestDecorator.basicRun(TestDecorator.java:22)
at junit.extensions.TestSetup$1.protect(TestSetup.java:19)
at junit.extensions.TestSetup.run(TestSetup.java:23)

2.129
testQueueLocal Failure expected:<null> but was:<method2>

junit.framework.ComparisonFailure: expected:<null> but was:<method2>
at org.jboss.ejb3.test.consumer.unit.ConsumerUnitTestCase.testQueueLocal(ConsumerUnitTestCase.java:115)
at junit.extensions.TestDecorator.basicRun(TestDecorator.java:22)
at junit.extensions.TestSetup$1.protect(TestSetup.java:19)
at junit.extensions.TestSetup.run(TestSetup.java:23)

2.136
testDeploymentDescriptorQueue Failure expected:<null> but was:<method1>

junit.framework.ComparisonFailure: expected:<null> but was:<method1>
at org.jboss.ejb3.test.consumer.unit.ConsumerUnitTestCase.testDeploymentDescriptorQueue(ConsumerUnitTestCase.java:132)
at junit.extensions.TestDecorator.basicRun(TestDecorator.java:22)
at junit.extensions.TestSetup$1.protect(TestSetup.java:19)
at junit.extensions.TestSetup.run(TestSetup.java:23)

1.110
testDeploymentDescriptorQueueXA Failure expected:<null> but was:<method2>

junit.framework.ComparisonFailure: expected:<null> but was:<method2>
at org.jboss.ejb3.test.consumer.unit.ConsumerUnitTestCase.testDeploymentDescriptorQueueXA(ConsumerUnitTestCase.java:164)
at junit.extensions.TestDecorator.basicRun(TestDecorator.java:22)
at junit.extensions.TestSetup$1.protect(TestSetup.java:19)
at junit.extensions.TestSetup.run(TestSetup.java:23)

2.100
testDeploymentDescriptorQueueLocal Failure expected:<null> but was:<method2>

junit.framework.ComparisonFailure: expected:<null> but was:<method2>
at org.jboss.ejb3.test.consumer.unit.ConsumerUnitTestCase.testDeploymentDescriptorQueueLocal(ConsumerUnitTestCase.java:177)
at junit.extensions.TestDecorator.basicRun(TestDecorator.java:22)
at junit.extensions.TestSetup$1.protect(TestSetup.java:19)
at junit.extensions.TestSetup.run(TestSetup.java:23)

The server log shows this:

16:30:36,699 WARN [JmsActivation] Failure in jms activation org.jboss.resource.adapter.jms.inflow.JmsActivationSpec@14ba90f(ra=org.jboss.resource.adapter.jms.JmsResourceAdapter@621e34 destination=queue/mdbtest isTopic=false tx=true durable=false reconnect=10 provider=java:/DefaultJMSProvider user=null maxMessages=1 minSession=1 maxSession=15 keepAlive=60000 useDLQ=true DLQHandler=org.jboss.resource.adapter.jms.inflow.dlq.GenericDLQHandler DLQJndiName=queue/DLQ DLQUser=null DLQMaxResent=5)
java.lang.NullPointerException
at sun.reflect.GeneratedMethodAccessor29.invoke(Unknown Source)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
at java.lang.reflect.Method.invoke(Method.java:585)
at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:112)
at org.jboss.ejb3.mdb.MessagingContainer.localInvoke(MessagingContainer.java:245)
at org.jboss.ejb3.mdb.ConsumerContainer.localInvoke(ConsumerContainer.java:162)
at org.jboss.ejb3.mdb.inflow.MessageInflowLocalProxy.delivery(MessageInflowLocalProxy.java:268)
at org.jboss.ejb3.mdb.inflow.MessageInflowLocalProxy.invoke(MessageInflowLocalProxy.java:138)
at $Proxy129.toString(Unknown Source)
at java.lang.String.valueOf(String.java:2615)
at java.lang.StringBuilder.append(StringBuilder.java:116)
at org.jboss.ejb3.mdb.inflow.JBossMessageEndpointFactory.createEndpoint(JBossMessageEndpointFactory.java:134)
at org.jboss.resource.adapter.jms.inflow.JmsServerSession.setup(JmsServerSession.java:125)
at org.jboss.resource.adapter.jms.inflow.JmsServerSessionPool.setupSessions(JmsServerSessionPool.java:200)
at org.jboss.resource.adapter.jms.inflow.JmsServerSessionPool.start(JmsServerSessionPool.java:90)
at org.jboss.resource.adapter.jms.inflow.JmsActivation.setupSessionPool(JmsActivation.java:551)
at org.jboss.resource.adapter.jms.inflow.JmsActivation.setup(JmsActivation.java:323)
at org.jboss.resource.adapter.jms.inflow.JmsActivation$SetupActivation.run(JmsActivation.java:601)
at org.jboss.resource.work.WorkWrapper.execute(WorkWrapper.java:204)
at org.jboss.util.threadpool.BasicTaskWrapper.run(BasicTaskWrapper.java:275)
at EDU.oswego.cs.dl.util.concurrent.PooledExecutor$Worker.run(PooledExecutor.java:756)
at java.lang.Thread.run(Thread.java:595)

Call comes from org.jboss.ejb3.mdb.inflow.JBossMessageEndpointFactory:

public MessageEndpoint createEndpoint(XAResource resource) throws UnavailableException

{ trace = log.isTraceEnabled(); if (trace) log.trace("createEndpoint " + this + " xaResource=" + resource); MessageEndpoint endpoint = createProxy(resource); if (trace) log.trace("Created endpoint " + endpoint + " from " + this); return endpoint; }

The exception comes from executing this line:

log.trace("Created endpoint " + endpoint + " from " + this);