qpid-dev mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From "Jiri Danek (JIRA)" <j...@apache.org>
Subject [jira] [Updated] (DISPATCH-782) [unit-tests] SIGSEGV in qd_python_finalize
Date Wed, 31 May 2017 10:26:04 GMT

     [ https://issues.apache.org/jira/browse/DISPATCH-782?page=com.atlassian.jira.plugin.system.issuetabpanels:all-tabpanel
]

Jiri Danek updated DISPATCH-782:
--------------------------------
    Attachment: rr.zip

I've been doing it wrong, trying to record the whole ctest command... A way that works is
doing

ret=0
while [[ $ret == 0 ]]; do rm -rf ~/.local/share/rr || true; rr record /usr/bin/python "/main/qpid-dispatch/build/tests/run.py"
"-x" "unit_tests" "/main/qpid-dispatch/tests/threads4.conf"; ret=$?; done;

The resulting recording is 44 MB, which is small enough. Attached as rr.zip. (Actually, the
recording is 22 MB, there is symbolic link so I've it twice in the zip....)

> [unit-tests] SIGSEGV in qd_python_finalize
> ------------------------------------------
>
>                 Key: DISPATCH-782
>                 URL: https://issues.apache.org/jira/browse/DISPATCH-782
>             Project: Qpid Dispatch
>          Issue Type: Bug
>          Components: Tests
>    Affects Versions: 1.0.0
>            Reporter: Jiri Danek
>         Attachments: 9.core, rr.zip
>
>
> {noformat}
> commit 8d862beabd32bd22d4b315ff3a35bd937aa4b2a1
> Author: Andrew Stitcher <astitcher@apache.org>
> Date:   Fri May 26 16:16:18 2017 -0400
>     No-JIRA: really fix tox tests without breaking anything else!
> {noformat}
> {noformat}
> commit b1f09d5ea8101f40f3df4db5b4c5d2ee00e6ef7e
> Author: Alan Conway <aconway@redhat.com>
> Date:   Mon May 29 15:16:26 2017 -0400
>     DISPATCH-777: async-signal-safe shutdown of the reactor.
> {noformat}
> If repeatedly running the unit tests, eventually a SIGSEGV is raised.
> Reproducibility: the command below likely needs to be executed multiple times for the
error to appear.
> {noformat}
> ctest -VV -R unit_tests --repeat-until-fail 1000
> {noformat}
> {noformat}
> 9: Test command: /usr/bin/python "/main/qpid-dispatch/build/tests/run.py" "-x" "unit_tests"
"/main/qpid-dispatch/tests/threads4.conf"
> 9: Test timeout computed to be: 1500
> 9: 2017-05-30 13:23:52.630778 +0000 AGENT (debug) Add entity: LogEntity(enable=debug+,
identity=log/DEFAULT, module=DEFAULT, name=log/DEFAULT, output=stderr, source=False, timestamp=True,
type=org.apache.qpid.dispatch.log)
> 9: 2017-05-30 13:23:52.631948 +0000 AGENT (debug) Add entity: LogEntity(identity=log/HTTP,
module=HTTP, name=log/HTTP, type=org.apache.qpid.dispatch.log)
> 9: 2017-05-30 13:23:52.632602 +0000 AGENT (debug) Add entity: LogEntity(identity=log/ROUTER_LS,
module=ROUTER_LS, name=log/ROUTER_LS, type=org.apache.qpid.dispatch.log)
> 9: 2017-05-30 13:23:52.633137 +0000 AGENT (debug) Add entity: LogEntity(identity=log/PYTHON,
module=PYTHON, name=log/PYTHON, type=org.apache.qpid.dispatch.log)
> 9: 2017-05-30 13:23:52.633712 +0000 AGENT (debug) Add entity: LogEntity(identity=log/ROUTER_MA,
module=ROUTER_MA, name=log/ROUTER_MA, type=org.apache.qpid.dispatch.log)
> 9: 2017-05-30 13:23:52.634201 +0000 AGENT (debug) Add entity: LogEntity(identity=log/CONN_MGR,
module=CONN_MGR, name=log/CONN_MGR, type=org.apache.qpid.dispatch.log)
> 9: 2017-05-30 13:23:52.634756 +0000 AGENT (debug) Add entity: LogEntity(identity=log/ROUTER_HELLO,
module=ROUTER_HELLO, name=log/ROUTER_HELLO, type=org.apache.qpid.dispatch.log)
> 9: 2017-05-30 13:23:52.635661 +0000 AGENT (debug) Add entity: LogEntity(identity=log/SERVER,
module=SERVER, name=log/SERVER, type=org.apache.qpid.dispatch.log)
> 9: 2017-05-30 13:23:52.636837 +0000 AGENT (debug) Add entity: LogEntity(identity=log/POLICY,
module=POLICY, name=log/POLICY, type=org.apache.qpid.dispatch.log)
> 9: 2017-05-30 13:23:52.637421 +0000 AGENT (debug) Add entity: LogEntity(identity=log/CONTAINER,
module=CONTAINER, name=log/CONTAINER, type=org.apache.qpid.dispatch.log)
> 9: 2017-05-30 13:23:52.637968 +0000 AGENT (debug) Add entity: LogEntity(identity=log/AGENT,
module=AGENT, name=log/AGENT, type=org.apache.qpid.dispatch.log)
> 9: 2017-05-30 13:23:52.638828 +0000 AGENT (debug) Add entity: LogEntity(identity=log/ERROR,
module=ERROR, name=log/ERROR, type=org.apache.qpid.dispatch.log)
> 9: 2017-05-30 13:23:52.640134 +0000 AGENT (debug) Add entity: LogEntity(identity=log/ROUTER_CORE,
module=ROUTER_CORE, name=log/ROUTER_CORE, type=org.apache.qpid.dispatch.log)
> 9: 2017-05-30 13:23:52.640886 +0000 AGENT (debug) Add entity: LogEntity(identity=log/ROUTER,
module=ROUTER, name=log/ROUTER, type=org.apache.qpid.dispatch.log)
> 9: 2017-05-30 13:23:52.641383 +0000 AGENT (debug) Add entity: LogEntity(identity=log/MESSAGE,
module=MESSAGE, name=log/MESSAGE, type=org.apache.qpid.dispatch.log)
> 9: 2017-05-30 13:23:52.642008 +0000 AGENT (debug) Add entity: ContainerEntity(containerName=00000000-0000-0000-0000-000000000000,
identity=container/00000000-0000-0000-0000-000000000000, name=container/00000000-0000-0000-0000-000000000000,
type=org.apache.qpid.dispatch.container, workerThreads=4)
> 9: 2017-05-30 13:23:52.642676 +0000 AGENT (debug) Add entity: RouterEntity(allowUnsettledMulticast=False,
area=0, helloInterval=1, helloMaxAge=3, id=QDR, mobileAddrMaxAge=60, mode=standalone, raInterval=30,
raIntervalFlux=4, remoteLsMaxAge=60, saslConfigName=qdrouterd, type=org.apache.qpid.dispatch.router,
workerThreads=4)
> 9: 2017-05-30 13:23:52.643339 +0000 SERVER (warning) HTTP support is not available
> 9: 2017-05-30 13:23:52.643420 +0000 SERVER (info) Container Name: QDR
> 9: 2017-05-30 13:23:52.643591 +0000 ROUTER (info) Router started in Standalone mode
> 9: 2017-05-30 13:23:52.643661 +0000 ROUTER_CORE (info) Allow Unsettled Multicast: no
> 9: 2017-05-30 13:23:52.644000 +0000 ROUTER_CORE (info) Router Core thread running. 0/QDR
> 9: 2017-05-30 13:23:52.644029 +0000 ROUTER_CORE (info) In-process subscription M/$management
> 9: 2017-05-30 13:23:52.644201 +0000 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)
> 9: 2017-05-30 13:23:52.644799 +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)
> 9: 2017-05-30 13:23:52.645380 +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)
> 9: 2017-05-30 13:23:52.646276 +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)
> 9: 2017-05-30 13:23:52.647332 +0000 AGENT (debug) Add entity: LogStatsEntity(criticalCount=0,
debugCount=22, errorCount=0, identity=logStats/AGENT, infoCount=0, name=AGENT, noticeCount=0,
traceCount=0, type=org.apache.qpid.dispatch.logStats, warningCount=0)
> 9: 2017-05-30 13:23:52.647983 +0000 AGENT (debug) Add entity: LogStatsEntity(criticalCount=0,
debugCount=0, errorCount=0, identity=logStats/POLICY, infoCount=0, name=POLICY, noticeCount=0,
traceCount=0, type=org.apache.qpid.dispatch.logStats, warningCount=0)
> 9: 2017-05-30 13:23:52.648691 +0000 AGENT (debug) Add entity: AllocatorEntity(batchesRebalancedToGlobal=0,
batchesRebalancedToThreads=0, globalFreeListMax=0, heldByThreads=48, localFreeListMax=32,
totalAllocFromHeap=48, totalFreeToHeap=0, transferBatchSize=16, type=org.apache.qpid.dispatch.allocator,
typeName=qd_log_entry_t, typeSize=2112)
> 9: 2017-05-30 13:23:52.649446 +0000 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)
> 9: 2017-05-30 13:23:52.650160 +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)
> 9: 2017-05-30 13:23:52.650867 +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)
> 9: 2017-05-30 13:23:52.651620 +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)
> 9: 2017-05-30 13:23:52.652387 +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)
> 9: 2017-05-30 13:23:52.653149 +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=1)
> 9: 2017-05-30 13:23:52.653915 +0000 AGENT (debug) Add entity: LogStatsEntity(criticalCount=0,
debugCount=0, errorCount=0, identity=logStats/CONTAINER, infoCount=0, name=CONTAINER, noticeCount=0,
traceCount=0, type=org.apache.qpid.dispatch.logStats, warningCount=0)
> 9: 2017-05-30 13:23:52.654681 +0000 AGENT (debug) Add entity: LogStatsEntity(criticalCount=0,
debugCount=0, errorCount=0, identity=logStats/ROUTER_CORE, infoCount=3, name=ROUTER_CORE,
noticeCount=0, traceCount=0, type=org.apache.qpid.dispatch.logStats, warningCount=0)
> 9: 2017-05-30 13:23:52.655493 +0000 AGENT (debug) Add entity: LogStatsEntity(criticalCount=0,
debugCount=0, errorCount=0, identity=logStats/ROUTER, infoCount=1, name=ROUTER, noticeCount=0,
traceCount=0, type=org.apache.qpid.dispatch.logStats, warningCount=0)
> 9: 2017-05-30 13:23:52.656293 +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)
> 9: 2017-05-30 13:23:52.657078 +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=32)
> 9: 2017-05-30 13:23:52.657633 +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)
> 9: 2017-05-30 13:23:52.658124 +0000 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)
> 9: 2017-05-30 13:23:52.658608 +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=160)
> 9: 2017-05-30 13:23:52.659356 +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)
> 9: 2017-05-30 13:23:52.659976 +0000 AGENT (debug) Add entity: AllocatorEntity(batchesRebalancedToGlobal=0,
batchesRebalancedToThreads=0, globalFreeListMax=0, heldByThreads=16, localFreeListMax=32,
totalAllocFromHeap=16, totalFreeToHeap=0, transferBatchSize=16, type=org.apache.qpid.dispatch.allocator,
typeName=qd_buffer_t, typeSize=536)
> 9: 2017-05-30 13:23:52.660521 +0000 AGENT (info) Activating management agent on $_management_internal
> 9: 2017-05-30 13:23:52.661007 +0000 POLICY (info) Policy configured maxConnections: 65535,
policyDir: '', access rules enabled: 'false'
> 9: 2017-05-30 13:23:52.661157 +0000 AGENT (debug) Add entity: PolicyEntity(defaultVhost=$default,
enableVhostPolicy=False, maxConnections=65535, policyDir=, type=org.apache.qpid.dispatch.policy)
> 9: 2017-05-30 13:23:52.661710 +0000 AGENT (debug) Add entity: ConsoleEntity(identity=console/0,
name=console/0, type=org.apache.qpid.dispatch.console, wsport=5673)
> 9: 2017-05-30 13:23:52.662275 +0000 POLICY (info) Policy fallback defaultVhost is defined:
'$default'
> 9: 2017-05-30 13:23:52.665409 +0000 ERROR (error) Value: No network host in failover
item
> 9: 2017-05-30 13:23:52.665557 +0000 ROUTER_CORE (info) Router Core thread exited
> 9: Test Case timer_tests.test_quiet: PASS
> 9: Test Case timer_tests.test_immediate: PASS
> 9: Test Case timer_tests.test_immediate_reschedule: PASS
> 9: Test Case timer_tests.test_immediate_plus_delayed: PASS
> 9: Test Case timer_tests.test_single: PASS
> 9: Test Case timer_tests.test_two_inorder: PASS
> 9: Test Case timer_tests.test_two_reverse: PASS
> 9: Test Case timer_tests.test_two_duplicate: PASS
> 9: Test Case timer_tests.test_separated: PASS
> 9: Test Case timer_tests.test_big: PASS
> 9: Test Case tool_tests.test_deq_basic: PASS
> 9: Test Case tool_tests.test_deq_basic2: PASS
> 9: Test Case tool_tests.test_deq_multi: PASS
> 9: Test Case tool_tests.test_bitmask: PASS
> 9: Test Case compose_tests.test_compose_list_of_maps: PASS
> 9: Test Case compose_tests.test_compose_nested_composites: PASS
> 9: Test Case compose_tests.test_compose_scalars: PASS
> 9: Test Case compose_tests.test_compose_subfields: PASS
> 9: Test Case alloc_tests.test_alloc_basic: PASS
> 9: Test Case policy_tests.test_link_name_lookup: PASS
> 9: Test Case failover_tests.test_failover_list_empty: PASS
> 9: Test Case failover_tests.test_failover_list_single: PASS
> 9: Test Case failover_tests.test_failover_list_multiple: PASS
>       Test  #9: unit_tests ................................***Exception: SegFault  0.28
sec
> {noformat}
> {noformat}
> root@357c0ab10383:/main/qpid-dispatch/build# gdb -c /main/qpid-dispatch/build/tests/core
tests/unit_tests
> GNU gdb (Debian 7.12-6) 7.12.0.20161007-git
> Copyright (C) 2016 Free Software Foundation, Inc.
> [...]
> Reading symbols from tests/unit_tests...done.
> [New LWP 10094]
> [Thread debugging using libthread_db enabled]
> Using host libthread_db library "/lib/x86_64-linux-gnu/libthread_db.so.1".
> Core was generated by `unit_tests /main/qpid-dispatch/tests/threads4.conf'.
> Program terminated with signal SIGSEGV, Segmentation fault.
> #0  malloc_consolidate (av=av@entry=0x7fa793e92b00 <main_arena>) at malloc.c:4204
> 4204    malloc.c: No such file or directory.
> gdb) bt
> #0  malloc_consolidate (av=av@entry=0x7fa793e92b00 <main_arena>) at malloc.c:4204
> #1  0x00007fa793b71df0 in _int_free (av=0x7fa793e92b00 <main_arena>, p=0x5598d50b9310,
have_lock=0) at malloc.c:4110
> #2  0x00007fa79400971c in dict_dealloc.lto_priv.401 (mp=0x7fa79210eb40) at ../Objects/dictobject.c:1044
> #3  0x00007fa79400979b in dict_dealloc.lto_priv.401 (mp=0x7fa791dcd5c8) at ../Objects/dictobject.c:1040
> #4  0x00007fa79400979b in dict_dealloc.lto_priv.401 (mp=0x7fa7953265c8) at ../Objects/dictobject.c:1040
> #5  0x00007fa79401748a in _PyImport_Fini () at ../Python/import.c:272
> #6  0x00007fa793fcad13 in Py_Finalize () at ../Python/pythonrun.c:480
> #7  0x00007fa794ed9d08 in qd_python_finalize () at /main/qpid-dispatch/src/python_embedded.c:69
> #8  0x00007fa794ecc3f8 in qd_dispatch_free (qd=0x5598d4e74010) at /main/qpid-dispatch/src/dispatch.c:319
> #9  0x00005598d3b0aec5 in main (argc=2, argv=0x7ffec8808858) at /main/qpid-dispatch/tests/run_unit_tests.c:63
> (gdb) 
> {noformat}



--
This message was sent by Atlassian JIRA
(v6.3.15#6346)

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


Mime
View raw message