Jul 26 19:21:06 crestwood ./openser[27474]: tcpconn_new: new tcp connection: 10.0.1.41 
Jul 26 19:21:06 crestwood ./openser[27474]: tcpconn_new: on port 47197, type 2 
Jul 26 19:21:06 crestwood ./openser[27474]: tcpconn_add: hashes: 87, 9 
Jul 26 19:21:06 crestwood ./openser[27474]: tcp_main_loop: new connection: 0x403b6e10 64 
Jul 26 19:21:06 crestwood ./openser[27474]: send2child: to tcp child 0 35(27466), 0x403b6e10 
Jul 26 19:21:06 crestwood ./openser[27466]: received n=4 con=0x403b6e10, fd=49 
Jul 26 19:21:06 crestwood ./openser[27466]: tcp_read_req: content-length= 0 
Jul 26 19:21:06 crestwood ./openser[27466]: SIP Reply  (status): 
Jul 26 19:21:06 crestwood ./openser[27466]:  version: <SIP/2.0> 
Jul 26 19:21:06 crestwood ./openser[27466]:  status:  <180> 
Jul 26 19:21:06 crestwood ./openser[27466]:  reason:  <Ringing> 
Jul 26 19:21:06 crestwood ./openser[27466]: parse_headers: flags=1 
Jul 26 19:21:06 crestwood ./openser[27466]: Found param type 232, <branch> = <z9hG4bK-1-0>; state=16 
Jul 26 19:21:06 crestwood ./openser[27466]: end of header reached, state=5 
Jul 26 19:21:06 crestwood ./openser[27466]: parse_headers: Via found, flags=1 
Jul 26 19:21:06 crestwood ./openser[27466]: parse_headers: this is the first via 
Jul 26 19:21:06 crestwood ./openser[27466]: After parse_msg... 
Jul 26 19:21:06 crestwood ./openser[27466]: forward_reply: found module tm, passing reply to it 
Jul 26 19:21:06 crestwood ./openser[27466]: DEBUG: t_check: msg id=7 global id=6 T start=(nil) 
Jul 26 19:21:06 crestwood ./openser[27466]: parse_headers: flags=17 
Jul 26 19:21:06 crestwood ./openser[27466]: DEBUG: add_param: tag=1 
Jul 26 19:21:06 crestwood ./openser[27466]: end of header reached, state=29 
Jul 26 19:21:06 crestwood ./openser[27466]: DEBUG: get_hdr_field: <To> [40]; uri=[sip:service@10.0.1.41:5060]  
Jul 26 19:21:06 crestwood ./openser[27466]: DEBUG: to body [sut <sip:service@10.0.1.41:5060>] 
Jul 26 19:21:06 crestwood ./openser[27466]: get_hdr_field: cseq <CSeq>: <1> <INVITE> 
Jul 26 19:21:06 crestwood ./openser[27466]: parse_headers: flags=4 
Jul 26 19:21:06 crestwood ./openser[27466]: DEBUG: t_reply_matching: failure to match a transaction 
Jul 26 19:21:06 crestwood ./openser[27466]: DEBUG: t_check: msg id=7 global id=7 T end=(nil) 
Jul 26 19:21:06 crestwood ./openser[27466]: parse_headers: flags=2 
Jul 26 19:21:06 crestwood ./openser[27466]: DEBUG: get_hdr_body : content_length=0 
Jul 26 19:21:06 crestwood ./openser[27466]: found end of header 
Jul 26 19:21:06 crestwood ./openser[27466]: ERROR: forward_reply: no 2nd via found in reply 
Jul 26 19:21:06 crestwood ./openser[27466]: DEBUG:destroy_avp_list: destroying list (nil) 
Jul 26 19:21:06 crestwood ./openser[27466]: receive_msg: cleaning up 
Jul 26 19:21:06 crestwood ./openser[27466]: tcp_read_req: content-length= 130 
Jul 26 19:21:06 crestwood ./openser[27466]: SIP Reply  (status): 
Jul 26 19:21:06 crestwood ./openser[27466]:  version: <SIP/2.0> 
Jul 26 19:21:06 crestwood ./openser[27466]:  status:  <200> 
Jul 26 19:21:06 crestwood ./openser[27466]:  reason:  <OK> 
Jul 26 19:21:06 crestwood ./openser[27466]: parse_headers: flags=1 
Jul 26 19:21:06 crestwood ./openser[27466]: Found param type 232, <branch> = <z9hG4bK-1-0>; state=16 
Jul 26 19:21:06 crestwood ./openser[27466]: end of header reached, state=5 
Jul 26 19:21:06 crestwood ./openser[27466]: parse_headers: Via found, flags=1 
Jul 26 19:21:06 crestwood ./openser[27466]: parse_headers: this is the first via 
Jul 26 19:21:06 crestwood ./openser[27466]: After parse_msg... 
Jul 26 19:21:06 crestwood ./openser[27466]: forward_reply: found module tm, passing reply to it 
Jul 26 19:21:06 crestwood ./openser[27466]: DEBUG: t_check: msg id=8 global id=7 T start=(nil) 
Jul 26 19:21:06 crestwood ./openser[27466]: parse_headers: flags=17 
Jul 26 19:21:06 crestwood ./openser[27466]: DEBUG: add_param: tag=1 
Jul 26 19:21:06 crestwood ./openser[27466]: end of header reached, state=29 
Jul 26 19:21:06 crestwood ./openser[27466]: DEBUG: get_hdr_field: <To> [40]; uri=[sip:service@10.0.1.41:5060]  
Jul 26 19:21:06 crestwood ./openser[27466]: DEBUG: to body [sut <sip:service@10.0.1.41:5060>] 
Jul 26 19:21:06 crestwood ./openser[27466]: get_hdr_field: cseq <CSeq>: <1> <INVITE> 
Jul 26 19:21:06 crestwood ./openser[27466]: parse_headers: flags=4 
Jul 26 19:21:06 crestwood ./openser[27466]: DEBUG: t_reply_matching: failure to match a transaction 
Jul 26 19:21:06 crestwood ./openser[27466]: DEBUG: t_check: msg id=8 global id=8 T end=(nil) 
Jul 26 19:21:06 crestwood ./openser[27466]: parse_headers: flags=2 
Jul 26 19:21:06 crestwood ./openser[27466]: DEBUG: get_hdr_body : content_length=130 
Jul 26 19:21:06 crestwood ./openser[27466]: found end of header 
Jul 26 19:21:06 crestwood ./openser[27466]: ERROR: forward_reply: no 2nd via found in reply 
Jul 26 19:21:06 crestwood ./openser[27466]: DEBUG:destroy_avp_list: destroying list (nil) 
Jul 26 19:21:06 crestwood ./openser[27466]: receive_msg: cleaning up 
Jul 26 19:21:07 crestwood ./openser[27474]: tcpconn_new: new tcp connection: 10.0.1.41 
Jul 26 19:21:07 crestwood ./openser[27474]: tcpconn_new: on port 47198, type 2 
Jul 26 19:21:07 crestwood ./openser[27474]: tcpconn_add: hashes: 84, 10 
Jul 26 19:21:07 crestwood ./openser[27474]: tcp_main_loop: new connection: 0x403a6cf8 66 
Jul 26 19:21:07 crestwood ./openser[27474]: send2child: to tcp child 1 36(27467), 0x403a6cf8 
Jul 26 19:21:07 crestwood ./openser[27467]: received n=4 con=0x403a6cf8, fd=50 
Jul 26 19:21:07 crestwood ./openser[27467]: tcp_read_req: content-length= 0 
Jul 26 19:21:07 crestwood ./openser[27467]: SIP Reply  (status): 
Jul 26 19:21:07 crestwood ./openser[27467]:  version: <SIP/2.0> 
Jul 26 19:21:07 crestwood ./openser[27467]:  status:  <180> 
Jul 26 19:21:07 crestwood ./openser[27467]:  reason:  <Ringing> 
Jul 26 19:21:07 crestwood ./openser[27467]: parse_headers: flags=1 
Jul 26 19:21:07 crestwood ./openser[27467]: Found param type 232, <branch> = <z9hG4bK-2-0>; state=16 
Jul 26 19:21:07 crestwood ./openser[27467]: end of header reached, state=5 
Jul 26 19:21:07 crestwood ./openser[27467]: parse_headers: Via found, flags=1 
Jul 26 19:21:07 crestwood ./openser[27467]: parse_headers: this is the first via 
Jul 26 19:21:07 crestwood ./openser[27467]: After parse_msg... 
Jul 26 19:21:07 crestwood ./openser[27467]: forward_reply: found module tm, passing reply to it 
Jul 26 19:21:07 crestwood ./openser[27467]: DEBUG: t_check: msg id=7 global id=6 T start=(nil) 
Jul 26 19:21:07 crestwood ./openser[27467]: parse_headers: flags=17 
Jul 26 19:21:07 crestwood ./openser[27467]: DEBUG: add_param: tag=2 
Jul 26 19:21:07 crestwood ./openser[27467]: end of header reached, state=29 
Jul 26 19:21:07 crestwood ./openser[27467]: DEBUG: get_hdr_field: <To> [40]; uri=[sip:service@10.0.1.41:5060]  
Jul 26 19:21:07 crestwood ./openser[27467]: DEBUG: to body [sut <sip:service@10.0.1.41:5060>] 
Jul 26 19:21:07 crestwood ./openser[27467]: get_hdr_field: cseq <CSeq>: <1> <INVITE> 
Jul 26 19:21:07 crestwood ./openser[27467]: parse_headers: flags=4 
Jul 26 19:21:07 crestwood ./openser[27467]: DEBUG: t_reply_matching: failure to match a transaction 
Jul 26 19:21:07 crestwood ./openser[27467]: DEBUG: t_check: msg id=7 global id=7 T end=(nil) 
Jul 26 19:21:07 crestwood ./openser[27467]: parse_headers: flags=2 
Jul 26 19:21:07 crestwood ./openser[27467]: DEBUG: get_hdr_body : content_length=0 
Jul 26 19:21:07 crestwood ./openser[27467]: found end of header 
Jul 26 19:21:07 crestwood ./openser[27467]: ERROR: forward_reply: no 2nd via found in reply 
Jul 26 19:21:07 crestwood ./openser[27467]: DEBUG:destroy_avp_list: destroying list (nil) 
Jul 26 19:21:07 crestwood ./openser[27467]: receive_msg: cleaning up 
Jul 26 19:21:07 crestwood ./openser[27467]: tcp_read_req: content-length= 130 
Jul 26 19:21:07 crestwood ./openser[27467]: SIP Reply  (status): 
Jul 26 19:21:07 crestwood ./openser[27467]:  version: <SIP/2.0> 
Jul 26 19:21:07 crestwood ./openser[27467]:  status:  <200> 
Jul 26 19:21:07 crestwood ./openser[27467]:  reason:  <OK> 
Jul 26 19:21:07 crestwood ./openser[27467]: parse_headers: flags=1 
Jul 26 19:21:07 crestwood ./openser[27467]: Found param type 232, <branch> = <z9hG4bK-2-0>; state=16 
Jul 26 19:21:07 crestwood ./openser[27467]: end of header reached, state=5 
Jul 26 19:21:07 crestwood ./openser[27467]: parse_headers: Via found, flags=1 
Jul 26 19:21:07 crestwood ./openser[27467]: parse_headers: this is the first via 
Jul 26 19:21:07 crestwood ./openser[27467]: After parse_msg... 
Jul 26 19:21:07 crestwood ./openser[27467]: forward_reply: found module tm, passing reply to it 
Jul 26 19:21:07 crestwood ./openser[27467]: DEBUG: t_check: msg id=8 global id=7 T start=(nil) 
Jul 26 19:21:07 crestwood ./openser[27467]: parse_headers: flags=17 
Jul 26 19:21:07 crestwood ./openser[27467]: DEBUG: add_param: tag=2 
Jul 26 19:21:07 crestwood ./openser[27467]: end of header reached, state=29 
Jul 26 19:21:07 crestwood ./openser[27467]: DEBUG: get_hdr_field: <To> [40]; uri=[sip:service@10.0.1.41:5060]  
Jul 26 19:21:07 crestwood ./openser[27467]: DEBUG: to body [sut <sip:service@10.0.1.41:5060>] 
Jul 26 19:21:07 crestwood ./openser[27467]: get_hdr_field: cseq <CSeq>: <1> <INVITE> 
Jul 26 19:21:07 crestwood ./openser[27467]: parse_headers: flags=4 
Jul 26 19:21:07 crestwood ./openser[27467]: DEBUG: t_reply_matching: failure to match a transaction 
Jul 26 19:21:07 crestwood ./openser[27467]: DEBUG: t_check: msg id=8 global id=8 T end=(nil) 
Jul 26 19:21:07 crestwood ./openser[27467]: parse_headers: flags=2 
Jul 26 19:21:07 crestwood ./openser[27467]: DEBUG: get_hdr_body : content_length=130 
Jul 26 19:21:07 crestwood ./openser[27467]: found end of header 
Jul 26 19:21:07 crestwood ./openser[27467]: ERROR: forward_reply: no 2nd via found in reply 
Jul 26 19:21:07 crestwood ./openser[27467]: DEBUG:destroy_avp_list: destroying list (nil) 
Jul 26 19:21:07 crestwood ./openser[27467]: receive_msg: cleaning up 
Jul 26 19:21:08 crestwood ./openser[27474]: tcpconn_new: new tcp connection: 10.0.1.41 
Jul 26 19:21:08 crestwood ./openser[27474]: tcpconn_new: on port 47199, type 2 
Jul 26 19:21:08 crestwood ./openser[27474]: tcpconn_add: hashes: 85, 11 
Jul 26 19:21:08 crestwood ./openser[27474]: tcp_main_loop: new connection: 0x403c6f28 67 
Jul 26 19:21:08 crestwood ./openser[27474]: send2child: to tcp child 2 37(27468), 0x403c6f28 
Jul 26 19:21:08 crestwood ./openser[27468]: received n=4 con=0x403c6f28, fd=52 
Jul 26 19:21:08 crestwood ./openser[27468]: tcp_read_req: content-length= 0 
Jul 26 19:21:08 crestwood ./openser[27468]: SIP Reply  (status): 
Jul 26 19:21:08 crestwood ./openser[27468]:  version: <SIP/2.0> 
Jul 26 19:21:08 crestwood ./openser[27468]:  status:  <180> 
Jul 26 19:21:08 crestwood ./openser[27468]:  reason:  <Ringing> 
Jul 26 19:21:08 crestwood ./openser[27468]: parse_headers: flags=1 
Jul 26 19:21:08 crestwood ./openser[27468]: Found param type 232, <branch> = <z9hG4bK-3-0>; state=16 
Jul 26 19:21:08 crestwood ./openser[27468]: end of header reached, state=5 
Jul 26 19:21:08 crestwood ./openser[27468]: parse_headers: Via found, flags=1 
Jul 26 19:21:08 crestwood ./openser[27468]: parse_headers: this is the first via 
Jul 26 19:21:08 crestwood ./openser[27468]: After parse_msg... 
Jul 26 19:21:08 crestwood ./openser[27468]: forward_reply: found module tm, passing reply to it 
Jul 26 19:21:08 crestwood ./openser[27468]: DEBUG: t_check: msg id=5 global id=4 T start=(nil) 
Jul 26 19:21:08 crestwood ./openser[27468]: parse_headers: flags=17 
Jul 26 19:21:08 crestwood ./openser[27468]: DEBUG: add_param: tag=3 
Jul 26 19:21:08 crestwood ./openser[27468]: end of header reached, state=29 
Jul 26 19:21:08 crestwood ./openser[27468]: DEBUG: get_hdr_field: <To> [40]; uri=[sip:service@10.0.1.41:5060]  
Jul 26 19:21:08 crestwood ./openser[27468]: DEBUG: to body [sut <sip:service@10.0.1.41:5060>] 
Jul 26 19:21:08 crestwood ./openser[27468]: get_hdr_field: cseq <CSeq>: <1> <INVITE> 
Jul 26 19:21:08 crestwood ./openser[27468]: parse_headers: flags=4 
Jul 26 19:21:08 crestwood ./openser[27468]: DEBUG: t_reply_matching: failure to match a transaction 
Jul 26 19:21:08 crestwood ./openser[27468]: DEBUG: t_check: msg id=5 global id=5 T end=(nil) 
Jul 26 19:21:08 crestwood ./openser[27468]: parse_headers: flags=2 
Jul 26 19:21:08 crestwood ./openser[27468]: DEBUG: get_hdr_body : content_length=0 
Jul 26 19:21:08 crestwood ./openser[27468]: found end of header 
Jul 26 19:21:08 crestwood ./openser[27468]: ERROR: forward_reply: no 2nd via found in reply 
Jul 26 19:21:08 crestwood ./openser[27468]: DEBUG:destroy_avp_list: destroying list (nil) 
Jul 26 19:21:08 crestwood ./openser[27468]: receive_msg: cleaning up 
Jul 26 19:21:08 crestwood ./openser[27468]: tcp_read_req: content-length= 130 
Jul 26 19:21:08 crestwood ./openser[27468]: SIP Reply  (status): 
Jul 26 19:21:08 crestwood ./openser[27468]:  version: <SIP/2.0> 
Jul 26 19:21:08 crestwood ./openser[27468]:  status:  <200> 
Jul 26 19:21:08 crestwood ./openser[27468]:  reason:  <OK> 
Jul 26 19:21:08 crestwood ./openser[27468]: parse_headers: flags=1 
Jul 26 19:21:08 crestwood ./openser[27468]: Found param type 232, <branch> = <z9hG4bK-3-0>; state=16 
Jul 26 19:21:08 crestwood ./openser[27468]: end of header reached, state=5 
Jul 26 19:21:08 crestwood ./openser[27468]: parse_headers: Via found, flags=1 
Jul 26 19:21:08 crestwood ./openser[27468]: parse_headers: this is the first via 
Jul 26 19:21:08 crestwood ./openser[27468]: After parse_msg... 
Jul 26 19:21:08 crestwood ./openser[27468]: forward_reply: found module tm, passing reply to it 
Jul 26 19:21:08 crestwood ./openser[27468]: DEBUG: t_check: msg id=6 global id=5 T start=(nil) 
Jul 26 19:21:08 crestwood ./openser[27468]: parse_headers: flags=17 
Jul 26 19:21:08 crestwood ./openser[27468]: DEBUG: add_param: tag=3 
Jul 26 19:21:08 crestwood ./openser[27468]: end of header reached, state=29 
Jul 26 19:21:08 crestwood ./openser[27468]: DEBUG: get_hdr_field: <To> [40]; uri=[sip:service@10.0.1.41:5060]  
Jul 26 19:21:08 crestwood ./openser[27468]: DEBUG: to body [sut <sip:service@10.0.1.41:5060>] 
Jul 26 19:21:08 crestwood ./openser[27468]: get_hdr_field: cseq <CSeq>: <1> <INVITE> 
Jul 26 19:21:08 crestwood ./openser[27468]: parse_headers: flags=4 
Jul 26 19:21:08 crestwood ./openser[27468]: DEBUG: t_reply_matching: failure to match a transaction 
Jul 26 19:21:08 crestwood ./openser[27468]: DEBUG: t_check: msg id=6 global id=6 T end=(nil) 
Jul 26 19:21:08 crestwood ./openser[27468]: parse_headers: flags=2 
Jul 26 19:21:08 crestwood ./openser[27468]: DEBUG: get_hdr_body : content_length=130 
Jul 26 19:21:08 crestwood ./openser[27468]: found end of header 
Jul 26 19:21:08 crestwood ./openser[27468]: ERROR: forward_reply: no 2nd via found in reply 
Jul 26 19:21:08 crestwood ./openser[27468]: DEBUG:destroy_avp_list: destroying list (nil) 
Jul 26 19:21:08 crestwood ./openser[27468]: receive_msg: cleaning up 
Jul 26 19:21:10 crestwood ./openser[27466]: tcp_read: EOF on 0x403b6e10, FD 49 
Jul 26 19:21:10 crestwood ./openser[27466]: tcp_read_req: EOF 
Jul 26 19:21:10 crestwood ./openser[27466]: releasing con 0x403b6e10, state -1, fd=49, id=9 
Jul 26 19:21:10 crestwood ./openser[27466]:  extra_data (nil) 
Jul 26 19:21:10 crestwood ./openser[27467]: tcp_read: EOF on 0x403a6cf8, FD 50 
Jul 26 19:21:10 crestwood ./openser[27467]: tcp_read_req: EOF 
Jul 26 19:21:10 crestwood ./openser[27467]: releasing con 0x403a6cf8, state -1, fd=50, id=10 
Jul 26 19:21:10 crestwood ./openser[27467]:  extra_data (nil) 
Jul 26 19:21:10 crestwood ./openser[27468]: tcp_read: EOF on 0x403c6f28, FD 52 
Jul 26 19:21:10 crestwood ./openser[27468]: tcp_read_req: EOF 
Jul 26 19:21:10 crestwood ./openser[27468]: releasing con 0x403c6f28, state -1, fd=52, id=11 
Jul 26 19:21:10 crestwood ./openser[27474]: tcp_main_loop: reader response= 403b6e10, -1 from 0  
Jul 26 19:21:10 crestwood ./openser[27474]: tcp_main_loop: destroying connection 
Jul 26 19:21:10 crestwood ./openser[27474]: tcp_main_loop: reader response= 403a6cf8, -1 from 1  
Jul 26 19:21:10 crestwood ./openser[27474]: tcp_main_loop: destroying connection 
Jul 26 19:21:10 crestwood ./openser[27468]:  extra_data (nil) 
Jul 26 19:21:10 crestwood ./openser[27474]: tcp_main_loop: reader response= 403c6f28, -1 from 2  
Jul 26 19:21:10 crestwood ./openser[27474]: tcp_main_loop: destroying connection