<--- SIP read from 192.168.200.240:5060 --->
NOTIFY sip:192.168.201.237 SIP/2.0
Via: SIP/2.0/UDP 192.168.200.240:5060;branch=z9hG4bK-407cc668;rport
From: "1302" <sip:1302@192.168.201.237>;tag=d314623ecf3b06dao0
To: <sip:192.168.201.237>
Call-ID: 298ffc8e-d203b0ca@192.168.200.240
CSeq: 57843 NOTIFY
Max-Forwards: 70
Event: keep-alive
User-Agent: Linksys/SPA921-4.1.17
Content-Length: 0


<------------->
--- (10 headers 0 lines) ---

<--- Transmitting (no NAT) to 192.168.200.240:5060 --->
SIP/2.0 489 Bad event
Via: SIP/2.0/UDP 192.168.200.240:5060;branch=z9hG4bK-407cc668;received=192.168.200.240;rport=5060
From: "1302" <sip:1302@192.168.201.237>;tag=d314623ecf3b06dao0
To: <sip:192.168.201.237>;tag=as1df2b8d0
Call-ID: 298ffc8e-d203b0ca@192.168.200.240
CSeq: 57843 NOTIFY
User-Agent: Asterisk PBX
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Supported: replaces
Content-Length: 0


<------------>

<--- SIP read from 192.168.200.246:5060 --->
INVITE sip:1723@192.168.201.237 SIP/2.0
Via: SIP/2.0/UDP 192.168.200.246:5060;branch=z9hG4bK-9e683507;rport
From: "1301" <sip:1301@192.168.201.237>;tag=e32fa13dc1cd1c4fo0
To: <sip:1723@192.168.201.237>
Call-ID: b7711e55-b3c80217@192.168.200.246
CSeq: 101 INVITE
Max-Forwards: 70
Contact: "1301" <sip:1301@192.168.200.246:5060>
Expires: 240
User-Agent: Linksys/SPA921-4.1.17
Content-Length: 405
Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, REFER
Content-Type: application/sdp

v=0
o=- 87548393 87548393 IN IP4 192.168.200.246
s=-
c=IN IP4 192.168.200.246
t=0 0
m=audio 16386 RTP/AVP 0 2 4 8 18 96 97 98 101
a=rtpmap:0 PCMU/8000
a=rtpmap:2 G726-32/8000
a=rtpmap:4 G723/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:18 G729a/8000
a=rtpmap:96 G726-40/8000
a=rtpmap:97 G726-24/8000
a=rtpmap:98 G726-16/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=ptime:30
a=sendrecv

<------------->
--- (13 headers 18 lines) ---
Sending to 192.168.200.246 : 5060 (NAT)
Using INVITE request as basis request - b7711e55-b3c80217@192.168.200.246
[Nov 27 14:33:47] DEBUG[23400]: res_config_mysql.c:809 mysql_reconnect: MySQL RealTime: Everything is fine.
[Nov 27 14:33:47] DEBUG[23400]: res_config_mysql.c:139 realtime_mysql: MySQL RealTime: Retrieve SQL: SELECT * FROM sip WHERE name = '1301'
Found user '1301' for '1301'

<--- Reliably Transmitting (NAT) to 192.168.200.246:5060 --->
SIP/2.0 401 Unauthorized
Via: SIP/2.0/UDP 192.168.200.246:5060;branch=z9hG4bK-9e683507;received=192.168.200.246;rport=5060
From: "1301" <sip:1301@192.168.201.237>;tag=e32fa13dc1cd1c4fo0
To: <sip:1723@192.168.201.237>;tag=as458f6dba
Call-ID: b7711e55-b3c80217@192.168.200.246
CSeq: 101 INVITE
User-Agent: Asterisk PBX
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Supported: replaces
WWW-Authenticate: Digest algorithm=MD5, realm="asterisk", nonce="0b9051d3"
Content-Length: 0


<------------>
Scheduling destruction of SIP dialog 'b7711e55-b3c80217@192.168.200.246' in 32000 ms (Method: INVITE)

<--- SIP read from 192.168.200.246:5060 --->
ACK sip:1723@192.168.201.237 SIP/2.0
Via: SIP/2.0/UDP 192.168.200.246:5060;branch=z9hG4bK-9e683507;rport
From: "1301" <sip:1301@192.168.201.237>;tag=e32fa13dc1cd1c4fo0
To: <sip:1723@192.168.201.237>;tag=as458f6dba
Call-ID: b7711e55-b3c80217@192.168.200.246
CSeq: 101 ACK
Max-Forwards: 70
Contact: "1301" <sip:1301@192.168.200.246:5060>
User-Agent: Linksys/SPA921-4.1.17
Content-Length: 0


<------------->
--- (10 headers 0 lines) ---

<--- SIP read from 192.168.200.246:5060 --->
INVITE sip:1723@192.168.201.237 SIP/2.0
Via: SIP/2.0/UDP 192.168.200.246:5060;branch=z9hG4bK-7618849b;rport
From: "1301" <sip:1301@192.168.201.237>;tag=e32fa13dc1cd1c4fo0
To: <sip:1723@192.168.201.237>
Call-ID: b7711e55-b3c80217@192.168.200.246
CSeq: 102 INVITE
Max-Forwards: 70
Authorization: Digest username="1301",realm="asterisk",nonce="0b9051d3",uri="sip:1723@192.168.201.237",algorithm=MD5,response="127626a581c5d728d68b816c5a0d64da"
Contact: "1301" <sip:1301@192.168.200.246:5060>
Expires: 240
User-Agent: Linksys/SPA921-4.1.17
Content-Length: 405
Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, REFER
Content-Type: application/sdp

v=0
o=- 87548393 87548393 IN IP4 192.168.200.246
s=-
c=IN IP4 192.168.200.246
t=0 0
m=audio 16386 RTP/AVP 0 2 4 8 18 96 97 98 101
a=rtpmap:0 PCMU/8000
a=rtpmap:2 G726-32/8000
a=rtpmap:4 G723/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:18 G729a/8000
a=rtpmap:96 G726-40/8000
a=rtpmap:97 G726-24/8000
a=rtpmap:98 G726-16/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=ptime:30
a=sendrecv

<------------->
--- (14 headers 18 lines) ---
Sending to 192.168.200.246 : 5060 (NAT)
Using INVITE request as basis request - b7711e55-b3c80217@192.168.200.246
[Nov 27 14:33:47] DEBUG[23400]: res_config_mysql.c:809 mysql_reconnect: MySQL RealTime: Everything is fine.
[Nov 27 14:33:47] DEBUG[23400]: res_config_mysql.c:139 realtime_mysql: MySQL RealTime: Retrieve SQL: SELECT * FROM sip WHERE name = '1301'
Found user '1301' for '1301'
Found RTP audio format 0
Found RTP audio format 2
Found RTP audio format 4
Found RTP audio format 8
Found RTP audio format 18
Found RTP audio format 96
Found RTP audio format 97
Found RTP audio format 98
Found RTP audio format 101
Peer audio RTP is at port 192.168.200.246:16386
Found description format PCMU for ID 0
Found description format G726-32 for ID 2
Found description format G723 for ID 4
Found description format PCMA for ID 8
Found description format G729a for ID 18
Found description format G726-40 for ID 96
Found description format G726-24 for ID 97
Found description format G726-16 for ID 98
Found description format telephone-event for ID 101
Capabilities: us - 0x3f1fff (g723|gsm|ulaw|alaw|g726|adpcm|slin|lpc10|g729|speex|ilbc|g726aal2|g722|jpeg|png|h261|h263|h263p|h264), peer - audio=0xd0d (g723|ulaw|alaw|g726|g729|ilbc)/video=0x0 (nothing), combined - 0xd0d (g723|ulaw|alaw|g726|g729|ilbc)
Non-codec capabilities (dtmf): us - 0x1 (telephone-event), peer - 0x1 (telephone-event), combined - 0x1 (telephone-event)
Peer audio RTP is at port 192.168.200.246:16386
Peer video RTP is at port 192.168.200.246:15031
Looking for 1723 in default (domain 192.168.201.237)
[Nov 27 14:33:47] DEBUG[23400]: res_config_mysql.c:809 mysql_reconnect: MySQL RealTime: Everything is fine.
[Nov 27 14:33:47] DEBUG[23400]: res_config_mysql.c:139 realtime_mysql: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = '1723' AND context = 'default' AND priority = '1'
[Nov 27 14:33:47] DEBUG[23400]: res_config_mysql.c:809 mysql_reconnect: MySQL RealTime: Everything is fine.
[Nov 27 14:33:47] DEBUG[23400]: res_config_mysql.c:259 realtime_multi_mysql: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten LIKE '\\_%' AND context = 'default' AND priority = '1' ORDER BY exten
list_route: hop: <sip:1301@192.168.200.246:5060>

<--- Transmitting (NAT) to 192.168.200.246:5060 --->
SIP/2.0 100 Trying
Via: SIP/2.0/UDP 192.168.200.246:5060;branch=z9hG4bK-7618849b;received=192.168.200.246;rport=5060
From: "1301" <sip:1301@192.168.201.237>;tag=e32fa13dc1cd1c4fo0
To: <sip:1723@192.168.201.237>
Call-ID: b7711e55-b3c80217@192.168.200.246
CSeq: 102 INVITE
User-Agent: Asterisk PBX
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Supported: replaces
Contact: <sip:1723@192.168.201.237>
Content-Length: 0


<------------>
[Nov 27 14:33:47] DEBUG[23384]: res_config_mysql.c:809 mysql_reconnect: MySQL RealTime: Everything is fine.
[Nov 27 14:33:47] DEBUG[23384]: res_config_mysql.c:139 realtime_mysql: MySQL RealTime: Retrieve SQL: SELECT * FROM sip WHERE name = '1301'
    -- SIP Seeding peer from astdb: '1301' at 1301@192.168.200.246:5060 for 3600
[Nov 27 14:33:47] DEBUG[23524]: res_config_mysql.c:809 mysql_reconnect: MySQL RealTime: Everything is fine.
[Nov 27 14:33:47] DEBUG[23524]: res_config_mysql.c:139 realtime_mysql: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = '1723' AND context = 'default' AND priority = '1'
[Nov 27 14:33:47] DEBUG[23524]: res_config_mysql.c:809 mysql_reconnect: MySQL RealTime: Everything is fine.
[Nov 27 14:33:47] DEBUG[23524]: res_config_mysql.c:259 realtime_multi_mysql: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten LIKE '\\_%' AND context = 'default' AND priority = '1' ORDER BY exten
[Nov 27 14:33:47] DEBUG[23524]: res_config_mysql.c:809 mysql_reconnect: MySQL RealTime: Everything is fine.
[Nov 27 14:33:47] DEBUG[23524]: res_config_mysql.c:139 realtime_mysql: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = '1723' AND context = 'default' AND priority = '1'
[Nov 27 14:33:47] DEBUG[23524]: res_config_mysql.c:809 mysql_reconnect: MySQL RealTime: Everything is fine.
[Nov 27 14:33:47] DEBUG[23524]: res_config_mysql.c:259 realtime_multi_mysql: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten LIKE '\\_%' AND context = 'default' AND priority = '1' ORDER BY exten
[Nov 27 14:33:47] DEBUG[23524]: res_config_mysql.c:809 mysql_reconnect: MySQL RealTime: Everything is fine.
[Nov 27 14:33:47] DEBUG[23524]: res_config_mysql.c:139 realtime_mysql: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = '1723' AND context = 'default' AND priority = '1'
[Nov 27 14:33:47] DEBUG[23524]: res_config_mysql.c:809 mysql_reconnect: MySQL RealTime: Everything is fine.
[Nov 27 14:33:47] DEBUG[23524]: res_config_mysql.c:259 realtime_multi_mysql: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten LIKE '\\_%' AND context = 'default' AND priority = '1' ORDER BY exten
[Nov 27 14:33:47] DEBUG[23524]: res_config_mysql.c:809 mysql_reconnect: MySQL RealTime: Everything is fine.
[Nov 27 14:33:47] DEBUG[23524]: res_config_mysql.c:139 realtime_mysql: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = '1723' AND context = 'default' AND priority = '1'
[Nov 27 14:33:47] DEBUG[23524]: res_config_mysql.c:809 mysql_reconnect: MySQL RealTime: Everything is fine.
[Nov 27 14:33:47] DEBUG[23524]: res_config_mysql.c:259 realtime_multi_mysql: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten LIKE '\\_%' AND context = 'default' AND priority = '1' ORDER BY exten
    -- Executing DIAL("SIP/1301-081def28", "SIP/uplink/1723")
[Nov 27 14:33:47] DEBUG[23524]: res_config_mysql.c:809 mysql_reconnect: MySQL RealTime: Everything is fine.
[Nov 27 14:33:47] DEBUG[23524]: res_config_mysql.c:139 realtime_mysql: MySQL RealTime: Retrieve SQL: SELECT * FROM sip WHERE name = 'uplink'
Video is at 192.168.201.237 port 16150
Audio is at 192.168.201.237 port 13856
Adding codec 0x4 (ulaw) to SDP
Adding codec 0x2 (gsm) to SDP
Adding codec 0x8 (alaw) to SDP
Adding codec 0x80000 (h263) to SDP
Adding non-codec 0x1 (telephone-event) to SDP
Reliably Transmitting (NAT) to 192.168.201.238:5060:
INVITE sip:1723@192.168.201.238 SIP/2.0
Via: SIP/2.0/UDP 192.168.201.237:5060;branch=z9hG4bK7d83ceea;rport
Max-Forwards: 70
From: "1301" <sip:1301@192.168.201.237>;tag=as648b7460
To: <sip:1723@192.168.201.238>
Contact: <sip:1301@192.168.201.237>
Call-ID: 4c9015394d3664323306095604d938c2@192.168.201.237
CSeq: 102 INVITE
User-Agent: Asterisk PBX
Date: Mon, 27 Nov 2006 11:33:47 GMT
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Supported: replaces
Content-Type: application/sdp
Content-Length: 365

v=0
o=root 23381 23381 IN IP4 192.168.201.237
s=session
c=IN IP4 192.168.201.237
b=CT:384
t=0 0
m=audio 13856 RTP/AVP 0 3 8 101
a=rtpmap:0 PCMU/8000
a=rtpmap:3 GSM/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=silenceSupp:off - - - -
a=ptime:20
a=sendrecv
m=video 16150 RTP/AVP 34
a=rtpmap:34 H263/90000
a=sendrecv

---
    -- Called uplink/1723
[Nov 27 14:33:47] DEBUG[23524]: res_config_mysql.c:809 mysql_reconnect: MySQL RealTime: Everything is fine.
[Nov 27 14:33:47] DEBUG[23524]: res_config_mysql.c:139 realtime_mysql: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = '1723' AND context = 'default' AND priority = '-1'
[Nov 27 14:33:47] DEBUG[23524]: res_config_mysql.c:809 mysql_reconnect: MySQL RealTime: Everything is fine.
[Nov 27 14:33:47] DEBUG[23524]: res_config_mysql.c:259 realtime_multi_mysql: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten LIKE '\\_%' AND context = 'default' AND priority = '-1' ORDER BY exten
[Nov 27 14:33:47] DEBUG[23524]: res_config_mysql.c:809 mysql_reconnect: MySQL RealTime: Everything is fine.
[Nov 27 14:33:47] DEBUG[23524]: res_config_mysql.c:139 realtime_mysql: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = '1723' AND context = 'moscow' AND priority = '-1'
[Nov 27 14:33:47] DEBUG[23524]: res_config_mysql.c:809 mysql_reconnect: MySQL RealTime: Everything is fine.
[Nov 27 14:33:47] DEBUG[23524]: res_config_mysql.c:259 realtime_multi_mysql: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten LIKE '\\_%' AND context = 'moscow' AND priority = '-1' ORDER BY exten

<--- SIP read from 192.168.201.238:5060 --->
SIP/2.0 100 Trying
Via: SIP/2.0/UDP 192.168.201.237:5060;branch=z9hG4bK7d83ceea;received=192.168.201.237;rport=5060
From: "1301" <sip:1301@192.168.201.237>;tag=as648b7460
To: <sip:1723@192.168.201.238>
Call-ID: 4c9015394d3664323306095604d938c2@192.168.201.237
CSeq: 102 INVITE
User-Agent: Asterisk PBX
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Supported: replaces
Contact: <sip:1723@192.168.201.238>
Content-Length: 0


<------------->
--- (11 headers 0 lines) ---

<--- SIP read from 192.168.201.238:5060 --->
SIP/2.0 183 Session Progress
Via: SIP/2.0/UDP 192.168.201.237:5060;branch=z9hG4bK7d83ceea;received=192.168.201.237;rport=5060
From: "1301" <sip:1301@192.168.201.237>;tag=as648b7460
To: <sip:1723@192.168.201.238>;tag=as23077df2
Call-ID: 4c9015394d3664323306095604d938c2@192.168.201.237
CSeq: 102 INVITE
User-Agent: Asterisk PBX
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Supported: replaces
Contact: <sip:1723@192.168.201.238>
Content-Type: application/sdp
Content-Length: 293

v=0
o=root 21607 21607 IN IP4 192.168.201.238
s=session
c=IN IP4 192.168.201.238
t=0 0
m=audio 19220 RTP/AVP 3 0 8 101
a=rtpmap:3 GSM/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=silenceSupp:off - - - -
a=ptime:20
a=sendrecv

<------------->
--- (12 headers 14 lines) ---
Found RTP audio format 3
Found RTP audio format 0
Found RTP audio format 8
Found RTP audio format 101
Peer audio RTP is at port 192.168.201.238:19220
Found description format GSM for ID 3
Found description format PCMU for ID 0
Found description format PCMA for ID 8
Found description format telephone-event for ID 101
Capabilities: us - 0x8000e (gsm|ulaw|alaw|h263), peer - audio=0xe (gsm|ulaw|alaw)/video=0x0 (nothing), combined - 0xe (gsm|ulaw|alaw)
Non-codec capabilities (dtmf): us - 0x1 (telephone-event), peer - 0x1 (telephone-event), combined - 0x1 (telephone-event)
Peer audio RTP is at port 192.168.201.238:19220
Peer video RTP is at port 192.168.201.238:15031
    -- SIP/uplink-081e0aa8 is making progress passing it to SIP/1301-081def28
Audio is at 192.168.201.237 port 15252
Adding codec 0x1 (g723) to SDP
Adding codec 0x4 (ulaw) to SDP
Adding codec 0x8 (alaw) to SDP
Adding codec 0x100 (g729) to SDP
Adding codec 0x400 (ilbc) to SDP
Adding codec 0x800 (g726) to SDP
Adding non-codec 0x1 (telephone-event) to SDP

<--- Transmitting (NAT) to 192.168.200.246:5060 --->
SIP/2.0 183 Session Progress
Via: SIP/2.0/UDP 192.168.200.246:5060;branch=z9hG4bK-7618849b;received=192.168.200.246;rport=5060
From: "1301" <sip:1301@192.168.201.237>;tag=e32fa13dc1cd1c4fo0
To: <sip:1723@192.168.201.237>;tag=as42ef4bae
Call-ID: b7711e55-b3c80217@192.168.200.246
CSeq: 102 INVITE
User-Agent: Asterisk PBX
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Supported: replaces
Contact: <sip:1723@192.168.201.237>
Content-Type: application/sdp
Content-Length: 413

v=0
o=root 23381 23381 IN IP4 192.168.201.237
s=session
c=IN IP4 192.168.201.237
t=0 0
m=audio 15252 RTP/AVP 4 0 8 18 97 2 101
a=rtpmap:4 G723/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=rtpmap:97 iLBC/8000
a=fmtp:97 mode=30
a=rtpmap:2 G726-32/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=silenceSupp:off - - - -
a=ptime:20
a=sendrecv

<------------>

*CLI>
*CLI>
*CLI>
*CLI>
*CLI>
*CLI>
*CLI>
*CLI>
*CLI>
*CLI>
*CLI>
*CLI>
*CLI>
*CLI>
*CLI>
*CLI>
*CLI>
*CLI>
*CLI>
*CLI>
<--- SIP read from 192.168.200.246:5060 --->
CANCEL sip:1723@192.168.201.237 SIP/2.0
Via: SIP/2.0/UDP 192.168.200.246:5060;branch=z9hG4bK-7618849b;rport
From: "1301" <sip:1301@192.168.201.237>;tag=e32fa13dc1cd1c4fo0
To: <sip:1723@192.168.201.237>
Call-ID: b7711e55-b3c80217@192.168.200.246
CSeq: 102 CANCEL
Max-Forwards: 70
Authorization: Digest username="1301",realm="asterisk",nonce="0b9051d3",uri="sip:1723@192.168.201.237",algorithm=MD5,response="e1dc68933eeea19af5b375401a0ba5ee"
User-Agent: Linksys/SPA921-4.1.17
Content-Length: 0


<------------->
--- (10 headers 0 lines) ---
Sending to 192.168.200.246 : 5060 (NAT)

<--- Reliably Transmitting (NAT) to 192.168.200.246:5060 --->
SIP/2.0 487 Request Terminated
Via: SIP/2.0/UDP 192.168.200.246:5060;branch=z9hG4bK-7618849b;received=192.168.200.246;rport=5060
From: "1301" <sip:1301@192.168.201.237>;tag=e32fa13dc1cd1c4fo0
To: <sip:1723@192.168.201.237>;tag=as42ef4bae
Call-ID: b7711e55-b3c80217@192.168.200.246
CSeq: 102 INVITE
User-Agent: Asterisk PBX
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Supported: replaces
Content-Length: 0


<------------>

<--- Transmitting (NAT) to 192.168.200.246:5060 --->
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.200.246:5060;branch=z9hG4bK-7618849b;received=192.168.200.246;rport=5060
From: "1301" <sip:1301@192.168.201.237>;tag=e32fa13dc1cd1c4fo0
To: <sip:1723@192.168.201.237>;tag=as42ef4bae
Call-ID: b7711e55-b3c80217@192.168.200.246
CSeq: 102 CANCEL
User-Agent: Asterisk PBX
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Supported: replaces
Contact: <sip:1723@192.168.201.237>
Content-Length: 0


<------------>
Scheduling destruction of SIP dialog '4c9015394d3664323306095604d938c2@192.168.201.237' in 32000 ms (Method: INVITE)
  == Spawn extension (default, 1723, 1) exited non-zero on 'SIP/1301-081def28'
[Nov 27 14:33:56] DEBUG[23524]: res_config_mysql.c:809 mysql_reconnect: MySQL RealTime: Everything is fine.
[Nov 27 14:33:56] DEBUG[23524]: res_config_mysql.c:139 realtime_mysql: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = 'h' AND context = 'default' AND priority = '1'
[Nov 27 14:33:56] DEBUG[23524]: res_config_mysql.c:809 mysql_reconnect: MySQL RealTime: Everything is fine.
[Nov 27 14:33:56] DEBUG[23524]: res_config_mysql.c:259 realtime_multi_mysql: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten LIKE '\\_%' AND context = 'default' AND priority = '1' ORDER BY exten
[Nov 27 14:33:56] DEBUG[23524]: res_config_mysql.c:809 mysql_reconnect: MySQL RealTime: Everything is fine.
[Nov 27 14:33:56] DEBUG[23524]: res_config_mysql.c:139 realtime_mysql: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = 'h' AND context = 'moscow' AND priority = '1'
[Nov 27 14:33:56] DEBUG[23524]: res_config_mysql.c:809 mysql_reconnect: MySQL RealTime: Everything is fine.
[Nov 27 14:33:56] DEBUG[23524]: res_config_mysql.c:259 realtime_multi_mysql: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten LIKE '\\_%' AND context = 'moscow' AND priority = '1' ORDER BY exten
[Nov 27 14:33:56] DEBUG[23524]: cdr_addon_mysql.c:179 mysql_log: Sergey's code started. Creating pointers.
[Nov 27 14:33:56] DEBUG[23524]: cdr_addon_mysql.c:193 mysql_log: Fetching 'owner'...
[Nov 27 14:33:56] DEBUG[23524]: cdr_addon_mysql.c:195 mysql_log: Got '(null)'...
[Nov 27 14:33:56] DEBUG[23524]: cdr_addon_mysql.c:196 mysql_log: Fetching 'price'...
[Nov 27 14:33:56] DEBUG[23524]: cdr_addon_mysql.c:198 mysql_log: Got '(null)'...
[Nov 27 14:33:56] DEBUG[23524]: cdr_addon_mysql.c:199 mysql_log: Fetching 'useragent'...
[Nov 27 14:33:56] DEBUG[23524]: cdr_addon_mysql.c:201 mysql_log: Got '(null)'...
[Nov 27 14:33:56] DEBUG[23524]: cdr_addon_mysql.c:202 mysql_log: Fetching 'routename'...
[Nov 27 14:33:56] DEBUG[23524]: cdr_addon_mysql.c:204 mysql_log: Got '(null)'...
[Nov 27 14:33:56] DEBUG[23524]: cdr_addon_mysql.c:205 mysql_log: Fetching 'price_real'...
[Nov 27 14:33:56] DEBUG[23524]: cdr_addon_mysql.c:207 mysql_log: Got '(null)'...
[Nov 27 14:33:56] DEBUG[23524]: cdr_addon_mysql.c:208 mysql_log: Fetching 'rate_id'...
[Nov 27 14:33:56] DEBUG[23524]: cdr_addon_mysql.c:210 mysql_log: Got '(null)'...
[Nov 27 14:33:56] DEBUG[23524]: cdr_addon_mysql.c:211 mysql_log: Fetching 'rates_route_id'...
[Nov 27 14:33:56] DEBUG[23524]: cdr_addon_mysql.c:213 mysql_log: Got '(null)'...
[Nov 27 14:33:56] DEBUG[23524]: cdr_addon_mysql.c:226 mysql_log: EXTRA parameters (xowner,xprice,xprice_real,xroutename,xuseragent,xrate_id,xrates_route_id): '','','','','','',''
[Nov 27 14:33:56] DEBUG[23524]: cdr_addon_mysql.c:227 mysql_log: End of Sergey's code
[Nov 27 14:33:56] DEBUG[23524]: cdr_addon_mysql.c:260 mysql_log: cdr_mysql: inserting a CDR record.
[Nov 27 14:33:56] DEBUG[23524]: cdr_addon_mysql.c:276 mysql_log: cdr_mysql: SQL command as follows: INSERT INTO cdr (calldate,clid,src,dst,dcontext,channel,dstchannel,lastapp,lastdata,duration,billsec,disposition,amaflags,accountcode,userfield,owner,price,price_real,routename,useragent,rate_id,rates_route_id) VALUES ('2006-11-27 14:33:47','\"1301\" <1301>','1301','1723','default', 'SIP/1301-081def28','SIP/uplink-081e0aa8','Dial','SIP/uplink/1723',9,0,'NO ANSWER',3,'','','','','','','','','')
[Nov 27 14:33:56] DEBUG[23384]: res_config_mysql.c:809 mysql_reconnect: MySQL RealTime: Everything is fine.
[Nov 27 14:33:56] DEBUG[23384]: res_config_mysql.c:139 realtime_mysql: MySQL RealTime: Retrieve SQL: SELECT * FROM sip WHERE name = 'uplink'
[Nov 27 14:33:56] DEBUG[23384]: res_config_mysql.c:809 mysql_reconnect: MySQL RealTime: Everything is fine.
[Nov 27 14:33:56] DEBUG[23384]: res_config_mysql.c:139 realtime_mysql: MySQL RealTime: Retrieve SQL: SELECT * FROM sip WHERE name = '1301'
    -- SIP Seeding peer from astdb: '1301' at 1301@192.168.200.246:5060 for 3600

<--- SIP read from 192.168.200.246:5060 --->
ACK sip:1723@192.168.201.237 SIP/2.0
Via: SIP/2.0/UDP 192.168.200.246:5060;branch=z9hG4bK-7618849b;rport
From: "1301" <sip:1301@192.168.201.237>;tag=e32fa13dc1cd1c4fo0
To: <sip:1723@192.168.201.237>;tag=as42ef4bae
Call-ID: b7711e55-b3c80217@192.168.200.246
CSeq: 102 ACK
Max-Forwards: 70
Authorization: Digest username="1301",realm="asterisk",nonce="0b9051d3",uri="sip:1723@192.168.201.237",algorithm=MD5,response="096d5cf9dd86389f71de214c347c0367"
Contact: "1301" <sip:1301@192.168.200.246:5060>
User-Agent: Linksys/SPA921-4.1.17
Content-Length: 0


<------------->
--- (11 headers 0 lines) ---
Really destroying SIP dialog 'b7711e55-b3c80217@192.168.200.246' Method: ACK

*CLI>
*CLI>
*CLI>
*CLI>
*CLI>
*CLI>
*CLI>
*CLI>
*CLI>
<--- SIP read from 192.168.200.240:5060 --->
NOTIFY sip:192.168.201.237 SIP/2.0
Via: SIP/2.0/UDP 192.168.200.240:5060;branch=z9hG4bK-ded8bc1f;rport
From: "1302" <sip:1302@192.168.201.237>;tag=d314623ecf3b06dao0
To: <sip:192.168.201.237>
Call-ID: 298ffc8e-d203b0ca@192.168.200.240
CSeq: 57844 NOTIFY
Max-Forwards: 70
Event: keep-alive
User-Agent: Linksys/SPA921-4.1.17
Content-Length: 0


<------------->
--- (10 headers 0 lines) ---

<--- Transmitting (no NAT) to 192.168.200.240:5060 --->
SIP/2.0 489 Bad event
Via: SIP/2.0/UDP 192.168.200.240:5060;branch=z9hG4bK-ded8bc1f;received=192.168.200.240;rport=5060
From: "1302" <sip:1302@192.168.201.237>;tag=d314623ecf3b06dao0
To: <sip:192.168.201.237>;tag=as174e0c26
Call-ID: 298ffc8e-d203b0ca@192.168.200.240
CSeq: 57844 NOTIFY
User-Agent: Asterisk PBX
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Supported: replaces
Content-Length: 0


<------------>

<--- SIP read from 192.168.201.238:5060 --->
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.201.237:5060;branch=z9hG4bK7d83ceea;received=192.168.201.237;rport=5060
From: "1301" <sip:1301@192.168.201.237>;tag=as648b7460
To: <sip:1723@192.168.201.238>;tag=as23077df2
Call-ID: 4c9015394d3664323306095604d938c2@192.168.201.237
CSeq: 102 INVITE
User-Agent: Asterisk PBX
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Supported: replaces
Contact: <sip:1723@192.168.201.238>
Content-Type: application/sdp
Content-Length: 293

v=0
o=root 21607 21608 IN IP4 192.168.201.238
s=session
c=IN IP4 192.168.201.238
t=0 0
m=audio 19220 RTP/AVP 3 0 8 101
a=rtpmap:3 GSM/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=silenceSupp:off - - - -
a=ptime:20
a=sendrecv

<------------->
--- (12 headers 14 lines) ---
Found RTP audio format 3
Found RTP audio format 0
Found RTP audio format 8
Found RTP audio format 101
Peer audio RTP is at port 192.168.201.238:19220
Found description format GSM for ID 3
Found description format PCMU for ID 0
Found description format PCMA for ID 8
Found description format telephone-event for ID 101
Capabilities: us - 0x8000e (gsm|ulaw|alaw|h263), peer - audio=0xe (gsm|ulaw|alaw)/video=0x0 (nothing), combined - 0xe (gsm|ulaw|alaw)
Non-codec capabilities (dtmf): us - 0x1 (telephone-event), peer - 0x1 (telephone-event), combined - 0x1 (telephone-event)
Peer audio RTP is at port 192.168.201.238:19220
Peer video RTP is at port 192.168.201.238:15031
list_route: hop: <sip:1723@192.168.201.238>
[Nov 27 14:34:09] DEBUG[23400]: chan_sip.c:5663 reqprep: Strict routing enforced for session 4c9015394d3664323306095604d938c2@192.168.201.237
set_destination: Parsing <sip:1723@192.168.201.238> for address/port to send to
set_destination: set destination to 192.168.201.238, port 5060
Transmitting (NAT) to 192.168.201.238:5060:
ACK sip:1723@192.168.201.238 SIP/2.0
Via: SIP/2.0/UDP 192.168.201.237:5060;branch=z9hG4bK13c4d25f;rport
Max-Forwards: 70
From: "1301" <sip:1301@192.168.201.237>;tag=as648b7460
To: <sip:1723@192.168.201.238>;tag=as23077df2
Contact: <sip:1301@192.168.201.237>
Call-ID: 4c9015394d3664323306095604d938c2@192.168.201.237
CSeq: 102 ACK
User-Agent: Asterisk PBX
Content-Length: 0


---
[Nov 27 14:34:09] DEBUG[23400]: chan_sip.c:5663 reqprep: Strict routing enforced for session 4c9015394d3664323306095604d938c2@192.168.201.237
set_destination: Parsing <sip:1723@192.168.201.238> for address/port to send to
set_destination: set destination to 192.168.201.238, port 5060
Reliably Transmitting (NAT) to 192.168.201.238:5060:
BYE sip:1723@192.168.201.238 SIP/2.0
Via: SIP/2.0/UDP 192.168.201.237:5060;branch=z9hG4bK6393abe5;rport
Max-Forwards: 70
From: "1301" <sip:1301@192.168.201.237>;tag=as648b7460
To: <sip:1723@192.168.201.238>;tag=as23077df2
Call-ID: 4c9015394d3664323306095604d938c2@192.168.201.237
CSeq: 103 BYE
User-Agent: Asterisk PBX
Content-Length: 0


---
Scheduling destruction of SIP dialog '4c9015394d3664323306095604d938c2@192.168.201.237' in 32000 ms (Method: INVITE)

<--- SIP read from 192.168.201.238:5060 --->
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.201.237:5060;branch=z9hG4bK6393abe5;received=192.168.201.237;rport=5060
From: "1301" <sip:1301@192.168.201.237>;tag=as648b7460
To: <sip:1723@192.168.201.238>;tag=as23077df2
Call-ID: 4c9015394d3664323306095604d938c2@192.168.201.237
CSeq: 103 BYE
User-Agent: Asterisk PBX
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Supported: replaces
Contact: <sip:1723@192.168.201.238>
Content-Length: 0


<------------->
--- (11 headers 0 lines) ---
Really destroying SIP dialog '4c9015394d3664323306095604d938c2@192.168.201.237' Method: INVITE

*CLI>
*CLI>
*CLI>
*CLI>
*CLI>
*CLI>
*CLI>
*CLI>
*CLI>
*CLI>
*CLI>
*CLI>
<--- SIP read from 192.168.200.240:5060 --->
NOTIFY sip:192.168.201.237 SIP/2.0
Via: SIP/2.0/UDP 192.168.200.240:5060;branch=z9hG4bK-fd18158f;rport
From: "1302" <sip:1302@192.168.201.237>;tag=d314623ecf3b06dao0
To: <sip:192.168.201.237>
Call-ID: 298ffc8e-d203b0ca@192.168.200.240
CSeq: 57845 NOTIFY
Max-Forwards: 70
Event: keep-alive
User-Agent: Linksys/SPA921-4.1.17
Content-Length: 0


<------------->
--- (10 headers 0 lines) ---

<--- Transmitting (no NAT) to 192.168.200.240:5060 --->
SIP/2.0 489 Bad event
Via: SIP/2.0/UDP 192.168.200.240:5060;branch=z9hG4bK-fd18158f;received=192.168.200.240;rport=5060
From: "1302" <sip:1302@192.168.201.237>;tag=d314623ecf3b06dao0
To: <sip:192.168.201.237>;tag=as106f0595
Call-ID: 298ffc8e-d203b0ca@192.168.200.240
CSeq: 57845 NOTIFY
User-Agent: Asterisk PBX
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Supported: replaces
Content-Length: 0


<------------>