<-- SIP read from 10.131.2.1:5060: INVITE sip:*2@10.131.0.1;user=phone SIP/2.0 Via: SIP/2.0/UDP 10.131.2.1;branch=z9hG4bKacXxvujhW Max-Forwards: 70 From: ;tag=1c2128430854 To: Call-ID: 473614247zELK@10.131.2.1 CSeq: 1 INVITE Contact: Supported: em,100rel,timer,replaces,path Allow: REGISTER,OPTIONS,INVITE,ACK,CANCEL,BYE,NOTIFY,PRACK,REFER,INFO,SUBSCRIBE,UPDATE User-Agent: Audiocodes-Sip-Gateway-MP-124 FXS/v.4.60A.008.006 Content-Type: application/sdp Content-Length: 242 v=0 o=AudiocodesGW 765645 210110 IN IP4 10.131.2.1 s=Phone-Call c=IN IP4 10.131.2.1 t=0 0 m=audio 6070 RTP/AVP 8 0 96 a=rtpmap:8 pcma/8000 a=rtpmap:0 pcmu/8000 a=rtpmap:96 telephone-event/8000 a=fmtp:96 0-15 a=ptime:20 a=sendrecv Oct 4 13:49:32 DEBUG[6042]: chan_sip.c:3174 parse_request: Header: INVITE sip:*2@10.131.0.1;user=phone SIP/2.0 (43) Oct 4 13:49:32 DEBUG[6042]: chan_sip.c:3174 parse_request: Header: Via: SIP/2.0/UDP 10.131.2.1;branch=z9hG4bKacXxvujhW (51) Oct 4 13:49:32 DEBUG[6042]: chan_sip.c:3174 parse_request: Header: Max-Forwards: 70 (16) Oct 4 13:49:32 DEBUG[6042]: chan_sip.c:3174 parse_request: Header: From: ;tag=1c2128430854 (46) Oct 4 13:49:32 DEBUG[6042]: chan_sip.c:3174 parse_request: Header: To: (34) Oct 4 13:49:32 DEBUG[6042]: chan_sip.c:3174 parse_request: Header: Call-ID: 473614247zELK@10.131.2.1 (33) Oct 4 13:49:32 DEBUG[6042]: chan_sip.c:3174 parse_request: Header: CSeq: 1 INVITE (14) Oct 4 13:49:32 DEBUG[6042]: chan_sip.c:3174 parse_request: Header: Contact: (32) Oct 4 13:49:32 DEBUG[6042]: chan_sip.c:3174 parse_request: Header: Supported: em,100rel,timer,replaces,path (40) Oct 4 13:49:32 DEBUG[6042]: chan_sip.c:3174 parse_request: Header: Allow: REGISTER,OPTIONS,INVITE,ACK,CANCEL,BYE,NOTIFY,PRACK,REFER,INFO,SUBSCRIBE,UPDATE (86) Oct 4 13:49:32 DEBUG[6042]: chan_sip.c:3174 parse_request: Header: User-Agent: Audiocodes-Sip-Gateway-MP-124 FXS/v.4.60A.008.006 (61) Oct 4 13:49:32 DEBUG[6042]: chan_sip.c:3174 parse_request: Header: Content-Type: application/sdp (29) Oct 4 13:49:32 DEBUG[6042]: chan_sip.c:3174 parse_request: Header: Content-Length: 242 (19) Oct 4 13:49:32 DEBUG[6042]: chan_sip.c:3174 parse_request: Header: (0) Oct 4 13:49:32 DEBUG[6042]: chan_sip.c:3203 parse_request: Line: v=0 (3) Oct 4 13:49:32 DEBUG[6042]: chan_sip.c:3203 parse_request: Line: o=AudiocodesGW 765645 210110 IN IP4 10.131.2.1 (46) Oct 4 13:49:32 DEBUG[6042]: chan_sip.c:3203 parse_request: Line: s=Phone-Call (12) Oct 4 13:49:32 DEBUG[6042]: chan_sip.c:3203 parse_request: Line: c=IN IP4 10.131.2.1 (19) Oct 4 13:49:32 DEBUG[6042]: chan_sip.c:3203 parse_request: Line: t=0 0 (5) Oct 4 13:49:32 DEBUG[6042]: chan_sip.c:3203 parse_request: Line: m=audio 6070 RTP/AVP 8 0 96 (27) Oct 4 13:49:32 DEBUG[6042]: chan_sip.c:3203 parse_request: Line: a=rtpmap:8 pcma/8000 (20) Oct 4 13:49:32 DEBUG[6042]: chan_sip.c:3203 parse_request: Line: a=rtpmap:0 pcmu/8000 (20) Oct 4 13:49:32 DEBUG[6042]: chan_sip.c:3203 parse_request: Line: a=rtpmap:96 telephone-event/8000 (32) Oct 4 13:49:32 DEBUG[6042]: chan_sip.c:3203 parse_request: Line: a=fmtp:96 0-15 (14) Oct 4 13:49:32 DEBUG[6042]: chan_sip.c:3203 parse_request: Line: a=ptime:20 (10) Oct 4 13:49:32 DEBUG[6042]: chan_sip.c:3203 parse_request: Line: a=sendrecv (10) --- (13 headers 12 lines)--- Oct 4 13:49:32 DEBUG[6042]: chan_sip.c:2978 sip_alloc: Allocating new SIP dialog for 473614247zELK@10.131.2.1 - INVITE (With RTP) Oct 4 13:49:32 DEBUG[6042]: chan_sip.c:9919 handle_request: **** Received INVITE (5) - Command in SIP INVITE Oct 4 13:49:32 DEBUG[6042]: chan_sip.c:892 parse_sip_options: Begin: parsing SIP "Supported: em,100rel,timer,replaces,path" Oct 4 13:49:32 DEBUG[6042]: chan_sip.c:904 parse_sip_options: Found SIP option: -em- Oct 4 13:49:32 DEBUG[6042]: chan_sip.c:915 parse_sip_options: Found no match for SIP option: em (Please file bug report!) Oct 4 13:49:32 DEBUG[6042]: chan_sip.c:904 parse_sip_options: Found SIP option: -100rel- Oct 4 13:49:32 DEBUG[6042]: chan_sip.c:910 parse_sip_options: Matched SIP option: 100rel Oct 4 13:49:32 DEBUG[6042]: chan_sip.c:904 parse_sip_options: Found SIP option: -timer- Oct 4 13:49:32 DEBUG[6042]: chan_sip.c:910 parse_sip_options: Matched SIP option: timer Oct 4 13:49:32 DEBUG[6042]: chan_sip.c:904 parse_sip_options: Found SIP option: -replaces- Oct 4 13:49:32 DEBUG[6042]: chan_sip.c:910 parse_sip_options: Matched SIP option: replaces Oct 4 13:49:32 DEBUG[6042]: chan_sip.c:904 parse_sip_options: Found SIP option: -path- Oct 4 13:49:32 DEBUG[6042]: chan_sip.c:910 parse_sip_options: Matched SIP option: path Oct 4 13:49:32 DEBUG[6042]: chan_sip.c:921 parse_sip_options: * SIP extension value: 39 for call 473614247zELK@10.131.2.1 Using INVITE request as basis request - 473614247zELK@10.131.2.1 Sending to 10.131.2.1 : 5060 (non-NAT) Oct 4 13:49:32 DEBUG[6042]: chan_sip.c:6350 check_user_full: Setting NAT on RTP to 0 Reliably Transmitting (no NAT) to 10.131.2.1:5060: SIP/2.0 407 Proxy Authentication Required Via: SIP/2.0/UDP 10.131.2.1;branch=z9hG4bKacXxvujhW From: ;tag=1c2128430854 To: ;tag=as077c858c Call-ID: 473614247zELK@10.131.2.1 CSeq: 1 INVITE User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY Contact: Proxy-Authenticate: Digest realm="asterisk", nonce="31c42f33" Content-Length: 0 --- Oct 4 13:49:32 DEBUG[6042]: chan_sip.c:1179 __sip_reliable_xmit: *** SIP TIMER: Initalizing retransmit timer on packet: Id #1 Scheduling destruction of call '473614247zELK@10.131.2.1' in 15000 ms Found user '070001' localhost*CLI> <-- SIP read from 10.131.2.1:5060: ACK sip:*2@10.131.0.1;user=phone SIP/2.0 Via: SIP/2.0/UDP 10.131.2.1;branch=z9hG4bKacXxvujhW Max-Forwards: 70 From: ;tag=1c2128430854 To: ;tag=as077c858c Call-ID: 473614247zELK@10.131.2.1 CSeq: 1 ACK Contact: Supported: em,timer,replaces,path Allow: REGISTER,OPTIONS,INVITE,ACK,CANCEL,BYE,NOTIFY,PRACK,REFER,INFO,SUBSCRIBE,UPDATE User-Agent: Audiocodes-Sip-Gateway-MP-124 FXS/v.4.60A.008.006 Content-Length: 0 Oct 4 13:49:32 DEBUG[6042]: chan_sip.c:3174 parse_request: Header: ACK sip:*2@10.131.0.1;user=phone SIP/2.0 (40) Oct 4 13:49:32 DEBUG[6042]: chan_sip.c:3174 parse_request: Header: Via: SIP/2.0/UDP 10.131.2.1;branch=z9hG4bKacXxvujhW (51) Oct 4 13:49:32 DEBUG[6042]: chan_sip.c:3174 parse_request: Header: Max-Forwards: 70 (16) Oct 4 13:49:32 DEBUG[6042]: chan_sip.c:3174 parse_request: Header: From: ;tag=1c2128430854 (46) Oct 4 13:49:32 DEBUG[6042]: chan_sip.c:3174 parse_request: Header: To: ;tag=as077c858c (49) Oct 4 13:49:32 DEBUG[6042]: chan_sip.c:3174 parse_request: Header: Call-ID: 473614247zELK@10.131.2.1 (33) Oct 4 13:49:32 DEBUG[6042]: chan_sip.c:3174 parse_request: Header: CSeq: 1 ACK (11) Oct 4 13:49:32 DEBUG[6042]: chan_sip.c:3174 parse_request: Header: Contact: (32) Oct 4 13:49:32 DEBUG[6042]: chan_sip.c:3174 parse_request: Header: Supported: em,timer,replaces,path (33) Oct 4 13:49:32 DEBUG[6042]: chan_sip.c:3174 parse_request: Header: Allow: REGISTER,OPTIONS,INVITE,ACK,CANCEL,BYE,NOTIFY,PRACK,REFER,INFO,SUBSCRIBE,UPDATE (86) Oct 4 13:49:32 DEBUG[6042]: chan_sip.c:3174 parse_request: Header: User-Agent: Audiocodes-Sip-Gateway-MP-124 FXS/v.4.60A.008.006 (61) Oct 4 13:49:32 DEBUG[6042]: chan_sip.c:3174 parse_request: Header: Content-Length: 0 (17) Oct 4 13:49:32 DEBUG[6042]: chan_sip.c:3174 parse_request: Header: (0) --- (12 headers 0 lines)--- Oct 4 13:49:32 DEBUG[6042]: chan_sip.c:9919 handle_request: **** Received ACK (6) - Command in SIP ACK Oct 4 13:49:32 DEBUG[6042]: chan_sip.c:1263 __sip_ack: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #1 Oct 4 13:49:32 DEBUG[6042]: chan_sip.c:1274 __sip_ack: Stopping retransmission on '473614247zELK@10.131.2.1' of Response 1: Match Found localhost*CLI> <-- SIP read from 10.131.2.1:5060: INVITE sip:*2@10.131.0.1;user=phone SIP/2.0 Via: SIP/2.0/UDP 10.131.2.1;branch=z9hG4bKacjBqodcR Max-Forwards: 70 From: ;tag=1c2128430854 To: Call-ID: 473614247zELK@10.131.2.1 CSeq: 2 INVITE Proxy-Authorization: Digest username="070001",realm="asterisk",nonce="31c42f33" ",uri="sip:*2@10.131.0.1",algorithm=MD5,response="6523d00bb205257c95d6e8c1612e1309" Contact: Supported: em,100rel,timer,replaces,path Allow: REGISTER,OPTIONS,INVITE,ACK,CANCEL,BYE,NOTIFY,PRACK,REFER,INFO,SUBSCRIBE,UPDATE User-Agent: Audiocodes-Sip-Gateway-MP-124 FXS/v.4.60A.008.006 Content-Type: application/sdp Content-Length: 242 v=0 o=AudiocodesGW 765645 210110 IN IP4 10.131.2.1 s=Phone-Call c=IN IP4 10.131.2.1 t=0 0 m=audio 6070 RTP/AVP 8 0 96 a=rtpmap:8 pcma/8000 a=rtpmap:0 pcmu/8000 a=rtpmap:96 telephone-event/8000 a=fmtp:96 0-15 a=ptime:20 a=sendrecv Oct 4 13:49:32 DEBUG[6042]: chan_sip.c:3174 parse_request: Header: INVITE sip:*2@10.131.0.1;user=phone SIP/2.0 (43) Oct 4 13:49:32 DEBUG[6042]: chan_sip.c:3174 parse_request: Header: Via: SIP/2.0/UDP 10.131.2.1;branch=z9hG4bKacjBqodcR (51) Oct 4 13:49:32 DEBUG[6042]: chan_sip.c:3174 parse_request: Header: Max-Forwards: 70 (16) Oct 4 13:49:32 DEBUG[6042]: chan_sip.c:3174 parse_request: Header: From: ;tag=1c2128430854 (46) Oct 4 13:49:32 DEBUG[6042]: chan_sip.c:3174 parse_request: Header: To: (34) Oct 4 13:49:32 DEBUG[6042]: chan_sip.c:3174 parse_request: Header: Call-ID: 473614247zELK@10.131.2.1 (33) Oct 4 13:49:32 DEBUG[6042]: chan_sip.c:3174 parse_request: Header: CSeq: 2 INVITE (14) Oct 4 13:49:32 DEBUG[6042]: chan_sip.c:3174 parse_request: Header: Proxy-Authorization: Digest username="070001",realm="asterisk",nonce="31c42f33" ",uri="sip:*2@10.131.0.1",algorithm=MD5,response="6523d00bb205257c95d6e8c1612e1309" (163) Oct 4 13:49:32 DEBUG[6042]: chan_sip.c:3174 parse_request: Header: Contact: (32) Oct 4 13:49:32 DEBUG[6042]: chan_sip.c:3174 parse_request: Header: Supported: em,100rel,timer,replaces,path (40) Oct 4 13:49:32 DEBUG[6042]: chan_sip.c:3174 parse_request: Header: Allow: REGISTER,OPTIONS,INVITE,ACK,CANCEL,BYE,NOTIFY,PRACK,REFER,INFO,SUBSCRIBE,UPDATE (86) Oct 4 13:49:32 DEBUG[6042]: chan_sip.c:3174 parse_request: Header: User-Agent: Audiocodes-Sip-Gateway-MP-124 FXS/v.4.60A.008.006 (61) Oct 4 13:49:32 DEBUG[6042]: chan_sip.c:3174 parse_request: Header: Content-Type: application/sdp (29) Oct 4 13:49:32 DEBUG[6042]: chan_sip.c:3174 parse_request: Header: Content-Length: 242 (19) Oct 4 13:49:32 DEBUG[6042]: chan_sip.c:3174 parse_request: Header: (0) Oct 4 13:49:32 DEBUG[6042]: chan_sip.c:3203 parse_request: Line: v=0 (3) Oct 4 13:49:32 DEBUG[6042]: chan_sip.c:3203 parse_request: Line: o=AudiocodesGW 765645 210110 IN IP4 10.131.2.1 (46) Oct 4 13:49:32 DEBUG[6042]: chan_sip.c:3203 parse_request: Line: s=Phone-Call (12) Oct 4 13:49:32 DEBUG[6042]: chan_sip.c:3203 parse_request: Line: c=IN IP4 10.131.2.1 (19) Oct 4 13:49:32 DEBUG[6042]: chan_sip.c:3203 parse_request: Line: t=0 0 (5) Oct 4 13:49:32 DEBUG[6042]: chan_sip.c:3203 parse_request: Line: m=audio 6070 RTP/AVP 8 0 96 (27) Oct 4 13:49:32 DEBUG[6042]: chan_sip.c:3203 parse_request: Line: a=rtpmap:8 pcma/8000 (20) Oct 4 13:49:32 DEBUG[6042]: chan_sip.c:3203 parse_request: Line: a=rtpmap:0 pcmu/8000 (20) Oct 4 13:49:32 DEBUG[6042]: chan_sip.c:3203 parse_request: Line: a=rtpmap:96 telephone-event/8000 (32) Oct 4 13:49:32 DEBUG[6042]: chan_sip.c:3203 parse_request: Line: a=fmtp:96 0-15 (14) Oct 4 13:49:32 DEBUG[6042]: chan_sip.c:3203 parse_request: Line: a=ptime:20 (10) Oct 4 13:49:32 DEBUG[6042]: chan_sip.c:3203 parse_request: Line: a=sendrecv (10) --- (14 headers 12 lines)--- Oct 4 13:49:32 DEBUG[6042]: chan_sip.c:9919 handle_request: **** Received INVITE (5) - Command in SIP INVITE Using INVITE request as basis request - 473614247zELK@10.131.2.1 Sending to 10.131.2.1 : 5060 (non-NAT) Oct 4 13:49:32 DEBUG[6042]: chan_sip.c:6350 check_user_full: Setting NAT on RTP to 0 Oct 4 13:49:32 NOTICE[6042]: chan_sip.c:5710 check_auth: stale nonce received from '' Reliably Transmitting (no NAT) to 10.131.2.1:5060: SIP/2.0 407 Proxy Authentication Required Via: SIP/2.0/UDP 10.131.2.1;branch=z9hG4bKacjBqodcR From: ;tag=1c2128430854 To: ;tag=as077c858c Call-ID: 473614247zELK@10.131.2.1 CSeq: 2 INVITE User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY Contact: Proxy-Authenticate: Digest realm="asterisk", nonce="5654a579" Content-Length: 0 --- Oct 4 13:49:32 DEBUG[6042]: chan_sip.c:1179 __sip_reliable_xmit: *** SIP TIMER: Initalizing retransmit timer on packet: Id #3 Scheduling destruction of call '473614247zELK@10.131.2.1' in 15000 ms Found user '070001' localhost*CLI> <-- SIP read from 10.131.2.1:5060: ACK sip:*2@10.131.0.1;user=phone SIP/2.0 Via: SIP/2.0/UDP 10.131.2.1;branch=z9hG4bKacjBqodcR Max-Forwards: 70 From: ;tag=1c2128430854 To: ;tag=as077c858c Call-ID: 473614247zELK@10.131.2.1 CSeq: 2 ACK Contact: Supported: em,timer,replaces,path Allow: REGISTER,OPTIONS,INVITE,ACK,CANCEL,BYE,NOTIFY,PRACK,REFER,INFO,SUBSCRIBE,UPDATE User-Agent: Audiocodes-Sip-Gateway-MP-124 FXS/v.4.60A.008.006 Content-Length: 0 Oct 4 13:49:32 DEBUG[6042]: chan_sip.c:3174 parse_request: Header: ACK sip:*2@10.131.0.1;user=phone SIP/2.0 (40) Oct 4 13:49:32 DEBUG[6042]: chan_sip.c:3174 parse_request: Header: Via: SIP/2.0/UDP 10.131.2.1;branch=z9hG4bKacjBqodcR (51) Oct 4 13:49:32 DEBUG[6042]: chan_sip.c:3174 parse_request: Header: Max-Forwards: 70 (16) Oct 4 13:49:32 DEBUG[6042]: chan_sip.c:3174 parse_request: Header: From: ;tag=1c2128430854 (46) Oct 4 13:49:32 DEBUG[6042]: chan_sip.c:3174 parse_request: Header: To: ;tag=as077c858c (49) Oct 4 13:49:32 DEBUG[6042]: chan_sip.c:3174 parse_request: Header: Call-ID: 473614247zELK@10.131.2.1 (33) Oct 4 13:49:32 DEBUG[6042]: chan_sip.c:3174 parse_request: Header: CSeq: 2 ACK (11) Oct 4 13:49:32 DEBUG[6042]: chan_sip.c:3174 parse_request: Header: Contact: (32) Oct 4 13:49:32 DEBUG[6042]: chan_sip.c:3174 parse_request: Header: Supported: em,timer,replaces,path (33) Oct 4 13:49:32 DEBUG[6042]: chan_sip.c:3174 parse_request: Header: Allow: REGISTER,OPTIONS,INVITE,ACK,CANCEL,BYE,NOTIFY,PRACK,REFER,INFO,SUBSCRIBE,UPDATE (86) Oct 4 13:49:32 DEBUG[6042]: chan_sip.c:3174 parse_request: Header: User-Agent: Audiocodes-Sip-Gateway-MP-124 FXS/v.4.60A.008.006 (61) Oct 4 13:49:32 DEBUG[6042]: chan_sip.c:3174 parse_request: Header: Content-Length: 0 (17) Oct 4 13:49:32 DEBUG[6042]: chan_sip.c:3174 parse_request: Header: (0) --- (12 headers 0 lines)--- Oct 4 13:49:32 DEBUG[6042]: chan_sip.c:9919 handle_request: **** Received ACK (6) - Command in SIP ACK Oct 4 13:49:32 DEBUG[6042]: chan_sip.c:1263 __sip_ack: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #3 Oct 4 13:49:32 DEBUG[6042]: chan_sip.c:1274 __sip_ack: Stopping retransmission on '473614247zELK@10.131.2.1' of Response 2: Match Found localhost*CLI>