Here are the files.
Thanks,
Olivier
-----Original Message-----
From: Ganesh Murthy <[email protected]>
Sent: jeudi 20 septembre 2018 15:19
To: [email protected]
Subject: Re: [Dispatch-Router] max message size
Can you please enable trace logging on the router and share the log file?
To enable trace logging add the following to your router configuration file.
log {
module: DEFAULT
enable: trace+
output: qdrouterd.log
}
Also please share your router config file if possible and which broker you are
using to test.
On Thu, Sep 20, 2018 at 8:47 AM VERMEULEN Olivier <
[email protected]> wrote:
> Hello,
>
> We did a test with 1 dispatch-router and 2 brokers.
> On the brokers we configured a maximum message size of 10KB.
> The use case is the following:
>
> * We send a message above 10 KB: we receive a "failure at remote"
> exception from the dispatch-router
> * We send a message under 10KB: send is successful
> * We send a message above 10 KB again: we receive a "failure at
> remote" exception from the dispatch-router
> * We send a message under 10KB again: the send hangs...
>
> This was reproduced with the latest version of the dispatch-router.
> Do you have any idea what is going on and is it a known issue?
>
> Thanks,
> Olivier
>
> *******************************
>
> This e-mail contains information for the intended recipient only. It
> may contain proprietary material or confidential information. If you
> are not the intended recipient you are not authorised to distribute,
> copy or use this e-mail or any attachment to it. Murex cannot
> guarantee that it is virus free and accepts no responsibility for any
> loss or damage arising from its use. If you have received this e-mail
> in error please notify immediately the sender and delete the original
> email received, any attachments and all copies from your system.
>
*******************************
This e-mail contains information for the intended recipient only. It may
contain proprietary material or confidential information. If you are not the
intended recipient you are not authorised to distribute, copy or use this
e-mail or any attachment to it. Murex cannot guarantee that it is virus free
and accepts no responsibility for any loss or damage arising from its use. If
you have received this e-mail in error please notify immediately the sender and
delete the original email received, any attachments and all copies from your
system.
2018-09-21 04:09:36 Using configuration file: dispatchWith2brokers.conf
2018-09-21 04:09:36 Starting dispatch router with work directory:
/nfshome/murex/alhadi/dispatch_dir_default
2018-09-21 04:09:36 /src/new/alhadi/qpid-dispatch-linux-1.3.0/sbin/qdrouterd -c
/nfshome/murex/alhadi/dispatch_dir_default/dispatch.conf -I
/src/new/alhadi/qpid-dispatch-linux-1.3.0/lib/qpid-dispatch/python
2018-09-21 04:09:36.122405 +0200 AGENT (debug) Add entity:
LogEntity(enable=trace+, identity=log/DEFAULT, includeSource=False,
includeTimestamp=True, module=DEFAULT, name=log/DEFAULT, outputFile=stdout,
type=org.apache.qpid.dispatch.log)
2018-09-21 04:09:36.122778 +0200 AGENT (debug) Add entity:
LogEntity(identity=log/HTTP, module=HTTP, name=log/HTTP,
type=org.apache.qpid.dispatch.log)
2018-09-21 04:09:36.123048 +0200 AGENT (debug) Add entity:
LogEntity(identity=log/ROUTER_LS, module=ROUTER_LS, name=log/ROUTER_LS,
type=org.apache.qpid.dispatch.log)
2018-09-21 04:09:36.123301 +0200 AGENT (debug) Add entity:
LogEntity(identity=log/PYTHON, module=PYTHON, name=log/PYTHON,
type=org.apache.qpid.dispatch.log)
2018-09-21 04:09:36.123579 +0200 AGENT (debug) Add entity:
LogEntity(identity=log/ROUTER_MA, module=ROUTER_MA, name=log/ROUTER_MA,
type=org.apache.qpid.dispatch.log)
2018-09-21 04:09:36.123822 +0200 AGENT (debug) Add entity:
LogEntity(identity=log/CONN_MGR, module=CONN_MGR, name=log/CONN_MGR,
type=org.apache.qpid.dispatch.log)
2018-09-21 04:09:36.124110 +0200 AGENT (debug) Add entity:
LogEntity(identity=log/ROUTER_HELLO, module=ROUTER_HELLO,
name=log/ROUTER_HELLO, type=org.apache.qpid.dispatch.log)
2018-09-21 04:09:36.124357 +0200 AGENT (debug) Add entity:
LogEntity(identity=log/SERVER, module=SERVER, name=log/SERVER,
type=org.apache.qpid.dispatch.log)
2018-09-21 04:09:36.124634 +0200 AGENT (debug) Add entity:
LogEntity(identity=log/POLICY, module=POLICY, name=log/POLICY,
type=org.apache.qpid.dispatch.log)
2018-09-21 04:09:36.124920 +0200 AGENT (debug) Add entity:
LogEntity(identity=log/CONTAINER, module=CONTAINER, name=log/CONTAINER,
type=org.apache.qpid.dispatch.log)
2018-09-21 04:09:36.125220 +0200 AGENT (debug) Add entity:
LogEntity(identity=log/AGENT, module=AGENT, name=log/AGENT,
type=org.apache.qpid.dispatch.log)
2018-09-21 04:09:36.125502 +0200 AGENT (debug) Add entity:
LogEntity(identity=log/ERROR, module=ERROR, name=log/ERROR,
type=org.apache.qpid.dispatch.log)
2018-09-21 04:09:36.125799 +0200 AGENT (debug) Add entity:
LogEntity(identity=log/ROUTER_CORE, module=ROUTER_CORE, name=log/ROUTER_CORE,
type=org.apache.qpid.dispatch.log)
2018-09-21 04:09:36.126065 +0200 AGENT (debug) Add entity:
LogEntity(identity=log/ROUTER, module=ROUTER, name=log/ROUTER,
type=org.apache.qpid.dispatch.log)
2018-09-21 04:09:36.126336 +0200 AGENT (debug) Add entity:
LogEntity(identity=log/MESSAGE, module=MESSAGE, name=log/MESSAGE,
type=org.apache.qpid.dispatch.log)
2018-09-21 04:09:36.126946 +0200 AGENT (debug) Add entity:
RouterEntity(allowResumableLinkRoute=True, allowUnsettledMulticast=False,
area=0, defaultDistribution=unavailable, helloIntervalSeconds=1,
helloMaxAgeSeconds=3, hostName=green-lx-slave1, id=router.10105, mode=interior,
raIntervalFluxSeconds=4, raIntervalSeconds=30, remoteLsMaxAgeSeconds=60,
saslConfigName=qdrouterd, type=org.apache.qpid.dispatch.router, workerThreads=4)
2018-09-21 04:09:36.127431 +0200 SERVER (warning) HTTP support is not available
2018-09-21 04:09:36.127444 +0200 SERVER (info) Container Name: router.10105
2018-09-21 04:09:36.127460 +0200 CONTAINER (trace) Container Initialized
2018-09-21 04:09:36.127494 +0200 CONTAINER (trace) Node Type Registered - router
2018-09-21 04:09:36.127508 +0200 CONTAINER (trace) Node of type 'router'
installed as default node
2018-09-21 04:09:36.127536 +0200 ROUTER (info) Router started in Interior mode,
area=0 id=router.10105
2018-09-21 04:09:36.127541 +0200 ROUTER (info) Version: 1.3.0
2018-09-21 04:09:36.127569 +0200 POLICY (trace) Policy Initialized
2018-09-21 04:09:36.128123 +0200 ROUTER (info) Router Engine Instantiated:
id=router.10105 instance=1537495776 max_routers=128
2018-09-21 04:09:36.128195 +0200 ROUTER_CORE (info) Router Core thread running.
0/router.10105
2018-09-21 04:09:36.128233 +0200 ROUTER_CORE (trace) Core action 'subscribe'
2018-09-21 04:09:36.128240 +0200 ROUTER_CORE (info) In-process subscription
M/$management
2018-09-21 04:09:36.128255 +0200 ROUTER_CORE (trace) Core action 'subscribe'
2018-09-21 04:09:36.128260 +0200 ROUTER_CORE (info) In-process subscription
L/$management
2018-09-21 04:09:36.128266 +0200 ROUTER_CORE (trace) Core action 'subscribe'
2018-09-21 04:09:36.128270 +0200 ROUTER_CORE (info) In-process subscription
L/qdrouter
2018-09-21 04:09:36.128275 +0200 ROUTER_CORE (trace) Core action 'subscribe'
2018-09-21 04:09:36.128279 +0200 ROUTER_CORE (info) In-process subscription
L/qdrouter.ma
2018-09-21 04:09:36.128284 +0200 ROUTER_CORE (trace) Core action 'subscribe'
2018-09-21 04:09:36.128288 +0200 ROUTER_CORE (info) In-process subscription
T/qdrouter
2018-09-21 04:09:36.128292 +0200 ROUTER_CORE (trace) Core action 'subscribe'
2018-09-21 04:09:36.128303 +0200 ROUTER_CORE (info) In-process subscription
T/qdrouter.ma
2018-09-21 04:09:36.128310 +0200 ROUTER_CORE (trace) Core action 'subscribe'
2018-09-21 04:09:36.128314 +0200 ROUTER_CORE (info) In-process subscription
L/qdhello
2018-09-21 04:09:36.128450 +0200 AGENT (debug) Add entity:
RouterNodeEntity(address=amqp:/_topo/0/router.10105, id=router.10105, index=0,
instance=1537495776, lastTopoChange=0, linkState=[], nextHop=(self),
protocolVersion=1, type=org.apache.qpid.dispatch.router.node, validOrigins=[])
2018-09-21 04:09:36.128922 +0200 AGENT (debug) Add entity:
LogStatsEntity(criticalCount=0, debugCount=0, errorCount=0,
identity=logStats/DEFAULT, infoCount=0, name=DEFAULT, noticeCount=0,
traceCount=0, type=org.apache.qpid.dispatch.logStats, warningCount=0)
2018-09-21 04:09:36.129259 +0200 AGENT (debug) Add entity:
LogStatsEntity(criticalCount=0, debugCount=0, errorCount=0,
identity=logStats/ERROR, infoCount=0, name=ERROR, noticeCount=0, traceCount=0,
type=org.apache.qpid.dispatch.logStats, warningCount=0)
2018-09-21 04:09:36.129561 +0200 AGENT (debug) Add entity:
LogStatsEntity(criticalCount=0, debugCount=0, errorCount=0,
identity=logStats/PYTHON, infoCount=0, name=PYTHON, noticeCount=0,
traceCount=0, type=org.apache.qpid.dispatch.logStats, warningCount=0)
2018-09-21 04:09:36.129857 +0200 AGENT (debug) Add entity:
LogStatsEntity(criticalCount=0, debugCount=0, errorCount=0,
identity=logStats/MESSAGE, infoCount=0, name=MESSAGE, noticeCount=0,
traceCount=0, type=org.apache.qpid.dispatch.logStats, warningCount=0)
2018-09-21 04:09:36.130177 +0200 AGENT (debug) Add entity:
LogStatsEntity(criticalCount=0, debugCount=0, errorCount=0,
identity=logStats/MAIN, infoCount=0, name=MAIN, noticeCount=0, traceCount=0,
type=org.apache.qpid.dispatch.logStats, warningCount=0)
2018-09-21 04:09:36.130508 +0200 AGENT (debug) Add entity:
LogStatsEntity(criticalCount=0, debugCount=23, errorCount=0,
identity=logStats/AGENT, infoCount=0, name=AGENT, noticeCount=0, traceCount=0,
type=org.apache.qpid.dispatch.logStats, warningCount=6)
2018-09-21 04:09:36.130851 +0200 AGENT (debug) Add entity:
AllocatorEntity(batchesRebalancedToGlobal=0, batchesRebalancedToThreads=0,
globalFreeListMax=0, heldByThreads=64, localFreeListMax=32,
totalAllocFromHeap=64, totalFreeToHeap=0, transferBatchSize=16,
type=org.apache.qpid.dispatch.allocator, typeName=qd_log_entry_t, typeSize=2112)
2018-09-21 04:09:36.131267 +0200 AGENT (debug) Add entity:
LogStatsEntity(criticalCount=0, debugCount=0, errorCount=0,
identity=logStats/POLICY, infoCount=0, name=POLICY, noticeCount=0,
traceCount=1, type=org.apache.qpid.dispatch.logStats, warningCount=0)
2018-09-21 04:09:36.131598 +0200 AGENT (debug) Add entity:
LogStatsEntity(criticalCount=0, debugCount=0, errorCount=0,
identity=logStats/HTTP, infoCount=0, name=HTTP, noticeCount=0, traceCount=0,
type=org.apache.qpid.dispatch.logStats, warningCount=0)
2018-09-21 04:09:36.131923 +0200 AGENT (debug) Add entity:
LogStatsEntity(criticalCount=0, debugCount=0, errorCount=0,
identity=logStats/ROUTER_LS, infoCount=0, name=ROUTER_LS, noticeCount=0,
traceCount=0, type=org.apache.qpid.dispatch.logStats, warningCount=0)
2018-09-21 04:09:36.132288 +0200 AGENT (debug) Add entity:
LogStatsEntity(criticalCount=0, debugCount=0, errorCount=0,
identity=logStats/ROUTER_MA, infoCount=0, name=ROUTER_MA, noticeCount=0,
traceCount=0, type=org.apache.qpid.dispatch.logStats, warningCount=0)
2018-09-21 04:09:36.132626 +0200 AGENT (debug) Add entity:
LogStatsEntity(criticalCount=0, debugCount=0, errorCount=0,
identity=logStats/CONN_MGR, infoCount=0, name=CONN_MGR, noticeCount=0,
traceCount=0, type=org.apache.qpid.dispatch.logStats, warningCount=0)
2018-09-21 04:09:36.132965 +0200 AGENT (debug) Add entity:
LogStatsEntity(criticalCount=0, debugCount=0, errorCount=0,
identity=logStats/ROUTER_HELLO, infoCount=0, name=ROUTER_HELLO, noticeCount=0,
traceCount=0, type=org.apache.qpid.dispatch.logStats, warningCount=0)
2018-09-21 04:09:36.133321 +0200 AGENT (debug) Add entity:
LogStatsEntity(criticalCount=0, debugCount=0, errorCount=0,
identity=logStats/SERVER, infoCount=1, name=SERVER, noticeCount=0,
traceCount=0, type=org.apache.qpid.dispatch.logStats, warningCount=1)
2018-09-21 04:09:36.133674 +0200 AGENT (debug) Add entity:
LogStatsEntity(criticalCount=0, debugCount=0, errorCount=0,
identity=logStats/CONTAINER, infoCount=0, name=CONTAINER, noticeCount=0,
traceCount=3, type=org.apache.qpid.dispatch.logStats, warningCount=0)
2018-09-21 04:09:36.134042 +0200 AGENT (debug) Add entity:
LogStatsEntity(criticalCount=0, debugCount=0, errorCount=0,
identity=logStats/ROUTER_CORE, infoCount=8, name=ROUTER_CORE, noticeCount=0,
traceCount=7, type=org.apache.qpid.dispatch.logStats, warningCount=0)
2018-09-21 04:09:36.134427 +0200 AGENT (debug) Add entity:
LogStatsEntity(criticalCount=0, debugCount=0, errorCount=0,
identity=logStats/ROUTER, infoCount=3, name=ROUTER, noticeCount=0,
traceCount=0, type=org.apache.qpid.dispatch.logStats, warningCount=0)
2018-09-21 04:09:36.134920 +0200 AGENT (debug) Add entity:
AllocatorEntity(batchesRebalancedToGlobal=0, batchesRebalancedToThreads=0,
globalFreeListMax=0, heldByThreads=128, localFreeListMax=128,
totalAllocFromHeap=128, totalFreeToHeap=0, transferBatchSize=64,
type=org.apache.qpid.dispatch.allocator, typeName=qd_iterator_t, typeSize=160)
2018-09-21 04:09:36.135383 +0200 AGENT (debug) Add entity:
AllocatorEntity(batchesRebalancedToGlobal=0, batchesRebalancedToThreads=0,
globalFreeListMax=0, heldByThreads=128, localFreeListMax=128,
totalAllocFromHeap=128, totalFreeToHeap=0, transferBatchSize=64,
type=org.apache.qpid.dispatch.allocator, typeName=qd_hash_item_t, typeSize=32)
2018-09-21 04:09:36.135813 +0200 AGENT (debug) Add entity:
AllocatorEntity(batchesRebalancedToGlobal=0, batchesRebalancedToThreads=0,
globalFreeListMax=0, heldByThreads=64, localFreeListMax=128,
totalAllocFromHeap=64, totalFreeToHeap=0, transferBatchSize=64,
type=org.apache.qpid.dispatch.allocator, typeName=qd_node_t, typeSize=56)
2018-09-21 04:09:36.136254 +0200 AGENT (debug) Add entity:
AllocatorEntity(batchesRebalancedToGlobal=0, batchesRebalancedToThreads=0,
globalFreeListMax=0, heldByThreads=128, localFreeListMax=128,
totalAllocFromHeap=128, totalFreeToHeap=0, transferBatchSize=64,
type=org.apache.qpid.dispatch.allocator, typeName=qd_timer_t, typeSize=56)
2018-09-21 04:09:36.136675 +0200 AGENT (debug) Add entity:
AllocatorEntity(batchesRebalancedToGlobal=0, batchesRebalancedToThreads=0,
globalFreeListMax=0, heldByThreads=128, localFreeListMax=128,
totalAllocFromHeap=128, totalFreeToHeap=0, transferBatchSize=64,
type=org.apache.qpid.dispatch.allocator, typeName=qd_parse_node_t, typeSize=104)
2018-09-21 04:09:36.137105 +0200 AGENT (debug) Add entity:
AllocatorEntity(batchesRebalancedToGlobal=0, batchesRebalancedToThreads=0,
globalFreeListMax=0, heldByThreads=64, localFreeListMax=128,
totalAllocFromHeap=64, totalFreeToHeap=0, transferBatchSize=64,
type=org.apache.qpid.dispatch.allocator, typeName=qdr_action_t, typeSize=160)
2018-09-21 04:09:36.137581 +0200 AGENT (debug) Add entity:
AllocatorEntity(batchesRebalancedToGlobal=0, batchesRebalancedToThreads=0,
globalFreeListMax=0, heldByThreads=64, localFreeListMax=128,
totalAllocFromHeap=64, totalFreeToHeap=0, transferBatchSize=64,
type=org.apache.qpid.dispatch.allocator, typeName=qdr_field_t, typeSize=40)
2018-09-21 04:09:36.138045 +0200 AGENT (debug) Add entity:
AllocatorEntity(batchesRebalancedToGlobal=0, batchesRebalancedToThreads=0,
globalFreeListMax=0, heldByThreads=64, localFreeListMax=128,
totalAllocFromHeap=64, totalFreeToHeap=0, transferBatchSize=64,
type=org.apache.qpid.dispatch.allocator, typeName=qd_buffer_t, typeSize=536)
2018-09-21 04:09:36.138502 +0200 AGENT (debug) Add entity:
AllocatorEntity(batchesRebalancedToGlobal=0, batchesRebalancedToThreads=0,
globalFreeListMax=0, heldByThreads=64, localFreeListMax=128,
totalAllocFromHeap=64, totalFreeToHeap=0, transferBatchSize=64,
type=org.apache.qpid.dispatch.allocator, typeName=qdr_address_t, typeSize=304)
2018-09-21 04:09:36.138943 +0200 AGENT (debug) Add entity:
AllocatorEntity(batchesRebalancedToGlobal=0, batchesRebalancedToThreads=0,
globalFreeListMax=0, heldByThreads=64, localFreeListMax=128,
totalAllocFromHeap=64, totalFreeToHeap=0, transferBatchSize=64,
type=org.apache.qpid.dispatch.allocator, typeName=qd_bitmask_t, typeSize=24)
2018-09-21 04:09:36.139407 +0200 AGENT (debug) Add entity:
AllocatorEntity(batchesRebalancedToGlobal=0, batchesRebalancedToThreads=0,
globalFreeListMax=0, heldByThreads=64, localFreeListMax=128,
totalAllocFromHeap=64, totalFreeToHeap=0, transferBatchSize=64,
type=org.apache.qpid.dispatch.allocator, typeName=qd_hash_handle_t, typeSize=16)
2018-09-21 04:09:36.140023 +0200 AGENT (info) Activating management agent on
$_management_internal
2018-09-21 04:09:36.140084 +0200 ROUTER_CORE (trace) Core action 'subscribe'
2018-09-21 04:09:36.140228 +0200 ROUTER_CORE (info) In-process subscription
L/$_management_internal
2018-09-21 04:09:36.140558 +0200 POLICY (info) Policy configured
maxConnections: 65535, policyDir: '',access rules enabled: 'false', use
hostname patterns: 'false'
2018-09-21 04:09:36.140660 +0200 AGENT (debug) Add entity:
PolicyEntity(defaultVhost=$default, enableVhostNamePatterns=False,
enableVhostPolicy=False, maxConnections=65535, policyDir=,
type=org.apache.qpid.dispatch.policy)
2018-09-21 04:09:36.141401 +0200 POLICY (info) Policy fallback defaultVhost is
defined: '$default'
2018-09-21 04:09:36.141696 +0200 CONN_MGR (info) Configured Listener:
0.0.0.0:10105 proto=any, role=normal
2018-09-21 04:09:36.141992 +0200 AGENT (debug) Add entity:
ListenerEntity(authenticatePeer=False, cost=1, host=0.0.0.0, http=False,
identity=listener/0.0.0.0:10105, idleTimeoutSeconds=16,
initialHandshakeTimeoutSeconds=0, maxFrameSize=16384, maxSessions=32768,
messageLoggingComponents=none, multiTenant=False, port=10105,
requireEncryption=False, requireSsl=False, role=normal,
saslMechanisms=ANONYMOUS, stripAnnotations=both,
type=org.apache.qpid.dispatch.listener)
2018-09-21 04:09:36.142753 +0200 CONN_MGR (info) Configured Connector:
10.27.43.61:5672 proto=any, role=route-container
2018-09-21 04:09:36.142911 +0200 AGENT (debug) Add entity:
ConnectorEntity(allowRedirect=True, cost=1,
failoverUrls=amqp://10.27.43.61:5672, host=10.27.43.61,
identity=connector/10.27.43.61:5672:broker1, idleTimeoutSeconds=16,
maxFrameSize=16384, maxSessions=32768, messageLoggingComponents=none,
name=broker1, port=5672, role=route-container, stripAnnotations=both,
type=org.apache.qpid.dispatch.connector, verifyHostname=True)
2018-09-21 04:09:36.143590 +0200 CONN_MGR (info) Configured Connector:
10.27.43.61:5673 proto=any, role=route-container
2018-09-21 04:09:36.143721 +0200 AGENT (debug) Add entity:
ConnectorEntity(allowRedirect=True, cost=1,
failoverUrls=amqp://10.27.43.61:5673, host=10.27.43.61,
identity=connector/10.27.43.61:5673:broker2, idleTimeoutSeconds=16,
maxFrameSize=16384, maxSessions=32768, messageLoggingComponents=none,
name=broker2, port=5673, role=route-container, stripAnnotations=both,
type=org.apache.qpid.dispatch.connector, verifyHostname=True)
2018-09-21 04:09:36.144438 +0200 ROUTER_CORE (trace) Core action 'manage_create'
2018-09-21 04:09:36.144470 +0200 DEFAULT (trace) Parse tree get address pattern
'myQueue/#'
2018-09-21 04:09:36.144530 +0200 AGENT (debug) Add entity:
AddressEntity(distribution=balanced, identity=address/0, name=address/0,
prefix=myQueue, type=org.apache.qpid.dispatch.router.config.address,
waypoint=True)
2018-09-21 04:09:36.144556 +0200 DEFAULT (trace) Parse tree add address pattern
'myQueue/#'
2018-09-21 04:09:36.145111 +0200 ROUTER_CORE (trace) Core action 'manage_create'
2018-09-21 04:09:36.145163 +0200 DEFAULT (trace) Parse tree search for 'myQueue'
2018-09-21 04:09:36.145217 +0200 AGENT (debug) Add entity:
AutoLinkEntity(addr=myQueue, connection=broker1, direction=in,
identity=autoLink/0, name=queuelinkin1,
type=org.apache.qpid.dispatch.router.config.autoLink)
2018-09-21 04:09:36.145741 +0200 ROUTER_CORE (trace) Core action 'manage_create'
2018-09-21 04:09:36.145756 +0200 DEFAULT (trace) Parse tree search for 'myQueue'
2018-09-21 04:09:36.145802 +0200 AGENT (debug) Add entity:
AutoLinkEntity(addr=myQueue, connection=broker1, direction=out,
identity=autoLink/1, name=queuelinkout1,
type=org.apache.qpid.dispatch.router.config.autoLink)
2018-09-21 04:09:36.146320 +0200 ROUTER_CORE (trace) Core action 'manage_create'
2018-09-21 04:09:36.146378 +0200 AGENT (debug) Add entity:
AutoLinkEntity(addr=myQueue, connection=broker2, direction=in,
identity=autoLink/2, name=queuelinkin2,
type=org.apache.qpid.dispatch.router.config.autoLink)
2018-09-21 04:09:36.146913 +0200 ROUTER_CORE (trace) Core action 'manage_create'
2018-09-21 04:09:36.146990 +0200 AGENT (debug) Add entity:
AutoLinkEntity(addr=myQueue, connection=broker2, direction=out,
identity=autoLink/3, name=queuelinkout2,
type=org.apache.qpid.dispatch.router.config.autoLink)
2018-09-21 04:09:36.147435 +0200 SERVER (notice) Operational, 4 Threads Running
(process ID 1946)
2018-09-21 04:09:36.147667 +0200 SERVER (notice) Listening on 0.0.0.0:10105
2018-09-21 04:09:36.147735 +0200 SERVER (trace) [1] Connecting to
10.27.43.61:5673
2018-09-21 04:09:36.147888 +0200 SERVER (trace) [2] Connecting to
10.27.43.61:5672
2018-09-21 04:09:36.149422 +0200 SERVER (trace) [2]: -> SASL
2018-09-21 04:09:36.149445 +0200 SERVER (trace) [1]: -> SASL
2018-09-21 04:09:36.155474 +0200 SERVER (trace) [1]: <- SASL
2018-09-21 04:09:36.155530 +0200 SERVER (trace) [1]:0 <- @sasl-mechanisms(64)
[sasl-server-mechanisms=@PN_SYMBOL[:ANONYMOUS]]
2018-09-21 04:09:36.155581 +0200 SERVER (trace) [1]:0 -> @sasl-init(65)
[mechanism=:ANONYMOUS, initial-response=b"anonymous"]
2018-09-21 04:09:36.157246 +0200 SERVER (trace) [1]:0 <- @sasl-outcome(68)
[code=0]
2018-09-21 04:09:36.157264 +0200 SERVER (trace) [1]: -> AMQP
2018-09-21 04:09:36.157296 +0200 SERVER (trace) [1]:0 -> @open(16)
[container-id="router.10105", hostname="10.27.43.61", max-frame-size=16384,
channel-max=32767, idle-time-out=8000, offered-capabilities=:"ANONYMOUS-RELAY",
properties={:product="qpid-dispatch-router", :version="1.3.0"}]
2018-09-21 04:09:36.160463 +0200 SERVER (trace) [1]: <- AMQP
2018-09-21 04:09:36.160495 +0200 SERVER (trace) [1]:0 <- @open(16)
[container-id="a51c297e-c548-42df-a42c-a3e8e0ae2356", max-frame-size=16384,
channel-max=255, idle-time-out=0,
offered-capabilities=@PN_SYMBOL[:"ANONYMOUS-RELAY", :"SHARED-SUBS",
:"sole-connection-for-container"], properties={:product="qpid",
:version="7.0.3", :"qpid.build"="62d7ae692b47746c42949c2e646f33a966e6a1a1",
:"qpid.instance_name"="Broker",
:"qpid.virtualhost_properties_supported"="true"}]
2018-09-21 04:09:36.160694 +0200 ROUTER_CORE (trace) Core action
'connection_opened'
2018-09-21 04:09:36.160755 +0200 ROUTER_CORE (info) Auto Link Activated
'queuelinkin2' on connection broker2
2018-09-21 04:09:36.160878 +0200 ROUTER_CORE (info) Auto Link Activated
'queuelinkout2' on connection broker2
2018-09-21 04:09:36.161011 +0200 SERVER (trace) [1]:0 -> @begin(17)
[next-outgoing-id=0, incoming-window=2147483647, outgoing-window=2147483647]
2018-09-21 04:09:36.161043 +0200 SERVER (trace) [1]:0 -> @attach(18)
[name="qdlink.zklLdMBBQylnBn0", handle=0, role=true, snd-settle-mode=2,
rcv-settle-mode=0, source=@source(40) [address="myQueue", durable=0,
expiry-policy=:"link-detach", timeout=0, dynamic=false], target=@target(41)
[durable=0, expiry-policy=:"link-detach", timeout=0, dynamic=false],
initial-delivery-count=0, max-message-size=0]
2018-09-21 04:09:36.161070 +0200 SERVER (trace) [1]:0 -> @attach(18)
[name="qdlink.I_oRtFllHUlJktF", handle=1, role=false, snd-settle-mode=2,
rcv-settle-mode=0, source=@source(40) [durable=0, expiry-policy=:"link-detach",
timeout=0, dynamic=false], target=@target(41) [address="myQueue", durable=0,
expiry-policy=:"link-detach", timeout=0, dynamic=false],
initial-delivery-count=0, max-message-size=0]
2018-09-21 04:09:36.162863 +0200 SERVER (trace) [2]: <- SASL
2018-09-21 04:09:36.162885 +0200 SERVER (trace) [2]:0 <- @sasl-mechanisms(64)
[sasl-server-mechanisms=@PN_SYMBOL[:ANONYMOUS]]
2018-09-21 04:09:36.162902 +0200 SERVER (trace) [2]:0 -> @sasl-init(65)
[mechanism=:ANONYMOUS, initial-response=b"anonymous"]
2018-09-21 04:09:36.164509 +0200 SERVER (trace) [2]:0 <- @sasl-outcome(68)
[code=0]
2018-09-21 04:09:36.164528 +0200 SERVER (trace) [2]: -> AMQP
2018-09-21 04:09:36.164582 +0200 SERVER (trace) [2]:0 -> @open(16)
[container-id="router.10105", hostname="10.27.43.61", max-frame-size=16384,
channel-max=32767, idle-time-out=8000, offered-capabilities=:"ANONYMOUS-RELAY",
properties={:product="qpid-dispatch-router", :version="1.3.0"}]
2018-09-21 04:09:36.167050 +0200 SERVER (trace) [1]:0 <- @begin(17)
[remote-channel=0, next-outgoing-id=0, incoming-window=8192,
outgoing-window=2048]
2018-09-21 04:09:36.167088 +0200 SERVER (trace) [1]:0 <- @attach(18)
[name="qdlink.zklLdMBBQylnBn0", handle=0, role=false, snd-settle-mode=2,
rcv-settle-mode=0, source=@source(40) [address="myQueue", durable=0,
expiry-policy=:"link-detach", dynamic=false, default-outcome=@modified(39)
[delivery-failed=true], outcomes=@PN_SYMBOL[:"amqp:accepted:list",
:"amqp:released:list", :"amqp:rejected:list"],
capabilities=@PN_SYMBOL[:queue]], target=@target(41) [durable=0,
expiry-policy=:"link-detach", timeout=0, dynamic=false], unsettled={},
initial-delivery-count=0, offered-capabilities=@PN_SYMBOL[:"SHARED-SUBS"],
properties={}]
2018-09-21 04:09:36.167130 +0200 SERVER (trace) [1]:0 <- @attach(18)
[name="qdlink.I_oRtFllHUlJktF", handle=1, role=true, snd-settle-mode=2,
rcv-settle-mode=0, source=@source(40) [durable=0, expiry-policy=:"link-detach",
timeout=0, dynamic=false], target=@target(41) [address="myQueue", durable=0,
expiry-policy=:"link-detach", dynamic=false,
capabilities=@PN_SYMBOL[:"REJECT_UNROUTABLE", :"DELAYED_DELIVERY"]],
unsettled={}, max-message-size=10240,
offered-capabilities=@PN_SYMBOL[:"REJECT_UNROUTABLE", :"DELAYED_DELIVERY"],
properties={}]
2018-09-21 04:09:36.167145 +0200 SERVER (trace) [1]:0 <- @flow(19)
[next-incoming-id=0, incoming-window=8192, next-outgoing-id=0,
outgoing-window=2048, handle=1, delivery-count=0, link-credit=20000, echo=false]
2018-09-21 04:09:36.167198 +0200 ROUTER_CORE (trace) Core action
'link_second_attach'
2018-09-21 04:09:36.167218 +0200 ROUTER_CORE (trace) Core action
'link_second_attach'
2018-09-21 04:09:36.167290 +0200 ROUTER_CORE (trace) Core action 'link_flow'
2018-09-21 04:09:36.168904 +0200 SERVER (trace) [2]: <- AMQP
2018-09-21 04:09:36.168932 +0200 SERVER (trace) [2]:0 <- @open(16)
[container-id="a51c297e-c548-42df-a42c-a3e8e0ae2356", max-frame-size=16384,
channel-max=255, idle-time-out=0,
offered-capabilities=@PN_SYMBOL[:"ANONYMOUS-RELAY", :"SHARED-SUBS",
:"sole-connection-for-container"], properties={:product="qpid",
:version="7.0.3", :"qpid.build"="62d7ae692b47746c42949c2e646f33a966e6a1a1",
:"qpid.instance_name"="Broker",
:"qpid.virtualhost_properties_supported"="true"}]
2018-09-21 04:09:36.169024 +0200 ROUTER_CORE (trace) Core action
'connection_opened'
2018-09-21 04:09:36.169038 +0200 ROUTER_CORE (info) Auto Link Activated
'queuelinkin1' on connection broker1
2018-09-21 04:09:36.169053 +0200 ROUTER_CORE (info) Auto Link Activated
'queuelinkout1' on connection broker1
2018-09-21 04:09:36.169151 +0200 SERVER (trace) [2]:0 -> @begin(17)
[next-outgoing-id=0, incoming-window=2147483647, outgoing-window=2147483647]
2018-09-21 04:09:36.169183 +0200 SERVER (trace) [2]:0 -> @attach(18)
[name="qdlink.D9komknyHjBJugZ", handle=0, role=true, snd-settle-mode=2,
rcv-settle-mode=0, source=@source(40) [address="myQueue", durable=0,
expiry-policy=:"link-detach", timeout=0, dynamic=false], target=@target(41)
[durable=0, expiry-policy=:"link-detach", timeout=0, dynamic=false],
initial-delivery-count=0, max-message-size=0]
2018-09-21 04:09:36.169206 +0200 SERVER (trace) [2]:0 -> @attach(18)
[name="qdlink.CPf9USsD1_a93Wn", handle=1, role=false, snd-settle-mode=2,
rcv-settle-mode=0, source=@source(40) [durable=0, expiry-policy=:"link-detach",
timeout=0, dynamic=false], target=@target(41) [address="myQueue", durable=0,
expiry-policy=:"link-detach", timeout=0, dynamic=false],
initial-delivery-count=0, max-message-size=0]
2018-09-21 04:09:36.173693 +0200 SERVER (trace) [2]:0 <- @begin(17)
[remote-channel=0, next-outgoing-id=0, incoming-window=8192,
outgoing-window=2048]
2018-09-21 04:09:36.173733 +0200 SERVER (trace) [2]:0 <- @attach(18)
[name="qdlink.D9komknyHjBJugZ", handle=0, role=false, snd-settle-mode=2,
rcv-settle-mode=0, source=@source(40) [address="myQueue", durable=0,
expiry-policy=:"link-detach", dynamic=false, default-outcome=@modified(39)
[delivery-failed=true], outcomes=@PN_SYMBOL[:"amqp:accepted:list",
:"amqp:released:list", :"amqp:rejected:list"],
capabilities=@PN_SYMBOL[:queue]], target=@target(41) [durable=0,
expiry-policy=:"link-detach", timeout=0, dynamic=false], unsettled={},
initial-delivery-count=0, offered-capabilities=@PN_SYMBOL[:"SHARED-SUBS"],
properties={}]
2018-09-21 04:09:36.173758 +0200 SERVER (trace) [2]:0 <- @attach(18)
[name="qdlink.CPf9USsD1_a93Wn", handle=1, role=true, snd-settle-mode=2,
rcv-settle-mode=0, source=@source(40) [durable=0, expiry-policy=:"link-detach",
timeout=0, dynamic=false], target=@target(41) [address="myQueue", durable=0,
expiry-policy=:"link-detach", dynamic=false,
capabilities=@PN_SYMBOL[:"REJECT_UNROUTABLE", :"DELAYED_DELIVERY"]],
unsettled={}, max-message-size=10240,
offered-capabilities=@PN_SYMBOL[:"REJECT_UNROUTABLE", :"DELAYED_DELIVERY"],
properties={}]
2018-09-21 04:09:36.173771 +0200 SERVER (trace) [2]:0 <- @flow(19)
[next-incoming-id=0, incoming-window=8192, next-outgoing-id=0,
outgoing-window=2048, handle=1, delivery-count=0, link-credit=20000, echo=false]
2018-09-21 04:09:36.173807 +0200 ROUTER_CORE (trace) Core action
'link_second_attach'
2018-09-21 04:09:36.173821 +0200 ROUTER_CORE (trace) Core action
'link_second_attach'
2018-09-21 04:09:36.173828 +0200 ROUTER_CORE (trace) Core action 'link_flow'
2018-09-21 04:09:37.128774 +0200 ROUTER_CORE (trace) Core action 'send_to'
2018-09-21 04:09:37.128960 +0200 ROUTER_HELLO (trace) SENT:
HELLO(id=router.10105 pv=1 area=0 inst=1537495776 seen=[])
2018-09-21 04:09:37.129044 +0200 ROUTER_CORE (trace) Core action 'send_to'
2018-09-21 04:09:37.129120 +0200 ROUTER_LS (trace) SENT: RA(id=router.10105
pv=1 area=0 inst=1537495776 ls_seq=0 mobile_seq=0)
2018-09-21 04:09:37.129194 +0200 ROUTER_CORE (trace) Core action 'send_to'
2018-09-21 04:09:37.129329 +0200 ROUTER_MA (trace) SENT: MAU(id=router.10105
pv=1 area=0 mobile_seq=1 add=[u'M0myQueue'] del=[])
2018-09-21 04:09:38.129617 +0200 ROUTER_CORE (trace) Core action 'send_to'
2018-09-21 04:09:38.129702 +0200 ROUTER_HELLO (trace) SENT:
HELLO(id=router.10105 pv=1 area=0 inst=1537495776 seen=[])
2018-09-21 04:09:39.129962 +0200 ROUTER_CORE (trace) Core action 'send_to'
2018-09-21 04:09:39.130062 +0200 ROUTER_HELLO (trace) SENT:
HELLO(id=router.10105 pv=1 area=0 inst=1537495776 seen=[])
2018-09-21 04:09:40.130329 +0200 ROUTER_CORE (trace) Core action 'send_to'
2018-09-21 04:09:40.130439 +0200 ROUTER_HELLO (trace) SENT:
HELLO(id=router.10105 pv=1 area=0 inst=1537495776 seen=[])
2018-09-21 04:09:40.167723 +0200 SERVER (trace) [1]:0 <- (EMPTY FRAME)
2018-09-21 04:09:40.173632 +0200 SERVER (trace) [2]:0 <- (EMPTY FRAME)
2018-09-21 04:09:41.130693 +0200 ROUTER_CORE (trace) Core action 'send_to'
2018-09-21 04:09:41.130792 +0200 ROUTER_HELLO (trace) SENT:
HELLO(id=router.10105 pv=1 area=0 inst=1537495776 seen=[])
2018-09-21 04:09:42.131092 +0200 ROUTER_CORE (trace) Core action 'send_to'
2018-09-21 04:09:42.131194 +0200 ROUTER_HELLO (trace) SENT:
HELLO(id=router.10105 pv=1 area=0 inst=1537495776 seen=[])
2018-09-21 04:09:43.131488 +0200 ROUTER_CORE (trace) Core action 'send_to'
2018-09-21 04:09:43.131579 +0200 ROUTER_HELLO (trace) SENT:
HELLO(id=router.10105 pv=1 area=0 inst=1537495776 seen=[])
2018-09-21 04:09:44.131854 +0200 ROUTER_CORE (trace) Core action 'send_to'
2018-09-21 04:09:44.131945 +0200 ROUTER_HELLO (trace) SENT:
HELLO(id=router.10105 pv=1 area=0 inst=1537495776 seen=[])
2018-09-21 04:09:44.168408 +0200 SERVER (trace) [1]:0 <- (EMPTY FRAME)
2018-09-21 04:09:44.174218 +0200 SERVER (trace) [2]:0 <- (EMPTY FRAME)
2018-09-21 04:09:45.132292 +0200 ROUTER_CORE (trace) Core action 'send_to'
2018-09-21 04:09:45.132351 +0200 ROUTER_HELLO (trace) SENT:
HELLO(id=router.10105 pv=1 area=0 inst=1537495776 seen=[])
2018-09-21 04:09:46.132645 +0200 ROUTER_CORE (trace) Core action 'send_to'
2018-09-21 04:09:46.132776 +0200 ROUTER_HELLO (trace) SENT:
HELLO(id=router.10105 pv=1 area=0 inst=1537495776 seen=[])
2018-09-21 04:09:47.133056 +0200 ROUTER_CORE (trace) Core action 'send_to'
2018-09-21 04:09:47.133153 +0200 ROUTER_HELLO (trace) SENT:
HELLO(id=router.10105 pv=1 area=0 inst=1537495776 seen=[])
2018-09-21 04:09:48.133398 +0200 ROUTER_CORE (trace) Core action 'send_to'
2018-09-21 04:09:48.133514 +0200 ROUTER_HELLO (trace) SENT:
HELLO(id=router.10105 pv=1 area=0 inst=1537495776 seen=[])
2018-09-21 04:09:48.168877 +0200 SERVER (trace) [1]:0 <- (EMPTY FRAME)
2018-09-21 04:09:48.174293 +0200 SERVER (trace) [2]:0 <- (EMPTY FRAME)
2018-09-21 04:09:49.133847 +0200 ROUTER_CORE (trace) Core action 'send_to'
2018-09-21 04:09:49.133941 +0200 ROUTER_HELLO (trace) SENT:
HELLO(id=router.10105 pv=1 area=0 inst=1537495776 seen=[])
2018-09-21 04:09:50.134210 +0200 ROUTER_CORE (trace) Core action 'send_to'
2018-09-21 04:09:50.134306 +0200 ROUTER_HELLO (trace) SENT:
HELLO(id=router.10105 pv=1 area=0 inst=1537495776 seen=[])
2018-09-21 04:09:50.418944 +0200 SERVER (trace) Accepting connection on
0.0.0.0:10105
2018-09-21 04:09:50.419093 +0200 SERVER (trace) [3]: Accepting incoming
connection to '0.0.0.0:10105'
2018-09-21 04:09:50.419184 +0200 POLICY (trace) ALLOW Connection '10.27.43.61'
based on global connection count. nConnections= 1
2018-09-21 04:09:50.419209 +0200 SERVER (info) [3]: Accepted connection to
0.0.0.0:10105 from 10.27.43.61:49196
2018-09-21 04:09:50.524764 +0200 SERVER (trace) [3]: <- SASL
2018-09-21 04:09:50.524823 +0200 SERVER (trace) [3]: -> SASL
2018-09-21 04:09:50.524851 +0200 SERVER (trace) [3]:0 -> @sasl-mechanisms(64)
[sasl-server-mechanisms=@PN_SYMBOL[:ANONYMOUS]]
2018-09-21 04:09:50.532846 +0200 SERVER (trace) [3]:0 <- @sasl-init(65)
[mechanism=:ANONYMOUS, initial-response=b"", hostname="green-lx-slave1"]
2018-09-21 04:09:50.532866 +0200 SERVER (trace) [3]:0 -> @sasl-outcome(68)
[code=0]
2018-09-21 04:09:50.538298 +0200 SERVER (trace) [3]: <- AMQP
2018-09-21 04:09:50.538324 +0200 SERVER (trace) [3]:0 <- @open(16)
[container-id="ID:0196e534-babb-4a1b-ba8a-8b7c80b087ec:1",
hostname="green-lx-slave1", max-frame-size=1048576, channel-max=32767,
idle-time-out=30000,
desired-capabilities=@PN_SYMBOL[:"sole-connection-for-container"],
properties={:product="QpidJMS", :version="0.11.1", :platform="JVM: 1.8.0_141,
25.141-b15, Oracle Corporation, OS: Windows 10, 10.0, amd64"}]
2018-09-21 04:09:50.538398 +0200 SERVER (trace) [3]: -> AMQP
2018-09-21 04:09:50.538415 +0200 ROUTER_CORE (trace) Core action
'connection_opened'
2018-09-21 04:09:50.538493 +0200 SERVER (trace) [3]:0 -> @open(16)
[container-id="router.10105", max-frame-size=16384, channel-max=32767,
idle-time-out=8000, offered-capabilities=:"ANONYMOUS-RELAY",
properties={:product="qpid-dispatch-router", :version="1.3.0"}]
2018-09-21 04:09:50.546516 +0200 SERVER (trace) [3]:0 <- @begin(17)
[next-outgoing-id=1, incoming-window=2047, outgoing-window=2147483647,
handle-max=65535]
2018-09-21 04:09:50.546553 +0200 SERVER (trace) [3]:0 -> @begin(17)
[remote-channel=0, next-outgoing-id=0, incoming-window=2147483647,
outgoing-window=2147483647]
2018-09-21 04:09:50.558585 +0200 SERVER (trace) [3]:1 <- @begin(17)
[next-outgoing-id=1, incoming-window=2047, outgoing-window=2147483647,
handle-max=65535]
2018-09-21 04:09:50.558643 +0200 SERVER (trace) [3]:1 -> @begin(17)
[remote-channel=1, next-outgoing-id=0, incoming-window=2147483647,
outgoing-window=2147483647]
2018-09-21 04:09:50.577237 +0200 SERVER (trace) [3]:1 <- @attach(18)
[name="qpid-jms:sender:ID:a42ac611-4b9f-45be-8e42-bf9c7f782724:1:1:1:myQueue",
handle=0, role=false, snd-settle-mode=0, rcv-settle-mode=0, source=@source(40)
[address="ID:a42ac611-4b9f-45be-8e42-bf9c7f782724:1:1:1", durable=0,
expiry-policy=:"session-end", timeout=0, dynamic=false,
outcomes=@PN_SYMBOL[:"amqp:accepted:list", :"amqp:rejected:list"]],
target=@target(41) [address="myQueue", durable=0, expiry-policy=:"session-end",
timeout=0, dynamic=false, capabilities=@PN_SYMBOL[:queue]],
incomplete-unsettled=false, initial-delivery-count=0]
2018-09-21 04:09:50.577440 +0200 ROUTER_CORE (trace) Core action
'link_first_attach'
2018-09-21 04:09:50.577462 +0200 DEFAULT (trace) Parse tree search for 'myQueue'
2018-09-21 04:09:50.577469 +0200 DEFAULT (trace) Parse tree match not found
2018-09-21 04:09:50.577491 +0200 DEFAULT (trace) Parse tree search for 'myQueue'
2018-09-21 04:09:50.577600 +0200 SERVER (trace) [3]:1 -> @attach(18)
[name="qpid-jms:sender:ID:a42ac611-4b9f-45be-8e42-bf9c7f782724:1:1:1:myQueue",
handle=0, role=true, snd-settle-mode=2, rcv-settle-mode=0, source=@source(40)
[address="ID:a42ac611-4b9f-45be-8e42-bf9c7f782724:1:1:1", durable=0, timeout=0,
dynamic=false, outcomes=@PN_SYMBOL[:"amqp:accepted:list",
:"amqp:rejected:list"]], target=@target(41) [address="myQueue", durable=0,
timeout=0, dynamic=false, capabilities=@PN_SYMBOL[:queue]],
initial-delivery-count=0, max-message-size=0]
2018-09-21 04:09:50.577621 +0200 SERVER (trace) [3]:1 -> @flow(19)
[next-incoming-id=1, incoming-window=2147483647, next-outgoing-id=0,
outgoing-window=2147483647, handle=0, delivery-count=0, link-credit=250,
drain=false]
2018-09-21 04:09:50.630912 +0200 SERVER (trace) [3]:1 <- @transfer(20)
[handle=0, delivery-id=0, delivery-tag=b"0", message-format=0, more=true]
(16362)
"\x00Sp\xc0\x02\x01A\x00Sr\xc1)\x04\xa3\x0ex-opt-jms-destQ\x00\xa3\x12x-opt-jms-msg-typeQ\x01\x00Ss\xc0p\x0a\xa1/ID:a42ac611-4b9f-45be-8e42-bf9c7f782724:1:1:1-1@\xa1\x07myQueue@@@\xa3$application/x-java-serialized-object@@\x83\x00\x00\x01e\xf7Q\x80p\x00Su\xb0\x00\x00O+\xac\xed\x00\x05ur\x00\x02[B\xac\xf3\x17\xf8\x06\x08T\xe0\x02\x00\x00xp\x00\x00O\x10\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x0"...
(truncated)
2018-09-21 04:09:50.631284 +0200 ROUTER_CORE (debug) Delivery incref:
dlv:7fe1ec0464d0 rc:1 qdr_link_deliver - newly created delivery, add to action
list
2018-09-21 04:09:50.631297 +0200 ROUTER_CORE (debug) Delivery incref:
dlv:7fe1ec0464d0 rc:2 qdr_link_deliver - protect returned value
2018-09-21 04:09:50.631322 +0200 ROUTER_CORE (trace) Core action 'link_deliver'
2018-09-21 04:09:50.631350 +0200 ROUTER_CORE (debug) Delivery incref:
dlv:7fe1ec0464d0 rc:3 referenced by a pn_delivery
2018-09-21 04:09:50.631363 +0200 ROUTER_CORE (debug) Delivery decref:
dlv:7fe1ec0464d0 rc:2 release protection of return from deliver
2018-09-21 04:09:50.631409 +0200 ROUTER_CORE (debug) Delivery incref:
dlv:7fe1fc08d410 rc:1 qdr_delivery_link_peers_CT - linked to peer (1)
2018-09-21 04:09:50.631418 +0200 ROUTER_CORE (debug) Delivery incref:
dlv:7fe1ec0464d0 rc:3 qdr_delivery_link_peers_CT - linked to peer (2)
2018-09-21 04:09:50.631437 +0200 ROUTER_CORE (debug) Delivery incref:
dlv:7fe1fc08d410 rc:2 qdr_forward_deliver_CT - add to undelivered list
2018-09-21 04:09:50.631449 +0200 ROUTER_CORE (debug) Delivery transfer:
dlv:7fe1ec0464d0 qdr_link_forward_CT: action-list -> unsettled-list
2018-09-21 04:09:50.631461 +0200 SERVER (trace) [3]:1 <- @transfer(20)
[handle=0, delivery-id=0, delivery-tag=b"0", message-format=0] (4083)
"\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x0"...
(truncated)
2018-09-21 04:09:50.631488 +0200 ROUTER_CORE (debug) Delivery incref:
dlv:7fe1ec0464d0 rc:4 qdr_deliver_continue - add to action list
2018-09-21 04:09:50.631610 +0200 ROUTER_CORE (trace) Core action
'deliver_continue'
2018-09-21 04:09:50.631625 +0200 ROUTER_CORE (debug) Delivery decref_CT:
dlv:7fe1ec0464d0 rc:3 qdr_deliver_continue_CT - remove from action
2018-09-21 04:09:50.631639 +0200 ROUTER_CORE (debug) Delivery incref:
dlv:7fe1fc08d410 rc:3 referenced by a pn_delivery
2018-09-21 04:09:50.631704 +0200 ROUTER_CORE (debug) Delivery transfer:
dlv:7fe1fc08d410 qdr_link_process_deliveries: undelivered-list -> unsettled-list
2018-09-21 04:09:50.631762 +0200 SERVER (trace) [1]:0 -> @transfer(20)
[handle=1, delivery-id=0, delivery-tag=b"\x00\x00\x00\x00\x00\x00\x00\x00",
message-format=0, more=true] (16346)
"\x00Sp\xc0\x02\x01A\x00Sr\xd1\x00\x00\x00,\x00\x00\x00\x04\xa3\x0ex-opt-jms-destQ\x00\xa3\x12x-opt-jms-msg-typeQ\x01\x00Ss\xc0p\x0a\xa1/ID:a42ac611-4b9f-45be-8e42-bf9c7f782724:1:1:1-1@\xa1\x07myQueue@@@\xa3$application/x-java-serialized-object@@\x83\x00\x00\x01e\xf7Q\x80p\x00Su\xb0\x00\x00O+\xac\xed\x00\x05ur\x00\x02[B\xac\xf3\x17\xf8\x06\x08T\xe0\x02\x00\x00xp\x00\x00O\x10\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x0"...
(truncated)
2018-09-21 04:09:50.631821 +0200 SERVER (trace) [1]:0 -> @transfer(20)
[handle=1, delivery-id=0, delivery-tag=b"\x00\x00\x00\x00\x00\x00\x00\x00",
message-format=0] (4105)
"\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x0"...
(truncated)
2018-09-21 04:09:50.631890 +0200 ROUTER_CORE (trace) Core action 'link_flow'
2018-09-21 04:09:50.638148 +0200 SERVER (trace) [1]:0 <- @detach(22) [handle=1,
closed=true, error=@error(29) [condition=:"amqp:link:message-size-exceeded",
description="delivery '\x00\x00\x00\x00\x00\x00\x00\x00' exceeds
max-message-size 10240"]]
2018-09-21 04:09:50.638182 +0200 SERVER (trace) [1]:0 <- @detach(22) [handle=0,
closed=true]
2018-09-21 04:09:50.638192 +0200 SERVER (trace) [1]:0 <- @end(23)
[error=@error(29) [condition=:"amqp:session:errant-link", description="Received
TRANSFER for link handle 1 which is in errored state."]]
2018-09-21 04:09:50.638250 +0200 ROUTER_CORE (trace) Core action 'link_detach'
2018-09-21 04:09:50.638274 +0200 ROUTER_CORE (debug) Delivery incref:
dlv:7fe1ec0464d0 rc:4 qdr_delivery_push_CT - add to updated list
2018-09-21 04:09:50.638285 +0200 SERVER (trace) [1]:0 -> @end(23) []
2018-09-21 04:09:50.638309 +0200 ROUTER_CORE (debug) Delivery decref_CT:
dlv:7fe1ec0464d0 rc:3 qdr_delivery_failed_CT - remove from unsettled list
2018-09-21 04:09:50.638317 +0200 ROUTER_CORE (debug) Delivery decref_CT:
dlv:7fe1fc08d410 rc:2 qdr_delivery_unlink_peers_CT - unlinked from peer (1)
2018-09-21 04:09:50.638321 +0200 ROUTER_CORE (debug) Delivery decref_CT:
dlv:7fe1ec0464d0 rc:2 qdr_delivery_unlink_peers_CT - unlinked from peer (2)
2018-09-21 04:09:50.638326 +0200 ROUTER_CORE (debug) Delivery decref_CT:
dlv:7fe1fc08d410 rc:1 qdr_link_cleanup_deliveries_CT - remove from unsettled
list
2018-09-21 04:09:50.638336 +0200 ROUTER_CORE (trace) Core action 'link_detach'
2018-09-21 04:09:50.638372 +0200 ROUTER_CORE (debug) Delivery decref:
dlv:7fe1ec0464d0 rc:1 removed reference from pn_delivery
2018-09-21 04:09:50.638392 +0200 ROUTER_CORE (debug) Delivery decref:
dlv:7fe1ec0464d0 rc:0 qdr_connection_process - remove from updated list
2018-09-21 04:09:50.638404 +0200 ROUTER_CORE (trace) Core action
'delete_delivery'
2018-09-21 04:09:50.638414 +0200 SERVER (trace) [3]:1 -> @flow(19)
[next-incoming-id=3, incoming-window=2147483647, next-outgoing-id=0,
outgoing-window=2147483647, handle=0, delivery-count=1, link-credit=250,
drain=false]
2018-09-21 04:09:50.638422 +0200 ROUTER_CORE (trace) Core action 'link_delete'
2018-09-21 04:09:50.638429 +0200 SERVER (trace) [3]:1 -> @disposition(21)
[role=true, first=0, settled=true, state=@modified(39) [delivery-failed=true,
undeliverable-here=false]]
2018-09-21 04:09:50.638450 +0200 ROUTER_CORE (trace) Core action 'link_delete'
2018-09-21 04:09:50.644818 +0200 SERVER (trace) [3]:0 <- @close(24) []
2018-09-21 04:09:50.644835 +0200 SERVER (trace) [3]: <- EOS
2018-09-21 04:09:50.644895 +0200 ROUTER_CORE (trace) Core action 'link_detach'
2018-09-21 04:09:50.644914 +0200 SERVER (trace) [3]:0 -> @close(24) []
2018-09-21 04:09:50.644997 +0200 SERVER (trace) [3]: -> EOS
2018-09-21 04:09:50.645019 +0200 POLICY (debug) Connection '10.27.43.61:49196'
closed with resources n_sessions=0, n_senders=0, n_receivers=0. nConnections= 0.
2018-09-21 04:09:50.645032 +0200 ROUTER_CORE (trace) Core action
'connection_closed'
2018-09-21 04:09:51.134684 +0200 ROUTER_CORE (trace) Core action 'send_to'
2018-09-21 04:09:51.134777 +0200 ROUTER_HELLO (trace) SENT:
HELLO(id=router.10105 pv=1 area=0 inst=1537495776 seen=[])
2018-09-21 04:09:52.135139 +0200 ROUTER_CORE (trace) Core action 'send_to'
2018-09-21 04:09:52.135201 +0200 ROUTER_HELLO (trace) SENT:
HELLO(id=router.10105 pv=1 area=0 inst=1537495776 seen=[])
2018-09-21 04:09:52.175173 +0200 SERVER (trace) [2]:0 <- (EMPTY FRAME)
2018-09-21 04:09:53.135469 +0200 ROUTER_CORE (trace) Core action 'send_to'
2018-09-21 04:09:53.135561 +0200 ROUTER_HELLO (trace) SENT:
HELLO(id=router.10105 pv=1 area=0 inst=1537495776 seen=[])
2018-09-21 04:09:54.135842 +0200 ROUTER_CORE (trace) Core action 'send_to'
2018-09-21 04:09:54.135927 +0200 ROUTER_HELLO (trace) SENT:
HELLO(id=router.10105 pv=1 area=0 inst=1537495776 seen=[])
2018-09-21 04:09:54.638302 +0200 SERVER (trace) [1]:0 <- (EMPTY FRAME)
2018-09-21 04:09:55.136269 +0200 ROUTER_CORE (trace) Core action 'send_to'
2018-09-21 04:09:55.136379 +0200 ROUTER_HELLO (trace) SENT:
HELLO(id=router.10105 pv=1 area=0 inst=1537495776 seen=[])
2018-09-21 04:09:56.136662 +0200 ROUTER_CORE (trace) Core action 'send_to'
2018-09-21 04:09:56.136741 +0200 ROUTER_HELLO (trace) SENT:
HELLO(id=router.10105 pv=1 area=0 inst=1537495776 seen=[])
2018-09-21 04:09:56.175736 +0200 SERVER (trace) [2]:0 <- (EMPTY FRAME)
2018-09-21 04:09:57.136985 +0200 ROUTER_CORE (trace) Core action 'send_to'
2018-09-21 04:09:57.137087 +0200 ROUTER_HELLO (trace) SENT:
HELLO(id=router.10105 pv=1 area=0 inst=1537495776 seen=[])
2018-09-21 04:09:58.137347 +0200 ROUTER_CORE (trace) Core action 'send_to'
2018-09-21 04:09:58.137450 +0200 ROUTER_HELLO (trace) SENT:
HELLO(id=router.10105 pv=1 area=0 inst=1537495776 seen=[])
2018-09-21 04:09:58.638280 +0200 SERVER (trace) [1]:0 <- (EMPTY FRAME)
2018-09-21 04:09:58.842513 +0200 SERVER (trace) Accepting connection on
0.0.0.0:10105
2018-09-21 04:09:58.842588 +0200 SERVER (trace) [4]: Accepting incoming
connection to '0.0.0.0:10105'
2018-09-21 04:09:58.842722 +0200 POLICY (trace) ALLOW Connection '10.27.43.61'
based on global connection count. nConnections= 1
2018-09-21 04:09:58.842734 +0200 SERVER (info) [4]: Accepted connection to
0.0.0.0:10105 from 10.27.43.61:49209
2018-09-21 04:09:58.945338 +0200 SERVER (trace) [4]: <- SASL
2018-09-21 04:09:58.945389 +0200 SERVER (trace) [4]: -> SASL
2018-09-21 04:09:58.945411 +0200 SERVER (trace) [4]:0 -> @sasl-mechanisms(64)
[sasl-server-mechanisms=@PN_SYMBOL[:ANONYMOUS]]
2018-09-21 04:09:58.958905 +0200 SERVER (trace) [4]:0 <- @sasl-init(65)
[mechanism=:ANONYMOUS, initial-response=b"", hostname="green-lx-slave1"]
2018-09-21 04:09:58.958931 +0200 SERVER (trace) [4]:0 -> @sasl-outcome(68)
[code=0]
2018-09-21 04:09:58.969020 +0200 SERVER (trace) [4]: <- AMQP
2018-09-21 04:09:58.969051 +0200 SERVER (trace) [4]:0 <- @open(16)
[container-id="ID:92107a9d-ea74-4cd0-80ba-5b59463c70fc:1",
hostname="green-lx-slave1", max-frame-size=1048576, channel-max=32767,
idle-time-out=30000,
desired-capabilities=@PN_SYMBOL[:"sole-connection-for-container"],
properties={:product="QpidJMS", :version="0.11.1", :platform="JVM: 1.8.0_141,
25.141-b15, Oracle Corporation, OS: Windows 10, 10.0, amd64"}]
2018-09-21 04:09:58.969145 +0200 SERVER (trace) [4]: -> AMQP
2018-09-21 04:09:58.969163 +0200 ROUTER_CORE (trace) Core action
'connection_opened'
2018-09-21 04:09:58.969195 +0200 SERVER (trace) [4]:0 -> @open(16)
[container-id="router.10105", max-frame-size=16384, channel-max=32767,
idle-time-out=8000, offered-capabilities=:"ANONYMOUS-RELAY",
properties={:product="qpid-dispatch-router", :version="1.3.0"}]
2018-09-21 04:09:58.982905 +0200 SERVER (trace) [4]:0 <- @begin(17)
[next-outgoing-id=1, incoming-window=2047, outgoing-window=2147483647,
handle-max=65535]
2018-09-21 04:09:58.982960 +0200 SERVER (trace) [4]:0 -> @begin(17)
[remote-channel=0, next-outgoing-id=0, incoming-window=2147483647,
outgoing-window=2147483647]
2018-09-21 04:09:59.003650 +0200 SERVER (trace) [4]:1 <- @begin(17)
[next-outgoing-id=1, incoming-window=2047, outgoing-window=2147483647,
handle-max=65535]
2018-09-21 04:09:59.003692 +0200 SERVER (trace) [4]:1 -> @begin(17)
[remote-channel=1, next-outgoing-id=0, incoming-window=2147483647,
outgoing-window=2147483647]
2018-09-21 04:09:59.023799 +0200 SERVER (trace) [4]:1 <- @attach(18)
[name="qpid-jms:sender:ID:ebe502fb-4181-4120-a2c4-281ac1629eee:1:1:1:myQueue",
handle=0, role=false, snd-settle-mode=0, rcv-settle-mode=0, source=@source(40)
[address="ID:ebe502fb-4181-4120-a2c4-281ac1629eee:1:1:1", durable=0,
expiry-policy=:"session-end", timeout=0, dynamic=false,
outcomes=@PN_SYMBOL[:"amqp:accepted:list", :"amqp:rejected:list"]],
target=@target(41) [address="myQueue", durable=0, expiry-policy=:"session-end",
timeout=0, dynamic=false, capabilities=@PN_SYMBOL[:queue]],
incomplete-unsettled=false, initial-delivery-count=0]
2018-09-21 04:09:59.023997 +0200 ROUTER_CORE (trace) Core action
'link_first_attach'
2018-09-21 04:09:59.024041 +0200 DEFAULT (trace) Parse tree search for 'myQueue'
2018-09-21 04:09:59.024052 +0200 DEFAULT (trace) Parse tree match not found
2018-09-21 04:09:59.024058 +0200 DEFAULT (trace) Parse tree search for 'myQueue'
2018-09-21 04:09:59.024179 +0200 SERVER (trace) [4]:1 -> @attach(18)
[name="qpid-jms:sender:ID:ebe502fb-4181-4120-a2c4-281ac1629eee:1:1:1:myQueue",
handle=0, role=true, snd-settle-mode=2, rcv-settle-mode=0, source=@source(40)
[address="ID:ebe502fb-4181-4120-a2c4-281ac1629eee:1:1:1", durable=0, timeout=0,
dynamic=false, outcomes=@PN_SYMBOL[:"amqp:accepted:list",
:"amqp:rejected:list"]], target=@target(41) [address="myQueue", durable=0,
timeout=0, dynamic=false, capabilities=@PN_SYMBOL[:queue]],
initial-delivery-count=0, max-message-size=0]
2018-09-21 04:09:59.024204 +0200 SERVER (trace) [4]:1 -> @flow(19)
[next-incoming-id=1, incoming-window=2147483647, next-outgoing-id=0,
outgoing-window=2147483647, handle=0, delivery-count=0, link-credit=250,
drain=false]
2018-09-21 04:09:59.060091 +0200 SERVER (trace) [4]:1 <- @transfer(20)
[handle=0, delivery-id=0, delivery-tag=b"0", message-format=0] (445)
"\x00Sp\xc0\x02\x01A\x00Sr\xc1)\x04\xa3\x0ex-opt-jms-destQ\x00\xa3\x12x-opt-jms-msg-typeQ\x01\x00Ss\xc0p\x0a\xa1/ID:ebe502fb-4181-4120-a2c4-281ac1629eee:1:1:1-1@\xa1\x07myQueue@@@\xa3$application/x-java-serialized-object@@\x83\x00\x00\x01e\xf7Q\xa1m\x00Su\xb0\x00\x00\x01\x0b\xac\xed\x00\x05ur\x00\x02[B\xac\xf3\x17\xf8\x06\x08T\xe0\x02\x00\x00xp\x00\x00\x00\xf0\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x0"...
(truncated)
2018-09-21 04:09:59.060168 +0200 ROUTER_CORE (debug) Delivery incref:
dlv:7fe1ec046690 rc:1 qdr_link_deliver - newly created delivery, add to action
list
2018-09-21 04:09:59.060178 +0200 ROUTER_CORE (debug) Delivery incref:
dlv:7fe1ec046690 rc:2 qdr_link_deliver - protect returned value
2018-09-21 04:09:59.060199 +0200 ROUTER_CORE (debug) Delivery incref:
dlv:7fe1ec046690 rc:3 referenced by a pn_delivery
2018-09-21 04:09:59.060205 +0200 ROUTER_CORE (debug) Delivery decref:
dlv:7fe1ec046690 rc:2 release protection of return from deliver
2018-09-21 04:09:59.060216 +0200 ROUTER_CORE (trace) Core action 'link_deliver'
2018-09-21 04:09:59.060253 +0200 ROUTER_CORE (debug) Delivery incref:
dlv:7fe1fc08d5d0 rc:1 qdr_delivery_link_peers_CT - linked to peer (1)
2018-09-21 04:09:59.060263 +0200 ROUTER_CORE (debug) Delivery incref:
dlv:7fe1ec046690 rc:3 qdr_delivery_link_peers_CT - linked to peer (2)
2018-09-21 04:09:59.060271 +0200 ROUTER_CORE (debug) Delivery incref:
dlv:7fe1fc08d5d0 rc:2 qdr_forward_deliver_CT - add to undelivered list
2018-09-21 04:09:59.060318 +0200 ROUTER_CORE (debug) Delivery transfer:
dlv:7fe1ec046690 qdr_link_forward_CT: action-list -> unsettled-list
2018-09-21 04:09:59.060358 +0200 ROUTER_CORE (debug) Delivery incref:
dlv:7fe1fc08d5d0 rc:3 referenced by a pn_delivery
2018-09-21 04:09:59.060373 +0200 ROUTER_CORE (debug) Delivery transfer:
dlv:7fe1fc08d5d0 qdr_link_process_deliveries: undelivered-list -> unsettled-list
2018-09-21 04:09:59.060415 +0200 SERVER (trace) [2]:0 -> @transfer(20)
[handle=1, delivery-id=0, delivery-tag=b"\x01\x00\x00\x00\x00\x00\x00\x00",
message-format=0] (451)
"\x00Sp\xc0\x02\x01A\x00Sr\xd1\x00\x00\x00,\x00\x00\x00\x04\xa3\x0ex-opt-jms-destQ\x00\xa3\x12x-opt-jms-msg-typeQ\x01\x00Ss\xc0p\x0a\xa1/ID:ebe502fb-4181-4120-a2c4-281ac1629eee:1:1:1-1@\xa1\x07myQueue@@@\xa3$application/x-java-serialized-object@@\x83\x00\x00\x01e\xf7Q\xa1m\x00Su\xb0\x00\x00\x01\x0b\xac\xed\x00\x05ur\x00\x02[B\xac\xf3\x17\xf8\x06\x08T\xe0\x02\x00\x00xp\x00\x00\x00\xf0\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x0"...
(truncated)
2018-09-21 04:09:59.060469 +0200 ROUTER_CORE (trace) Core action 'link_flow'
2018-09-21 04:09:59.069924 +0200 SERVER (trace) [2]:0 <- @disposition(21)
[role=true, first=0, last=0, settled=true, state=@accepted(36) []]
2018-09-21 04:09:59.069953 +0200 ROUTER_CORE (debug) Delivery incref:
dlv:7fe1fc08d5d0 rc:4 qdr_delivery_update_disposition - add to action list
2018-09-21 04:09:59.069966 +0200 ROUTER_CORE (debug) Delivery decref:
dlv:7fe1fc08d5d0 rc:3 removed reference from pn_delivery
2018-09-21 04:09:59.069979 +0200 ROUTER_CORE (trace) Core action
'update_delivery'
2018-09-21 04:09:59.069996 +0200 ROUTER_CORE (debug) Delivery decref_CT:
dlv:7fe1fc08d5d0 rc:2 qdr_delivery_unlink_peers_CT - unlinked from peer (1)
2018-09-21 04:09:59.070000 +0200 ROUTER_CORE (debug) Delivery decref_CT:
dlv:7fe1ec046690 rc:2 qdr_delivery_unlink_peers_CT - unlinked from peer (2)
2018-09-21 04:09:59.070005 +0200 ROUTER_CORE (debug) Delivery incref:
dlv:7fe1ec046690 rc:3 qdr_delivery_push_CT - add to updated list
2018-09-21 04:09:59.070010 +0200 ROUTER_CORE (debug) Delivery decref_CT:
dlv:7fe1fc08d5d0 rc:1 qdr_update_delivery_CT - remove from action
2018-09-21 04:09:59.070014 +0200 ROUTER_CORE (debug) Delivery decref_CT:
dlv:7fe1fc08d5d0 rc:0 qdr_update_delivery_CT - removed from unsettled (1)
2018-09-21 04:09:59.070029 +0200 ROUTER_CORE (debug) Delivery decref_CT:
dlv:7fe1ec046690 rc:2 qdr_update_delivery_CT - removed from unsettled (2)
2018-09-21 04:09:59.070069 +0200 ROUTER_CORE (debug) Delivery decref:
dlv:7fe1ec046690 rc:1 removed reference from pn_delivery
2018-09-21 04:09:59.070081 +0200 ROUTER_CORE (debug) Delivery decref:
dlv:7fe1ec046690 rc:0 qdr_connection_process - remove from updated list
2018-09-21 04:09:59.070097 +0200 ROUTER_CORE (trace) Core action
'delete_delivery'
2018-09-21 04:09:59.070115 +0200 SERVER (trace) [4]:1 -> @flow(19)
[next-incoming-id=2, incoming-window=2147483647, next-outgoing-id=0,
outgoing-window=2147483647, handle=0, delivery-count=1, link-credit=250,
drain=false]
2018-09-21 04:09:59.070172 +0200 SERVER (trace) [4]:1 -> @disposition(21)
[role=true, first=0, settled=true, state=@accepted(36) []]
2018-09-21 04:09:59.075845 +0200 SERVER (trace) [4]:0 <- @close(24) []
2018-09-21 04:09:59.075858 +0200 SERVER (trace) [4]: <- EOS
2018-09-21 04:09:59.075882 +0200 SERVER (trace) [4]:0 -> @close(24) []
2018-09-21 04:09:59.075890 +0200 ROUTER_CORE (trace) Core action 'link_detach'
2018-09-21 04:09:59.075914 +0200 SERVER (trace) [4]: -> EOS
2018-09-21 04:09:59.075930 +0200 POLICY (debug) Connection '10.27.43.61:49209'
closed with resources n_sessions=0, n_senders=0, n_receivers=0. nConnections= 0.
2018-09-21 04:09:59.075943 +0200 ROUTER_CORE (trace) Core action
'connection_closed'
2018-09-21 04:09:59.137328 +0200 ROUTER_CORE (trace) Core action 'send_to'
2018-09-21 04:09:59.137431 +0200 ROUTER_HELLO (trace) SENT:
HELLO(id=router.10105 pv=1 area=0 inst=1537495776 seen=[])
2018-09-21 04:10:00.137680 +0200 ROUTER_CORE (trace) Core action 'send_to'
2018-09-21 04:10:00.137771 +0200 ROUTER_HELLO (trace) SENT:
HELLO(id=router.10105 pv=1 area=0 inst=1537495776 seen=[])
2018-09-21 04:10:01.138012 +0200 ROUTER_CORE (trace) Core action 'send_to'
2018-09-21 04:10:01.138105 +0200 ROUTER_HELLO (trace) SENT:
HELLO(id=router.10105 pv=1 area=0 inst=1537495776 seen=[])
2018-09-21 04:10:02.138361 +0200 ROUTER_CORE (trace) Core action 'send_to'
2018-09-21 04:10:02.138452 +0200 ROUTER_HELLO (trace) SENT:
HELLO(id=router.10105 pv=1 area=0 inst=1537495776 seen=[])
2018-09-21 04:10:02.638317 +0200 SERVER (trace) [1]:0 <- (EMPTY FRAME)
2018-09-21 04:10:03.070757 +0200 SERVER (trace) [2]:0 <- (EMPTY FRAME)
2018-09-21 04:10:03.138785 +0200 ROUTER_CORE (trace) Core action 'send_to'
2018-09-21 04:10:03.138829 +0200 ROUTER_HELLO (trace) SENT:
HELLO(id=router.10105 pv=1 area=0 inst=1537495776 seen=[])
2018-09-21 04:10:04.139055 +0200 ROUTER_CORE (trace) Core action 'send_to'
2018-09-21 04:10:04.139151 +0200 ROUTER_HELLO (trace) SENT:
HELLO(id=router.10105 pv=1 area=0 inst=1537495776 seen=[])
2018-09-21 04:10:05.139401 +0200 ROUTER_CORE (trace) Core action 'send_to'
2018-09-21 04:10:05.139493 +0200 ROUTER_HELLO (trace) SENT:
HELLO(id=router.10105 pv=1 area=0 inst=1537495776 seen=[])
2018-09-21 04:10:06.139715 +0200 ROUTER_CORE (trace) Core action 'send_to'
2018-09-21 04:10:06.139806 +0200 ROUTER_HELLO (trace) SENT:
HELLO(id=router.10105 pv=1 area=0 inst=1537495776 seen=[])
2018-09-21 04:10:06.639270 +0200 SERVER (trace) [1]:0 <- (EMPTY FRAME)
2018-09-21 04:10:07.071292 +0200 SERVER (trace) [2]:0 <- (EMPTY FRAME)
2018-09-21 04:10:07.140040 +0200 ROUTER_CORE (trace) Core action 'send_to'
2018-09-21 04:10:07.140130 +0200 ROUTER_HELLO (trace) SENT:
HELLO(id=router.10105 pv=1 area=0 inst=1537495776 seen=[])
2018-09-21 04:10:07.140203 +0200 ROUTER_CORE (trace) Core action 'send_to'
2018-09-21 04:10:07.140236 +0200 ROUTER_LS (trace) SENT: RA(id=router.10105
pv=1 area=0 inst=1537495776 ls_seq=0 mobile_seq=1)
2018-09-21 04:10:07.605035 +0200 SERVER (trace) Accepting connection on
0.0.0.0:10105
2018-09-21 04:10:07.605125 +0200 SERVER (trace) [5]: Accepting incoming
connection to '0.0.0.0:10105'
2018-09-21 04:10:07.605216 +0200 POLICY (trace) ALLOW Connection '10.27.43.61'
based on global connection count. nConnections= 1
2018-09-21 04:10:07.605227 +0200 SERVER (info) [5]: Accepted connection to
0.0.0.0:10105 from 10.27.43.61:49217
2018-09-21 04:10:07.702154 +0200 SERVER (trace) [5]: <- SASL
2018-09-21 04:10:07.702184 +0200 SERVER (trace) [5]: -> SASL
2018-09-21 04:10:07.702203 +0200 SERVER (trace) [5]:0 -> @sasl-mechanisms(64)
[sasl-server-mechanisms=@PN_SYMBOL[:ANONYMOUS]]
2018-09-21 04:10:07.714775 +0200 SERVER (trace) [5]:0 <- @sasl-init(65)
[mechanism=:ANONYMOUS, initial-response=b"", hostname="green-lx-slave1"]
2018-09-21 04:10:07.714795 +0200 SERVER (trace) [5]:0 -> @sasl-outcome(68)
[code=0]
2018-09-21 04:10:07.721676 +0200 SERVER (trace) [5]: <- AMQP
2018-09-21 04:10:07.721703 +0200 SERVER (trace) [5]:0 <- @open(16)
[container-id="ID:bf1926bb-8ff3-43e1-ab4f-c8a52b1de35a:1",
hostname="green-lx-slave1", max-frame-size=1048576, channel-max=32767,
idle-time-out=30000,
desired-capabilities=@PN_SYMBOL[:"sole-connection-for-container"],
properties={:product="QpidJMS", :version="0.11.1", :platform="JVM: 1.8.0_141,
25.141-b15, Oracle Corporation, OS: Windows 10, 10.0, amd64"}]
2018-09-21 04:10:07.721747 +0200 SERVER (trace) [5]: -> AMQP
2018-09-21 04:10:07.721763 +0200 ROUTER_CORE (trace) Core action
'connection_opened'
2018-09-21 04:10:07.721830 +0200 SERVER (trace) [5]:0 -> @open(16)
[container-id="router.10105", max-frame-size=16384, channel-max=32767,
idle-time-out=8000, offered-capabilities=:"ANONYMOUS-RELAY",
properties={:product="qpid-dispatch-router", :version="1.3.0"}]
2018-09-21 04:10:07.731166 +0200 SERVER (trace) [5]:0 <- @begin(17)
[next-outgoing-id=1, incoming-window=2047, outgoing-window=2147483647,
handle-max=65535]
2018-09-21 04:10:07.731195 +0200 SERVER (trace) [5]:0 -> @begin(17)
[remote-channel=0, next-outgoing-id=0, incoming-window=2147483647,
outgoing-window=2147483647]
2018-09-21 04:10:07.749381 +0200 SERVER (trace) [5]:1 <- @begin(17)
[next-outgoing-id=1, incoming-window=2047, outgoing-window=2147483647,
handle-max=65535]
2018-09-21 04:10:07.749416 +0200 SERVER (trace) [5]:1 -> @begin(17)
[remote-channel=1, next-outgoing-id=0, incoming-window=2147483647,
outgoing-window=2147483647]
2018-09-21 04:10:07.769090 +0200 SERVER (trace) [5]:1 <- @attach(18)
[name="qpid-jms:sender:ID:1d9a434b-cca8-4dc1-836c-715d62ea9fb5:1:1:1:myQueue",
handle=0, role=false, snd-settle-mode=0, rcv-settle-mode=0, source=@source(40)
[address="ID:1d9a434b-cca8-4dc1-836c-715d62ea9fb5:1:1:1", durable=0,
expiry-policy=:"session-end", timeout=0, dynamic=false,
outcomes=@PN_SYMBOL[:"amqp:accepted:list", :"amqp:rejected:list"]],
target=@target(41) [address="myQueue", durable=0, expiry-policy=:"session-end",
timeout=0, dynamic=false, capabilities=@PN_SYMBOL[:queue]],
incomplete-unsettled=false, initial-delivery-count=0]
2018-09-21 04:10:07.769191 +0200 ROUTER_CORE (trace) Core action
'link_first_attach'
2018-09-21 04:10:07.769217 +0200 DEFAULT (trace) Parse tree search for 'myQueue'
2018-09-21 04:10:07.769236 +0200 DEFAULT (trace) Parse tree match not found
2018-09-21 04:10:07.769242 +0200 DEFAULT (trace) Parse tree search for 'myQueue'
2018-09-21 04:10:07.769312 +0200 SERVER (trace) [5]:1 -> @attach(18)
[name="qpid-jms:sender:ID:1d9a434b-cca8-4dc1-836c-715d62ea9fb5:1:1:1:myQueue",
handle=0, role=true, snd-settle-mode=2, rcv-settle-mode=0, source=@source(40)
[address="ID:1d9a434b-cca8-4dc1-836c-715d62ea9fb5:1:1:1", durable=0, timeout=0,
dynamic=false, outcomes=@PN_SYMBOL[:"amqp:accepted:list",
:"amqp:rejected:list"]], target=@target(41) [address="myQueue", durable=0,
timeout=0, dynamic=false, capabilities=@PN_SYMBOL[:queue]],
initial-delivery-count=0, max-message-size=0]
2018-09-21 04:10:07.769331 +0200 SERVER (trace) [5]:1 -> @flow(19)
[next-incoming-id=1, incoming-window=2147483647, next-outgoing-id=0,
outgoing-window=2147483647, handle=0, delivery-count=0, link-credit=250,
drain=false]
2018-09-21 04:10:07.822183 +0200 SERVER (trace) [5]:1 <- @transfer(20)
[handle=0, delivery-id=0, delivery-tag=b"0", message-format=0, more=true]
(16362)
"\x00Sp\xc0\x02\x01A\x00Sr\xc1)\x04\xa3\x0ex-opt-jms-destQ\x00\xa3\x12x-opt-jms-msg-typeQ\x01\x00Ss\xc0p\x0a\xa1/ID:1d9a434b-cca8-4dc1-836c-715d62ea9fb5:1:1:1-1@\xa1\x07myQueue@@@\xa3$application/x-java-serialized-object@@\x83\x00\x00\x01e\xf7Q\xc3\x9c\x00Su\xb0\x00\x00O+\xac\xed\x00\x05ur\x00\x02[B\xac\xf3\x17\xf8\x06\x08T\xe0\x02\x00\x00xp\x00\x00O\x10\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x0"...
(truncated)
2018-09-21 04:10:07.822381 +0200 ROUTER_CORE (debug) Delivery incref:
dlv:7fe1f4053650 rc:1 qdr_link_deliver - newly created delivery, add to action
list
2018-09-21 04:10:07.822393 +0200 ROUTER_CORE (debug) Delivery incref:
dlv:7fe1f4053650 rc:2 qdr_link_deliver - protect returned value
2018-09-21 04:10:07.822422 +0200 ROUTER_CORE (trace) Core action 'link_deliver'
2018-09-21 04:10:07.822454 +0200 ROUTER_CORE (debug) Delivery incref:
dlv:7fe1f4053650 rc:3 referenced by a pn_delivery
2018-09-21 04:10:07.822464 +0200 ROUTER_CORE (debug) Delivery decref:
dlv:7fe1f4053650 rc:2 release protection of return from deliver
2018-09-21 04:10:07.822471 +0200 ROUTER_CORE (debug) Delivery incref:
dlv:7fe1fc08d790 rc:1 qdr_delivery_link_peers_CT - linked to peer (1)
2018-09-21 04:10:07.822490 +0200 ROUTER_CORE (debug) Delivery incref:
dlv:7fe1f4053650 rc:3 qdr_delivery_link_peers_CT - linked to peer (2)
2018-09-21 04:10:07.822495 +0200 ROUTER_CORE (debug) Delivery incref:
dlv:7fe1fc08d790 rc:2 qdr_forward_deliver_CT - add to undelivered list
2018-09-21 04:10:07.822515 +0200 ROUTER_CORE (debug) Delivery transfer:
dlv:7fe1f4053650 qdr_link_forward_CT: action-list -> unsettled-list
2018-09-21 04:10:07.822531 +0200 SERVER (trace) [5]:1 <- @transfer(20)
[handle=0, delivery-id=0, delivery-tag=b"0", message-format=0] (4083)
"\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x0"...
(truncated)
2018-09-21 04:10:07.822551 +0200 ROUTER_CORE (debug) Delivery incref:
dlv:7fe1fc08d790 rc:3 referenced by a pn_delivery
2018-09-21 04:10:07.822580 +0200 ROUTER_CORE (debug) Delivery incref:
dlv:7fe1f4053650 rc:4 qdr_deliver_continue - add to action list
2018-09-21 04:10:07.822707 +0200 ROUTER_CORE (trace) Core action
'deliver_continue'
2018-09-21 04:10:07.822721 +0200 ROUTER_CORE (debug) Delivery decref_CT:
dlv:7fe1f4053650 rc:3 qdr_deliver_continue_CT - remove from action
2018-09-21 04:10:07.822734 +0200 ROUTER_CORE (debug) Delivery transfer:
dlv:7fe1fc08d790 qdr_link_process_deliveries: undelivered-list -> unsettled-list
2018-09-21 04:10:07.822797 +0200 SERVER (trace) [2]:0 -> @transfer(20)
[handle=1, delivery-id=1, delivery-tag=b"\x02\x00\x00\x00\x00\x00\x00\x00",
message-format=0, more=true] (16346)
"\x00Sp\xc0\x02\x01A\x00Sr\xd1\x00\x00\x00,\x00\x00\x00\x04\xa3\x0ex-opt-jms-destQ\x00\xa3\x12x-opt-jms-msg-typeQ\x01\x00Ss\xc0p\x0a\xa1/ID:1d9a434b-cca8-4dc1-836c-715d62ea9fb5:1:1:1-1@\xa1\x07myQueue@@@\xa3$application/x-java-serialized-object@@\x83\x00\x00\x01e\xf7Q\xc3\x9c\x00Su\xb0\x00\x00O+\xac\xed\x00\x05ur\x00\x02[B\xac\xf3\x17\xf8\x06\x08T\xe0\x02\x00\x00xp\x00\x00O\x10\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x0"...
(truncated)
2018-09-21 04:10:07.822865 +0200 SERVER (trace) [2]:0 -> @transfer(20)
[handle=1, delivery-id=1, delivery-tag=b"\x02\x00\x00\x00\x00\x00\x00\x00",
message-format=0] (4105)
"\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x0"...
(truncated)
2018-09-21 04:10:07.822937 +0200 ROUTER_CORE (trace) Core action 'link_flow'
2018-09-21 04:10:07.826722 +0200 SERVER (trace) [2]:0 <- @detach(22) [handle=1,
closed=true, error=@error(29) [condition=:"amqp:link:message-size-exceeded",
description="delivery '\x02\x00\x00\x00\x00\x00\x00\x00' exceeds
max-message-size 10240"]]
2018-09-21 04:10:07.826741 +0200 SERVER (trace) [2]:0 <- @detach(22) [handle=0,
closed=true]
2018-09-21 04:10:07.826751 +0200 SERVER (trace) [2]:0 <- @end(23)
[error=@error(29) [condition=:"amqp:session:errant-link", description="Received
TRANSFER for link handle 1 which is in errored state."]]
2018-09-21 04:10:07.826777 +0200 ROUTER_CORE (trace) Core action 'link_detach'
2018-09-21 04:10:07.826788 +0200 ROUTER_CORE (debug) Delivery incref:
dlv:7fe1f4053650 rc:4 qdr_delivery_push_CT - add to updated list
2018-09-21 04:10:07.826793 +0200 SERVER (trace) [2]:0 -> @end(23) []
2018-09-21 04:10:07.826809 +0200 ROUTER_CORE (debug) Delivery decref_CT:
dlv:7fe1f4053650 rc:3 qdr_delivery_failed_CT - remove from unsettled list
2018-09-21 04:10:07.826831 +0200 ROUTER_CORE (debug) Delivery decref_CT:
dlv:7fe1fc08d790 rc:2 qdr_delivery_unlink_peers_CT - unlinked from peer (1)
2018-09-21 04:10:07.826837 +0200 ROUTER_CORE (debug) Delivery decref_CT:
dlv:7fe1f4053650 rc:2 qdr_delivery_unlink_peers_CT - unlinked from peer (2)
2018-09-21 04:10:07.826842 +0200 ROUTER_CORE (debug) Delivery decref_CT:
dlv:7fe1fc08d790 rc:1 qdr_link_cleanup_deliveries_CT - remove from unsettled
list
2018-09-21 04:10:07.826861 +0200 ROUTER_CORE (trace) Core action 'link_detach'
2018-09-21 04:10:07.826935 +0200 ROUTER_CORE (debug) Delivery decref:
dlv:7fe1f4053650 rc:1 removed reference from pn_delivery
2018-09-21 04:10:07.826952 +0200 ROUTER_CORE (debug) Delivery decref:
dlv:7fe1f4053650 rc:0 qdr_connection_process - remove from updated list
2018-09-21 04:10:07.826964 +0200 ROUTER_CORE (trace) Core action 'link_delete'
2018-09-21 04:10:07.826974 +0200 ROUTER_CORE (trace) Core action 'link_delete'
2018-09-21 04:10:07.826980 +0200 ROUTER_CORE (trace) Core action
'delete_delivery'
2018-09-21 04:10:07.826995 +0200 SERVER (trace) [5]:1 -> @flow(19)
[next-incoming-id=3, incoming-window=2147483647, next-outgoing-id=0,
outgoing-window=2147483647, handle=0, delivery-count=1, link-credit=250,
drain=false]
2018-09-21 04:10:07.827012 +0200 SERVER (trace) [5]:1 -> @disposition(21)
[role=true, first=0, settled=true, state=@modified(39) [delivery-failed=true,
undeliverable-here=false]]
2018-09-21 04:10:07.833052 +0200 SERVER (trace) [5]:0 <- @close(24) []
2018-09-21 04:10:07.833064 +0200 SERVER (trace) [5]: <- EOS
2018-09-21 04:10:07.833085 +0200 ROUTER_CORE (trace) Core action 'link_detach'
2018-09-21 04:10:07.833101 +0200 SERVER (trace) [5]:0 -> @close(24) []
2018-09-21 04:10:07.833120 +0200 SERVER (trace) [5]: -> EOS
2018-09-21 04:10:07.833139 +0200 POLICY (debug) Connection '10.27.43.61:49217'
closed with resources n_sessions=0, n_senders=0, n_receivers=0. nConnections= 0.
2018-09-21 04:10:07.833152 +0200 ROUTER_CORE (trace) Core action
'connection_closed'
2018-09-21 04:10:08.140211 +0200 ROUTER_CORE (trace) Core action 'send_to'
2018-09-21 04:10:08.140301 +0200 ROUTER_HELLO (trace) SENT:
HELLO(id=router.10105 pv=1 area=0 inst=1537495776 seen=[])
2018-09-21 04:10:08.140449 +0200 ROUTER_CORE (trace) Core action 'send_to'
2018-09-21 04:10:08.140471 +0200 ROUTER_MA (trace) SENT: MAU(id=router.10105
pv=1 area=0 mobile_seq=2 add=[] del=[u'M0myQueue'])
2018-09-21 04:10:09.140709 +0200 ROUTER_CORE (trace) Core action 'send_to'
2018-09-21 04:10:09.140800 +0200 ROUTER_HELLO (trace) SENT:
HELLO(id=router.10105 pv=1 area=0 inst=1537495776 seen=[])
2018-09-21 04:10:10.141047 +0200 ROUTER_CORE (trace) Core action 'send_to'
2018-09-21 04:10:10.141146 +0200 ROUTER_HELLO (trace) SENT:
HELLO(id=router.10105 pv=1 area=0 inst=1537495776 seen=[])
2018-09-21 04:10:10.639357 +0200 SERVER (trace) [1]:0 <- (EMPTY FRAME)
2018-09-21 04:10:11.141391 +0200 ROUTER_CORE (trace) Core action 'send_to'
2018-09-21 04:10:11.141488 +0200 ROUTER_HELLO (trace) SENT:
HELLO(id=router.10105 pv=1 area=0 inst=1537495776 seen=[])
2018-09-21 04:10:11.827195 +0200 SERVER (trace) [2]:0 <- (EMPTY FRAME)
2018-09-21 04:10:12.141771 +0200 ROUTER_CORE (trace) Core action 'send_to'
2018-09-21 04:10:12.141937 +0200 ROUTER_HELLO (trace) SENT:
HELLO(id=router.10105 pv=1 area=0 inst=1537495776 seen=[])
2018-09-21 04:10:13.142229 +0200 ROUTER_CORE (trace) Core action 'send_to'
2018-09-21 04:10:13.142312 +0200 ROUTER_HELLO (trace) SENT:
HELLO(id=router.10105 pv=1 area=0 inst=1537495776 seen=[])
2018-09-21 04:10:14.142607 +0200 ROUTER_CORE (trace) Core action 'send_to'
2018-09-21 04:10:14.142699 +0200 ROUTER_HELLO (trace) SENT:
HELLO(id=router.10105 pv=1 area=0 inst=1537495776 seen=[])
2018-09-21 04:10:14.640665 +0200 SERVER (trace) [1]:0 <- (EMPTY FRAME)
2018-09-21 04:10:15.142961 +0200 ROUTER_CORE (trace) Core action 'send_to'
2018-09-21 04:10:15.143055 +0200 ROUTER_HELLO (trace) SENT:
HELLO(id=router.10105 pv=1 area=0 inst=1537495776 seen=[])
2018-09-21 04:10:15.828183 +0200 SERVER (trace) [2]:0 <- (EMPTY FRAME)
2018-09-21 04:10:16.143287 +0200 ROUTER_CORE (trace) Core action 'send_to'
2018-09-21 04:10:16.143383 +0200 ROUTER_HELLO (trace) SENT:
HELLO(id=router.10105 pv=1 area=0 inst=1537495776 seen=[])
2018-09-21 04:10:17.143617 +0200 ROUTER_CORE (trace) Core action 'send_to'
2018-09-21 04:10:17.143704 +0200 ROUTER_HELLO (trace) SENT:
HELLO(id=router.10105 pv=1 area=0 inst=1537495776 seen=[])
2018-09-21 04:10:17.164120 +0200 SERVER (trace) Accepting connection on
0.0.0.0:10105
2018-09-21 04:10:17.164169 +0200 SERVER (trace) [6]: Accepting incoming
connection to '0.0.0.0:10105'
2018-09-21 04:10:17.164274 +0200 POLICY (trace) ALLOW Connection '10.27.43.61'
based on global connection count. nConnections= 1
2018-09-21 04:10:17.164286 +0200 SERVER (info) [6]: Accepted connection to
0.0.0.0:10105 from 10.27.43.61:49225
2018-09-21 04:10:17.255660 +0200 SERVER (trace) [6]: <- SASL
2018-09-21 04:10:17.255701 +0200 SERVER (trace) [6]: -> SASL
2018-09-21 04:10:17.255723 +0200 SERVER (trace) [6]:0 -> @sasl-mechanisms(64)
[sasl-server-mechanisms=@PN_SYMBOL[:ANONYMOUS]]
2018-09-21 04:10:17.265832 +0200 SERVER (trace) [6]:0 <- @sasl-init(65)
[mechanism=:ANONYMOUS, initial-response=b"", hostname="green-lx-slave1"]
2018-09-21 04:10:17.265896 +0200 SERVER (trace) [6]:0 -> @sasl-outcome(68)
[code=0]
2018-09-21 04:10:17.273230 +0200 SERVER (trace) [6]: <- AMQP
2018-09-21 04:10:17.273262 +0200 SERVER (trace) [6]:0 <- @open(16)
[container-id="ID:0076c95e-90fc-4e49-9cac-687c6e40af15:1",
hostname="green-lx-slave1", max-frame-size=1048576, channel-max=32767,
idle-time-out=30000,
desired-capabilities=@PN_SYMBOL[:"sole-connection-for-container"],
properties={:product="QpidJMS", :version="0.11.1", :platform="JVM: 1.8.0_141,
25.141-b15, Oracle Corporation, OS: Windows 10, 10.0, amd64"}]
2018-09-21 04:10:17.273311 +0200 SERVER (trace) [6]: -> AMQP
2018-09-21 04:10:17.273336 +0200 SERVER (trace) [6]:0 -> @open(16)
[container-id="router.10105", max-frame-size=16384, channel-max=32767,
idle-time-out=8000, offered-capabilities=:"ANONYMOUS-RELAY",
properties={:product="qpid-dispatch-router", :version="1.3.0"}]
2018-09-21 04:10:17.273347 +0200 ROUTER_CORE (trace) Core action
'connection_opened'
2018-09-21 04:10:17.283514 +0200 SERVER (trace) [6]:0 <- @begin(17)
[next-outgoing-id=1, incoming-window=2047, outgoing-window=2147483647,
handle-max=65535]
2018-09-21 04:10:17.283544 +0200 SERVER (trace) [6]:0 -> @begin(17)
[remote-channel=0, next-outgoing-id=0, incoming-window=2147483647,
outgoing-window=2147483647]
2018-09-21 04:10:17.302916 +0200 SERVER (trace) [6]:1 <- @begin(17)
[next-outgoing-id=1, incoming-window=2047, outgoing-window=2147483647,
handle-max=65535]
2018-09-21 04:10:17.302956 +0200 SERVER (trace) [6]:1 -> @begin(17)
[remote-channel=1, next-outgoing-id=0, incoming-window=2147483647,
outgoing-window=2147483647]
2018-09-21 04:10:17.325788 +0200 SERVER (trace) [6]:1 <- @attach(18)
[name="qpid-jms:sender:ID:6188de17-e806-40ab-b911-9bb8b7adb735:1:1:1:myQueue",
handle=0, role=false, snd-settle-mode=0, rcv-settle-mode=0, source=@source(40)
[address="ID:6188de17-e806-40ab-b911-9bb8b7adb735:1:1:1", durable=0,
expiry-policy=:"session-end", timeout=0, dynamic=false,
outcomes=@PN_SYMBOL[:"amqp:accepted:list", :"amqp:rejected:list"]],
target=@target(41) [address="myQueue", durable=0, expiry-policy=:"session-end",
timeout=0, dynamic=false, capabilities=@PN_SYMBOL[:queue]],
incomplete-unsettled=false, initial-delivery-count=0]
2018-09-21 04:10:17.325887 +0200 ROUTER_CORE (trace) Core action
'link_first_attach'
2018-09-21 04:10:17.325911 +0200 DEFAULT (trace) Parse tree search for 'myQueue'
2018-09-21 04:10:17.325918 +0200 DEFAULT (trace) Parse tree match not found
2018-09-21 04:10:17.325924 +0200 DEFAULT (trace) Parse tree search for 'myQueue'
2018-09-21 04:10:17.326000 +0200 SERVER (trace) [6]:1 -> @attach(18)
[name="qpid-jms:sender:ID:6188de17-e806-40ab-b911-9bb8b7adb735:1:1:1:myQueue",
handle=0, role=true, snd-settle-mode=2, rcv-settle-mode=0, source=@source(40)
[address="ID:6188de17-e806-40ab-b911-9bb8b7adb735:1:1:1", durable=0, timeout=0,
dynamic=false, outcomes=@PN_SYMBOL[:"amqp:accepted:list",
:"amqp:rejected:list"]], target=@target(41) [address="myQueue", durable=0,
timeout=0, dynamic=false, capabilities=@PN_SYMBOL[:queue]],
initial-delivery-count=0, max-message-size=0]
2018-09-21 04:10:18.143966 +0200 ROUTER_CORE (trace) Core action 'send_to'
2018-09-21 04:10:18.144052 +0200 ROUTER_HELLO (trace) SENT:
HELLO(id=router.10105 pv=1 area=0 inst=1537495776 seen=[])
2018-09-21 04:10:18.641225 +0200 SERVER (trace) [1]:0 <- (EMPTY FRAME)
2018-09-21 04:10:19.144375 +0200 ROUTER_CORE (trace) Core action 'send_to'
2018-09-21 04:10:19.144421 +0200 ROUTER_HELLO (trace) SENT:
HELLO(id=router.10105 pv=1 area=0 inst=1537495776 seen=[])
2018-09-21 04:10:19.828124 +0200 SERVER (trace) [2]:0 <- (EMPTY FRAME)
2018-09-21 04:10:20.144665 +0200 ROUTER_CORE (trace) Core action 'send_to'
2018-09-21 04:10:20.144754 +0200 ROUTER_HELLO (trace) SENT:
HELLO(id=router.10105 pv=1 area=0 inst=1537495776 seen=[])
2018-09-21 04:10:21.144994 +0200 ROUTER_CORE (trace) Core action 'send_to'
2018-09-21 04:10:21.145156 +0200 ROUTER_HELLO (trace) SENT:
HELLO(id=router.10105 pv=1 area=0 inst=1537495776 seen=[])
2018-09-21 04:10:22.145391 +0200 ROUTER_CORE (trace) Core action 'send_to'
2018-09-21 04:10:22.145482 +0200 ROUTER_HELLO (trace) SENT:
HELLO(id=router.10105 pv=1 area=0 inst=1537495776 seen=[])
2018-09-21 04:10:22.642076 +0200 SERVER (trace) [1]:0 <- (EMPTY FRAME)
2018-09-21 04:10:23.145719 +0200 ROUTER_CORE (trace) Core action 'send_to'
2018-09-21 04:10:23.145804 +0200 ROUTER_HELLO (trace) SENT:
HELLO(id=router.10105 pv=1 area=0 inst=1537495776 seen=[])
2018-09-21 04:10:23.828080 +0200 SERVER (trace) [2]:0 <- (EMPTY FRAME)
2018-09-21 04:10:24.142131 +0200 SERVER (notice) Shut Down
2018-09-21 04:10:24.142194 +0200 CONTAINER (trace) Default node removed
2018-09-21 04:10:24.142228 +0200 ROUTER_CORE (info) Router Core thread exited
2018-09-21 04:10:24.142324 +0200 DEFAULT (trace) Parse tree remove address
pattern 'myQueue/#'
2018-09-21 04:10:24.142418 +0200 SERVER (trace) [6]:0 -> @close(24)
[error=@error(29) [condition=:"amqp:connection:framing-error",
description="connection aborted"]]
2018-09-21 04:10:24.142436 +0200 SERVER (trace) [6]: <- EOS
2018-09-21 04:10:24.142566 +0200 SERVER (trace) [2]:0 -> @close(24)
[error=@error(29) [condition=:"amqp:connection:framing-error",
description="connection aborted"]]
2018-09-21 04:10:24.142576 +0200 SERVER (trace) [2]: <- EOS
2018-09-21 04:10:24.142633 +0200 SERVER (trace) [1]:0 -> @close(24)
[error=@error(29) [condition=:"amqp:connection:framing-error",
description="connection aborted"]]
2018-09-21 04:10:24.142649 +0200 SERVER (trace) [1]: <- EOS
---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]
