[sr-dev] [kamailio/kamailio] kamailio crashes on CANCEL due to empty reply_lumps (#872)

Thu Dec 1 16:29:09 CET 2016

core: core.kamailio.sig11.29831
log from pid that crash:
```
2016-12-01T16:16:21.463526+01:00 spce proxy[29831]: DEBUG: <core> [parser/msg_parser.c:595]: parse_msg(): SIP Request:
2016-12-01T16:16:21.463531+01:00 spce proxy[29831]: DEBUG: <core> [parser/msg_parser.c:597]: parse_msg():  method:  <CANCEL>
2016-12-01T16:16:21.463534+01:00 spce proxy[29831]: DEBUG: <core> [parser/msg_parser.c:599]: parse_msg():  uri:     <sip:4311003 at spce.test:5060>
2016-12-01T16:16:21.463538+01:00 spce proxy[29831]: DEBUG: <core> [parser/msg_parser.c:601]: parse_msg():  version: <SIP/2.0>
2016-12-01T16:16:21.463548+01:00 spce proxy[29831]: DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param(): Found param type 232, <branch> = <z9hG4bKed7a.bc0c3a7e71de87e4c6127fda1fd1b048.0>; state=16
2016-12-01T16:16:21.463562+01:00 spce proxy[29831]: DEBUG: <core> [parser/parse_via.c:2642]: parse_via(): end of header reached, state=5
2016-12-01T16:16:21.463567+01:00 spce proxy[29831]: DEBUG: <core> [parser/msg_parser.c:486]: parse_headers(): Via found, flags=2
2016-12-01T16:16:21.463570+01:00 spce proxy[29831]: DEBUG: <core> [parser/msg_parser.c:488]: parse_headers(): this is the first via
2016-12-01T16:16:21.463574+01:00 spce proxy[29831]: DEBUG: <core> [receive.c:178]: receive_msg(): After parse_msg...
2016-12-01T16:16:21.463583+01:00 spce proxy[29831]: DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param(): Found param type 234, <received> = <127.0.2.1>; state=6
2016-12-01T16:16:21.463638+01:00 spce proxy[29831]: DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param(): Found param type 235, <rport> = <51602>; state=6
2016-12-01T16:16:21.463653+01:00 spce proxy[29831]: DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param(): Found param type 232, <branch> = <z9hG4bK-30543-1-0>; state=16
2016-12-01T16:16:21.463657+01:00 spce proxy[29831]: DEBUG: <core> [parser/parse_via.c:2642]: parse_via(): end of header reached, state=5
2016-12-01T16:16:21.463661+01:00 spce proxy[29831]: DEBUG: <core> [parser/msg_parser.c:486]: parse_headers(): Via found, flags=ffffffffffffffff
2016-12-01T16:16:21.463664+01:00 spce proxy[29831]: DEBUG: <core> [parser/msg_parser.c:499]: parse_headers(): this is the second via
2016-12-01T16:16:21.463671+01:00 spce proxy[29831]: DEBUG: <core> [parser/parse_addr_spec.c:871]: parse_addr_spec(): end of header reached, state=10
2016-12-01T16:16:21.463675+01:00 spce proxy[29831]: DEBUG: <core> [parser/msg_parser.c:171]: get_hdr_field(): <To> [25]; uri=[sip:4311003 at spce.test]
2016-12-01T16:16:21.463678+01:00 spce proxy[29831]: DEBUG: <core> [parser/msg_parser.c:172]: get_hdr_field(): to body [<sip:4311003 at spce.test>#015#012]
2016-12-01T16:16:21.463704+01:00 spce proxy[29831]: DEBUG: <core> [parser/msg_parser.c:152]: get_hdr_field(): cseq <CSeq>: <1> <CANCEL>
2016-12-01T16:16:21.463720+01:00 spce proxy[29831]: DEBUG: <core> [parser/msg_parser.c:183]: get_hdr_field(): content_length=0
2016-12-01T16:16:21.463727+01:00 spce proxy[29831]: DEBUG: <core> [parser/msg_parser.c:89]: get_hdr_field(): found end of header
2016-12-01T16:16:21.463734+01:00 spce proxy[29831]: {1 1 CANCEL NGCP%incoming_peer_user_404%///1-30543 at 127.0.2.1} DEBUG: <core> [receive.c:221]: receive_msg(): preparing to run routing scripts...
2016-12-01T16:16:21.463784+01:00 spce proxy[29831]: {1 1 CANCEL NGCP%incoming_peer_user_404%///1-30543 at 127.0.2.1} DEBUG: <core> [forward.c:702]: update_sock_struct_from_via(): trying SRV lookup
2016-12-01T16:16:21.463800+01:00 spce proxy[29831]: {1 1 CANCEL NGCP%incoming_peer_user_404%///1-30543 at 127.0.2.1} DEBUG: <core> [msg_translator.c:158]: check_via_address(): (127.0.0.1, 127.0.0.1, 0)
2016-12-01T16:16:21.463839+01:00 spce proxy[29831]: {1 1 CANCEL NGCP%incoming_peer_user_404%///1-30543 at 127.0.2.1} DEBUG: <core> [parser/parse_addr_spec.c:171]: parse_to_param(): DEBUG: add_param: tag=30543SIPpTag001
2016-12-01T16:16:21.463881+01:00 spce proxy[29831]: {1 1 CANCEL NGCP%incoming_peer_user_404%///1-30543 at 127.0.2.1} DEBUG: <core> [parser/parse_addr_spec.c:871]: parse_addr_spec(): end of header reached, state=29
2016-12-01T16:16:21.463891+01:00 spce proxy[29831]: {1 1 CANCEL NGCP%incoming_peer_user_404%///1-30543 at 127.0.2.1} NOTICE: <script>: New request on proxy - M=CANCEL R=sip:4311003 at spce.test:5060 F=sip:004321001 at 127.0.2.1 T=sip:4311003 at spce.test IP=127.0.2.1:51602 (127.0.0.1:5060) ID=NGCP%incoming_peer_user_404%///1-30543 at 127.0.2.1 UA='<null>'
2016-12-01T16:16:21.463963+01:00 spce proxy[29831]: {1 1 CANCEL NGCP%incoming_peer_user_404%///1-30543 at 127.0.2.1} DEBUG: tm [t_lookup.c:701]: t_lookupOriginalT(): DEBUG: t_lookupOriginalT: searching on hash entry 42974
2016-12-01T16:16:21.463980+01:00 spce proxy[29831]: {1 1 CANCEL NGCP%incoming_peer_user_404%///1-30543 at 127.0.2.1} DEBUG: tm [t_lookup.c:409]: matching_3261(): DEBUG: RFC3261 transaction matched, tid=ed7a.bc0c3a7e71de87e4c6127fda1fd1b048.0
2016-12-01T16:16:21.463985+01:00 spce proxy[29831]: {1 1 CANCEL NGCP%incoming_peer_user_404%///1-30543 at 127.0.2.1} DEBUG: tm [t_lookup.c:798]: t_lookupOriginalT(): DEBUG: t_lookupOriginalT: canceled transaction found (0x7f85207f3680)! 
2016-12-01T16:16:21.463988+01:00 spce proxy[29831]: {1 1 CANCEL NGCP%incoming_peer_user_404%///1-30543 at 127.0.2.1} DEBUG: tm [t_lookup.c:801]: t_lookupOriginalT(): DEBUG: t_lookupOriginalT completed
2016-12-01T16:16:21.463992+01:00 spce proxy[29831]: {1 1 CANCEL NGCP%incoming_peer_user_404%///1-30543 at 127.0.2.1} DEBUG: tm [tm.c:992]: w_t_lookup_cancel(): lookup_original: t_lookupOriginalT returned: 0x7f85207f3680
2016-12-01T16:16:21.463997+01:00 spce proxy[29831]: {1 1 CANCEL NGCP%incoming_peer_user_404%///1-30543 at 127.0.2.1} NOTICE: <script>: Request leaving server via local route - R=sip:4311003 at spce.test:5060 ID=NGCP%incoming_peer_user_404%///1-30543 at 127.0.2.1 UA='<null>'
2016-12-01T16:16:21.464004+01:00 spce proxy[29831]: {1 1 CANCEL NGCP%incoming_peer_user_404%///1-30543 at 127.0.2.1} DEBUG: tm [t_lookup.c:1312]: t_newtran(): DEBUG: t_newtran: msg id=10 , global msg id=9 , T on entrance=0xffffffffffffffff
2016-12-01T16:16:21.464008+01:00 spce proxy[29831]: {1 1 CANCEL NGCP%incoming_peer_user_404%///1-30543 at 127.0.2.1} DEBUG: tm [t_lookup.c:466]: t_lookup_request(): t_lookup_request: start searching: hash=42974, isACK=0
2016-12-01T16:16:21.464012+01:00 spce proxy[29831]: {1 1 CANCEL NGCP%incoming_peer_user_404%///1-30543 at 127.0.2.1} DEBUG: tm [t_lookup.c:424]: matching_3261(): DEBUG: RFC3261 transaction matching failed
2016-12-01T16:16:21.464037+01:00 spce proxy[29831]: {1 1 CANCEL NGCP%incoming_peer_user_404%///1-30543 at 127.0.2.1} DEBUG: tm [t_lookup.c:648]: t_lookup_request(): DEBUG: t_lookup_request: no transaction found
2016-12-01T16:16:21.464045+01:00 spce proxy[29831]: {1 1 CANCEL NGCP%incoming_peer_user_404%///1-30543 at 127.0.2.1} DEBUG: tm [t_hooks.c:358]: run_reqin_callbacks_internal(): DBG: trans=0x7f85207efe58, callback type 1, id 0 entered
2016-12-01T16:16:21.464049+01:00 spce proxy[29831]: {1 1 CANCEL NGCP%incoming_peer_user_404%///1-30543 at 127.0.2.1} DEBUG: tm [t_hooks.c:358]: run_reqin_callbacks_internal(): DBG: trans=0x7f85207efe58, callback type 1, id 0 entered
2016-12-01T16:16:21.464062+01:00 spce proxy[29831]: {1 1 CANCEL NGCP%incoming_peer_user_404%///1-30543 at 127.0.2.1} DEBUG: <core> [md5utils.c:67]: MD5StringArray(): MD5 calculated: 62ec5b8389d2cbfd0b410a84d83a829a
2016-12-01T16:16:21.464068+01:00 spce proxy[29831]: {1 1 CANCEL NGCP%incoming_peer_user_404%///1-30543 at 127.0.2.1} DEBUG: <core> [forward.c:702]: update_sock_struct_from_via(): trying SRV lookup
2016-12-01T16:16:21.464083+01:00 spce proxy[29831]: {1 1 CANCEL NGCP%incoming_peer_user_404%///1-30543 at 127.0.2.1} DEBUG: tm [t_lookup.c:701]: t_lookupOriginalT(): DEBUG: t_lookupOriginalT: searching on hash entry 42974
2016-12-01T16:16:21.464087+01:00 spce proxy[29831]: {1 1 CANCEL NGCP%incoming_peer_user_404%///1-30543 at 127.0.2.1} DEBUG: tm [t_lookup.c:409]: matching_3261(): DEBUG: RFC3261 transaction matched, tid=ed7a.bc0c3a7e71de87e4c6127fda1fd1b048.0
2016-12-01T16:16:21.464091+01:00 spce proxy[29831]: {1 1 CANCEL NGCP%incoming_peer_user_404%///1-30543 at 127.0.2.1} DEBUG: tm [t_lookup.c:798]: t_lookupOriginalT(): DEBUG: t_lookupOriginalT: canceled transaction found (0x7f85207f3680)! 
2016-12-01T16:16:21.464094+01:00 spce proxy[29831]: {1 1 CANCEL NGCP%incoming_peer_user_404%///1-30543 at 127.0.2.1} DEBUG: tm [t_lookup.c:801]: t_lookupOriginalT(): DEBUG: t_lookupOriginalT completed
2016-12-01T16:16:21.464101+01:00 spce proxy[29831]: {1 1 CANCEL NGCP%incoming_peer_user_404%///1-30543 at 127.0.2.1} DEBUG: <core> [msg_translator.c:158]: check_via_address(): (127.0.0.1, 127.0.0.1, 0)
```

log from pid that received the 404
```
2016-12-01T16:16:21.460389+01:00 spce proxy[29830]: DEBUG: <core> [parser/msg_parser.c:605]: parse_msg(): SIP Reply  (status):
2016-12-01T16:16:21.460395+01:00 spce proxy[29830]: DEBUG: <core> [parser/msg_parser.c:607]: parse_msg():  version: <SIP/2.0>
2016-12-01T16:16:21.460399+01:00 spce proxy[29830]: DEBUG: <core> [parser/msg_parser.c:609]: parse_msg():  status:  <404>
2016-12-01T16:16:21.460402+01:00 spce proxy[29830]: DEBUG: <core> [parser/msg_parser.c:611]: parse_msg():  reason:  <Not Found>
2016-12-01T16:16:21.460407+01:00 spce proxy[29830]: DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param(): Found param type 232, <branch> = <z9hG4bKed7a.62ec5b8389d2cbfd0b410a84d83a829a.0>; state=16
2016-12-01T16:16:21.460421+01:00 spce proxy[29830]: DEBUG: <core> [parser/parse_via.c:2642]: parse_via(): end of header reached, state=5
2016-12-01T16:16:21.460426+01:00 spce proxy[29830]: DEBUG: <core> [parser/msg_parser.c:486]: parse_headers(): Via found, flags=2
2016-12-01T16:16:21.460430+01:00 spce proxy[29830]: DEBUG: <core> [parser/msg_parser.c:488]: parse_headers(): this is the first via
2016-12-01T16:16:21.460433+01:00 spce proxy[29830]: DEBUG: <core> [receive.c:178]: receive_msg(): After parse_msg...
2016-12-01T16:16:21.460442+01:00 spce proxy[29830]: DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param(): Found param type 232, <branch> = <z9hG4bKed7a.bc0c3a7e71de87e4c6127fda1fd1b048.0>; state=16
2016-12-01T16:16:21.460446+01:00 spce proxy[29830]: DEBUG: <core> [parser/parse_via.c:2642]: parse_via(): end of header reached, state=5
2016-12-01T16:16:21.460494+01:00 spce proxy[29830]: DEBUG: <core> [parser/msg_parser.c:486]: parse_headers(): Via found, flags=ffffffffffffffff
2016-12-01T16:16:21.460502+01:00 spce proxy[29830]: DEBUG: <core> [parser/msg_parser.c:499]: parse_headers(): this is the second via
2016-12-01T16:16:21.460508+01:00 spce proxy[29830]: DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param(): Found param type 235, <rport> = <51602>; state=6
2016-12-01T16:16:21.460513+01:00 spce proxy[29830]: DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param(): Found param type 232, <branch> = <z9hG4bK-30543-1-0>; state=16
2016-12-01T16:16:21.460516+01:00 spce proxy[29830]: DEBUG: <core> [parser/parse_via.c:2642]: parse_via(): end of header reached, state=5
2016-12-01T16:16:21.460519+01:00 spce proxy[29830]: DEBUG: <core> [parser/msg_parser.c:486]: parse_headers(): Via found, flags=ffffffffffffffff
2016-12-01T16:16:21.460526+01:00 spce proxy[29830]: DEBUG: <core> [parser/parse_addr_spec.c:171]: parse_to_param(): DEBUG: add_param: tag=6FD1D24D-58403EC500061CBC-B71CF700
2016-12-01T16:16:21.460529+01:00 spce proxy[29830]: DEBUG: <core> [parser/parse_addr_spec.c:871]: parse_addr_spec(): end of header reached, state=29
2016-12-01T16:16:21.460534+01:00 spce proxy[29830]: DEBUG: <core> [parser/msg_parser.c:171]: get_hdr_field(): <To> [64]; uri=[sip:4311003 at spce.test]
2016-12-01T16:16:21.460537+01:00 spce proxy[29830]: DEBUG: <core> [parser/msg_parser.c:172]: get_hdr_field(): to body [<sip:4311003 at spce.test>]
2016-12-01T16:16:21.460543+01:00 spce proxy[29830]: DEBUG: <core> [parser/msg_parser.c:152]: get_hdr_field(): cseq <CSeq>: <1> <INVITE>
2016-12-01T16:16:21.460587+01:00 spce proxy[29830]: DEBUG: <core> [parser/msg_parser.c:183]: get_hdr_field(): content_length=0
2016-12-01T16:16:21.460595+01:00 spce proxy[29830]: DEBUG: <core> [parser/msg_parser.c:89]: get_hdr_field(): found end of header
2016-12-01T16:16:21.460608+01:00 spce proxy[29830]: {2 1 INVITE NGCP%incoming_peer_user_404%///1-30543 at 127.0.2.1} DEBUG: tm [t_lookup.c:1011]: t_check_msg(): DEBUG: t_check_msg: msg id=9 global id=8 T start=0xffffffffffffffff
2016-12-01T16:16:21.460615+01:00 spce proxy[29830]: {2 1 INVITE NGCP%incoming_peer_user_404%///1-30543 at 127.0.2.1} DEBUG: tm [t_lookup.c:888]: t_reply_matching(): DEBUG: t_reply_matching: hash 42974 label 0 branch 0
2016-12-01T16:16:21.460621+01:00 spce proxy[29830]: {2 1 INVITE NGCP%incoming_peer_user_404%///1-30543 at 127.0.2.1} DEBUG: tm [t_lookup.c:943]: t_reply_matching(): DEBUG: t_reply_matching: reply matched (T=0x7f85207f3680)!
2016-12-01T16:16:21.460626+01:00 spce proxy[29830]: {2 1 INVITE NGCP%incoming_peer_user_404%///1-30543 at 127.0.2.1} DEBUG: tm [t_hooks.c:266]: run_trans_callbacks_internal(): DBG: trans=0x7f85207f3680, callback type 2, id 0 entered
2016-12-01T16:16:21.460633+01:00 spce proxy[29830]: {2 1 INVITE NGCP%incoming_peer_user_404%///1-30543 at 127.0.2.1} DEBUG: <core> [parser/parse_addr_spec.c:171]: parse_to_param(): DEBUG: add_param: tag=30543SIPpTag001
2016-12-01T16:16:21.460636+01:00 spce proxy[29830]: {2 1 INVITE NGCP%incoming_peer_user_404%///1-30543 at 127.0.2.1} DEBUG: <core> [parser/parse_addr_spec.c:871]: parse_addr_spec(): end of header reached, state=29
2016-12-01T16:16:21.460642+01:00 spce proxy[29830]: {2 1 INVITE NGCP%incoming_peer_user_404%///1-30543 at 127.0.2.1} DEBUG: tm [t_hooks.c:266]: run_trans_callbacks_internal(): DBG: trans=0x7f85207f3680, callback type 2, id 0 entered
2016-12-01T16:16:21.460648+01:00 spce proxy[29830]: {2 1 INVITE NGCP%incoming_peer_user_404%///1-30543 at 127.0.2.1} DEBUG: tm [t_hooks.c:266]: run_trans_callbacks_internal(): DBG: trans=0x7f85207f3680, callback type 2, id 0 entered
2016-12-01T16:16:21.460656+01:00 spce proxy[29830]: {2 1 INVITE NGCP%incoming_peer_user_404%///1-30543 at 127.0.2.1} DEBUG: tm [t_lookup.c:1080]: t_check_msg(): DEBUG: t_check_msg: msg id=9 global id=9 T end=0x7f85207f3680
2016-12-01T16:16:21.460703+01:00 spce proxy[29830]: {2 1 INVITE NGCP%incoming_peer_user_404%///1-30543 at 127.0.2.1} DEBUG: tm [t_reply.c:2161]: reply_received(): DEBUG: reply_received: org. status uas=0, uac[0]=100 local=0 is_invite=1)
2016-12-01T16:16:21.460792+01:00 spce proxy[29830]: {2 1 INVITE NGCP%incoming_peer_user_404%///1-30543 at 127.0.2.1} NOTICE: <script>: NAT-Reply - S=404 - Not Found M=INVITE IP=127.0.2.1:51602 (127.0.0.1:5080) ID=NGCP%incoming_peer_user_404%///1-30543 at 127.0.2.1 UA='<null>'
2016-12-01T16:16:21.460829+01:00 spce proxy[29830]: {2 1 INVITE NGCP%incoming_peer_user_404%///1-30543 at 127.0.2.1} DEBUG: tm [t_reply.c:1233]: t_should_relay_response(): ->>>>>>>>> T_code=0, new_code=404
[...]
2016-12-01T16:16:21.466199+01:00 spce proxy[29830]: {2 1 INVITE NGCP%incoming_peer_user_404%///1-30543 at 127.0.2.1} INFO: <script>: Adding P-NGCP-Callee-Info '<sip:4311003 at spce.test>;ip=127.0.0.1;port=5060;primary=4311003' - R=sip:4311003 at 127.1.0.2:50604 ID=NGCP%incoming_peer_user_404%///1-30543 at 127.0.2.1 UA='<null>'
2016-12-01T16:16:21.466386+01:00 spce proxy[29830]: {2 1 INVITE NGCP%incoming_peer_user_404%///1-30543 at 127.0.2.1} NOTICE: <script>: Appending P-D-URI 'sip:lb at 127.0.0.1;lr;socket=sip:127.0.0.1:5060' - R=sip:4311003 at 127.1.0.2:50604 ID=NGCP%incoming_peer_user_404%///1-30543 at 127.0.2.1 UA='<null>'
2016-12-01T16:16:21.466406+01:00 spce proxy[29830]: {2 1 INVITE NGCP%incoming_peer_user_404%///1-30543 at 127.0.2.1} NOTICE: <script>: Forcing request via B2BUA 'sip:127.0.0.1:5080' - R=sip:4311003 at 127.1.0.2:50604 ID=NGCP%incoming_peer_user_404%///1-30543 at 127.0.2.1 UA='<null>'
2016-12-01T16:16:21.466414+01:00 spce proxy[29830]: {2 1 INVITE NGCP%incoming_peer_user_404%///1-30543 at 127.0.2.1} NOTICE: <script>: Request leaving server, D-URI='sip:127.0.0.1:5080' - R=sip:4311003 at 127.1.0.2:50604 ID=NGCP%incoming_peer_user_404%///1-30543 at 127.0.2.1 UA='<null>'
2016-12-01T16:16:21.466436+01:00 spce proxy[29830]: {2 1 INVITE NGCP%incoming_peer_user_404%///1-30543 at 127.0.2.1} DEBUG: <core> [msg_translator.c:423]: clen_builder(): content-length: 168 (168)
2016-12-01T16:16:21.466447+01:00 spce proxy[29830]: {2 1 INVITE NGCP%incoming_peer_user_404%///1-30543 at 127.0.2.1} DEBUG: <core> [msg_translator.c:158]: check_via_address(): (127.0.0.1, 127.0.0.1, 0)
2016-12-01T16:16:21.466501+01:00 spce proxy[29830]: {2 1 INVITE NGCP%incoming_peer_user_404%///1-30543 at 127.0.2.1} DEBUG: tm [t_fwd.c:1766]: t_forward_nonack(): t_forward_non_ack: no forwarding on a canceled transaction
2016-12-01T16:16:21.466510+01:00 spce proxy[29830]: {2 1 INVITE NGCP%incoming_peer_user_404%///1-30543 at 127.0.2.1} ERROR: tm [tm.c:1415]: _w_t_relay_to(): ERROR: w_t_relay_to: t_relay_to failed
2016-12-01T16:16:21.466522+01:00 spce proxy[29830]: {2 1 INVITE NGCP%incoming_peer_user_404%///1-30543 at 127.0.2.1} DEBUG: <core> [forward.c:702]: update_sock_struct_from_via(): trying SRV lookup
2016-12-01T16:16:21.466529+01:00 spce proxy[29830]: {2 1 INVITE NGCP%incoming_peer_user_404%///1-30543 at 127.0.2.1} DEBUG: <core> [msg_translator.c:158]: check_via_address(): (127.0.0.1, 127.0.0.1, 0)
2016-12-01T16:16:21.467761+01:00 spce proxy[29830]: {2 1 INVITE NGCP%incoming_peer_user_404%///1-30543 at 127.0.2.1} ERROR: sl [sl_funcs.c:363]: sl_reply_error(): ERROR: sl_reply_error used: transaction canceled (487/SL)
2016-12-01T16:16:21.467789+01:00 spce proxy[29830]: {2 1 INVITE NGCP%incoming_peer_user_404%///1-30543 at 127.0.2.1} INFO: <script>: No mediaproxy engaged for this branch, no need for stopping  - R=sip:testuser1003 at 127.1.0.2:50604 ID=NGCP%incoming_peer_user_404%///1-30543 at 127.0.2.1 UA='<null>'
2016-12-01T16:16:21.467807+01:00 spce proxy[29830]: {2 1 INVITE NGCP%incoming_peer_user_404%///1-30543 at 127.0.2.1} DEBUG: <core> [parser/sdp/sdp.c:794]: free_sdp(): _sdp = 0x7f8531f9b2a0
2016-12-01T16:16:21.467812+01:00 spce proxy[29830]: {2 1 INVITE NGCP%incoming_peer_user_404%///1-30543 at 127.0.2.1} DEBUG: <core> [parser/sdp/sdp.c:796]: free_sdp(): sdp = 0x7f8533fbd738
2016-12-01T16:16:21.467816+01:00 spce proxy[29830]: {2 1 INVITE NGCP%incoming_peer_user_404%///1-30543 at 127.0.2.1} DEBUG: <core> [parser/sdp/sdp.c:798]: free_sdp(): session = 0x7f8533f7cdf0
2016-12-01T16:16:21.467830+01:00 spce proxy[29830]: {2 1 INVITE NGCP%incoming_peer_user_404%///1-30543 at 127.0.2.1} DEBUG: tm [t_reply.c:1752]: relay_reply(): DEBUG: relay_reply: branch=0, save=0, relay=-1 icode=0
2016-12-01T16:16:21.467837+01:00 spce proxy[29830]: {2 1 INVITE NGCP%incoming_peer_user_404%///1-30543 at 127.0.2.1} DEBUG: <core> [usr_avp.c:631]: destroy_avp_list(): destroying list (nil)
2016-12-01T16:16:21.467841+01:00 spce proxy[29830]: {2 1 INVITE NGCP%incoming_peer_user_404%///1-30543 at 127.0.2.1} DEBUG: <core> [usr_avp.c:631]: destroy_avp_list(): destroying list (nil)
2016-12-01T16:16:21.467845+01:00 spce proxy[29830]: {2 1 INVITE NGCP%incoming_peer_user_404%///1-30543 at 127.0.2.1} DEBUG: <core> [usr_avp.c:631]: destroy_avp_list(): destroying list (nil)
2016-12-01T16:16:21.467850+01:00 spce proxy[29830]: {2 1 INVITE NGCP%incoming_peer_user_404%///1-30543 at 127.0.2.1} DEBUG: <core> [usr_avp.c:631]: destroy_avp_list(): destroying list (nil)
2016-12-01T16:16:21.467854+01:00 spce proxy[29830]: {2 1 INVITE NGCP%incoming_peer_user_404%///1-30543 at 127.0.2.1} DEBUG: <core> [usr_avp.c:631]: destroy_avp_list(): destroying list (nil)
2016-12-01T16:16:21.467994+01:00 spce proxy[29830]: {2 1 INVITE NGCP%incoming_peer_user_404%///1-30543 at 127.0.2.1} DEBUG: <core> [usr_avp.c:631]: destroy_avp_list(): destroying list (nil)
2016-12-01T16:16:21.468015+01:00 spce proxy[29830]: {2 1 INVITE NGCP%incoming_peer_user_404%///1-30543 at 127.0.2.1} DEBUG: <core> [xavp.c:446]: xavp_destroy_list(): destroying xavp list (nil)
2016-12-01T16:16:21.468030+01:00 spce proxy[29830]: {2 1 INVITE NGCP%incoming_peer_user_404%///1-30543 at 127.0.2.1} DEBUG: <core> [receive.c:322]: receive_msg(): cleaning up
2016-12-01T16:16:21.822718+01:00 spce proxy[29830]: INFO: <core> [main.c:810]: sig_usr(): signal 15 received
```
pcap txt
[872_2.txt](https://github.com/kamailio/kamailio/files/624999/872_2.txt)

full log
[kamailio.txt](https://github.com/kamailio/kamailio/files/624993/kamailio.txt)


-- 
You are receiving this because you are subscribed to this thread.
Reply to this email directly or view it on GitHub:
https://github.com/kamailio/kamailio/issues/872#issuecomment-264203248
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <http://lists.sip-router.org/pipermail/sr-dev/attachments/20161201/5e419e04/attachment-0001.html>