<-- SIP read from 10.0.0.10:5060: INVITE sip:s@10.0.1.9 SIP/2.0 Via: SIP/2.0/UDP 10.0.0.10:5060;branch=z9hG4bK4c3bd523;rport From: "Hasco5" ;tag=as73051ef9 To: Contact: Call-ID: 36072d60087520850b53504c01c8fd70@10.0.0.10 CSeq: 102 INVITE User-Agent: LAM PBX Max-Forwards: 70 Remote-Party-ID: "Hasco5" ;privacy=off;screen=no Date: Wed, 27 Sep 2006 21:54:34 GMT Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Content-Type: application/sdp Content-Length: 243 v=0 o=root 23900 23900 IN IP4 10.0.0.10 s=session c=IN IP4 10.0.0.10 t=0 0 m=audio 18388 RTP/AVP 18 101 a=rtpmap:18 G729/8000 a=fmtp:18 annexb=no a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=silenceSupp:off - - - - Sep 27 23:54:27 DEBUG[16507]: chan_sip.c:3382 parse_request: Header 0: INVITE sip:s@10.0.1.9 SIP/2.0 (32) Sep 27 23:54:27 DEBUG[16507]: chan_sip.c:3382 parse_request: Header 1: Via: SIP/2.0/UDP 10.0.0.10:5060;branch=z9hG4bK4c3bd523;rport (65) Sep 27 23:54:27 DEBUG[16507]: chan_sip.c:3382 parse_request: Header 2: From: "Hasco5" ;tag=as73051ef9 (54) Sep 27 23:54:27 DEBUG[16507]: chan_sip.c:3382 parse_request: Header 3: To: (23) Sep 27 23:54:27 DEBUG[16507]: chan_sip.c:3382 parse_request: Header 4: Contact: (33) Sep 27 23:54:27 DEBUG[16507]: chan_sip.c:3382 parse_request: Header 5: Call-ID: 36072d60087520850b53504c01c8fd70@10.0.0.10 (56) Sep 27 23:54:27 DEBUG[16507]: chan_sip.c:3382 parse_request: Header 6: CSeq: 102 INVITE (16) Sep 27 23:54:27 DEBUG[16507]: chan_sip.c:3382 parse_request: Header 7: User-Agent: LAM PBX (19) Sep 27 23:54:27 DEBUG[16507]: chan_sip.c:3382 parse_request: Header 8: Max-Forwards: 70 (16) Sep 27 23:54:27 DEBUG[16507]: chan_sip.c:3382 parse_request: Header 9: Remote-Party-ID: "Hasco5" ;privacy=off;screen=no (72) Sep 27 23:54:27 DEBUG[16507]: chan_sip.c:3382 parse_request: Header 10: Date: Wed, 27 Sep 2006 21:54:34 GMT (35) Sep 27 23:54:27 DEBUG[16507]: chan_sip.c:3382 parse_request: Header 11: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY (66) Sep 27 23:54:27 DEBUG[16507]: chan_sip.c:3382 parse_request: Header 12: Content-Type: application/sdp (29) Sep 27 23:54:27 DEBUG[16507]: chan_sip.c:3382 parse_request: Header 13: Content-Length: 243 (19) Sep 27 23:54:27 DEBUG[16507]: chan_sip.c:3382 parse_request: Header 14: (0) Sep 27 23:54:27 DEBUG[16507]: chan_sip.c:3414 parse_request: Line: v=0 (3) Sep 27 23:54:27 DEBUG[16507]: chan_sip.c:3414 parse_request: Line: o=root 23900 23900 IN IP4 10.0.0.10 (40) Sep 27 23:54:27 DEBUG[16507]: chan_sip.c:3414 parse_request: Line: s=session (9) Sep 27 23:54:27 DEBUG[16507]: chan_sip.c:3414 parse_request: Line: c=IN IP4 10.0.0.10 (23) Sep 27 23:54:27 DEBUG[16507]: chan_sip.c:3414 parse_request: Line: t=0 0 (5) Sep 27 23:54:27 DEBUG[16507]: chan_sip.c:3414 parse_request: Line: m=audio 18388 RTP/AVP 18 101 (28) Sep 27 23:54:27 DEBUG[16507]: chan_sip.c:3414 parse_request: Line: a=rtpmap:18 G729/8000 (21) Sep 27 23:54:27 DEBUG[16507]: chan_sip.c:3414 parse_request: Line: a=fmtp:18 annexb=no (19) Sep 27 23:54:27 DEBUG[16507]: chan_sip.c:3414 parse_request: Line: a=rtpmap:101 telephone-event/8000 (33) Sep 27 23:54:27 DEBUG[16507]: chan_sip.c:3414 parse_request: Line: a=fmtp:101 0-16 (15) Sep 27 23:54:27 DEBUG[16507]: chan_sip.c:3414 parse_request: Line: a=silenceSupp:off - - - - (25) --- (14 headers 11 lines)--- Sep 27 23:54:27 DEBUG[16507]: chan_sip.c:3166 sip_alloc: Allocating new SIP dialog for 36072d60087520850b53504c01c8fd70@10.0.0.10 - INVITE (With RTP) Sep 27 23:54:27 DEBUG[16507]: chan_sip.c:11179 handle_request: **** Received INVITE (5) - Command in SIP INVITE Using INVITE request as basis request - 36072d60087520850b53504c01c8fd70@10.0.0.10 Sending to 10.0.0.10 : 5060 (NAT) Found peer 'test' Sep 27 23:54:27 DEBUG[16507]: chan_sip.c:7262 check_user_full: Setting NAT on RTP to 524288 Reliably Transmitting (NAT) to 10.0.0.10:5060: SIP/2.0 407 Proxy Authentication Required Via: SIP/2.0/UDP 10.0.0.10:5060;branch=z9hG4bK4c3bd523;received=10.0.0.10;rport=5060 From: "Hasco5" ;tag=as73051ef9 To: ;tag=as47d5c066 Call-ID: 36072d60087520850b53504c01c8fd70@10.0.0.10 CSeq: 102 INVITE User-Agent: LAM PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Contact: Proxy-Authenticate: Digest algorithm=MD5, realm="asterisk", nonce="73046c52" Content-Length: 0 --- Sep 27 23:54:27 DEBUG[16507]: chan_sip.c:1302 __sip_reliable_xmit: *** SIP TIMER: Initalizing retransmit timer on packet: Id #4588 Scheduling destruction of call '36072d60087520850b53504c01c8fd70@10.0.0.10' in 15000 ms test*CLI> <-- SIP read from 10.0.0.10:5060: ACK sip:s@10.0.1.9 SIP/2.0 Via: SIP/2.0/UDP 10.0.0.10:5060;branch=z9hG4bK4c3bd523;rport From: "Hasco5" ;tag=as73051ef9 To: ;tag=as47d5c066 Contact: Call-ID: 36072d60087520850b53504c01c8fd70@10.0.0.10 CSeq: 102 ACK User-Agent: LAM PBX Max-Forwards: 70 Remote-Party-ID: "Hasco5" ;privacy=off;screen=no Content-Length: 0 Sep 27 23:54:27 DEBUG[16507]: chan_sip.c:3382 parse_request: Header 0: ACK sip:s@10.0.1.9 SIP/2.0 (29) Sep 27 23:54:27 DEBUG[16507]: chan_sip.c:3382 parse_request: Header 1: Via: SIP/2.0/UDP 10.0.0.10:5060;branch=z9hG4bK4c3bd523;rport (65) Sep 27 23:54:27 DEBUG[16507]: chan_sip.c:3382 parse_request: Header 2: From: "Hasco5" ;tag=as73051ef9 (54) Sep 27 23:54:27 DEBUG[16507]: chan_sip.c:3382 parse_request: Header 3: To: ;tag=as47d5c066 (38) Sep 27 23:54:27 DEBUG[16507]: chan_sip.c:3382 parse_request: Header 4: Contact: (33) Sep 27 23:54:27 DEBUG[16507]: chan_sip.c:3382 parse_request: Header 5: Call-ID: 36072d60087520850b53504c01c8fd70@10.0.0.10 (56) Sep 27 23:54:27 DEBUG[16507]: chan_sip.c:3382 parse_request: Header 6: CSeq: 102 ACK (13) Sep 27 23:54:27 DEBUG[16507]: chan_sip.c:3382 parse_request: Header 7: User-Agent: LAM PBX (19) Sep 27 23:54:27 DEBUG[16507]: chan_sip.c:3382 parse_request: Header 8: Max-Forwards: 70 (16) Sep 27 23:54:27 DEBUG[16507]: chan_sip.c:3382 parse_request: Header 9: Remote-Party-ID: "Hasco5" ;privacy=off;screen=no (72) Sep 27 23:54:27 DEBUG[16507]: chan_sip.c:3382 parse_request: Header 10: Content-Length: 0 (17) Sep 27 23:54:27 DEBUG[16507]: chan_sip.c:3382 parse_request: Header 11: (0) --- (11 headers 0 lines)--- Sep 27 23:54:27 DEBUG[16507]: chan_sip.c:11179 handle_request: **** Received ACK (6) - Command in SIP ACK Sep 27 23:54:27 DEBUG[16507]: chan_sip.c:1399 __sip_ack: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #4588 test*CLI> <-- SIP read from 10.0.0.10:5060: INVITE sip:s@10.0.1.9 SIP/2.0 Via: SIP/2.0/UDP 10.0.0.10:5060;branch=z9hG4bK002a1381;rport From: "Hasco5" ;tag=as73051ef9 To: Contact: Call-ID: 36072d60087520850b53504c01c8fd70@10.0.0.10 CSeq: 103 INVITE User-Agent: LAM PBX Max-Forwards: 70 Remote-Party-ID: "Hasco5" ;privacy=off;screen=no Proxy-Authorization: Digest username="s", realm="asterisk", algorithm=MD5, uri="sip:s@10.0.1.9", nonce="73046c52", response="3ba50db9f29f5589752fde5f744c9e6d", opaque="" Date: Wed, 27 Sep 2006 21:54:34 GMT Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Content-Type: application/sdp Content-Length: 243 v=0 o=root 23900 23901 IN IP4 10.0.0.10 s=session c=IN IP4 10.0.0.10 t=0 0 m=audio 18388 RTP/AVP 18 101 a=rtpmap:18 G729/8000 a=fmtp:18 annexb=no a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=silenceSupp:off - - - - Sep 27 23:54:27 DEBUG[16507]: chan_sip.c:3382 parse_request: Header 0: INVITE sip:s@10.0.1.9 SIP/2.0 (32) Sep 27 23:54:27 DEBUG[16507]: chan_sip.c:3382 parse_request: Header 1: Via: SIP/2.0/UDP 10.0.0.10:5060;branch=z9hG4bK002a1381;rport (65) Sep 27 23:54:27 DEBUG[16507]: chan_sip.c:3382 parse_request: Header 2: From: "Hasco5" ;tag=as73051ef9 (54) Sep 27 23:54:27 DEBUG[16507]: chan_sip.c:3382 parse_request: Header 3: To: (23) Sep 27 23:54:27 DEBUG[16507]: chan_sip.c:3382 parse_request: Header 4: Contact: (33) Sep 27 23:54:27 DEBUG[16507]: chan_sip.c:3382 parse_request: Header 5: Call-ID: 36072d60087520850b53504c01c8fd70@10.0.0.10 (56) Sep 27 23:54:27 DEBUG[16507]: chan_sip.c:3382 parse_request: Header 6: CSeq: 103 INVITE (16) Sep 27 23:54:27 DEBUG[16507]: chan_sip.c:3382 parse_request: Header 7: User-Agent: LAM PBX (19) Sep 27 23:54:27 DEBUG[16507]: chan_sip.c:3382 parse_request: Header 8: Max-Forwards: 70 (16) Sep 27 23:54:27 DEBUG[16507]: chan_sip.c:3382 parse_request: Header 9: Remote-Party-ID: "Hasco5" ;privacy=off;screen=no (72) Sep 27 23:54:27 DEBUG[16507]: chan_sip.c:3382 parse_request: Header 10: Proxy-Authorization: Digest username="s", realm="asterisk", algorithm=MD5, uri="sip:s@10.0.1.9", nonce="73046c52", response="3ba50db9f29f5589752fde5f744c9e6d", opaque="" (172) Sep 27 23:54:27 DEBUG[16507]: chan_sip.c:3382 parse_request: Header 11: Date: Wed, 27 Sep 2006 21:54:34 GMT (35) Sep 27 23:54:27 DEBUG[16507]: chan_sip.c:3382 parse_request: Header 12: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY (66) Sep 27 23:54:27 DEBUG[16507]: chan_sip.c:3382 parse_request: Header 13: Content-Type: application/sdp (29) Sep 27 23:54:27 DEBUG[16507]: chan_sip.c:3382 parse_request: Header 14: Content-Length: 243 (19) Sep 27 23:54:27 DEBUG[16507]: chan_sip.c:3382 parse_request: Header 15: (0) Sep 27 23:54:27 DEBUG[16507]: chan_sip.c:3414 parse_request: Line: v=0 (3) Sep 27 23:54:27 DEBUG[16507]: chan_sip.c:3414 parse_request: Line: o=root 23900 23901 IN IP4 10.0.0.10 (40) Sep 27 23:54:27 DEBUG[16507]: chan_sip.c:3414 parse_request: Line: s=session (9) Sep 27 23:54:27 DEBUG[16507]: chan_sip.c:3414 parse_request: Line: c=IN IP4 10.0.0.10 (23) Sep 27 23:54:27 DEBUG[16507]: chan_sip.c:3414 parse_request: Line: t=0 0 (5) Sep 27 23:54:27 DEBUG[16507]: chan_sip.c:3414 parse_request: Line: m=audio 18388 RTP/AVP 18 101 (28) Sep 27 23:54:27 DEBUG[16507]: chan_sip.c:3414 parse_request: Line: a=rtpmap:18 G729/8000 (21) Sep 27 23:54:27 DEBUG[16507]: chan_sip.c:3414 parse_request: Line: a=fmtp:18 annexb=no (19) Sep 27 23:54:27 DEBUG[16507]: chan_sip.c:3414 parse_request: Line: a=rtpmap:101 telephone-event/8000 (33) Sep 27 23:54:27 DEBUG[16507]: chan_sip.c:3414 parse_request: Line: a=fmtp:101 0-16 (15) Sep 27 23:54:27 DEBUG[16507]: chan_sip.c:3414 parse_request: Line: a=silenceSupp:off - - - - (25) --- (15 headers 11 lines)--- Sep 27 23:54:27 DEBUG[16507]: chan_sip.c:11179 handle_request: **** Received INVITE (5) - Command in SIP INVITE Using INVITE request as basis request - 36072d60087520850b53504c01c8fd70@10.0.0.10 Sending to 10.0.0.10 : 5060 (NAT) Found peer 'test' Sep 27 23:54:27 DEBUG[16507]: chan_sip.c:7262 check_user_full: Setting NAT on RTP to 524288 !!!! username:test, digestusername:s Sep 27 23:54:27 NOTICE[16507]: chan_sip.c:10503 handle_request_invite: Failed to authenticate user "Hasco5" ;tag=as73051ef9, f:-2 Reliably Transmitting (NAT) to 10.0.0.10:5060: SIP/2.0 403 Forbidden Via: SIP/2.0/UDP 10.0.0.10:5060;branch=z9hG4bK002a1381;received=10.0.0.10;rport=5060 From: "Hasco5" ;tag=as73051ef9 To: ;tag=as47d5c066 Call-ID: 36072d60087520850b53504c01c8fd70@10.0.0.10 CSeq: 103 INVITE User-Agent: LAM PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Contact: Content-Length: 0 --- Sep 27 23:54:27 DEBUG[16507]: chan_sip.c:1302 __sip_reliable_xmit: *** SIP TIMER: Initalizing retransmit timer on packet: Id #4590 test*CLI> <-- SIP read from 10.0.0.10:5060: ACK sip:s@10.0.1.9 SIP/2.0 Via: SIP/2.0/UDP 10.0.0.10:5060;branch=z9hG4bK002a1381;rport From: "Hasco5" ;tag=as73051ef9 To: ;tag=as47d5c066 Contact: Call-ID: 36072d60087520850b53504c01c8fd70@10.0.0.10 CSeq: 103 ACK User-Agent: LAM PBX Max-Forwards: 70 Remote-Party-ID: "Hasco5" ;privacy=off;screen=no Content-Length: 0 Sep 27 23:54:27 DEBUG[16507]: chan_sip.c:3382 parse_request: Header 0: ACK sip:s@10.0.1.9 SIP/2.0 (29) Sep 27 23:54:27 DEBUG[16507]: chan_sip.c:3382 parse_request: Header 1: Via: SIP/2.0/UDP 10.0.0.10:5060;branch=z9hG4bK002a1381;rport (65) Sep 27 23:54:27 DEBUG[16507]: chan_sip.c:3382 parse_request: Header 2: From: "Hasco5" ;tag=as73051ef9 (54) Sep 27 23:54:27 DEBUG[16507]: chan_sip.c:3382 parse_request: Header 3: To: ;tag=as47d5c066 (38) Sep 27 23:54:27 DEBUG[16507]: chan_sip.c:3382 parse_request: Header 4: Contact: (33) Sep 27 23:54:27 DEBUG[16507]: chan_sip.c:3382 parse_request: Header 5: Call-ID: 36072d60087520850b53504c01c8fd70@10.0.0.10 (56) Sep 27 23:54:27 DEBUG[16507]: chan_sip.c:3382 parse_request: Header 6: CSeq: 103 ACK (13) Sep 27 23:54:27 DEBUG[16507]: chan_sip.c:3382 parse_request: Header 7: User-Agent: LAM PBX (19) Sep 27 23:54:27 DEBUG[16507]: chan_sip.c:3382 parse_request: Header 8: Max-Forwards: 70 (16) Sep 27 23:54:27 DEBUG[16507]: chan_sip.c:3382 parse_request: Header 9: Remote-Party-ID: "Hasco5" ;privacy=off;screen=no (72) Sep 27 23:54:27 DEBUG[16507]: chan_sip.c:3382 parse_request: Header 10: Content-Length: 0 (17) Sep 27 23:54:27 DEBUG[16507]: chan_sip.c:3382 parse_request: Header 11: (0) --- (11 headers 0 lines)--- Sep 27 23:54:27 DEBUG[16507]: chan_sip.c:11179 handle_request: **** Received ACK (6) - Command in SIP ACK Sep 27 23:54:27 DEBUG[16507]: chan_sip.c:1399 __sip_ack: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #4590 Destroying call '36072d60087520850b53504c01c8fd70@10.0.0.10' test*CLI>