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=z9hG4bK212ea25f;rport From: "Hasco5" ;tag=as446a27dc To: Contact: Call-ID: 6dd2bd364ef2cecb63a77228487a1a51@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 22:00:31 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 11026 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 28 00:00:23 DEBUG[16507]: chan_sip.c:3382 parse_request: Header 0: INVITE sip:s@10.0.1.9 SIP/2.0 (32) Sep 28 00:00:23 DEBUG[16507]: chan_sip.c:3382 parse_request: Header 1: Via: SIP/2.0/UDP 10.0.0.10:5060;branch=z9hG4bK212ea25f;rport (65) Sep 28 00:00:23 DEBUG[16507]: chan_sip.c:3382 parse_request: Header 2: From: "Hasco5" ;tag=as446a27dc (54) Sep 28 00:00:23 DEBUG[16507]: chan_sip.c:3382 parse_request: Header 3: To: (23) Sep 28 00:00:23 DEBUG[16507]: chan_sip.c:3382 parse_request: Header 4: Contact: (33) Sep 28 00:00:23 DEBUG[16507]: chan_sip.c:3382 parse_request: Header 5: Call-ID: 6dd2bd364ef2cecb63a77228487a1a51@10.0.0.10 (56) Sep 28 00:00:23 DEBUG[16507]: chan_sip.c:3382 parse_request: Header 6: CSeq: 102 INVITE (16) Sep 28 00:00:23 DEBUG[16507]: chan_sip.c:3382 parse_request: Header 7: User-Agent: LAM PBX (19) Sep 28 00:00:23 DEBUG[16507]: chan_sip.c:3382 parse_request: Header 8: Max-Forwards: 70 (16) Sep 28 00:00:23 DEBUG[16507]: chan_sip.c:3382 parse_request: Header 9: Remote-Party-ID: "Hasco5" ;privacy=off;screen=no (72) Sep 28 00:00:23 DEBUG[16507]: chan_sip.c:3382 parse_request: Header 10: Date: Wed, 27 Sep 2006 22:00:31 GMT (35) Sep 28 00:00:23 DEBUG[16507]: chan_sip.c:3382 parse_request: Header 11: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY (66) Sep 28 00:00:23 DEBUG[16507]: chan_sip.c:3382 parse_request: Header 12: Content-Type: application/sdp (29) Sep 28 00:00:23 DEBUG[16507]: chan_sip.c:3382 parse_request: Header 13: Content-Length: 243 (19) Sep 28 00:00:23 DEBUG[16507]: chan_sip.c:3382 parse_request: Header 14: (0) Sep 28 00:00:23 DEBUG[16507]: chan_sip.c:3414 parse_request: Line: v=0 (3) Sep 28 00:00:23 DEBUG[16507]: chan_sip.c:3414 parse_request: Line: o=root 23900 23900 IN IP4 10.0.0.10 (40) Sep 28 00:00:23 DEBUG[16507]: chan_sip.c:3414 parse_request: Line: s=session (9) Sep 28 00:00:23 DEBUG[16507]: chan_sip.c:3414 parse_request: Line: c=IN IP4 10.0.0.10 (23) Sep 28 00:00:23 DEBUG[16507]: chan_sip.c:3414 parse_request: Line: t=0 0 (5) Sep 28 00:00:23 DEBUG[16507]: chan_sip.c:3414 parse_request: Line: m=audio 11026 RTP/AVP 18 101 (28) Sep 28 00:00:23 DEBUG[16507]: chan_sip.c:3414 parse_request: Line: a=rtpmap:18 G729/8000 (21) Sep 28 00:00:23 DEBUG[16507]: chan_sip.c:3414 parse_request: Line: a=fmtp:18 annexb=no (19) Sep 28 00:00:23 DEBUG[16507]: chan_sip.c:3414 parse_request: Line: a=rtpmap:101 telephone-event/8000 (33) Sep 28 00:00:23 DEBUG[16507]: chan_sip.c:3414 parse_request: Line: a=fmtp:101 0-16 (15) Sep 28 00:00:23 DEBUG[16507]: chan_sip.c:3414 parse_request: Line: a=silenceSupp:off - - - - (25) --- (14 headers 11 lines)--- Sep 28 00:00:23 DEBUG[16507]: chan_sip.c:3166 sip_alloc: Allocating new SIP dialog for 6dd2bd364ef2cecb63a77228487a1a51@10.0.0.10 - INVITE (With RTP) Sep 28 00:00:23 DEBUG[16507]: chan_sip.c:11179 handle_request: **** Received INVITE (5) - Command in SIP INVITE Using INVITE request as basis request - 6dd2bd364ef2cecb63a77228487a1a51@10.0.0.10 Sending to 10.0.0.10 : 5060 (NAT) Found peer 'test' Sep 28 00:00:23 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=z9hG4bK212ea25f;received=10.0.0.10;rport=5060 From: "Hasco5" ;tag=as446a27dc To: ;tag=as3c7ffcb8 Call-ID: 6dd2bd364ef2cecb63a77228487a1a51@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="0633798d" Content-Length: 0 --- Sep 28 00:00:23 DEBUG[16507]: chan_sip.c:1302 __sip_reliable_xmit: *** SIP TIMER: Initalizing retransmit timer on packet: Id #4627 Scheduling destruction of call '6dd2bd364ef2cecb63a77228487a1a51@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=z9hG4bK212ea25f;rport From: "Hasco5" ;tag=as446a27dc To: ;tag=as3c7ffcb8 Contact: Call-ID: 6dd2bd364ef2cecb63a77228487a1a51@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 28 00:00:23 DEBUG[16507]: chan_sip.c:3382 parse_request: Header 0: ACK sip:s@10.0.1.9 SIP/2.0 (29) Sep 28 00:00:23 DEBUG[16507]: chan_sip.c:3382 parse_request: Header 1: Via: SIP/2.0/UDP 10.0.0.10:5060;branch=z9hG4bK212ea25f;rport (65) Sep 28 00:00:23 DEBUG[16507]: chan_sip.c:3382 parse_request: Header 2: From: "Hasco5" ;tag=as446a27dc (54) Sep 28 00:00:23 DEBUG[16507]: chan_sip.c:3382 parse_request: Header 3: To: ;tag=as3c7ffcb8 (38) Sep 28 00:00:23 DEBUG[16507]: chan_sip.c:3382 parse_request: Header 4: Contact: (33) Sep 28 00:00:23 DEBUG[16507]: chan_sip.c:3382 parse_request: Header 5: Call-ID: 6dd2bd364ef2cecb63a77228487a1a51@10.0.0.10 (56) Sep 28 00:00:23 DEBUG[16507]: chan_sip.c:3382 parse_request: Header 6: CSeq: 102 ACK (13) Sep 28 00:00:23 DEBUG[16507]: chan_sip.c:3382 parse_request: Header 7: User-Agent: LAM PBX (19) Sep 28 00:00:23 DEBUG[16507]: chan_sip.c:3382 parse_request: Header 8: Max-Forwards: 70 (16) Sep 28 00:00:23 DEBUG[16507]: chan_sip.c:3382 parse_request: Header 9: Remote-Party-ID: "Hasco5" ;privacy=off;screen=no (72) Sep 28 00:00:23 DEBUG[16507]: chan_sip.c:3382 parse_request: Header 10: Content-Length: 0 (17) Sep 28 00:00:23 DEBUG[16507]: chan_sip.c:3382 parse_request: Header 11: (0) --- (11 headers 0 lines)--- Sep 28 00:00:23 DEBUG[16507]: chan_sip.c:11179 handle_request: **** Received ACK (6) - Command in SIP ACK Sep 28 00:00:23 DEBUG[16507]: chan_sip.c:1399 __sip_ack: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #4627 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=z9hG4bK1a815c10;rport From: "Hasco5" ;tag=as446a27dc To: Contact: Call-ID: 6dd2bd364ef2cecb63a77228487a1a51@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="test", realm="asterisk", algorithm=MD5, uri="sip:s@10.0.1.9", nonce="0633798d", response="f16790d740d7763d6386d02df0ccf21b", opaque="" Date: Wed, 27 Sep 2006 22:00:31 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 11026 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 28 00:00:23 DEBUG[16507]: chan_sip.c:3382 parse_request: Header 0: INVITE sip:s@10.0.1.9 SIP/2.0 (32) Sep 28 00:00:23 DEBUG[16507]: chan_sip.c:3382 parse_request: Header 1: Via: SIP/2.0/UDP 10.0.0.10:5060;branch=z9hG4bK1a815c10;rport (65) Sep 28 00:00:23 DEBUG[16507]: chan_sip.c:3382 parse_request: Header 2: From: "Hasco5" ;tag=as446a27dc (54) Sep 28 00:00:23 DEBUG[16507]: chan_sip.c:3382 parse_request: Header 3: To: (23) Sep 28 00:00:23 DEBUG[16507]: chan_sip.c:3382 parse_request: Header 4: Contact: (33) Sep 28 00:00:23 DEBUG[16507]: chan_sip.c:3382 parse_request: Header 5: Call-ID: 6dd2bd364ef2cecb63a77228487a1a51@10.0.0.10 (56) Sep 28 00:00:23 DEBUG[16507]: chan_sip.c:3382 parse_request: Header 6: CSeq: 103 INVITE (16) Sep 28 00:00:23 DEBUG[16507]: chan_sip.c:3382 parse_request: Header 7: User-Agent: LAM PBX (19) Sep 28 00:00:23 DEBUG[16507]: chan_sip.c:3382 parse_request: Header 8: Max-Forwards: 70 (16) Sep 28 00:00:23 DEBUG[16507]: chan_sip.c:3382 parse_request: Header 9: Remote-Party-ID: "Hasco5" ;privacy=off;screen=no (72) Sep 28 00:00:23 DEBUG[16507]: chan_sip.c:3382 parse_request: Header 10: Proxy-Authorization: Digest username="test", realm="asterisk", algorithm=MD5, uri="sip:s@10.0.1.9", nonce="0633798d", response="f16790d740d7763d6386d02df0ccf21b", opaque="" (178) Sep 28 00:00:23 DEBUG[16507]: chan_sip.c:3382 parse_request: Header 11: Date: Wed, 27 Sep 2006 22:00:31 GMT (35) Sep 28 00:00:23 DEBUG[16507]: chan_sip.c:3382 parse_request: Header 12: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY (66) Sep 28 00:00:23 DEBUG[16507]: chan_sip.c:3382 parse_request: Header 13: Content-Type: application/sdp (29) Sep 28 00:00:23 DEBUG[16507]: chan_sip.c:3382 parse_request: Header 14: Content-Length: 243 (19) Sep 28 00:00:23 DEBUG[16507]: chan_sip.c:3382 parse_request: Header 15: (0) Sep 28 00:00:23 DEBUG[16507]: chan_sip.c:3414 parse_request: Line: v=0 (3) Sep 28 00:00:23 DEBUG[16507]: chan_sip.c:3414 parse_request: Line: o=root 23900 23901 IN IP4 10.0.0.10 (40) Sep 28 00:00:23 DEBUG[16507]: chan_sip.c:3414 parse_request: Line: s=session (9) Sep 28 00:00:23 DEBUG[16507]: chan_sip.c:3414 parse_request: Line: c=IN IP4 10.0.0.10 (23) Sep 28 00:00:23 DEBUG[16507]: chan_sip.c:3414 parse_request: Line: t=0 0 (5) Sep 28 00:00:23 DEBUG[16507]: chan_sip.c:3414 parse_request: Line: m=audio 11026 RTP/AVP 18 101 (28) Sep 28 00:00:23 DEBUG[16507]: chan_sip.c:3414 parse_request: Line: a=rtpmap:18 G729/8000 (21) Sep 28 00:00:23 DEBUG[16507]: chan_sip.c:3414 parse_request: Line: a=fmtp:18 annexb=no (19) Sep 28 00:00:23 DEBUG[16507]: chan_sip.c:3414 parse_request: Line: a=rtpmap:101 telephone-event/8000 (33) Sep 28 00:00:23 DEBUG[16507]: chan_sip.c:3414 parse_request: Line: a=fmtp:101 0-16 (15) Sep 28 00:00:23 DEBUG[16507]: chan_sip.c:3414 parse_request: Line: a=silenceSupp:off - - - - (25) --- (15 headers 11 lines)--- Sep 28 00:00:23 DEBUG[16507]: chan_sip.c:11179 handle_request: **** Received INVITE (5) - Command in SIP INVITE Using INVITE request as basis request - 6dd2bd364ef2cecb63a77228487a1a51@10.0.0.10 Sending to 10.0.0.10 : 5060 (NAT) Found peer 'test' Sep 28 00:00:23 DEBUG[16507]: chan_sip.c:7262 check_user_full: Setting NAT on RTP to 524288 Found RTP audio format 18 Found RTP audio format 101 Peer audio RTP is at port 10.0.0.10:11026 Sep 28 00:00:23 DEBUG[16507]: chan_sip.c:3621 process_sdp: Peer audio RTP is at port 10.0.0.10:11026 Found description format G729 Found description format telephone-event Capabilities: us - 0x50e (gsm|ulaw|alaw|g729|ilbc), peer - audio=0x100 (g729)/video=0x0 (nothing), combined - 0x100 (g729) Non-codec capabilities: us - 0x1 (telephone-event), peer - 0x1 (telephone-event), combined - 0x1 (telephone-event) Sep 28 00:00:23 DEBUG[16507]: chan_sip.c:10531 handle_request_invite: Checking SIP call limits for device test Sep 28 00:00:23 DEBUG[16507]: chan_sip.c:2215 update_call_counter: Updating call counter for incoming call Looking for s in inRoute-SIPid-9 (domain 10.0.1.9) Sep 28 00:00:23 DEBUG[16507]: chan_sip.c:6152 build_route: build_route: Contact hop: list_route: hop: Transmitting (NAT) to 10.0.0.10:5060: SIP/2.0 100 Trying Via: SIP/2.0/UDP 10.0.0.10:5060;branch=z9hG4bK1a815c10;received=10.0.0.10;rport=5060 From: "Hasco5" ;tag=as446a27dc To: Call-ID: 6dd2bd364ef2cecb63a77228487a1a51@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 28 00:00:23 DEBUG[16457]: chan_sip.c:11720 sip_devicestate: Checking device state for peer test Sep 28 00:00:23 DEBUG[16457]: devicestate.c:187 do_state_change: Changing state for SIP/test - state 2 (In use) Sep 28 00:00:23 DEBUG[21709]: pbx.c:1677 pbx_extension_helper: Launching 'Set' -- Executing Set("SIP/test-081db0c8", "SEXTEN=s") in new stack