[ 
https://issues.apache.org/jira/browse/DISPATCH-1689?page=com.atlassian.jira.plugin.system.issuetabpanels:comment-tabpanel&focusedCommentId=17484219#comment-17484219
 ] 

Jiri Daněk commented on DISPATCH-1689:
--------------------------------------

I haven't seen this one for a long time. This one crash could be it, returned 
after a while

https://github.com/jiridanek/qpid-dispatch/runs/4992641079?check_suite_focus=true#step:27:1815

{noformat}
25: Router QDR.Policy command file:
25: >>>>
25: qdrouterd -c vhost-policy-config-hash-pattern.conf -I 
/home/runner/work/qpid-dispatch/qpid-dispatch/qpid-dispatch/python
25: pid=2350
25: 
25: <<<<
25: 
25: Router QDR.Policy log file tail:
25: >>>>
25: 2022-01-29 18:48:46.429209 +0000 AGENT (debug) Add entity: 
LogStatsEntity(criticalCount=0, debugCount=0, errorCount=0, 
identity=logStats/DEFAULT, infoCount=0, name=DEFAULT, noticeCount=0, 
traceCount=2, type=org.apache.qpid.dispatch.logStats, warningCount=0) 
(/home/runner/work/qpid-dispatch/qpid-dispatch/qpid-dispatch/python/qpid_dispatch_internal/management/agent.py:682)
25: 2022-01-29 18:48:46.429428 +0000 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) 
(/home/runner/work/qpid-dispatch/qpid-dispatch/qpid-dispatch/python/qpid_dispatch_internal/management/agent.py:682)
25: 2022-01-29 18:48:46.429622 +0000 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) 
(/home/runner/work/qpid-dispatch/qpid-dispatch/qpid-dispatch/python/qpid_dispatch_internal/management/agent.py:682)
25: 2022-01-29 18:48:46.429790 +0000 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) 
(/home/runner/work/qpid-dispatch/qpid-dispatch/qpid-dispatch/python/qpid_dispatch_internal/management/agent.py:682)
25: 2022-01-29 18:48:46.429951 +0000 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) 
(/home/runner/work/qpid-dispatch/qpid-dispatch/qpid-dispatch/python/qpid_dispatch_internal/management/agent.py:682)
25: 2022-01-29 18:48:46.430112 +0000 AGENT (debug) Add entity: 
LogStatsEntity(criticalCount=0, debugCount=26, errorCount=0, 
identity=logStats/AGENT, infoCount=0, name=AGENT, noticeCount=0, traceCount=0, 
type=org.apache.qpid.dispatch.logStats, warningCount=0) 
(/home/runner/work/qpid-dispatch/qpid-dispatch/qpid-dispatch/python/qpid_dispatch_internal/management/agent.py:682)
25: 2022-01-29 18:48:46.430281 +0000 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) 
(/home/runner/work/qpid-dispatch/qpid-dispatch/qpid-dispatch/python/qpid_dispatch_internal/management/agent.py:682)
25: 2022-01-29 18:48:46.430495 +0000 AGENT (debug) Add entity: 
AllocatorEntity(batchesRebalancedToGlobal=0, batchesRebalancedToThreads=0, 
globalFreeListMax=0, heldByThreads=96, localFreeListMax=32, 
totalAllocFromHeap=96, totalFreeToHeap=0, transferBatchSize=16, 
type=org.apache.qpid.dispatch.allocator, typeName=qd_log_entry_t, 
typeSize=2112) 
(/home/runner/work/qpid-dispatch/qpid-dispatch/qpid-dispatch/python/qpid_dispatch_internal/management/agent.py:682)
25: 2022-01-29 18:48:46.430685 +0000 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) 
(/home/runner/work/qpid-dispatch/qpid-dispatch/qpid-dispatch/python/qpid_dispatch_internal/management/agent.py:682)
25: 2022-01-29 18:48:46.430849 +0000 AGENT (debug) Add entity: 
LogStatsEntity(criticalCount=0, debugCount=2, errorCount=0, 
identity=logStats/HTTP, infoCount=0, name=HTTP, noticeCount=0, traceCount=18, 
type=org.apache.qpid.dispatch.logStats, warningCount=0) 
(/home/runner/work/qpid-dispatch/qpid-dispatch/qpid-dispatch/python/qpid_dispatch_internal/management/agent.py:682)
25: 2022-01-29 18:48:46.431013 +0000 AGENT (debug) Add entity: 
LogStatsEntity(criticalCount=0, debugCount=0, errorCount=0, 
identity=logStats/TCP_ADAPTOR, infoCount=0, name=TCP_ADAPTOR, noticeCount=0, 
traceCount=0, type=org.apache.qpid.dispatch.logStats, warningCount=0) 
(/home/runner/work/qpid-dispatch/qpid-dispatch/qpid-dispatch/python/qpid_dispatch_internal/management/agent.py:682)
25: 2022-01-29 18:48:46.431188 +0000 AGENT (debug) Add entity: 
LogStatsEntity(criticalCount=0, debugCount=0, errorCount=0, 
identity=logStats/HTTP_ADAPTOR, infoCount=0, name=HTTP_ADAPTOR, noticeCount=0, 
traceCount=0, type=org.apache.qpid.dispatch.logStats, warningCount=0) 
(/home/runner/work/qpid-dispatch/qpid-dispatch/qpid-dispatch/python/qpid_dispatch_internal/management/agent.py:682)
25: 2022-01-29 18:48:46.431356 +0000 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) 
(/home/runner/work/qpid-dispatch/qpid-dispatch/qpid-dispatch/python/qpid_dispatch_internal/management/agent.py:682)
25: 2022-01-29 18:48:46.431525 +0000 AGENT (debug) Add entity: 
LogStatsEntity(criticalCount=0, debugCount=0, errorCount=0, 
identity=logStats/PROTOCOL, infoCount=0, name=PROTOCOL, noticeCount=0, 
traceCount=0, type=org.apache.qpid.dispatch.logStats, warningCount=0) 
(/home/runner/work/qpid-dispatch/qpid-dispatch/qpid-dispatch/python/qpid_dispatch_internal/management/agent.py:682)
25: 2022-01-29 18:48:46.431691 +0000 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) 
(/home/runner/work/qpid-dispatch/qpid-dispatch/qpid-dispatch/python/qpid_dispatch_internal/management/agent.py:682)
25: 2022-01-29 18:48:46.431867 +0000 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=0) 
(/home/runner/work/qpid-dispatch/qpid-dispatch/qpid-dispatch/python/qpid_dispatch_internal/management/agent.py:682)
25: 2022-01-29 18:48:46.432110 +0000 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) 
(/home/runner/work/qpid-dispatch/qpid-dispatch/qpid-dispatch/python/qpid_dispatch_internal/management/agent.py:682)
25: 2022-01-29 18:48:46.432296 +0000 AGENT (debug) Add entity: 
LogStatsEntity(criticalCount=0, debugCount=0, errorCount=0, 
identity=logStats/ROUTER, infoCount=2, name=ROUTER, noticeCount=0, 
traceCount=0, type=org.apache.qpid.dispatch.logStats, warningCount=0) 
(/home/runner/work/qpid-dispatch/qpid-dispatch/qpid-dispatch/python/qpid_dispatch_internal/management/agent.py:682)
25: 2022-01-29 18:48:46.432468 +0000 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) 
(/home/runner/work/qpid-dispatch/qpid-dispatch/qpid-dispatch/python/qpid_dispatch_internal/management/agent.py:682)
25: 2022-01-29 18:48:46.432655 +0000 AGENT (debug) Add entity: 
LogStatsEntity(criticalCount=0, debugCount=0, errorCount=0, 
identity=logStats/AUTHSERVICE, infoCount=0, name=AUTHSERVICE, noticeCount=0, 
traceCount=0, type=org.apache.qpid.dispatch.logStats, warningCount=0) 
(/home/runner/work/qpid-dispatch/qpid-dispatch/qpid-dispatch/python/qpid_dispatch_internal/management/agent.py:682)
25: 2022-01-29 18:48:46.432838 +0000 AGENT (debug) Add entity: 
LogStatsEntity(criticalCount=0, debugCount=0, errorCount=0, 
identity=logStats/ROUTER_CORE, infoCount=16, name=ROUTER_CORE, noticeCount=0, 
traceCount=0, type=org.apache.qpid.dispatch.logStats, warningCount=0) 
(/home/runner/work/qpid-dispatch/qpid-dispatch/qpid-dispatch/python/qpid_dispatch_internal/management/agent.py:682)
25: 2022-01-29 18:48:46.433040 +0000 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_iterator_t, typeSize=160) 
(/home/runner/work/qpid-dispatch/qpid-dispatch/qpid-dispatch/python/qpid_dispatch_internal/management/agent.py:682)
25: 2022-01-29 18:48:46.433234 +0000 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_item_t, typeSize=40) 
(/home/runner/work/qpid-dispatch/qpid-dispatch/qpid-dispatch/python/qpid_dispatch_internal/management/agent.py:682)
25: 2022-01-29 18:48:46.433431 +0000 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) 
(/home/runner/work/qpid-dispatch/qpid-dispatch/qpid-dispatch/python/qpid_dispatch_internal/management/agent.py:682)
25: 2022-01-29 18:48:46.433659 +0000 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_timer_t, typeSize=72) 
(/home/runner/work/qpid-dispatch/qpid-dispatch/qpid-dispatch/python/qpid_dispatch_internal/management/agent.py:682)
25: 2022-01-29 18:48:46.433868 +0000 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_parse_tree_t, typeSize=32) 
(/home/runner/work/qpid-dispatch/qpid-dispatch/qpid-dispatch/python/qpid_dispatch_internal/management/agent.py:682)
25: 2022-01-29 18:48:46.434075 +0000 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_parse_node_t, 
typeSize=112) 
(/home/runner/work/qpid-dispatch/qpid-dispatch/qpid-dispatch/python/qpid_dispatch_internal/management/agent.py:682)
25: 2022-01-29 18:48:46.434297 +0000 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=416) 
(/home/runner/work/qpid-dispatch/qpid-dispatch/qpid-dispatch/python/qpid_dispatch_internal/management/agent.py:682)
25: 2022-01-29 18:48:46.434502 +0000 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) 
(/home/runner/work/qpid-dispatch/qpid-dispatch/qpid-dispatch/python/qpid_dispatch_internal/management/agent.py:682)
25: 2022-01-29 18:48:46.434701 +0000 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) 
(/home/runner/work/qpid-dispatch/qpid-dispatch/qpid-dispatch/python/qpid_dispatch_internal/management/agent.py:682)
25: 2022-01-29 18:48:46.434906 +0000 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_core_timer_t, 
typeSize=40) 
(/home/runner/work/qpid-dispatch/qpid-dispatch/qpid-dispatch/python/qpid_dispatch_internal/management/agent.py:682)
25: 2022-01-29 18:48:46.435116 +0000 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=136) 
(/home/runner/work/qpid-dispatch/qpid-dispatch/qpid-dispatch/python/qpid_dispatch_internal/management/agent.py:682)
25: 2022-01-29 18:48:46.435383 +0000 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) 
(/home/runner/work/qpid-dispatch/qpid-dispatch/qpid-dispatch/python/qpid_dispatch_internal/management/agent.py:682)
25: 2022-01-29 18:48:46.435617 +0000 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) 
(/home/runner/work/qpid-dispatch/qpid-dispatch/qpid-dispatch/python/qpid_dispatch_internal/management/agent.py:682)
25: 2022-01-29 18:48:46.435961 +0000 AGENT (info) Activating management agent 
on $_management_internal 
(/home/runner/work/qpid-dispatch/qpid-dispatch/qpid-dispatch/python/qpid_dispatch_internal/management/agent.py:909)
25: 2022-01-29 18:48:46.436936 +0000 POLICY (info) Policy configured 
maxConnections: 100, policyDir: '',access rules enabled: 'true', use hostname 
patterns: 'true' 
(/home/runner/work/qpid-dispatch/qpid-dispatch/qpid-dispatch/src/policy.c:166)
25: 2022-01-29 18:48:46.437041 +0000 AGENT (debug) Add entity: 
PolicyEntity(defaultVhost=$default, enableVhostNamePatterns=True, 
enableVhostPolicy=True, identity=policy/None, maxConnections=100, 
maxMessageSize=0, name=policy/None, policyDir=, 
type=org.apache.qpid.dispatch.policy) 
(/home/runner/work/qpid-dispatch/qpid-dispatch/qpid-dispatch/python/qpid_dispatch_internal/management/agent.py:682)
25: 2022-01-29 18:48:46.437626 +0000 ROUTER_CORE (trace) Core action 
'subscribe' 
(/home/runner/work/qpid-dispatch/qpid-dispatch/qpid-dispatch/src/router_core/router_core_thread.c:235)
25: 2022-01-29 18:48:46.437679 +0000 ROUTER_CORE (info) In-process subscription 
M/$management 
(/home/runner/work/qpid-dispatch/qpid-dispatch/qpid-dispatch/src/router_core/route_tables.c:651)
25: 2022-01-29 18:48:46.437939 +0000 ROUTER_CORE (trace) Core action 
'subscribe' 
(/home/runner/work/qpid-dispatch/qpid-dispatch/qpid-dispatch/src/router_core/router_core_thread.c:235)
25: 2022-01-29 18:48:46.437960 +0000 ROUTER_CORE (info) In-process subscription 
L/$management 
(/home/runner/work/qpid-dispatch/qpid-dispatch/qpid-dispatch/src/router_core/route_tables.c:651)
25: 2022-01-29 18:48:46.437992 +0000 ROUTER_CORE (trace) Core action 
'subscribe' 
(/home/runner/work/qpid-dispatch/qpid-dispatch/qpid-dispatch/src/router_core/router_core_thread.c:235)
25: 2022-01-29 18:48:46.438005 +0000 ROUTER_CORE (info) In-process subscription 
L/$_management_internal 
(/home/runner/work/qpid-dispatch/qpid-dispatch/qpid-dispatch/src/router_core/route_tables.c:651)
25: 2022-01-29 18:48:46.438447 +0000 POLICY (info) Policy fallback defaultVhost 
is defined: '$default' 
(/home/runner/work/qpid-dispatch/qpid-dispatch/qpid-dispatch/python/qpid_dispatch_internal/policy/policy_local.py:757)
25: 2022-01-29 18:48:46.438852 +0000 DEFAULT (trace) Parse tree add pattern 
'#.example.com' 
(/home/runner/work/qpid-dispatch/qpid-dispatch/qpid-dispatch/src/parse_tree.c:448)
25: 2022-01-29 18:48:46.439015 +0000 AGENT (debug) Add entity: 
VhostStatsEntity(connectionsApproved=0, connectionsCurrent=0, 
connectionsDenied=0, hostname=#.example.com, id=#.example.com, 
maxMessageSizeDenied=0, perHostState={}, perUserState={}, receiverDenied=0, 
senderDenied=0, sessionDenied=0, type=org.apache.qpid.dispatch.vhostStats) 
(/home/runner/work/qpid-dispatch/qpid-dispatch/qpid-dispatch/python/qpid_dispatch_internal/management/agent.py:682)
25: 2022-01-29 18:48:46.439232 +0000 POLICY (info) Created policy rules for 
vhost #.example.com 
(/home/runner/work/qpid-dispatch/qpid-dispatch/qpid-dispatch/python/qpid_dispatch_internal/policy/policy_local.py:710)
25: 2022-01-29 18:48:46.439297 +0000 AGENT (debug) Add entity: 
VhostEntity(allowUnknownUser=True, groups={'$default': {'users': '*', 
'remoteHosts': '*', 'sources': '*', 'targets': '*', 'allowDynamicSource': 
True}}, hostname=#.example.com, identity=vhost/#.example.com, maxConnections=2, 
maxConnectionsPerHost=65535, maxConnectionsPerUser=65535, 
name=vhost/#.example.com, type=org.apache.qpid.dispatch.vhost) 
(/home/runner/work/qpid-dispatch/qpid-dispatch/qpid-dispatch/python/qpid_dispatch_internal/management/agent.py:682)
25: 2022-01-29 18:48:46.439918 +0000 CONN_MGR (info) Configured Listener: 
0.0.0.0:21907 proto=any, role=normal 
(/home/runner/work/qpid-dispatch/qpid-dispatch/qpid-dispatch/src/connection_manager.c:702)
25: 2022-01-29 18:48:46.440277 +0000 AGENT (debug) Add entity: 
ListenerEntity(authenticatePeer=False, cost=1, healthz=True, host=0.0.0.0, 
http=False, identity=listener/0.0.0.0:21907, idleTimeoutSeconds=120, 
initialHandshakeTimeoutSeconds=0, maxFrameSize=16384, maxSessions=32768, 
messageLoggingComponents=none, metrics=True, multiTenant=False, 
name=listener/0.0.0.0:21907, port=21907, requireEncryption=False, 
requireSsl=False, role=normal, saslMechanisms=ANONYMOUS, stripAnnotations=both, 
type=org.apache.qpid.dispatch.listener, websockets=True) 
(/home/runner/work/qpid-dispatch/qpid-dispatch/qpid-dispatch/python/qpid_dispatch_internal/management/agent.py:682)
25: 
25: <<<<
25: 
25: ::VhostPolicyConfigHashPattern::test_vhost_created ERROR
25: ::PolicyVhostAlias::test_100_policy_aliases PASSED
25: Router A output file:
25: >>>>
25: -----------------------------------------------------
25: Suppressions used:
25:   count      bytes template
25:       4        224 ^IoAdapter_init$
25:       1         32 ^qd_policy_c_counts_alloc$
25:     589     592281 /libpython3.*.so
25: -----------------------------------------------------
25: 
25: 
25: <<<<
25: 
25: ::PolicyVhostMultiTenantBlankHostname::test_101_policy_alias_blank_vhost 
PASSED
25: Router A output file:
25: >>>>
25: -----------------------------------------------------
25: Suppressions used:
25:   count      bytes template
25:       4        224 ^IoAdapter_init$
25:       1          7 ^load_server_config$
25:       1         32 ^qd_policy_c_counts_alloc$
25:     589     592281 /libpython3.*.so
25: -----------------------------------------------------
25: 
25: 
25: <<<<
25: 
25: ::PolicyVhostFrameSessionWindowOverride::test_1_check_frame_sessions PASSED
25: Router A output file:
25: >>>>
25: -----------------------------------------------------
25: Suppressions used:
25:   count      bytes template
25:       4        224 ^IoAdapter_init$
25:       2         22 ^load_server_config$
25:       2         64 ^qd_policy_c_counts_alloc$
25:     594     596233 /libpython3.*.so
25: -----------------------------------------------------
25: 
25: 
25: <<<<
25: 
25: 
25: ==================================== ERRORS 
====================================
25: _____ ERROR at teardown of VhostPolicyConfigHashPattern.test_vhost_created 
_____
25: 
25: cls = <class 'system_tests_policy.VhostPolicyConfigHashPattern'>
25: 
25:     @classmethod
25:     def tearDownClass(cls):
25:         if hasattr(cls, 'tester'):
25: >           cls.tester.teardown()
25: 
25: 
/home/runner/work/qpid-dispatch/qpid-dispatch/qpid-dispatch/tests/system_test.py:838:
 
25: _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ 
_ _ 
25: 
25: self = <system_test.Tester object at 0x7fea57ab0df0>
25: 
25:     def teardown(self):
25:         """Clean up (tear-down, stop or close) objects recorded via 
cleanup()"""
25:         self.cleanup_list.reverse()
25:         errors = []
25:         for obj in self.cleanup_list:
25:             try:
25:                 for method in ["teardown", "tearDown", "stop", "close"]:
25:                     cleanup = getattr(obj, method, None)
25:                     if cleanup:
25:                         cleanup()
25:                         break
25:             except Exception as exc:
25:                 errors.append(exc)
25:         if errors:
25: >           raise RuntimeError("Errors during teardown: \n\n%s" % 
"\n\n".join([str(e) for e in errors]))
25: E           RuntimeError: Errors during teardown: 
25: E           
25: E           Process 2350 error: exit code -15, expected 0
25: E           qdrouterd -c vhost-policy-config-hash-pattern.conf -I 
/home/runner/work/qpid-dispatch/qpid-dispatch/qpid-dispatch/python
25: E           
/__w/qpid-dispatch/qpid-dispatch/qpid-dispatch/build/tests/system_test.dir/system_tests_policy/VhostPolicyConfigHashPattern/setUpClass/vhost-policy-config-hash-pattern-16.cmd
25: E           >>>>
25: E           <<<<
25: 
25: 
/home/runner/work/qpid-dispatch/qpid-dispatch/qpid-dispatch/tests/system_test.py:781:
 RuntimeError
25: - generated xml file: 
/__w/qpid-dispatch/qpid-dispatch/qpid-dispatch/build/tests/junitxmls/system_tests_policy.xml
 -
25: =========================== short test summary info 
============================
25: ERROR ::VhostPolicyConfigHashPattern::test_vhost_created - RuntimeError: 
Erro...
25: ========================= 27 passed, 1 error in 54.51s 
=========================
15/38 Test #25: system_tests_policy .....................***Failed   54.71 sec
============================= test session starts ==============================
{noformat}

> [test] test framework can't handle qdrouter that is torn down too soon
> ----------------------------------------------------------------------
>
>                 Key: DISPATCH-1689
>                 URL: https://issues.apache.org/jira/browse/DISPATCH-1689
>             Project: Qpid Dispatch
>          Issue Type: Bug
>          Components: Tests
>    Affects Versions: 1.12.0
>            Reporter: Charles E. Rolke
>            Priority: Major
>
> Demonstration steps:
>  # simple router is created with cls.tester.qdrouterd(..., wait=False)
>  # skip cls.router.wait_ready()
>  # run a test that executes quickly and creates no connection to the router
> Then default teardown will show something like:
> {noformat}
> 24: ERROR
> 24: 
> 24: ======================================================================
> 24: ERROR: tearDownClass (system_tests_policy.PolicyWarnings)
> 24: ----------------------------------------------------------------------
> 24: Traceback (most recent call last):
> 24:   File "/home/chug/git/qpid-dispatch/tests/system_test.py", line 723, in 
> tearDownClass
> 24:     cls.tester.teardown()
> 24:   File "/home/chug/git/qpid-dispatch/tests/system_test.py", line 669, in 
> teardown
> 24:     raise RuntimeError("Errors during teardown: \n\n%s" % 
> "\n\n".join([str(e) for e in errors]))
> 24: RuntimeError: Errors during teardown: 
> 24: 
> 24: Process 65135 error: exit code -15, expected 0
> 24: qdrouterd -c PolicyWarnings.conf -I /home/chug/git/qpid-dispatch/python
> 24: 
> /home/chug/git/qpid-dispatch/build/tests/system_test.dir/system_tests_policy/PolicyWarnings/setUpClass/PolicyWarnings-1.cmd
> 24: >>>>
> 24: <<<<
> {noformat}
>  The router did not crash and the test did nothing to cause a teardown issue. 
> Conversely the test can do nothing to fix the issue outside of a sleep.



--
This message was sent by Atlassian Jira
(v8.20.1#820001)

---------------------------------------------------------------------
To unsubscribe, e-mail: dev-unsubscr...@qpid.apache.org
For additional commands, e-mail: dev-h...@qpid.apache.org

Reply via email to