<-- SIP read from 202.171.182.120:12083:
INVITE sip:97131806@203.82.162.33:5060 SIP/2.0
Via: SIP/2.0/UDP 192.168.1.51:5060;rport;branch=z9hG4bKa9f6b09600706
From: 97131800<sip:97131800@203.82.162.33:5060>;tag=b0b53142
To: <sip:97131806@203.82.162.33:5060>
Call-ID: b88677df-c@192.168.1.51
CSeq: 1132883133 INVITE
Max-Forwards: 70
Contact: 97131800<sip:97131800@192.168.1.51:5060>
User-Agent: Soundwin_ATA
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, SUBSCRIBE, REFER, NOTIFY, PRACK, UPDATE, MESSAGE, SERVICE, INFO
Allow-Events: refer
Supported: 100rel, replaces
Content-Length: 308
Content-Type: application/sdp

v=0
o=- 304089172 1303455736 IN IP4 192.168.1.51
s=Voice call
e=you@yourcompany.com
c=IN IP4 192.168.1.51
t=0 0
a=sendrecv
m=image 5000 udptl t38
a=sendrecv
a=T38MaxBitRate:14400
a=T38FaxRateManagement:transferredTCF
a=T38FaxMaxBuffer:280
a=T38FaxMaxDatagram:316
a=T38FaxUdpEC:t38UDPRedundancy

Jul 26 10:25:08 DEBUG[29504]: chan_sip.c:4316 parse_request: Header 0: INVITE sip:97131806@203.82.162.33:5060 SIP/2.0 (46)
Jul 26 10:25:08 DEBUG[29504]: chan_sip.c:4316 parse_request: Header 1: Via: SIP/2.0/UDP 192.168.1.51:5060;rport;branch=z9hG4bKa9f6b09600706 (68)
Jul 26 10:25:08 DEBUG[29504]: chan_sip.c:4316 parse_request: Header 2: From: 97131800<sip:97131800@203.82.162.33:5060>;tag=b0b53142 (60)
Jul 26 10:25:08 DEBUG[29504]: chan_sip.c:4316 parse_request: Header 3: To: <sip:97131806@203.82.162.33:5060> (37)
Jul 26 10:25:08 DEBUG[29504]: chan_sip.c:4316 parse_request: Header 4: Call-ID: b88677df-c@192.168.1.51 (32)
Jul 26 10:25:08 DEBUG[29504]: chan_sip.c:4316 parse_request: Header 5: CSeq: 1132883133 INVITE (23)
Jul 26 10:25:08 DEBUG[29504]: chan_sip.c:4316 parse_request: Header 6: Max-Forwards: 70 (16)
Jul 26 10:25:08 DEBUG[29504]: chan_sip.c:4316 parse_request: Header 7: Contact: 97131800<sip:97131800@192.168.1.51:5060> (49)
Jul 26 10:25:08 DEBUG[29504]: chan_sip.c:4316 parse_request: Header 8: User-Agent: Soundwin_ATA (24)
Jul 26 10:25:08 DEBUG[29504]: chan_sip.c:4316 parse_request: Header 9: Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, SUBSCRIBE, REFER, NOTIFY, PRACK, UPDATE, MESSAGE, SERVICE, INFO (105)
Jul 26 10:25:08 DEBUG[29504]: chan_sip.c:4316 parse_request: Header 10: Allow-Events: refer (19)
Jul 26 10:25:08 DEBUG[29504]: chan_sip.c:4316 parse_request: Header 11: Supported: 100rel, replaces (27)
Jul 26 10:25:08 DEBUG[29504]: chan_sip.c:4316 parse_request: Header 12: Content-Length: 308 (19)
Jul 26 10:25:08 DEBUG[29504]: chan_sip.c:4316 parse_request: Header 13: Content-Type: application/sdp (29)
Jul 26 10:25:08 DEBUG[29504]: chan_sip.c:4316 parse_request: Header 14:  (0)
Jul 26 10:25:08 DEBUG[29504]: chan_sip.c:4348 parse_request: Line: v=0 (3)
Jul 26 10:25:08 DEBUG[29504]: chan_sip.c:4348 parse_request: Line: o=- 304089172 1303455736 IN IP4 192.168.1.51 (44)
Jul 26 10:25:08 DEBUG[29504]: chan_sip.c:4348 parse_request: Line: s=Voice call (12)
Jul 26 10:25:08 DEBUG[29504]: chan_sip.c:4348 parse_request: Line: e=you@yourcompany.com (21)
Jul 26 10:25:08 DEBUG[29504]: chan_sip.c:4348 parse_request: Line: c=IN IP4 192.168.1.51 (21)
Jul 26 10:25:08 DEBUG[29504]: chan_sip.c:4348 parse_request: Line: t=0 0 (5)
Jul 26 10:25:08 DEBUG[29504]: chan_sip.c:4348 parse_request: Line: a=sendrecv (10)
Jul 26 10:25:08 DEBUG[29504]: chan_sip.c:4348 parse_request: Line: m=image 5000 udptl t38 (22)
Jul 26 10:25:08 DEBUG[29504]: chan_sip.c:4348 parse_request: Line: a=sendrecv (10)
Jul 26 10:25:08 DEBUG[29504]: chan_sip.c:4348 parse_request: Line: a=T38MaxBitRate:14400 (21)
Jul 26 10:25:08 DEBUG[29504]: chan_sip.c:4348 parse_request: Line: a=T38FaxRateManagement:transferredTCF (37)
Jul 26 10:25:08 DEBUG[29504]: chan_sip.c:4348 parse_request: Line: a=T38FaxMaxBuffer:280 (21)
Jul 26 10:25:08 DEBUG[29504]: chan_sip.c:4348 parse_request: Line: a=T38FaxMaxDatagram:316 (23)
Jul 26 10:25:08 DEBUG[29504]: chan_sip.c:4348 parse_request: Line: a=T38FaxUdpEC:t38UDPRedundancy (30)
--- (14 headers 14 lines)---
Jul 26 10:25:08 DEBUG[29504]: chan_sip.c:4096 sip_alloc: Allocating new SIP dialog for b88677df-c@192.168.1.51 - INVITE (With RTP)
Jul 26 10:25:08 DEBUG[29504]: chan_sip.c:13969 handle_request: **** Received INVITE (5) - Command in SIP INVITE
Jul 26 10:25:08 DEBUG[29504]: chan_sip.c:1610 parse_sip_options: Begin: parsing SIP "Supported: 100rel, replaces"
Jul 26 10:25:08 DEBUG[29504]: chan_sip.c:1618 parse_sip_options: Found SIP option: -100rel-
Jul 26 10:25:08 DEBUG[29504]: chan_sip.c:1624 parse_sip_options: Matched SIP option: 100rel
Jul 26 10:25:08 DEBUG[29504]: chan_sip.c:1618 parse_sip_options: Found SIP option: -replaces-
Jul 26 10:25:08 DEBUG[29504]: chan_sip.c:1624 parse_sip_options: Matched SIP option: replaces
Sending to 202.171.182.120 : 12083 (NAT)
Using INVITE request as basis request - b88677df-c@192.168.1.51
Jul 26 10:25:08 DEBUG[29504]: chan_sip.c:8615 check_user_full: Setting NAT on RTP to On
Jul 26 10:25:08 DEBUG[29504]: chan_sip.c:8620 check_user_full: Setting NAT on VRTP to On
Jul 26 10:25:08 DEBUG[29504]: chan_sip.c:8625 check_user_full: Setting NAT on UDPTL to On
Reliably Transmitting (NAT) to 202.171.182.120:12083:
SIP/2.0 407 Proxy Authentication Required
Via: SIP/2.0/UDP 192.168.1.51:5060;branch=z9hG4bKa9f6b09600706;received=202.171.182.120;rport=12083
From: 97131800<sip:97131800@203.82.162.33:5060>;tag=b0b53142
To: <sip:97131806@203.82.162.33:5060>;tag=as52cdc8aa
Call-ID: b88677df-c@192.168.1.51
CSeq: 1132883133 INVITE
User-Agent: Asterisk PBX
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Supported: replaces
Contact: <sip:97131806@203.82.162.33>
Proxy-Authenticate: Digest algorithm=MD5, realm="asterisk", nonce="144204ba"
Content-Length: 0


---
Jul 26 10:25:08 DEBUG[29504]: chan_sip.c:4316 parse_request: Header 0: SIP/2.0 407 Proxy Authentication Required (41)
Jul 26 10:25:08 DEBUG[29504]: chan_sip.c:4316 parse_request: Header 1: Via: SIP/2.0/UDP 192.168.1.51:5060;branch=z9hG4bKa9f6b09600706;received=202.171.182.120;rport=12083 (99)
Jul 26 10:25:08 DEBUG[29504]: chan_sip.c:4316 parse_request: Header 2: From: 97131800<sip:97131800@203.82.162.33:5060>;tag=b0b53142 (60)
Jul 26 10:25:08 DEBUG[29504]: chan_sip.c:4316 parse_request: Header 3: To: <sip:97131806@203.82.162.33:5060>;tag=as52cdc8aa (52)
Jul 26 10:25:08 DEBUG[29504]: chan_sip.c:4316 parse_request: Header 4: Call-ID: b88677df-c@192.168.1.51 (32)
Jul 26 10:25:08 DEBUG[29504]: chan_sip.c:4316 parse_request: Header 5: CSeq: 1132883133 INVITE (23)
Jul 26 10:25:08 DEBUG[29504]: chan_sip.c:4316 parse_request: Header 6: User-Agent: Asterisk PBX (24)
Jul 26 10:25:08 DEBUG[29504]: chan_sip.c:4316 parse_request: Header 7: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY (66)
Jul 26 10:25:08 DEBUG[29504]: chan_sip.c:4316 parse_request: Header 8: Supported: replaces (19)
Jul 26 10:25:08 DEBUG[29504]: chan_sip.c:4316 parse_request: Header 9: Contact: <sip:97131806@203.82.162.33> (37)
Jul 26 10:25:08 DEBUG[29504]: chan_sip.c:4316 parse_request: Header 10: Proxy-Authenticate: Digest algorithm=MD5, realm="asterisk", nonce="144204ba" (76)
Jul 26 10:25:08 DEBUG[29504]: chan_sip.c:4316 parse_request: Header 11: Content-Length: 0 (17)
Jul 26 10:25:08 DEBUG[29504]: chan_sip.c:4316 parse_request: Header 12:  (0)
Jul 26 10:25:08 DEBUG[29504]: chan_sip.c:1901 __sip_reliable_xmit: *** SIP TIMER: Initalizing retransmit timer on packet: Id  #36
Scheduling destruction of SIP dialog 'b88677df-c@192.168.1.51' in 32000 ms (Method: INVITE)
Found user '97131800'
thirtythree*CLI>
<-- SIP read from 202.171.182.120:12083:
ACK sip:97131806@203.82.162.33:5060 SIP/2.0
Via: SIP/2.0/UDP 192.168.1.51:5060;rport;branch=z9hG4bKa9f6b09600706
From: 97131800<sip:97131800@203.82.162.33:5060>;tag=b0b53142
To: <sip:97131806@203.82.162.33:5060>;tag=as52cdc8aa
Call-ID: b88677df-c@192.168.1.51
CSeq: 1132883133 ACK
Max-Forwards: 70
User-Agent: Soundwin_ATA
Content-Length: 0


Jul 26 10:25:08 DEBUG[29504]: chan_sip.c:4316 parse_request: Header 0: ACK sip:97131806@203.82.162.33:5060 SIP/2.0 (43)
Jul 26 10:25:08 DEBUG[29504]: chan_sip.c:4316 parse_request: Header 1: Via: SIP/2.0/UDP 192.168.1.51:5060;rport;branch=z9hG4bKa9f6b09600706 (68)
Jul 26 10:25:08 DEBUG[29504]: chan_sip.c:4316 parse_request: Header 2: From: 97131800<sip:97131800@203.82.162.33:5060>;tag=b0b53142 (60)
Jul 26 10:25:08 DEBUG[29504]: chan_sip.c:4316 parse_request: Header 3: To: <sip:97131806@203.82.162.33:5060>;tag=as52cdc8aa (52)
Jul 26 10:25:08 DEBUG[29504]: chan_sip.c:4316 parse_request: Header 4: Call-ID: b88677df-c@192.168.1.51 (32)
Jul 26 10:25:08 DEBUG[29504]: chan_sip.c:4316 parse_request: Header 5: CSeq: 1132883133 ACK (20)
Jul 26 10:25:08 DEBUG[29504]: chan_sip.c:4316 parse_request: Header 6: Max-Forwards: 70 (16)
Jul 26 10:25:08 DEBUG[29504]: chan_sip.c:4316 parse_request: Header 7: User-Agent: Soundwin_ATA (24)
Jul 26 10:25:08 DEBUG[29504]: chan_sip.c:4316 parse_request: Header 8: Content-Length: 0 (17)
Jul 26 10:25:08 DEBUG[29504]: chan_sip.c:4316 parse_request: Header 9:  (0)
--- (9 headers 0 lines)---
Jul 26 10:25:08 DEBUG[29504]: chan_sip.c:13969 handle_request: **** Received ACK (6) - Command in SIP ACK
Jul 26 10:25:08 DEBUG[29504]: chan_sip.c:1994 __sip_ack: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #36
Jul 26 10:25:08 DEBUG[29504]: chan_sip.c:2004 __sip_ack: Stopping retransmission on 'b88677df-c@192.168.1.51' of Response 1132883133: Match Not Found
thirtythree*CLI>
<-- SIP read from 202.171.182.120:12083:
INVITE sip:97131806@203.82.162.33:5060 SIP/2.0
Via: SIP/2.0/UDP 192.168.1.51:5060;rport;branch=z9hG4bK98c794b600707
From: 97131800<sip:97131800@203.82.162.33:5060>;tag=b0b53142
To: <sip:97131806@203.82.162.33:5060>
Call-ID: b88677df-c@192.168.1.51
CSeq: 1132883134 INVITE
Max-Forwards: 70
Contact: 97131800<sip:97131800@192.168.1.51:5060>
User-Agent: Soundwin_ATA
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, SUBSCRIBE, REFER, NOTIFY, PRACK, UPDATE, MESSAGE, SERVICE, INFO
Allow-Events: refer
Supported: 100rel, replaces
Proxy-Authorization: Digest username="97131800", realm="asterisk", uri="sip:97131806@203.82.162.33", nonce="144204ba", response="24fb4523926f3db4c10a8d1976c36415"
Content-Length: 308
Content-Type: application/sdp

v=0
o=- 304089172 1303455736 IN IP4 192.168.1.51
s=Voice call
e=you@yourcompany.com
c=IN IP4 192.168.1.51
t=0 0
a=sendrecv
m=image 5000 udptl t38
a=sendrecv
a=T38MaxBitRate:14400
a=T38FaxRateManagement:transferredTCF
a=T38FaxMaxBuffer:280
a=T38FaxMaxDatagram:316
a=T38FaxUdpEC:t38UDPRedundancy

Jul 26 10:25:08 DEBUG[29504]: chan_sip.c:4316 parse_request: Header 0: INVITE sip:97131806@203.82.162.33:5060 SIP/2.0 (46)
Jul 26 10:25:08 DEBUG[29504]: chan_sip.c:4316 parse_request: Header 1: Via: SIP/2.0/UDP 192.168.1.51:5060;rport;branch=z9hG4bK98c794b600707 (68)
Jul 26 10:25:08 DEBUG[29504]: chan_sip.c:4316 parse_request: Header 2: From: 97131800<sip:97131800@203.82.162.33:5060>;tag=b0b53142 (60)
Jul 26 10:25:08 DEBUG[29504]: chan_sip.c:4316 parse_request: Header 3: To: <sip:97131806@203.82.162.33:5060> (37)
Jul 26 10:25:08 DEBUG[29504]: chan_sip.c:4316 parse_request: Header 4: Call-ID: b88677df-c@192.168.1.51 (32)
Jul 26 10:25:08 DEBUG[29504]: chan_sip.c:4316 parse_request: Header 5: CSeq: 1132883134 INVITE (23)
Jul 26 10:25:08 DEBUG[29504]: chan_sip.c:4316 parse_request: Header 6: Max-Forwards: 70 (16)
Jul 26 10:25:08 DEBUG[29504]: chan_sip.c:4316 parse_request: Header 7: Contact: 97131800<sip:97131800@192.168.1.51:5060> (49)
Jul 26 10:25:08 DEBUG[29504]: chan_sip.c:4316 parse_request: Header 8: User-Agent: Soundwin_ATA (24)
Jul 26 10:25:08 DEBUG[29504]: chan_sip.c:4316 parse_request: Header 9: Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, SUBSCRIBE, REFER, NOTIFY, PRACK, UPDATE, MESSAGE, SERVICE, INFO (105)
Jul 26 10:25:08 DEBUG[29504]: chan_sip.c:4316 parse_request: Header 10: Allow-Events: refer (19)
Jul 26 10:25:08 DEBUG[29504]: chan_sip.c:4316 parse_request: Header 11: Supported: 100rel, replaces (27)
Jul 26 10:25:08 DEBUG[29504]: chan_sip.c:4316 parse_request: Header 12: Proxy-Authorization: Digest username="97131800", realm="asterisk", uri="sip:97131806@203.82.162.33", nonce="144204ba", response="24fb4523926f3db4c10a8d1976c36415" (162)
Jul 26 10:25:08 DEBUG[29504]: chan_sip.c:4316 parse_request: Header 13: Content-Length: 308 (19)
Jul 26 10:25:08 DEBUG[29504]: chan_sip.c:4316 parse_request: Header 14: Content-Type: application/sdp (29)
Jul 26 10:25:08 DEBUG[29504]: chan_sip.c:4316 parse_request: Header 15:  (0)
Jul 26 10:25:08 DEBUG[29504]: chan_sip.c:4348 parse_request: Line: v=0 (3)
Jul 26 10:25:08 DEBUG[29504]: chan_sip.c:4348 parse_request: Line: o=- 304089172 1303455736 IN IP4 192.168.1.51 (44)
Jul 26 10:25:08 DEBUG[29504]: chan_sip.c:4348 parse_request: Line: s=Voice call (12)
Jul 26 10:25:08 DEBUG[29504]: chan_sip.c:4348 parse_request: Line: e=you@yourcompany.com (21)
Jul 26 10:25:08 DEBUG[29504]: chan_sip.c:4348 parse_request: Line: c=IN IP4 192.168.1.51 (21)
Jul 26 10:25:08 DEBUG[29504]: chan_sip.c:4348 parse_request: Line: t=0 0 (5)
Jul 26 10:25:08 DEBUG[29504]: chan_sip.c:4348 parse_request: Line: a=sendrecv (10)
Jul 26 10:25:08 DEBUG[29504]: chan_sip.c:4348 parse_request: Line: m=image 5000 udptl t38 (22)
Jul 26 10:25:08 DEBUG[29504]: chan_sip.c:4348 parse_request: Line: a=sendrecv (10)
Jul 26 10:25:08 DEBUG[29504]: chan_sip.c:4348 parse_request: Line: a=T38MaxBitRate:14400 (21)
Jul 26 10:25:08 DEBUG[29504]: chan_sip.c:4348 parse_request: Line: a=T38FaxRateManagement:transferredTCF (37)
Jul 26 10:25:08 DEBUG[29504]: chan_sip.c:4348 parse_request: Line: a=T38FaxMaxBuffer:280 (21)
Jul 26 10:25:08 DEBUG[29504]: chan_sip.c:4348 parse_request: Line: a=T38FaxMaxDatagram:316 (23)
Jul 26 10:25:08 DEBUG[29504]: chan_sip.c:4348 parse_request: Line: a=T38FaxUdpEC:t38UDPRedundancy (30)
--- (15 headers 14 lines)---
Jul 26 10:25:08 DEBUG[29504]: chan_sip.c:13969 handle_request: **** Received INVITE (5) - Command in SIP INVITE
Sending to 202.171.182.120 : 12083 (NAT)
Using INVITE request as basis request - b88677df-c@192.168.1.51
Jul 26 10:25:08 DEBUG[29504]: chan_sip.c:8615 check_user_full: Setting NAT on RTP to On
Jul 26 10:25:08 DEBUG[29504]: chan_sip.c:8620 check_user_full: Setting NAT on VRTP to On
Jul 26 10:25:08 DEBUG[29504]: chan_sip.c:8625 check_user_full: Setting NAT on UDPTL to On
Found user '97131800'
Got T.38 offer in SDP in dialog b88677df-c@192.168.1.51
Jul 26 10:25:08 DEBUG[29504]: chan_sip.c:4569 process_sdp: T38 state changed to 3 on channel <none>
Peer doesn't provide audio
Jul 26 10:25:08 DEBUG[29504]: chan_sip.c:4631 process_sdp: Peer T.38 UDPTL is at port 192.168.1.51:5000
Jul 26 10:25:08 DEBUG[29504]: chan_sip.c:4723 process_sdp: T38MaxBitRate: 14400
Jul 26 10:25:08 DEBUG[29504]: chan_sip.c:4785 process_sdp: RateMangement: transferredTCF
Jul 26 10:25:08 DEBUG[29504]: chan_sip.c:4718 process_sdp: MaxBufferSize:280
Jul 26 10:25:08 DEBUG[29504]: chan_sip.c:4757 process_sdp: FaxMaxDatagram: 316
Jul 26 10:25:08 DEBUG[29504]: chan_sip.c:4794 process_sdp: UDP EC: t38UDPRedundancy
Jul 26 10:25:08 DEBUG[29504]: chan_sip.c:4814 process_sdp: Our T38 capability = (3856), peer T38 capability (16160), joint T38 capability (3872)
Capabilities: us - 0xc (ulaw|alaw), peer - audio=0x0 (nothing)/video=0x0 (nothing), combined - 0x0 (nothing)
Non-codec capabilities (dtmf): us - 0x1 (telephone-event), peer - 0x0 (nothing), combined - 0x0 (nothing)
Peer audio RTP is at port 192.168.1.51:5000
Peer video RTP is at port 192.168.1.51:53513
Jul 26 10:25:08 DEBUG[29504]: chan_sip.c:4895 process_sdp: We're settling with these formats: 0x0 (nothing)
Jul 26 10:25:08 DEBUG[29504]: chan_sip.c:12782 handle_request_invite: Checking SIP call limits for device 97131800
Jul 26 10:25:08 DEBUG[29504]: chan_sip.c:2869 update_call_counter: Updating call counter for incoming call
Looking for 97131806 in from-internal (domain 203.82.162.33)
Jul 26 10:25:08 DEBUG[29504]: chan_sip.c:3598 sip_new: *** Our native formats are 0x4 (ulaw)
Jul 26 10:25:08 DEBUG[29504]: chan_sip.c:3599 sip_new: *** Joint capabilities are 0x0 (nothing)
Jul 26 10:25:08 DEBUG[29504]: chan_sip.c:3600 sip_new: *** Our capabilities are 0xc (ulaw|alaw)
Jul 26 10:25:08 DEBUG[29504]: chan_sip.c:3601 sip_new: *** AST_CODEC_CHOOSE formats are 0x4 (ulaw)
Jul 26 10:25:08 DEBUG[29504]: chan_sip.c:3624 sip_new: This channel will not be able to handle video.
Jul 26 10:25:08 DEBUG[29504]: chan_sip.c:7581 build_route: build_route: Contact hop: 97131800<sip:97131800@192.168.1.51:5060>
list_route: hop: <sip:97131800@192.168.1.51:5060>
Jul 26 10:25:08 DEBUG[29504]: chan_sip.c:12852 handle_request_invite: SIP/97131800-b7b03990: New call is still down.... Trying...
Transmitting (NAT) to 202.171.182.120:12083:
SIP/2.0 100 Trying
Via: SIP/2.0/UDP 192.168.1.51:5060;branch=z9hG4bK98c794b600707;received=202.171.182.120;rport=12083
From: 97131800<sip:97131800@203.82.162.33:5060>;tag=b0b53142
To: <sip:97131806@203.82.162.33:5060>
Call-ID: b88677df-c@192.168.1.51
CSeq: 1132883134 INVITE
User-Agent: Asterisk PBX
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Supported: replaces
Contact: <sip:97131806@203.82.162.33>
Content-Length: 0


---
Jul 26 10:25:08 DEBUG[29504]: chan_sip.c:4316 parse_request: Header 0: SIP/2.0 100 Trying (18)
Jul 26 10:25:08 DEBUG[29504]: chan_sip.c:4316 parse_request: Header 1: Via: SIP/2.0/UDP 192.168.1.51:5060;branch=z9hG4bK98c794b600707;received=202.171.182.120;rport=12083 (99)
Jul 26 10:25:08 DEBUG[29504]: chan_sip.c:4316 parse_request: Header 2: From: 97131800<sip:97131800@203.82.162.33:5060>;tag=b0b53142 (60)
Jul 26 10:25:08 DEBUG[29504]: chan_sip.c:4316 parse_request: Header 3: To: <sip:97131806@203.82.162.33:5060> (37)
Jul 26 10:25:08 DEBUG[29504]: chan_sip.c:4316 parse_request: Header 4: Call-ID: b88677df-c@192.168.1.51 (32)
Jul 26 10:25:08 DEBUG[29504]: chan_sip.c:4316 parse_request: Header 5: CSeq: 1132883134 INVITE (23)
Jul 26 10:25:08 DEBUG[29504]: chan_sip.c:4316 parse_request: Header 6: User-Agent: Asterisk PBX (24)
Jul 26 10:25:08 DEBUG[29504]: chan_sip.c:4316 parse_request: Header 7: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY (66)
Jul 26 10:25:08 DEBUG[29504]: chan_sip.c:4316 parse_request: Header 8: Supported: replaces (19)
Jul 26 10:25:08 DEBUG[29504]: chan_sip.c:4316 parse_request: Header 9: Contact: <sip:97131806@203.82.162.33> (37)
Jul 26 10:25:08 DEBUG[29504]: chan_sip.c:4316 parse_request: Header 10: Content-Length: 0 (17)
Jul 26 10:25:08 DEBUG[29504]: chan_sip.c:4316 parse_request: Header 11:  (0)
Jul 26 10:25:08 DEBUG[29504]: devicestate.c:303 __ast_device_state_changed_literal: Notification of state change to be queued on device/channel SIP/97131800-b7b03990
Jul 26 10:25:08 DEBUG[29497]: devicestate.c:161 ast_device_state: No provider found, checking channel drivers for SIP - 97131800
Jul 26 10:25:08 DEBUG[29497]: chan_sip.c:14533 sip_devicestate: Checking device state for peer 97131800
Jul 26 10:25:08 DEBUG[29497]: devicestate.c:287 do_state_change: Changing state for SIP/97131800 - state 1 (Not in use)
Jul 26 10:25:08 DEBUG[29497]: devicestate.c:161 ast_device_state: No provider found, checking channel drivers for SIP - 97131800
Jul 26 10:25:08 DEBUG[29497]: chan_sip.c:14533 sip_devicestate: Checking device state for peer 97131800
Jul 26 10:25:08 DEBUG[29579]: pbx.c:1675 pbx_extension_helper: Launching 'Macro'
Jul 26 10:25:08 DEBUG[29580]: app_queue.c:536 changethread: Device 'SIP/97131800' changed to state '1' (Not in use) but we don't care because they're not a member of any queue.
Jul 26 10:25:08 DEBUG[29579]: pbx.c:1675 pbx_extension_helper: Launching 'Macro'
Jul 26 10:25:08 DEBUG[29579]: pbx.c:1529 pbx_substitute_variables_helper_full: Function result is '97131800'
Jul 26 10:25:08 DEBUG[29579]: pbx.c:1529 pbx_substitute_variables_helper_full: Function result is '97131800'
Jul 26 10:25:08 DEBUG[29579]: pbx.c:1675 pbx_extension_helper: Launching 'Set'
Jul 26 10:25:08 DEBUG[29579]: pbx.c:1529 pbx_substitute_variables_helper_full: Function result is '97131800'
Jul 26 10:25:08 DEBUG[29579]: db.c:197 ast_db_get: Unable to find key '97131800/emergency_cid' in family 'DEVICE'
Jul 26 10:25:08 DEBUG[29579]: func_db.c:69 function_db_read: DB: DEVICE/97131800/emergency_cid not found in database.
Jul 26 10:25:08 DEBUG[29579]: pbx.c:1529 pbx_substitute_variables_helper_full: Function result is ''
Jul 26 10:25:08 DEBUG[29579]: pbx.c:1675 pbx_extension_helper: Launching 'Set'
Jul 26 10:25:08 DEBUG[29579]: pbx.c:1529 pbx_substitute_variables_helper_full: Function result is '97131800'
Jul 26 10:25:08 DEBUG[29579]: pbx.c:1675 pbx_extension_helper: Launching 'Set'
Jul 26 10:25:08 DEBUG[29579]: pbx.c:1596 pbx_substitute_variables_helper_full: Expression result is '0'
Jul 26 10:25:08 DEBUG[29579]: pbx.c:1675 pbx_extension_helper: Launching 'GotoIf'
Jul 26 10:25:08 DEBUG[29579]: pbx.c:5554 pbx_builtin_gotoif: Not taking any branch
Jul 26 10:25:08 DEBUG[29579]: pbx.c:1675 pbx_extension_helper: Launching 'Set'
Jul 26 10:25:08 DEBUG[29579]: pbx.c:1529 pbx_substitute_variables_helper_full: Function result is '"97131800" <97131800>'
Jul 26 10:25:08 DEBUG[29579]: pbx.c:1675 pbx_extension_helper: Launching 'NoOp'
Jul 26 10:25:08 DEBUG[29579]: pbx.c:1675 pbx_extension_helper: Launching 'Set'
Jul 26 10:25:08 DEBUG[29579]: pbx.c:1675 pbx_extension_helper: Launching 'Macro'
Jul 26 10:25:08 DEBUG[29579]: pbx.c:1529 pbx_substitute_variables_helper_full: Function result is '0'
Jul 26 10:25:08 DEBUG[29579]: pbx.c:1675 pbx_extension_helper: Launching 'GotoIf'
Jul 26 10:25:08 DEBUG[29579]: pbx.c:1675 pbx_extension_helper: Launching 'AGI'
Jul 26 10:25:10 DEBUG[29579]: pbx.c:1675 pbx_extension_helper: Launching 'NoOp'
Jul 26 10:25:10 DEBUG[29579]: pbx.c:1675 pbx_extension_helper: Launching 'Macro'
Jul 26 10:25:10 DEBUG[29579]: pbx.c:1675 pbx_extension_helper: Launching 'AGI'
Jul 26 10:25:10 DEBUG[29579]: db.c:197 ast_db_get: Unable to find key '97131806' in family 'CF'
Jul 26 10:25:10 DEBUG[29579]: db.c:197 ast_db_get: Unable to find key '97131806' in family 'DND'
Jul 26 10:25:10 DEBUG[29579]: db.c:197 ast_db_get: Unable to find key '97131806' in family 'CW'
Jul 26 10:25:10 DEBUG[29579]: db.c:197 ast_db_get: Unable to find key '97131806' in family 'CFB'
Jul 26 10:25:10 DEBUG[29583]: manager.c:1803 process_message: Manager received command 'Login'
Jul 26 10:25:10 DEBUG[29583]: config.c:718 config_text_file_load: Parsing /etc/asterisk/manager.conf
Jul 26 10:25:10 DEBUG[29583]: config.c:718 config_text_file_load: Parsing /etc/asterisk/manager_custom.conf
Jul 26 10:25:10 DEBUG[29583]: acl.c:199 ast_append_ha: 0.0.0.0/0.0.0.0/0.0.0.0 appended to acl for peer
Jul 26 10:25:10 DEBUG[29583]: acl.c:199 ast_append_ha: 127.0.0.1/255.255.255.0/255.255.255.0 appended to acl for peer
Jul 26 10:25:10 DEBUG[29583]: acl.c:213 ast_apply_ha: ##### Testing 127.0.0.1 with 0.0.0.0
Jul 26 10:25:10 DEBUG[29583]: acl.c:213 ast_apply_ha: ##### Testing 127.0.0.1 with 127.0.0.0
Jul 26 10:25:10 DEBUG[29583]: manager.c:1803 process_message: Manager received command 'ExtensionState'
Jul 26 10:25:10 DEBUG[29583]: devicestate.c:161 ast_device_state: No provider found, checking channel drivers for SIP - 97131806
Jul 26 10:25:10 DEBUG[29583]: chan_sip.c:14533 sip_devicestate: Checking device state for peer 97131806
Jul 26 10:25:10 DEBUG[29583]: manager.c:1803 process_message: Manager received command 'Logoff'
Jul 26 10:25:10 DEBUG[29579]: pbx.c:1675 pbx_extension_helper: Launching 'Dial'
Jul 26 10:25:10 DEBUG[29579]: chan_sip.c:14589 sip_request_call: Asked to create a SIP channel with formats: 0x4 (ulaw)
Jul 26 10:25:10 DEBUG[29579]: chan_sip.c:4096 sip_alloc: Allocating new SIP dialog for (No Call-ID) - INVITE (With RTP)
Jul 26 10:25:10 DEBUG[29579]: chan_sip.c:2500 create_addr_from_peer: Our T38 capability (3856)
Jul 26 10:25:10 DEBUG[29579]: chan_sip.c:2510 create_addr_from_peer: Setting NAT on RTP to On
Jul 26 10:25:10 DEBUG[29579]: chan_sip.c:2516 create_addr_from_peer: Setting NAT on VRTP to On
Jul 26 10:25:10 DEBUG[29579]: chan_sip.c:2522 create_addr_from_peer: Setting NAT on UDPTL to On
Jul 26 10:25:10 DEBUG[29579]: chan_sip.c:3598 sip_new: *** Our native formats are 0x4 (ulaw)
Jul 26 10:25:10 DEBUG[29579]: chan_sip.c:3599 sip_new: *** Joint capabilities are 0x0 (nothing)
Jul 26 10:25:10 DEBUG[29579]: chan_sip.c:3600 sip_new: *** Our capabilities are 0xc (ulaw|alaw)
Jul 26 10:25:10 DEBUG[29579]: chan_sip.c:3601 sip_new: *** AST_CODEC_CHOOSE formats are 0x4 (ulaw)
Jul 26 10:25:10 DEBUG[29579]: chan_sip.c:3603 sip_new: *** Our preferred formats from the incoming channel are 0x4 (ulaw)
Jul 26 10:25:10 DEBUG[29579]: chan_sip.c:3624 sip_new: This channel will not be able to handle video.
Jul 26 10:25:10 DEBUG[29579]: channel.c:2964 ast_channel_inherit_variables: Not copying variable STACK-macro-dial-s-10.
Jul 26 10:25:10 DEBUG[29579]: channel.c:2964 ast_channel_inherit_variables: Not copying variable MACRO_DEPTH.
Jul 26 10:25:10 DEBUG[29579]: channel.c:2964 ast_channel_inherit_variables: Not copying variable AGISTATUS.
Jul 26 10:25:10 DEBUG[29579]: channel.c:2964 ast_channel_inherit_variables: Not copying variable ds.
Jul 26 10:25:10 DEBUG[29579]: channel.c:2964 ast_channel_inherit_variables: Not copying variable STACK-macro-dial-s-1.
Jul 26 10:25:10 DEBUG[29579]: channel.c:2964 ast_channel_inherit_variables: Not copying variable ARG3.
Jul 26 10:25:10 DEBUG[29579]: channel.c:2964 ast_channel_inherit_variables: Not copying variable ARG2.
Jul 26 10:25:10 DEBUG[29579]: channel.c:2964 ast_channel_inherit_variables: Not copying variable ARG1.
Jul 26 10:25:10 DEBUG[29579]: channel.c:2964 ast_channel_inherit_variables: Not copying variable MACRO_PRIORITY.
Jul 26 10:25:10 DEBUG[29579]: channel.c:2964 ast_channel_inherit_variables: Not copying variable MACRO_CONTEXT.
Jul 26 10:25:10 DEBUG[29579]: channel.c:2964 ast_channel_inherit_variables: Not copying variable MACRO_EXTEN.
Jul 26 10:25:10 DEBUG[29579]: channel.c:2964 ast_channel_inherit_variables: Not copying variable STACK-macro-exten-vm-s-4.
Jul 26 10:25:10 DEBUG[29579]: channel.c:2964 ast_channel_inherit_variables: Not copying variable STACK-macro-record-enable-s-5.
Jul 26 10:25:10 DEBUG[29579]: channel.c:2964 ast_channel_inherit_variables: Not copying variable STACK-macro-record-enable-s-4.
Jul 26 10:25:10 DEBUG[29579]: channel.c:2964 ast_channel_inherit_variables: Not copying variable STACK-macro-record-enable-s-1.
Jul 26 10:25:10 DEBUG[29579]: channel.c:2964 ast_channel_inherit_variables: Not copying variable STACK-macro-exten-vm-s-3.
Jul 26 10:25:10 DEBUG[29579]: channel.c:2964 ast_channel_inherit_variables: Not copying variable FROMCONTEXT.
Jul 26 10:25:10 DEBUG[29579]: channel.c:2964 ast_channel_inherit_variables: Not copying variable STACK-macro-exten-vm-s-2.
Jul 26 10:25:10 DEBUG[29579]: channel.c:2964 ast_channel_inherit_variables: Not copying variable STACK-macro-user-callerid-s-6.
Jul 26 10:25:10 DEBUG[29579]: channel.c:2964 ast_channel_inherit_variables: Not copying variable STACK-macro-user-callerid-s-5.
Jul 26 10:25:10 DEBUG[29579]: channel.c:2964 ast_channel_inherit_variables: Not copying variable STACK-macro-user-callerid-s-4.
Jul 26 10:25:10 DEBUG[29579]: channel.c:2964 ast_channel_inherit_variables: Not copying variable AMPUSERCIDNAME.
Jul 26 10:25:10 DEBUG[29579]: channel.c:2964 ast_channel_inherit_variables: Not copying variable STACK-macro-user-callerid-s-3.
Jul 26 10:25:10 DEBUG[29579]: channel.c:2964 ast_channel_inherit_variables: Not copying variable DB_RESULT.
Jul 26 10:25:10 DEBUG[29579]: channel.c:2964 ast_channel_inherit_variables: Not copying variable EMERGENCYCID.
Jul 26 10:25:10 DEBUG[29579]: channel.c:2964 ast_channel_inherit_variables: Not copying variable STACK-macro-user-callerid-s-2.
Jul 26 10:25:10 DEBUG[29579]: channel.c:2964 ast_channel_inherit_variables: Not copying variable AMPUSER.
Jul 26 10:25:10 DEBUG[29579]: channel.c:2964 ast_channel_inherit_variables: Not copying variable STACK-macro-user-callerid-s-1.
Jul 26 10:25:10 DEBUG[29579]: channel.c:2964 ast_channel_inherit_variables: Not copying variable STACK-macro-exten-vm-s-1.
Jul 26 10:25:10 DEBUG[29579]: channel.c:2964 ast_channel_inherit_variables: Not copying variable STACK-from-internal-97131806-1.
Jul 26 10:25:10 DEBUG[29579]: channel.c:2964 ast_channel_inherit_variables: Not copying variable SIPCALLID.
Jul 26 10:25:10 DEBUG[29579]: channel.c:2964 ast_channel_inherit_variables: Not copying variable SIPUSERAGENT.
Jul 26 10:25:10 DEBUG[29579]: channel.c:2964 ast_channel_inherit_variables: Not copying variable SIPDOMAIN.
Jul 26 10:25:10 DEBUG[29579]: channel.c:2964 ast_channel_inherit_variables: Not copying variable SIPURI.
Jul 26 10:25:10 DEBUG[29579]: chan_sip.c:2708 sip_call: Outgoing Call for 97131806
Jul 26 10:25:10 DEBUG[29579]: chan_sip.c:2869 update_call_counter: Updating call counter for outgoing call
Jul 26 10:25:10 DEBUG[29579]: chan_sip.c:2716 sip_call: Our T38 capability (3856), joint T38 capability (3856)
Jul 26 10:25:10 DEBUG[29579]: chan_sip.c:5800 add_sdp: ** Our capability: 0xc (ulaw|alaw) Video flag: False
Jul 26 10:25:10 DEBUG[29579]: chan_sip.c:5801 add_sdp: ** Our prefcodec: 0x4 (ulaw)
Audio is at 203.82.162.33 port 12402
Adding codec 0x4 (ulaw) to SDP
Adding codec 0x8 (alaw) to SDP
Adding non-codec 0x1 (telephone-event) to SDP
Jul 26 10:25:10 DEBUG[29579]: chan_sip.c:5953 add_sdp: -- Done with adding codecs to SDP
Jul 26 10:25:10 DEBUG[29579]: chan_sip.c:5992 add_sdp: Done building SDP. Settling with this capability: 0xc (ulaw|alaw)
Jul 26 10:25:10 DEBUG[29579]: chan_sip.c:4316 parse_request: Header 0: INVITE sip:97131806@192.168.1.68:5060 SIP/2.0 (45)
Jul 26 10:25:10 DEBUG[29579]: chan_sip.c:4316 parse_request: Header 1: Via: SIP/2.0/UDP 203.82.162.33:5060;branch=z9hG4bK3159ce5e;rport (64)
Jul 26 10:25:10 DEBUG[29579]: chan_sip.c:4316 parse_request: Header 2: From: "97131800" <sip:97131800@203.82.162.33>;tag=as22533180 (60)
Jul 26 10:25:10 DEBUG[29579]: chan_sip.c:4316 parse_request: Header 3: To: <sip:97131806@192.168.1.68:5060> (36)
Jul 26 10:25:10 DEBUG[29579]: chan_sip.c:4316 parse_request: Header 4: Contact: <sip:97131800@203.82.162.33> (37)
Jul 26 10:25:10 DEBUG[29579]: chan_sip.c:4316 parse_request: Header 5: Call-ID: 14c6543c3a0e7f493547194778454a0e@203.82.162.33 (55)
Jul 26 10:25:10 DEBUG[29579]: chan_sip.c:4316 parse_request: Header 6: CSeq: 102 INVITE (16)
Jul 26 10:25:10 DEBUG[29579]: chan_sip.c:4316 parse_request: Header 7: User-Agent: Asterisk PBX (24)
Jul 26 10:25:10 DEBUG[29579]: chan_sip.c:4316 parse_request: Header 8: Max-Forwards: 70 (16)
Jul 26 10:25:10 DEBUG[29579]: chan_sip.c:4316 parse_request: Header 9: Date: Wed, 26 Jul 2006 00:25:10 GMT (35)
Jul 26 10:25:10 DEBUG[29579]: chan_sip.c:4316 parse_request: Header 10: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY (66)
Jul 26 10:25:10 DEBUG[29579]: chan_sip.c:4316 parse_request: Header 11: Supported: replaces (19)
Jul 26 10:25:10 DEBUG[29579]: chan_sip.c:4316 parse_request: Header 12: Content-Type: application/sdp (29)
Jul 26 10:25:10 DEBUG[29579]: chan_sip.c:4316 parse_request: Header 13: Content-Length: 254 (19)
Jul 26 10:25:10 DEBUG[29579]: chan_sip.c:4316 parse_request: Header 14:  (0)
Jul 26 10:25:10 DEBUG[29579]: chan_sip.c:4348 parse_request: Line: v=0 (3)
Jul 26 10:25:10 DEBUG[29579]: chan_sip.c:4348 parse_request: Line: o=root 29493 29493 IN IP4 203.82.162.33 (39)
Jul 26 10:25:10 DEBUG[29579]: chan_sip.c:4348 parse_request: Line: s=session (9)
Jul 26 10:25:10 DEBUG[29579]: chan_sip.c:4348 parse_request: Line: c=IN IP4 203.82.162.33 (22)
Jul 26 10:25:10 DEBUG[29579]: chan_sip.c:4348 parse_request: Line: t=0 0 (5)
Jul 26 10:25:10 DEBUG[29579]: chan_sip.c:4348 parse_request: Line: m=audio 12402 RTP/AVP 0 8 101 (29)
Jul 26 10:25:10 DEBUG[29579]: chan_sip.c:4348 parse_request: Line: a=rtpmap:0 PCMU/8000 (20)
Jul 26 10:25:10 DEBUG[29579]: chan_sip.c:4348 parse_request: Line: a=rtpmap:8 PCMA/8000 (20)
Jul 26 10:25:10 DEBUG[29579]: chan_sip.c:4348 parse_request: Line: a=rtpmap:101 telephone-event/8000 (33)
Jul 26 10:25:10 DEBUG[29579]: chan_sip.c:4348 parse_request: Line: a=fmtp:101 0-16 (15)
Jul 26 10:25:10 DEBUG[29579]: chan_sip.c:4348 parse_request: Line: a=silenceSupp:off - - - - (25)
Jul 26 10:25:10 DEBUG[29579]: chan_sip.c:4348 parse_request: Line: a=sendrecv (10)
Reliably Transmitting (NAT) to 202.171.182.120:5060:
INVITE sip:97131806@192.168.1.68:5060 SIP/2.0
Via: SIP/2.0/UDP 203.82.162.33:5060;branch=z9hG4bK3159ce5e;rport
From: "97131800" <sip:97131800@203.82.162.33>;tag=as22533180
To: <sip:97131806@192.168.1.68:5060>
Contact: <sip:97131800@203.82.162.33>
Call-ID: 14c6543c3a0e7f493547194778454a0e@203.82.162.33
CSeq: 102 INVITE
User-Agent: Asterisk PBX
Max-Forwards: 70
Date: Wed, 26 Jul 2006 00:25:10 GMT
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Supported: replaces
Content-Type: application/sdp
Content-Length: 254

v=0
o=root 29493 29493 IN IP4 203.82.162.33
s=session
c=IN IP4 203.82.162.33
t=0 0
m=audio 12402 RTP/AVP 0 8 101
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=sendrecv

---
Jul 26 10:25:10 DEBUG[29579]: chan_sip.c:4316 parse_request: Header 0: INVITE sip:97131806@192.168.1.68:5060 SIP/2.0 (45)
Jul 26 10:25:10 DEBUG[29579]: chan_sip.c:4316 parse_request: Header 1: Via: SIP/2.0/UDP 203.82.162.33:5060;branch=z9hG4bK3159ce5e;rport (64)
Jul 26 10:25:10 DEBUG[29579]: chan_sip.c:4316 parse_request: Header 2: From: "97131800" <sip:97131800@203.82.162.33>;tag=as22533180 (60)
Jul 26 10:25:10 DEBUG[29579]: chan_sip.c:4316 parse_request: Header 3: To: <sip:97131806@192.168.1.68:5060> (36)
Jul 26 10:25:10 DEBUG[29579]: chan_sip.c:4316 parse_request: Header 4: Contact: <sip:97131800@203.82.162.33> (37)
Jul 26 10:25:10 DEBUG[29579]: chan_sip.c:4316 parse_request: Header 5: Call-ID: 14c6543c3a0e7f493547194778454a0e@203.82.162.33 (55)
Jul 26 10:25:10 DEBUG[29579]: chan_sip.c:4316 parse_request: Header 6: CSeq: 102 INVITE (16)
Jul 26 10:25:10 DEBUG[29579]: chan_sip.c:4316 parse_request: Header 7: User-Agent: Asterisk PBX (24)
Jul 26 10:25:10 DEBUG[29579]: chan_sip.c:4316 parse_request: Header 8: Max-Forwards: 70 (16)
Jul 26 10:25:10 DEBUG[29579]: chan_sip.c:4316 parse_request: Header 9: Date: Wed, 26 Jul 2006 00:25:10 GMT (35)
Jul 26 10:25:10 DEBUG[29579]: chan_sip.c:4316 parse_request: Header 10: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY (66)
Jul 26 10:25:10 DEBUG[29579]: chan_sip.c:4316 parse_request: Header 11: Supported: replaces (19)
Jul 26 10:25:10 DEBUG[29579]: chan_sip.c:4316 parse_request: Header 12: Content-Type: application/sdp (29)
Jul 26 10:25:10 DEBUG[29579]: chan_sip.c:4316 parse_request: Header 13: Content-Length: 254 (19)
Jul 26 10:25:10 DEBUG[29579]: chan_sip.c:4316 parse_request: Header 14:  (0)
Jul 26 10:25:10 DEBUG[29579]: chan_sip.c:4348 parse_request: Line: v=0 (3)
Jul 26 10:25:10 DEBUG[29579]: chan_sip.c:4348 parse_request: Line: o=root 29493 29493 IN IP4 203.82.162.33 (39)
Jul 26 10:25:10 DEBUG[29579]: chan_sip.c:4348 parse_request: Line: s=session (9)
Jul 26 10:25:10 DEBUG[29579]: chan_sip.c:4348 parse_request: Line: c=IN IP4 203.82.162.33 (22)
Jul 26 10:25:10 DEBUG[29579]: chan_sip.c:4348 parse_request: Line: t=0 0 (5)
Jul 26 10:25:10 DEBUG[29579]: chan_sip.c:4348 parse_request: Line: m=audio 12402 RTP/AVP 0 8 101 (29)
Jul 26 10:25:10 DEBUG[29579]: chan_sip.c:4348 parse_request: Line: a=rtpmap:0 PCMU/8000 (20)
Jul 26 10:25:10 DEBUG[29579]: chan_sip.c:4348 parse_request: Line: a=rtpmap:8 PCMA/8000 (20)
Jul 26 10:25:10 DEBUG[29579]: chan_sip.c:4348 parse_request: Line: a=rtpmap:101 telephone-event/8000 (33)
Jul 26 10:25:10 DEBUG[29579]: chan_sip.c:4348 parse_request: Line: a=fmtp:101 0-16 (15)
Jul 26 10:25:10 DEBUG[29579]: chan_sip.c:4348 parse_request: Line: a=silenceSupp:off - - - - (25)
Jul 26 10:25:10 DEBUG[29579]: chan_sip.c:4348 parse_request: Line: a=sendrecv (10)
Jul 26 10:25:10 DEBUG[29579]: chan_sip.c:1901 __sip_reliable_xmit: *** SIP TIMER: Initalizing retransmit timer on packet: Id  #38
Jul 26 10:25:10 DEBUG[29579]: channel.c:2499 set_format: Set channel SIP/97131806-b7b19e90 to read format ulaw
Jul 26 10:25:10 DEBUG[29579]: channel.c:2499 set_format: Set channel SIP/97131800-b7b03990 to write format ulaw
Jul 26 10:25:10 DEBUG[29579]: channel.c:2499 set_format: Set channel SIP/97131800-b7b03990 to read format ulaw
Jul 26 10:25:10 DEBUG[29579]: channel.c:2499 set_format: Set channel SIP/97131806-b7b19e90 to write format ulaw
thirtythree*CLI>
<-- SIP read from 202.171.182.120:5060:
SIP/2.0 100 Trying
Via: SIP/2.0/UDP 203.82.162.33:5060;rport=5060;received=203.82.162.33;branch=z9hG4bK3159ce5e
From: "97131800"<sip:97131800@203.82.162.33>;tag=as22533180
To: <sip:97131806@192.168.1.68:5060>
Call-ID: 14c6543c3a0e7f493547194778454a0e@203.82.162.33
CSeq: 102 INVITE
Max-Forwards: 70
User-Agent: Soundwin_ATA
Content-Length: 0


Jul 26 10:25:10 DEBUG[29504]: chan_sip.c:4316 parse_request: Header 0: SIP/2.0 100 Trying (18)
Jul 26 10:25:10 DEBUG[29504]: chan_sip.c:4316 parse_request: Header 1: Via: SIP/2.0/UDP 203.82.162.33:5060;rport=5060;received=203.82.162.33;branch=z9hG4bK3159ce5e (92)
Jul 26 10:25:10 DEBUG[29504]: chan_sip.c:4316 parse_request: Header 2: From: "97131800"<sip:97131800@203.82.162.33>;tag=as22533180 (59)
Jul 26 10:25:10 DEBUG[29504]: chan_sip.c:4316 parse_request: Header 3: To: <sip:97131806@192.168.1.68:5060> (36)
Jul 26 10:25:10 DEBUG[29504]: chan_sip.c:4316 parse_request: Header 4: Call-ID: 14c6543c3a0e7f493547194778454a0e@203.82.162.33 (55)
Jul 26 10:25:10 DEBUG[29504]: chan_sip.c:4316 parse_request: Header 5: CSeq: 102 INVITE (16)
Jul 26 10:25:10 DEBUG[29504]: chan_sip.c:4316 parse_request: Header 6: Max-Forwards: 70 (16)
Jul 26 10:25:10 DEBUG[29504]: chan_sip.c:4316 parse_request: Header 7: User-Agent: Soundwin_ATA (24)
Jul 26 10:25:10 DEBUG[29504]: chan_sip.c:4316 parse_request: Header 8: Content-Length: 0 (17)
Jul 26 10:25:10 DEBUG[29504]: chan_sip.c:4316 parse_request: Header 9:  (0)
--- (9 headers 0 lines)---
Jul 26 10:25:10 DEBUG[29504]: chan_sip.c:2037 __sip_semi_ack: *** SIP TIMER: Cancelling retransmission #38 - INVITE (got response)
Jul 26 10:25:10 DEBUG[29504]: chan_sip.c:2046 __sip_semi_ack: (Provisional) Stopping retransmission (but retaining packet) on '14c6543c3a0e7f493547194778454a0e@203.82.162.33' Request 102: Found
Jul 26 10:25:10 DEBUG[29504]: chan_sip.c:11143 handle_response_invite: SIP response 100 to standard invite

<-- SIP read from 202.171.182.120:5060:
SIP/2.0 488 Incompatible Media Format
Via: SIP/2.0/UDP 203.82.162.33:5060;rport=5060;received=203.82.162.33;branch=z9hG4bK3159ce5e
From: "97131800"<sip:97131800@203.82.162.33>;tag=as22533180
To: <sip:97131806@192.168.1.68:5060>;tag=7905d60
Call-ID: 14c6543c3a0e7f493547194778454a0e@203.82.162.33
CSeq: 102 INVITE
Max-Forwards: 70
User-Agent: Soundwin_ATA
Warning: 305 ATA.Soundwin "Incompatible Media Format"
Content-Length: 0


Jul 26 10:25:10 DEBUG[29504]: chan_sip.c:4316 parse_request: Header 0: SIP/2.0 488 Incompatible Media Format (37)
Jul 26 10:25:10 DEBUG[29504]: chan_sip.c:4316 parse_request: Header 1: Via: SIP/2.0/UDP 203.82.162.33:5060;rport=5060;received=203.82.162.33;branch=z9hG4bK3159ce5e (92)
Jul 26 10:25:10 DEBUG[29504]: chan_sip.c:4316 parse_request: Header 2: From: "97131800"<sip:97131800@203.82.162.33>;tag=as22533180 (59)
Jul 26 10:25:10 DEBUG[29504]: chan_sip.c:4316 parse_request: Header 3: To: <sip:97131806@192.168.1.68:5060>;tag=7905d60 (48)
Jul 26 10:25:10 DEBUG[29504]: chan_sip.c:4316 parse_request: Header 4: Call-ID: 14c6543c3a0e7f493547194778454a0e@203.82.162.33 (55)
Jul 26 10:25:10 DEBUG[29504]: chan_sip.c:4316 parse_request: Header 5: CSeq: 102 INVITE (16)
Jul 26 10:25:10 DEBUG[29504]: chan_sip.c:4316 parse_request: Header 6: Max-Forwards: 70 (16)
Jul 26 10:25:10 DEBUG[29504]: chan_sip.c:4316 parse_request: Header 7: User-Agent: Soundwin_ATA (24)
Jul 26 10:25:10 DEBUG[29504]: chan_sip.c:4316 parse_request: Header 8: Warning: 305 ATA.Soundwin "Incompatible Media Format" (53)
Jul 26 10:25:10 DEBUG[29504]: chan_sip.c:4316 parse_request: Header 9: Content-Length: 0 (17)
Jul 26 10:25:10 DEBUG[29504]: chan_sip.c:4316 parse_request: Header 10:  (0)
--- (10 headers 0 lines)---
Jul 26 10:25:10 DEBUG[29504]: chan_sip.c:1986 __sip_ack: Acked pending invite 102
Jul 26 10:25:10 DEBUG[29504]: chan_sip.c:2004 __sip_ack: Stopping retransmission on '14c6543c3a0e7f493547194778454a0e@203.82.162.33' of Request 102: Match Not Found
Transmitting (NAT) to 202.171.182.120:5060:
ACK sip:97131806@192.168.1.68:5060 SIP/2.0
Via: SIP/2.0/UDP 203.82.162.33:5060;branch=z9hG4bK3159ce5e;rport
From: "97131800" <sip:97131800@203.82.162.33>;tag=as22533180
To: <sip:97131806@192.168.1.68:5060>;tag=7905d60
Contact: <sip:97131800@203.82.162.33>
Call-ID: 14c6543c3a0e7f493547194778454a0e@203.82.162.33
CSeq: 102 ACK
User-Agent: Asterisk PBX
Max-Forwards: 70
Content-Length: 0


---
Jul 26 10:25:10 DEBUG[29504]: chan_sip.c:4316 parse_request: Header 0: ACK sip:97131806@192.168.1.68:5060 SIP/2.0 (42)
Jul 26 10:25:10 DEBUG[29504]: chan_sip.c:4316 parse_request: Header 1: Via: SIP/2.0/UDP 203.82.162.33:5060;branch=z9hG4bK3159ce5e;rport (64)
Jul 26 10:25:10 DEBUG[29504]: chan_sip.c:4316 parse_request: Header 2: From: "97131800" <sip:97131800@203.82.162.33>;tag=as22533180 (60)
Jul 26 10:25:10 DEBUG[29504]: chan_sip.c:4316 parse_request: Header 3: To: <sip:97131806@192.168.1.68:5060>;tag=7905d60 (48)
Jul 26 10:25:10 DEBUG[29504]: chan_sip.c:4316 parse_request: Header 4: Contact: <sip:97131800@203.82.162.33> (37)
Jul 26 10:25:10 DEBUG[29504]: chan_sip.c:4316 parse_request: Header 5: Call-ID: 14c6543c3a0e7f493547194778454a0e@203.82.162.33 (55)
Jul 26 10:25:10 DEBUG[29504]: chan_sip.c:4316 parse_request: Header 6: CSeq: 102 ACK (13)
Jul 26 10:25:10 DEBUG[29504]: chan_sip.c:4316 parse_request: Header 7: User-Agent: Asterisk PBX (24)
Jul 26 10:25:10 DEBUG[29504]: chan_sip.c:4316 parse_request: Header 8: Max-Forwards: 70 (16)
Jul 26 10:25:10 DEBUG[29504]: chan_sip.c:4316 parse_request: Header 9: Content-Length: 0 (17)
Jul 26 10:25:10 DEBUG[29504]: chan_sip.c:4316 parse_request: Header 10:  (0)
Jul 26 10:25:10 DEBUG[29579]: channel.c:1500 ast_hangup: Hanging up channel 'SIP/97131806-b7b19e90'
Jul 26 10:25:10 DEBUG[29579]: chan_sip.c:3170 sip_hangup: Hangup call SIP/97131806-b7b19e90, SIP callid 14c6543c3a0e7f493547194778454a0e@203.82.162.33)
Jul 26 10:25:10 DEBUG[29579]: chan_sip.c:3178 sip_hangup: update_call_counter(97131806) - decrement call limit counter on hangup
Jul 26 10:25:10 DEBUG[29579]: chan_sip.c:2869 update_call_counter: Updating call counter for outgoing call
Jul 26 10:25:10 DEBUG[29579]: chan_sip.c:3191 sip_hangup: Hanging up channel in state Down (not UP)
Jul 26 10:25:10 DEBUG[29579]: devicestate.c:303 __ast_device_state_changed_literal: Notification of state change to be queued on device/channel SIP/97131806-b7b19e90
Jul 26 10:25:10 DEBUG[29579]: rtp.c:1317 ast_rtp_early_bridge: Channel '<unspecified>' has no RTP, not doing anything
Jul 26 10:25:10 DEBUG[29497]: devicestate.c:161 ast_device_state: No provider found, checking channel drivers for SIP - 97131806
Jul 26 10:25:10 DEBUG[29579]: app_dial.c:1624 dial_exec_full: Exiting with DIALSTATUS=CONGESTION.
Jul 26 10:25:10 DEBUG[29497]: chan_sip.c:14533 sip_devicestate: Checking device state for peer 97131806
Jul 26 10:25:10 DEBUG[29497]: devicestate.c:287 do_state_change: Changing state for SIP/97131806 - state 1 (Not in use)
Jul 26 10:25:10 DEBUG[29579]: pbx.c:1596 pbx_substitute_variables_helper_full: Expression result is '0'
Jul 26 10:25:10 DEBUG[29584]: app_queue.c:536 changethread: Device 'SIP/97131806' changed to state '1' (Not in use) but we don't care because they're not a member of any queue.
Jul 26 10:25:10 DEBUG[29579]: pbx.c:1675 pbx_extension_helper: Launching 'GotoIf'
Jul 26 10:25:10 DEBUG[29497]: devicestate.c:161 ast_device_state: No provider found, checking channel drivers for SIP - 97131806
Jul 26 10:25:10 DEBUG[29579]: pbx.c:5554 pbx_builtin_gotoif: Not taking any branch
Jul 26 10:25:10 DEBUG[29497]: chan_sip.c:14533 sip_devicestate: Checking device state for peer 97131806
Jul 26 10:25:10 DEBUG[29579]: pbx.c:1596 pbx_substitute_variables_helper_full: Expression result is '1'
Jul 26 10:25:10 DEBUG[29579]: pbx.c:1675 pbx_extension_helper: Launching 'GotoIf'
Jul 26 10:25:10 DEBUG[29579]: pbx.c:1675 pbx_extension_helper: Launching 'Congestion'
Transmitting (NAT) to 202.171.182.120:12083:
SIP/2.0 503 Service Unavailable
Via: SIP/2.0/UDP 192.168.1.51:5060;branch=z9hG4bK98c794b600707;received=202.171.182.120;rport=12083
From: 97131800<sip:97131800@203.82.162.33:5060>;tag=b0b53142
To: <sip:97131806@203.82.162.33:5060>;tag=as7e7bc1e6
Call-ID: b88677df-c@192.168.1.51
CSeq: 1132883134 INVITE
User-Agent: Asterisk PBX
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Supported: replaces
Contact: <sip:97131806@203.82.162.33>
Content-Length: 0
X-Asterisk-HangupCause: Bearer capability not available
X-Asterisk-HangupCauseCode: 58


---
Jul 26 10:25:10 DEBUG[29579]: chan_sip.c:4316 parse_request: Header 0: SIP/2.0 503 Service Unavailable (31)
Jul 26 10:25:10 DEBUG[29579]: chan_sip.c:4316 parse_request: Header 1: Via: SIP/2.0/UDP 192.168.1.51:5060;branch=z9hG4bK98c794b600707;received=202.171.182.120;rport=12083 (99)
Jul 26 10:25:10 DEBUG[29579]: chan_sip.c:4316 parse_request: Header 2: From: 97131800<sip:97131800@203.82.162.33:5060>;tag=b0b53142 (60)
Jul 26 10:25:10 DEBUG[29579]: chan_sip.c:4316 parse_request: Header 3: To: <sip:97131806@203.82.162.33:5060>;tag=as7e7bc1e6 (52)
Jul 26 10:25:10 DEBUG[29579]: chan_sip.c:4316 parse_request: Header 4: Call-ID: b88677df-c@192.168.1.51 (32)
Jul 26 10:25:10 DEBUG[29579]: chan_sip.c:4316 parse_request: Header 5: CSeq: 1132883134 INVITE (23)
Jul 26 10:25:10 DEBUG[29579]: chan_sip.c:4316 parse_request: Header 6: User-Agent: Asterisk PBX (24)
Jul 26 10:25:10 DEBUG[29579]: chan_sip.c:4316 parse_request: Header 7: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY (66)
Jul 26 10:25:10 DEBUG[29579]: chan_sip.c:4316 parse_request: Header 8: Supported: replaces (19)
Jul 26 10:25:10 DEBUG[29579]: chan_sip.c:4316 parse_request: Header 9: Contact: <sip:97131806@203.82.162.33> (37)
Jul 26 10:25:10 DEBUG[29579]: chan_sip.c:4316 parse_request: Header 10: Content-Length: 0 (17)
Jul 26 10:25:10 DEBUG[29579]: chan_sip.c:4316 parse_request: Header 11: X-Asterisk-HangupCause: Bearer capability not available (55)
Jul 26 10:25:10 DEBUG[29579]: chan_sip.c:4316 parse_request: Header 12: X-Asterisk-HangupCauseCode: 58 (30)
Jul 26 10:25:10 DEBUG[29579]: chan_sip.c:4316 parse_request: Header 13:  (0)
Jul 26 10:25:10 DEBUG[29579]: channel.c:1282 ast_softhangup_nolock: Soft-Hanging up channel 'SIP/97131800-b7b03990'
Jul 26 10:25:10 DEBUG[29579]: devicestate.c:303 __ast_device_state_changed_literal: Notification of state change to be queued on device/channel SIP/97131800-b7b03990
Jul 26 10:25:10 DEBUG[29497]: devicestate.c:161 ast_device_state: No provider found, checking channel drivers for SIP - 97131800
Jul 26 10:25:10 DEBUG[29579]: app_macro.c:221 macro_exec: Spawn extension (macro-exten-vm,s-CONGESTION,1) exited non-zero on 'SIP/97131800-b7b03990' in macro 'exten-vm'
Jul 26 10:25:10 DEBUG[29497]: chan_sip.c:14533 sip_devicestate: Checking device state for peer 97131800
Jul 26 10:25:10 DEBUG[29579]: pbx.c:2271 __ast_pbx_run: Spawn extension (macro-exten-vm,s-CONGESTION,1) exited non-zero on 'SIP/97131800-b7b03990'
Jul 26 10:25:10 DEBUG[29497]: devicestate.c:287 do_state_change: Changing state for SIP/97131800 - state 1 (Not in use)
Jul 26 10:25:10 DEBUG[29579]: pbx.c:1529 pbx_substitute_variables_helper_full: Function result is '"97131800" <97131800>'
Jul 26 10:25:10 DEBUG[29497]: devicestate.c:161 ast_device_state: No provider found, checking channel drivers for SIP - 97131800
Jul 26 10:25:10 DEBUG[29497]: chan_sip.c:14533 sip_devicestate: Checking device state for peer 97131800
Jul 26 10:25:10 DEBUG[29579]: pbx.c:1529 pbx_substitute_variables_helper_full: Function result is '97131800'
Jul 26 10:25:10 DEBUG[29585]: app_queue.c:536 changethread: Device 'SIP/97131800' changed to state '1' (Not in use) but we don't care because they're not a member of any queue.
Jul 26 10:25:10 DEBUG[29579]: pbx.c:1529 pbx_substitute_variables_helper_full: Function result is '97131806'
Jul 26 10:25:10 DEBUG[29579]: pbx.c:1529 pbx_substitute_variables_helper_full: Function result is 'from-internal'
Jul 26 10:25:10 DEBUG[29579]: pbx.c:1529 pbx_substitute_variables_helper_full: Function result is 'SIP/97131800-b7b03990'
Jul 26 10:25:10 DEBUG[29579]: pbx.c:1529 pbx_substitute_variables_helper_full: Function result is 'SIP/97131806-b7b19e90'
Jul 26 10:25:10 DEBUG[29579]: pbx.c:1529 pbx_substitute_variables_helper_full: Function result is 'Congestion'
Jul 26 10:25:10 DEBUG[29579]: pbx.c:1529 pbx_substitute_variables_helper_full: Function result is ''
Jul 26 10:25:10 DEBUG[29579]: pbx.c:1529 pbx_substitute_variables_helper_full: Function result is '2006-07-26 10:25:08'
Jul 26 10:25:10 DEBUG[29579]: pbx.c:1529 pbx_substitute_variables_helper_full: Function result is ''
Jul 26 10:25:10 DEBUG[29579]: pbx.c:1529 pbx_substitute_variables_helper_full: Function result is '2006-07-26 10:25:10'
Jul 26 10:25:10 DEBUG[29579]: pbx.c:1529 pbx_substitute_variables_helper_full: Function result is '2'
Jul 26 10:25:10 DEBUG[29579]: pbx.c:1529 pbx_substitute_variables_helper_full: Function result is '0'
Jul 26 10:25:10 DEBUG[29579]: pbx.c:1529 pbx_substitute_variables_helper_full: Function result is 'FAILED'
Jul 26 10:25:10 DEBUG[29579]: pbx.c:1529 pbx_substitute_variables_helper_full: Function result is 'DOCUMENTATION'
Jul 26 10:25:10 DEBUG[29579]: pbx.c:1529 pbx_substitute_variables_helper_full: Function result is ''
Jul 26 10:25:10 DEBUG[29579]: pbx.c:1529 pbx_substitute_variables_helper_full: Function result is '1153873508.2'
Jul 26 10:25:10 DEBUG[29579]: pbx.c:1529 pbx_substitute_variables_helper_full: Function result is ''
Jul 26 10:25:10 DEBUG[29579]: channel.c:1500 ast_hangup: Hanging up channel 'SIP/97131800-b7b03990'
Jul 26 10:25:10 DEBUG[29579]: chan_sip.c:3170 sip_hangup: Hangup call SIP/97131800-b7b03990, SIP callid b88677df-c@192.168.1.51)
Jul 26 10:25:10 DEBUG[29579]: chan_sip.c:3178 sip_hangup: update_call_counter(97131800) - decrement call limit counter on hangup
Jul 26 10:25:10 DEBUG[29579]: chan_sip.c:2869 update_call_counter: Updating call counter for incoming call
Jul 26 10:25:10 DEBUG[29579]: chan_sip.c:3191 sip_hangup: Hanging up channel in state Busy (not UP)
Jul 26 10:25:10 DEBUG[29579]: devicestate.c:303 __ast_device_state_changed_literal: Notification of state change to be queued on device/channel SIP/97131800-b7b03990
Jul 26 10:25:10 DEBUG[29497]: devicestate.c:161 ast_device_state: No provider found, checking channel drivers for SIP - 97131800
Jul 26 10:25:10 DEBUG[29497]: chan_sip.c:14533 sip_devicestate: Checking device state for peer 97131800
Jul 26 10:25:10 DEBUG[29497]: devicestate.c:287 do_state_change: Changing state for SIP/97131800 - state 1 (Not in use)
Jul 26 10:25:10 DEBUG[29497]: devicestate.c:161 ast_device_state: No provider found, checking channel drivers for SIP - 97131800
Jul 26 10:25:10 DEBUG[29497]: chan_sip.c:14533 sip_devicestate: Checking device state for peer 97131800
Jul 26 10:25:10 DEBUG[29586]: app_queue.c:536 changethread: Device 'SIP/97131800' changed to state '1' (Not in use) but we don't care because they're not a member of any queue.

<-- SIP read from 202.171.182.120:12083:
ACK sip:97131806@203.82.162.33:5060 SIP/2.0
Via: SIP/2.0/UDP 192.168.1.51:5060;rport;branch=z9hG4bK98c794b600707
From: 97131800<sip:97131800@203.82.162.33:5060>;tag=b0b53142
To: <sip:97131806@203.82.162.33:5060>;tag=as7e7bc1e6
Call-ID: b88677df-c@192.168.1.51
CSeq: 1132883134 ACK
Max-Forwards: 70
User-Agent: Soundwin_ATA
Content-Length: 0


Jul 26 10:25:10 DEBUG[29504]: chan_sip.c:4316 parse_request: Header 0: ACK sip:97131806@203.82.162.33:5060 SIP/2.0 (43)
Jul 26 10:25:10 DEBUG[29504]: chan_sip.c:4316 parse_request: Header 1: Via: SIP/2.0/UDP 192.168.1.51:5060;rport;branch=z9hG4bK98c794b600707 (68)
Jul 26 10:25:10 DEBUG[29504]: chan_sip.c:4316 parse_request: Header 2: From: 97131800<sip:97131800@203.82.162.33:5060>;tag=b0b53142 (60)
Jul 26 10:25:10 DEBUG[29504]: chan_sip.c:4316 parse_request: Header 3: To: <sip:97131806@203.82.162.33:5060>;tag=as7e7bc1e6 (52)
Jul 26 10:25:10 DEBUG[29504]: chan_sip.c:4316 parse_request: Header 4: Call-ID: b88677df-c@192.168.1.51 (32)
Jul 26 10:25:10 DEBUG[29504]: chan_sip.c:4316 parse_request: Header 5: CSeq: 1132883134 ACK (20)
Jul 26 10:25:10 DEBUG[29504]: chan_sip.c:4316 parse_request: Header 6: Max-Forwards: 70 (16)
Jul 26 10:25:10 DEBUG[29504]: chan_sip.c:4316 parse_request: Header 7: User-Agent: Soundwin_ATA (24)
Jul 26 10:25:10 DEBUG[29504]: chan_sip.c:4316 parse_request: Header 8: Content-Length: 0 (17)
Jul 26 10:25:10 DEBUG[29504]: chan_sip.c:4316 parse_request: Header 9:  (0)
--- (9 headers 0 lines)---
Jul 26 10:25:10 DEBUG[29504]: chan_sip.c:13969 handle_request: **** Received ACK (6) - Command in SIP ACK
Jul 26 10:25:10 DEBUG[29504]: chan_sip.c:2004 __sip_ack: Stopping retransmission on 'b88677df-c@192.168.1.51' of Response 1132883134: Match Found
Really destroying SIP dialog '14c6543c3a0e7f493547194778454a0e@203.82.162.33' Method: INVITE
Really destroying SIP dialog 'b88677df-c@192.168.1.51' Method: ACK