[Home]

Summary:ASTERISK-30169: res_pjsip: assertion on send_msg
Reporter:N A (InterLinked)Labels:
Date Opened:2022-08-05 15:28:17Date Closed:2022-08-05 17:29:16
Priority:MinorRegression?
Status:Closed/CompleteComponents:Resources/res_pjsip
Versions:18.9.0 Frequency of
Occurrence		Occasional
Related
Issues:
duplicatesASTERISK-28689 res_pjsip: Crash when locking group lock when sending stateful response
Environment:Attachments:
Description:Apologies if this is a duplicate (it may well be) - I checked ASTERISK-29466 but this doesn't appear to be quite the same, so I'm opening an issue just in case this is a new PJSIP issue.

Backtrace attached:

{noformat}
Thread 1 (Thread 0x7f401ec0a700 (LWP 18253)):
#0  0x00007f404b40f7bb in __GI_raise (sig=sig@entry=6) at ../sysdeps/unix/sysv/linux/raise.c:50
       set = {__val = {134238211, 94450194275824, 139913929202736, 139914117448839, 139917077086208, 94450194275824, 94450194275824, 94450194275824, 94450194275824, 94450194275906, 94450194275924, 94450194275824, 94450194275924, 0, 0, 0}}
       pid = <optimized out>
       tid = <optimized out>
#1  0x00007f404b3fa535 in __GI_abort () at abort.c:79
       save_stage = 1
       act = {__sigaction_handler = {sa_handler = 0x55e6e6475df0, sa_sigaction = 0x55e6e6475df0}, sa_mask = {__val = {0, 139914118769728, 139914118504064, 0, 0, 0, 21474836480, 139913370573096, 139913370572944, 139914118560528, 139914118545120, 0, 18337308624731703296, 139914118529978, 0, 139914118545120}}, sa_flags = 1278240456, sa_restorer = 0x7f404c306986}
       sigs = {__val = {32, 0 <repeats 15 times>}}
#2  0x00007f404b3fa40f in __assert_fail_base (fmt=0x7f404b55bee0 "%s%s%s:%u: %s%sAssertion `%s' failed.n%n", assertion=0x7f404c306986 "mutex", file=0x7f404c3066c8 "../src/pj/os_core_unix.c", line=1270, function=<optimized out>) at assert.c:92
       str = 0x55e6e6475df0 "320177&344346U"
       total = 4096
#3  0x00007f404b408102 in __GI___assert_fail (assertion=0x7f404c306986 "mutex", file=0x7f404c3066c8 "../src/pj/os_core_unix.c", line=1270, function=0x7f404c306d00 <__PRETTY_FUNCTION__.6466> "pj_mutex_lock") at assert.c:101
#4  0x00007f404c2b9748 in pj_mutex_lock (mutex=0x0) at ../src/pj/os_core_unix.c:1270
       status = 32576
       __PRETTY_FUNCTION__ = "pj_mutex_lock"
#5  0x00007f404c2b9108 in pj_atomic_get (atomic_var=0x55e6e62cbd78) at ../src/pj/os_core_unix.c:929
       oldval = 1277924348
#6  0x00007f404c2c2376 in grp_lock_acquire (p=0x55e6e62cbce8) at ../src/pj/lock.c:286
       glock = 0x55e6e62cbce8
       lck = 0x7f404c2c2ea2 <pj_log_add_indent+30>
       __PRETTY_FUNCTION__ = "grp_lock_acquire"
#7  0x00007f404c2c2955 in pj_grp_lock_acquire (grp_lock=0x55e6e62cbce8) at ../src/pj/lock.c:478
#8  0x00007f404c2290c2 in pjsip_tsx_send_msg (tsx=0x7f403151ee48, tdata=0x55e6e7aa6d58) at ../src/pjsip/sip_transaction.c:1788
       event = {prev = 0x7f401ec09b00, next = 0x7f402949f629 <supplement_outgoing_response+425>, type = PJSIP_EVENT_TX_MSG, body = {timer = {entry = 0x55e6e7aa6d58}, tsx_state = {src = {rdata = 0x55e6e7aa6d58, tdata = 0x55e6e7aa6d58, timer = 0x55e6e7aa6d58, status = -408261288, data = 0x55e6e7aa6d58}, tsx = 0x7f401ec09b00, prev_state = -439175464, type = 21990}, tx_msg = {tdata = 0x55e6e7aa6d58}, tx_error = {tdata = 0x55e6e7aa6d58, tsx = 0x7f401ec09b00}, rx_msg = {rdata = 0x55e6e7aa6d58}, user = {user1 = 0x55e6e7aa6d58, user2 = 0x7f401ec09b00, user3 = 0x55e6e5d2b6d8, user4 = 0x55e6e5749f58}}}
       status = 0
       __PRETTY_FUNCTION__ = "pjsip_tsx_send_msg"
#9  0x00007f402949f7c3 in ast_sip_send_stateful_response (rdata=0x7f403354e188, tdata=0x55e6e7aa6d58, sip_endpoint=0x55e6e3e985e8) at res_pjsip.c:5477
       tsx = 0x7f403151ee48
       __PRETTY_FUNCTION__ = "ast_sip_send_stateful_response"
#10 0x00007f402937c15e in register_aor (rdata=0x7f403354e188, endpoint=0x55e6e3e985e8, aor=0x55e6e26772c0, aor_name=0x55e6e2861ad0 "REDACTED") at res_pjsip_registrar.c:1023
       response = {tdata = 0x55e6e7aa6d58, code = 500}
       contacts = 0x55e6e7953c68
       __PRETTY_FUNCTION__ = "register_aor"
#11 0x00007f402937ce7d in registrar_on_rx_request (rdata=0x7f403354e188) at res_pjsip_registrar.c:1218
       endpoint = 0x55e6e3e985e8
       aor = 0x55e6e26772c0
       aor_name = 0x55e6e2861ad0 "REDACTED"
       __PRETTY_FUNCTION__ = "registrar_on_rx_request"
       __FUNCTION__ = "registrar_on_rx_request"
#12 0x00007f404c20a58f in pjsip_endpt_process_rx_data (endpt=0x55e6e29e6778, rdata=0x7f403354e188, p=0x7f40294f56e0 <param>, p_handled=0x7f401ec09c94) at ../src/pjsip/sip_endpoint.c:930
       msg = 0x7f403355e4e0
       def_prm = {start_prio = 515939504, start_mod = 0x55e6e0013acd <__ao2_unlock+237>, idx_after_start = 515939504, silent = 32576}
       mod = 0x7f40293813c0 <registrar_module>
       handled = 0
       i = 1
       status = 21990
       __PRETTY_FUNCTION__ = "pjsip_endpt_process_rx_data"
#13 0x00007f40294bde23 in distribute (data=0x7f403354e188) at res_pjsip/pjsip_distributor.c:955
       param = {start_prio = 0, start_mod = 0x7f40294f5540 <distributor_mod>, idx_after_start = 1, silent = 0}
       handled = 0
       rdata = 0x7f403354e188
       is_request = 1
       is_ack = 0
       endpoint = 0x55e6e25d60c8
       __PRETTY_FUNCTION__ = "distribute"
#14 0x000055e6e019c8a2 in ast_taskprocessor_execute (tps=0x55e6e25d60e0) at taskprocessor.c:1237
       local = {local_data = 0x7f4028d0cb4f, data = 0x55e6e01a39e9 <ast_threadstorage_set_ptr+60>}
       t = 0x7f4032db2060
       size = 94450128609504
       __PRETTY_FUNCTION__ = "ast_taskprocessor_execute"
#15 0x000055e6e01a66c0 in execute_tasks (data=0x55e6e25d60e0) at threadpool.c:1354
       tps = 0x55e6e25d60e0
#16 0x000055e6e019c8a2 in ast_taskprocessor_execute (tps=0x55e6e24b52b0) at taskprocessor.c:1237
       local = {local_data = 0x55e6e21881d0, data = 0x55e6e2188208}
       t = 0x7f4032db03d0
       size = 515939760
       __PRETTY_FUNCTION__ = "ast_taskprocessor_execute"
#17 0x000055e6e01a4226 in threadpool_execute (pool=0x55e6e2188220) at threadpool.c:367
       __PRETTY_FUNCTION__ = "threadpool_execute"
#18 0x000055e6e01a5e68 in worker_active (worker=0x7f402444d270) at threadpool.c:1137
       alive = 0
#19 0x000055e6e01a5bd2 in worker_start (arg=0x7f402444d270) at threadpool.c:1056
       worker = 0x7f402444d270
       saved_state = (unknown: 32576)
       __PRETTY_FUNCTION__ = "worker_start"
#20 0x000055e6e01b21a5 in dummy_start (data=0x55e6e2370cf0) at utils.c:1572
       __cancel_buf = {__cancel_jmp_buf = {{__cancel_jmp_buf = {0, 6222314222226408977, 139913539406670, 139913539406671, 139913370576640, 139913929202736, 6222314222318683665, 222897833836709393}, __mask_was_saved = 0}}, __pad = {0x7f401ec09ed0, 0x0, 0xfe7b3509f9d70800, 0x7f40447bd330}}
       __cancel_routine = 0x55e6e0002ee2 <ast_unregister_thread>
       __cancel_arg = 0x7f401ec0a700
       __not_first_call = 0
       ret = 0x7f401ec0a700
       a = {start_routine = 0x55e6e01a5b3c <worker_start>, data = 0x7f402444d270, name = 0x7f40380cf060 "worker_start         started at [ 1111] threadpool.c worker_thread_start()"}
       lock_info = 0x55e6e22fd780
       mutex_attr = {__size = "001000000", __align = 1}
       __PRETTY_FUNCTION__ = "dummy_start"
#21 0x00007f404ba3cfa3 in start_thread (arg=<optimized out>) at pthread_create.c:486
       ret = <optimized out>
       pd = <optimized out>
       now = <optimized out>
       unwind_buf = {cancel_jmp_buf = {{jmp_buf = {139913370576640, -6279666802825198063, 139913539406670, 139913539406671, 139913370576640, 139913929202736, 6222314222220117521, 6222482749808739857}, mask_was_saved = 0}}, priv = {pad = {0x0, 0x0, 0x0, 0x0}, data = {prev = 0x0, cleanup = 0x0, canceltype = 0}}}
       not_first_call = <optimized out>
#22 0x00007f404b4d0eff in clone () at ../sysdeps/unix/sysv/linux/x86_64/clone.S:95
{noformat}
Comments:By: Asterisk Team (asteriskteam) 2022-08-05 15:28:21.452-0500

Thanks for creating a report! The issue has entered the triage process. That means the issue will wait in this status until a Bug Marshal has an opportunity to review the issue. Once the issue has been reviewed you will receive comments regarding the next steps towards resolution. Please note that log messages and other files should not be sent to the Sangoma Asterisk Team unless explicitly asked for. All files should be placed on this issue in a sanitized fashion as needed.

A good first step is for you to review the [Asterisk Issue Guidelines|https://wiki.asterisk.org/wiki/display/AST/Asterisk+Issue+Guidelines] if you haven't already. The guidelines detail what is expected from an Asterisk issue report.

Then, if you are submitting a patch, please review the [Patch Contribution Process|https://wiki.asterisk.org/wiki/display/AST/Patch+Contribution+Process].

Please note that once your issue enters an open state it has been accepted. As Asterisk is an open source project there is no guarantee or timeframe on when your issue will be looked into. If you need expedient resolution you will need to find and pay a suitable developer. Asking for an update on your issue will not yield any progress on it and will not result in a response. All updates are posted to the issue when they occur.

Please note that by submitting data, code, or documentation to Sangoma through JIRA, you accept the Terms of Use present at [https://www.asterisk.org/terms-of-use/|https://www.asterisk.org/terms-of-use/].