Aug 21 18:00:02 VERBOSE[1506]: 8 headers, 0 lines Aug 21 18:00:02 DEBUG[1506]: Stopping retransmission on '7a417dd526dfbb1c0e7981055c1fb1c8@10.11.12.22' of Request 102: Found Aug 21 18:00:02 VERBOSE[1506]: Destroying call '7a417dd526dfbb1c0e7981055c1fb1c8@10.11.12.22' Aug 21 18:00:07 VERBOSE[1506]: Sip read: INVITE sip:1000@10.11.12.22:5060 SIP/2.0 Via: SIP/2.0/UDP 10.11.12.101;branch=z9hG4bK3272ce583 Content-Length: 193 To: sip:1000@10.11.12.22:5060 From: +17143316706 ;tag=4e0e967a6b8fecf Call-ID: 36a72d23eabc7b8a62e6be9379c01789@10.11.12.22 CSeq: 505426505 INVITE Route: Supported: timer Min-SE: 1800 Session-Expires: 3600 Allow: INVITE, ACK, BYE, CANCEL, REFER, NOTIFY Content-Type: application/sdp Contact: Port 1 Supported: replaces User-Agent: MxSipApp/4.4.11.68 MxSF/v3.2.7.30 v=0 o=MxSIP 928473109364946641 8470749269371233534 IN IP4 10.11.12.101 s=- c=IN IP4 10.11.12.101 t=0 0 a=sendrecv m=audio 5004 RTP/AVP 0 8 13 a=rtpmap:0 PCMU/8000 a=rtpmap:8 PCMA/8000 Aug 21 18:00:07 VERBOSE[1506]: 16 headers, 9 lines Aug 21 18:00:07 VERBOSE[1506]: Using latest request as basis request Aug 21 18:00:07 VERBOSE[1506]: Sending to 10.11.12.101 : 5060 (non-NAT) Aug 21 18:00:07 VERBOSE[1506]: Found peer 'mediatrix' Aug 21 18:00:07 DEBUG[1506]: Setting NAT on RTP to 0 Aug 21 18:00:07 VERBOSE[1506]: Found RTP audio format 0 Aug 21 18:00:07 VERBOSE[1506]: Found RTP audio format 8 Aug 21 18:00:07 VERBOSE[1506]: Found RTP audio format 13 Aug 21 18:00:07 VERBOSE[1506]: Peer audio RTP is at port 10.11.12.101:5004 Aug 21 18:00:07 DEBUG[1506]: Peer audio RTP is at port 10.11.12.101:5004 Aug 21 18:00:07 VERBOSE[1506]: Found description format PCMU Aug 21 18:00:07 VERBOSE[1506]: Found description format PCMA Aug 21 18:00:07 VERBOSE[1506]: Capabilities: us - 0xc (ulaw|alaw), peer - audio=0xc (ulaw|alaw)/video=0x0 (nothing), combined - 0xc (ulaw|alaw) Aug 21 18:00:07 VERBOSE[1506]: Non-codec capabilities: us - 0x1 (g723), peer - 0x2 (gsm), combined - 0x0 (nothing) Aug 21 18:00:07 DEBUG[1506]: Check for res for Aug 21 18:00:07 DEBUG[1506]: is not a local user Aug 21 18:00:07 VERBOSE[1506]: Looking for 1000 in from-sip-external Aug 21 18:00:07 DEBUG[1506]: build_route: Contact hop: Port 1 Aug 21 18:00:07 VERBOSE[1506]: list_route: hop: Aug 21 18:00:07 VERBOSE[1506]: Transmitting (no NAT): SIP/2.0 100 Trying Via: SIP/2.0/UDP 10.11.12.101;branch=z9hG4bK3272ce583 From: +17143316706 ;tag=4e0e967a6b8fecf To: sip:1000@10.11.12.22:5060 Call-ID: 36a72d23eabc7b8a62e6be9379c01789@10.11.12.22 CSeq: 505426505 INVITE User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER Contact: Content-Length: 0 to 10.11.12.101:5060 Aug 21 18:00:07 VERBOSE[1506]: -- Executing AbsoluteTimeout("SIP/5060-08b421d8", "15") in new stack Aug 21 18:00:07 VERBOSE[1506]: -- Set Absolute Timeout to 15 Aug 21 18:00:07 VERBOSE[1506]: -- Executing Congestion("SIP/5060-08b421d8", "") in new stack Aug 21 18:00:07 VERBOSE[1506]: Transmitting (no NAT): SIP/2.0 503 Service Unavailable Via: SIP/2.0/UDP 10.11.12.101;branch=z9hG4bK3272ce583 From: +17143316706 ;tag=4e0e967a6b8fecf To: sip:1000@10.11.12.22:5060;tag=as01c9cc47 Call-ID: 36a72d23eabc7b8a62e6be9379c01789@10.11.12.22 CSeq: 505426505 INVITE User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER Contact: Content-Length: 0 to 10.11.12.101:5060 Aug 21 18:00:07 VERBOSE[1506]: == Spawn extension (from-sip-external, 1000, 2) exited non-zero on 'SIP/5060-08b421d8' Aug 21 18:00:07 VERBOSE[1506]: -- Executing AbsoluteTimeout("SIP/5060-08b421d8", "15") in new stack Aug 21 18:00:07 VERBOSE[1506]: -- Set Absolute Timeout to 15 Aug 21 18:00:07 VERBOSE[1506]: -- Executing Congestion("SIP/5060-08b421d8", "") in new stack Aug 21 18:00:07 VERBOSE[1506]: == Spawn extension (from-sip-external, h, 2) exited non-zero on 'SIP/5060-08b421d8' Aug 21 18:00:07 DEBUG[1506]: cdr_mysql: inserting a CDR record. Aug 21 18:00:07 DEBUG[1506]: cdr_mysql: SQL command as follows: INSERT INTO cdr (calldate,clid,src,dst,dcontext,channel,dstchannel,lastapp,lastdata,duration,billsec,disposition,amaflags,accountcode) VALUES ('2005-08-21 18:00:07','\"+17143316706\" <7143316706>','7143316706','1000','from-sip-external', 'SIP/5060-08b421d8','','Congestion','',0,0,'NO ANSWER',3,'') Aug 21 18:00:07 DEBUG[1506]: update_user_counter() - decrement inUse counter Aug 21 18:00:07 DEBUG[1506]: is not a local user Aug 21 18:00:08 VERBOSE[1506]: Destroying call '36a72d23eabc7b8a62e6be9379c01789@10.11.12.22' Aug 21 18:00:20 VERBOSE[1506]: Sip read: CANCEL sip:1000@10.11.12.22:5060 SIP/2.0 Via: SIP/2.0/UDP 10.11.12.101;branch=z9hG4bK3272ce583 Content-Length: 0 To: sip:1000@10.11.12.22:5060 From: +17143316706 ;tag=4e0e967a6b8fecf Call-ID: 36a72d23eabc7b8a62e6be9379c01789@10.11.12.22 CSeq: 505426505 CANCEL Route: Supported: timer Supported: replaces User-Agent: MxSipApp/4.4.11.68 MxSF/v3.2.7.30 Aug 21 18:00:20 VERBOSE[1506]: 11 headers, 0 lines Aug 21 18:00:20 VERBOSE[1506]: Sending to 10.11.12.101 : 5060 (non-NAT) Aug 21 18:00:20 VERBOSE[1506]: Transmitting (no NAT): SIP/2.0 481 Call Leg Does Not Exist Via: SIP/2.0/UDP 10.11.12.101;branch=z9hG4bK3272ce583 From: +17143316706 ;tag=4e0e967a6b8fecf To: sip:1000@10.11.12.22:5060;tag=as6c97a1e9 Call-ID: 36a72d23eabc7b8a62e6be9379c01789@10.11.12.22 CSeq: 505426505 CANCEL User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER Contact: Content-Length: 0 to 10.11.12.101:5060 Aug 21 18:00:20 VERBOSE[1506]: Destroying call '36a72d23eabc7b8a62e6be9379c01789@10.11.12.22' Aug 21 18:00:20 VERBOSE[1506]: Destroying call '36a72d23eabc7b8a62e6be9379c01789@10.11.12.22'