Sep 10 16:01:23 sp1 proxy[14142]: DEBUG: tm [h_table.c:129]: free_cell_helper(): freeing transaction 0x7f2f57249f38 from timer.c:654 Sep 10 16:01:23 sp1 proxy[14142]: DEBUG: tm [h_table.c:129]: free_cell_helper(): freeing transaction 0x7f2f5724b8d0 from timer.c:654 Sep 10 16:01:23 sp1 proxy[14142]: DEBUG: tm [h_table.c:129]: free_cell_helper(): freeing transaction 0x7f2f5724d1e8 from timer.c:654 Sep 10 16:01:23 sp1 proxy[14142]: DEBUG: tm [h_table.c:129]: free_cell_helper(): freeing transaction 0x7f2f5724eb00 from timer.c:654 Sep 10 16:01:23 sp1 proxy[14142]: DEBUG: tm [h_table.c:129]: free_cell_helper(): freeing transaction 0x7f2f57250418 from timer.c:654 Sep 10 16:01:24 sp1 proxy[14140]: DEBUG: [core/udp_server.c:491]: udp_rcv_loop(): received on udp socket: (106/100/1432) [[INVITE sip:43993003@10.50.3.130 SIP/2.0 0D 0A Record-Route: [core/parser/msg_parser.c:604]: parse_msg(): SIP Request: Sep 10 16:01:24 sp1 proxy[14140]: DEBUG: [core/parser/msg_parser.c:606]: parse_msg(): method: Sep 10 16:01:24 sp1 proxy[14140]: DEBUG: [core/parser/msg_parser.c:608]: parse_msg(): uri: Sep 10 16:01:24 sp1 proxy[14140]: DEBUG: [core/parser/msg_parser.c:610]: parse_msg(): version: Sep 10 16:01:24 sp1 proxy[14140]: DEBUG: [core/parser/parse_via.c:1303]: parse_via_param(): Found param type 232, = ; state=16 Sep 10 16:01:24 sp1 proxy[14140]: DEBUG: [core/parser/parse_via.c:2639]: parse_via(): end of header reached, state=5 Sep 10 16:01:24 sp1 proxy[14140]: DEBUG: [core/parser/msg_parser.c:492]: parse_headers(): Via found, flags=2 Sep 10 16:01:24 sp1 proxy[14140]: DEBUG: [core/parser/msg_parser.c:494]: parse_headers(): this is the first via Sep 10 16:01:24 sp1 proxy[14140]: DEBUG: [core/parser/parse_via.c:1303]: parse_via_param(): Found param type 234, = <10.50.3.126>; state=6 Sep 10 16:01:24 sp1 proxy[14140]: DEBUG: [core/parser/parse_via.c:1303]: parse_via_param(): Found param type 235, = <35901>; state=6 Sep 10 16:01:24 sp1 proxy[14140]: DEBUG: [core/parser/parse_via.c:1303]: parse_via_param(): Found param type 232, = ; state=16 Sep 10 16:01:24 sp1 proxy[14140]: DEBUG: [core/parser/parse_via.c:2639]: parse_via(): end of header reached, state=5 Sep 10 16:01:24 sp1 proxy[14140]: DEBUG: [core/parser/msg_parser.c:492]: parse_headers(): Via found, flags=78 Sep 10 16:01:24 sp1 proxy[14140]: DEBUG: [core/parser/msg_parser.c:505]: parse_headers(): this is the second via Sep 10 16:01:24 sp1 proxy[14140]: DEBUG: [core/parser/parse_addr_spec.c:864]: parse_addr_spec(): end of header reached, state=10 Sep 10 16:01:24 sp1 proxy[14140]: DEBUG: [core/parser/msg_parser.c:171]: get_hdr_field(): [28]; uri=[sip:43993003@10.50.3.130] Sep 10 16:01:24 sp1 proxy[14140]: DEBUG: [core/parser/msg_parser.c:172]: get_hdr_field(): to body [#015#012] Sep 10 16:01:24 sp1 proxy[14140]: DEBUG: [core/parser/msg_parser.c:152]: get_hdr_field(): cseq : <36> Sep 10 16:01:24 sp1 proxy[14140]: DEBUG: [core/receive.c:200]: receive_msg(): --- received sip message - request - call-id: [188856461@10.50.3.130] - cseq: [36 INVITE] Sep 10 16:01:24 sp1 proxy[14140]: DEBUG: [core/parser/msg_parser.c:183]: get_hdr_field(): content_length=502 Sep 10 16:01:24 sp1 proxy[14140]: DEBUG: [core/parser/msg_parser.c:89]: get_hdr_field(): found end of header Sep 10 16:01:24 sp1 proxy[14140]: {1 36 INVITE 188856461@10.50.3.130} DEBUG: [core/receive.c:248]: receive_msg(): preparing to run routing scripts... Sep 10 16:01:24 sp1 proxy[14140]: {1 36 INVITE 188856461@10.50.3.130} DEBUG: [core/parser/parse_addr_spec.c:185]: parse_to_param(): add param: tag=490754281 Sep 10 16:01:24 sp1 proxy[14140]: {1 36 INVITE 188856461@10.50.3.130} DEBUG: [core/parser/parse_addr_spec.c:864]: parse_addr_spec(): end of header reached, state=29 Sep 10 16:01:24 sp1 proxy[14140]: {1 36 INVITE 188856461@10.50.3.130} NOTICE: