[Home]

Summary:ASTERISK-21202: Asterisk SIP message (SMS) stops working
Reporter:Abhay Gupta (agupta)Labels:
Date Opened:2013-03-01 22:58:36.000-0600Date Closed:2013-03-03 11:17:53.000-0600
Priority:MajorRegression?No
Status:Closed/CompleteComponents:Channels/chan_sip/Messaging
Versions:10.12.1 Frequency of
Occurrence		Frequent
Related
Issues:
Environment:CentOSAttachments:( 0) QUEUE_MSG_Callingo.txt
Description:The message stops working and in taskprocessor ast_msg_queue ,  queue starts growing from then on no messages are delivered till asterisk is restarted .

This feature started in version 10 and in all the version till date this problem is present .

+----- Processor -----+--- Processed ---+- In Queue -+- Max Depth -+
              app_queue               81342            0            1
  core_event_dispatcher       358663            0            1
          app_voicemail                      0            0            0
               pbx-core                  81343            0            1
          ast_msg_queue                 20            10            0
              CCSS core                       0            0            0
          iax2_transmit                        0            0            0
       +---------------------+-----------------+------------+-------------+
       7 taskprocessors

Thread apply all bt full on the running process text file is attached with during the said problem .
Comments:By: Abhay Gupta (agupta) 2013-03-01 23:00:07.982-0600

This is the gdb bt full during the time of problem .
By: Abhay Gupta (agupta) 2013-03-01 23:16:32.637-0600

Just for addition , it looks to me that the area of concern is that task processor thread in msg_q_cb hangs at ast_waitfor_nandfds.

#0  0x0000003b78acaf36 in poll () from /lib64/libc.so.6
No symbol table info available.
#1  0x0000000000471bce in ast_waitfor_nandfds (c=0x41e5c790, n=1, fds=0x0, nfds=0, exception=0x0, outfd=0x0, ms=0x41e5c78c) at channel.c:3319
       kbrms = -4
       pfds = 0x41e5c620
       res = <value optimized out>
       rms = 100000
---Type <return> to continue, or q <return> to quit---
       x = <value optimized out>
       max = -1
       sz = <value optimized out>
       now = {tv_sec = 1105578192, tv_usec = 1105586384}
       diff = {tv_sec = 0, tv_usec = 1105595632}
       winner = <value optimized out>
       __PRETTY_FUNCTION__ = "ast_waitfor_nandfds"
#2  0x0000000000472033 in ast_waitfor (c=0x2aaaacf4cb28, ms=100000) at channel.c:3581
No locals.
#3  0x00000000004f37ad in wait_for_hangup (chan=0x2aaaacf4cb28, data=0xfffffffffffffffc) at pbx.c:9727
       res = <value optimized out>
       f = <value optimized out>
       waitsec = -1
#4  0x00000000004f385f in pbx_builtin_congestion (chan=0x2aaaacf4cb28, data=0x5697fb "") at pbx.c:9789
No locals.
#5  0x00000000004fa02f in pbx_exec (c=0x2aaaacf4cb28, app=0x2aaaac023730, data=0x41e5e8d0 "") at pbx.c:1567
       res = <value optimized out>


       u = 0x0
       saved_c_appl = 0x0
       saved_c_data = 0x0
       __PRETTY_FUNCTION__ = "pbx_exec"
#6  0x000000000050a1ec in pbx_extension_helper (c=0x2aaaacf4cb28, con=<value optimized out>, context=0x2aaaacf4d3f8 "sip-in", exten=0x2aaaacf4d448 "s", priority=1,
   label=0x0, callerid=0x0, action=E_SPAWN, found=0x41e60f4c, combined_find_spawn=1) at pbx.c:4630
       e = <value optimized out>
       app = 0x2aaaac023730
       res = <value optimized out>
       q = {incstack = {0x0 <repeats 128 times>}, stacklen = 0, status = 5, swo = 0x0, data = 0x0, foundcontext = 0x2aaaacf4d3f8 "sip-in"}
       passdata = "\000?15\000/209.143.142.99:3333/AGI?textMessage", '\000' <repeats 5903 times>"\264, \342\246x;", '\000' <repeats 11 times>"\340, \006\346A\000\000\000\000SrY\000\000\000\000\000SrY\000\000\000\000\000]\000\000\000\000\000\000\000?5\244x;", '\000' <repeats 11 times>"\240, \006\346A", '\000' <repeats 12 times>"\300, \001\346A", '\000' <repeats 28 times>"\220, \b\346A\000\000\000\000QrY\000\000\000\000\000\360\006\346A", '\000' <repeats 36 times>"\377, \377\377\377\377\377\377\377QrY", '\000' <repeats 13 times>"\250, \005\346A", '\000' <repeats 12 times>, "\035", '\000' <repeats 55 times>, " ", '\000' <repeats 18 times>...
       matching_action = 0
       __PRETTY_FUNCTION__ = "pbx_extension_helper"
#7  0x000000000050d0ab in ast_spawn_extension (c=0x2aaaacf4cb28, args=0x41e60ff0) at pbx.c:5293
No locals.
#8  __ast_pbx_run (c=0x2aaaacf4cb28, args=0x41e60ff0) at pbx.c:5389
       digit = <value optimized out>
       invalid = <value optimized out>
       dst_exten = "\000\301\272\254", '\000' <repeats 20 times>, "k'\247x\001\000\000\000\030Zi\254\252*\000\000\030[v\006", '\000' <repeats 12 times>, "\001\000\000\000\001", '\000' <repeats 15 times>, "\001\000\000\000\000\000\000\000\001\000\000\000\070Zi\254\252*\000\000\000\000\000\000\000\000\000\000\030Zi\254\252*\000\000\370\202[\254\252*\000\000\000 \346A\000\000\000\000\000\000\000\000\001\000\000\000k'\247x;\000\000\000\250\031\000\254\000\000\000\000\246\234O\000\000\000\000\000\300Yi\254\252*\000\000\001\000\000\000\000\000\000\000\370\202[\254\252*\000\000\000 \346A\000\000\000\000\000\020\000\000\000\000\000\000\370\202[\254\252*\000\000\240\202[\254\252*\000\000\001\000\000\000\000\000\000\000\370\202[\254\252*\000\000\000 \346A\000\000\000\000\000\020\000\000\000\000\000\000k'\247x;\000\000"
---Type <return> to continue, or q <return> to quit---
       pos = 0
       found = 1
       res = 1105596400
       error = 0
       __PRETTY_FUNCTION__ = "__ast_pbx_run"
#9  0x000000000050e8fa in ast_pbx_run_args (c=0x2aaaacf4cb28, args=0x41e60ff0) at pbx.c:5778
       res = AST_PBX_CALL_LIMIT
       __PRETTY_FUNCTION__ = "ast_pbx_run_args"
#10 0x00000000004ee604 in msg_route (data=0x2aaaac83d1c8) at message.c:654
       pbx_args = {{__padding = 1, {no_hangup_chan = 1}}}
#11 msg_q_cb (data=0x2aaaac83d1c8) at message.c:755
       msg = <value optimized out>
       chan = 0x2aaaacf4cb28
       ds = 0x2aaaacbac150
       __PRETTY_FUNCTION__ = "msg_q_cb"
#12 0x000000000053e2cb in tps_processing_function (data=<value optimized out>) at taskprocessor.c:330
       i = 0x5d7ac78
       t = 0x2aaaad0dd5b0
       size = 0
       __PRETTY_FUNCTION__ = "tps_processing_function"
#13 0x000000000054bbac in dummy_start (data=<value optimized out>) at utils.c:1016
       __cancel_buf = {__cancel_jmp_buf = {{__cancel_jmp_buf = {98020688, -4300517074105066492, 0, 3, 1105600512, 4096, -4300517073046401948, -4300517074110535786},
             __mask_was_saved = 0}}, __pad = {0x41e611a0, 0x0, 0x0, 0x0}}
       __cancel_arg = 0x41e61940
       not_first_call = <value optimized out>
       ret = <value optimized out>
#14 0x0000003b7960673d in start_thread () from /lib64/libpthread.so.0
No symbol table info available.
#15 0x0000003b78ad3d1d in clone () from /lib64/libc.so.6
No symbol table info available.
By: Michael L. Young (elguero) 2013-03-03 11:17:40.650-0600

Maintenance (bug) support for the 10 branch has ended. For continued maintenance support please move to the 11 branch which is a long term support (LTS) branch. For more information about branch support, please see https://wiki.asterisk.org/wiki/display/AST/Asterisk+Versions. After testing with Asterisk 11, if you find this problem has not been resolved, please open a new issue against the supported version of Asterisk.