[asterisk-users] help with dialplan

Todd Reese treese65 at gmail.com
Mon Aug 30 10:42:03 CDT 2010


  Here's a debug for extension 150



[Aug 30 11:34:53] VERBOSE[2099] config.c:   == Parsing 
'/etc/asterisk/logger.conf': [Aug 30 11:34:53] DEBUG[2099] config.c: 
Parsing /etc/asterisk/logger.conf
[Aug 30 11:34:53] VERBOSE[2099] config.c:   == Found
[Aug 30 11:34:53] VERBOSE[2099] logger.c:  Asterisk Event Logger restarted
[Aug 30 11:34:53] VERBOSE[2099] logger.c:  Asterisk Queue Logger restarted
[Aug 30 11:34:53] DEBUG[2079] chan_sip.c:  Header  0 [ 38]: OPTIONS 
sip:76.122.117.31:5060 SIP/2.0
[Aug 30 11:34:53] DEBUG[2079] chan_sip.c:  Header  1 [ 44]: Via: 
SIP/2.0/UDP 64.34.245.174:5060;branch=0
[Aug 30 11:34:53] DEBUG[2079] chan_sip.c:  Header  2 [ 38]: From: 
sip:pinger at voip.com;tag=7c9c6206
[Aug 30 11:34:53] DEBUG[2079] chan_sip.c:  Header  3 [ 26]: To: 
sip:76.122.117.31:5060
[Aug 30 11:34:53] DEBUG[2079] chan_sip.c:  Header  4 [ 47]: Call-ID: 
89c833e4-9c8f2516-5bb7e3 at 64.34.245.174
[Aug 30 11:34:53] DEBUG[2079] chan_sip.c:  Header  5 [ 15]: CSeq: 1 OPTIONS
[Aug 30 11:34:53] DEBUG[2079] chan_sip.c:  Header  6 [ 17]: 
Content-Length: 0
[Aug 30 11:34:53] DEBUG[2079] chan_sip.c:  Header  7 [  0]:
[Aug 30 11:34:53] DEBUG[2079] acl.c: Found IP address for this socket
[Aug 30 11:34:53] DEBUG[2079] chan_sip.c: Setting SIP_TRANSPORT_UDP with 
address 10.0.1.102:5060
[Aug 30 11:34:53] DEBUG[2079] chan_sip.c: Allocating new SIP dialog for 
89c833e4-9c8f2516-5bb7e3 at 64.34.245.174 - OPTIONS (No RTP)
[Aug 30 11:34:53] DEBUG[2079] chan_sip.c: **** Received OPTIONS (3) - 
Command in SIP OPTIONS
[Aug 30 11:34:53] DEBUG[2079] chan_sip.c: Trying to put 'SIP/2.0 404' 
onto UDP socket destined for 64.34.245.174:5060
[Aug 30 11:34:53] DEBUG[2079] chan_sip.c: SIP message could not be 
handled, bad request: 89c833e4-9c8f2516-5bb7e3 at 64.34.245.174
[Aug 30 11:34:53] DEBUG[2079] chan_sip.c:  Header  0 [ 38]: OPTIONS 
sip:76.122.117.31:5060 SIP/2.0
[Aug 30 11:34:53] DEBUG[2079] chan_sip.c:  Header  1 [ 44]: Via: 
SIP/2.0/UDP 64.34.245.174:5060;branch=0
[Aug 30 11:34:53] DEBUG[2079] chan_sip.c:  Header  2 [ 38]: From: 
sip:pinger at voip.com;tag=1f9c6206
[Aug 30 11:34:53] DEBUG[2079] chan_sip.c:  Header  3 [ 26]: To: 
sip:76.122.117.31:5060
[Aug 30 11:34:53] DEBUG[2079] chan_sip.c:  Header  4 [ 47]: Call-ID: 
89c833e4-3f8f2516-5bb7e3 at 64.34.245.174
[Aug 30 11:34:53] DEBUG[2079] chan_sip.c:  Header  5 [ 15]: CSeq: 1 OPTIONS
[Aug 30 11:34:53] DEBUG[2079] chan_sip.c:  Header  6 [ 17]: 
Content-Length: 0
[Aug 30 11:34:53] DEBUG[2079] chan_sip.c:  Header  7 [  0]:
[Aug 30 11:34:53] DEBUG[2079] acl.c: Found IP address for this socket
[Aug 30 11:34:53] DEBUG[2079] chan_sip.c: Setting SIP_TRANSPORT_UDP with 
address 10.0.1.102:5060
[Aug 30 11:34:53] DEBUG[2079] chan_sip.c: Allocating new SIP dialog for 
89c833e4-3f8f2516-5bb7e3 at 64.34.245.174 - OPTIONS (No RTP)
[Aug 30 11:34:53] DEBUG[2079] chan_sip.c: **** Received OPTIONS (3) - 
Command in SIP OPTIONS
[Aug 30 11:34:53] DEBUG[2079] chan_sip.c: Trying to put 'SIP/2.0 404' 
onto UDP socket destined for 64.34.245.174:5060
[Aug 30 11:34:53] DEBUG[2079] chan_sip.c: SIP message could not be 
handled, bad request: 89c833e4-3f8f2516-5bb7e3 at 64.34.245.174
[Aug 30 11:34:54] DEBUG[2079] chan_sip.c: Auto destroying SIP dialog 
'89c833e4-806e2516-79b7e3 at 64.34.245.174'
[Aug 30 11:34:54] DEBUG[2079] chan_sip.c: Destroying SIP dialog 
89c833e4-806e2516-79b7e3 at 64.34.245.174
[Aug 30 11:34:54] DEBUG[2079] chan_sip.c: Auto destroying SIP dialog 
'89c833e4-236e2516-79b7e3 at 64.34.245.174'
[Aug 30 11:34:54] DEBUG[2079] chan_sip.c: Destroying SIP dialog 
89c833e4-236e2516-79b7e3 at 64.34.245.174
[Aug 30 11:34:54] VERBOSE[2079] chan_sip.c:
<--- SIP read from UDP:97.80.176.231:5060 --->



<------------->
[Aug 30 11:34:54] DEBUG[2079] chan_sip.c:  Header  0 [  0]:
[Aug 30 11:34:54] DEBUG[2079] chan_sip.c:    Body  0 [  0]:
[Aug 30 11:34:54] VERBOSE[2079] chan_sip.c:
<--- SIP read from UDP:97.80.176.231:5060 --->
INVITE sip:6789542133 at qci.homeip.net SIP/2.0
Via: SIP/2.0/UDP 10.11.17.24:5060;branch=z9hG4bK82d40955a28df1af
From: "ATAP" <sip:150 at qci.homeip.net>;tag=ee0cedf5f71d40f9
To: <sip:6789542133 at qci.homeip.net>
Contact: <sip:150 at 10.11.17.24:5060;transport=udp>
Supported: replaces, timer, path
P-Early-Media: Supported
Call-ID: 62f35b2ee0ada782 at 10.11.17.24
CSeq: 21395 INVITE
User-Agent: Grandstream GXP2000 1.2.3.5
Max-Forwards: 70
Allow: 
INVITE,ACK,CANCEL,BYE,NOTIFY,REFER,OPTIONS,INFO,SUBSCRIBE,UPDATE,PRACK,MESSAGE
Content-Type: application/sdp
Content-Length: 345

v=0
o=150 8000 8000 IN IP4 10.11.17.24
s=SIP Call
c=IN IP4 10.11.17.24
t=0 0
m=audio 5050 RTP/AVP 0 8 4 18 2 97 9 3
a=sendrecv
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:4 G723/8000
a=rtpmap:18 G729/8000
a=rtpmap:2 G726-32/8000
a=rtpmap:97 iLBC/8000
a=fmtp:97 mode=20
a=rtpmap:9 G722/8000
a=rtpmap:3 GSM/8000
a=ptime:20

<------------->
[Aug 30 11:34:54] DEBUG[2079] chan_sip.c:  Header  0 [ 44]: INVITE 
sip:6789542133 at qci.homeip.net SIP/2.0
[Aug 30 11:34:54] DEBUG[2079] chan_sip.c:  Header  1 [ 64]: Via: 
SIP/2.0/UDP 10.11.17.24:5060;branch=z9hG4bK82d40955a28df1af
[Aug 30 11:34:54] DEBUG[2079] chan_sip.c:  Header  2 [ 58]: From: "ATAP" 
<sip:150 at qci.homeip.net>;tag=ee0cedf5f71d40f9
[Aug 30 11:34:54] DEBUG[2079] chan_sip.c:  Header  3 [ 35]: To: 
<sip:6789542133 at qci.homeip.net>
[Aug 30 11:34:54] DEBUG[2079] chan_sip.c:  Header  4 [ 49]: Contact: 
<sip:150 at 10.11.17.24:5060;transport=udp>
[Aug 30 11:34:54] DEBUG[2079] chan_sip.c:  Header  5 [ 32]: Supported: 
replaces, timer, path
[Aug 30 11:34:54] DEBUG[2079] chan_sip.c:  Header  6 [ 24]: 
P-Early-Media: Supported
[Aug 30 11:34:54] DEBUG[2079] chan_sip.c:  Header  7 [ 37]: Call-ID: 
62f35b2ee0ada782 at 10.11.17.24
[Aug 30 11:34:54] DEBUG[2079] chan_sip.c:  Header  8 [ 18]: CSeq: 21395 
INVITE
[Aug 30 11:34:54] DEBUG[2079] chan_sip.c:  Header  9 [ 39]: User-Agent: 
Grandstream GXP2000 1.2.3.5
[Aug 30 11:34:54] DEBUG[2079] chan_sip.c:  Header 10 [ 16]: Max-Forwards: 70
[Aug 30 11:34:54] DEBUG[2079] chan_sip.c:  Header 11 [ 85]: Allow: 
INVITE,ACK,CANCEL,BYE,NOTIFY,REFER,OPTIONS,INFO,SUBSCRIBE,UPDATE,PRACK,MESSAGE
[Aug 30 11:34:54] DEBUG[2079] chan_sip.c:  Header 12 [ 29]: 
Content-Type: application/sdp
[Aug 30 11:34:54] DEBUG[2079] chan_sip.c:  Header 13 [ 19]: 
Content-Length: 345
[Aug 30 11:34:54] DEBUG[2079] chan_sip.c:  Header 14 [  0]:
[Aug 30 11:34:54] DEBUG[2079] chan_sip.c:    Body  0 [  3]: v=0
[Aug 30 11:34:54] DEBUG[2079] chan_sip.c:    Body  1 [ 34]: o=150 8000 
8000 IN IP4 10.11.17.24
[Aug 30 11:34:54] DEBUG[2079] chan_sip.c:    Body  2 [ 10]: s=SIP Call
[Aug 30 11:34:54] DEBUG[2079] chan_sip.c:    Body  3 [ 20]: c=IN IP4 
10.11.17.24
[Aug 30 11:34:54] DEBUG[2079] chan_sip.c:    Body  4 [  5]: t=0 0
[Aug 30 11:34:54] DEBUG[2079] chan_sip.c:    Body  5 [ 38]: m=audio 5050 
RTP/AVP 0 8 4 18 2 97 9 3
[Aug 30 11:34:54] DEBUG[2079] chan_sip.c:    Body  6 [ 10]: a=sendrecv
[Aug 30 11:34:54] DEBUG[2079] chan_sip.c:    Body  7 [ 20]: a=rtpmap:0 
PCMU/8000
[Aug 30 11:34:54] DEBUG[2079] chan_sip.c:    Body  8 [ 20]: a=rtpmap:8 
PCMA/8000
[Aug 30 11:34:54] DEBUG[2079] chan_sip.c:    Body  9 [ 20]: a=rtpmap:4 
G723/8000
[Aug 30 11:34:54] DEBUG[2079] chan_sip.c:    Body 10 [ 21]: a=rtpmap:18 
G729/8000
[Aug 30 11:34:54] DEBUG[2079] chan_sip.c:    Body 11 [ 23]: a=rtpmap:2 
G726-32/8000
[Aug 30 11:34:54] DEBUG[2079] chan_sip.c:    Body 12 [ 21]: a=rtpmap:97 
iLBC/8000
[Aug 30 11:34:54] DEBUG[2079] chan_sip.c:    Body 13 [ 17]: a=fmtp:97 
mode=20
[Aug 30 11:34:54] DEBUG[2079] chan_sip.c:    Body 14 [ 20]: a=rtpmap:9 
G722/8000
[Aug 30 11:34:54] DEBUG[2079] chan_sip.c:    Body 15 [ 19]: a=rtpmap:3 
GSM/8000
[Aug 30 11:34:54] DEBUG[2079] chan_sip.c:    Body 16 [ 10]: a=ptime:20
[Aug 30 11:34:54] VERBOSE[2079] chan_sip.c: --- (14 headers 17 lines) ---
[Aug 30 11:34:54] DEBUG[2079] acl.c: Found IP address for this socket
[Aug 30 11:34:54] DEBUG[2079] chan_sip.c: Setting SIP_TRANSPORT_UDP with 
address 10.0.1.102:5060
[Aug 30 11:34:54] VERBOSE[2079] netsock.c:   == Using SIP RTP TOS bits 184
[Aug 30 11:34:54] VERBOSE[2079] netsock.c:   == Using SIP RTP CoS mark 5
[Aug 30 11:34:54] DEBUG[2079] chan_sip.c: Setting NAT on RTP to On
[Aug 30 11:34:54] DEBUG[2079] chan_sip.c: Allocating new SIP dialog for 
62f35b2ee0ada782 at 10.11.17.24 - INVITE (With RTP)
[Aug 30 11:34:54] DEBUG[2079] chan_sip.c: **** Received INVITE (5) - 
Command in SIP INVITE
[Aug 30 11:34:54] DEBUG[2079] chan_sip.c: Begin: parsing SIP "Supported: 
replaces, timer, path"
[Aug 30 11:34:54] DEBUG[2079] chan_sip.c: Found SIP option: -replaces-
[Aug 30 11:34:54] DEBUG[2079] chan_sip.c: Matched SIP option: replaces
[Aug 30 11:34:54] DEBUG[2079] chan_sip.c: Found SIP option: -timer-
[Aug 30 11:34:54] DEBUG[2079] chan_sip.c: Matched SIP option: timer
[Aug 30 11:34:54] DEBUG[2079] chan_sip.c: Found SIP option: -path-
[Aug 30 11:34:54] DEBUG[2079] chan_sip.c: Matched SIP option: path
[Aug 30 11:34:54] VERBOSE[2079] chan_sip.c: Sending to 97.80.176.231 : 
5060 (NAT)
[Aug 30 11:34:54] DEBUG[2079] chan_sip.c: Initializing initreq for 
method INVITE - callid 62f35b2ee0ada782 at 10.11.17.24
[Aug 30 11:34:54] VERBOSE[2079] chan_sip.c: Using INVITE request as 
basis request - 62f35b2ee0ada782 at 10.11.17.24
[Aug 30 11:34:54] VERBOSE[2079] chan_sip.c: Found peer '150' for '150' 
from 97.80.176.231:5060
[Aug 30 11:34:54] DEBUG[2079] chan_sip.c: Setting NAT on RTP to On
[Aug 30 11:34:54] VERBOSE[2079] chan_sip.c:
<--- Reliably Transmitting (NAT) to 97.80.176.231:5060 --->
SIP/2.0 401 Unauthorized
Via: SIP/2.0/UDP 
10.11.17.24:5060;branch=z9hG4bK82d40955a28df1af;received=97.80.176.231
From: "ATAP" <sip:150 at qci.homeip.net>;tag=ee0cedf5f71d40f9
To: <sip:6789542133 at qci.homeip.net>;tag=as277ae45d
Call-ID: 62f35b2ee0ada782 at 10.11.17.24
CSeq: 21395 INVITE
Server: Asterisk PBX 1.6.2.11
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO
Supported: replaces, timer
WWW-Authenticate: Digest algorithm=MD5, realm="asterisk", nonce="449798ce"
Content-Length: 0


<------------>
[Aug 30 11:34:54] DEBUG[2079] chan_sip.c: *** SIP TIMER: Initializing 
retransmit timer on packet: Id  #1721
[Aug 30 11:34:54] DEBUG[2079] chan_sip.c: Trying to put 'SIP/2.0 401' 
onto UDP socket destined for 97.80.176.231:5060
[Aug 30 11:34:54] VERBOSE[2079] chan_sip.c: Scheduling destruction of 
SIP dialog '62f35b2ee0ada782 at 10.11.17.24' in 6400 ms (Method: INVITE)
[Aug 30 11:34:55] VERBOSE[2079] chan_sip.c:
<--- SIP read from UDP:97.80.176.231:5060 --->
ACK sip:6789542133 at qci.homeip.net SIP/2.0
Via: SIP/2.0/UDP 10.11.17.24:5060;branch=z9hG4bK82d40955a28df1af
From: "ATAP" <sip:150 at qci.homeip.net>;tag=ee0cedf5f71d40f9
To: <sip:6789542133 at qci.homeip.net>;tag=as277ae45d
Contact: <sip:150 at 10.11.17.24:5060;transport=udp>
Supported: path
Call-ID: 62f35b2ee0ada782 at 10.11.17.24
CSeq: 21395 ACK
User-Agent: Grandstream GXP2000 1.2.3.5
Max-Forwards: 70
Allow: 
INVITE,ACK,CANCEL,BYE,NOTIFY,REFER,OPTIONS,INFO,SUBSCRIBE,UPDATE,PRACK,MESSAGE
Content-Length: 0


<------------->
[Aug 30 11:34:55] DEBUG[2079] chan_sip.c:  Header  0 [ 41]: ACK 
sip:6789542133 at qci.homeip.net SIP/2.0
[Aug 30 11:34:55] DEBUG[2079] chan_sip.c:  Header  1 [ 64]: Via: 
SIP/2.0/UDP 10.11.17.24:5060;branch=z9hG4bK82d40955a28df1af
[Aug 30 11:34:55] DEBUG[2079] chan_sip.c:  Header  2 [ 58]: From: "ATAP" 
<sip:150 at qci.homeip.net>;tag=ee0cedf5f71d40f9
[Aug 30 11:34:55] DEBUG[2079] chan_sip.c:  Header  3 [ 50]: To: 
<sip:6789542133 at qci.homeip.net>;tag=as277ae45d
[Aug 30 11:34:55] DEBUG[2079] chan_sip.c:  Header  4 [ 49]: Contact: 
<sip:150 at 10.11.17.24:5060;transport=udp>
[Aug 30 11:34:55] DEBUG[2079] chan_sip.c:  Header  5 [ 15]: Supported: path
[Aug 30 11:34:55] DEBUG[2079] chan_sip.c:  Header  6 [ 37]: Call-ID: 
62f35b2ee0ada782 at 10.11.17.24
[Aug 30 11:34:55] DEBUG[2079] chan_sip.c:  Header  7 [ 15]: CSeq: 21395 ACK
[Aug 30 11:34:55] DEBUG[2079] chan_sip.c:  Header  8 [ 39]: User-Agent: 
Grandstream GXP2000 1.2.3.5
[Aug 30 11:34:55] DEBUG[2079] chan_sip.c:  Header  9 [ 16]: Max-Forwards: 70
[Aug 30 11:34:55] DEBUG[2079] chan_sip.c:  Header 10 [ 85]: Allow: 
INVITE,ACK,CANCEL,BYE,NOTIFY,REFER,OPTIONS,INFO,SUBSCRIBE,UPDATE,PRACK,MESSAGE
[Aug 30 11:34:55] DEBUG[2079] chan_sip.c:  Header 11 [ 17]: 
Content-Length: 0
[Aug 30 11:34:55] DEBUG[2079] chan_sip.c:  Header 12 [  0]:
[Aug 30 11:34:55] VERBOSE[2079] chan_sip.c: --- (12 headers 0 lines) ---
[Aug 30 11:34:55] DEBUG[2079] chan_sip.c: **** Received ACK (6) - 
Command in SIP ACK
[Aug 30 11:34:55] DEBUG[2079] chan_sip.c: ** SIP TIMER: Cancelling 
retransmit of packet (reply received) Retransid #1721
[Aug 30 11:34:55] DEBUG[2079] chan_sip.c: Stopping retransmission on 
'62f35b2ee0ada782 at 10.11.17.24' of Response 21395: Match Found
[Aug 30 11:34:55] VERBOSE[2079] chan_sip.c:
<--- SIP read from UDP:97.80.176.231:5060 --->
INVITE sip:6789542133 at qci.homeip.net SIP/2.0
Via: SIP/2.0/UDP 10.11.17.24:5060;branch=z9hG4bK45bf7291c591a19a
From: "ATAP" <sip:150 at qci.homeip.net>;tag=ee0cedf5f71d40f9
To: <sip:6789542133 at qci.homeip.net>
Contact: <sip:150 at 10.11.17.24:5060;transport=udp>
Supported: replaces, timer, path
P-Early-Media: Supported
Authorization: Digest username="150", realm="asterisk", algorithm=MD5, 
uri="sip:6789542133 at qci.homeip.net", nonce="449798ce", 
response="237f1b41f316074f60903086366682b4"
Call-ID: 62f35b2ee0ada782 at 10.11.17.24
CSeq: 21396 INVITE
User-Agent: Grandstream GXP2000 1.2.3.5
Max-Forwards: 70
Allow: 
INVITE,ACK,CANCEL,BYE,NOTIFY,REFER,OPTIONS,INFO,SUBSCRIBE,UPDATE,PRACK,MESSAGE
Content-Type: application/sdp
Content-Length: 345

v=0
o=150 8000 8001 IN IP4 10.11.17.24
s=SIP Call
c=IN IP4 10.11.17.24
t=0 0
m=audio 5050 RTP/AVP 0 8 4 18 2 97 9 3
a=sendrecv
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:4 G723/8000
a=rtpmap:18 G729/8000
a=rtpmap:2 G726-32/8000
a=rtpmap:97 iLBC/8000
a=fmtp:97 mode=20
a=rtpmap:9 G722/8000
a=rtpmap:3 GSM/8000
a=ptime:20

<------------->
[Aug 30 11:34:55] DEBUG[2079] chan_sip.c:  Header  0 [ 44]: INVITE 
sip:6789542133 at qci.homeip.net SIP/2.0
[Aug 30 11:34:55] DEBUG[2079] chan_sip.c:  Header  1 [ 64]: Via: 
SIP/2.0/UDP 10.11.17.24:5060;branch=z9hG4bK45bf7291c591a19a
[Aug 30 11:34:55] DEBUG[2079] chan_sip.c:  Header  2 [ 58]: From: "ATAP" 
<sip:150 at qci.homeip.net>;tag=ee0cedf5f71d40f9
[Aug 30 11:34:55] DEBUG[2079] chan_sip.c:  Header  3 [ 35]: To: 
<sip:6789542133 at qci.homeip.net>
[Aug 30 11:34:55] DEBUG[2079] chan_sip.c:  Header  4 [ 49]: Contact: 
<sip:150 at 10.11.17.24:5060;transport=udp>
[Aug 30 11:34:55] DEBUG[2079] chan_sip.c:  Header  5 [ 32]: Supported: 
replaces, timer, path
[Aug 30 11:34:55] DEBUG[2079] chan_sip.c:  Header  6 [ 24]: 
P-Early-Media: Supported
[Aug 30 11:34:55] DEBUG[2079] chan_sip.c:  Header  7 [169]: 
Authorization: Digest username="150", realm="asterisk", algorithm=MD5, 
uri="sip:6789542133 at qci.homeip.net", nonce="449798ce", 
response="237f1b41f316074f60903086366682b4"
[Aug 30 11:34:55] DEBUG[2079] chan_sip.c:  Header  8 [ 37]: Call-ID: 
62f35b2ee0ada782 at 10.11.17.24
[Aug 30 11:34:55] DEBUG[2079] chan_sip.c:  Header  9 [ 18]: CSeq: 21396 
INVITE
[Aug 30 11:34:55] DEBUG[2079] chan_sip.c:  Header 10 [ 39]: User-Agent: 
Grandstream GXP2000 1.2.3.5
[Aug 30 11:34:55] DEBUG[2079] chan_sip.c:  Header 11 [ 16]: Max-Forwards: 70
[Aug 30 11:34:55] DEBUG[2079] chan_sip.c:  Header 12 [ 85]: Allow: 
INVITE,ACK,CANCEL,BYE,NOTIFY,REFER,OPTIONS,INFO,SUBSCRIBE,UPDATE,PRACK,MESSAGE
[Aug 30 11:34:55] DEBUG[2079] chan_sip.c:  Header 13 [ 29]: 
Content-Type: application/sdp
[Aug 30 11:34:55] DEBUG[2079] chan_sip.c:  Header 14 [ 19]: 
Content-Length: 345
[Aug 30 11:34:55] DEBUG[2079] chan_sip.c:  Header 15 [  0]:
[Aug 30 11:34:55] DEBUG[2079] chan_sip.c:    Body  0 [  3]: v=0
[Aug 30 11:34:55] DEBUG[2079] chan_sip.c:    Body  1 [ 34]: o=150 8000 
8001 IN IP4 10.11.17.24
[Aug 30 11:34:55] DEBUG[2079] chan_sip.c:    Body  2 [ 10]: s=SIP Call
[Aug 30 11:34:55] DEBUG[2079] chan_sip.c:    Body  3 [ 20]: c=IN IP4 
10.11.17.24
[Aug 30 11:34:55] DEBUG[2079] chan_sip.c:    Body  4 [  5]: t=0 0
[Aug 30 11:34:55] DEBUG[2079] chan_sip.c:    Body  5 [ 38]: m=audio 5050 
RTP/AVP 0 8 4 18 2 97 9 3
[Aug 30 11:34:55] DEBUG[2079] chan_sip.c:    Body  6 [ 10]: a=sendrecv
[Aug 30 11:34:55] DEBUG[2079] chan_sip.c:    Body  7 [ 20]: a=rtpmap:0 
PCMU/8000
[Aug 30 11:34:55] DEBUG[2079] chan_sip.c:    Body  8 [ 20]: a=rtpmap:8 
PCMA/8000
[Aug 30 11:34:55] DEBUG[2079] chan_sip.c:    Body  9 [ 20]: a=rtpmap:4 
G723/8000
[Aug 30 11:34:55] DEBUG[2079] chan_sip.c:    Body 10 [ 21]: a=rtpmap:18 
G729/8000
[Aug 30 11:34:55] DEBUG[2079] chan_sip.c:    Body 11 [ 23]: a=rtpmap:2 
G726-32/8000
[Aug 30 11:34:55] DEBUG[2079] chan_sip.c:    Body 12 [ 21]: a=rtpmap:97 
iLBC/8000
[Aug 30 11:34:55] DEBUG[2079] chan_sip.c:    Body 13 [ 17]: a=fmtp:97 
mode=20
[Aug 30 11:34:55] DEBUG[2079] chan_sip.c:    Body 14 [ 20]: a=rtpmap:9 
G722/8000
[Aug 30 11:34:55] DEBUG[2079] chan_sip.c:    Body 15 [ 19]: a=rtpmap:3 
GSM/8000
[Aug 30 11:34:55] DEBUG[2079] chan_sip.c:    Body 16 [ 10]: a=ptime:20
[Aug 30 11:34:55] VERBOSE[2079] chan_sip.c: --- (15 headers 17 lines) ---
[Aug 30 11:34:55] DEBUG[2079] chan_sip.c: **** Received INVITE (5) - 
Command in SIP INVITE
[Aug 30 11:34:55] VERBOSE[2079] chan_sip.c: Sending to 97.80.176.231 : 
5060 (NAT)
[Aug 30 11:34:55] DEBUG[2079] chan_sip.c: Initializing initreq for 
method INVITE - callid 62f35b2ee0ada782 at 10.11.17.24
[Aug 30 11:34:55] VERBOSE[2079] chan_sip.c: Using INVITE request as 
basis request - 62f35b2ee0ada782 at 10.11.17.24
[Aug 30 11:34:55] VERBOSE[2079] chan_sip.c: Found peer '150' for '150' 
from 97.80.176.231:5060
[Aug 30 11:34:55] DEBUG[2079] chan_sip.c: Setting NAT on RTP to On
[Aug 30 11:34:55] DEBUG[2079] chan_sip.c: Processing session-level SDP 
v=0... UNSUPPORTED.
[Aug 30 11:34:55] DEBUG[2079] chan_sip.c: Processing session-level SDP 
o=150 8000 8001 IN IP4 10.11.17.24... UNSUPPORTED.
[Aug 30 11:34:55] DEBUG[2079] chan_sip.c: Processing session-level SDP 
s=SIP Call... UNSUPPORTED.
[Aug 30 11:34:55] DEBUG[2079] chan_sip.c: Processing session-level SDP 
c=IN IP4 10.11.17.24... OK.
[Aug 30 11:34:55] DEBUG[2079] chan_sip.c: Processing session-level SDP 
t=0 0... UNSUPPORTED.
[Aug 30 11:34:55] VERBOSE[2079] chan_sip.c: Found RTP audio format 0
[Aug 30 11:34:55] VERBOSE[2079] chan_sip.c: Found RTP audio format 8
[Aug 30 11:34:55] VERBOSE[2079] chan_sip.c: Found RTP audio format 4
[Aug 30 11:34:55] VERBOSE[2079] chan_sip.c: Found RTP audio format 18
[Aug 30 11:34:55] VERBOSE[2079] chan_sip.c: Found RTP audio format 2
[Aug 30 11:34:55] VERBOSE[2079] chan_sip.c: Found RTP audio format 97
[Aug 30 11:34:55] VERBOSE[2079] chan_sip.c: Found RTP audio format 9
[Aug 30 11:34:55] VERBOSE[2079] chan_sip.c: Found RTP audio format 3
[Aug 30 11:34:55] DEBUG[2079] chan_sip.c: Processing media-level (audio) 
SDP a=sendrecv... OK.
[Aug 30 11:34:55] VERBOSE[2079] chan_sip.c: Found audio description 
format PCMU for ID 0
[Aug 30 11:34:55] DEBUG[2079] chan_sip.c: Processing media-level (audio) 
SDP a=rtpmap:0 PCMU/8000... OK.
[Aug 30 11:34:55] VERBOSE[2079] chan_sip.c: Found audio description 
format PCMA for ID 8
[Aug 30 11:34:55] DEBUG[2079] chan_sip.c: Processing media-level (audio) 
SDP a=rtpmap:8 PCMA/8000... OK.
[Aug 30 11:34:55] VERBOSE[2079] chan_sip.c: Found audio description 
format G723 for ID 4
[Aug 30 11:34:55] DEBUG[2079] chan_sip.c: Processing media-level (audio) 
SDP a=rtpmap:4 G723/8000... OK.
[Aug 30 11:34:55] VERBOSE[2079] chan_sip.c: Found audio description 
format G729 for ID 18
[Aug 30 11:34:55] DEBUG[2079] chan_sip.c: Processing media-level (audio) 
SDP a=rtpmap:18 G729/8000... OK.
[Aug 30 11:34:55] VERBOSE[2079] chan_sip.c: Found audio description 
format G726-32 for ID 2
[Aug 30 11:34:55] DEBUG[2079] chan_sip.c: Processing media-level (audio) 
SDP a=rtpmap:2 G726-32/8000... OK.
[Aug 30 11:34:55] VERBOSE[2079] chan_sip.c: Found audio description 
format iLBC for ID 97
[Aug 30 11:34:55] DEBUG[2079] chan_sip.c: Processing media-level (audio) 
SDP a=rtpmap:97 iLBC/8000... OK.
[Aug 30 11:34:55] DEBUG[2079] chan_sip.c: Processing media-level (audio) 
SDP a=fmtp:97 mode=20... UNSUPPORTED.
[Aug 30 11:34:55] VERBOSE[2079] chan_sip.c: Found audio description 
format G722 for ID 9
[Aug 30 11:34:55] DEBUG[2079] chan_sip.c: Processing media-level (audio) 
SDP a=rtpmap:9 G722/8000... OK.
[Aug 30 11:34:55] VERBOSE[2079] chan_sip.c: Found audio description 
format GSM for ID 3
[Aug 30 11:34:55] DEBUG[2079] chan_sip.c: Processing media-level (audio) 
SDP a=rtpmap:3 GSM/8000... OK.
[Aug 30 11:34:55] DEBUG[2079] chan_sip.c: Processing media-level (audio) 
SDP a=ptime:20... OK.
[Aug 30 11:34:55] VERBOSE[2079] chan_sip.c: Capabilities: us - 0x4 
(ulaw), peer - audio=0x1d0f 
(g723|gsm|ulaw|alaw|g726|g729|ilbc|g722)/video=0x0 (nothing)/text=0x0 
(nothing), combined - 0x4 (ulaw)
[Aug 30 11:34:55] VERBOSE[2079] chan_sip.c: Non-codec capabilities 
(dtmf): us - 0x1 (telephone-event), peer - 0x0 (nothing), combined - 0x0 
(nothing)
[Aug 30 11:34:55] VERBOSE[2079] chan_sip.c: Peer audio RTP is at port 
10.11.17.24:5050
[Aug 30 11:34:55] DEBUG[2079] chan_sip.c: We're settling with these 
formats: 0x4 (ulaw)
[Aug 30 11:34:55] DEBUG[2079] chan_sip.c: Checking SIP call limits for 
device 150
[Aug 30 11:34:55] DEBUG[2079] chan_sip.c: Updating call counter for 
incoming call
[Aug 30 11:34:55] DEBUG[2079] chan_sip.c: Call from peer '150' is 1 out 
of 50
[Aug 30 11:34:55] VERBOSE[2079] chan_sip.c: Looking for 6789542133 in 
extensions.conf (domain qci.homeip.net)
[Aug 30 11:34:55] DEBUG[2065] devicestate.c: No provider found, checking 
channel drivers for SIP - 150
[Aug 30 11:34:55] DEBUG[2065] chan_sip.c: Checking device state for peer 150
[Aug 30 11:34:55] DEBUG[2065] devicestate.c: Changing state for SIP/150 
- state 2 (In use)
[Aug 30 11:34:55] DEBUG[2065] devicestate.c: device 'SIP/150' state '2'
[Aug 30 11:34:55] VERBOSE[2079] chan_sip.c:
<--- Reliably Transmitting (NAT) to 97.80.176.231:5060 --->
SIP/2.0 404 Not Found
Via: SIP/2.0/UDP 
10.11.17.24:5060;branch=z9hG4bK45bf7291c591a19a;received=97.80.176.231
From: "ATAP" <sip:150 at qci.homeip.net>;tag=ee0cedf5f71d40f9
To: <sip:6789542133 at qci.homeip.net>;tag=as277ae45d
Call-ID: 62f35b2ee0ada782 at 10.11.17.24
CSeq: 21396 INVITE
Server: Asterisk PBX 1.6.2.11
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO
Supported: replaces, timer
Content-Length: 0


<------------>
[Aug 30 11:34:55] DEBUG[2079] chan_sip.c: *** SIP TIMER: Initializing 
retransmit timer on packet: Id  #1723
[Aug 30 11:34:55] DEBUG[2079] chan_sip.c: Trying to put 'SIP/2.0 404' 
onto UDP socket destined for 97.80.176.231:5060
[Aug 30 11:34:55] DEBUG[2074] app_queue.c: Device 'SIP/150' changed to 
state '2' (In use) but we don't care because they're not a member of any 
queue.
[Aug 30 11:34:55] NOTICE[2079] chan_sip.c: Call from '150' to extension 
'6789542133' rejected because extension not found in context 
'extensions.conf'.
[Aug 30 11:34:55] DEBUG[2079] chan_sip.c: Updating call counter for 
incoming call
[Aug 30 11:34:55] DEBUG[2079] chan_sip.c: Call from peer '150' removed 
from call limit 50
[Aug 30 11:34:55] DEBUG[2065] devicestate.c: No provider found, checking 
channel drivers for SIP - 150
[Aug 30 11:34:55] VERBOSE[2079] chan_sip.c: Scheduling destruction of 
SIP dialog '62f35b2ee0ada782 at 10.11.17.24' in 6400 ms (Method: INVITE)
[Aug 30 11:34:55] DEBUG[2065] chan_sip.c: Checking device state for peer 150
[Aug 30 11:34:55] DEBUG[2065] devicestate.c: Changing state for SIP/150 
- state 1 (Not in use)
[Aug 30 11:34:55] DEBUG[2065] devicestate.c: device 'SIP/150' state '1'
[Aug 30 11:34:55] DEBUG[2074] app_queue.c: Device 'SIP/150' changed to 
state '1' (Not in use) but we don't care because they're not a member of 
any queue.
[Aug 30 11:34:55] VERBOSE[2079] chan_sip.c:
<--- SIP read from UDP:97.80.176.231:5060 --->
ACK sip:6789542133 at qci.homeip.net SIP/2.0
Via: SIP/2.0/UDP 10.11.17.24:5060;branch=z9hG4bK45bf7291c591a19a
From: "ATAP" <sip:150 at qci.homeip.net>;tag=ee0cedf5f71d40f9
To: <sip:6789542133 at qci.homeip.net>;tag=as277ae45d
Contact: <sip:150 at 10.11.17.24:5060;transport=udp>
Supported: path
Authorization: Digest username="150", realm="asterisk", algorithm=MD5, 
uri="sip:6789542133 at qci.homeip.net", nonce="449798ce", 
response="237f1b41f316074f60903086366682b4"
Call-ID: 62f35b2ee0ada782 at 10.11.17.24
CSeq: 21396 ACK
User-Agent: Grandstream GXP2000 1.2.3.5
Max-Forwards: 70
Allow: 
INVITE,ACK,CANCEL,BYE,NOTIFY,REFER,OPTIONS,INFO,SUBSCRIBE,UPDATE,PRACK,MESSAGE
Content-Length: 0


<------------->
[Aug 30 11:34:55] DEBUG[2079] chan_sip.c:  Header  0 [ 41]: ACK 
sip:6789542133 at qci.homeip.net SIP/2.0
[Aug 30 11:34:55] DEBUG[2079] chan_sip.c:  Header  1 [ 64]: Via: 
SIP/2.0/UDP 10.11.17.24:5060;branch=z9hG4bK45bf7291c591a19a
[Aug 30 11:34:55] DEBUG[2079] chan_sip.c:  Header  2 [ 58]: From: "ATAP" 
<sip:150 at qci.homeip.net>;tag=ee0cedf5f71d40f9
[Aug 30 11:34:55] DEBUG[2079] chan_sip.c:  Header  3 [ 50]: To: 
<sip:6789542133 at qci.homeip.net>;tag=as277ae45d
[Aug 30 11:34:55] DEBUG[2079] chan_sip.c:  Header  4 [ 49]: Contact: 
<sip:150 at 10.11.17.24:5060;transport=udp>
[Aug 30 11:34:55] DEBUG[2079] chan_sip.c:  Header  5 [ 15]: Supported: path
[Aug 30 11:34:55] DEBUG[2079] chan_sip.c:  Header  6 [169]: 
Authorization: Digest username="150", realm="asterisk", algorithm=MD5, 
uri="sip:6789542133 at qci.homeip.net", nonce="449798ce", 
response="237f1b41f316074f60903086366682b4"
[Aug 30 11:34:55] DEBUG[2079] chan_sip.c:  Header  7 [ 37]: Call-ID: 
62f35b2ee0ada782 at 10.11.17.24
[Aug 30 11:34:55] DEBUG[2079] chan_sip.c:  Header  8 [ 15]: CSeq: 21396 ACK
[Aug 30 11:34:55] DEBUG[2079] chan_sip.c:  Header  9 [ 39]: User-Agent: 
Grandstream GXP2000 1.2.3.5
[Aug 30 11:34:55] DEBUG[2079] chan_sip.c:  Header 10 [ 16]: Max-Forwards: 70
[Aug 30 11:34:55] DEBUG[2079] chan_sip.c:  Header 11 [ 85]: Allow: 
INVITE,ACK,CANCEL,BYE,NOTIFY,REFER,OPTIONS,INFO,SUBSCRIBE,UPDATE,PRACK,MESSAGE
[Aug 30 11:34:55] DEBUG[2079] chan_sip.c:  Header 12 [ 17]: 
Content-Length: 0
[Aug 30 11:34:55] DEBUG[2079] chan_sip.c:  Header 13 [  0]:
[Aug 30 11:34:55] VERBOSE[2079] chan_sip.c: --- (13 headers 0 lines) ---
[Aug 30 11:34:55] DEBUG[2079] chan_sip.c: **** Received ACK (6) - 
Command in SIP ACK
[Aug 30 11:34:55] DEBUG[2079] chan_sip.c: ** SIP TIMER: Cancelling 
retransmit of packet (reply received) Retransid #1723
[Aug 30 11:34:55] DEBUG[2079] chan_sip.c: Stopping retransmission on 
'62f35b2ee0ada782 at 10.11.17.24' of Response 21396: Match Found
[Aug 30 11:35:01] DEBUG[2079] chan_sip.c: Auto destroying SIP dialog 
'447f358d384b5f3d194e157e19053d7d at 10.0.1.102'
[Aug 30 11:35:01] DEBUG[2079] chan_sip.c: Destroying SIP dialog 
447f358d384b5f3d194e157e19053d7d at 10.0.1.102
[Aug 30 11:35:01] DEBUG[2079] chan_sip.c: Auto destroying SIP dialog 
'08bfa4c101c769a876067caa779efca5 at 10.0.1.102'
[Aug 30 11:35:01] DEBUG[2079] chan_sip.c: Destroying SIP dialog 
08bfa4c101c769a876067caa779efca5 at 10.0.1.102
[Aug 30 11:35:01] DEBUG[2079] chan_sip.c: Auto destroying SIP dialog 
'66cafdb06008db6d566d39fc3477a659 at 10.0.1.102'
[Aug 30 11:35:01] DEBUG[2079] chan_sip.c: Destroying SIP dialog 
66cafdb06008db6d566d39fc3477a659 at 10.0.1.102
[Aug 30 11:35:01] DEBUG[2079] chan_sip.c: Auto destroying SIP dialog 
'1fe133904ae2c3f9229778514394e48d at 10.0.1.102'
[Aug 30 11:35:01] DEBUG[2079] chan_sip.c: Destroying SIP dialog 
1fe133904ae2c3f9229778514394e48d at 10.0.1.102
[Aug 30 11:35:01] DEBUG[2079] chan_sip.c: Auto destroying SIP dialog 
'62f35b2ee0ada782 at 10.11.17.24'
[Aug 30 11:35:01] DEBUG[2079] chan_sip.c: Destroying SIP dialog 
62f35b2ee0ada782 at 10.11.17.24
[Aug 30 11:35:01] VERBOSE[2079] chan_sip.c: Really destroying SIP dialog 
'62f35b2ee0ada782 at 10.11.17.24' Method: ACK
[Aug 30 11:35:01] DEBUG[2079] chan_sip.c: Auto destroying SIP dialog 
'05b171257bbe690373de757163fdbb6c at 10.0.1.102'
[Aug 30 11:35:01] DEBUG[2079] chan_sip.c: Destroying SIP dialog 
05b171257bbe690373de757163fdbb6c at 10.0.1.102
[Aug 30 11:35:01] DEBUG[2079] chan_sip.c: Auto destroying SIP dialog 
'48d5fa817d96bb632813ff2943107888 at 10.0.1.102'
[Aug 30 11:35:01] DEBUG[2079] chan_sip.c: Destroying SIP dialog 
48d5fa817d96bb632813ff2943107888 at 10.0.1.102
[Aug 30 11:35:11] DEBUG[2079] chan_sip.c: Allocating new SIP dialog for 
01c252e3142ee4a01cffcb0a3f854026 at 10.0.1.102 - OPTIONS (No RTP)
[Aug 30 11:35:11] DEBUG[2079] acl.c: Found IP address for this socket
[Aug 30 11:35:11] DEBUG[2079] chan_sip.c: Setting SIP_TRANSPORT_UDP with 
address 10.0.1.102:5060
[Aug 30 11:35:11] DEBUG[2079] chan_sip.c: Initializing initreq for 
method OPTIONS - callid 29bd1b0b32dd25b13a44c3236c030727 at 10.0.1.102
[Aug 30 11:35:11] DEBUG[2079] chan_sip.c: Trying to put 'OPTIONS sip' 
onto UDP socket destined for 10.0.1.132:5060
[Aug 30 11:35:11] DEBUG[2079] chan_sip.c: Stopping retransmission on 
'29bd1b0b32dd25b13a44c3236c030727 at 10.0.1.102' of Request 102: Match Found
[Aug 30 11:35:11] DEBUG[2079] chan_sip.c: Destroying SIP dialog 
29bd1b0b32dd25b13a44c3236c030727 at 10.0.1.102
[Aug 30 11:35:13] DEBUG[2079] chan_sip.c: Allocating new SIP dialog for 
1be7697f05f93db02d5286ca2671685d at 10.0.1.102 - OPTIONS (No RTP)
[Aug 30 11:35:13] DEBUG[2079] acl.c: Found IP address for this socket
[Aug 30 11:35:13] DEBUG[2079] chan_sip.c: Setting SIP_TRANSPORT_UDP with 
address 10.0.1.102:5060
[Aug 30 11:35:13] DEBUG[2079] chan_sip.c: Initializing initreq for 
method OPTIONS - callid 67f02b79570a1edf0525fb6e0ffdeef2 at 10.0.1.102
[Aug 30 11:35:13] DEBUG[2079] chan_sip.c: Trying to put 'OPTIONS sip' 
onto UDP socket destined for 10.0.1.132:5062
[Aug 30 11:35:13] DEBUG[2079] chan_sip.c: Stopping retransmission on 
'67f02b79570a1edf0525fb6e0ffdeef2 at 10.0.1.102' of Request 102: Match Found
[Aug 30 11:35:13] DEBUG[2079] chan_sip.c: Destroying SIP dialog 
67f02b79570a1edf0525fb6e0ffdeef2 at 10.0.1.102
[Aug 30 11:35:15] DEBUG[2079] chan_sip.c: Allocating new SIP dialog for 
7088bae963d26a31717a8cc3476d1196 at 10.0.1.102 - OPTIONS (No RTP)
[Aug 30 11:35:15] DEBUG[2079] acl.c: Found IP address for this socket
[Aug 30 11:35:15] DEBUG[2079] chan_sip.c: Setting SIP_TRANSPORT_UDP with 
address 10.0.1.102:5060
[Aug 30 11:35:15] DEBUG[2079] chan_sip.c: Initializing initreq for 
method OPTIONS - callid 76b217753bdfbedd260a49f378f09993 at 10.0.1.102
[Aug 30 11:35:15] DEBUG[2079] chan_sip.c: Trying to put 'OPTIONS sip' 
onto UDP socket destined for 10.0.1.132:5064
[Aug 30 11:35:15] DEBUG[2079] chan_sip.c: Stopping retransmission on 
'76b217753bdfbedd260a49f378f09993 at 10.0.1.102' of Request 102: Match Found
[Aug 30 11:35:15] DEBUG[2079] chan_sip.c: Destroying SIP dialog 
76b217753bdfbedd260a49f378f09993 at 10.0.1.102
[Aug 30 11:35:16] DEBUG[2079] acl.c: Found IP address for this socket
[Aug 30 11:35:16] DEBUG[2079] chan_sip.c: Setting SIP_TRANSPORT_UDP with 
address 10.0.1.102:5060
[Aug 30 11:35:16] DEBUG[2079] chan_sip.c: Allocating new SIP dialog for 
89c833e4-75603516-bcb7e3 at 64.34.245.174 - OPTIONS (No RTP)
[Aug 30 11:35:16] DEBUG[2079] chan_sip.c: **** Received OPTIONS (3) - 
Command in SIP OPTIONS
[Aug 30 11:35:16] DEBUG[2079] chan_sip.c: Trying to put 'SIP/2.0 404' 
onto UDP socket destined for 64.34.245.174:5060
[Aug 30 11:35:16] DEBUG[2079] chan_sip.c: SIP message could not be 
handled, bad request: 89c833e4-75603516-bcb7e3 at 64.34.245.174
[Aug 30 11:35:16] DEBUG[2079] acl.c: Found IP address for this socket
[Aug 30 11:35:16] DEBUG[2079] chan_sip.c: Setting SIP_TRANSPORT_UDP with 
address 10.0.1.102:5060
[Aug 30 11:35:16] DEBUG[2079] chan_sip.c: Allocating new SIP dialog for 
89c833e4-98603516-bcb7e3 at 64.34.245.174 - OPTIONS (No RTP)
[Aug 30 11:35:16] DEBUG[2079] chan_sip.c: **** Received OPTIONS (3) - 
Command in SIP OPTIONS
[Aug 30 11:35:16] DEBUG[2079] chan_sip.c: Trying to put 'SIP/2.0 404' 
onto UDP socket destined for 64.34.245.174:5060
[Aug 30 11:35:16] DEBUG[2079] chan_sip.c: SIP message could not be 
handled, bad request: 89c833e4-98603516-bcb7e3 at 64.34.245.174
[Aug 30 11:35:17] DEBUG[2079] chan_sip.c: Allocating new SIP dialog for 
0d3bb3e908ff7e923527a72d3b7a5ee2 at 10.0.1.102 - OPTIONS (No RTP)
[Aug 30 11:35:17] DEBUG[2079] acl.c: Found IP address for this socket
[Aug 30 11:35:17] DEBUG[2079] chan_sip.c: Setting SIP_TRANSPORT_UDP with 
address 10.0.1.102:5060
[Aug 30 11:35:17] DEBUG[2079] chan_sip.c: Initializing initreq for 
method OPTIONS - callid 6be9fbbb285c466f630fa66b480c8b29 at 10.0.1.102
[Aug 30 11:35:17] DEBUG[2079] chan_sip.c: Trying to put 'OPTIONS sip' 
onto UDP socket destined for 10.0.1.132:5066
[Aug 30 11:35:17] DEBUG[2079] chan_sip.c: Stopping retransmission on 
'6be9fbbb285c466f630fa66b480c8b29 at 10.0.1.102' of Request 102: Match Found
[Aug 30 11:35:17] DEBUG[2079] chan_sip.c: Destroying SIP dialog 
6be9fbbb285c466f630fa66b480c8b29 at 10.0.1.102
[Aug 30 11:35:17] DEBUG[2079] chan_sip.c: Auto destroying SIP dialog 
'89c833e4-993f2516-dab7e3 at 64.34.245.174'
[Aug 30 11:35:17] DEBUG[2079] chan_sip.c: Destroying SIP dialog 
89c833e4-993f2516-dab7e3 at 64.34.245.174
[Aug 30 11:35:17] DEBUG[2079] chan_sip.c: Auto destroying SIP dialog 
'89c833e4-bc3f2516-dab7e3 at 64.34.245.174'
[Aug 30 11:35:17] DEBUG[2079] chan_sip.c: Destroying SIP dialog 
89c833e4-bc3f2516-dab7e3 at 64.34.245.174
[Aug 30 11:35:18] DEBUG[2079] acl.c: Found IP address for this socket
[Aug 30 11:35:18] DEBUG[2079] chan_sip.c: Setting SIP_TRANSPORT_UDP with 
address 10.0.1.102:5060
[Aug 30 11:35:18] DEBUG[2079] chan_sip.c: Allocating new SIP dialog for 
89c833e4-fa703516-dcb7e3 at 64.34.245.174 - OPTIONS (No RTP)
[Aug 30 11:35:18] DEBUG[2079] chan_sip.c: **** Received OPTIONS (3) - 
Command in SIP OPTIONS
[Aug 30 11:35:18] DEBUG[2079] chan_sip.c: Trying to put 'SIP/2.0 404' 
onto UDP socket destined for 64.34.245.174:5060
[Aug 30 11:35:18] DEBUG[2079] chan_sip.c: SIP message could not be 
handled, bad request: 89c833e4-fa703516-dcb7e3 at 64.34.245.174
[Aug 30 11:35:19] DEBUG[2079] chan_sip.c: Auto destroying SIP dialog 
'89c833e4-1f4f2516-fab7e3 at 64.34.245.174'
[Aug 30 11:35:19] DEBUG[2079] chan_sip.c: Destroying SIP dialog 
89c833e4-1f4f2516-fab7e3 at 64.34.245.174




More information about the asterisk-users mailing list