-
Bug
-
Resolution: Done
-
Minor
-
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