As suggested by Gordon => Running with env var PN_TRACE_FRM=1 and post the
protocol trace you see.

Configured a proper logger and got more details:


2017-08-08T12:27:31.202 TRACE [org.apache.qpid.jms.transports.TransportSupport]
- Getting SSLContext instance using protocol: TLS
2017-08-08T12:27:31.562 TRACE [org.apache.qpid.jms.transports.TransportSupport]
- Default protocols from the SSLEngine: [SSLv2Hello, TLSv1, TLSv1.1,
TLSv1.2]
2017-08-08T12:27:31.562 TRACE [org.apache.qpid.jms.transports.TransportSupport]
- Disabled protocols: [SSLv2Hello, SSLv3]
2017-08-08T12:27:31.562 TRACE [org.apache.qpid.jms.transports.TransportSupport]
- Enabled protocols: [TLSv1, TLSv1.1, TLSv1.2]
2017-08-08T12:27:31.562 TRACE [org.apache.qpid.jms.transports.TransportSupport]
- Default cipher suites from the SSLEngine:
[TLS_ECDHE_ECDSA_WITH_AES_128_CBC_SHA256,
TLS_ECDHE_RSA_WITH_AES_128_CBC_SHA256, TLS_RSA_WITH_AES_128_CBC_SHA256,
TLS_ECDH_ECDSA_WITH_AES_128_CBC_SHA256, TLS_ECDH_RSA_WITH_AES_128_CBC_SHA256,
TLS_DHE_RSA_WITH_AES_128_CBC_SHA256, TLS_DHE_DSS_WITH_AES_128_CBC_SHA256,
TLS_ECDHE_ECDSA_WITH_AES_128_CBC_SHA, TLS_ECDHE_RSA_WITH_AES_128_CBC_SHA,
TLS_RSA_WITH_AES_128_CBC_SHA, TLS_ECDH_ECDSA_WITH_AES_128_CBC_SHA,
TLS_ECDH_RSA_WITH_AES_128_CBC_SHA, TLS_DHE_RSA_WITH_AES_128_CBC_SHA,
TLS_DHE_DSS_WITH_AES_128_CBC_SHA, TLS_ECDHE_ECDSA_WITH_AES_128_GCM_SHA256,
TLS_ECDHE_RSA_WITH_AES_128_GCM_SHA256, TLS_RSA_WITH_AES_128_GCM_SHA256,
TLS_ECDH_ECDSA_WITH_AES_128_GCM_SHA256, TLS_ECDH_RSA_WITH_AES_128_GCM_SHA256,
TLS_DHE_RSA_WITH_AES_128_GCM_SHA256, TLS_DHE_DSS_WITH_AES_128_GCM_SHA256,
TLS_ECDHE_ECDSA_WITH_3DES_EDE_CBC_SHA, TLS_ECDHE_RSA_WITH_3DES_EDE_CBC_SHA,
SSL_RSA_WITH_3DES_EDE_CBC_SHA, TLS_ECDH_ECDSA_WITH_3DES_EDE_CBC_SHA,
TLS_ECDH_RSA_WITH_3DES_EDE_CBC_SHA, SSL_DHE_RSA_WITH_3DES_EDE_CBC_SHA,
SSL_DHE_DSS_WITH_3DES_EDE_CBC_SHA, TLS_EMPTY_RENEGOTIATION_INFO_SCSV]
2017-08-08T12:27:31.563 TRACE [org.apache.qpid.jms.transports.TransportSupport]
- Enabled cipher suites: [TLS_ECDHE_ECDSA_WITH_AES_128_CBC_SHA256,
TLS_ECDHE_RSA_WITH_AES_128_CBC_SHA256, TLS_RSA_WITH_AES_128_CBC_SHA256,
TLS_ECDH_ECDSA_WITH_AES_128_CBC_SHA256, TLS_ECDH_RSA_WITH_AES_128_CBC_SHA256,
TLS_DHE_RSA_WITH_AES_128_CBC_SHA256, TLS_DHE_DSS_WITH_AES_128_CBC_SHA256,
TLS_ECDHE_ECDSA_WITH_AES_128_CBC_SHA, TLS_ECDHE_RSA_WITH_AES_128_CBC_SHA,
TLS_RSA_WITH_AES_128_CBC_SHA, TLS_ECDH_ECDSA_WITH_AES_128_CBC_SHA,
TLS_ECDH_RSA_WITH_AES_128_CBC_SHA, TLS_DHE_RSA_WITH_AES_128_CBC_SHA,
TLS_DHE_DSS_WITH_AES_128_CBC_SHA, TLS_ECDHE_ECDSA_WITH_AES_128_GCM_SHA256,
TLS_ECDHE_RSA_WITH_AES_128_GCM_SHA256, TLS_RSA_WITH_AES_128_GCM_SHA256,
TLS_ECDH_ECDSA_WITH_AES_128_GCM_SHA256, TLS_ECDH_RSA_WITH_AES_128_GCM_SHA256,
TLS_DHE_RSA_WITH_AES_128_GCM_SHA256, TLS_DHE_DSS_WITH_AES_128_GCM_SHA256,
TLS_ECDHE_ECDSA_WITH_3DES_EDE_CBC_SHA, TLS_ECDHE_RSA_WITH_3DES_EDE_CBC_SHA,
SSL_RSA_WITH_3DES_EDE_CBC_SHA, TLS_ECDH_ECDSA_WITH_3DES_EDE_CBC_SHA,
TLS_ECDH_RSA_WITH_3DES_EDE_CBC_SHA, SSL_DHE_RSA_WITH_3DES_EDE_CBC_SHA,
SSL_DHE_DSS_WITH_3DES_EDE_CBC_SHA, TLS_EMPTY_RENEGOTIATION_INFO_SCSV]
2017-08-08T12:27:31.571 DEBUG
[io.netty.util.internal.logging.InternalLoggerFactory]
- Using SLF4J as the default logging framework
2017-08-08T12:27:31.586 DEBUG [io.netty.util.internal.PlatformDependent] -
-Dio.netty.noUnsafe: false
2017-08-08T12:27:31.587 DEBUG [io.netty.util.internal.PlatformDependent0] -
sun.misc.Unsafe.theUnsafe: available
2017-08-08T12:27:31.588 DEBUG [io.netty.util.internal.PlatformDependent0] -
sun.misc.Unsafe.copyMemory: available
2017-08-08T12:27:31.588 DEBUG [io.netty.util.internal.PlatformDependent0] -
java.nio.Buffer.address: available
2017-08-08T12:27:31.588 DEBUG [io.netty.util.internal.PlatformDependent0] -
direct buffer constructor: available
2017-08-08T12:27:31.589 DEBUG [io.netty.util.internal.PlatformDependent0] -
java.nio.Bits.unaligned: available, true
2017-08-08T12:27:31.589 DEBUG [io.netty.util.internal.PlatformDependent0] -
java.nio.DirectByteBuffer.<init>(long, int): available
2017-08-08T12:27:31.589 DEBUG [io.netty.util.internal.Cleaner0] -
java.nio.ByteBuffer.cleaner(): available
2017-08-08T12:27:31.590 DEBUG [io.netty.util.internal.PlatformDependent] -
Platform: Windows
2017-08-08T12:27:31.590 DEBUG [io.netty.util.internal.PlatformDependent] -
Java version: 8
2017-08-08T12:27:31.590 DEBUG [io.netty.util.internal.PlatformDependent] -
sun.misc.Unsafe: available
2017-08-08T12:27:31.590 DEBUG [io.netty.util.internal.PlatformDependent] -
-Dio.netty.tmpdir: C:\Users\myuser\AppData\Local\Temp (java.io.tmpdir)
2017-08-08T12:27:31.590 DEBUG [io.netty.util.internal.PlatformDependent] -
-Dio.netty.bitMode: 64 (sun.arch.data.model)
2017-08-08T12:27:31.591 DEBUG [io.netty.util.internal.PlatformDependent] -
-Dio.netty.noPreferDirect: false
2017-08-08T12:27:31.591 DEBUG [io.netty.util.internal.PlatformDependent] -
io.netty.maxDirectMemory: 3743416320 bytes
2017-08-08T12:27:31.594 TRACE
[org.apache.qpid.jms.transports.netty.NettyTcpTransport]
- Netty Transport using NIO mode
2017-08-08T12:27:31.597 DEBUG [io.netty.channel.MultithreadEventLoopGroup]
- -Dio.netty.eventLoopThreads: 16
2017-08-08T12:27:31.607 DEBUG [io.netty.channel.nio.NioEventLoop] -
-Dio.netty.noKeySetOptimization: false
2017-08-08T12:27:31.607 DEBUG [io.netty.channel.nio.NioEventLoop] -
-Dio.netty.selectorAutoRebuildThreshold: 512
2017-08-08T12:27:31.608 DEBUG [io.netty.util.internal.PlatformDependent] -
org.jctools-core.MpscChunkedArrayQueue: available
2017-08-08T12:27:31.620 TRACE [io.netty.channel.nio.NioEventLoop] -
instrumented a special java.util.Set into: sun.nio.ch.
WindowsSelectorImpl@830d696
2017-08-08T12:27:31.647 DEBUG [io.netty.channel.DefaultChannelId] -
-Dio.netty.processId: 11272 (auto-detected)
2017-08-08T12:27:31.649 DEBUG [io.netty.util.NetUtil] -
-Djava.net.preferIPv4Stack: false
2017-08-08T12:27:31.649 DEBUG [io.netty.util.NetUtil] -
-Djava.net.preferIPv6Addresses:
false
2017-08-08T12:27:31.782 DEBUG [io.netty.util.NetUtil] - Loopback interface:
lo (Software Loopback Interface 1, 127.0.0.1)
2017-08-08T12:27:31.782 DEBUG [io.netty.util.NetUtil] -
\proc\sys\net\core\somaxconn: 200 (non-existent)
2017-08-08T12:27:31.942 DEBUG [io.netty.channel.DefaultChannelId] -
-Dio.netty.machineId: 00:00:00:00:00:00:00:e0 (auto-detected)
2017-08-08T12:27:31.950 DEBUG [io.netty.util.ResourceLeakDetector] -
-Dio.netty.leakDetection.level: simple
2017-08-08T12:27:31.950 DEBUG [io.netty.util.ResourceLeakDetector] -
-Dio.netty.leakDetection.maxRecords: 4
2017-08-08T12:27:31.965 DEBUG [io.netty.buffer.PooledByteBufAllocator] -
-Dio.netty.allocator.numHeapArenas: 16
2017-08-08T12:27:31.965 DEBUG [io.netty.buffer.PooledByteBufAllocator] -
-Dio.netty.allocator.numDirectArenas: 16
2017-08-08T12:27:31.965 DEBUG [io.netty.buffer.PooledByteBufAllocator] -
-Dio.netty.allocator.pageSize: 8192
2017-08-08T12:27:31.965 DEBUG [io.netty.buffer.PooledByteBufAllocator] -
-Dio.netty.allocator.maxOrder: 11
2017-08-08T12:27:31.965 DEBUG [io.netty.buffer.PooledByteBufAllocator] -
-Dio.netty.allocator.chunkSize: 16777216
2017-08-08T12:27:31.965 DEBUG [io.netty.buffer.PooledByteBufAllocator] -
-Dio.netty.allocator.tinyCacheSize: 512
2017-08-08T12:27:31.965 DEBUG [io.netty.buffer.PooledByteBufAllocator] -
-Dio.netty.allocator.smallCacheSize: 256
2017-08-08T12:27:31.965 DEBUG [io.netty.buffer.PooledByteBufAllocator] -
-Dio.netty.allocator.normalCacheSize: 64
2017-08-08T12:27:31.965 DEBUG [io.netty.buffer.PooledByteBufAllocator] -
-Dio.netty.allocator.maxCachedBufferCapacity: 32768
2017-08-08T12:27:31.965 DEBUG [io.netty.buffer.PooledByteBufAllocator] -
-Dio.netty.allocator.cacheTrimInterval: 8192
2017-08-08T12:27:31.965 DEBUG [io.netty.buffer.PooledByteBufAllocator] -
-Dio.netty.allocator.useCacheForAllThreads: true
2017-08-08T12:27:31.972 DEBUG [io.netty.buffer.ByteBufUtil] -
-Dio.netty.allocator.type: pooled
2017-08-08T12:27:31.972 DEBUG [io.netty.buffer.ByteBufUtil] -
-Dio.netty.threadLocalDirectBufferSize:
65536
2017-08-08T12:27:31.972 DEBUG [io.netty.buffer.ByteBufUtil] -
-Dio.netty.maxThreadLocalCharBufferSize:
16384
2017-08-08T12:27:32.048 DEBUG [io.netty.util.Recycler] -
-Dio.netty.recycler.maxCapacityPerThread: 32768
2017-08-08T12:27:32.048 DEBUG [io.netty.util.Recycler] -
-Dio.netty.recycler.maxSharedCapacityFactor: 2
2017-08-08T12:27:32.048 DEBUG [io.netty.util.Recycler] -
-Dio.netty.recycler.linkCapacity: 16
2017-08-08T12:27:32.048 DEBUG [io.netty.util.Recycler] -
-Dio.netty.recycler.ratio: 8
2017-08-08T12:27:32.054 DEBUG [io.netty.buffer.AbstractByteBuf] -
-Dio.netty.buffer.bytebuf.checkAccessible: true
2017-08-08T12:27:32.055 DEBUG [io.netty.util.ResourceLeakDetectorFactory] -
Loaded default ResourceLeakDetector: io.netty.util.
ResourceLeakDetector@4fa0104d
2017-08-08T12:27:32.187 TRACE
[org.apache.qpid.jms.transports.netty.NettyTcpTransport]
- SSL Handshake has completed: [id: 0x9d1338b4, L:/10.251.135.241:61550 - R:
my-namespace.servicebus.windows.net/52.166.127.37:5671]
2017-08-08T12:27:32.187 TRACE
[org.apache.qpid.jms.transports.netty.NettyTcpTransport]
- Channel has become active! Channel is [id: 0x9d1338b4, L:/
10.251.135.241:61550 - R:my-namespace.servicebus.
windows.net/52.166.127.37:5671]
2017-08-08T12:27:32.188 DEBUG [io.netty.handler.ssl.SslHandler] - [id:
0x9d1338b4, L:/10.251.135.241:61550 - R:my-namespace.servicebus.
windows.net/52.166.127.37:5671] HANDSHAKEN: TLS_ECDHE_RSA_WITH_AES_128_
CBC_SHA256
2017-08-08T12:27:32.206 TRACE
[org.apache.qpid.jms.transports.netty.NettyTcpTransport]
- Attempted write of: 8 bytes
2017-08-08T12:27:32.231 TRACE
[org.apache.qpid.jms.transports.netty.NettyTcpTransport]
- New data read: 8 bytes incoming: PooledUnsafeDirectByteBuf(ridx: 0, widx:
8, cap: 69)
2017-08-08T12:27:32.232 TRACE [org.apache.qpid.jms.provider.amqp.AmqpProvider]
- New Proton Event: CONNECTION_INIT
2017-08-08T12:27:32.502 TRACE
[org.apache.qpid.jms.transports.netty.NettyTcpTransport]
- New data read: 63 bytes incoming: PooledUnsafeDirectByteBuf(ridx: 0,
widx: 63, cap: 117)
2017-08-08T12:27:32.509 DEBUG [org.apache.qpid.jms.sasl.SaslMechanismFinder]
- Unknown SASL mechanism: [MSSBCBS]
2017-08-08T12:27:32.522 DEBUG [org.apache.qpid.jms.sasl.SaslMechanismFinder]
- Skipping SASL-EXTERNAL mechanism because the available credentials are
not sufficient
2017-08-08T12:27:32.523 INFO  [org.apache.qpid.jms.sasl.SaslMechanismFinder]
- Best match for SASL auth was: SASL-PLAIN
2017-08-08T12:27:32.525 TRACE
[org.apache.qpid.jms.transports.netty.NettyTcpTransport]
- Attempted write of: 119 bytes
2017-08-08T12:27:32.549 TRACE
[org.apache.qpid.jms.transports.netty.NettyTcpTransport]
- New data read: 26 bytes incoming: PooledUnsafeDirectByteBuf(ridx: 0,
widx: 26, cap: 85)
2017-08-08T12:27:32.550 TRACE
[org.apache.qpid.jms.transports.netty.NettyTcpTransport]
- Attempted write of: 8 bytes
2017-08-08T12:27:32.555 TRACE [org.apache.qpid.jms.util.MetaDataSupport] -
Problem generating primary version details
java.lang.NullPointerException
at java.util.regex.Matcher.getTextLength(Matcher.java:1283)
at java.util.regex.Matcher.reset(Matcher.java:309)
at java.util.regex.Matcher.<init>(Matcher.java:229)
at java.util.regex.Pattern.matcher(Pattern.java:1093)
at org.apache.qpid.jms.util.MetaDataSupport.<clinit>(
MetaDataSupport.java:47)
at org.apache.qpid.jms.provider.amqp.builders.AmqpConnectionBuilder.
createEndpoint(AmqpConnectionBuilder.java:113)
at org.apache.qpid.jms.provider.amqp.builders.AmqpConnectionBuilder.
createEndpoint(AmqpConnectionBuilder.java:45)
at org.apache.qpid.jms.provider.amqp.builders.AmqpResourceBuilder.
buildResource(AmqpResourceBuilder.java:76)
at org.apache.qpid.jms.provider.amqp.builders.AmqpConnectionBuilder.
buildResource(AmqpConnectionBuilder.java:55)
at org.apache.qpid.jms.provider.amqp.AmqpProvider$5$1.processConnectionInfo(
AmqpProvider.java:372)
at org.apache.qpid.jms.meta.JmsConnectionInfo.visit(
JmsConnectionInfo.java:417)
at org.apache.qpid.jms.provider.amqp.AmqpProvider$5.run(
AmqpProvider.java:331)
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:748)
[350616316:0] -> Open{ containerId='TestSubscriber', hostname='my-namespace.
servicebus.windows.net', maxFrameSize=1048576, channelMax=32767,
idleTimeOut=60000, outgoingLocales=null, incomingLocales=null,
offeredCapabilities=null, desiredCapabilities=[sole-connection-for-container],
properties={product=QpidJMS, version=0.23.0, platform=JVM: 1.8.0_131,
25.131-b11, Oracle Corporation, OS: Windows 7, 6.1, amd64}}
2017-08-08T12:27:32.565 TRACE [org.apache.qpid.jms.provider.amqp.FRAMES] -
SENT: Open{ containerId='TestSubscriber', hostname='my-namespace.
servicebus.windows.net', maxFrameSize=1048576, channelMax=32767,
idleTimeOut=60000, outgoingLocales=null, incomingLocales=null,
offeredCapabilities=null, desiredCapabilities=[sole-connection-for-container],
properties={product=QpidJMS, version=0.23.0, platform=JVM: 1.8.0_131,
25.131-b11, Oracle Corporation, OS: Windows 7, 6.1, amd64}}
2017-08-08T12:27:32.566 TRACE
[org.apache.qpid.jms.transports.netty.NettyTcpTransport]
- Attempted write of: 236 bytes
2017-08-08T12:27:32.574 TRACE
[org.apache.qpid.jms.transports.netty.NettyTcpTransport]
- New data read: 8 bytes incoming: PooledUnsafeDirectByteBuf(ridx: 0, widx:
8, cap: 69)
2017-08-08T12:27:32.574 TRACE [org.apache.qpid.jms.provider.amqp.AmqpProvider]
- New Proton Event: CONNECTION_LOCAL_OPEN
2017-08-08T12:27:32.803 TRACE
[org.apache.qpid.jms.transports.netty.NettyTcpTransport]
- New data read: 71 bytes incoming: PooledUnsafeDirectByteBuf(ridx: 0,
widx: 71, cap: 133)
[350616316:0] <- Open{ containerId='eddac23358584ea695974b0e0738b5cc_G28',
hostname='null', maxFrameSize=65536, channelMax=4999, idleTimeOut=240000,
outgoingLocales=null, incomingLocales=null, offeredCapabilities=null,
desiredCapabilities=null, properties=null}
2017-08-08T12:27:32.804 TRACE [org.apache.qpid.jms.provider.amqp.FRAMES] -
RECV: Open{ containerId='eddac23358584ea695974b0e0738b5cc_G28',
hostname='null', maxFrameSize=65536, channelMax=4999, idleTimeOut=240000,
outgoingLocales=null, incomingLocales=null, offeredCapabilities=null,
desiredCapabilities=null, properties=null}
2017-08-08T12:27:32.805 TRACE [org.apache.qpid.jms.provider.amqp.AmqpProvider]
- New Proton Event: CONNECTION_REMOTE_OPEN
2017-08-08T12:27:32.811 TRACE [org.apache.qpid.jms.provider.amqp.AmqpProvider]
- New Proton Event: SESSION_INIT
2017-08-08T12:27:32.812 TRACE [org.apache.qpid.jms.provider.amqp.AmqpProvider]
- New Proton Event: SESSION_LOCAL_OPEN
[350616316:0] -> Begin{remoteChannel=null, nextOutgoingId=1,
incomingWindow=2047, outgoingWindow=2147483647 <(214)%20748-3647>,
handleMax=65535, offeredCapabilities=null, desiredCapabilities=null,
properties=null}
2017-08-08T12:27:32.816 TRACE [org.apache.qpid.jms.provider.amqp.FRAMES] -
SENT: Begin{remoteChannel=null, nextOutgoingId=1, incomingWindow=2047,
outgoingWindow=2147483647 <(214)%20748-3647>, handleMax=65535,
offeredCapabilities=null, desiredCapabilities=null, properties=null}
2017-08-08T12:27:32.817 TRACE
[org.apache.qpid.jms.transports.netty.NettyTcpTransport]
- Attempted write of: 32 bytes
2017-08-08T12:27:32.841 TRACE
[org.apache.qpid.jms.transports.netty.NettyTcpTransport]
- New data read: 34 bytes incoming: PooledUnsafeDirectByteBuf(ridx: 0,
widx: 34, cap: 101)
[350616316:0] <- Begin{remoteChannel=0, nextOutgoingId=1,
incomingWindow=5000, outgoingWindow=2047, handleMax=255,
offeredCapabilities=null, desiredCapabilities=null, properties=null}
2017-08-08T12:27:32.842 TRACE [org.apache.qpid.jms.provider.amqp.FRAMES] -
RECV: Begin{remoteChannel=0, nextOutgoingId=1, incomingWindow=5000,
outgoingWindow=2047, handleMax=255, offeredCapabilities=null,
desiredCapabilities=null, properties=null}
2017-08-08T12:27:32.842 TRACE [org.apache.qpid.jms.provider.amqp.AmqpProvider]
- New Proton Event: SESSION_REMOTE_OPEN
2017-08-08T12:27:32.842 DEBUG
[org.apache.qpid.jms.provider.amqp.builders.AmqpConnectionBuilder]
- AmqpConnection { ID:5a68cd00-6948-46ff-9599-1b3803aaa73c:1 } is now open:
2017-08-08T12:27:32.842 TRACE [org.apache.qpid.jms.provider.amqp.AmqpProvider]
- IdleTimeoutCheck being initiated, initial delay: 120000
2017-08-08T12:27:32.843 INFO  [org.apache.qpid.jms.JmsConnection] -
Connection ID:5a68cd00-6948-46ff-9599-1b3803aaa73c:1 connected to remote
Broker: amqps://my-namespace.servicebus.windows.net:5671?
amqp.idleTimeout=120000&amqp.traceFrames=true
<http://my-namespace.servicebus.windows.net:5671/?amqp.idleTimeout=120000&amqp.traceFrames=true>
[350616316:1] -> Begin{remoteChannel=null, nextOutgoingId=1,
incomingWindow=2047, outgoingWindow=2147483647 <(214)%20748-3647>,
handleMax=65535, offeredCapabilities=null, desiredCapabilities=null,
properties=null}
2017-08-08T12:27:32.859 TRACE [org.apache.qpid.jms.provider.amqp.FRAMES] -
SENT: Begin{remoteChannel=null, nextOutgoingId=1, incomingWindow=2047,
outgoingWindow=2147483647 <(214)%20748-3647>, handleMax=65535,
offeredCapabilities=null, desiredCapabilities=null, properties=null}
2017-08-08T12:27:32.859 TRACE
[org.apache.qpid.jms.transports.netty.NettyTcpTransport]
- Attempted write of: 32 bytes
2017-08-08T12:27:32.883 TRACE
[org.apache.qpid.jms.transports.netty.NettyTcpTransport]
- New data read: 34 bytes incoming: PooledUnsafeDirectByteBuf(ridx: 0,
widx: 34, cap: 101)
[350616316:1] <- Begin{remoteChannel=1, nextOutgoingId=1,
incomingWindow=5000, outgoingWindow=2047, handleMax=255,
offeredCapabilities=null, desiredCapabilities=null, properties=null}
2017-08-08T12:27:32.883 TRACE [org.apache.qpid.jms.provider.amqp.FRAMES] -
RECV: Begin{remoteChannel=1, nextOutgoingId=1, incomingWindow=5000,
outgoingWindow=2047, handleMax=255, offeredCapabilities=null,
desiredCapabilities=null, properties=null}
2017-08-08T12:27:32.884 TRACE [org.apache.qpid.jms.provider.amqp.AmqpProvider]
- New Proton Event: SESSION_INIT
2017-08-08T12:27:32.884 TRACE [org.apache.qpid.jms.provider.amqp.AmqpProvider]
- New Proton Event: SESSION_LOCAL_OPEN
2017-08-08T12:27:32.884 TRACE [org.apache.qpid.jms.provider.amqp.AmqpProvider]
- New Proton Event: SESSION_REMOTE_OPEN
[350616316:1] -> Attach{name='qpid-jms:receiver:ID:5a68cd00-6948-
46ff-9599-1b3803aaa73c:1:1:1:test.topic', handle=0, role=RECEIVER,
sndSettleMode=UNSETTLED, rcvSettleMode=FIRST,
source=Source{address='test.topic',
durable=NONE, expiryPolicy=LINK_DETACH, timeout=0, dynamic=false,
dynamicNodeProperties=null, distributionMode=null, filter=null,
defaultOutcome=Modified{deliveryFailed=true, undeliverableHere=null,
messageAnnotations=null}, outcomes=[amqp:accepted:list, amqp:rejected:list,
amqp:released:list, amqp:modified:list], capabilities=[topic]},
target=Target{address='null', durable=NONE, expiryPolicy=SESSION_END,
timeout=0, dynamic=false, dynamicNodeProperties=null, capabilities=null},
unsettled=null, incompleteUnsettled=false, initialDeliveryCount=null,
maxMessageSize=null, offeredCapabilities=null, desiredCapabilities=null,
properties=null}
2017-08-08T12:27:32.905 TRACE [org.apache.qpid.jms.provider.amqp.FRAMES] -
SENT: Attach{name='qpid-jms:receiver:ID:5a68cd00-6948-
46ff-9599-1b3803aaa73c:1:1:1:test.topic', handle=0, role=RECEIVER,
sndSettleMode=UNSETTLED, rcvSettleMode=FIRST,
source=Source{address='test.topic',
durable=NONE, expiryPolicy=LINK_DETACH, timeout=0, dynamic=false,
dynamicNodeProperties=null, distributionMode=null, filter=null,
defaultOutcome=Modified{deliveryFailed=true, undeliverableHere=null,
messageAnnotations=null}, outcomes=[amqp:accepted:list, amqp:rejected:list,
amqp:released:list, amqp:modified:list], capabilities=[topic]},
target=Target{address='null', durable=NONE, expiryPolicy=SESSION_END,
timeout=0, dynamic=false, dynamicNodeProperties=null, capabilities=null},
unsettled=null, incompleteUnsettled=false, initialDeliveryCount=null,
maxMessageSize=null, offeredCapabilities=null, desiredCapabilities=null,
properties=null}
2017-08-08T12:27:32.906 TRACE
[org.apache.qpid.jms.transports.netty.NettyTcpTransport]
- Attempted write of: 234 bytes
2017-08-08T12:27:33.395 TRACE
[org.apache.qpid.jms.transports.netty.NettyTcpTransport]
- New data read: 180 bytes incoming: PooledUnsafeDirectByteBuf(ridx: 0,
widx: 180, cap: 245)
[350616316:1] <- Attach{name='qpid-jms:receiver:ID:5a68cd00-6948-
46ff-9599-1b3803aaa73c:1:1:1:test.topic', handle=0, role=SENDER,
sndSettleMode=UNSETTLED, rcvSettleMode=FIRST, source=null, target=null,
unsettled=null, incompleteUnsettled=false, initialDeliveryCount=0,
maxMessageSize=266240, offeredCapabilities=null, desiredCapabilities=null,
properties={com.microsoft:tracking-id=eddac23358584ea695974b0e0738b5cc_G28}}
2017-08-08T12:27:33.396 TRACE [org.apache.qpid.jms.provider.amqp.FRAMES] -
RECV: Attach{name='qpid-jms:receiver:ID:5a68cd00-6948-
46ff-9599-1b3803aaa73c:1:1:1:test.topic', handle=0, role=SENDER,
sndSettleMode=UNSETTLED, rcvSettleMode=FIRST, source=null, target=null,
unsettled=null, incompleteUnsettled=false, initialDeliveryCount=0,
maxMessageSize=266240, offeredCapabilities=null, desiredCapabilities=null,
properties={com.microsoft:tracking-id=eddac23358584ea695974b0e0738b5cc_G28}}
2017-08-08T12:27:33.397 TRACE [org.apache.qpid.jms.provider.amqp.AmqpProvider]
- New Proton Event: LINK_INIT
2017-08-08T12:27:33.397 TRACE [org.apache.qpid.jms.provider.amqp.AmqpProvider]
- New Proton Event: LINK_LOCAL_OPEN
2017-08-08T12:27:33.397 TRACE [org.apache.qpid.jms.provider.amqp.AmqpProvider]
- New Proton Event: LINK_REMOTE_OPEN
2017-08-08T12:27:33.617 TRACE
[org.apache.qpid.jms.transports.netty.NettyTcpTransport]
- New data read: 509 bytes incoming: PooledUnsafeDirectByteBuf(ridx: 0,
widx: 509, cap: 565)
[350616316:1] <- Detach{handle=0, closed=true,
error=Error{condition=amqp:not-found,
description='The messaging entity 'my-namespace:topic:test.
topic~15|qpid-jms:receiver:ID:5a68cd00-6948-46ff-9599-1b3803aaa73c:1:1:1:test.topic'
could not be found. TrackingId:12ecc2a3-f8f3-42a3-8bd5-ad5d9823c367_B20,
SystemTracker:my-namespace:topic:test.topic~15|qpid-jms:
receiver:ID:5a68cd00-6948-46ff-9599-1b3803aaa73c:1:1:1:test.topic,
Timestamp:8/8/2017 10:27:32 AM TrackingId:eddac23358584ea695974b0e0738b5cc_G28,
SystemTracker:gateway6, Timestamp:8/8/2017 10:27:32 AM', info=null}}
2017-08-08T12:27:33.618 TRACE [org.apache.qpid.jms.provider.amqp.FRAMES] -
RECV: Detach{handle=0, closed=true, error=Error{condition=amqp:not-found,
description='The messaging entity 'my-namespace:topic:test.
topic~15|qpid-jms:receiver:ID:5a68cd00-6948-46ff-9599-1b3803aaa73c:1:1:1:test.topic'
could not be found. TrackingId:12ecc2a3-f8f3-42a3-8bd5-ad5d9823c367_B20,
SystemTracker:my-namespace:topic:test.topic~15|qpid-jms:
receiver:ID:5a68cd00-6948-46ff-9599-1b3803aaa73c:1:1:1:test.topic,
Timestamp:8/8/2017 10:27:32 AM TrackingId:eddac23358584ea695974b0e0738b5cc_G28,
SystemTracker:gateway6, Timestamp:8/8/2017 10:27:32 AM', info=null}}
2017-08-08T12:27:33.618 TRACE [org.apache.qpid.jms.provider.amqp.AmqpProvider]
- New Proton Event: LINK_REMOTE_CLOSE
2017-08-08T12:27:33.618 WARN
[org.apache.qpid.jms.provider.amqp.builders.AmqpResourceBuilder]
- Open of resource:(JmsConsumerInfo: {
ID:5a68cd00-6948-46ff-9599-1b3803aaa73c:1:1:1,
destination = test.topic }) failed: The messaging entity
'my-namespace:topic:test.topic~15|qpid-jms:receiver:ID:
5a68cd00-6948-46ff-9599-1b3803aaa73c:1:1:1:test.topic' could not be found.
TrackingId:12ecc2a3-f8f3-42a3-8bd5-ad5d9823c367_B20,
SystemTracker:my-namespace:topic:test.topic~15|qpid-jms:
receiver:ID:5a68cd00-6948-46ff-9599-1b3803aaa73c:1:1:1:test.topic,
Timestamp:8/8/2017 10:27:32 AM TrackingId:eddac23358584ea695974b0e0738b5cc_G28,
SystemTracker:gateway6, Timestamp:8/8/2017 10:27:32 AM [condition =
amqp:not-found]
2017-08-08T12:27:33.619 TRACE [org.apache.qpid.jms.provider.amqp.AmqpProvider]
- New Proton Event: LINK_LOCAL_CLOSE
[350616316:1] -> Detach{handle=0, closed=true, error=null}
2017-08-08T12:27:33.619 TRACE [org.apache.qpid.jms.provider.amqp.FRAMES] -
SENT: Detach{handle=0, closed=true, error=null}
2017-08-08T12:27:33.620 TRACE
[org.apache.qpid.jms.transports.netty.NettyTcpTransport]
- Attempted write of: 16 bytes
javax.jms.InvalidDestinationException: The messaging entity
'my-namespace:topic:test.topic~15|qpid-jms:receiver:ID:
5a68cd00-6948-46ff-9599-1b3803aaa73c:1:1:1:test.topic' could not be found.
TrackingId:12ecc2a3-f8f3-42a3-8bd5-ad5d9823c367_B20,
SystemTracker:my-namespace:topic:test.topic~15|qpid-jms:
receiver:ID:5a68cd00-6948-46ff-9599-1b3803aaa73c:1:1:1:test.topic,
Timestamp:8/8/2017 10:27:32 AM TrackingId:eddac23358584ea695974b0e0738b5cc_G28,
SystemTracker:gateway6, Timestamp:8/8/2017 10:27:32 AM [condition =
amqp:not-found]
at org.apache.qpid.jms.provider.amqp.AmqpSupport.convertToException(
AmqpSupport.java:150)
at org.apache.qpid.jms.provider.amqp.AmqpSupport.convertToException(
AmqpSupport.java:117)
at org.apache.qpid.jms.provider.amqp.builders.AmqpResourceBuilder.
handleClosed(AmqpResourceBuilder.java:185)
at org.apache.qpid.jms.provider.amqp.builders.AmqpResourceBuilder.
processRemoteClose(AmqpResourceBuilder.java:129)
at org.apache.qpid.jms.provider.amqp.AmqpProvider.
processUpdates(AmqpProvider.java:905)
at org.apache.qpid.jms.provider.amqp.AmqpProvider.access$1900(
AmqpProvider.java:93)
at org.apache.qpid.jms.provider.amqp.AmqpProvider$18.run(
AmqpProvider.java:791)
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:748)
2017-08-08T12:28:25.904 TRACE
[org.apache.qpid.jms.transports.netty.NettyTcpTransport]
- New data read: 8 bytes incoming: PooledUnsafeDirectByteBuf(ridx: 0, widx:
8, cap: 69)
[350616316:0] <- Empty Frame
2017-08-08T12:28:25.905 TRACE [org.apache.qpid.jms.provider.amqp.FRAMES] -
RECV: Empty Frame
2017-08-08T12:28:25.906 TRACE [org.apache.qpid.jms.provider.amqp.AmqpProvider]
- New Proton Event: LINK_FINAL


/ Joacim




On Tue, Aug 8, 2017 at 10:58 AM, 4 Integration <4integrat...@gmail.com>
wrote:

> Tested to change but the same error.
>
>         //subscriber = subscriberSession.createDurableSubscriber(topic,
> "DurableSubscriber1");
>         subscriber = subscriberSession.createConsumer(topic);
>
> In the exception - what is "~15" characters?
>
>
> javax.jms.InvalidDestinationException: The messaging entity
> 'my-namespace:topic:test.topic~15|qpid-jms:receiver:ID:
> 26eaaacf-965f-4d10-869f-f77b9dca5918:1:1:1:test.topic' could not be
> found. TrackingId:12ecc2a3-f8f3-42a3-8bd5-ad5d9823c367_B20,
> SystemTracker:my-namespace:topic:test.topic~15|qpid-jms:
> receiver:ID:26eaaacf-965f-4d10-869f-f77b9dca5918:1:1:1:test.topic,
> Timestamp:8/8/2017 8:51:31 AM TrackingId:eddac23358584ea695974b0e0738b5cc_G28,
> SystemTracker:gateway6, Timestamp:8/8/2017 8:51:31 AM [condition =
> amqp:not-found]
> at org.apache.qpid.jms.provider.amqp.AmqpSupport.convertToException(
> AmqpSupport.java:150)
> at org.apache.qpid.jms.provider.amqp.AmqpSupport.convertToException(
> AmqpSupport.java:117)
> at org.apache.qpid.jms.provider.amqp.builders.AmqpResourceBuilder.
> handleClosed(AmqpResourceBuilder.java:185)
> at org.apache.qpid.jms.provider.amqp.builders.AmqpResourceBuilder.
> processRemoteClose(AmqpResourceBuilder.java:129)
> at org.apache.qpid.jms.provider.amqp.AmqpProvider.
> processUpdates(AmqpProvider.java:905)
> at org.apache.qpid.jms.provider.amqp.AmqpProvider.access$1900(
> AmqpProvider.java:93)
> at org.apache.qpid.jms.provider.amqp.AmqpProvider$18.run(
> AmqpProvider.java:791)
> 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:748)
>
>
> / Joacim
>
>
>
> On Tue, Aug 8, 2017 at 10:10 AM, Gordon Sim <g...@redhat.com> wrote:
>
>> Does a non-durable subscription work?
>>
>>
>> ---------------------------------------------------------------------
>> To unsubscribe, e-mail: users-unsubscr...@qpid.apache.org
>> For additional commands, e-mail: users-h...@qpid.apache.org
>>
>>
>

Reply via email to