Re: [SR-Users] Guidance tracking down "qm_free(): BUG: freeing already freed pointer"

Hello, is it a custom configuration file or simply the default kamailio.cfg with parts of branch_route enabled/disabled? If it is custom, can you paste here the content of the branch route that you disable/enable parts of it and get the error messages? Overall, seem like trying to (re-)use a terminated transaction or branch. Cheers, Daniel On 23.07.21 00:19, Anthony Joseph Messina wrote: > I'm seeking guidance on how to track down "qm_free(): BUG: freeing already > freed pointer" which occurs only on branched calls. These errors don't > crash so I don't get any core dumps. The different log entries below are > the result of me selectively disabling sections of the script that apply > in branch route, all to no avail. I'm running Kamailio on the current > tip of the 5.5 branch (1f9f6fff6e). I'm reviewing > https://www.kamailio.org/wiki/tutorials/troubleshooting/memory in the > meantime as a place to start. > > > version: kamailio 5.5.1-5.git1f9f6fff6e.fc34 (x86_64/linux) 7abebb > flags: USE_TCP, USE_TLS, USE_SCTP, TLS_HOOKS, USE_RAW_SOCKS, > DISABLE_NAGLE, USE_MCAST, DNS_IP_HACK, SHM_MMAP, PKG_MALLOC, Q_MALLOC, > F_MALLOC, TLSF_MALLOC, DBG_SR_MEMORY, USE_FUTEX, FAST_LOCK-ADAPTIVE_WAIT, > USE_DNS_CACHE, USE_DNS_FAILOVER, USE_NAPTR, USE_DST_BLOCKLIST, > HAVE_RESOLV_RES, TLS_PTHREAD_MUTEX_SHARED ADAPTIVE_WAIT_LOOPS 1024, > MAX_RECV_BUFFER_SIZE 262144, MAX_URI_SIZE 1024, BUF_SIZE 65535, DEFAULT > PKG_SIZE 8MB poll method support: poll, epoll_lt, epoll_et, sigio_rt, > select. > > CRITICAL: <core> [core/mem/q_malloc.c:519]: qm_free(): BUG: freeing > already freed pointer (0x7f04b8f47e90), called from core: > core/data_lump.c: free_lump(470), first free textops: textops.c: > add_hf_helper(3474) - ignoring CRITICAL: <core> > [core/mem/q_malloc.c:519]: qm_free(): BUG: freeing already freed pointer > (0x7f04b8f06c70), called from core: core/data_lump.c: free_lump(470), > first free textops: textops.c: add_hf_helper(3474) - ignoring CRITICAL: > <core> [core/mem/q_malloc.c:519]: qm_free(): BUG: freeing already freed > pointer (0x7f08e8f9cf80), called from core: core/data_lump.c: > free_lump(470), first free core: core/parser/msg_parser.c: reset_ua(994) > - ignoring CRITICAL: <core> [core/mem/q_malloc.c:519]: qm_free(): BUG: > freeing already freed pointer (0x7f2afafa60d8), called from core: > core/parser/sdp/sdp.c: free_sdp(825), first free core: > core/parser/sdp/sdp.c: init_p_payload_attr(183) - ignoring CRITICAL: > <core> [core/mem/q_malloc.c:519]: qm_free(): BUG: freeing already freed > pointer (0x7f2afae2a018), called from core: core/data_lump.c: > free_lump(470), first free core: core/parser/msg_parser.c: reset_ua(994) > - ignoring CRITICAL: <core> [core/mem/q_malloc.c:519]: qm_free(): BUG: > freeing already freed pointer (0x7f2afae431e8), called from core: > core/data_lump.c: free_lump(470), first free core: > core/parser/msg_parser.c: reset_ua(994) - ignoring CRITICAL: <core> > [core/mem/q_malloc.c:519]: qm_free(): BUG: freeing already freed pointer > (0x7f2afafa3900), called from core: core/data_lump.c: free_lump(470), > first free core: core/parser/msg_parser.c: reset_ua(994) - ignoring > CRITICAL: <core> [core/mem/q_malloc.c:519]: qm_free(): BUG: freeing > already freed pointer (0x7f5fd4044ef0), called from core: > core/data_lump.c: free_lump(470), first free core: > core/parser/msg_parser.c: reset_ua(994) - ignoring CRITICAL: <core> > [core/mem/q_malloc.c:519]: qm_free(): BUG: freeing already freed pointer > (0x7ffbb2d2e2f0), called from core: core/data_lump.c: free_lump(470), > first free core: core/parser/msg_parser.c: reset_ua(994) - ignoring > CRITICAL: <core> [core/mem/q_malloc.c:519]: qm_free(): BUG: freeing > already freed pointer (0x7f6d1a50b1d8), called from core: > core/data_lump.c: free_lump(470), first free core: > core/parser/msg_parser.c: reset_ua(994) - ignoring CRITICAL: <core> > [core/mem/q_malloc.c:519]: qm_free(): BUG: freeing already freed pointer > (0x7fc19c165c70), called from core: core/parser/msg_parser.c: > reset_instance(916), first free core: core/parser/parse_addr_spec.c: > free_to_params(895) - ignoring CRITICAL: <core> > [core/mem/q_malloc.c:519]: qm_free(): BUG: freeing already freed pointer > (0x7fc19c17adb8), called from core: core/parser/msg_parser.c: > reset_instance(916), first free core: core/parser/parse_addr_spec.c: > free_to_params(895) - ignoring CRITICAL: <core> > [core/mem/q_malloc.c:519]: qm_free(): BUG: freeing already freed pointer > (0x7fc19c125790), called from core: core/data_lump.c: free_lump(470), > first free core: core/parser/msg_parser.c: reset_ua(994) - ignoring > CRITICAL: <core> [core/mem/q_malloc.c:519]: qm_free(): BUG: freeing > already freed pointer (0x7f4d969c9d48), called from core: > core/data_lump.c: free_lump(470), first free core: > core/parser/msg_parser.c: reset_ua(994) - ignoring CRITICAL: <core> > [core/mem/q_malloc.c:519]: qm_free(): BUG: freeing already freed pointer > (0x7fc29bdca3d0), called from core: core/data_lump.c: free_lump(470), > first free textops: textops.c: add_hf_helper(3474) - ignoring CRITICAL: > <core> [core/mem/q_malloc.c:519]: qm_free(): BUG: freeing already freed > pointer (0x7fc2c2eccc80), called from core: core/data_lump.c: > free_lump(470), first free textops: textops.c: add_hf_helper(3474) - > ignoring CRITICAL: <core> [core/mem/q_malloc.c:519]: qm_free(): BUG: > freeing already freed pointer (0x7fc2c2ec52c0), called from core: > core/data_lump.c: free_lump(470), first free core: > core/parser/msg_parser.c: reset_ua(994) - ignoring CRITICAL: <core> > [core/mem/q_malloc.c:519]: qm_free(): BUG: freeing already freed pointer > (0x7f910c04e240), called from core: core/data_lump.c: free_lump(470), > first free textops: textops.c: add_hf_helper(3474) - ignoring CRITICAL: > <core> [core/mem/q_malloc.c:519]: qm_free(): BUG: freeing already freed > pointer (0x7fa351286b18), called from core: core/parser/msg_parser.c: > reset_instance(916), first free core: core/parser/parse_addr_spec.c: > free_to_params(895) - ignoring CRITICAL: <core> > [core/mem/q_malloc.c:519]: qm_free(): BUG: freeing already freed pointer > (0x7fa3512a7ae8), called from core: core/data_lump.c: free_lump(470), > first free textops: textops.c: add_hf_helper(3474) - ignoring CRITICAL: > <core> [core/mem/q_malloc.c:519]: qm_free(): BUG: freeing already freed > pointer (0x7fa0777d9e70), called from core: core/parser/msg_parser.c: > reset_instance(916), first free core: core/parser/parse_addr_spec.c: > free_to_params(895) - ignoring CRITICAL: <core> > [core/mem/q_malloc.c:519]: qm_free(): BUG: freeing already freed pointer > (0x7fa0777d7a80), called from core: core/parser/msg_parser.c: > reset_instance(916), first free core: core/parser/parse_addr_spec.c: > free_to_params(895) - ignoring CRITICAL: <core> > [core/mem/q_malloc.c:519]: qm_free(): BUG: freeing already freed pointer > (0x7f4f2c92cf20), called from core: core/data_lump.c: free_lump(470), > first free core: core/parser/msg_parser.c: reset_ua(994) - ignoring > CRITICAL: <core> [core/mem/q_malloc.c:519]: qm_free(): BUG: freeing > already freed pointer (0x7f4f2c8afa00), called from core: > core/data_lump.c: free_lump(470), first free textops: textops.c: > add_hf_helper(3474) - ignoring CRITICAL: <core> > [core/mem/q_malloc.c:519]: qm_free(): BUG: freeing already freed pointer > (0x7f4f2c92aa48), called from core: core/parser/sdp/sdp.c: free_sdp(825), > first free core: core/parser/msg_parser.c: reset_ua(994) - ignoring > CRITICAL: <core> [core/mem/q_malloc.c:519]: qm_free(): BUG: freeing > already freed pointer (0x7fe55825eb30), called from core: > core/parser/sdp/sdp.c: free_sdp(825), first free core: > core/parser/sdp/sdp.c: init_p_payload_attr(183) - ignoring CRITICAL: > <core> [core/mem/q_malloc.c:519]: qm_free(): BUG: freeing already freed > pointer (0x7f6526db8378), called from core: core/parser/sdp/sdp.c: > free_sdp(825), first free core: core/parser/msg_parser.c: reset_ua(994) - > ignoring CRITICAL: <core> [core/mem/q_malloc.c:519]: qm_free(): BUG: > freeing already freed pointer (0x7fcd9b465980), called from core: > core/parser/msg_parser.c: reset_instance(916), first free core: > core/parser/hf.c: free_hdr_field_lst(217) - ignoring CRITICAL: <core> > [core/mem/q_malloc.c:519]: qm_free(): BUG: freeing already freed pointer > (0x7ff7ca92ff60), called from core: core/parser/msg_parser.c: > reset_instance(916), first free core: core/parser/parse_addr_spec.c: > free_to_params(895) - ignoring CRITICAL: <core> > [core/mem/q_malloc.c:519]: qm_free(): BUG: freeing already freed pointer > (0x7f978a2dc938), called from core: core/parser/msg_parser.c: > reset_instance(916), first free core: core/parser/parse_addr_spec.c: > free_to_params(895) - ignoring CRITICAL: <core> > [core/mem/q_malloc.c:519]: qm_free(): BUG: freeing already freed pointer > (0x7f978a2b7eb8), called from core: core/parser/msg_parser.c: > reset_instance(916), first free core: core/parser/parse_addr_spec.c: > free_to_params(895) - ignoring CRITICAL: <core> > [core/mem/q_malloc.c:519]: qm_free(): BUG: freeing already freed pointer > (0x7fa1556c1610), called from core: core/parser/sdp/sdp.c: free_sdp(825), > first free core: core/parser/msg_parser.c: reset_ua(994) - ignoring > CRITICAL: <core> [core/mem/q_malloc.c:519]: qm_free(): BUG: freeing > already freed pointer (0x7fd81dfbba78), called from core: > core/parser/sdp/sdp.c: free_sdp(825), first free core: > core/parser/msg_parser.c: reset_ua(994) - ignoring CRITICAL: <core> > [core/mem/q_malloc.c:519]: qm_free(): BUG: freeing already freed pointer > (0x7fb2750d9988), called from core: core/parser/sdp/sdp.c: free_sdp(825), > first free core: core/parser/msg_parser.c: reset_ua(994) - ignoring > CRITICAL: <core> [core/mem/q_malloc.c:519]: qm_free(): BUG: freeing > already freed pointer (0x7fb2750e1b28), called from core: > core/parser/msg_parser.c: reset_instance(916), first free core: > core/parser/parse_addr_spec.c: free_to_params(895) - ignoring CRITICAL: > <core> [core/mem/q_malloc.c:519]: qm_free(): BUG: freeing already freed > pointer (0x7f32f7b45d30), called from core: core/parser/msg_parser.c: > reset_instance(916), first free core: core/parser/hf.c: > free_hdr_field_lst(217) - ignoring CRITICAL: <core> > [core/mem/q_malloc.c:519]: qm_free(): BUG: freeing already freed pointer > (0x7f35849e1940), called from core: core/parser/msg_parser.c: > reset_instance(916), first free core: core/parser/hf.c: > free_hdr_field_lst(217) - ignoring CRITICAL: <core> > [core/mem/q_malloc.c:519]: qm_free(): BUG: freeing already freed pointer > (0x7fd3a2c04280), called from core: core/parser/sdp/sdp.c: free_sdp(825), > first free core: core/parser/msg_parser.c: reset_ua(994) - ignoring > CRITICAL: <core> [core/mem/q_malloc.c:519]: qm_free(): BUG: freeing > already freed pointer (0x7f5de857a040), called from core: > core/parser/msg_parser.c: reset_instance(916), first free core: > core/parser/hf.c: free_hdr_field_lst(217) - ignoring