Oct 9 12:58:33 VERBOSE[2945] logger.c: <-- SIP read from 10.1.16.132:39112: INVITE sip:7135152830@10.1.16.142 SIP/2.0 Via: SIP/2.0/UDP 10.1.16.132:39112;branch=z9hG4bK-d87543-36549217703d3201-1--d87 543-;rport Max-Forwards: 70 Contact: To: "7135152830" From: "Sean Stoner";tag=c9143e61 Call-ID: YjNmNGZiMzAwMzgyMTA5NDJlMmJmY2U4ZTA0YTc0Y2E. CSeq: 1 INVITE Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, SUBSCRIBE, INF O Content-Type: application/sdp User-Agent: X-Lite release 1011s stamp 41150 Content-Length: 518 v=0 o=- 2 2 IN IP4 10.1.16.132 s=CounterPath X-Lite 3.0 c=IN IP4 10.1.16.132 t=0 0 m=audio 24138 RTP/AVP 107 119 100 106 0 105 98 8 101 a=alt:1 3 : /SzN5dZq gUO5Bi1o 10.1.16.132 24138 a=alt:2 2 : 2z+XgGpN KBvV4JQe 192.168.29.1 24138 a=alt:3 1 : M8mZzSTC W21Htzvp 192.168.130.1 24138 a=fmtp:101 0-15 a=rtpmap:107 BV32/16000 a=rtpmap:119 BV32-FEC/16000 a=rtpmap:100 SPEEX/16000 a=rtpmap:106 SPEEX-FEC/16000 a=rtpmap:105 SPEEX-FEC/8000 a=rtpmap:98 iLBC/8000 a=rtpmap:101 telephone-event/8000 a=sendrecv Oct 9 12:58:33 VERBOSE[2945] logger.c: --- (12 headers 18 lines) --- Oct 9 12:58:33 VERBOSE[2945] logger.c: Using INVITE request as basis request - YjNmNGZiMzAwMzgyMTA5NDJlMmJmY2U4ZTA0YTc0Y2E. Oct 9 12:58:33 VERBOSE[2945] logger.c: Sending to 10.1.16.132 : 39112 (NAT) Oct 9 12:58:33 DEBUG[2945] chan_sip.c: Setting NAT on RTP to 0 Oct 9 12:58:33 VERBOSE[2945] logger.c: Reliably Transmitting (no NAT) to 10.1.1 6.132:39112: SIP/2.0 407 Proxy Authentication Required Via: SIP/2.0/UDP 10.1.16.132:39112;branch=z9hG4bK-d87543-36549217703d3201-1--d87 543-;received=10.1.16.132;rport=39112 From: "Sean Stoner";tag=c9143e61 To: "7135152830";tag=as71e72065 Call-ID: YjNmNGZiMzAwMzgyMTA5NDJlMmJmY2U4ZTA0YTc0Y2E. CSeq: 1 INVITE User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Proxy-Authenticate: Digest algorithm=MD5, realm="asterisk", nonce="4e683cc5" Content-Length: 0 --- Oct 9 12:58:33 VERBOSE[2945] logger.c: Scheduling destruction of call 'YjNmNGZi MzAwMzgyMTA5NDJlMmJmY2U4ZTA0YTc0Y2E.' in 15000 ms Oct 9 12:58:33 VERBOSE[2945] logger.c: Found user '8001' Oct 9 12:58:33 VERBOSE[2945] logger.c: <-- SIP read from 10.1.16.132:39112: ACK sip:7135152830@10.1.16.142 SIP/2.0 Via: SIP/2.0/UDP 10.1.16.132:39112;branch=z9hG4bK-d87543-36549217703d3201-1--d87 543-;rport To: "7135152830";tag=as71e72065 From: "Sean Stoner";tag=c9143e61 Call-ID: YjNmNGZiMzAwMzgyMTA5NDJlMmJmY2U4ZTA0YTc0Y2E. CSeq: 1 ACK Content-Length: 0 Oct 9 12:58:33 VERBOSE[2945] logger.c: --- (7 headers 0 lines) --- Oct 9 12:58:33 DEBUG[2945] chan_sip.c: Stopping retransmission on 'YjNmNGZiMzAw MzgyMTA5NDJlMmJmY2U4ZTA0YTc0Y2E.' of Response 1: Match Found Oct 9 12:58:33 VERBOSE[2945] logger.c: <-- SIP read from 10.1.16.132:39112: INVITE sip:7135152830@10.1.16.142 SIP/2.0 Via: SIP/2.0/UDP 10.1.16.132:39112;branch=z9hG4bK-d87543-d16016231903fc29-1--d87 543-;rport Max-Forwards: 70 Contact: To: "7135152830" From: "Sean Stoner";tag=c9143e61 Call-ID: YjNmNGZiMzAwMzgyMTA5NDJlMmJmY2U4ZTA0YTc0Y2E. CSeq: 2 INVITE Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, SUBSCRIBE, INF O Content-Type: application/sdp Proxy-Authorization: Digest username="8001",realm="asterisk",nonce="4e683cc5",ur i="sip:7135152830@10.1.16.142",response="27fe86961a69aca0c77f55d941cb6d7d",algor ithm=MD5 User-Agent: X-Lite release 1011s stamp 41150 Content-Length: 518 v=0 o=- 2 2 IN IP4 10.1.16.132 s=CounterPath X-Lite 3.0 c=IN IP4 10.1.16.132 t=0 0 m=audio 24138 RTP/AVP 107 119 100 106 0 105 98 8 101 a=alt:1 3 : /SzN5dZq gUO5Bi1o 10.1.16.132 24138 a=alt:2 2 : 2z+XgGpN KBvV4JQe 192.168.29.1 24138 a=alt:3 1 : M8mZzSTC W21Htzvp 192.168.130.1 24138 a=fmtp:101 0-15 a=rtpmap:107 BV32/16000 a=rtpmap:119 BV32-FEC/16000 a=rtpmap:100 SPEEX/16000 a=rtpmap:106 SPEEX-FEC/16000 a=rtpmap:105 SPEEX-FEC/8000 a=rtpmap:98 iLBC/8000 a=rtpmap:101 telephone-event/8000 a=sendrecv Oct 9 12:58:33 VERBOSE[2945] logger.c: --- (13 headers 18 lines) --- Oct 9 12:58:33 VERBOSE[2945] logger.c: Using INVITE request as basis request - YjNmNGZiMzAwMzgyMTA5NDJlMmJmY2U4ZTA0YTc0Y2E. Oct 9 12:58:33 VERBOSE[2945] logger.c: Sending to 10.1.16.132 : 39112 (NAT) Oct 9 12:58:33 DEBUG[2945] chan_sip.c: Setting NAT on RTP to 0 Oct 9 12:58:33 VERBOSE[2945] logger.c: Found user '8001' Oct 9 12:58:33 VERBOSE[2945] logger.c: Found RTP audio format 107 Oct 9 12:58:33 VERBOSE[2945] logger.c: Found RTP audio format 119 Oct 9 12:58:33 VERBOSE[2945] logger.c: Found RTP audio format 100 Oct 9 12:58:33 VERBOSE[2945] logger.c: Found RTP audio format 106 Oct 9 12:58:33 VERBOSE[2945] logger.c: Found RTP audio format 0 Oct 9 12:58:33 VERBOSE[2945] logger.c: Found RTP audio format 105 Oct 9 12:58:33 VERBOSE[2945] logger.c: Found RTP audio format 98 Oct 9 12:58:33 VERBOSE[2945] logger.c: Found RTP audio format 8 Oct 9 12:58:33 VERBOSE[2945] logger.c: Found RTP audio format 101 Oct 9 12:58:33 VERBOSE[2945] logger.c: Peer audio RTP is at port 10.1.16.132:24 138 Oct 9 12:58:33 DEBUG[2945] chan_sip.c: Peer audio RTP is at port 10.1.16.132:24 138 Oct 9 12:58:33 VERBOSE[2945] logger.c: Found description format BV32 Oct 9 12:58:33 VERBOSE[2945] logger.c: Found description format BV32-FEC Oct 9 12:58:33 VERBOSE[2945] logger.c: Found description format SPEEX Oct 9 12:58:33 VERBOSE[2945] logger.c: Found description format SPEEX-FEC Oct 9 12:58:33 VERBOSE[2945] logger.c: Found description format SPEEX-FEC Oct 9 12:58:33 VERBOSE[2945] logger.c: Found description format iLBC Oct 9 12:58:33 VERBOSE[2945] logger.c: Found description format telephone-event Oct 9 12:58:33 VERBOSE[2945] logger.c: Capabilities: us - 0xc (ulaw|alaw), peer - audio=0x60c (ulaw|alaw|speex|ilbc)/video=0x0 (nothing), combined - 0xc (ulaw| alaw) Oct 9 12:58:33 VERBOSE[2945] logger.c: Non-codec capabilities: us - 0x1 (teleph one-event), peer - 0x1 (telephone-event), combined - 0x1 (telephone-event) Oct 9 12:58:33 DEBUG[2945] chan_sip.c: Checking SIP call limits for device 8001 Oct 9 12:58:33 VERBOSE[2945] logger.c: Looking for 7135152830 in sstest (domain 10.1.16.142) Oct 9 12:58:33 DEBUG[2945] chan_sip.c: build_route: Contact hop: Oct 9 12:58:33 VERBOSE[2945] logger.c: list_route: hop: Oct 9 12:58:33 VERBOSE[2945] logger.c: Transmitting (no NAT) to 10.1.16.132:391 12: SIP/2.0 100 Trying Via: SIP/2.0/UDP 10.1.16.132:39112;branch=z9hG4bK-d87543-d16016231903fc29-1--d87 543-;received=10.1.16.132;rport=39112 From: "Sean Stoner";tag=c9143e61 To: "7135152830" Call-ID: YjNmNGZiMzAwMzgyMTA5NDJlMmJmY2U4ZTA0YTc0Y2E. CSeq: 2 INVITE User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Contact: Content-Length: 0 --- Oct 9 12:58:33 VERBOSE[21278] logger.c: -- Executing Dial("SIP/8001-09325ab 8", "SIP/7135152830@mediant") in new stack Oct 9 12:58:33 DEBUG[21278] chan_sip.c: Setting NAT on RTP to 0 Oct 9 12:58:33 DEBUG[21278] chan_sip.c: Outgoing Call for 7135152830 Oct 9 12:58:33 VERBOSE[21278] logger.c: We're at 10.1.16.142 port 15188 Oct 9 12:58:33 VERBOSE[21278] logger.c: Adding codec 0x4 (ulaw) to SDP Oct 9 12:58:33 VERBOSE[21278] logger.c: Adding non-codec 0x1 (telephone-event) to SDP Oct 9 12:58:33 VERBOSE[21278] logger.c: 13 headers, 10 lines Oct 9 12:58:33 VERBOSE[21278] logger.c: Reliably Transmitting (no NAT) to 10.1. 16.13:5060: INVITE sip:7135152830@10.1.16.13 SIP/2.0 Via: SIP/2.0/UDP 10.1.16.142:5060;branch=z9hG4bK50ac417a;rport From: "Sean Stoner" ;tag=as61283080 To: Contact: Call-ID: 45c059546f09680f2b8d99f36f7ef5c6@10.1.16.142 CSeq: 102 INVITE User-Agent: Asterisk PBX Max-Forwards: 70 Date: Tue, 09 Oct 2007 17:58:33 GMT Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Content-Type: application/sdp Content-Length: 212 v=0 o=root 2930 2930 IN IP4 10.1.16.142 s=session c=IN IP4 10.1.16.142 t=0 0 m=audio 15188 RTP/AVP 0 101 a=rtpmap:0 PCMU/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=silenceSupp:off - - - - --- Oct 9 12:58:33 VERBOSE[21278] logger.c: -- Called 7135152830@mediant Oct 9 12:58:33 VERBOSE[2945] logger.c: <-- SIP read from 10.1.16.13:5060: SIP/2.0 404 Not Found Via: SIP/2.0/UDP 10.1.16.142:5060;branch=z9hG4bK50ac417a;rport From: "Sean Stoner" ;tag=as61283080 To: ;tag=1c467638913 Call-ID: 45c059546f09680f2b8d99f36f7ef5c6@10.1.16.142 CSeq: 102 INVITE Supported: em,timer,replaces,path,resource-priority Allow: REGISTER,OPTIONS,INVITE,ACK,CANCEL,BYE,NOTIFY,PRACK,REFER,INFO,SUBSCRIBE, UPDATE Server: Audiocodes-Sip-Gateway-Mediant 1000/v.5.00A.024 Reason: Q.850 ;cause=3 Content-Length: 0 Oct 9 12:58:33 VERBOSE[2945] logger.c: --- (11 headers 0 lines) --- Oct 9 12:58:33 DEBUG[2945] chan_sip.c: Acked pending invite 102 Oct 9 12:58:33 DEBUG[2945] chan_sip.c: Stopping retransmission on '45c059546f09 680f2b8d99f36f7ef5c6@10.1.16.142' of Request 102: Match Found Oct 9 12:58:33 VERBOSE[2945] logger.c: Transmitting (no NAT) to 10.1.16.13:5060 : ACK sip:7135152830@10.1.16.13 SIP/2.0 Via: SIP/2.0/UDP 10.1.16.142:5060;branch=z9hG4bK50ac417a;rport From: "Sean Stoner" ;tag=as61283080 To: ;tag=1c467638913 Contact: Call-ID: 45c059546f09680f2b8d99f36f7ef5c6@10.1.16.142 CSeq: 102 ACK User-Agent: Asterisk PBX Max-Forwards: 70 Content-Length: 0 --- Oct 9 12:58:33 VERBOSE[21278] logger.c: -- SIP/mediant-0932c058 is circuit- busy Oct 9 12:58:33 DEBUG[21278] chan_sip.c: update_call_counter(7135152830) - decre ment call limit counter Oct 9 12:58:33 VERBOSE[21278] logger.c: == Everyone is busy/congested at this time (1:0/1/0) Oct 9 12:58:33 DEBUG[21278] app_dial.c: Exiting with DIALSTATUS=CONGESTION. Oct 9 12:58:33 VERBOSE[21278] logger.c: -- Executing NoOp("SIP/8001-09325ab 8", "DIALSTATUS=CONGESTION HANGUPCAUSE is 1") in new stack Oct 9 12:58:34 VERBOSE[2945] logger.c: Destroying call '45c059546f09680f2b8d99f 36f7ef5c6@10.1.16.142'