[SR-Users] Problems with Mar portion of registration

treyormsbee trey85stang at gmail.com
Wed Mar 20 16:11:26 CET 2013


Hello jason,  we tried that block of code but the core is still crashing. 
Here is debug output:

 0(22585) DEBUG: <core> [parser/msg_parser.c:623]: SIP Request:
 0(22585) DEBUG: <core> [parser/msg_parser.c:625]:  method:  <REGISTER>
 0(22585) DEBUG: <core> [parser/msg_parser.c:627]:  uri:    
<sip:scscf.ims.fakedomain.com:6060>
 0(22585) DEBUG: <core> [parser/msg_parser.c:629]:  version: <SIP/2.0>
 0(22585) DEBUG: <core> [parser/msg_parser.c:170]: get_hdr_field: cseq
<CSeq>: <5> <REGISTER>
 0(22585) DEBUG: <core> [parser/parse_to.c:799]: end of header reached,
state=10
 0(22585) DEBUG: <core> [parser/msg_parser.c:190]: DEBUG: get_hdr_field:
<To> [34]; uri=[sip:bob at ims.fakedomain.com]
 0(22585) DEBUG: <core> [parser/msg_parser.c:192]: DEBUG: to body ["bob"
<sip:bob at ims.fakedomain.com>
]
 0(22585) DEBUG: <core> [parser/parse_via.c:1284]: Found param type 232,
<branch> = <z9hG4bKe566.738c1d82.1>; state=16
 0(22585) DEBUG: <core> [parser/parse_via.c:2672]: end of header reached,
state=5
 0(22585) DEBUG: <core> [parser/msg_parser.c:513]: parse_headers: Via found,
flags=2
 0(22585) DEBUG: <core> [parser/msg_parser.c:515]: parse_headers: this is
the first via
 0(22585) DEBUG: <core> [receive.c:149]: After parse_msg...
 0(22585) DEBUG: <core> [receive.c:190]: preparing to run routing scripts...
 0(22585) DEBUG: maxfwd [mf_funcs.c:85]: value = 15
 0(22585) DEBUG: <core> [parser/parse_via.c:1284]: Found param type 232,
<branch> = <z9hG4bKe566.d4c68714a351b6b34834598a13530efc.0>; state=16
 0(22585) DEBUG: <core> [parser/parse_via.c:2672]: end of header reached,
state=5
 0(22585) DEBUG: <core> [parser/msg_parser.c:513]: parse_headers: Via found,
flags=1000
 0(22585) DEBUG: <core> [parser/msg_parser.c:526]: parse_headers: this is
the second via
 0(22585) DEBUG: <core> [parser/parse_via.c:1284]: Found param type 232,
<branch> = <z9hG4bK-363131-766c10be16725e03e2d075cb5c6e58b4>; state=16
 0(22585) DEBUG: <core> [parser/parse_via.c:2672]: end of header reached,
state=5
 0(22585) DEBUG: <core> [parser/msg_parser.c:513]: parse_headers: Via found,
flags=1000
 0(22585) DEBUG: <core> [parser/msg_parser.c:204]: DEBUG: get_hdr_body :
content_length=0
 0(22585) DEBUG: <core> [parser/msg_parser.c:106]: found end of header
 0(22585) DEBUG: <core> [parser/parse_to.c:176]: DEBUG: add_param:
tag=a6fdd1ee
 0(22585) DEBUG: <core> [parser/parse_to.c:799]: end of header reached,
state=29
 0(22585) DEBUG: sanity [mod_sanity.c:255]: sanity checks result: 1
 0(22585) DEBUG: siputils [checks.c:103]: no totag
 0(22585) DEBUG: <core> [select.c:425]: Calling SELECT 0x7fc7f7c33ea0
 0(22585) DEBUG: tm [t_lookup.c:1095]: DEBUG: t_check_msg: msg id=5 global
id=4 T start=0xffffffffffffffff
 0(22585) DEBUG: tm [t_lookup.c:534]: t_lookup_request: start searching:
hash=26206, isACK=0
 0(22585) DEBUG: tm [t_lookup.c:492]: DEBUG: RFC3261 transaction matching
failed
 0(22585) DEBUG: tm [t_lookup.c:716]: DEBUG: t_lookup_request: no
transaction found
 0(22585) DEBUG: tm [t_lookup.c:1164]: DEBUG: t_check_msg: msg id=5 global
id=5 T end=(nil)
 0(22585) DEBUG: tm [t_lookup.c:1395]: DEBUG: t_newtran: msg id=5 , global
msg id=5 , T on entrance=(nil)
 0(22585) DEBUG: tm [t_lookup.c:534]: t_lookup_request: start searching:
hash=26206, isACK=0
 0(22585) DEBUG: tm [t_lookup.c:492]: DEBUG: RFC3261 transaction matching
failed
 0(22585) DEBUG: tm [t_lookup.c:716]: DEBUG: t_lookup_request: no
transaction found
 0(22585) DEBUG: tm [t_hooks.c:374]: DBG: trans=0x7fc7f0a66570, callback
type 1, id 0 entered
 0(22585) DEBUG: ims_auth [authorize.c:567]: Checking if REGISTER is
authorized for realm [ims.fakedomain.com]...
 0(22585) DEBUG: ims_auth [authorize.c:597]: Nonce or response missing:
nonce len [-138155584], response16 len[-72537977]
 0(22585) DEBUG: <script>: About to challenge! auth_ims
 0(22585) INFO: ims_auth [cxdx_mar.c:81]: created AVP successfully :
[maa_return_code] - [-2]
 0(22585) DEBUG: ims_auth [authorize.c:298]: Need to challenge for realm
[ims.fakedomain.com]
 0(22585) DEBUG: ims_auth [authorize.c:305]: Checking if REGISTER is
authorized for realm [ims.fakedomain.com]...
 0(22585) DEBUG: ims_auth [authorize.c:435]: Suspending SIP TM transaction
 0(22585) DEBUG: ims_auth [authorize.c:1271]: Sending MAR
 0(22585) DEBUG: cdp [session.c:276]: adding a session with id
scscf.ims.fakedomain.com;3913928633;5
 0(22585) DEBUG: cdp [routing.c:80]: get_first_connected_route in list
0x7fc7f0a37bf0 for app_id 16777216 and vendor_id 10415
 0(22585) DEBUG: cdp [routing.c:88]: The peer hss.ims.fakedomain.com state
is opened
 0(22585) DEBUG: cdp [routing.c:63]: Checking if peer hss.ims.fakedomain.com
handles application 16777216 for vendord 10415
 0(22585) DEBUG: cdp [routing.c:90]: The peer hss.ims.fakedomain.com matches
- will forward there
 0(22585) DEBUG: cdp [peerstatemachine.c:91]: sm_process(): Peer
hss.ims.fakedomain.com 	State I_Open 	Event Send_Message
 0(22585) DEBUG: cdp [peerstatemachine.c:1096]: Snd_Message called to peer
[hss.ims.fakedomain.com] for request with code 303
 0(22585) DEBUG: cdp [session.c:297]: called get session with id
scscf.ims.fakedomain.com;3913928633;5 and hash 34
 0(22585) DEBUG: cdp [session.c:306]: no session found
 0(22585) DEBUG: cdp [diameter_msg.c:83]: AAABuildMsgBuffer(): len=348
 0(22585) DEBUG: cdp [receiver.c:967]: peer_send_msg(): Pipe push
[0x7fc7f0a68e70]
 0(22585) DEBUG: ims_auth [cxdx_mar.c:516]: Successfully sent async diameter
 0(22585) DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying
list (nil)
 0(22585) DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying
list (nil)
 0(22585) DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying
list (nil)
 0(22585) DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying
list (nil)
 0(22585) DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying
list (nil)
 0(22585) DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying
list (nil)
 0(22585) DEBUG: <core> [xavp.c:447]: destroying xavp list (nil)
 0(22585) DEBUG: <core> [receive.c:293]: receive_msg: cleaning up
10(22595) DEBUG: cdp [receiver.c:743]: select_recv(): There is something on
the send pipe
10(22595) DEBUG: cdp [receiver.c:756]: select_recv(): Send pipe says
[0x7fc7f0a68e70] 8
10(22595) DEBUG: cdp [diameter_msg.c:412]: AAAFreeMessage: Freeing message
(0x7fc7f0a68e70) 303
10(22595) DEBUG: cdp [receiver.c:104]: --- Receiver
[cdp_receiver_peer=hss.ims.fakedomain.com] Serviced Peers: ---
10(22595) DEBUG: cdp [receiver.c:110]:  Peer: [hss.ims.fakedomain.com]  TCP
Socket: [15] Recv.State: [0]
10(22595) DEBUG: cdp [receiver.c:112]:
--------------------------------------------------------
10(22595) DEBUG: cdp [receiver.c:104]: --- Receiver
[cdp_receiver_peer=hss.ims.fakedomain.com] Serviced Peers: ---
10(22595) DEBUG: cdp [receiver.c:110]:  Peer: [hss.ims.fakedomain.com]  TCP
Socket: [15] Recv.State: [1]
10(22595) DEBUG: cdp [receiver.c:112]:
--------------------------------------------------------
10(22595) DEBUG: cdp [receiver.c:567]: receive_loop():
[hss.ims.fakedomain.com] Recv Version 1 Length 172
10(22595) DEBUG: cdp [receiver.c:104]: --- Receiver
[cdp_receiver_peer=hss.ims.fakedomain.com] Serviced Peers: ---
10(22595) DEBUG: cdp [receiver.c:110]:  Peer: [hss.ims.fakedomain.com]  TCP
Socket: [15] Recv.State: [2]
10(22595) DEBUG: cdp [receiver.c:112]:
--------------------------------------------------------
10(22595) DEBUG: cdp [receiver.c:1061]: receive_message():
[hss.ims.fakedomain.com] Recv msg 303
10(22595) DEBUG: cdp [peerstatemachine.c:91]: sm_process(): Peer
hss.ims.fakedomain.com 	State I_Open 	Event I_Rcv_Message
10(22595) DEBUG: cdp [session.c:297]: called get session with id
scscf.ims.fakedomain.com;3913928633;5 and hash 34
10(22595) DEBUG: cdp [session.c:306]: no session found
10(22595) DEBUG: cdp [receiver.c:104]: --- Receiver
[cdp_receiver_peer=hss.ims.fakedomain.com] Serviced Peers: ---
10(22595) DEBUG: cdp [receiver.c:110]:  Peer: [hss.ims.fakedomain.com]  TCP
Socket: [15] Recv.State: [0]
10(22595) DEBUG: cdp [receiver.c:112]:
--------------------------------------------------------
 0(22585) ALERT: <core> [main.c:788]: child process 22591 exited by a signal
11
 0(22585) ALERT: <core> [main.c:791]: core was generated
 0(22585) INFO: <core> [main.c:800]: INFO: dont_fork turned on, living on
 0(22585) ALERT: <core> [main.c:788]: child process 22593 exited by a signal
11
 0(22585) ALERT: <core> [main.c:791]: core was generated
 0(22585) INFO: <core> [main.c:800]: INFO: dont_fork turned on, living on





And here is the core:

Program terminated with signal 11, Segmentation fault.
#0  async_cdp_callback (is_timeout=<value optimized out>,
param=0x7f21b67a4d70, maa=0x7f21b67adc60, elapsed_msecs=<value optimized
out>) at cxdx_mar.c:414
414	    start_reg_await_timer(avlist[0]); //start the timer to remove stale
or unused Auth Vectors
Missing separate debuginfos, use: debuginfo-install
glibc-2.12-1.107.el6.x86_64 libxml2-2.7.6-8.el6_3.4.x86_64
pcre-7.8-6.el6.x86_64 zlib-1.2.3-29.el6.x86_64
(gdb) bt
#0  async_cdp_callback (is_timeout=<value optimized out>,
param=0x7f21b67a4d70, maa=0x7f21b67adc60, elapsed_msecs=<value optimized
out>) at cxdx_mar.c:414
#1  0x00007f21b9c8a55d in api_callback (p=0x7f21b67830e0,
msg=0x7f21b67adc60, ptr=<value optimized out>) at api_process.c:115
#2  0x00007f21b9cc0494 in worker_process (id=3) at worker.c:327
#3  0x00007f21b9ca3e80 in diameter_peer_start (blocking=0) at
diameter_peer.c:307
#4  0x00007f21b9ca5023 in cdp_child_init (rank=<value optimized out>) at
mod.c:228
#5  0x00000000004e2351 in init_mod_child (m=0x7f21bd957fa0, rank=0) at
sr_module.c:893
#6  0x00000000004e22d4 in init_mod_child (m=0x7f21bd958930, rank=0) at
sr_module.c:890
#7  0x00000000004e22d4 in init_mod_child (m=0x7f21bd958b80, rank=0) at
sr_module.c:890
#8  0x00000000004e22d4 in init_mod_child (m=0x7f21bd958d60, rank=0) at
sr_module.c:890
#9  0x00000000004e22d4 in init_mod_child (m=0x7f21bd9592a0, rank=0) at
sr_module.c:890
#10 0x00000000004e22d4 in init_mod_child (m=0x7f21bd959560, rank=0) at
sr_module.c:890
#11 0x00000000004e22d4 in init_mod_child (m=0x7f21bd959910, rank=0) at
sr_module.c:890
#12 0x00000000004e22d4 in init_mod_child (m=0x7f21bd959af0, rank=0) at
sr_module.c:890
#13 0x0000000000464d1f in main_loop () at main.c:1426
#14 0x0000000000467a6c in main (argc=<value optimized out>, argv=<value
optimized out>) at main.c:2566


Perhaps I need to file a bug report?



--
View this message in context: http://sip-router.1086192.n5.nabble.com/Problems-with-Mar-portion-of-registration-tp116527p116614.html
Sent from the Users mailing list archive at Nabble.com.



More information about the sr-users mailing list