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:[email protected];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: [email protected] [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 [email protected] - 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: [email protected] [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:[email protected];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: [email protected] [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 [email protected] - 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: [email protected] [Aug 30 11:34:54] DEBUG[2079] chan_sip.c: Auto destroying SIP dialog '[email protected]' [Aug 30 11:34:54] DEBUG[2079] chan_sip.c: Destroying SIP dialog [email protected] [Aug 30 11:34:54] DEBUG[2079] chan_sip.c: Auto destroying SIP dialog '[email protected]' [Aug 30 11:34:54] DEBUG[2079] chan_sip.c: Destroying SIP dialog [email protected] [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:[email protected] SIP/2.0 Via: SIP/2.0/UDP 10.11.17.24:5060;branch=z9hG4bK82d40955a28df1af From: "ATAP" <sip:[email protected]>;tag=ee0cedf5f71d40f9 To: <sip:[email protected]> Contact: <sip:[email protected]:5060;transport=udp> Supported: replaces, timer, path P-Early-Media: Supported Call-ID: [email protected] 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:[email protected] 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:[email protected]>;tag=ee0cedf5f71d40f9 [Aug 30 11:34:54] DEBUG[2079] chan_sip.c: Header 3 [ 35]: To: <sip:[email protected]> [Aug 30 11:34:54] DEBUG[2079] chan_sip.c: Header 4 [ 49]: Contact: <sip:[email protected]: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: [email protected] [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 [email protected] - 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 [email protected] [Aug 30 11:34:54] VERBOSE[2079] chan_sip.c: Using INVITE request as basis request - [email protected] [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:[email protected]>;tag=ee0cedf5f71d40f9 To: <sip:[email protected]>;tag=as277ae45d Call-ID: [email protected] 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 '[email protected]' 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:[email protected] SIP/2.0 Via: SIP/2.0/UDP 10.11.17.24:5060;branch=z9hG4bK82d40955a28df1af From: "ATAP" <sip:[email protected]>;tag=ee0cedf5f71d40f9 To: <sip:[email protected]>;tag=as277ae45d Contact: <sip:[email protected]:5060;transport=udp> Supported: path Call-ID: [email protected] 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:[email protected] 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:[email protected]>;tag=ee0cedf5f71d40f9 [Aug 30 11:34:55] DEBUG[2079] chan_sip.c: Header 3 [ 50]: To: <sip:[email protected]>;tag=as277ae45d [Aug 30 11:34:55] DEBUG[2079] chan_sip.c: Header 4 [ 49]: Contact: <sip:[email protected]: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: [email protected] [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 '[email protected]' 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:[email protected] SIP/2.0 Via: SIP/2.0/UDP 10.11.17.24:5060;branch=z9hG4bK45bf7291c591a19a From: "ATAP" <sip:[email protected]>;tag=ee0cedf5f71d40f9 To: <sip:[email protected]> Contact: <sip:[email protected]:5060;transport=udp> Supported: replaces, timer, path P-Early-Media: Supported Authorization: Digest username="150", realm="asterisk", algorithm=MD5, uri="sip:[email protected]", nonce="449798ce", response="237f1b41f316074f60903086366682b4" Call-ID: [email protected] 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:[email protected] 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:[email protected]>;tag=ee0cedf5f71d40f9 [Aug 30 11:34:55] DEBUG[2079] chan_sip.c: Header 3 [ 35]: To: <sip:[email protected]> [Aug 30 11:34:55] DEBUG[2079] chan_sip.c: Header 4 [ 49]: Contact: <sip:[email protected]: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:[email protected]", nonce="449798ce", response="237f1b41f316074f60903086366682b4" [Aug 30 11:34:55] DEBUG[2079] chan_sip.c: Header 8 [ 37]: Call-ID: [email protected] [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 [email protected] [Aug 30 11:34:55] VERBOSE[2079] chan_sip.c: Using INVITE request as basis request - [email protected] [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:[email protected]>;tag=ee0cedf5f71d40f9 To: <sip:[email protected]>;tag=as277ae45d Call-ID: [email protected] 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 '[email protected]' 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:[email protected] SIP/2.0 Via: SIP/2.0/UDP 10.11.17.24:5060;branch=z9hG4bK45bf7291c591a19a From: "ATAP" <sip:[email protected]>;tag=ee0cedf5f71d40f9 To: <sip:[email protected]>;tag=as277ae45d Contact: <sip:[email protected]:5060;transport=udp> Supported: path Authorization: Digest username="150", realm="asterisk", algorithm=MD5, uri="sip:[email protected]", nonce="449798ce", response="237f1b41f316074f60903086366682b4" Call-ID: [email protected] 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:[email protected] 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:[email protected]>;tag=ee0cedf5f71d40f9 [Aug 30 11:34:55] DEBUG[2079] chan_sip.c: Header 3 [ 50]: To: <sip:[email protected]>;tag=as277ae45d [Aug 30 11:34:55] DEBUG[2079] chan_sip.c: Header 4 [ 49]: Contact: <sip:[email protected]: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:[email protected]", nonce="449798ce", response="237f1b41f316074f60903086366682b4" [Aug 30 11:34:55] DEBUG[2079] chan_sip.c: Header 7 [ 37]: Call-ID: [email protected] [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 '[email protected]' of Response 21396: Match Found [Aug 30 11:35:01] DEBUG[2079] chan_sip.c: Auto destroying SIP dialog '[email protected]' [Aug 30 11:35:01] DEBUG[2079] chan_sip.c: Destroying SIP dialog [email protected] [Aug 30 11:35:01] DEBUG[2079] chan_sip.c: Auto destroying SIP dialog '[email protected]' [Aug 30 11:35:01] DEBUG[2079] chan_sip.c: Destroying SIP dialog [email protected] [Aug 30 11:35:01] DEBUG[2079] chan_sip.c: Auto destroying SIP dialog '[email protected]' [Aug 30 11:35:01] DEBUG[2079] chan_sip.c: Destroying SIP dialog [email protected] [Aug 30 11:35:01] DEBUG[2079] chan_sip.c: Auto destroying SIP dialog '[email protected]' [Aug 30 11:35:01] DEBUG[2079] chan_sip.c: Destroying SIP dialog [email protected] [Aug 30 11:35:01] DEBUG[2079] chan_sip.c: Auto destroying SIP dialog '[email protected]' [Aug 30 11:35:01] DEBUG[2079] chan_sip.c: Destroying SIP dialog [email protected] [Aug 30 11:35:01] VERBOSE[2079] chan_sip.c: Really destroying SIP dialog '[email protected]' Method: ACK [Aug 30 11:35:01] DEBUG[2079] chan_sip.c: Auto destroying SIP dialog '[email protected]' [Aug 30 11:35:01] DEBUG[2079] chan_sip.c: Destroying SIP dialog [email protected] [Aug 30 11:35:01] DEBUG[2079] chan_sip.c: Auto destroying SIP dialog '[email protected]' [Aug 30 11:35:01] DEBUG[2079] chan_sip.c: Destroying SIP dialog [email protected] [Aug 30 11:35:11] DEBUG[2079] chan_sip.c: Allocating new SIP dialog for [email protected] - 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 [email protected] [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 '[email protected]' of Request 102: Match Found [Aug 30 11:35:11] DEBUG[2079] chan_sip.c: Destroying SIP dialog [email protected] [Aug 30 11:35:13] DEBUG[2079] chan_sip.c: Allocating new SIP dialog for [email protected] - 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 [email protected] [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 '[email protected]' of Request 102: Match Found [Aug 30 11:35:13] DEBUG[2079] chan_sip.c: Destroying SIP dialog [email protected] [Aug 30 11:35:15] DEBUG[2079] chan_sip.c: Allocating new SIP dialog for [email protected] - 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 [email protected] [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 '[email protected]' of Request 102: Match Found [Aug 30 11:35:15] DEBUG[2079] chan_sip.c: Destroying SIP dialog [email protected] [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 [email protected] - 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: [email protected] [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 [email protected] - 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: [email protected] [Aug 30 11:35:17] DEBUG[2079] chan_sip.c: Allocating new SIP dialog for [email protected] - 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 [email protected] [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 '[email protected]' of Request 102: Match Found [Aug 30 11:35:17] DEBUG[2079] chan_sip.c: Destroying SIP dialog [email protected] [Aug 30 11:35:17] DEBUG[2079] chan_sip.c: Auto destroying SIP dialog '[email protected]' [Aug 30 11:35:17] DEBUG[2079] chan_sip.c: Destroying SIP dialog [email protected] [Aug 30 11:35:17] DEBUG[2079] chan_sip.c: Auto destroying SIP dialog '[email protected]' [Aug 30 11:35:17] DEBUG[2079] chan_sip.c: Destroying SIP dialog [email protected] [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 [email protected] - 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: [email protected] [Aug 30 11:35:19] DEBUG[2079] chan_sip.c: Auto destroying SIP dialog '[email protected]' [Aug 30 11:35:19] DEBUG[2079] chan_sip.c: Destroying SIP dialog [email protected] -- _____________________________________________________________________ -- Bandwidth and Colocation Provided by http://www.api-digital.com -- New to Asterisk? Join us for a live introductory webinar every Thurs: http://www.asterisk.org/hello asterisk-users mailing list To UNSUBSCRIBE or update options visit: http://lists.digium.com/mailman/listinfo/asterisk-users
