Uploaded image for project: 'AMQ Broker'
  1. AMQ Broker
  2. ENTMQBR-5

Security exception of a "null" user is being thrown on broker, while correct user/login is being used

XMLWordPrintable

    • Icon: Bug Bug
    • Resolution: Done
    • Icon: Minor Minor
    • A-MQ 7.0.0.ER14
    • None
    • None
    • None

      When sending a message to a not existing destination (queue) -> broker refuses valid user (null?) and reports FailedLoginException, then announces target address does not exist.

      I believe such security exception should not happen, nor be thrown by broker.

      $ ./aac1_sender.java.sh  -msg-content "lala message" --log-msgs dict --conn-username admin --conn-password admin --address lalaQ --log-lib=debug
      DEBUG Connection=amqp://localhost:5672?jms.username=admin&jms.password=admin
      [562401096:0] -> Open{ containerId='ID:9f065440-0dfe-416d-9f38-4fd06ccef29d:1', hostname='localhost', maxFrameSize=1048576, channelMax=32767, idleTimeOut=30000, outgoingLocales=null, incomingLocales=null, offeredCapabilities=null, desiredCapabilities=[sole-connection-for-container], properties={product=QpidJMS, version=0.11.0.redhat-1, platform=JVM: 1.8.0_51, 25.51-b03, Oracle Corporation, OS: Linux, 3.10.0-327.28.2.el7.x86_64, amd64}}
      [562401096:0] <- Open{ containerId='amq', hostname='', maxFrameSize=4294967295, channelMax=65535, idleTimeOut=30000, outgoingLocales=null, incomingLocales=null, offeredCapabilities=[sole-connection-for-container, DELAYED_DELIVERY], desiredCapabilities=null, properties={product=apache-activemq-artemis, version=1.3.0.amq-700005-redhat-1}}
      [562401096:0] -> Begin{remoteChannel=null, nextOutgoingId=1, incomingWindow=2047, outgoingWindow=2147483647, handleMax=65535, offeredCapabilities=null, desiredCapabilities=null, properties=null}
      [562401096:0] <- Begin{remoteChannel=0, nextOutgoingId=1, incomingWindow=2147483647, outgoingWindow=2147483647, handleMax=65535, offeredCapabilities=null, desiredCapabilities=null, properties=null}
      [562401096:1] -> Begin{remoteChannel=null, nextOutgoingId=1, incomingWindow=2047, outgoingWindow=2147483647, handleMax=65535, offeredCapabilities=null, desiredCapabilities=null, properties=null}
      [562401096:1] <- Begin{remoteChannel=1, nextOutgoingId=1, incomingWindow=2147483647, outgoingWindow=2147483647, handleMax=65535, offeredCapabilities=null, desiredCapabilities=null, properties=null}
      [562401096:1] -> Attach{name='qpid-jms:sender:ID:67cbfca5-1a6b-4097-a32c-ea37e949198a:1:1:1:lalaQ', handle=0, role=SENDER, sndSettleMode=UNSETTLED, rcvSettleMode=FIRST, source=Source{address='ID:67cbfca5-1a6b-4097-a32c-ea37e949198a:1:1:1', durable=NONE, expiryPolicy=SESSION_END, timeout=0, dynamic=false, dynamicNodeProperties=null, distributionMode=null, filter=null, defaultOutcome=null, outcomes=[amqp:accepted:list, amqp:rejected:list], capabilities=null}, target=Target{address='lalaQ', durable=NONE, expiryPolicy=SESSION_END, timeout=0, dynamic=false, dynamicNodeProperties=null, capabilities=[queue]}, unsettled=null, incompleteUnsettled=false, initialDeliveryCount=0, maxMessageSize=null, offeredCapabilities=null, desiredCapabilities=null, properties=null}
      [562401096:1] <- Attach{name='qpid-jms:sender:ID:67cbfca5-1a6b-4097-a32c-ea37e949198a:1:1:1:lalaQ', handle=0, role=RECEIVER, sndSettleMode=MIXED, rcvSettleMode=FIRST, source=Source{address='ID:67cbfca5-1a6b-4097-a32c-ea37e949198a:1:1:1', durable=NONE, expiryPolicy=SESSION_END, timeout=0, dynamic=false, dynamicNodeProperties=null, distributionMode=null, filter=null, defaultOutcome=null, outcomes=[amqp:accepted:list, amqp:rejected:list], capabilities=null}, target=null, unsettled=null, incompleteUnsettled=false, initialDeliveryCount=null, maxMessageSize=null, offeredCapabilities=null, desiredCapabilities=null, properties=null}
      [562401096:1] <- Detach{handle=0, closed=true, error=Error{condition=amqp:not-found, description='AMQ219002: target address does not exist', info=null}}
      12:31:53,629 WARN Open of resource:(JmsProducerInfo { ID:67cbfca5-1a6b-4097-a32c-ea37e949198a:1:1:1, destination = lalaQ }) failed: AMQ219002: target address does not exist [condition = amqp:not-found]
      [562401096:1] -> Detach{handle=0, closed=true, error=null}
      12:31:53,639 ERROR Error while sending a message!
      javax.jms.InvalidDestinationException: AMQ219002: target address does not exist [condition = amqp:not-found]
      	at org.apache.qpid.jms.provider.amqp.AmqpSupport.convertToException(AmqpSupport.java:136)
      	at org.apache.qpid.jms.provider.amqp.AmqpSupport.convertToException(AmqpSupport.java:105)
      	at org.apache.qpid.jms.provider.amqp.builders.AmqpResourceBuilder.handleClosed(AmqpResourceBuilder.java:167)
      	at org.apache.qpid.jms.provider.amqp.builders.AmqpResourceBuilder.processRemoteClose(AmqpResourceBuilder.java:113)
      	at org.apache.qpid.jms.provider.amqp.AmqpProvider.processUpdates(AmqpProvider.java:795)
      	at org.apache.qpid.jms.provider.amqp.AmqpProvider.access$1900(AmqpProvider.java:90)
      	at org.apache.qpid.jms.provider.amqp.AmqpProvider$17.run(AmqpProvider.java:699)
      	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
      	at java.util.concurrent.FutureTask.run(FutureTask.java:266)
      	at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$201(ScheduledThreadPoolExecutor.java:180)
      	at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:293)
      	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
      	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
      	at java.lang.Thread.run(Thread.java:745)
      
      
      ------------------ Broker log
      12:31:49.740 DEBUG [org.apache.activemq.artemis.core.server.reload.ReloadManagerImpl] Validating lastModified 1476781588000 modified = 1476781588000 on file:/opt/jboss-amq-7-i0/etc/broker.xml
      12:31:53.468 DEBUG [org.apache.activemq.artemis.spi.core.security.jaas.PropertiesLoader] Initialized debug
      12:31:53.470 DEBUG [org.apache.activemq.artemis.spi.core.security.jaas.PropertiesLoginModule] abort
      12:31:53.470 DEBUG [org.apache.activemq.artemis.spi.core.security.ActiveMQJAASSecurityManager] Couldn't validate user: javax.security.auth.login.FailedLoginException: user name is null
      	at org.apache.activemq.artemis.spi.core.security.jaas.PropertiesLoginModule.login(PropertiesLoginModule.java:88) [artemis-server-1.3.0.amq-700005-redhat-1.jar:1.3.0.amq-700005-redhat-1]
      	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) [rt.jar:1.8.0_51]
      	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) [rt.jar:1.8.0_51]
      	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) [rt.jar:1.8.0_51]
      	at java.lang.reflect.Method.invoke(Method.java:497) [rt.jar:1.8.0_51]
      	at javax.security.auth.login.LoginContext.invoke(LoginContext.java:755) [rt.jar:1.8.0_51]
      	at javax.security.auth.login.LoginContext.access$000(LoginContext.java:195) [rt.jar:1.8.0_51]
      	at javax.security.auth.login.LoginContext$4.run(LoginContext.java:682) [rt.jar:1.8.0_51]
      	at javax.security.auth.login.LoginContext$4.run(LoginContext.java:680) [rt.jar:1.8.0_51]
      	at java.security.AccessController.doPrivileged(Native Method) [rt.jar:1.8.0_51]
      	at javax.security.auth.login.LoginContext.invokePriv(LoginContext.java:680) [rt.jar:1.8.0_51]
      	at javax.security.auth.login.LoginContext.login(LoginContext.java:587) [rt.jar:1.8.0_51]
      	at org.apache.activemq.artemis.spi.core.security.ActiveMQJAASSecurityManager.getAuthenticatedSubject(ActiveMQJAASSecurityManager.java:185) [artemis-server-1.3.0.amq-700005-redhat-1.jar:1.3.0.amq-700005-redhat-1]
      	at org.apache.activemq.artemis.spi.core.security.ActiveMQJAASSecurityManager.validateUser(ActiveMQJAASSecurityManager.java:91) [artemis-server-1.3.0.amq-700005-redhat-1.jar:1.3.0.amq-700005-redhat-1]
      	at org.apache.activemq.artemis.core.security.impl.SecurityStoreImpl.authenticate(SecurityStoreImpl.java:127) [artemis-server-1.3.0.amq-700005-redhat-1.jar:1.3.0.amq-700005-redhat-1]
      	at org.apache.activemq.artemis.core.protocol.proton.plug.ActiveMQProtonConnectionCallback.isSupportsAnonymous(ActiveMQProtonConnectionCallback.java:120) [artemis-amqp-protocol-1.3.0.amq-700005-redhat-1.jar:]
      	at org.apache.activemq.artemis.core.protocol.proton.plug.ActiveMQProtonConnectionCallback.getSASLMechnisms(ActiveMQProtonConnectionCallback.java:107) [artemis-amqp-protocol-1.3.0.amq-700005-redhat-1.jar:]
      	at org.proton.plug.context.AbstractConnectionContext$LocalListener.onAuthInit(AbstractConnectionContext.java:204) [artemis-proton-plug-1.3.0.amq-700005-redhat-1.jar:1.3.0.amq-700005-redhat-1]
      	at org.proton.plug.handler.impl.ProtonHandlerImpl.dispatchAuth(ProtonHandlerImpl.java:353) [artemis-proton-plug-1.3.0.amq-700005-redhat-1.jar:1.3.0.amq-700005-redhat-1]
      	at org.proton.plug.handler.impl.ProtonHandlerImpl.inputBuffer(ProtonHandlerImpl.java:179) [artemis-proton-plug-1.3.0.amq-700005-redhat-1.jar:1.3.0.amq-700005-redhat-1]
      	at org.proton.plug.context.AbstractConnectionContext.inputBuffer(AbstractConnectionContext.java:110) [artemis-proton-plug-1.3.0.amq-700005-redhat-1.jar:1.3.0.amq-700005-redhat-1]
      	at org.apache.activemq.artemis.core.protocol.proton.ActiveMQProtonRemotingConnection.bufferReceived(ActiveMQProtonRemotingConnection.java:128) [artemis-amqp-protocol-1.3.0.amq-700005-redhat-1.jar:]
      	at org.apache.activemq.artemis.core.remoting.server.impl.RemotingServiceImpl$DelegatingBufferHandler.bufferReceived(RemotingServiceImpl.java:631) [artemis-server-1.3.0.amq-700005-redhat-1.jar:1.3.0.amq-700005-redhat-1]
      	at org.apache.activemq.artemis.core.remoting.impl.netty.ActiveMQChannelHandler.channelRead(ActiveMQChannelHandler.java:68) [artemis-core-client-1.3.0.amq-700005-redhat-1.jar:1.3.0.amq-700005-redhat-1]
      	at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:342) [netty-all-4.0.37.Final-redhat-2.jar:4.0.37.Final-redhat-2]
      	at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:328) [netty-all-4.0.37.Final-redhat-2.jar:4.0.37.Final-redhat-2]
      	at io.netty.channel.AbstractChannelHandlerContext.fireChannelRead(AbstractChannelHandlerContext.java:321) [netty-all-4.0.37.Final-redhat-2.jar:4.0.37.Final-redhat-2]
      	at io.netty.handler.codec.ByteToMessageDecoder.handlerRemoved(ByteToMessageDecoder.java:219) [netty-all-4.0.37.Final-redhat-2.jar:4.0.37.Final-redhat-2]
      	at io.netty.channel.DefaultChannelPipeline.callHandlerRemoved0(DefaultChannelPipeline.java:605) [netty-all-4.0.37.Final-redhat-2.jar:4.0.37.Final-redhat-2]
      	at io.netty.channel.DefaultChannelPipeline.remove(DefaultChannelPipeline.java:446) [netty-all-4.0.37.Final-redhat-2.jar:4.0.37.Final-redhat-2]
      	at io.netty.channel.DefaultChannelPipeline.remove(DefaultChannelPipeline.java:406) [netty-all-4.0.37.Final-redhat-2.jar:4.0.37.Final-redhat-2]
      	at org.apache.activemq.artemis.core.protocol.ProtocolHandler$ProtocolDecoder.decode(ProtocolHandler.java:189) [artemis-server-1.3.0.amq-700005-redhat-1.jar:1.3.0.amq-700005-redhat-1]
      	at io.netty.handler.codec.ByteToMessageDecoder.callDecode(ByteToMessageDecoder.java:411) [netty-all-4.0.37.Final-redhat-2.jar:4.0.37.Final-redhat-2]
      	at io.netty.handler.codec.ByteToMessageDecoder.channelRead(ByteToMessageDecoder.java:248) [netty-all-4.0.37.Final-redhat-2.jar:4.0.37.Final-redhat-2]
      	at org.apache.activemq.artemis.core.protocol.ProtocolHandler$ProtocolDecoder.channelRead(ProtocolHandler.java:132) [artemis-server-1.3.0.amq-700005-redhat-1.jar:1.3.0.amq-700005-redhat-1]
      	at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:342) [netty-all-4.0.37.Final-redhat-2.jar:4.0.37.Final-redhat-2]
      	at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:328) [netty-all-4.0.37.Final-redhat-2.jar:4.0.37.Final-redhat-2]
      	at io.netty.channel.AbstractChannelHandlerContext.fireChannelRead(AbstractChannelHandlerContext.java:321) [netty-all-4.0.37.Final-redhat-2.jar:4.0.37.Final-redhat-2]
      	at io.netty.channel.DefaultChannelPipeline$HeadContext.channelRead(DefaultChannelPipeline.java:1280) [netty-all-4.0.37.Final-redhat-2.jar:4.0.37.Final-redhat-2]
      	at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:342) [netty-all-4.0.37.Final-redhat-2.jar:4.0.37.Final-redhat-2]
      	at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:328) [netty-all-4.0.37.Final-redhat-2.jar:4.0.37.Final-redhat-2]
      	at io.netty.channel.DefaultChannelPipeline.fireChannelRead(DefaultChannelPipeline.java:890) [netty-all-4.0.37.Final-redhat-2.jar:4.0.37.Final-redhat-2]
      	at io.netty.channel.nio.AbstractNioByteChannel$NioByteUnsafe.read(AbstractNioByteChannel.java:131) [netty-all-4.0.37.Final-redhat-2.jar:4.0.37.Final-redhat-2]
      	at io.netty.channel.nio.NioEventLoop.processSelectedKey(NioEventLoop.java:564) [netty-all-4.0.37.Final-redhat-2.jar:4.0.37.Final-redhat-2]
      	at io.netty.channel.nio.NioEventLoop.processSelectedKeysOptimized(NioEventLoop.java:505) [netty-all-4.0.37.Final-redhat-2.jar:4.0.37.Final-redhat-2]
      	at io.netty.channel.nio.NioEventLoop.processSelectedKeys(NioEventLoop.java:419) [netty-all-4.0.37.Final-redhat-2.jar:4.0.37.Final-redhat-2]
      	at io.netty.channel.nio.NioEventLoop.run(NioEventLoop.java:391) [netty-all-4.0.37.Final-redhat-2.jar:4.0.37.Final-redhat-2]
      	at io.netty.util.concurrent.SingleThreadEventExecutor$2.run(SingleThreadEventExecutor.java:112) [netty-all-4.0.37.Final-redhat-2.jar:4.0.37.Final-redhat-2]
      	at java.lang.Thread.run(Thread.java:745) [rt.jar:1.8.0_51]
      
      12:31:53.479 DEBUG [org.apache.activemq.artemis.core.paging.impl.Page] Couldn't find any bindings for address=activemq.notifications on message=ServerMessage[messageID=7,durable=true,userID=null,priority=0, bodySize=512, timestamp=0,expiration=0, durable=true, address=activemq.notifications,properties=TypedProperties[_AMQ_NotifType=SECURITY_AUTHENTICATION_VIOLATION,_AMQ_NotifTimestamp=1476786713478]]@1143892773
      12:31:53.479 DEBUG [org.apache.activemq.artemis.core.paging.impl.Page] Message ServerMessage[messageID=7,durable=true,userID=null,priority=0, bodySize=512, timestamp=0,expiration=0, durable=true, address=activemq.notifications,properties=TypedProperties[_AMQ_NotifType=SECURITY_AUTHENTICATION_VIOLATION,_AMQ_NotifTimestamp=1476786713478]]@1143892773 is not going anywhere as it didn't have a binding on address:activemq.notifications
      12:31:53.501 DEBUG [org.apache.activemq.artemis.spi.core.security.jaas.PropertiesLoader] Initialized debug
      12:31:53.501 DEBUG [org.apache.activemq.artemis.spi.core.security.jaas.PropertiesLoginModule] login admin
      12:31:53.501 DEBUG [org.apache.activemq.artemis.spi.core.security.jaas.PropertiesLoginModule] commit, result: true
      12:31:53.501 FINE  [org.apache.qpid.proton.engine.impl.SaslImpl] SASL negotiation done: SaslImpl [_outcome=PN_SASL_OK, state=PN_SASL_PASS, done=true, role=SERVER]
      12:31:53.516 FINE  [proton.trace] IN: CH[0] : Open{ containerId='ID:9f065440-0dfe-416d-9f38-4fd06ccef29d:1', hostname='localhost', maxFrameSize=1048576, channelMax=32767, idleTimeOut=30000, outgoingLocales=null, incomingLocales=null, offeredCapabilities=null, desiredCapabilities=[sole-connection-for-container], properties={product=QpidJMS, version=0.11.0.redhat-1, platform=JVM: 1.8.0_51, 25.51-b03, Oracle Corporation, OS: Linux, 3.10.0-327.28.2.el7.x86_64, amd64}}
      12:31:53.558 FINE  [proton.trace] IN: CH[0] : Begin{remoteChannel=null, nextOutgoingId=1, incomingWindow=2047, outgoingWindow=2147483647, handleMax=65535, offeredCapabilities=null, desiredCapabilities=null, properties=null}
      12:31:53.560 DEBUG [org.apache.activemq.artemis.spi.core.security.jaas.PropertiesLoader] Initialized debug
      12:31:53.561 DEBUG [org.apache.activemq.artemis.spi.core.security.jaas.PropertiesLoginModule] login admin
      12:31:53.561 DEBUG [org.apache.activemq.artemis.spi.core.security.jaas.PropertiesLoginModule] commit, result: true
      12:31:53.587 FINE  [proton.trace] IN: CH[1] : Begin{remoteChannel=null, nextOutgoingId=1, incomingWindow=2047, outgoingWindow=2147483647, handleMax=65535, offeredCapabilities=null, desiredCapabilities=null, properties=null}
      12:31:53.588 DEBUG [org.apache.activemq.artemis.spi.core.security.jaas.PropertiesLoader] Initialized debug
      12:31:53.588 DEBUG [org.apache.activemq.artemis.spi.core.security.jaas.PropertiesLoginModule] login admin
      12:31:53.588 DEBUG [org.apache.activemq.artemis.spi.core.security.jaas.PropertiesLoginModule] commit, result: true
      12:31:53.616 FINE  [proton.trace] IN: CH[1] : Attach{name='qpid-jms:sender:ID:67cbfca5-1a6b-4097-a32c-ea37e949198a:1:1:1:lalaQ', handle=0, role=SENDER, sndSettleMode=UNSETTLED, rcvSettleMode=FIRST, source=Source{address='ID:67cbfca5-1a6b-4097-a32c-ea37e949198a:1:1:1', durable=NONE, expiryPolicy=SESSION_END, timeout=0, dynamic=false, dynamicNodeProperties=null, distributionMode=null, filter=null, defaultOutcome=null, outcomes=[amqp:accepted:list, amqp:rejected:list], capabilities=null}, target=Target{address='lalaQ', durable=NONE, expiryPolicy=SESSION_END, timeout=0, dynamic=false, dynamicNodeProperties=null, capabilities=[queue]}, unsettled=null, incompleteUnsettled=false, initialDeliveryCount=0, maxMessageSize=null, offeredCapabilities=null, desiredCapabilities=null, properties=null}
      12:31:53.634 FINE  [proton.trace] IN: CH[1] : Detach{handle=0, closed=true, error=null}
      12:31:53.967 DEBUG [org.apache.activemq.artemis.core.remoting.server.impl.RemotingServiceImpl] RemotingServiceImpl::removing connection ID -895651085
      12:31:53.967 WARN  [org.apache.activemq.artemis.core.client] AMQ212037: Connection failure has been detected: null [code=REMOTE_DISCONNECT]
      12:31:53.968 WARN  [org.apache.activemq.artemis.core.server] AMQ222061: Client connection failed, clearing up resources for session 1636658f-951e-11e6-9a60-525400061be7
      12:31:53.968 WARN  [org.apache.activemq.artemis.core.server] AMQ222107: Cleared up resources for session 1636658f-951e-11e6-9a60-525400061be7
      12:31:53.969 WARN  [org.apache.activemq.artemis.core.server] AMQ222061: Client connection failed, clearing up resources for session 163a8440-951e-11e6-9a60-525400061be7
      12:31:53.969 WARN  [org.apache.activemq.artemis.core.server] AMQ222107: Cleared up resources for session 163a8440-951e-11e6-9a60-525400061be7
      
      
      [0,root@r7x0_mtoth clients]$ cat /opt/jboss-amq-7-i0/etc/artemis-roles.properties
      amq=tckuser,superuser,administrator,admin
      [0,root@r7x0_mtoth clients]$ cat /opt/jboss-amq-7-i0/etc/artemis-users.properties
      tckuser=tckuser
      superuser=superuser
      administrator=administrator
      nobody=nobody
      admin=admin
      
      cat /opt/jboss-amq-7-i0/etc/login.config
      activemq {
      	org.apache.activemq.artemis.spi.core.security.jaas.PropertiesLoginModule sufficient
      	debug=True
      	org.apache.activemq.jaas.properties.role=artemis-roles.properties
      	org.apache.activemq.jaas.properties.user=artemis-users.properties
      	;
      
      };
      

      Same scenario, but queue is created upfront

      $ java -jar amqx.jar queue -a add -n lalaQ
      Queue 'lalaQ' created
      [0,root@r7x0_mtoth clients]$ ./aac1_sender.java.sh  -msg-content "lala message" --log-msgs dict --conn-username admin --conn-password admin --address lalaQ --log-lib=debug
      
      12:38:07,729 DEBUG Connection=amqp://localhost:5672?jms.username=admin&jms.password=admin
      12:38:08,507 INFO Best match for SASL auth was: SASL-PLAIN
      [47017011:0] -> Open{ containerId='ID:49930ef7-79e0-41bd-8213-4e7c46d11f97:1', hostname='localhost', maxFrameSize=1048576, channelMax=32767, idleTimeOut=30000, outgoingLocales=null, incomingLocales=null, offeredCapabilities=null, desiredCapabilities=[sole-connection-for-container], properties={product=QpidJMS, version=0.11.0.redhat-1, platform=JVM: 1.8.0_51, 25.51-b03, Oracle Corporation, OS: Linux, 3.10.0-327.28.2.el7.x86_64, amd64}}
      [47017011:0] <- Open{ containerId='amq', hostname='', maxFrameSize=4294967295, channelMax=65535, idleTimeOut=30000, outgoingLocales=null, incomingLocales=null, offeredCapabilities=[sole-connection-for-container, DELAYED_DELIVERY], desiredCapabilities=null, properties={product=apache-activemq-artemis, version=1.3.0.amq-700005-redhat-1}}
      [47017011:0] -> Begin{remoteChannel=null, nextOutgoingId=1, incomingWindow=2047, outgoingWindow=2147483647, handleMax=65535, offeredCapabilities=null, desiredCapabilities=null, properties=null}
      [47017011:0] <- Begin{remoteChannel=0, nextOutgoingId=1, incomingWindow=2147483647, outgoingWindow=2147483647, handleMax=65535, offeredCapabilities=null, desiredCapabilities=null, properties=null}
      12:38:08,575 DEBUG AmqpConnection { ID:c2c54d6c-d821-44fc-ac29-881f64cbfa5d:1 } is now open: 
      12:38:08,577 INFO Connection ID:c2c54d6c-d821-44fc-ac29-881f64cbfa5d:1 connected to remote Broker: amqp://localhost:5672
      [47017011:1] -> Begin{remoteChannel=null, nextOutgoingId=1, incomingWindow=2047, outgoingWindow=2147483647, handleMax=65535, offeredCapabilities=null, desiredCapabilities=null, properties=null}
      [47017011:1] <- Begin{remoteChannel=1, nextOutgoingId=1, incomingWindow=2147483647, outgoingWindow=2147483647, handleMax=65535, offeredCapabilities=null, desiredCapabilities=null, properties=null}
      12:38:08,606 DEBUG Creating AmqpFixedProducer for: lalaQ
      [47017011:1] -> Attach{name='qpid-jms:sender:ID:c2c54d6c-d821-44fc-ac29-881f64cbfa5d:1:1:1:lalaQ', handle=0, role=SENDER, sndSettleMode=UNSETTLED, rcvSettleMode=FIRST, source=Source{address='ID:c2c54d6c-d821-44fc-ac29-881f64cbfa5d:1:1:1', durable=NONE, expiryPolicy=SESSION_END, timeout=0, dynamic=false, dynamicNodeProperties=null, distributionMode=null, filter=null, defaultOutcome=null, outcomes=[amqp:accepted:list, amqp:rejected:list], capabilities=null}, target=Target{address='lalaQ', durable=NONE, expiryPolicy=SESSION_END, timeout=0, dynamic=false, dynamicNodeProperties=null, capabilities=[queue]}, unsettled=null, incompleteUnsettled=false, initialDeliveryCount=0, maxMessageSize=null, offeredCapabilities=null, desiredCapabilities=null, properties=null}
      [47017011:1] <- Attach{name='qpid-jms:sender:ID:c2c54d6c-d821-44fc-ac29-881f64cbfa5d:1:1:1:lalaQ', handle=0, role=RECEIVER, sndSettleMode=MIXED, rcvSettleMode=FIRST, source=Source{address='ID:c2c54d6c-d821-44fc-ac29-881f64cbfa5d:1:1:1', durable=NONE, expiryPolicy=SESSION_END, timeout=0, dynamic=false, dynamicNodeProperties=null, distributionMode=null, filter=null, defaultOutcome=null, outcomes=[amqp:accepted:list, amqp:rejected:list], capabilities=null}, target=Target{address='lalaQ', durable=NONE, expiryPolicy=SESSION_END, timeout=0, dynamic=false, dynamicNodeProperties=null, capabilities=[queue]}, unsettled=null, incompleteUnsettled=false, initialDeliveryCount=null, maxMessageSize=null, offeredCapabilities=null, desiredCapabilities=null, properties=null}
      [47017011:1] <- Flow{nextIncomingId=1, incomingWindow=2147483647, nextOutgoingId=1, outgoingWindow=2147483647, handle=0, deliveryCount=0, linkCredit=100, available=null, drain=false, echo=false, properties=null}
      [47017011:1] -> Transfer{handle=0, deliveryId=0, deliveryTag=0, messageFormat=0, settled=null, more=false, rcvSettleMode=null, state=null, resume=false, aborted=false, batchable=false} (148) "\x00Sp\xc0\x02\x01A\x00Sr\xc1)\x04\xa3\x0ex-opt-jms-destQ\x00\xa3\x12x-opt-jms-msg-typeQ\x05\x00Ss\xc0I\x0a\xa1/ID:c2c54d6c-d821-44fc-ac29-881f64cbfa5d:1:1:1-1@\xa1\x05lalaQ@@@@@@\x83\x00\x00\x01W\xd7^\xad\x18\x00Sw\xa1\x0clala message"
      [47017011:1] <- Disposition{role=RECEIVER, first=0, last=0, settled=true, state=Accepted{}, batchable=false}
      {'redelivered': False, 'reply_to': None, 'id': 'c2c54d6c-d821-44fc-ac29-881f64cbfa5d:1:1:1-1', 'user_id':None, 'correlation_id': None, 'priority': 4, 'durable': True, 'ttl': 0, 'type': None, 'expiration': 0, 'timestamp': 1476787088664, 'address': 'lalaQ', 'properties': {'JMSXDeliveryCount': 1}, 'content': 'lala message'}
      [47017011:1] -> End{error=null}
      [47017011:1] <- End{error=null}
      12:38:08,766 DEBUG AmqpSession { ID:c2c54d6c-d821-44fc-ac29-881f64cbfa5d:1:1 } is now closed: 
      [47017011:0] -> Close{error=null}
      [47017011:0] <- Close{error=null}
      12:38:08,775 DEBUG AmqpConnection { ID:c2c54d6c-d821-44fc-ac29-881f64cbfa5d:1 } is now closed: 
      12:38:08,776 DEBUG Transport connection remotely closed
      12:38:08,779 DEBUG Shutdown of ExecutorService: java.util.concurrent.ThreadPoolExecutor@3c22fc4c[Terminated, pool size = 0, active threads = 0, queued tasks = 0, completed tasks = 1] is shutdown: true and terminated: false took: 0.000 seconds.
      
      --------------------
      
      12:37:59.798 DEBUG [org.apache.activemq.artemis.core.server.reload.ReloadManagerImpl] Validating lastModified 1476781588000 modified = 1476781588000 on file:/opt/jboss-amq-7-i0/etc/broker.xml
      12:38:01.930 FINE  [sun.rmi.transport.tcp] RMI TCP Connection(1)-127.0.0.1: accepted socket from [127.0.0.1:60744]
      12:38:01.942 FINE  [sun.rmi.transport.tcp] RMI TCP Connection(1)-127.0.0.1: (port 1099) op = 80
      12:38:01.994 FINE  [sun.rmi.transport.tcp] RMI TCP Connection(2)-10.34.33.213: accepted socket from [10.34.33.213:41986]
      12:38:02.002 FINE  [sun.rmi.transport.tcp] RMI TCP Connection(2)-10.34.33.213: (port 1099) op = 80
      12:38:02.002 FINE  [sun.rmi.loader] RMI TCP Connection(2)-10.34.33.213: name = "[Ljava.rmi.server.ObjID;", codebase = ""
      12:38:02.004 FINE  [sun.rmi.loader] RMI TCP Connection(2)-10.34.33.213: name = "java.rmi.server.ObjID", codebase = ""
      12:38:02.006 FINE  [sun.rmi.loader] RMI TCP Connection(2)-10.34.33.213: name = "java.rmi.server.UID", codebase = ""
      12:38:02.008 FINE  [sun.rmi.loader] RMI TCP Connection(2)-10.34.33.213: name = "java.rmi.dgc.Lease", codebase = ""
      12:38:02.011 FINE  [sun.rmi.loader] RMI TCP Connection(2)-10.34.33.213: name = "java.rmi.dgc.VMID", codebase = ""
      12:38:02.013 FINE  [sun.rmi.loader] RMI TCP Connection(2)-10.34.33.213: name = "[B", codebase = ""
      12:38:02.020 FINE  [sun.rmi.transport.tcp] RMI TCP Connection(1)-127.0.0.1: (port 1099) op = 82
      12:38:02.020 FINE  [sun.rmi.transport.tcp] RMI TCP Connection(1)-127.0.0.1: (port 1099) op = 84
      12:38:02.022 FINE  [sun.rmi.transport.tcp] RMI TCP Connection(2)-10.34.33.213: (port 1099) op = 82
      12:38:02.023 FINE  [sun.rmi.transport.tcp] RMI TCP Connection(2)-10.34.33.213: (port 1099) op = 80
      12:38:02.023 FINE  [sun.rmi.loader] RMI TCP Connection(2)-10.34.33.213: name = "[Ljava.lang.String;", codebase = ""
      12:38:02.039 FINE  [sun.rmi.transport.tcp] RMI TCP Connection(2)-10.34.33.213: (port 1099) op = 82
      12:38:02.040 FINE  [sun.rmi.transport.tcp] RMI TCP Connection(2)-10.34.33.213: (port 1099) op = 80
      12:38:02.041 FINE  [sun.rmi.loader] RMI TCP Connection(2)-10.34.33.213: name = "[Ljava.rmi.server.ObjID;", codebase = ""
      12:38:02.041 FINE  [sun.rmi.loader] RMI TCP Connection(2)-10.34.33.213: name = "java.rmi.server.ObjID", codebase = ""
      12:38:02.041 FINE  [sun.rmi.loader] RMI TCP Connection(2)-10.34.33.213: name = "java.rmi.server.UID", codebase = ""
      12:38:02.042 FINE  [sun.rmi.loader] RMI TCP Connection(2)-10.34.33.213: name = "java.rmi.dgc.Lease", codebase = ""
      12:38:02.042 FINE  [sun.rmi.loader] RMI TCP Connection(2)-10.34.33.213: name = "java.rmi.dgc.VMID", codebase = ""
      12:38:02.042 FINE  [sun.rmi.loader] RMI TCP Connection(2)-10.34.33.213: name = "[B", codebase = ""
      12:38:02.046 FINE  [sun.rmi.transport.tcp] RMI TCP Connection(2)-10.34.33.213: (port 1099) op = 84
      12:38:02.046 FINE  [sun.rmi.transport.tcp] RMI TCP Connection(2)-10.34.33.213: (port 1099) op = 82
      12:38:02.047 FINE  [sun.rmi.transport.tcp] RMI TCP Connection(2)-10.34.33.213: (port 1099) op = 80
      12:38:02.093 FINE  [sun.rmi.transport.tcp] RMI TCP Connection(2)-10.34.33.213: (port 1099) op = 82
      12:38:02.095 FINE  [sun.rmi.transport.tcp] RMI TCP Connection(2)-10.34.33.213: (port 1099) op = 80
      12:38:02.096 FINE  [sun.rmi.loader] RMI TCP Connection(2)-10.34.33.213: name = "javax.management.ObjectName", codebase = ""
      12:38:02.143 FINE  [sun.rmi.transport.tcp] RMI TCP Connection(2)-10.34.33.213: (port 1099) op = 82
      12:38:02.145 FINE  [sun.rmi.transport.tcp] RMI TCP Connection(2)-10.34.33.213: (port 1099) op = 80
      12:38:02.145 FINE  [sun.rmi.loader] RMI TCP Connection(2)-10.34.33.213: name = "javax.management.ObjectName", codebase = ""
      12:38:02.146 FINE  [sun.rmi.loader] RMI TCP Connection(2)-10.34.33.213: name = "java.rmi.MarshalledObject", codebase = ""
      12:38:02.148 FINE  [sun.rmi.loader] RMI TCP Connection(2)-10.34.33.213: name = "[B", codebase = ""
      12:38:02.149 FINE  [sun.rmi.loader] RMI TCP Connection(2)-10.34.33.213: name = "[Ljava.lang.String;", codebase = ""
      12:38:02.155 FINE  [sun.rmi.loader] RMI TCP Connection(2)-10.34.33.213: name = "[Ljava.lang.Object;", codebase = ""
      12:38:02.156 FINE  [sun.rmi.loader] RMI TCP Connection(2)-10.34.33.213: name = "java.lang.Boolean", codebase = ""
      12:38:02.160 DEBUG [org.apache.activemq.artemis.core.paging.impl.Page] ClusterCommunication::Sending notification for addBinding LocalQueueBinding [address=lalaQ, queue=QueueImpl[name=lalaQ, postOffice=PostOfficeImpl [server=ActiveMQServerImpl::serverUUID=b45c31f8-951d-11e6-9a60-525400061be7]]@57d07335, filter=null, name=lalaQ, clusterName=lalaQb45c31f8-951d-11e6-9a60-525400061be7] from server ActiveMQServerImpl::serverUUID=b45c31f8-951d-11e6-9a60-525400061be7
      12:38:02.162 DEBUG [org.apache.activemq.artemis.core.paging.impl.Page] Couldn't find any bindings for address=activemq.notifications on message=ServerMessage[messageID=11,durable=true,userID=null,priority=0, bodySize=512, timestamp=0,expiration=0, durable=true, address=activemq.notifications,properties=TypedProperties[_AMQ_Binding_Type=0,_AMQ_RoutingName=lalaQ,_AMQ_Distance=0,_AMQ_Address=lalaQ,_AMQ_NotifType=BINDING_ADDED,_AMQ_Binding_ID=10,_AMQ_NotifTimestamp=1476787082161,_AMQ_ClusterName=lalaQb45c31f8-951d-11e6-9a60-525400061be7,foobar=f1ea4b13-951e-11e6-9a60-525400061be7]]@1230167572
      12:38:02.162 DEBUG [org.apache.activemq.artemis.core.paging.impl.Page] Message ServerMessage[messageID=11,durable=true,userID=null,priority=0, bodySize=512, timestamp=0,expiration=0, durable=true, address=activemq.notifications,properties=TypedProperties[_AMQ_Binding_Type=0,_AMQ_RoutingName=lalaQ,_AMQ_Distance=0,_AMQ_Address=lalaQ,_AMQ_NotifType=BINDING_ADDED,_AMQ_Binding_ID=10,_AMQ_NotifTimestamp=1476787082161,_AMQ_ClusterName=lalaQb45c31f8-951d-11e6-9a60-525400061be7,foobar=f1ea4b13-951e-11e6-9a60-525400061be7]]@1230167572 is not going anywhere as it didn't have a binding on address:activemq.notifications
      12:38:02.171 DEBUG [org.apache.activemq.artemis.core.server.management.impl.ManagementServiceImpl] registered address org.apache.activemq.artemis:type=Broker,brokerName="amq",module=Core,serviceType=Address,name="lalaQ"
      12:38:02.180 DEBUG [org.apache.activemq.artemis.core.server.management.impl.ManagementServiceImpl] registered queue org.apache.activemq.artemis:type=Broker,brokerName="amq",module=Core,serviceType=Queue,address="lalaQ",name="lalaQ"
      12:38:02.196 FINE  [sun.rmi.transport.tcp] RMI TCP Connection(1)-127.0.0.1: (port 1099) connection closed
      12:38:02.196 FINE  [sun.rmi.transport.tcp] RMI TCP Connection(1)-127.0.0.1: close connection
      12:38:02.197 FINE  [sun.rmi.transport.tcp] RMI TCP Connection(2)-10.34.33.213: (port 1099) connection closed
      12:38:02.197 FINE  [sun.rmi.transport.tcp] RMI TCP Connection(2)-10.34.33.213: close connection
      12:38:04.799 DEBUG [org.apache.activemq.artemis.core.server.reload.ReloadManagerImpl] Validating lastModified 1476781588000 modified = 1476781588000 on file:/opt/jboss-amq-7-i0/etc/broker.xml
      12:38:08.482 DEBUG [org.apache.activemq.artemis.spi.core.security.jaas.PropertiesLoader] Initialized debug
      12:38:08.483 DEBUG [org.apache.activemq.artemis.spi.core.security.jaas.PropertiesLoginModule] abort
      12:38:08.484 DEBUG [org.apache.activemq.artemis.spi.core.security.ActiveMQJAASSecurityManager] Couldn't validate user: javax.security.auth.login.FailedLoginException: user name is null
      	at org.apache.activemq.artemis.spi.core.security.jaas.PropertiesLoginModule.login(PropertiesLoginModule.java:88) [artemis-server-1.3.0.amq-700005-redhat-1.jar:1.3.0.amq-700005-redhat-1]
      	at sun.reflect.GeneratedMethodAccessor8.invoke(Unknown Source) [:1.8.0_51]
      	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) [rt.jar:1.8.0_51]
      	at java.lang.reflect.Method.invoke(Method.java:497) [rt.jar:1.8.0_51]
      	at javax.security.auth.login.LoginContext.invoke(LoginContext.java:755) [rt.jar:1.8.0_51]
      	at javax.security.auth.login.LoginContext.access$000(LoginContext.java:195) [rt.jar:1.8.0_51]
      	at javax.security.auth.login.LoginContext$4.run(LoginContext.java:682) [rt.jar:1.8.0_51]
      	at javax.security.auth.login.LoginContext$4.run(LoginContext.java:680) [rt.jar:1.8.0_51]
      	at java.security.AccessController.doPrivileged(Native Method) [rt.jar:1.8.0_51]
      	at javax.security.auth.login.LoginContext.invokePriv(LoginContext.java:680) [rt.jar:1.8.0_51]
      	at javax.security.auth.login.LoginContext.login(LoginContext.java:587) [rt.jar:1.8.0_51]
      	at org.apache.activemq.artemis.spi.core.security.ActiveMQJAASSecurityManager.getAuthenticatedSubject(ActiveMQJAASSecurityManager.java:185) [artemis-server-1.3.0.amq-700005-redhat-1.jar:1.3.0.amq-700005-redhat-1]
      	at org.apache.activemq.artemis.spi.core.security.ActiveMQJAASSecurityManager.validateUser(ActiveMQJAASSecurityManager.java:91) [artemis-server-1.3.0.amq-700005-redhat-1.jar:1.3.0.amq-700005-redhat-1]
      	at org.apache.activemq.artemis.core.security.impl.SecurityStoreImpl.authenticate(SecurityStoreImpl.java:127) [artemis-server-1.3.0.amq-700005-redhat-1.jar:1.3.0.amq-700005-redhat-1]
      	at org.apache.activemq.artemis.core.protocol.proton.plug.ActiveMQProtonConnectionCallback.isSupportsAnonymous(ActiveMQProtonConnectionCallback.java:120) [artemis-amqp-protocol-1.3.0.amq-700005-redhat-1.jar:]
      	at org.apache.activemq.artemis.core.protocol.proton.plug.ActiveMQProtonConnectionCallback.getSASLMechnisms(ActiveMQProtonConnectionCallback.java:107) [artemis-amqp-protocol-1.3.0.amq-700005-redhat-1.jar:]
      	at org.proton.plug.context.AbstractConnectionContext$LocalListener.onAuthInit(AbstractConnectionContext.java:204) [artemis-proton-plug-1.3.0.amq-700005-redhat-1.jar:1.3.0.amq-700005-redhat-1]
      	at org.proton.plug.handler.impl.ProtonHandlerImpl.dispatchAuth(ProtonHandlerImpl.java:353) [artemis-proton-plug-1.3.0.amq-700005-redhat-1.jar:1.3.0.amq-700005-redhat-1]
      	at org.proton.plug.handler.impl.ProtonHandlerImpl.inputBuffer(ProtonHandlerImpl.java:179) [artemis-proton-plug-1.3.0.amq-700005-redhat-1.jar:1.3.0.amq-700005-redhat-1]
      	at org.proton.plug.context.AbstractConnectionContext.inputBuffer(AbstractConnectionContext.java:110) [artemis-proton-plug-1.3.0.amq-700005-redhat-1.jar:1.3.0.amq-700005-redhat-1]
      	at org.apache.activemq.artemis.core.protocol.proton.ActiveMQProtonRemotingConnection.bufferReceived(ActiveMQProtonRemotingConnection.java:128) [artemis-amqp-protocol-1.3.0.amq-700005-redhat-1.jar:]
      	at org.apache.activemq.artemis.core.remoting.server.impl.RemotingServiceImpl$DelegatingBufferHandler.bufferReceived(RemotingServiceImpl.java:631) [artemis-server-1.3.0.amq-700005-redhat-1.jar:1.3.0.amq-700005-redhat-1]
      	at org.apache.activemq.artemis.core.remoting.impl.netty.ActiveMQChannelHandler.channelRead(ActiveMQChannelHandler.java:68) [artemis-core-client-1.3.0.amq-700005-redhat-1.jar:1.3.0.amq-700005-redhat-1]
      	at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:342) [netty-all-4.0.37.Final-redhat-2.jar:4.0.37.Final-redhat-2]
      	at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:328) [netty-all-4.0.37.Final-redhat-2.jar:4.0.37.Final-redhat-2]
      	at io.netty.channel.AbstractChannelHandlerContext.fireChannelRead(AbstractChannelHandlerContext.java:321) [netty-all-4.0.37.Final-redhat-2.jar:4.0.37.Final-redhat-2]
      	at io.netty.handler.codec.ByteToMessageDecoder.handlerRemoved(ByteToMessageDecoder.java:219) [netty-all-4.0.37.Final-redhat-2.jar:4.0.37.Final-redhat-2]
      	at io.netty.channel.DefaultChannelPipeline.callHandlerRemoved0(DefaultChannelPipeline.java:605) [netty-all-4.0.37.Final-redhat-2.jar:4.0.37.Final-redhat-2]
      	at io.netty.channel.DefaultChannelPipeline.remove(DefaultChannelPipeline.java:446) [netty-all-4.0.37.Final-redhat-2.jar:4.0.37.Final-redhat-2]
      	at io.netty.channel.DefaultChannelPipeline.remove(DefaultChannelPipeline.java:406) [netty-all-4.0.37.Final-redhat-2.jar:4.0.37.Final-redhat-2]
      	at org.apache.activemq.artemis.core.protocol.ProtocolHandler$ProtocolDecoder.decode(ProtocolHandler.java:189) [artemis-server-1.3.0.amq-700005-redhat-1.jar:1.3.0.amq-700005-redhat-1]
      	at io.netty.handler.codec.ByteToMessageDecoder.callDecode(ByteToMessageDecoder.java:411) [netty-all-4.0.37.Final-redhat-2.jar:4.0.37.Final-redhat-2]
      	at io.netty.handler.codec.ByteToMessageDecoder.channelRead(ByteToMessageDecoder.java:248) [netty-all-4.0.37.Final-redhat-2.jar:4.0.37.Final-redhat-2]
      	at org.apache.activemq.artemis.core.protocol.ProtocolHandler$ProtocolDecoder.channelRead(ProtocolHandler.java:132) [artemis-server-1.3.0.amq-700005-redhat-1.jar:1.3.0.amq-700005-redhat-1]
      	at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:342) [netty-all-4.0.37.Final-redhat-2.jar:4.0.37.Final-redhat-2]
      	at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:328) [netty-all-4.0.37.Final-redhat-2.jar:4.0.37.Final-redhat-2]
      	at io.netty.channel.AbstractChannelHandlerContext.fireChannelRead(AbstractChannelHandlerContext.java:321) [netty-all-4.0.37.Final-redhat-2.jar:4.0.37.Final-redhat-2]
      	at io.netty.channel.DefaultChannelPipeline$HeadContext.channelRead(DefaultChannelPipeline.java:1280) [netty-all-4.0.37.Final-redhat-2.jar:4.0.37.Final-redhat-2]
      	at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:342) [netty-all-4.0.37.Final-redhat-2.jar:4.0.37.Final-redhat-2]
      	at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:328) [netty-all-4.0.37.Final-redhat-2.jar:4.0.37.Final-redhat-2]
      	at io.netty.channel.DefaultChannelPipeline.fireChannelRead(DefaultChannelPipeline.java:890) [netty-all-4.0.37.Final-redhat-2.jar:4.0.37.Final-redhat-2]
      	at io.netty.channel.nio.AbstractNioByteChannel$NioByteUnsafe.read(AbstractNioByteChannel.java:131) [netty-all-4.0.37.Final-redhat-2.jar:4.0.37.Final-redhat-2]
      	at io.netty.channel.nio.NioEventLoop.processSelectedKey(NioEventLoop.java:564) [netty-all-4.0.37.Final-redhat-2.jar:4.0.37.Final-redhat-2]
      	at io.netty.channel.nio.NioEventLoop.processSelectedKeysOptimized(NioEventLoop.java:505) [netty-all-4.0.37.Final-redhat-2.jar:4.0.37.Final-redhat-2]
      	at io.netty.channel.nio.NioEventLoop.processSelectedKeys(NioEventLoop.java:419) [netty-all-4.0.37.Final-redhat-2.jar:4.0.37.Final-redhat-2]
      	at io.netty.channel.nio.NioEventLoop.run(NioEventLoop.java:391) [netty-all-4.0.37.Final-redhat-2.jar:4.0.37.Final-redhat-2]
      	at io.netty.util.concurrent.SingleThreadEventExecutor$2.run(SingleThreadEventExecutor.java:112) [netty-all-4.0.37.Final-redhat-2.jar:4.0.37.Final-redhat-2]
      	at java.lang.Thread.run(Thread.java:745) [rt.jar:1.8.0_51]
      
      12:38:08.491 DEBUG [org.apache.activemq.artemis.core.paging.impl.Page] Couldn't find any bindings for address=activemq.notifications on message=ServerMessage[messageID=12,durable=true,userID=null,priority=0, bodySize=512, timestamp=0,expiration=0, durable=true, address=activemq.notifications,properties=TypedProperties[_AMQ_NotifType=SECURITY_AUTHENTICATION_VIOLATION,_AMQ_NotifTimestamp=1476787088491]]@1587248775
      12:38:08.491 DEBUG [org.apache.activemq.artemis.core.paging.impl.Page] Message ServerMessage[messageID=12,durable=true,userID=null,priority=0, bodySize=512, timestamp=0,expiration=0, durable=true, address=activemq.notifications,properties=TypedProperties[_AMQ_NotifType=SECURITY_AUTHENTICATION_VIOLATION,_AMQ_NotifTimestamp=1476787088491]]@1587248775 is not going anywhere as it didn't have a binding on address:activemq.notifications
      12:38:08.513 DEBUG [org.apache.activemq.artemis.spi.core.security.jaas.PropertiesLoader] Initialized debug
      12:38:08.513 DEBUG [org.apache.activemq.artemis.spi.core.security.jaas.PropertiesLoginModule] login admin
      12:38:08.513 DEBUG [org.apache.activemq.artemis.spi.core.security.jaas.PropertiesLoginModule] commit, result: true
      12:38:08.513 FINE  [org.apache.qpid.proton.engine.impl.SaslImpl] SASL negotiation done: SaslImpl [_outcome=PN_SASL_OK, state=PN_SASL_PASS, done=true, role=SERVER]
      12:38:08.527 FINE  [proton.trace] IN: CH[0] : Open{ containerId='ID:49930ef7-79e0-41bd-8213-4e7c46d11f97:1', hostname='localhost', maxFrameSize=1048576, channelMax=32767, idleTimeOut=30000, outgoingLocales=null, incomingLocales=null, offeredCapabilities=null, desiredCapabilities=[sole-connection-for-container], properties={product=QpidJMS, version=0.11.0.redhat-1, platform=JVM: 1.8.0_51, 25.51-b03, Oracle Corporation, OS: Linux, 3.10.0-327.28.2.el7.x86_64, amd64}}
      12:38:08.543 FINE  [proton.trace] IN: CH[0] : Begin{remoteChannel=null, nextOutgoingId=1, incomingWindow=2047, outgoingWindow=2147483647, handleMax=65535, offeredCapabilities=null, desiredCapabilities=null, properties=null}
      12:38:08.544 DEBUG [org.apache.activemq.artemis.spi.core.security.jaas.PropertiesLoader] Initialized debug
      12:38:08.545 DEBUG [org.apache.activemq.artemis.spi.core.security.jaas.PropertiesLoginModule] login admin
      12:38:08.545 DEBUG [org.apache.activemq.artemis.spi.core.security.jaas.PropertiesLoginModule] commit, result: true
      12:38:08.593 FINE  [proton.trace] IN: CH[1] : Begin{remoteChannel=null, nextOutgoingId=1, incomingWindow=2047, outgoingWindow=2147483647, handleMax=65535, offeredCapabilities=null, desiredCapabilities=null, properties=null}
      12:38:08.594 DEBUG [org.apache.activemq.artemis.spi.core.security.jaas.PropertiesLoader] Initialized debug
      12:38:08.595 DEBUG [org.apache.activemq.artemis.spi.core.security.jaas.PropertiesLoginModule] login admin
      12:38:08.595 DEBUG [org.apache.activemq.artemis.spi.core.security.jaas.PropertiesLoginModule] commit, result: true
      12:38:08.620 FINE  [proton.trace] IN: CH[1] : Attach{name='qpid-jms:sender:ID:c2c54d6c-d821-44fc-ac29-881f64cbfa5d:1:1:1:lalaQ', handle=0, role=SENDER, sndSettleMode=UNSETTLED, rcvSettleMode=FIRST, source=Source{address='ID:c2c54d6c-d821-44fc-ac29-881f64cbfa5d:1:1:1', durable=NONE, expiryPolicy=SESSION_END, timeout=0, dynamic=false, dynamicNodeProperties=null, distributionMode=null, filter=null, defaultOutcome=null, outcomes=[amqp:accepted:list, amqp:rejected:list], capabilities=null}, target=Target{address='lalaQ', durable=NONE, expiryPolicy=SESSION_END, timeout=0, dynamic=false, dynamicNodeProperties=null, capabilities=[queue]}, unsettled=null, incompleteUnsettled=false, initialDeliveryCount=0, maxMessageSize=null, offeredCapabilities=null, desiredCapabilities=null, properties=null}
      12:38:08.685 FINE  [proton.trace] IN: CH[1] : Transfer{handle=0, deliveryId=0, deliveryTag=0, messageFormat=0, settled=null, more=false, rcvSettleMode=null, state=null, resume=false, aborted=false, batchable=false}[\x00Sp\xc0\x02\x01A\x00Sr\xc1)\x04\xa3\x0ex-opt-jms-destQ\x00\xa3\x12x-opt-jms-msg-typeQ\x05\x00Ss\xc0I\x0a\xa1/ID:c2c54d6c-d821-44fc-ac29-881f64cbfa5d:1:1:1-1@\xa1\x05lalaQ@@@@@@\x83\x00\x00\x01W\xd7^\xad\x18\x00Sw\xa1\x0clala message]
      12:38:08.723 DEBUG [org.apache.activemq.artemis.spi.core.security.jaas.PropertiesLoader] Initialized debug
      12:38:08.723 DEBUG [org.apache.activemq.artemis.spi.core.security.jaas.PropertiesLoginModule] login admin
      12:38:08.724 DEBUG [org.apache.activemq.artemis.spi.core.security.jaas.PropertiesLoginModule] commit, result: true
      12:38:08.735 DEBUG [org.apache.activemq.artemis.core.server.impl.QueueImpl] QueueImpl[name=lalaQ, postOffice=PostOfficeImpl [server=ActiveMQServerImpl::serverUUID=b45c31f8-951d-11e6-9a60-525400061be7]]@57d07335 doing deliver. messageReferences=0
      12:38:08.760 FINE  [proton.trace] IN: CH[1] : End{error=null}
      12:38:08.768 FINE  [proton.trace] IN: CH[0] : Close{error=null}
      12:38:08.772 DEBUG [org.apache.activemq.artemis.core.remoting.server.impl.RemotingServiceImpl] RemotingServiceImpl::removing connection ID 799896799
      12:38:08.773 WARN  [org.apache.activemq.artemis.core.client] AMQ212037: Connection failure has been detected: null [code=REMOTE_DISCONNECT]
      12:38:09.800 DEBUG [org.apache.activemq.artemis.core.server.reload.ReloadManagerImpl] Validating lastModified 1476781588000 modified = 1476781588000 on file:/opt/jboss-amq-7-i0/etc/broker.xml
      12:38:14.801 DEBUG [org.apache.activemq.artemis.core.server.reload.ReloadManagerImpl] Validating lastModified 1476781588000 modified = 1476781588000 on file:/opt/jboss-amq-7-i0/etc/broker.xml
      

            gaohoward Howard Gao
            mtoth@redhat.com Michal Toth
            Petra Svobodova Petra Svobodova (Inactive)
            Votes:
            0 Vote for this issue
            Watchers:
            4 Start watching this issue

              Created:
              Updated:
              Resolved: