Miscellany

/var/log/asterisk/full - last 2000 lines

---
May 2 12:23:22 VERBOSE[734] logger.c:
<-- SIP read from 67.133.234.125:5060:
SIP/2.0 100 Trying
v:SIP/2.0/UDP 192.168.1.3:5060;branch=z9hG4bK5019d9a3;received=76.173.56.41;rport=5060
f:"ITC INC" ;tag=as75d9b647
t:
i:08d01d031301c25015c9b75e3c23e08b@sunrocket.com
CSeq:103 INVITE
l:0


May 2 12:23:22 VERBOSE[734] logger.c: --- (7 headers 0 lines) ---
May 2 12:23:22 DEBUG[734] chan_sip.c: (Provisional) Stopping retransmission (but retaining packet) on '08d01d031301c25015c9b75e3c23e08b@sunrocket.com' Request 103: Found
May 2 12:23:24 VERBOSE[734] logger.c:
<-- SIP read from 67.133.234.125:5060:
SIP/2.0 183 Session Progress -- cc2-n06
Record-Route:
v:SIP/2.0/UDP 192.168.1.3:5060;branch=z9hG4bK5019d9a3;received=76.173.56.41;rport=5060
CSeq:103 INVITE
i:08d01d031301c25015c9b75e3c23e08b@sunrocket.com
f:"ITC INC" ;tag=as75d9b647
t:;tag=972c66fe-1dd1-11b2-9537-b03162323164+972c66fe
m:
c:application/sdp
P-Asserted-Identity: "8583206670"
l:163

v=0
o=- 79101 7910101 IN IP4 67.133.234.136
s=SIP Media Capabilities
c=IN IP4 67.133.234.136
t=0 0
m=audio 28866 RTP/AVP 0
a=rtpmap:0 PCMU/8000
a=sendrecv

May 2 12:23:24 VERBOSE[734] logger.c: --- (11 headers 8 lines) ---
May 2 12:23:24 DEBUG[734] chan_sip.c: (Provisional) Stopping retransmission (but retaining packet) on '08d01d031301c25015c9b75e3c23e08b@sunrocket.com' Request 103: Found
May 2 12:23:24 VERBOSE[734] logger.c: Found RTP audio format 0
May 2 12:23:24 VERBOSE[734] logger.c: Peer audio RTP is at port 67.133.234.136:28866
May 2 12:23:24 DEBUG[734] chan_sip.c: Peer audio RTP is at port 67.133.234.136:28866
May 2 12:23:24 VERBOSE[734] logger.c: Found description format PCMU
May 2 12:23:24 VERBOSE[734] logger.c: Capabilities: us - 0xc (ulaw|alaw), peer - audio=0x4 (ulaw)/video=0x0 (nothing), combined - 0x4 (ulaw)
May 2 12:23:24 VERBOSE[734] logger.c: Non-codec capabilities: us - 0x1 (telephone-event), peer - 0x0 (nothing), combined - 0x0 (nothing)
May 2 12:23:24 VERBOSE[5161] logger.c: -- SIP/SVoipProvider-09c225c8 is making progress passing it to SIP/2000-09bb06a8
May 2 12:23:24 VERBOSE[5161] logger.c: We're at 192.168.1.3 port 12030
May 2 12:23:24 VERBOSE[5161] logger.c: Adding codec 0x4 (ulaw) to SDP
May 2 12:23:24 VERBOSE[5161] logger.c: Adding codec 0x8 (alaw) to SDP
May 2 12:23:24 VERBOSE[5161] logger.c: Adding non-codec 0x1 (telephone-event) to SDP
May 2 12:23:24 VERBOSE[5161] logger.c: Transmitting (NAT) to 192.168.1.143:5061:
SIP/2.0 183 Session Progress
Via: SIP/2.0/UDP 192.168.1.1:5061;branch=z9hG4bK27f42a7e;received=192.168.1.143
From: "2000" ;tag=00141c2247581d9f3ce017e3-69493193
To: ;tag=as37ffeae4
Call-ID: 00141c22-4758006a-2dd594a0-2db7493e@192.168.1.1
CSeq: 102 INVITE
User-Agent: InnoMedia SIP MTA6328-2Re v3.0.77
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Contact:
Content-Type: application/sdp
Content-Length: 236

v=0
o=root 2758 2758 IN IP4 192.168.1.3
s=session
c=IN IP4 192.168.1.3
t=0 0
m=audio 12030 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 - - - -

---
May 2 12:23:39 DEBUG[5165] manager.c: Manager received command 'login'
May 2 12:23:39 VERBOSE[5165] logger.c: == Parsing '/etc/asterisk/manager.conf': May 2 12:23:39 VERBOSE[5165] logger.c: == Parsing '/etc/asterisk/manager.conf': Found
May 2 12:23:39 VERBOSE[5165] logger.c: == Parsing '/etc/asterisk/manager_custom.conf': May 2 12:23:39 VERBOSE[5165] logger.c: == Parsing '/etc/asterisk/manager_custom.conf': Found
May 2 12:23:39 DEBUG[5165] acl.c: 0.0.0.0/0.0.0.0/0.0.0.0 appended to acl for peer
May 2 12:23:39 DEBUG[5165] acl.c: 127.0.0.1/255.255.255.0/255.255.255.0 appended to acl for peer
May 2 12:23:39 DEBUG[5165] acl.c: ##### Testing 127.0.0.1 with 0.0.0.0
May 2 12:23:39 DEBUG[5165] acl.c: ##### Testing 127.0.0.1 with 127.0.0.0
May 2 12:23:39 VERBOSE[5165] logger.c: == Manager 'admin' logged on from 127.0.0.1
May 2 12:23:40 VERBOSE[5165] logger.c: == Manager 'admin' logged off from 127.0.0.1
May 2 12:23:52 VERBOSE[734] logger.c:
<-- SIP read from 67.133.234.125:5060:
SIP/2.0 200 OK -- cc2-n06
Record-Route:
v:SIP/2.0/UDP 192.168.1.3:5060;branch=z9hG4bK5019d9a3;received=76.173.56.41;rport=5060
CSeq:103 INVITE
i:08d01d031301c25015c9b75e3c23e08b@sunrocket.com
f:"ITC INC" ;tag=as75d9b647
t:;tag=972c66fe-1dd1-11b2-9537-b03162323164+972c66fe
m:
c:application/sdp
k:timer
P-Asserted-Identity: "8583206670"
Allow:INVITE,BYE,ACK,CANCEL,PRACK,REFER,OPTIONS,REGISTER,NOTIFY
l:163

v=0
o=- 79101 7910101 IN IP4 67.133.234.136
s=SIP Media Capabilities
c=IN IP4 67.133.234.136
t=0 0
m=audio 28866 RTP/AVP 0
a=rtpmap:0 PCMU/8000
a=sendrecv

May 2 12:23:52 VERBOSE[734] logger.c: --- (13 headers 8 lines) ---
May 2 12:23:52 DEBUG[734] chan_sip.c: Acked pending invite 103
May 2 12:23:52 DEBUG[734] chan_sip.c: Stopping retransmission on '08d01d031301c25015c9b75e3c23e08b@sunrocket.com' of Request 103: Match Found
May 2 12:23:52 VERBOSE[734] logger.c: Found RTP audio format 0
May 2 12:23:52 VERBOSE[734] logger.c: Peer audio RTP is at port 67.133.234.136:28866
May 2 12:23:52 DEBUG[734] chan_sip.c: Peer audio RTP is at port 67.133.234.136:28866
May 2 12:23:52 VERBOSE[734] logger.c: Found description format PCMU
May 2 12:23:52 VERBOSE[734] logger.c: Capabilities: us - 0xc (ulaw|alaw), peer - audio=0x4 (ulaw)/video=0x0 (nothing), combined - 0x4 (ulaw)
May 2 12:23:52 VERBOSE[734] logger.c: Non-codec capabilities: us - 0x1 (telephone-event), peer - 0x0 (nothing), combined - 0x0 (nothing)
May 2 12:23:52 DEBUG[734] chan_sip.c: build_route: Record-Route hop:
May 2 12:23:52 VERBOSE[734] logger.c: list_route: hop:
May 2 12:23:52 VERBOSE[734] logger.c: set_destination: Parsing for address/port to send to
May 2 12:23:52 VERBOSE[734] logger.c: set_destination: set destination to 67.133.234.125, port 5060
May 2 12:23:52 VERBOSE[734] logger.c: Transmitting (NAT) to 67.133.234.125:5060:
ACK sip:8583206670@192.168.201.102:5075;transport=udp SIP/2.0
Via: SIP/2.0/UDP 192.168.1.3:5060;branch=z9hG4bK3b89e349;rport
Route:
From: "ITC INC" ;tag=as75d9b647
To: ;tag=972c66fe-1dd1-11b2-9537-b03162323164+972c66fe
Contact:
Call-ID: 08d01d031301c25015c9b75e3c23e08b@sunrocket.com
CSeq: 103 ACK
User-Agent: InnoMedia SIP MTA6328-2Re v3.0.77
Max-Forwards: 70
Content-Length: 0


---
May 2 12:23:52 VERBOSE[5161] logger.c: -- SIP/SVoipProvider-09c225c8 answered SIP/2000-09bb06a8
May 2 12:23:52 VERBOSE[5161] logger.c: We're at 192.168.1.3 port 12030
May 2 12:23:52 VERBOSE[5161] logger.c: Adding codec 0x4 (ulaw) to SDP
May 2 12:23:52 VERBOSE[5161] logger.c: Adding codec 0x8 (alaw) to SDP
May 2 12:23:52 VERBOSE[5161] logger.c: Adding non-codec 0x1 (telephone-event) to SDP
May 2 12:23:52 VERBOSE[5161] logger.c: Reliably Transmitting (NAT) to 192.168.1.143:5061:
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.1.1:5061;branch=z9hG4bK27f42a7e;received=192.168.1.143
From: "2000" ;tag=00141c2247581d9f3ce017e3-69493193
To: ;tag=as37ffeae4
Call-ID: 00141c22-4758006a-2dd594a0-2db7493e@192.168.1.1
CSeq: 102 INVITE
User-Agent: InnoMedia SIP MTA6328-2Re v3.0.77
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Contact:
Content-Type: application/sdp
Content-Length: 236

v=0
o=root 2758 2759 IN IP4 192.168.1.3
s=session
c=IN IP4 192.168.1.3
t=0 0
m=audio 12030 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 - - - -

---
May 2 12:23:52 VERBOSE[5161] logger.c: -- Attempting native bridge of SIP/2000-09bb06a8 and SIP/SVoipProvider-09c225c8
May 2 12:23:52 VERBOSE[734] logger.c:
<-- SIP read from 192.168.1.143:5061:
ACK sip:8583206670@192.168.1.3:5060 SIP/2.0
Via: SIP/2.0/UDP 192.168.1.1:5061;branch=z9hG4bK67ab75a3
From: "2000" ;tag=00141c2247581d9f3ce017e3-69493193
To: ;tag=as37ffeae4
Call-ID: 00141c22-4758006a-2dd594a0-2db7493e@192.168.1.1
Max-Forwards: 70
Date: Wed, 02 May 2007 19:23:52 GMT
CSeq: 102 ACK
User-Agent: Cisco-CP7940G/8.0
Proxy-Authorization: Digest username="2000",realm="asterisk",uri="sip:8583206670@192.168.1.3",response="bee6c87413c8a99b8cb12c81799a3eb6",nonce="675e141b",algorithm=MD5
Remote-Party-ID: "2000" ;party=calling;id-type=subscriber;privacy=off;screen=yes
Content-Length: 0


May 2 12:23:52 VERBOSE[734] logger.c: --- (12 headers 0 lines) ---
May 2 12:23:52 DEBUG[734] chan_sip.c: Stopping retransmission on '00141c22-4758006a-2dd594a0-2db7493e@192.168.1.1' of Response 102: Match Found
May 2 12:24:06 NOTICE[734] chan_sip.c: -- Re-registration for 8182323476@sunrocket.com
May 2 12:24:06 DEBUG[734] chan_sip.c: Scheduled a registration timeout for sunrocket.com id #32179
May 2 12:24:06 VERBOSE[734] logger.c: REGISTER 12 headers, 0 lines
May 2 12:24:06 VERBOSE[734] logger.c: Reliably Transmitting (NAT) to 67.133.234.125:5060:
REGISTER sip:sunrocket.com SIP/2.0
Via: SIP/2.0/UDP 192.168.1.3:5060;branch=z9hG4bK1e61f5d3;rport
From: ;tag=as60c751a1
To:
Call-ID: 768d78be6bdf088b7acface60ddd936d@127.0.0.1
CSeq: 103 REGISTER
User-Agent: InnoMedia SIP MTA6328-2Re v3.0.77
Max-Forwards: 70
Expires: 120
Contact:
Event: registration
Content-Length: 0


---
May 2 12:24:06 VERBOSE[734] logger.c:
<-- SIP read from 67.133.234.125:5060:
SIP/2.0 200 OK
v:SIP/2.0/UDP 192.168.1.3:5060;branch=z9hG4bK1e61f5d3;rport
f:;tag=as60c751a1
t:
i:768d78be6bdf088b7acface60ddd936d@127.0.0.1
CSeq:103 REGISTER
User-Agent: InnoMedia SIP MTA6328-2Re v3.0.77
m:;expires=90
Event: registration
l:0


May 2 12:24:06 VERBOSE[734] logger.c: --- (10 headers 0 lines) ---
May 2 12:24:06 DEBUG[734] chan_sip.c: Stopping retransmission on '768d78be6bdf088b7acface60ddd936d@127.0.0.1' of Request 103: Match Found
May 2 12:24:06 DEBUG[734] chan_sip.c: Registration successful
May 2 12:24:06 DEBUG[734] chan_sip.c: Cancelling timeout 32179
May 2 12:24:06 VERBOSE[734] logger.c: Scheduling destruction of call '768d78be6bdf088b7acface60ddd936d@127.0.0.1' in 32000 ms
May 2 12:24:06 NOTICE[734] chan_sip.c: Outbound Registration: Expiry for sunrocket.com is 120 sec (Scheduling reregistration in 105 s)
May 2 12:24:06 NOTICE[734] chan_sip.c: -- Re-registration for 6418272550@sipnumber.net
May 2 12:24:06 DEBUG[734] chan_sip.c: Scheduled a registration timeout for sipnumber.net id #32183
May 2 12:24:06 DEBUG[734] chan_sip.c: >>> Re-using Auth data for 6418272550@sipnumber.net
May 2 12:24:06 VERBOSE[734] logger.c: REGISTER 13 headers, 0 lines
May 2 12:24:06 VERBOSE[734] logger.c: Reliably Transmitting (NAT) to 67.55.159.160:5060:
REGISTER sip:sipnumber.net SIP/2.0
Via: SIP/2.0/UDP 192.168.1.3:5060;branch=z9hG4bK38f01231;rport
From: ;tag=as288e06c7
To:
Call-ID: 32fa39c67608cb187a085a825a1ff024@127.0.0.1
CSeq: 104 REGISTER
User-Agent: InnoMedia SIP MTA6328-2Re v3.0.77
Max-Forwards: 70
Authorization: Digest username="6418272550", realm="sipnumber.net", algorithm=MD5, uri="sip:sipnumber.net", nonce="4638e619e78ba4995e3dc482bf48fb2c83a44644", response="c75e08f5e91d50a4399a4f3197ace6c2", opaque=""
Expires: 120
Contact:
Event: registration
Content-Length: 0


---
May 2 12:24:06 VERBOSE[734] logger.c:
<-- SIP read from 67.55.159.160:5060:
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.1.3:5060;branch=z9hG4bK38f01231;rport=5060
From: ;tag=as288e06c7
To: ;tag=9eacff2ee73e6595c6126247c2fa2a6e.8e94
Call-ID: 32fa39c67608cb187a085a825a1ff024@127.0.0.1
CSeq: 104 REGISTER
Contact: ;expires=120
Server: Sip EXpress router (0.9.3 (i386/linux))
Content-Length: 0
Warning: 392 67.55.159.160:5060 "Noisy feedback tells: pid=14146 req_src_ip=76.173.56.41 req_src_port=5060 in_uri=sip:sipnumber.net out_uri=sip:sipnumber.net via_cnt==1"


May 2 12:24:06 VERBOSE[734] logger.c: --- (10 headers 0 lines) ---
May 2 12:24:06 DEBUG[734] chan_sip.c: Stopping retransmission on '32fa39c67608cb187a085a825a1ff024@127.0.0.1' of Request 104: Match Found
May 2 12:24:06 DEBUG[734] chan_sip.c: Registration successful
May 2 12:24:06 DEBUG[734] chan_sip.c: Cancelling timeout 32183
May 2 12:24:06 VERBOSE[734] logger.c: Scheduling destruction of call '32fa39c67608cb187a085a825a1ff024@127.0.0.1' in 32000 ms
May 2 12:24:06 NOTICE[734] chan_sip.c: Outbound Registration: Expiry for sipnumber.net is 120 sec (Scheduling reregistration in 105 s)
May 2 12:24:16 VERBOSE[734] logger.c:
<-- SIP read from 192.168.1.143:5061:
BYE sip:8583206670@192.168.1.3:5060 SIP/2.0
Via: SIP/2.0/UDP 192.168.1.1:5061;branch=z9hG4bK3b875dc0
From: "2000" ;tag=00141c2247581d9f3ce017e3-69493193
To: ;tag=as37ffeae4
Call-ID: 00141c22-4758006a-2dd594a0-2db7493e@192.168.1.1
Max-Forwards: 70
Date: Wed, 02 May 2007 19:24:16 GMT
CSeq: 103 BYE
User-Agent: Cisco-CP7940G/8.0
Content-Length: 0
Proxy-Authorization: Digest username="2000",realm="asterisk",uri="sip:8583206670@192.168.1.3:5060",response="146417a3f94002075937f82591be58c8",nonce="675e141b",algorithm=MD5


May 2 12:24:16 VERBOSE[734] logger.c: --- (11 headers 0 lines) ---
May 2 12:24:16 VERBOSE[734] logger.c: Sending to 192.168.1.1 : 5061 (NAT)
May 2 12:24:16 VERBOSE[734] logger.c: Transmitting (NAT) to 192.168.1.143:5061:
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.1.1:5061;branch=z9hG4bK3b875dc0;received=192.168.1.143
From: "2000" ;tag=00141c2247581d9f3ce017e3-69493193
To: ;tag=as37ffeae4
Call-ID: 00141c22-4758006a-2dd594a0-2db7493e@192.168.1.1
CSeq: 103 BYE
User-Agent: InnoMedia SIP MTA6328-2Re v3.0.77
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Contact:
Content-Length: 0
X-Asterisk-HangupCause: Normal Clearing


---
May 2 12:24:16 DEBUG[5161] channel.c: Didn't get a frame from channel: SIP/2000-09bb06a8
May 2 12:24:16 DEBUG[5161] channel.c: Bridge stops bridging channels SIP/2000-09bb06a8 and SIP/SVoipProvider-09c225c8
May 2 12:24:16 DEBUG[5161] chan_sip.c: update_call_counter(8583206670) - decrement call limit counter
May 2 12:24:16 VERBOSE[5161] logger.c: Scheduling destruction of call '08d01d031301c25015c9b75e3c23e08b@sunrocket.com' in 32000 ms
May 2 12:24:16 VERBOSE[5161] logger.c: set_destination: Parsing for address/port to send to
May 2 12:24:16 VERBOSE[5161] logger.c: set_destination: set destination to 67.133.234.125, port 5060
May 2 12:24:16 VERBOSE[5161] logger.c: Reliably Transmitting (NAT) to 67.133.234.125:5060:
BYE sip:8583206670@192.168.201.102:5075;transport=udp SIP/2.0
Via: SIP/2.0/UDP 192.168.1.3:5060;branch=z9hG4bK76585167;rport
Route:
From: "ITC INC" ;tag=as75d9b647
To: ;tag=972c66fe-1dd1-11b2-9537-b03162323164+972c66fe
Contact:
Call-ID: 08d01d031301c25015c9b75e3c23e08b@sunrocket.com
CSeq: 104 BYE
User-Agent: InnoMedia SIP MTA6328-2Re v3.0.77
Max-Forwards: 70
Authorization: Digest username="101163321271823", realm="Registered_Subscribers", algorithm=MD5, uri="sip:sunrocket.com", nonce="0f8ee3601fb9e4eee262718ba790b602", response="ab87bc6e66caa34d0fcf929f49b54cfd", opaque=""
Content-Length: 0


---
May 2 12:24:16 DEBUG[5161] app_dial.c: Exiting with DIALSTATUS=ANSWER.
May 2 12:24:16 VERBOSE[5161] logger.c: == Spawn extension (macro-dialout-trunk, s, 20) exited non-zero on 'SIP/2000-09bb06a8' in macro 'dialout-trunk'
May 2 12:24:16 VERBOSE[5161] logger.c: == Spawn extension (macro-dialout-trunk, s, 20) exited non-zero on 'SIP/2000-09bb06a8'
May 2 12:24:16 VERBOSE[5161] logger.c: -- Executing Macro("SIP/2000-09bb06a8", "hangupcall") in new stack
May 2 12:24:16 VERBOSE[5161] logger.c: -- Executing ResetCDR("SIP/2000-09bb06a8", "w") in new stack
May 2 12:24:16 DEBUG[5161] cdr_addon_mysql.c: cdr_mysql: inserting a CDR record.
May 2 12:24:16 DEBUG[5161] cdr_addon_mysql.c: cdr_mysql: SQL command as follows: INSERT INTO cdr (calldate,clid,src,dst,dcontext,channel,dstchannel,lastapp,lastdata,duration,billsec,disposition,amaflags,accountcode,uniqueid) VALUES ('2007-05-02 12:23:21','\"ITC INC\" <3104991266>','3104991266','8583206670','from-internal', 'SIP/2000-09bb06a8','SIP/SVoipProvider-09c225c8','ResetCDR','w',55,24,'ANSWERED',3,'','1178133801.826')
May 2 12:24:16 VERBOSE[5161] logger.c: -- Executing NoCDR("SIP/2000-09bb06a8", "") in new stack
May 2 12:24:16 WARNING[5161] cdr.c: CDR on channel 'SIP/2000-09bb06a8' not posted
May 2 12:24:16 WARNING[5161] cdr.c: CDR on channel 'SIP/2000-09bb06a8' lacks end
May 2 12:24:16 DEBUG[5161] pbx.c: Expression result is '1'
May 2 12:24:16 VERBOSE[5161] logger.c: -- Executing GotoIf("SIP/2000-09bb06a8", "1?skiprg") in new stack
May 2 12:24:16 VERBOSE[5161] logger.c: -- Goto (macro-hangupcall,s,6)
May 2 12:24:16 DEBUG[5161] pbx.c: Expression result is '1'
May 2 12:24:16 VERBOSE[5161] logger.c: -- Executing GotoIf("SIP/2000-09bb06a8", "1?theend") in new stack
May 2 12:24:16 VERBOSE[5161] logger.c: -- Goto (macro-hangupcall,s,9)
May 2 12:24:16 VERBOSE[5161] logger.c: -- Executing Wait("SIP/2000-09bb06a8", "5") in new stack
May 2 12:24:16 VERBOSE[5161] logger.c: == Spawn extension (macro-hangupcall, s, 9) exited non-zero on 'SIP/2000-09bb06a8' in macro 'hangupcall'
May 2 12:24:16 VERBOSE[5161] logger.c: == Spawn extension (macro-hangupcall, s, 9) exited non-zero on 'SIP/2000-09bb06a8'
May 2 12:24:16 DEBUG[5161] chan_sip.c: update_call_counter(2000) - decrement call limit counter
May 2 12:24:16 VERBOSE[734] logger.c:
<-- SIP read from 67.133.234.125:5060:
SIP/2.0 200 OK -- cc2-n06
v:SIP/2.0/UDP 192.168.1.3:5060;branch=z9hG4bK76585167;received=76.173.56.41;rport=5060
CSeq:104 BYE
i:08d01d031301c25015c9b75e3c23e08b@sunrocket.com
f:"ITC INC" ;tag=as75d9b647
t:;tag=972c66fe-1dd1-11b2-9537-b03162323164+972c66fe
l:0


May 2 12:24:16 VERBOSE[734] logger.c: --- (7 headers 0 lines) ---
May 2 12:24:16 DEBUG[734] chan_sip.c: Stopping retransmission on '08d01d031301c25015c9b75e3c23e08b@sunrocket.com' of Request 104: Match Found
May 2 12:24:16 VERBOSE[734] logger.c: Destroying call '08d01d031301c25015c9b75e3c23e08b@sunrocket.com'
May 2 12:24:16 VERBOSE[734] logger.c: Destroying call '00141c22-4758006a-2dd594a0-2db7493e@192.168.1.1'
May 2 12:24:21 VERBOSE[734] logger.c: 12 headers, 0 lines
May 2 12:24:21 VERBOSE[734] logger.c: Reliably Transmitting (NAT) to 192.168.1.254:5061:
OPTIONS sip:2001@192.168.1.1:5061;transport=udp SIP/2.0
Via: SIP/2.0/UDP 192.168.1.3:5060;branch=z9hG4bK15a7c058;rport
From: "Private" ;tag=as1fe08b88
To:
Contact:
Call-ID: 0dee874c0477cba84b5a7d9b76e5741f@192.168.1.3
CSeq: 102 OPTIONS
User-Agent: InnoMedia SIP MTA6328-2Re v3.0.77
Max-Forwards: 70
Date: Wed, 02 May 2007 19:24:21 GMT
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Content-Length: 0


---
May 2 12:24:21 VERBOSE[734] logger.c: 12 headers, 0 lines
May 2 12:24:21 VERBOSE[734] logger.c: Reliably Transmitting (NAT) to 192.168.1.143:5061:
OPTIONS sip:2000@192.168.1.1:5061;transport=udp SIP/2.0
Via: SIP/2.0/UDP 192.168.1.3:5060;branch=z9hG4bK01bdc6ad;rport
From: "Private" ;tag=as05cdeac0
To:
Contact:
Call-ID: 1ebc42385f896de705e142ce34ce318e@192.168.1.3
CSeq: 102 OPTIONS
User-Agent: InnoMedia SIP MTA6328-2Re v3.0.77
Max-Forwards: 70
Date: Wed, 02 May 2007 19:24:21 GMT
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Content-Length: 0


---
May 2 12:24:21 VERBOSE[734] logger.c:
<-- SIP read from 192.168.1.254:5061:
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.1.3:5060;branch=z9hG4bK15a7c058;rport
From: "Private" ;tag=as1fe08b88
To: ;tag=00141c22475e1c1213650581-4bb1fea9
Call-ID: 0dee874c0477cba84b5a7d9b76e5741f@192.168.1.3
Date: Wed, 02 May 2007 19:24:21 GMT
CSeq: 102 OPTIONS
Server: Cisco-CP7940G/8.0
Allow: ACK,BYE,CANCEL,INVITE,NOTIFY,OPTIONS,REFER,REGISTER,UPDATE
Accept: application/sdp,multipart/mixed,multipart/alternative
Accept-Encoding: identity
Accept-Language: en
Supported: replaces,join,norefersub
Content-Length: 232
Content-Type: application/sdp
Content-Disposition: session;handling=optional

v=0
o=Cisco-SIPUA 6933 0 IN IP4 192.168.1.1
s=SIP Call
t=0 0
m=audio 0 RTP/AVP 0 8 18 101
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:18 G729/0
a=fmtp:18 annexb=no
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15

May 2 12:24:21 VERBOSE[734] logger.c: --- (16 headers 11 lines) ---
May 2 12:24:21 DEBUG[734] chan_sip.c: Stopping retransmission on '0dee874c0477cba84b5a7d9b76e5741f@192.168.1.3' of Request 102: Match Found
May 2 12:24:21 VERBOSE[734] logger.c: Destroying call '0dee874c0477cba84b5a7d9b76e5741f@192.168.1.3'
May 2 12:24:21 VERBOSE[734] logger.c:
<-- SIP read from 192.168.1.143:5061:
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.1.3:5060;branch=z9hG4bK01bdc6ad;rport
From: "Private" ;tag=as05cdeac0
To: ;tag=00141c2247581da17f1a78ca-79b6203e
Call-ID: 1ebc42385f896de705e142ce34ce318e@192.168.1.3
Date: Wed, 02 May 2007 19:24:21 GMT
CSeq: 102 OPTIONS
Server: Cisco-CP7940G/8.0
Allow: ACK,BYE,CANCEL,INVITE,NOTIFY,OPTIONS,REFER,REGISTER,UPDATE
Accept: application/sdp,multipart/mixed,multipart/alternative
Accept-Encoding: identity
Accept-Language: en
Supported: replaces,join,norefersub
Content-Length: 233
Content-Type: application/sdp
Content-Disposition: session;handling=optional

v=0
o=Cisco-SIPUA 14856 0 IN IP4 192.168.1.1
s=SIP Call
t=0 0
m=audio 0 RTP/AVP 0 8 18 101
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:18 G729/0
a=fmtp:18 annexb=no
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15

May 2 12:24:21 VERBOSE[734] logger.c: --- (16 headers 11 lines) ---
May 2 12:24:21 DEBUG[734] chan_sip.c: Stopping retransmission on '1ebc42385f896de705e142ce34ce318e@192.168.1.3' of Request 102: Match Found
May 2 12:24:21 VERBOSE[734] logger.c: Destroying call '1ebc42385f896de705e142ce34ce318e@192.168.1.3'
May 2 12:24:28 DEBUG[5120] manager.c: Manager received command 'Command'
May 2 12:24:28 DEBUG[5120] manager.c: Manager received command 'Command'
May 2 12:24:28 DEBUG[5120] manager.c: Manager received command 'Command'
May 2 12:24:38 DEBUG[734] chan_sip.c: Auto destroying call '768d78be6bdf088b7acface60ddd936d@127.0.0.1'
May 2 12:24:38 VERBOSE[734] logger.c: Destroying call '768d78be6bdf088b7acface60ddd936d@127.0.0.1'
May 2 12:24:38 DEBUG[734] chan_sip.c: Auto destroying call '32fa39c67608cb187a085a825a1ff024@127.0.0.1'
May 2 12:24:38 VERBOSE[734] logger.c: Destroying call '32fa39c67608cb187a085a825a1ff024@127.0.0.1'
May 2 12:24:46 DEBUG[5174] manager.c: Manager received command 'login'
May 2 12:24:46 VERBOSE[5174] logger.c: == Parsing '/etc/asterisk/manager.conf': May 2 12:24:46 VERBOSE[5174] logger.c: == Parsing '/etc/asterisk/manager.conf': Found
May 2 12:24:46 VERBOSE[5174] logger.c: == Parsing '/etc/asterisk/manager_custom.conf': May 2 12:24:46 VERBOSE[5174] logger.c: == Parsing '/etc/asterisk/manager_custom.conf': Found
May 2 12:24:46 DEBUG[5174] acl.c: 0.0.0.0/0.0.0.0/0.0.0.0 appended to acl for peer
May 2 12:24:46 DEBUG[5174] acl.c: 127.0.0.1/255.255.255.0/255.255.255.0 appended to acl for peer
May 2 12:24:46 DEBUG[5174] acl.c: ##### Testing 127.0.0.1 with 0.0.0.0
May 2 12:24:46 DEBUG[5174] acl.c: ##### Testing 127.0.0.1 with 127.0.0.0
May 2 12:24:46 VERBOSE[5174] logger.c: == Manager 'admin' logged on from 127.0.0.1
May 2 12:24:46 VERBOSE[5174] logger.c: == Manager 'admin' logged off from 127.0.0.1
May 2 12:24:51 DEBUG[5176] manager.c: Manager received command 'login'
May 2 12:24:51 VERBOSE[5176] logger.c: == Parsing '/etc/asterisk/manager.conf': May 2 12:24:51 VERBOSE[5176] logger.c: == Parsing '/etc/asterisk/manager.conf': Found
May 2 12:24:51 VERBOSE[5176] logger.c: == Parsing '/etc/asterisk/manager_custom.conf': May 2 12:24:51 VERBOSE[5176] logger.c: == Parsing '/etc/asterisk/manager_custom.conf': Found
May 2 12:24:51 DEBUG[5176] acl.c: 0.0.0.0/0.0.0.0/0.0.0.0 appended to acl for peer
May 2 12:24:51 DEBUG[5176] acl.c: 127.0.0.1/255.255.255.0/255.255.255.0 appended to acl for peer
May 2 12:24:51 DEBUG[5176] acl.c: ##### Testing 127.0.0.1 with 0.0.0.0
May 2 12:24:51 DEBUG[5176] acl.c: ##### Testing 127.0.0.1 with 127.0.0.0
May 2 12:24:51 VERBOSE[5176] logger.c: == Manager 'admin' logged on from 127.0.0.1
May 2 12:24:51 VERBOSE[5176] logger.c: == Manager 'admin' logged off from 127.0.0.1
May 2 12:25:05 VERBOSE[720] logger.c: -- Remote UNIX connection
May 2 12:25:05 VERBOSE[5185] logger.c: -- Remote UNIX connection disconnected
May 2 12:25:05 VERBOSE[720] logger.c: -- Remote UNIX connection
May 2 12:25:05 VERBOSE[5187] logger.c: -- Remote UNIX connection disconnected
May 2 12:25:05 VERBOSE[720] logger.c: -- Remote UNIX connection
May 2 12:25:05 VERBOSE[5189] logger.c: -- Remote UNIX connection disconnected
May 2 12:25:05 VERBOSE[720] logger.c: -- Remote UNIX connection
May 2 12:25:05 VERBOSE[5191] logger.c: -- Remote UNIX connection disconnected
May 2 12:25:05 VERBOSE[720] logger.c: -- Remote UNIX connection
May 2 12:25:05 VERBOSE[5193] logger.c: -- Remote UNIX connection disconnected
May 2 12:25:05 VERBOSE[720] logger.c: -- Remote UNIX connection
May 2 12:25:05 VERBOSE[5195] logger.c: -- Remote UNIX connection disconnected
May 2 12:25:05 VERBOSE[720] logger.c: -- Remote UNIX connection
May 2 12:25:05 VERBOSE[5197] logger.c: -- Remote UNIX connection disconnected
May 2 12:25:05 VERBOSE[720] logger.c: -- Remote UNIX connection
May 2 12:25:06 VERBOSE[5199] logger.c: -- Remote UNIX connection disconnected
May 2 12:25:06 VERBOSE[720] logger.c: -- Remote UNIX connection
May 2 12:25:06 VERBOSE[5201] logger.c: -- Remote UNIX connection disconnected
May 2 12:25:06 VERBOSE[720] logger.c: -- Remote UNIX connection
May 2 12:25:06 VERBOSE[5203] logger.c: -- Remote UNIX connection disconnected
May 2 12:25:06 VERBOSE[720] logger.c: -- Remote UNIX connection
May 2 12:25:06 VERBOSE[5205] logger.c: -- Remote UNIX connection disconnected
May 2 12:25:21 VERBOSE[734] logger.c: 12 headers, 0 lines
May 2 12:25:21 VERBOSE[734] logger.c: Reliably Transmitting (NAT) to 192.168.1.254:5061:
OPTIONS sip:2001@192.168.1.1:5061;transport=udp SIP/2.0
Via: SIP/2.0/UDP 192.168.1.3:5060;branch=z9hG4bK1ca9b65c;rport
From: "Private" ;tag=as003a5904
To:
Contact:
Call-ID: 1843076e03e72c731e608a4c3e1e0c6e@192.168.1.3
CSeq: 102 OPTIONS
User-Agent: InnoMedia SIP MTA6328-2Re v3.0.77
Max-Forwards: 70
Date: Wed, 02 May 2007 19:25:21 GMT
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Content-Length: 0


---
May 2 12:25:21 VERBOSE[734] logger.c: 12 headers, 0 lines
May 2 12:25:21 VERBOSE[734] logger.c: Reliably Transmitting (NAT) to 192.168.1.143:5061:
OPTIONS sip:2000@192.168.1.1:5061;transport=udp SIP/2.0
Via: SIP/2.0/UDP 192.168.1.3:5060;branch=z9hG4bK4cbd8e54;rport
From: "Private" ;tag=as4a725ce5
To:
Contact:
Call-ID: 2d0d5a6301417b43117eabc157cd465f@192.168.1.3
CSeq: 102 OPTIONS
User-Agent: InnoMedia SIP MTA6328-2Re v3.0.77
Max-Forwards: 70
Date: Wed, 02 May 2007 19:25:21 GMT
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Content-Length: 0


---
May 2 12:25:21 VERBOSE[734] logger.c:
<-- SIP read from 192.168.1.143:5061:
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.1.3:5060;branch=z9hG4bK4cbd8e54;rport
From: "Private" ;tag=as4a725ce5
To: ;tag=00141c2247581da228adc9cf-326aae85
Call-ID: 2d0d5a6301417b43117eabc157cd465f@192.168.1.3
Date: Wed, 02 May 2007 19:25:22 GMT
CSeq: 102 OPTIONS
Server: Cisco-CP7940G/8.0
Allow: ACK,BYE,CANCEL,INVITE,NOTIFY,OPTIONS,REFER,REGISTER,UPDATE
Accept: application/sdp,multipart/mixed,multipart/alternative
Accept-Encoding: identity
Accept-Language: en
Supported: replaces,join,norefersub
Content-Length: 232
Content-Type: application/sdp
Content-Disposition: session;handling=optional

v=0
o=Cisco-SIPUA 7685 0 IN IP4 192.168.1.1
s=SIP Call
t=0 0
m=audio 0 RTP/AVP 0 8 18 101
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:18 G729/0
a=fmtp:18 annexb=no
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15

May 2 12:25:21 VERBOSE[734] logger.c: --- (16 headers 11 lines) ---
May 2 12:25:21 DEBUG[734] chan_sip.c: Stopping retransmission on '2d0d5a6301417b43117eabc157cd465f@192.168.1.3' of Request 102: Match Found
May 2 12:25:21 VERBOSE[734] logger.c: Destroying call '2d0d5a6301417b43117eabc157cd465f@192.168.1.3'
May 2 12:25:21 VERBOSE[734] logger.c:
<-- SIP read from 192.168.1.254:5061:
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.1.3:5060;branch=z9hG4bK1ca9b65c;rport
From: "Private" ;tag=as003a5904
To: ;tag=00141c22475e1c133b06155d-4d5d8602
Call-ID: 1843076e03e72c731e608a4c3e1e0c6e@192.168.1.3
Date: Wed, 02 May 2007 19:25:21 GMT
CSeq: 102 OPTIONS
Server: Cisco-CP7940G/8.0
Allow: ACK,BYE,CANCEL,INVITE,NOTIFY,OPTIONS,REFER,REGISTER,UPDATE
Accept: application/sdp,multipart/mixed,multipart/alternative
Accept-Encoding: identity
Accept-Language: en
Supported: replaces,join,norefersub
Content-Length: 232
Content-Type: application/sdp
Content-Disposition: session;handling=optional

v=0
o=Cisco-SIPUA 7733 0 IN IP4 192.168.1.1
s=SIP Call
t=0 0
m=audio 0 RTP/AVP 0 8 18 101
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:18 G729/0
a=fmtp:18 annexb=no
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15

May 2 12:25:21 VERBOSE[734] logger.c: --- (16 headers 11 lines) ---
May 2 12:25:21 DEBUG[734] chan_sip.c: Stopping retransmission on '1843076e03e72c731e608a4c3e1e0c6e@192.168.1.3' of Request 102: Match Found
May 2 12:25:21 VERBOSE[734] logger.c: Destroying call '1843076e03e72c731e608a4c3e1e0c6e@192.168.1.3'
May 2 12:25:28 DEBUG[5210] manager.c: Manager received command 'login'
May 2 12:25:28 VERBOSE[5210] logger.c: == Parsing '/etc/asterisk/manager.conf': May 2 12:25:28 VERBOSE[5210] logger.c: == Parsing '/etc/asterisk/manager.conf': Found
May 2 12:25:28 VERBOSE[5210] logger.c: == Parsing '/etc/asterisk/manager_custom.conf': May 2 12:25:28 VERBOSE[5210] logger.c: == Parsing '/etc/asterisk/manager_custom.conf': Found
May 2 12:25:28 DEBUG[5210] acl.c: 0.0.0.0/0.0.0.0/0.0.0.0 appended to acl for peer
May 2 12:25:28 DEBUG[5210] acl.c: 127.0.0.1/255.255.255.0/255.255.255.0 appended to acl for peer
May 2 12:25:28 DEBUG[5210] acl.c: ##### Testing 127.0.0.1 with 0.0.0.0
May 2 12:25:28 DEBUG[5210] acl.c: ##### Testing 127.0.0.1 with 127.0.0.0
May 2 12:25:28 VERBOSE[5210] logger.c: == Manager 'admin' logged on from 127.0.0.1
May 2 12:25:28 VERBOSE[5210] logger.c: == Manager 'admin' logged off from 127.0.0.1
May 2 12:25:31 DEBUG[5212] manager.c: Manager received command 'login'
May 2 12:25:31 VERBOSE[5212] logger.c: == Parsing '/etc/asterisk/manager.conf': May 2 12:25:31 VERBOSE[5212] logger.c: == Parsing '/etc/asterisk/manager.conf': Found
May 2 12:25:31 VERBOSE[5212] logger.c: == Parsing '/etc/asterisk/manager_custom.conf': May 2 12:25:31 VERBOSE[5212] logger.c: == Parsing '/etc/asterisk/manager_custom.conf': Found
May 2 12:25:31 DEBUG[5212] acl.c: 0.0.0.0/0.0.0.0/0.0.0.0 appended to acl for peer
May 2 12:25:31 DEBUG[5212] acl.c: 127.0.0.1/255.255.255.0/255.255.255.0 appended to acl for peer
May 2 12:25:31 DEBUG[5212] acl.c: ##### Testing 127.0.0.1 with 0.0.0.0
May 2 12:25:31 DEBUG[5212] acl.c: ##### Testing 127.0.0.1 with 127.0.0.0
May 2 12:25:31 VERBOSE[5212] logger.c: == Manager 'admin' logged on from 127.0.0.1
May 2 12:25:31 VERBOSE[5212] logger.c: == Manager 'admin' logged off from 127.0.0.1
May 2 12:25:51 NOTICE[734] chan_sip.c: -- Re-registration for 8182323476@sunrocket.com
May 2 12:25:51 DEBUG[734] chan_sip.c: Scheduled a registration timeout for sunrocket.com id #32201
May 2 12:25:51 VERBOSE[734] logger.c: REGISTER 12 headers, 0 lines
May 2 12:25:51 VERBOSE[734] logger.c: Reliably Transmitting (NAT) to 67.133.234.125:5060:
REGISTER sip:sunrocket.com SIP/2.0
Via: SIP/2.0/UDP 192.168.1.3:5060;branch=z9hG4bK242e63b3;rport
From: ;tag=as56bf7b7b
To:
Call-ID: 768d78be6bdf088b7acface60ddd936d@127.0.0.1
CSeq: 104 REGISTER
User-Agent: InnoMedia SIP MTA6328-2Re v3.0.77
Max-Forwards: 70
Expires: 120
Contact:
Event: registration
Content-Length: 0


---
May 2 12:25:51 VERBOSE[734] logger.c:
<-- SIP read from 67.133.234.125:5060:
SIP/2.0 200 OK
v:SIP/2.0/UDP 192.168.1.3:5060;branch=z9hG4bK242e63b3;rport
f:;tag=as56bf7b7b
t:
i:768d78be6bdf088b7acface60ddd936d@127.0.0.1
CSeq:104 REGISTER
User-Agent: InnoMedia SIP MTA6328-2Re v3.0.77
m:;expires=90
Event: registration
l:0


May 2 12:25:51 VERBOSE[734] logger.c: --- (10 headers 0 lines) ---
May 2 12:25:51 DEBUG[734] chan_sip.c: Stopping retransmission on '768d78be6bdf088b7acface60ddd936d@127.0.0.1' of Request 104: Match Found
May 2 12:25:51 DEBUG[734] chan_sip.c: Registration successful
May 2 12:25:51 DEBUG[734] chan_sip.c: Cancelling timeout 32201
May 2 12:25:51 VERBOSE[734] logger.c: Scheduling destruction of call '768d78be6bdf088b7acface60ddd936d@127.0.0.1' in 32000 ms
May 2 12:25:51 NOTICE[734] chan_sip.c: Outbound Registration: Expiry for sunrocket.com is 120 sec (Scheduling reregistration in 105 s)
May 2 12:25:51 NOTICE[734] chan_sip.c: -- Re-registration for 6418272550@sipnumber.net
May 2 12:25:52 DEBUG[734] chan_sip.c: Scheduled a registration timeout for sipnumber.net id #32205
May 2 12:25:52 DEBUG[734] chan_sip.c: >>> Re-using Auth data for 6418272550@sipnumber.net
May 2 12:25:52 VERBOSE[734] logger.c: REGISTER 13 headers, 0 lines
May 2 12:25:52 VERBOSE[734] logger.c: Reliably Transmitting (NAT) to 67.55.159.160:5060:
REGISTER sip:sipnumber.net SIP/2.0
Via: SIP/2.0/UDP 192.168.1.3:5060;branch=z9hG4bK71df4a2f;rport
From: ;tag=as3baed5b4
To:
Call-ID: 32fa39c67608cb187a085a825a1ff024@127.0.0.1
CSeq: 105 REGISTER
User-Agent: InnoMedia SIP MTA6328-2Re v3.0.77
Max-Forwards: 70
Authorization: Digest username="6418272550", realm="sipnumber.net", algorithm=MD5, uri="sip:sipnumber.net", nonce="4638e619e78ba4995e3dc482bf48fb2c83a44644", response="c75e08f5e91d50a4399a4f3197ace6c2", opaque=""
Expires: 120
Contact:
Event: registration
Content-Length: 0


---
May 2 12:25:52 VERBOSE[734] logger.c:
<-- SIP read from 67.55.159.160:5060:
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.1.3:5060;branch=z9hG4bK71df4a2f;rport=5060
From: ;tag=as3baed5b4
To: ;tag=9eacff2ee73e6595c6126247c2fa2a6e.1956
Call-ID: 32fa39c67608cb187a085a825a1ff024@127.0.0.1
CSeq: 105 REGISTER
Contact: ;expires=120
Server: Sip EXpress router (0.9.3 (i386/linux))
Content-Length: 0
Warning: 392 67.55.159.160:5060 "Noisy feedback tells: pid=14145 req_src_ip=76.173.56.41 req_src_port=5060 in_uri=sip:sipnumber.net out_uri=sip:sipnumber.net via_cnt==1"


May 2 12:25:52 VERBOSE[734] logger.c: --- (10 headers 0 lines) ---
May 2 12:25:52 DEBUG[734] chan_sip.c: Stopping retransmission on '32fa39c67608cb187a085a825a1ff024@127.0.0.1' of Request 105: Match Found
May 2 12:25:52 DEBUG[734] chan_sip.c: Registration successful
May 2 12:25:52 DEBUG[734] chan_sip.c: Cancelling timeout 32205
May 2 12:25:52 VERBOSE[734] logger.c: Scheduling destruction of call '32fa39c67608cb187a085a825a1ff024@127.0.0.1' in 32000 ms
May 2 12:25:52 NOTICE[734] chan_sip.c: Outbound Registration: Expiry for sipnumber.net is 120 sec (Scheduling reregistration in 105 s)
May 2 12:26:21 VERBOSE[734] logger.c: 12 headers, 0 lines
May 2 12:26:21 VERBOSE[734] logger.c: Reliably Transmitting (NAT) to 192.168.1.143:5061:
OPTIONS sip:2000@192.168.1.1:5061;transport=udp SIP/2.0
Via: SIP/2.0/UDP 192.168.1.3:5060;branch=z9hG4bK7b0bc47d;rport
From: "Private" ;tag=as09a20ac0
To:
Contact:
Call-ID: 4eafbaf7773dc1be2715d1a5213f5f72@192.168.1.3
CSeq: 102 OPTIONS
User-Agent: InnoMedia SIP MTA6328-2Re v3.0.77
Max-Forwards: 70
Date: Wed, 02 May 2007 19:26:21 GMT
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Content-Length: 0


---
May 2 12:26:21 VERBOSE[734] logger.c: 12 headers, 0 lines
May 2 12:26:21 VERBOSE[734] logger.c: Reliably Transmitting (NAT) to 192.168.1.254:5061:
OPTIONS sip:2001@192.168.1.1:5061;transport=udp SIP/2.0
Via: SIP/2.0/UDP 192.168.1.3:5060;branch=z9hG4bK5c3fafec;rport
From: "Private" ;tag=as686da9db
To:
Contact:
Call-ID: 0ccce4f21abb6c3e1f3337e66a8db582@192.168.1.3
CSeq: 102 OPTIONS
User-Agent: InnoMedia SIP MTA6328-2Re v3.0.77
Max-Forwards: 70
Date: Wed, 02 May 2007 19:26:21 GMT
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Content-Length: 0


---
May 2 12:26:22 VERBOSE[734] logger.c:
<-- SIP read from 192.168.1.143:5061:
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.1.3:5060;branch=z9hG4bK7b0bc47d;rport
From: "Private" ;tag=as09a20ac0
To: ;tag=00141c2247581da32316cd3b-0b4a00f6
Call-ID: 4eafbaf7773dc1be2715d1a5213f5f72@192.168.1.3
Date: Wed, 02 May 2007 19:26:22 GMT
CSeq: 102 OPTIONS
Server: Cisco-CP7940G/8.0
Allow: ACK,BYE,CANCEL,INVITE,NOTIFY,OPTIONS,REFER,REGISTER,UPDATE
Accept: application/sdp,multipart/mixed,multipart/alternative
Accept-Encoding: identity
Accept-Language: en
Supported: replaces,join,norefersub
Content-Length: 233
Content-Type: application/sdp
Content-Disposition: session;handling=optional

v=0
o=Cisco-SIPUA 24668 0 IN IP4 192.168.1.1
s=SIP Call
t=0 0
m=audio 0 RTP/AVP 0 8 18 101
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:18 G729/0
a=fmtp:18 annexb=no
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15

May 2 12:26:22 VERBOSE[734] logger.c: --- (16 headers 11 lines) ---
May 2 12:26:22 DEBUG[734] chan_sip.c: Stopping retransmission on '4eafbaf7773dc1be2715d1a5213f5f72@192.168.1.3' of Request 102: Match Found
May 2 12:26:22 VERBOSE[734] logger.c: Destroying call '4eafbaf7773dc1be2715d1a5213f5f72@192.168.1.3'
May 2 12:26:22 VERBOSE[734] logger.c:
<-- SIP read from 192.168.1.254:5061:
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.1.3:5060;branch=z9hG4bK5c3fafec;rport
From: "Private" ;tag=as686da9db
To: ;tag=00141c22475e1c141c38f8be-3706e29c
Call-ID: 0ccce4f21abb6c3e1f3337e66a8db582@192.168.1.3
Date: Wed, 02 May 2007 19:26:22 GMT
CSeq: 102 OPTIONS
Server: Cisco-CP7940G/8.0
Allow: ACK,BYE,CANCEL,INVITE,NOTIFY,OPTIONS,REFER,REGISTER,UPDATE
Accept: application/sdp,multipart/mixed,multipart/alternative
Accept-Encoding: identity
Accept-Language: en
Supported: replaces,join,norefersub
Content-Length: 232
Content-Type: application/sdp
Content-Disposition: session;handling=optional

v=0
o=Cisco-SIPUA 4913 0 IN IP4 192.168.1.1
s=SIP Call
t=0 0
m=audio 0 RTP/AVP 0 8 18 101
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:18 G729/0
a=fmtp:18 annexb=no
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15

May 2 12:26:22 VERBOSE[734] logger.c: --- (16 headers 11 lines) ---
May 2 12:26:22 DEBUG[734] chan_sip.c: Stopping retransmission on '0ccce4f21abb6c3e1f3337e66a8db582@192.168.1.3' of Request 102: Match Found
May 2 12:26:22 VERBOSE[734] logger.c: Destroying call '0ccce4f21abb6c3e1f3337e66a8db582@192.168.1.3'
May 2 12:26:23 DEBUG[734] chan_sip.c: Auto destroying call '768d78be6bdf088b7acface60ddd936d@127.0.0.1'
May 2 12:26:23 VERBOSE[734] logger.c: Destroying call '768d78be6bdf088b7acface60ddd936d@127.0.0.1'
May 2 12:26:24 DEBUG[734] chan_sip.c: Auto destroying call '32fa39c67608cb187a085a825a1ff024@127.0.0.1'
May 2 12:26:24 VERBOSE[734] logger.c: Destroying call '32fa39c67608cb187a085a825a1ff024@127.0.0.1'
May 2 12:26:28 DEBUG[5120] manager.c: Manager received command 'Command'
May 2 12:26:28 DEBUG[5120] manager.c: Manager received command 'Command'
May 2 12:26:28 DEBUG[5120] manager.c: Manager received command 'Command'
May 2 12:27:22 VERBOSE[734] logger.c: 12 headers, 0 lines
May 2 12:27:22 VERBOSE[734] logger.c: Reliably Transmitting (NAT) to 192.168.1.143:5061:
OPTIONS sip:2000@192.168.1.1:5061;transport=udp SIP/2.0
Via: SIP/2.0/UDP 192.168.1.3:5060;branch=z9hG4bK0e383e54;rport
From: "Private" ;tag=as3e69e41c
To:
Contact:
Call-ID: 58cd0ea7778950df5712bec65cf40194@192.168.1.3
CSeq: 102 OPTIONS
User-Agent: InnoMedia SIP MTA6328-2Re v3.0.77
Max-Forwards: 70
Date: Wed, 02 May 2007 19:27:22 GMT
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Content-Length: 0


---
May 2 12:27:22 VERBOSE[734] logger.c: 12 headers, 0 lines
May 2 12:27:22 VERBOSE[734] logger.c: Reliably Transmitting (NAT) to 192.168.1.254:5061:
OPTIONS sip:2001@192.168.1.1:5061;transport=udp SIP/2.0
Via: SIP/2.0/UDP 192.168.1.3:5060;branch=z9hG4bK656e2a2e;rport
From: "Private" ;tag=as4ff46e6b
To:
Contact:
Call-ID: 5c519e697494a5d7787bd24b16dc5c97@192.168.1.3
CSeq: 102 OPTIONS
User-Agent: InnoMedia SIP MTA6328-2Re v3.0.77
Max-Forwards: 70
Date: Wed, 02 May 2007 19:27:22 GMT
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Content-Length: 0


---
May 2 12:27:22 VERBOSE[734] logger.c:
<-- SIP read from 192.168.1.143:5061:
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.1.3:5060;branch=z9hG4bK0e383e54;rport
From: "Private" ;tag=as3e69e41c
To: ;tag=00141c2247581da41871ee80-3eaf631d
Call-ID: 58cd0ea7778950df5712bec65cf40194@192.168.1.3
Date: Wed, 02 May 2007 19:27:22 GMT
CSeq: 102 OPTIONS
Server: Cisco-CP7940G/8.0
Allow: ACK,BYE,CANCEL,INVITE,NOTIFY,OPTIONS,REFER,REGISTER,UPDATE
Accept: application/sdp,multipart/mixed,multipart/alternative
Accept-Encoding: identity
Accept-Language: en
Supported: replaces,join,norefersub
Content-Length: 233
Content-Type: application/sdp
Content-Disposition: session;handling=optional

v=0
o=Cisco-SIPUA 25381 0 IN IP4 192.168.1.1
s=SIP Call
t=0 0
m=audio 0 RTP/AVP 0 8 18 101
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:18 G729/0
a=fmtp:18 annexb=no
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15

May 2 12:27:22 VERBOSE[734] logger.c: --- (16 headers 11 lines) ---
May 2 12:27:22 DEBUG[734] chan_sip.c: Stopping retransmission on '58cd0ea7778950df5712bec65cf40194@192.168.1.3' of Request 102: Match Found
May 2 12:27:22 VERBOSE[734] logger.c: Destroying call '58cd0ea7778950df5712bec65cf40194@192.168.1.3'
May 2 12:27:22 VERBOSE[734] logger.c:
<-- SIP read from 192.168.1.254:5061:
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.1.3:5060;branch=z9hG4bK656e2a2e;rport
From: "Private" ;tag=as4ff46e6b
To: ;tag=00141c22475e1c15082724fa-7f5b6dc0
Call-ID: 5c519e697494a5d7787bd24b16dc5c97@192.168.1.3
Date: Wed, 02 May 2007 19:27:22 GMT
CSeq: 102 OPTIONS
Server: Cisco-CP7940G/8.0
Allow: ACK,BYE,CANCEL,INVITE,NOTIFY,OPTIONS,REFER,REGISTER,UPDATE
Accept: application/sdp,multipart/mixed,multipart/alternative
Accept-Encoding: identity
Accept-Language: en
Supported: replaces,join,norefersub
Content-Length: 233
Content-Type: application/sdp
Content-Disposition: session;handling=optional

v=0
o=Cisco-SIPUA 13517 0 IN IP4 192.168.1.1
s=SIP Call
t=0 0
m=audio 0 RTP/AVP 0 8 18 101
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:18 G729/0
a=fmtp:18 annexb=no
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15

May 2 12:27:22 VERBOSE[734] logger.c: --- (16 headers 11 lines) ---
May 2 12:27:22 DEBUG[734] chan_sip.c: Stopping retransmission on '5c519e697494a5d7787bd24b16dc5c97@192.168.1.3' of Request 102: Match Found
May 2 12:27:22 VERBOSE[734] logger.c: Destroying call '5c519e697494a5d7787bd24b16dc5c97@192.168.1.3'
May 2 12:27:36 NOTICE[734] chan_sip.c: -- Re-registration for 8182323476@sunrocket.com
May 2 12:27:36 DEBUG[734] chan_sip.c: Scheduled a registration timeout for sunrocket.com id #32221
May 2 12:27:36 VERBOSE[734] logger.c: REGISTER 12 headers, 0 lines
May 2 12:27:36 VERBOSE[734] logger.c: Reliably Transmitting (NAT) to 67.133.234.125:5060:
REGISTER sip:sunrocket.com SIP/2.0
Via: SIP/2.0/UDP 192.168.1.3:5060;branch=z9hG4bK52e31e3c;rport
From: ;tag=as3ab4bb37
To:
Call-ID: 768d78be6bdf088b7acface60ddd936d@127.0.0.1
CSeq: 105 REGISTER
User-Agent: InnoMedia SIP MTA6328-2Re v3.0.77
Max-Forwards: 70
Expires: 120
Contact:
Event: registration
Content-Length: 0


---
May 2 12:27:36 VERBOSE[734] logger.c:
<-- SIP read from 67.133.234.125:5060:
SIP/2.0 200 OK
v:SIP/2.0/UDP 192.168.1.3:5060;branch=z9hG4bK52e31e3c;rport
f:;tag=as3ab4bb37
t:
i:768d78be6bdf088b7acface60ddd936d@127.0.0.1
CSeq:105 REGISTER
User-Agent: InnoMedia SIP MTA6328-2Re v3.0.77
m:;expires=90
Event: registration
l:0


May 2 12:27:36 VERBOSE[734] logger.c: --- (10 headers 0 lines) ---
May 2 12:27:36 DEBUG[734] chan_sip.c: Stopping retransmission on '768d78be6bdf088b7acface60ddd936d@127.0.0.1' of Request 105: Match Found
May 2 12:27:36 DEBUG[734] chan_sip.c: Registration successful
May 2 12:27:36 DEBUG[734] chan_sip.c: Cancelling timeout 32221
May 2 12:27:36 VERBOSE[734] logger.c: Scheduling destruction of call '768d78be6bdf088b7acface60ddd936d@127.0.0.1' in 32000 ms
May 2 12:27:36 NOTICE[734] chan_sip.c: Outbound Registration: Expiry for sunrocket.com is 120 sec (Scheduling reregistration in 105 s)
May 2 12:27:37 NOTICE[734] chan_sip.c: -- Re-registration for 6418272550@sipnumber.net
May 2 12:27:37 DEBUG[734] chan_sip.c: Scheduled a registration timeout for sipnumber.net id #32225
May 2 12:27:37 DEBUG[734] chan_sip.c: >>> Re-using Auth data for 6418272550@sipnumber.net
May 2 12:27:37 VERBOSE[734] logger.c: REGISTER 13 headers, 0 lines
May 2 12:27:37 VERBOSE[734] logger.c: Reliably Transmitting (NAT) to 67.55.159.160:5060:
REGISTER sip:sipnumber.net SIP/2.0
Via: SIP/2.0/UDP 192.168.1.3:5060;branch=z9hG4bK3387a5ab;rport
From: ;tag=as67174fb6
To:
Call-ID: 32fa39c67608cb187a085a825a1ff024@127.0.0.1
CSeq: 106 REGISTER
User-Agent: InnoMedia SIP MTA6328-2Re v3.0.77
Max-Forwards: 70
Authorization: Digest username="6418272550", realm="sipnumber.net", algorithm=MD5, uri="sip:sipnumber.net", nonce="4638e619e78ba4995e3dc482bf48fb2c83a44644", response="c75e08f5e91d50a4399a4f3197ace6c2", opaque=""
Expires: 120
Contact:
Event: registration
Content-Length: 0


---
May 2 12:27:37 VERBOSE[734] logger.c:
<-- SIP read from 67.55.159.160:5060:
SIP/2.0 401 Unauthorized
Via: SIP/2.0/UDP 192.168.1.3:5060;branch=z9hG4bK3387a5ab;rport=5060
From: ;tag=as67174fb6
To: ;tag=9eacff2ee73e6595c6126247c2fa2a6e.f3fa
Call-ID: 32fa39c67608cb187a085a825a1ff024@127.0.0.1
CSeq: 106 REGISTER
WWW-Authenticate: Digest realm="sipnumber.net", nonce="4638e75591db16a841e6e84733cf7415e7c08a62", stale=true
Server: Sip EXpress router (0.9.3 (i386/linux))
Content-Length: 0
Warning: 392 67.55.159.160:5060 "Noisy feedback tells: pid=14143 req_src_ip=76.173.56.41 req_src_port=5060 in_uri=sip:sipnumber.net out_uri=sip:sipnumber.net via_cnt==1"


May 2 12:27:37 VERBOSE[734] logger.c: --- (10 headers 0 lines) ---
May 2 12:27:37 DEBUG[734] chan_sip.c: Stopping retransmission on '32fa39c67608cb187a085a825a1ff024@127.0.0.1' of Request 106: Match Found
May 2 12:27:37 VERBOSE[734] logger.c: Responding to challenge, registration to domain/host name sipnumber.net
May 2 12:27:37 VERBOSE[734] logger.c: REGISTER 13 headers, 0 lines
May 2 12:27:37 VERBOSE[734] logger.c: Reliably Transmitting (NAT) to 67.55.159.160:5060:
REGISTER sip:sipnumber.net SIP/2.0
Via: SIP/2.0/UDP 192.168.1.3:5060;branch=z9hG4bK5aee5e3e;rport
From: ;tag=as57eb4fd4
To:
Call-ID: 32fa39c67608cb187a085a825a1ff024@127.0.0.1
CSeq: 107 REGISTER
User-Agent: InnoMedia SIP MTA6328-2Re v3.0.77
Max-Forwards: 70
Authorization: Digest username="6418272550", realm="sipnumber.net", algorithm=MD5, uri="sip:sipnumber.net", nonce="4638e75591db16a841e6e84733cf7415e7c08a62", response="917e61d1bfe740d1eeee64cd135f1129", opaque=""
Expires: 120
Contact:
Event: registration
Content-Length: 0


---
May 2 12:27:37 VERBOSE[734] logger.c:
<-- SIP read from 67.55.159.160:5060:
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.1.3:5060;branch=z9hG4bK5aee5e3e;rport=5060
From: ;tag=as57eb4fd4
To: ;tag=9eacff2ee73e6595c6126247c2fa2a6e.f415
Call-ID: 32fa39c67608cb187a085a825a1ff024@127.0.0.1
CSeq: 107 REGISTER
Contact: ;expires=120
Server: Sip EXpress router (0.9.3 (i386/linux))
Content-Length: 0
Warning: 392 67.55.159.160:5060 "Noisy feedback tells: pid=14144 req_src_ip=76.173.56.41 req_src_port=5060 in_uri=sip:sipnumber.net out_uri=sip:sipnumber.net via_cnt==1"


May 2 12:27:37 VERBOSE[734] logger.c: --- (10 headers 0 lines) ---
May 2 12:27:37 DEBUG[734] chan_sip.c: Stopping retransmission on '32fa39c67608cb187a085a825a1ff024@127.0.0.1' of Request 107: Match Found
May 2 12:27:37 DEBUG[734] chan_sip.c: Registration successful
May 2 12:27:37 DEBUG[734] chan_sip.c: Cancelling timeout 32225
May 2 12:27:37 VERBOSE[734] logger.c: Scheduling destruction of call '32fa39c67608cb187a085a825a1ff024@127.0.0.1' in 32000 ms
May 2 12:27:37 NOTICE[734] chan_sip.c: Outbound Registration: Expiry for sipnumber.net is 120 sec (Scheduling reregistration in 105 s)
May 2 12:28:08 DEBUG[734] chan_sip.c: Auto destroying call '768d78be6bdf088b7acface60ddd936d@127.0.0.1'
May 2 12:28:08 VERBOSE[734] logger.c: Destroying call '768d78be6bdf088b7acface60ddd936d@127.0.0.1'
May 2 12:28:09 DEBUG[734] chan_sip.c: Auto destroying call '32fa39c67608cb187a085a825a1ff024@127.0.0.1'
May 2 12:28:09 VERBOSE[734] logger.c: Destroying call '32fa39c67608cb187a085a825a1ff024@127.0.0.1'
May 2 12:28:22 VERBOSE[734] logger.c: 12 headers, 0 lines
May 2 12:28:22 VERBOSE[734] logger.c: Reliably Transmitting (NAT) to 192.168.1.143:5061:
OPTIONS sip:2000@192.168.1.1:5061;transport=udp SIP/2.0
Via: SIP/2.0/UDP 192.168.1.3:5060;branch=z9hG4bK2be76544;rport
From: "Private" ;tag=as6f847d37
To:
Contact:
Call-ID: 392e5fd247841ed94d134412612e19f0@192.168.1.3
CSeq: 102 OPTIONS
User-Agent: InnoMedia SIP MTA6328-2Re v3.0.77
Max-Forwards: 70
Date: Wed, 02 May 2007 19:28:22 GMT
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Content-Length: 0


---
May 2 12:28:22 VERBOSE[734] logger.c: 12 headers, 0 lines
May 2 12:28:22 VERBOSE[734] logger.c: Reliably Transmitting (NAT) to 192.168.1.254:5061:
OPTIONS sip:2001@192.168.1.1:5061;transport=udp SIP/2.0
Via: SIP/2.0/UDP 192.168.1.3:5060;branch=z9hG4bK05b3a6d2;rport
From: "Private" ;tag=as052bac47
To:
Contact:
Call-ID: 2fa72b4e50e68ac03ff79c607d4422ae@192.168.1.3
CSeq: 102 OPTIONS
User-Agent: InnoMedia SIP MTA6328-2Re v3.0.77
Max-Forwards: 70
Date: Wed, 02 May 2007 19:28:22 GMT
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Content-Length: 0


---
May 2 12:28:22 VERBOSE[734] logger.c:
<-- SIP read from 192.168.1.143:5061:
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.1.3:5060;branch=z9hG4bK2be76544;rport
From: "Private" ;tag=as6f847d37
To: ;tag=00141c2247581da560bd088c-7018254a
Call-ID: 392e5fd247841ed94d134412612e19f0@192.168.1.3
Date: Wed, 02 May 2007 19:28:22 GMT
CSeq: 102 OPTIONS
Server: Cisco-CP7940G/8.0
Allow: ACK,BYE,CANCEL,INVITE,NOTIFY,OPTIONS,REFER,REGISTER,UPDATE
Accept: application/sdp,multipart/mixed,multipart/alternative
Accept-Encoding: identity
Accept-Language: en
Supported: replaces,join,norefersub
Content-Length: 233
Content-Type: application/sdp
Content-Disposition: session;handling=optional

v=0
o=Cisco-SIPUA 27511 0 IN IP4 192.168.1.1
s=SIP Call
t=0 0
m=audio 0 RTP/AVP 0 8 18 101
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:18 G729/0
a=fmtp:18 annexb=no
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15

May 2 12:28:22 VERBOSE[734] logger.c: --- (16 headers 11 lines) ---
May 2 12:28:22 DEBUG[734] chan_sip.c: Stopping retransmission on '392e5fd247841ed94d134412612e19f0@192.168.1.3' of Request 102: Match Found
May 2 12:28:22 VERBOSE[734] logger.c: Destroying call '392e5fd247841ed94d134412612e19f0@192.168.1.3'
May 2 12:28:22 VERBOSE[734] logger.c:
<-- SIP read from 192.168.1.254:5061:
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.1.3:5060;branch=z9hG4bK05b3a6d2;rport
From: "Private" ;tag=as052bac47
To: ;tag=00141c22475e1c1647e84885-139689d3
Call-ID: 2fa72b4e50e68ac03ff79c607d4422ae@192.168.1.3
Date: Wed, 02 May 2007 19:28:22 GMT
CSeq: 102 OPTIONS
Server: Cisco-CP7940G/8.0
Allow: ACK,BYE,CANCEL,INVITE,NOTIFY,OPTIONS,REFER,REGISTER,UPDATE
Accept: application/sdp,multipart/mixed,multipart/alternative
Accept-Encoding: identity
Accept-Language: en
Supported: replaces,join,norefersub
Content-Length: 233
Content-Type: application/sdp
Content-Disposition: session;handling=optional

v=0
o=Cisco-SIPUA 22802 0 IN IP4 192.168.1.1
s=SIP Call
t=0 0
m=audio 0 RTP/AVP 0 8 18 101
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:18 G729/0
a=fmtp:18 annexb=no
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15

May 2 12:28:22 VERBOSE[734] logger.c: --- (16 headers 11 lines) ---
May 2 12:28:22 DEBUG[734] chan_sip.c: Stopping retransmission on '2fa72b4e50e68ac03ff79c607d4422ae@192.168.1.3' of Request 102: Match Found
May 2 12:28:22 VERBOSE[734] logger.c: Destroying call '2fa72b4e50e68ac03ff79c607d4422ae@192.168.1.3'
May 2 12:28:28 DEBUG[5120] manager.c: Manager received command 'Command'
May 2 12:28:28 DEBUG[5120] manager.c: Manager received command 'Command'
May 2 12:28:28 DEBUG[5120] manager.c: Manager received command 'Command'
May 2 12:29:21 NOTICE[734] chan_sip.c: -- Re-registration for 8182323476@sunrocket.com
May 2 12:29:21 DEBUG[734] chan_sip.c: Scheduled a registration timeout for sunrocket.com id #32236
May 2 12:29:21 VERBOSE[734] logger.c: REGISTER 12 headers, 0 lines
May 2 12:29:21 VERBOSE[734] logger.c: Reliably Transmitting (NAT) to 67.133.234.125:5060:
REGISTER sip:sunrocket.com SIP/2.0
Via: SIP/2.0/UDP 192.168.1.3:5060;branch=z9hG4bK68cb0047;rport
From: ;tag=as76985cb3
To:
Call-ID: 768d78be6bdf088b7acface60ddd936d@127.0.0.1
CSeq: 106 REGISTER
User-Agent: InnoMedia SIP MTA6328-2Re v3.0.77
Max-Forwards: 70
Expires: 120
Contact:
Event: registration
Content-Length: 0


---
May 2 12:29:22 VERBOSE[734] logger.c:
<-- SIP read from 67.133.234.125:5060:
SIP/2.0 200 OK
v:SIP/2.0/UDP 192.168.1.3:5060;branch=z9hG4bK68cb0047;rport
f:;tag=as76985cb3
t:
i:768d78be6bdf088b7acface60ddd936d@127.0.0.1
CSeq:106 REGISTER
User-Agent: InnoMedia SIP MTA6328-2Re v3.0.77
m:;expires=90
Event: registration
l:0


May 2 12:29:22 VERBOSE[734] logger.c: --- (10 headers 0 lines) ---
May 2 12:29:22 DEBUG[734] chan_sip.c: Stopping retransmission on '768d78be6bdf088b7acface60ddd936d@127.0.0.1' of Request 106: Match Found
May 2 12:29:22 DEBUG[734] chan_sip.c: Registration successful
May 2 12:29:22 DEBUG[734] chan_sip.c: Cancelling timeout 32236
May 2 12:29:22 VERBOSE[734] logger.c: Scheduling destruction of call '768d78be6bdf088b7acface60ddd936d@127.0.0.1' in 32000 ms
May 2 12:29:22 NOTICE[734] chan_sip.c: Outbound Registration: Expiry for sunrocket.com is 120 sec (Scheduling reregistration in 105 s)
May 2 12:29:22 VERBOSE[734] logger.c: 12 headers, 0 lines
May 2 12:29:22 VERBOSE[734] logger.c: Reliably Transmitting (NAT) to 192.168.1.143:5061:
OPTIONS sip:2000@192.168.1.1:5061;transport=udp SIP/2.0
Via: SIP/2.0/UDP 192.168.1.3:5060;branch=z9hG4bK38bb22cf;rport
From: "Private" ;tag=as2769629b
To:
Contact:
Call-ID: 4d52f35a0bbcd7774ff4a65351fa4ad4@192.168.1.3
CSeq: 102 OPTIONS
User-Agent: InnoMedia SIP MTA6328-2Re v3.0.77
Max-Forwards: 70
Date: Wed, 02 May 2007 19:29:22 GMT
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Content-Length: 0


---
May 2 12:29:22 NOTICE[734] chan_sip.c: -- Re-registration for 6418272550@sipnumber.net
May 2 12:29:22 DEBUG[734] chan_sip.c: Scheduled a registration timeout for sipnumber.net id #32242
May 2 12:29:22 DEBUG[734] chan_sip.c: >>> Re-using Auth data for 6418272550@sipnumber.net
May 2 12:29:22 VERBOSE[734] logger.c: REGISTER 13 headers, 0 lines
May 2 12:29:22 VERBOSE[734] logger.c: Reliably Transmitting (NAT) to 67.55.159.160:5060:
REGISTER sip:sipnumber.net SIP/2.0
Via: SIP/2.0/UDP 192.168.1.3:5060;branch=z9hG4bK505a619a;rport
From: ;tag=as63740fb1
To:
Call-ID: 32fa39c67608cb187a085a825a1ff024@127.0.0.1
CSeq: 108 REGISTER
User-Agent: InnoMedia SIP MTA6328-2Re v3.0.77
Max-Forwards: 70
Authorization: Digest username="6418272550", realm="sipnumber.net", algorithm=MD5, uri="sip:sipnumber.net", nonce="4638e75591db16a841e6e84733cf7415e7c08a62", response="917e61d1bfe740d1eeee64cd135f1129", opaque=""
Expires: 120
Contact:
Event: registration
Content-Length: 0


---
May 2 12:29:22 VERBOSE[734] logger.c: 12 headers, 0 lines
May 2 12:29:22 VERBOSE[734] logger.c: Reliably Transmitting (NAT) to 192.168.1.254:5061:
OPTIONS sip:2001@192.168.1.1:5061;transport=udp SIP/2.0
Via: SIP/2.0/UDP 192.168.1.3:5060;branch=z9hG4bK41abe9c6;rport
From: "Private" ;tag=as20c6540e
To:
Contact:
Call-ID: 21583a4f054db2a1619f510a5633f6e2@192.168.1.3
CSeq: 102 OPTIONS
User-Agent: InnoMedia SIP MTA6328-2Re v3.0.77
Max-Forwards: 70
Date: Wed, 02 May 2007 19:29:22 GMT
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Content-Length: 0


---
May 2 12:29:22 VERBOSE[734] logger.c:
<-- SIP read from 67.55.159.160:5060:
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.1.3:5060;branch=z9hG4bK505a619a;rport=5060
From: ;tag=as63740fb1
To: ;tag=9eacff2ee73e6595c6126247c2fa2a6e.f3fa
Call-ID: 32fa39c67608cb187a085a825a1ff024@127.0.0.1
CSeq: 108 REGISTER
Contact: ;expires=120
Server: Sip EXpress router (0.9.3 (i386/linux))
Content-Length: 0
Warning: 392 67.55.159.160:5060 "Noisy feedback tells: pid=14145 req_src_ip=76.173.56.41 req_src_port=5060 in_uri=sip:sipnumber.net out_uri=sip:sipnumber.net via_cnt==1"


May 2 12:29:22 VERBOSE[734] logger.c: --- (10 headers 0 lines) ---
May 2 12:29:22 DEBUG[734] chan_sip.c: Stopping retransmission on '32fa39c67608cb187a085a825a1ff024@127.0.0.1' of Request 108: Match Found
May 2 12:29:22 DEBUG[734] chan_sip.c: Registration successful
May 2 12:29:22 DEBUG[734] chan_sip.c: Cancelling timeout 32242
May 2 12:29:22 VERBOSE[734] logger.c: Scheduling destruction of call '32fa39c67608cb187a085a825a1ff024@127.0.0.1' in 32000 ms
May 2 12:29:22 NOTICE[734] chan_sip.c: Outbound Registration: Expiry for sipnumber.net is 120 sec (Scheduling reregistration in 105 s)
May 2 12:29:22 VERBOSE[734] logger.c:
<-- SIP read from 192.168.1.143:5061:
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.1.3:5060;branch=z9hG4bK38bb22cf;rport
From: "Private" ;tag=as2769629b
To: ;tag=00141c2247581da655c63aa5-51e410f9
Call-ID: 4d52f35a0bbcd7774ff4a65351fa4ad4@192.168.1.3
Date: Wed, 02 May 2007 19:29:22 GMT
CSeq: 102 OPTIONS
Server: Cisco-CP7940G/8.0
Allow: ACK,BYE,CANCEL,INVITE,NOTIFY,OPTIONS,REFER,REGISTER,UPDATE
Accept: application/sdp,multipart/mixed,multipart/alternative
Accept-Encoding: identity
Accept-Language: en
Supported: replaces,join,norefersub
Content-Length: 232
Content-Type: application/sdp
Content-Disposition: session;handling=optional

v=0
o=Cisco-SIPUA 4819 0 IN IP4 192.168.1.1
s=SIP Call
t=0 0
m=audio 0 RTP/AVP 0 8 18 101
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:18 G729/0
a=fmtp:18 annexb=no
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15

May 2 12:29:22 VERBOSE[734] logger.c: --- (16 headers 11 lines) ---
May 2 12:29:22 DEBUG[734] chan_sip.c: Stopping retransmission on '4d52f35a0bbcd7774ff4a65351fa4ad4@192.168.1.3' of Request 102: Match Found
May 2 12:29:22 VERBOSE[734] logger.c: Destroying call '4d52f35a0bbcd7774ff4a65351fa4ad4@192.168.1.3'
May 2 12:29:22 VERBOSE[734] logger.c:
<-- SIP read from 192.168.1.254:5061:
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.1.3:5060;branch=z9hG4bK41abe9c6;rport
From: "Private" ;tag=as20c6540e
To: ;tag=00141c22475e1c173e859805-08c73341
Call-ID: 21583a4f054db2a1619f510a5633f6e2@192.168.1.3
Date: Wed, 02 May 2007 19:29:22 GMT
CSeq: 102 OPTIONS
Server: Cisco-CP7940G/8.0
Allow: ACK,BYE,CANCEL,INVITE,NOTIFY,OPTIONS,REFER,REGISTER,UPDATE
Accept: application/sdp,multipart/mixed,multipart/alternative
Accept-Encoding: identity
Accept-Language: en
Supported: replaces,join,norefersub
Content-Length: 232
Content-Type: application/sdp
Content-Disposition: session;handling=optional

v=0
o=Cisco-SIPUA 4868 0 IN IP4 192.168.1.1
s=SIP Call
t=0 0
m=audio 0 RTP/AVP 0 8 18 101
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:18 G729/0
a=fmtp:18 annexb=no
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15

May 2 12:29:22 VERBOSE[734] logger.c: --- (16 headers 11 lines) ---
May 2 12:29:22 DEBUG[734] chan_sip.c: Stopping retransmission on '21583a4f054db2a1619f510a5633f6e2@192.168.1.3' of Request 102: Match Found
May 2 12:29:22 VERBOSE[734] logger.c: Destroying call '21583a4f054db2a1619f510a5633f6e2@192.168.1.3'
May 2 12:29:54 DEBUG[734] chan_sip.c: Auto destroying call '768d78be6bdf088b7acface60ddd936d@127.0.0.1'
May 2 12:29:54 VERBOSE[734] logger.c: Destroying call '768d78be6bdf088b7acface60ddd936d@127.0.0.1'
May 2 12:29:54 DEBUG[734] chan_sip.c: Auto destroying call '32fa39c67608cb187a085a825a1ff024@127.0.0.1'
May 2 12:29:54 VERBOSE[734] logger.c: Destroying call '32fa39c67608cb187a085a825a1ff024@127.0.0.1'
May 2 12:30:22 VERBOSE[734] logger.c: 12 headers, 0 lines
May 2 12:30:22 VERBOSE[734] logger.c: Reliably Transmitting (NAT) to 192.168.1.143:5061:
OPTIONS sip:2000@192.168.1.1:5061;transport=udp SIP/2.0
Via: SIP/2.0/UDP 192.168.1.3:5060;branch=z9hG4bK30fb89ff;rport
From: "Private" ;tag=as3a868ec9
To:
Contact:
Call-ID: 728311837d460da826e287d67d8c5c67@192.168.1.3
CSeq: 102 OPTIONS
User-Agent: InnoMedia SIP MTA6328-2Re v3.0.77
Max-Forwards: 70
Date: Wed, 02 May 2007 19:30:22 GMT
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Content-Length: 0


---
May 2 12:30:22 VERBOSE[734] logger.c: 12 headers, 0 lines
May 2 12:30:22 VERBOSE[734] logger.c: Reliably Transmitting (NAT) to 192.168.1.254:5061:
OPTIONS sip:2001@192.168.1.1:5061;transport=udp SIP/2.0
Via: SIP/2.0/UDP 192.168.1.3:5060;branch=z9hG4bK554d3246;rport
From: "Private" ;tag=as0018e9c5
To:
Contact:
Call-ID: 3cb8b8e875e718ba3d6b37930a7e7ba6@192.168.1.3
CSeq: 102 OPTIONS
User-Agent: InnoMedia SIP MTA6328-2Re v3.0.77
Max-Forwards: 70
Date: Wed, 02 May 2007 19:30:22 GMT
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Content-Length: 0


---
May 2 12:30:22 VERBOSE[734] logger.c:
<-- SIP read from 192.168.1.143:5061:
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.1.3:5060;branch=z9hG4bK30fb89ff;rport
From: "Private" ;tag=as3a868ec9
To: ;tag=00141c2247581da74366881b-0b217046
Call-ID: 728311837d460da826e287d67d8c5c67@192.168.1.3
Date: Wed, 02 May 2007 19:30:22 GMT
CSeq: 102 OPTIONS
Server: Cisco-CP7940G/8.0
Allow: ACK,BYE,CANCEL,INVITE,NOTIFY,OPTIONS,REFER,REGISTER,UPDATE
Accept: application/sdp,multipart/mixed,multipart/alternative
Accept-Encoding: identity
Accept-Language: en
Supported: replaces,join,norefersub
Content-Length: 232
Content-Type: application/sdp
Content-Disposition: session;handling=optional

v=0
o=Cisco-SIPUA 8365 0 IN IP4 192.168.1.1
s=SIP Call
t=0 0
m=audio 0 RTP/AVP 0 8 18 101
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:18 G729/0
a=fmtp:18 annexb=no
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15

May 2 12:30:22 VERBOSE[734] logger.c: --- (16 headers 11 lines) ---
May 2 12:30:22 DEBUG[734] chan_sip.c: Stopping retransmission on '728311837d460da826e287d67d8c5c67@192.168.1.3' of Request 102: Match Found
May 2 12:30:22 VERBOSE[734] logger.c: Destroying call '728311837d460da826e287d67d8c5c67@192.168.1.3'
May 2 12:30:22 VERBOSE[734] logger.c:
<-- SIP read from 192.168.1.254:5061:
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.1.3:5060;branch=z9hG4bK554d3246;rport
From: "Private" ;tag=as0018e9c5
To: ;tag=00141c22475e1c183114af7f-3f197152
Call-ID: 3cb8b8e875e718ba3d6b37930a7e7ba6@192.168.1.3
Date: Wed, 02 May 2007 19:30:22 GMT
CSeq: 102 OPTIONS
Server: Cisco-CP7940G/8.0
Allow: ACK,BYE,CANCEL,INVITE,NOTIFY,OPTIONS,REFER,REGISTER,UPDATE
Accept: application/sdp,multipart/mixed,multipart/alternative
Accept-Encoding: identity
Accept-Language: en
Supported: replaces,join,norefersub
Content-Length: 233
Content-Type: application/sdp
Content-Disposition: session;handling=optional

v=0
o=Cisco-SIPUA 15017 0 IN IP4 192.168.1.1
s=SIP Call
t=0 0
m=audio 0 RTP/AVP 0 8 18 101
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:18 G729/0
a=fmtp:18 annexb=no
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15

May 2 12:30:22 VERBOSE[734] logger.c: --- (16 headers 11 lines) ---
May 2 12:30:22 DEBUG[734] chan_sip.c: Stopping retransmission on '3cb8b8e875e718ba3d6b37930a7e7ba6@192.168.1.3' of Request 102: Match Found
May 2 12:30:22 VERBOSE[734] logger.c: Destroying call '3cb8b8e875e718ba3d6b37930a7e7ba6@192.168.1.3'
May 2 12:30:28 DEBUG[5120] manager.c: Manager received command 'Command'
May 2 12:30:28 DEBUG[5120] manager.c: Manager received command 'Command'
May 2 12:30:28 DEBUG[5120] manager.c: Manager received command 'Command'
May 2 12:31:07 NOTICE[734] chan_sip.c: -- Re-registration for 8182323476@sunrocket.com
May 2 12:31:07 DEBUG[734] chan_sip.c: Scheduled a registration timeout for sunrocket.com id #32256
May 2 12:31:07 VERBOSE[734] logger.c: REGISTER 12 headers, 0 lines
May 2 12:31:07 VERBOSE[734] logger.c: Reliably Transmitting (NAT) to 67.133.234.125:5060:
REGISTER sip:sunrocket.com SIP/2.0
Via: SIP/2.0/UDP 192.168.1.3:5060;branch=z9hG4bK6dd66aaf;rport
From: ;tag=as367f24a2
To:
Call-ID: 768d78be6bdf088b7acface60ddd936d@127.0.0.1
CSeq: 107 REGISTER
User-Agent: InnoMedia SIP MTA6328-2Re v3.0.77
Max-Forwards: 70
Expires: 120
Contact:
Event: registration
Content-Length: 0


---
May 2 12:31:07 VERBOSE[734] logger.c:
<-- SIP read from 67.133.234.125:5060:
SIP/2.0 200 OK
v:SIP/2.0/UDP 192.168.1.3:5060;branch=z9hG4bK6dd66aaf;rport
f:;tag=as367f24a2
t:
i:768d78be6bdf088b7acface60ddd936d@127.0.0.1
CSeq:107 REGISTER
User-Agent: InnoMedia SIP MTA6328-2Re v3.0.77
m:;expires=90
Event: registration
l:0


May 2 12:31:07 VERBOSE[734] logger.c: --- (10 headers 0 lines) ---
May 2 12:31:07 DEBUG[734] chan_sip.c: Stopping retransmission on '768d78be6bdf088b7acface60ddd936d@127.0.0.1' of Request 107: Match Found
May 2 12:31:07 DEBUG[734] chan_sip.c: Registration successful
May 2 12:31:07 DEBUG[734] chan_sip.c: Cancelling timeout 32256
May 2 12:31:07 VERBOSE[734] logger.c: Scheduling destruction of call '768d78be6bdf088b7acface60ddd936d@127.0.0.1' in 32000 ms
May 2 12:31:07 NOTICE[734] chan_sip.c: Outbound Registration: Expiry for sunrocket.com is 120 sec (Scheduling reregistration in 105 s)
May 2 12:31:07 NOTICE[734] chan_sip.c: -- Re-registration for 6418272550@sipnumber.net
May 2 12:31:07 DEBUG[734] chan_sip.c: Scheduled a registration timeout for sipnumber.net id #32260
May 2 12:31:07 DEBUG[734] chan_sip.c: >>> Re-using Auth data for 6418272550@sipnumber.net
May 2 12:31:07 VERBOSE[734] logger.c: REGISTER 13 headers, 0 lines
May 2 12:31:07 VERBOSE[734] logger.c: Reliably Transmitting (NAT) to 67.55.159.160:5060:
REGISTER sip:sipnumber.net SIP/2.0
Via: SIP/2.0/UDP 192.168.1.3:5060;branch=z9hG4bK5c799527;rport
From: ;tag=as096aedb0
To:
Call-ID: 32fa39c67608cb187a085a825a1ff024@127.0.0.1
CSeq: 109 REGISTER
User-Agent: InnoMedia SIP MTA6328-2Re v3.0.77
Max-Forwards: 70
Authorization: Digest username="6418272550", realm="sipnumber.net", algorithm=MD5, uri="sip:sipnumber.net", nonce="4638e75591db16a841e6e84733cf7415e7c08a62", response="917e61d1bfe740d1eeee64cd135f1129", opaque=""
Expires: 120
Contact:
Event: registration
Content-Length: 0


---
May 2 12:31:07 VERBOSE[734] logger.c:
<-- SIP read from 67.55.159.160:5060:
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.1.3:5060;branch=z9hG4bK5c799527;rport=5060
From: ;tag=as096aedb0
To: ;tag=9eacff2ee73e6595c6126247c2fa2a6e.9005
Call-ID: 32fa39c67608cb187a085a825a1ff024@127.0.0.1
CSeq: 109 REGISTER
Contact: ;expires=120
Server: Sip EXpress router (0.9.3 (i386/linux))
Content-Length: 0
Warning: 392 67.55.159.160:5060 "Noisy feedback tells: pid=14143 req_src_ip=76.173.56.41 req_src_port=5060 in_uri=sip:sipnumber.net out_uri=sip:sipnumber.net via_cnt==1"


May 2 12:31:07 VERBOSE[734] logger.c: --- (10 headers 0 lines) ---
May 2 12:31:07 DEBUG[734] chan_sip.c: Stopping retransmission on '32fa39c67608cb187a085a825a1ff024@127.0.0.1' of Request 109: Match Found
May 2 12:31:07 DEBUG[734] chan_sip.c: Registration successful
May 2 12:31:07 DEBUG[734] chan_sip.c: Cancelling timeout 32260
May 2 12:31:07 VERBOSE[734] logger.c: Scheduling destruction of call '32fa39c67608cb187a085a825a1ff024@127.0.0.1' in 32000 ms
May 2 12:31:07 NOTICE[734] chan_sip.c: Outbound Registration: Expiry for sipnumber.net is 120 sec (Scheduling reregistration in 105 s)
May 2 12:31:08 VERBOSE[734] logger.c:
<-- SIP read from 67.133.234.125:5060:
INVITE sip:8182323476@192.168.1.3:5060 SIP/2.0
f:"ITC INC" ;tag=acd8cd0c-1dd1-11b2-a7e8-b03162323164+acdd3932
m:"ITC INC"
t:
i:4830128-3943178@192.168.201.102
CSeq:1 INVITE
v:SIP/2.0/UDP 67.133.234.125:5060;branch=z9hG4bK76e53579abf17fef2e8ea580ac923ee1-0
v:SIP/2.0/UDP 192.168.201.89:5070;branch=0
v:SIP/2.0/UDP 192.168.201.102:5060;branch=z9hG4bK762935fdc36e6ae38abf52f3ae99deac.1
v:SIP/2.0/UDP 192.168.201.102:5075;branch=z9hG4bK1248144248918357
Record-Route:
Max-Forwards:68
Allow:INVITE,BYE,ACK,CANCEL,PRACK,REFER,OPTIONS,REGISTER,NOTIFY
c:application/sdp
k:timer
Min-SE: 1800
P-Asserted-Identity: "ITC INC"
l:178

v=0
o=- 55200 5520000 IN IP4 67.133.234.137
s=SIP Media Capabilities
c=IN IP4 67.133.234.137
t=0 0
m=audio 17640 RTP/AVP 0
a=rtpmap:0 PCMU/8000
a=sendrecv
a=maxptime:20

May 2 12:31:08 VERBOSE[734] logger.c: --- (18 headers 9 lines) ---
May 2 12:31:08 VERBOSE[734] logger.c: Using INVITE request as basis request - 4830128-3943178@192.168.201.102
May 2 12:31:08 VERBOSE[734] logger.c: Sending to 67.133.234.125 : 5060 (NAT)
May 2 12:31:08 VERBOSE[734] logger.c: Found peer 'SVoipProvider'
May 2 12:31:08 DEBUG[734] chan_sip.c: Setting NAT on RTP to 524288
May 2 12:31:08 VERBOSE[734] logger.c: Found RTP audio format 0
May 2 12:31:08 VERBOSE[734] logger.c: Peer audio RTP is at port 67.133.234.137:17640
May 2 12:31:08 DEBUG[734] chan_sip.c: Peer audio RTP is at port 67.133.234.137:17640
May 2 12:31:08 VERBOSE[734] logger.c: Found description format PCMU
May 2 12:31:08 VERBOSE[734] logger.c: Capabilities: us - 0xc (ulaw|alaw), peer - audio=0x4 (ulaw)/video=0x0 (nothing), combined - 0x4 (ulaw)
May 2 12:31:08 VERBOSE[734] logger.c: Non-codec capabilities: us - 0x1 (telephone-event), peer - 0x0 (nothing), combined - 0x0 (nothing)
May 2 12:31:08 DEBUG[734] chan_sip.c: Checking SIP call limits for device 101163321271823
May 2 12:31:08 VERBOSE[734] logger.c: Looking for 8182323476 in from-pstn (domain 192.168.1.3)
May 2 12:31:08 DEBUG[734] chan_sip.c: build_route: Record-Route hop:
May 2 12:31:08 VERBOSE[734] logger.c: list_route: hop:
May 2 12:31:08 VERBOSE[734] logger.c: Transmitting (NAT) to 67.133.234.125:5060:
SIP/2.0 100 Trying
Via: SIP/2.0/UDP 67.133.234.125:5060;branch=z9hG4bK76e53579abf17fef2e8ea580ac923ee1-0;received=67.133.234.125
Via: SIP/2.0/UDP 192.168.201.89:5070;branch=0
Via: SIP/2.0/UDP 192.168.201.102:5060;branch=z9hG4bK762935fdc36e6ae38abf52f3ae99deac.1
Via: SIP/2.0/UDP 192.168.201.102:5075;branch=z9hG4bK1248144248918357
From: "ITC INC" ;tag=acd8cd0c-1dd1-11b2-a7e8-b03162323164+acdd3932
To:
Call-ID: 4830128-3943178@192.168.201.102
CSeq: 1 INVITE
User-Agent: InnoMedia SIP MTA6328-2Re v3.0.77
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Contact:
Content-Length: 0


---
May 2 12:31:08 VERBOSE[5217] logger.c: -- Executing NoOp("SIP/101163321271823-09c225c8", "Catch-All DID Match - Found 8182323476 - You probably want a DID for this.") in new stack
May 2 12:31:08 VERBOSE[5217] logger.c: -- Executing Goto("SIP/101163321271823-09c225c8", "ext-did|s|1") in new stack
May 2 12:31:08 VERBOSE[5217] logger.c: -- Goto (ext-did,s,1)
May 2 12:31:08 VERBOSE[5217] logger.c: -- Executing Set("SIP/101163321271823-09c225c8", "FROM_DID=s") in new stack
May 2 12:31:08 VERBOSE[5217] logger.c: -- Executing Gosub("SIP/101163321271823-09c225c8", "app-blacklist-check|s|1") in new stack
May 2 12:31:08 VERBOSE[5217] logger.c: -- Executing LookupBlacklist("SIP/101163321271823-09c225c8", "") in new stack
May 2 12:31:08 DEBUG[5217] db.c: Unable to find key '8183550079' in family 'blacklist'
May 2 12:31:08 DEBUG[5217] db.c: Unable to find key 'ITC INC' in family 'blacklist'
May 2 12:31:08 DEBUG[5217] pbx.c: Expression result is '0'
May 2 12:31:08 VERBOSE[5217] logger.c: -- Executing GotoIf("SIP/101163321271823-09c225c8", "0?blacklisted") in new stack
May 2 12:31:08 DEBUG[5217] pbx.c: Not taking any branch
May 2 12:31:08 VERBOSE[5217] logger.c: -- Executing Return("SIP/101163321271823-09c225c8", "") in new stack
May 2 12:31:08 VERBOSE[5217] logger.c: -- Executing Goto("SIP/101163321271823-09c225c8", "timeconditions|1|1") in new stack
May 2 12:31:08 VERBOSE[5217] logger.c: -- Goto (timeconditions,1,1)
May 2 12:31:08 VERBOSE[5217] logger.c: -- Executing GotoIfTime("SIP/101163321271823-09c225c8", "09:00-20:30|mon-fri|*|jan-dec?app-announcement-1|s|1") in new stack
May 2 12:31:08 VERBOSE[5217] logger.c: -- Goto (app-announcement-1,s,1)
May 2 12:31:08 DEBUG[5217] pbx.c: Function result is 'NO ANSWER'
May 2 12:31:08 DEBUG[5217] pbx.c: Expression result is '0'
May 2 12:31:08 VERBOSE[5217] logger.c: -- Executing GotoIf("SIP/101163321271823-09c225c8", "0?begin") in new stack
May 2 12:31:08 DEBUG[5217] pbx.c: Not taking any branch
May 2 12:31:08 VERBOSE[5217] logger.c: -- Executing Answer("SIP/101163321271823-09c225c8", "") in new stack
May 2 12:31:08 VERBOSE[5217] logger.c: We're at 192.168.1.3 port 17354
May 2 12:31:08 VERBOSE[5217] logger.c: Adding codec 0x4 (ulaw) to SDP
May 2 12:31:08 VERBOSE[5217] logger.c: Reliably Transmitting (NAT) to 67.133.234.125:5060:
SIP/2.0 200 OK
Via: SIP/2.0/UDP 67.133.234.125:5060;branch=z9hG4bK76e53579abf17fef2e8ea580ac923ee1-0;received=67.133.234.125
Via: SIP/2.0/UDP 192.168.201.89:5070;branch=0
Via: SIP/2.0/UDP 192.168.201.102:5060;branch=z9hG4bK762935fdc36e6ae38abf52f3ae99deac.1
Via: SIP/2.0/UDP 192.168.201.102:5075;branch=z9hG4bK1248144248918357
Record-Route:
From: "ITC INC" ;tag=acd8cd0c-1dd1-11b2-a7e8-b03162323164+acdd3932
To: ;tag=as34cb22d9
Call-ID: 4830128-3943178@192.168.201.102
CSeq: 1 INVITE
User-Agent: InnoMedia SIP MTA6328-2Re v3.0.77
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Contact:
Content-Type: application/sdp
Content-Length: 156

v=0
o=root 2758 2758 IN IP4 192.168.1.3
s=session
c=IN IP4 192.168.1.3
t=0 0
m=audio 17354 RTP/AVP 0
a=rtpmap:0 PCMU/8000
a=silenceSupp:off - - - -

---
May 2 12:31:08 VERBOSE[5217] logger.c: -- Executing Wait("SIP/101163321271823-09c225c8", "1") in new stack
May 2 12:31:08 VERBOSE[734] logger.c:
<-- SIP read from 67.133.234.125:5060:
ACK sip:8182323476@192.168.1.3:5060 SIP/2.0
f:"ITC INC" ;tag=acd8cd0c-1dd1-11b2-a7e8-b03162323164+acdd3932
t:;tag=as34cb22d9
i:4830128-3943178@192.168.201.102
CSeq:1 ACK
v:SIP/2.0/UDP 67.133.234.125:5060;branch=z9hG4bK9b53c0363bdaa5f1b2e03a646c154269-0
v:SIP/2.0/UDP 192.168.201.102:5075;branch=z9hG4bK274782574382248
Max-Forwards:69
l:0


May 2 12:31:08 VERBOSE[734] logger.c: --- (9 headers 0 lines) ---
May 2 12:31:08 DEBUG[734] chan_sip.c: Stopping retransmission on '4830128-3943178@192.168.201.102' of Response 1: Match Found
May 2 12:31:09 VERBOSE[5217] logger.c: -- Executing NoOp("SIP/101163321271823-09c225c8", "Playing announcement Open Announce") in new stack
May 2 12:31:09 VERBOSE[5217] logger.c: -- Executing Playback("SIP/101163321271823-09c225c8", "custom/Open|noanswer") in new stack
May 2 12:31:09 DEBUG[5217] channel.c: Scheduling timer at 160 sample intervals
May 2 12:31:09 VERBOSE[5217] logger.c: -- Playing 'custom/Open' (language 'en')
May 2 12:31:18 DEBUG[5217] channel.c: Scheduling timer at 99 sample intervals
May 2 12:31:18 DEBUG[5217] channel.c: Scheduling timer at 0 sample intervals
May 2 12:31:18 DEBUG[5217] channel.c: Scheduling timer at 0 sample intervals
May 2 12:31:18 VERBOSE[5217] logger.c: -- Executing Goto("SIP/101163321271823-09c225c8", "ext-queues|310|1") in new stack
May 2 12:31:18 VERBOSE[5217] logger.c: -- Goto (ext-queues,310,1)
May 2 12:31:18 VERBOSE[5217] logger.c: -- Executing Answer("SIP/101163321271823-09c225c8", "") in new stack
May 2 12:31:18 VERBOSE[5217] logger.c: -- Executing Set("SIP/101163321271823-09c225c8", "__BLKVM_OVERRIDE=BLKVM/310/SIP/101163321271823-09c225c8") in new stack
May 2 12:31:18 VERBOSE[5217] logger.c: -- Executing Set("SIP/101163321271823-09c225c8", "__BLKVM_BASE=310") in new stack
May 2 12:31:18 VERBOSE[5217] logger.c: -- Executing Set("SIP/101163321271823-09c225c8", "DB(BLKVM/310/SIP/101163321271823-09c225c8)=TRUE") in new stack
May 2 12:31:18 VERBOSE[5217] logger.c: -- Executing Set("SIP/101163321271823-09c225c8", "_DIAL_OPTIONS=trM(auto-blkvm)") in new stack
May 2 12:31:18 VERBOSE[5217] logger.c: -- Executing Set("SIP/101163321271823-09c225c8", "__NODEST=310") in new stack
May 2 12:31:18 DEBUG[5217] pbx.c: Expression result is '0'
May 2 12:31:18 VERBOSE[5217] logger.c: -- Executing GotoIf("SIP/101163321271823-09c225c8", "0?USERCID:SETCID") in new stack
May 2 12:31:18 VERBOSE[5217] logger.c: -- Goto (ext-queues,310,9)
May 2 12:31:18 VERBOSE[5217] logger.c: -- Executing Set("SIP/101163321271823-09c225c8", "CALLERID(name)=ITC INC") in new stack
May 2 12:31:18 VERBOSE[5217] logger.c: -- Executing Set("SIP/101163321271823-09c225c8", "MONITOR_FILENAME=/var/spool/asterisk/monitor/q310-20070502-123118-1178134268.828") in new stack
May 2 12:31:18 VERBOSE[5217] logger.c: -- Executing Queue("SIP/101163321271823-09c225c8", "310|t||") in new stack
May 2 12:31:18 VERBOSE[5217] logger.c: -- Started music on hold, class 'default', on SIP/101163321271823-09c225c8
May 2 12:31:18 DEBUG[5217] channel.c: Scheduling timer at 160 sample intervals
May 2 12:31:18 VERBOSE[5217] logger.c: -- Called Local/2000@from-internal/n
May 2 12:31:18 VERBOSE[5217] logger.c: -- Called Local/2001@from-internal/n
May 2 12:31:18 VERBOSE[5220] logger.c: -- Executing Macro("Local/2000@from-internal-7f8c,2", "exten-vm|2000|2000") in new stack
May 2 12:31:18 VERBOSE[5220] logger.c: -- Executing Macro("Local/2000@from-internal-7f8c,2", "user-callerid") in new stack
May 2 12:31:18 DEBUG[5220] pbx.c: Function result is 'ITC INC'
May 2 12:31:18 DEBUG[5220] pbx.c: Function result is '8183550079'
May 2 12:31:18 VERBOSE[5220] logger.c: -- Executing NoOp("Local/2000@from-internal-7f8c,2", "user-callerid: ITC INC 8183550079") in new stack
May 2 12:31:18 DEBUG[5220] pbx.c: Expression result is '1'
May 2 12:31:18 VERBOSE[5220] logger.c: -- Executing GotoIf("Local/2000@from-internal-7f8c,2", "1?report") in new stack
May 2 12:31:18 VERBOSE[5220] logger.c: -- Goto (macro-user-callerid,s,11)
May 2 12:31:18 VERBOSE[5220] logger.c: -- Executing NoOp("Local/2000@from-internal-7f8c,2", "TTL: ARG1: 2000") in new stack
May 2 12:31:18 DEBUG[5220] pbx.c: Expression result is '0'
May 2 12:31:18 VERBOSE[5220] logger.c: -- Executing GotoIf("Local/2000@from-internal-7f8c,2", "0?continue") in new stack
May 2 12:31:18 DEBUG[5220] pbx.c: Not taking any branch
May 2 12:31:18 DEBUG[5220] pbx.c: Expression result is '1'
May 2 12:31:18 DEBUG[5220] pbx.c: Expression result is '-1'
May 2 12:31:18 DEBUG[5220] pbx.c: Function result is '64'
May 2 12:31:18 VERBOSE[5220] logger.c: -- Executing Set("Local/2000@from-internal-7f8c,2", "_TTL=64") in new stack
May 2 12:31:18 DEBUG[5220] pbx.c: Expression result is '1'
May 2 12:31:18 VERBOSE[5220] logger.c: -- Executing GotoIf("Local/2000@from-internal-7f8c,2", "1?continue") in new stack
May 2 12:31:18 VERBOSE[5220] logger.c: -- Goto (macro-user-callerid,s,21)
May 2 12:31:18 DEBUG[5220] pbx.c: Function result is '"ITC INC" <8183550079>'
May 2 12:31:18 VERBOSE[5220] logger.c: -- Executing NoOp("Local/2000@from-internal-7f8c,2", "Using CallerID "ITC INC" <8183550079>") in new stack
May 2 12:31:18 VERBOSE[5220] logger.c: -- Executing Set("Local/2000@from-internal-7f8c,2", "FROMCONTEXT=exten-vm") in new stack
May 2 12:31:18 VERBOSE[5220] logger.c: -- Executing Set("Local/2000@from-internal-7f8c,2", "VMBOX=2000") in new stack
May 2 12:31:18 VERBOSE[5220] logger.c: -- Executing Set("Local/2000@from-internal-7f8c,2", "EXTTOCALL=2000") in new stack
May 2 12:31:18 DEBUG[5220] db.c: Unable to find key '2000' in family 'CFU'
May 2 12:31:18 DEBUG[5220] func_db.c: DB: CFU/2000 not found in database.
May 2 12:31:18 DEBUG[5220] pbx.c: Function result is ''
May 2 12:31:18 VERBOSE[5220] logger.c: -- Executing Set("Local/2000@from-internal-7f8c,2", "CFUEXT=") in new stack
May 2 12:31:18 DEBUG[5220] pbx.c: Expression result is '1'
May 2 12:31:18 DEBUG[5220] pbx.c: Expression result is '0'
May 2 12:31:18 DEBUG[5220] pbx.c: Expression result is '1'
May 2 12:31:18 DEBUG[5220] pbx.c: Function result is '9999'
May 2 12:31:18 VERBOSE[5220] logger.c: -- Executing Set("Local/2000@from-internal-7f8c,2", "RT=9999") in new stack
May 2 12:31:18 VERBOSE[5220] logger.c: -- Executing Macro("Local/2000@from-internal-7f8c,2", "record-enable|2000|IN") in new stack
May 2 12:31:18 DEBUG[5220] pbx.c: Function result is '0'
May 2 12:31:18 VERBOSE[5220] logger.c: -- Executing GotoIf("Local/2000@from-internal-7f8c,2", "0 > 0?2:4") in new stack
May 2 12:31:18 VERBOSE[5220] logger.c: -- Goto (macro-record-enable,s,4)
May 2 12:31:18 VERBOSE[5220] logger.c: -- Executing DeadAGI("Local/2000@from-internal-7f8c,2", "recordingcheck|20070502-123118|1178134278.830") in new stack
May 2 12:31:18 VERBOSE[5220] logger.c: -- Launched AGI Script /var/lib/asterisk/agi-bin/recordingcheck
May 2 12:31:18 VERBOSE[5221] logger.c: -- Executing Macro("Local/2001@from-internal-8136,2", "exten-vm|novm|2001") in new stack
May 2 12:31:18 VERBOSE[5221] logger.c: -- Executing Macro("Local/2001@from-internal-8136,2", "user-callerid") in new stack
May 2 12:31:18 DEBUG[5221] pbx.c: Function result is 'ITC INC'
May 2 12:31:18 DEBUG[5221] pbx.c: Function result is '8183550079'
May 2 12:31:18 VERBOSE[5221] logger.c: -- Executing NoOp("Local/2001@from-internal-8136,2", "user-callerid: ITC INC 8183550079") in new stack
May 2 12:31:18 DEBUG[5221] pbx.c: Expression result is '1'
May 2 12:31:18 VERBOSE[5221] logger.c: -- Executing GotoIf("Local/2001@from-internal-8136,2", "1?report") in new stack
May 2 12:31:18 VERBOSE[5221] logger.c: -- Goto (macro-user-callerid,s,11)
May 2 12:31:18 VERBOSE[5221] logger.c: -- Executing NoOp("Local/2001@from-internal-8136,2", "TTL: ARG1: novm") in new stack
May 2 12:31:18 DEBUG[5221] pbx.c: Expression result is '0'
May 2 12:31:18 VERBOSE[5221] logger.c: -- Executing GotoIf("Local/2001@from-internal-8136,2", "0?continue") in new stack
May 2 12:31:18 DEBUG[5221] pbx.c: Not taking any branch
May 2 12:31:18 DEBUG[5221] pbx.c: Expression result is '1'
May 2 12:31:18 DEBUG[5221] pbx.c: Expression result is '-1'
May 2 12:31:18 DEBUG[5221] pbx.c: Function result is '64'
May 2 12:31:18 VERBOSE[5221] logger.c: -- Executing Set("Local/2001@from-internal-8136,2", "_TTL=64") in new stack
May 2 12:31:18 DEBUG[5221] pbx.c: Expression result is '1'
May 2 12:31:18 VERBOSE[5221] logger.c: -- Executing GotoIf("Local/2001@from-internal-8136,2", "1?continue") in new stack
May 2 12:31:18 VERBOSE[5221] logger.c: -- Goto (macro-user-callerid,s,21)
May 2 12:31:18 DEBUG[5221] pbx.c: Function result is '"ITC INC" <8183550079>'
May 2 12:31:18 VERBOSE[5221] logger.c: -- Executing NoOp("Local/2001@from-internal-8136,2", "Using CallerID "ITC INC" <8183550079>") in new stack
May 2 12:31:18 VERBOSE[5221] logger.c: -- Executing Set("Local/2001@from-internal-8136,2", "FROMCONTEXT=exten-vm") in new stack
May 2 12:31:18 VERBOSE[5221] logger.c: -- Executing Set("Local/2001@from-internal-8136,2", "VMBOX=novm") in new stack
May 2 12:31:18 VERBOSE[5221] logger.c: -- Executing Set("Local/2001@from-internal-8136,2", "EXTTOCALL=2001") in new stack
May 2 12:31:18 DEBUG[5221] db.c: Unable to find key '2001' in family 'CFU'
May 2 12:31:18 DEBUG[5221] func_db.c: DB: CFU/2001 not found in database.
May 2 12:31:18 DEBUG[5221] pbx.c: Function result is ''
May 2 12:31:18 VERBOSE[5221] logger.c: -- Executing Set("Local/2001@from-internal-8136,2", "CFUEXT=") in new stack
May 2 12:31:18 DEBUG[5221] pbx.c: Expression result is '0'
May 2 12:31:18 DEBUG[5221] pbx.c: Expression result is '0'
May 2 12:31:18 DEBUG[5221] pbx.c: Expression result is '0'
May 2 12:31:18 DEBUG[5221] pbx.c: Function result is ''
May 2 12:31:18 VERBOSE[5221] logger.c: -- Executing Set("Local/2001@from-internal-8136,2", "RT=") in new stack
May 2 12:31:18 VERBOSE[5221] logger.c: -- Executing Macro("Local/2001@from-internal-8136,2", "record-enable|2001|IN") in new stack
May 2 12:31:18 DEBUG[5221] pbx.c: Function result is '0'
May 2 12:31:18 VERBOSE[5221] logger.c: -- Executing GotoIf("Local/2001@from-internal-8136,2", "0 > 0?2:4") in new stack
May 2 12:31:18 VERBOSE[5221] logger.c: -- Goto (macro-record-enable,s,4)
May 2 12:31:18 VERBOSE[5221] logger.c: -- Executing DeadAGI("Local/2001@from-internal-8136,2", "recordingcheck|20070502-123118|1178134278.832") in new stack
May 2 12:31:18 VERBOSE[5221] logger.c: -- Launched AGI Script /var/lib/asterisk/agi-bin/recordingcheck
May 2 12:31:18 DEBUG[5217] channel.c: Generator got voice, switching to phase locked mode
May 2 12:31:18 DEBUG[5217] channel.c: Scheduling timer at 0 sample intervals
May 2 12:31:18 VERBOSE[5220] logger.c: recordingcheck|20070502-123118|1178134278.830: Inbound recording not enabled
May 2 12:31:18 VERBOSE[5220] logger.c: -- AGI Script recordingcheck completed, returning 0
May 2 12:31:18 VERBOSE[5220] logger.c: -- Executing NoOp("Local/2000@from-internal-7f8c,2", "No recording needed") in new stack
May 2 12:31:18 VERBOSE[5220] logger.c: -- Executing Macro("Local/2000@from-internal-7f8c,2", "dial|9999|trM(auto-blkvm)|2000") in new stack
May 2 12:31:18 VERBOSE[5220] logger.c: -- Executing DeadAGI("Local/2000@from-internal-7f8c,2", "dialparties.agi") in new stack
May 2 12:31:18 VERBOSE[5220] logger.c: -- Launched AGI Script /var/lib/asterisk/agi-bin/dialparties.agi
May 2 12:31:18 VERBOSE[5220] logger.c: dialparties.agi: Starting New Dialparties.agi
May 2 12:31:18 VERBOSE[5220] logger.c: dialparties.agi: priority is 1
May 2 12:31:18 VERBOSE[5220] logger.c: dialparties.agi: Caller ID name is 'ITC INC' number is '8183550079'
May 2 12:31:18 VERBOSE[5220] logger.c: dialparties.agi: Methodology of ring is 'none'
May 2 12:31:18 VERBOSE[5220] logger.c: > dialparties.agi: USE_CONFIRMATION: 'FALSE'
May 2 12:31:18 VERBOSE[5220] logger.c: > dialparties.agi: RINGGROUP_INDEX: ''
May 2 12:31:18 VERBOSE[5220] logger.c: -- dialparties.agi: Added extension 2000 to extension map
May 2 12:31:18 DEBUG[5220] db.c: Unable to find key '2000' in family 'CF'
May 2 12:31:18 VERBOSE[5220] logger.c: -- dialparties.agi: Extension 2000 cf is disabled
May 2 12:31:18 DEBUG[5220] db.c: Unable to find key '2000' in family 'DND'
May 2 12:31:18 VERBOSE[5220] logger.c: -- dialparties.agi: Extension 2000 do not disturb is disabled
May 2 12:31:18 DEBUG[5220] db.c: Unable to find key '2000' in family 'CFB'
May 2 12:31:18 DEBUG[5220] db.c: Unable to find key '2000' in family 'CFU'
May 2 12:31:18 VERBOSE[5220] logger.c: > dialparties.agi: extnum: 2000
May 2 12:31:18 VERBOSE[5220] logger.c: > dialparties.agi: exthascw: 1
May 2 12:31:18 VERBOSE[5220] logger.c: > dialparties.agi: exthascfb: 0
May 2 12:31:18 VERBOSE[5220] logger.c: > dialparties.agi: extcfb:
May 2 12:31:18 VERBOSE[5220] logger.c: > dialparties.agi: exthascfu: 0
May 2 12:31:18 VERBOSE[5220] logger.c: > dialparties.agi: extcfu:
May 2 12:31:18 VERBOSE[5220] logger.c: -- dialparties.agi: dbset CALLTRACE/2000 to 8183550079
May 2 12:31:18 VERBOSE[5220] logger.c: > dialparties.agi: NODEST: 310 blkvm enabled macro already in dialopts: trM(auto-blkvm)
May 2 12:31:18 VERBOSE[5220] logger.c: -- AGI Script dialparties.agi completed, returning 0
May 2 12:31:18 VERBOSE[5220] logger.c: -- Executing Dial("Local/2000@from-internal-7f8c,2", "SIP/2000|9999|trM(auto-blkvm)") in new stack
May 2 12:31:18 DEBUG[5220] chan_sip.c: Setting NAT on RTP to 524288
May 2 12:31:18 DEBUG[5220] chan_sip.c: Outgoing Call for 2000
May 2 12:31:18 VERBOSE[5220] logger.c: We're at 192.168.1.3 port 17076
May 2 12:31:18 VERBOSE[5220] logger.c: Adding codec 0x4 (ulaw) to SDP
May 2 12:31:18 VERBOSE[5220] logger.c: Adding codec 0x8 (alaw) to SDP
May 2 12:31:18 VERBOSE[5220] logger.c: Adding non-codec 0x1 (telephone-event) to SDP
May 2 12:31:18 VERBOSE[5220] logger.c: 13 headers, 11 lines
May 2 12:31:18 VERBOSE[5220] logger.c: Reliably Transmitting (NAT) to 192.168.1.143:5061:
INVITE sip:2000@192.168.1.1:5061;transport=udp SIP/2.0
Via: SIP/2.0/UDP 192.168.1.3:5060;branch=z9hG4bK3be49b3b;rport
From: "ITC INC" ;tag=as7434617a
To:
Contact:
Call-ID: 0bee95312e15f49738bcf5a21c310553@192.168.1.3
CSeq: 102 INVITE
User-Agent: InnoMedia SIP MTA6328-2Re v3.0.77
Max-Forwards: 70
Date: Wed, 02 May 2007 19:31:18 GMT
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Content-Type: application/sdp
Content-Length: 236

v=0
o=root 2758 2758 IN IP4 192.168.1.3
s=session
c=IN IP4 192.168.1.3
t=0 0
m=audio 17076 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 - - - -

---
May 2 12:31:18 VERBOSE[5220] logger.c: -- Called 2000
May 2 12:31:18 VERBOSE[5217] logger.c: -- Local/2000@from-internal-7f8c,1 is ringing
May 2 12:31:18 VERBOSE[5221] logger.c: recordingcheck|20070502-123118|1178134278.832: Inbound recording not enabled
May 2 12:31:18 VERBOSE[5221] logger.c: -- AGI Script recordingcheck completed, returning 0
May 2 12:31:18 VERBOSE[5221] logger.c: -- Executing NoOp("Local/2001@from-internal-8136,2", "No recording needed") in new stack
May 2 12:31:18 VERBOSE[5221] logger.c: -- Executing Macro("Local/2001@from-internal-8136,2", "dial||trM(auto-blkvm)|2001") in new stack
May 2 12:31:18 VERBOSE[5221] logger.c: -- Executing DeadAGI("Local/2001@from-internal-8136,2", "dialparties.agi") in new stack
May 2 12:31:18 VERBOSE[5221] logger.c: -- Launched AGI Script /var/lib/asterisk/agi-bin/dialparties.agi
May 2 12:31:18 VERBOSE[734] logger.c:
<-- SIP read from 192.168.1.143:5061:
SIP/2.0 100 Trying
Via: SIP/2.0/UDP 192.168.1.3:5060;branch=z9hG4bK3be49b3b;rport
From: "ITC INC" ;tag=as7434617a
To:
Call-ID: 0bee95312e15f49738bcf5a21c310553@192.168.1.3
Date: Wed, 02 May 2007 19:31:19 GMT
CSeq: 102 INVITE
Server: Cisco-CP7940G/8.0
Contact:
Allow: ACK,BYE,CANCEL,INVITE,NOTIFY,OPTIONS,REFER,REGISTER,UPDATE
Remote-Party-ID: "2000" ;party=called;id-type=subscriber;privacy=off;screen=yes
Content-Length: 0


May 2 12:31:18 VERBOSE[734] logger.c: --- (12 headers 0 lines) ---
May 2 12:31:18 DEBUG[734] chan_sip.c: (Provisional) Stopping retransmission (but retaining packet) on '0bee95312e15f49738bcf5a21c310553@192.168.1.3' Request 102: Found
May 2 12:31:18 VERBOSE[5221] logger.c: dialparties.agi: Starting New Dialparties.agi
May 2 12:31:18 VERBOSE[5221] logger.c: dialparties.agi: priority is 1
May 2 12:31:18 VERBOSE[5221] logger.c: dialparties.agi: Caller ID name is 'ITC INC' number is '8183550079'
May 2 12:31:18 VERBOSE[5221] logger.c: dialparties.agi: Methodology of ring is 'none'
May 2 12:31:18 VERBOSE[5221] logger.c: > dialparties.agi: USE_CONFIRMATION: 'FALSE'
May 2 12:31:18 VERBOSE[5221] logger.c: > dialparties.agi: RINGGROUP_INDEX: ''
May 2 12:31:18 VERBOSE[5221] logger.c: -- dialparties.agi: Added extension 2001 to extension map
May 2 12:31:18 DEBUG[5221] db.c: Unable to find key '2001' in family 'CF'
May 2 12:31:18 VERBOSE[5221] logger.c: -- dialparties.agi: Extension 2001 cf is disabled
May 2 12:31:18 DEBUG[5221] db.c: Unable to find key '2001' in family 'DND'
May 2 12:31:18 VERBOSE[5221] logger.c: -- dialparties.agi: Extension 2001 do not disturb is disabled
May 2 12:31:18 DEBUG[5221] db.c: Unable to find key '2001' in family 'CFB'
May 2 12:31:18 DEBUG[5221] db.c: Unable to find key '2001' in family 'CFU'
May 2 12:31:18 VERBOSE[5221] logger.c: > dialparties.agi: extnum: 2001
May 2 12:31:18 VERBOSE[5221] logger.c: > dialparties.agi: exthascw: 1
May 2 12:31:18 VERBOSE[5221] logger.c: > dialparties.agi: exthascfb: 0
May 2 12:31:18 VERBOSE[5221] logger.c: > dialparties.agi: extcfb:
May 2 12:31:18 VERBOSE[5221] logger.c: > dialparties.agi: exthascfu: 0
May 2 12:31:18 VERBOSE[5221] logger.c: > dialparties.agi: extcfu:
May 2 12:31:18 VERBOSE[5221] logger.c: -- dialparties.agi: dbset CALLTRACE/2001 to 8183550079
May 2 12:31:19 VERBOSE[5221] logger.c: > dialparties.agi: NODEST: 310 blkvm enabled macro already in dialopts: trM(auto-blkvm)
May 2 12:31:19 VERBOSE[5221] logger.c: -- AGI Script dialparties.agi completed, returning 0
May 2 12:31:19 VERBOSE[5221] logger.c: -- Executing Dial("Local/2001@from-internal-8136,2", "SIP/2001||trM(auto-blkvm)") in new stack
May 2 12:31:19 DEBUG[5221] chan_sip.c: Setting NAT on RTP to 524288
May 2 12:31:19 DEBUG[5221] chan_sip.c: Outgoing Call for 2001
May 2 12:31:19 VERBOSE[5221] logger.c: We're at 192.168.1.3 port 19714
May 2 12:31:19 VERBOSE[5221] logger.c: Adding codec 0x4 (ulaw) to SDP
May 2 12:31:19 VERBOSE[5221] logger.c: Adding codec 0x8 (alaw) to SDP
May 2 12:31:19 VERBOSE[5221] logger.c: Adding non-codec 0x1 (telephone-event) to SDP
May 2 12:31:19 VERBOSE[5221] logger.c: 13 headers, 11 lines
May 2 12:31:19 VERBOSE[5221] logger.c: Reliably Transmitting (NAT) to 192.168.1.254:5061:
INVITE sip:2001@192.168.1.1:5061;transport=udp SIP/2.0
Via: SIP/2.0/UDP 192.168.1.3:5060;branch=z9hG4bK62c0859a;rport
From: "ITC INC" ;tag=as3842a626
To:
Contact:
Call-ID: 07078c055d3b82e72beb4c14117771d8@192.168.1.3
CSeq: 102 INVITE
User-Agent: InnoMedia SIP MTA6328-2Re v3.0.77
Max-Forwards: 70
Date: Wed, 02 May 2007 19:31:19 GMT
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Content-Type: application/sdp
Content-Length: 236

v=0
o=root 2758 2758 IN IP4 192.168.1.3
s=session
c=IN IP4 192.168.1.3
t=0 0
m=audio 19714 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 - - - -

---
May 2 12:31:19 VERBOSE[5221] logger.c: -- Called 2001
May 2 12:31:19 VERBOSE[5217] logger.c: -- Local/2001@from-internal-8136,1 is ringing
May 2 12:31:19 VERBOSE[734] logger.c:
<-- SIP read from 192.168.1.143:5061:
SIP/2.0 180 Ringing
Via: SIP/2.0/UDP 192.168.1.3:5060;branch=z9hG4bK3be49b3b;rport
From: "ITC INC" ;tag=as7434617a
To: ;tag=00141c2247581da828547a70-6f78b1a3
Call-ID: 0bee95312e15f49738bcf5a21c310553@192.168.1.3
Date: Wed, 02 May 2007 19:31:19 GMT
CSeq: 102 INVITE
Server: Cisco-CP7940G/8.0
Contact:
Allow: ACK,BYE,CANCEL,INVITE,NOTIFY,OPTIONS,REFER,REGISTER,UPDATE
Remote-Party-ID: "2000" ;party=called;id-type=subscriber;privacy=off;screen=yes
Content-Length: 0


May 2 12:31:19 VERBOSE[734] logger.c: --- (12 headers 0 lines) ---
May 2 12:31:19 DEBUG[734] chan_sip.c: (Provisional) Stopping retransmission (but retaining packet) on '0bee95312e15f49738bcf5a21c310553@192.168.1.3' Request 102: Found
May 2 12:31:19 VERBOSE[5220] logger.c: -- SIP/2000-09bf5750 is ringing
May 2 12:31:19 VERBOSE[734] logger.c: Retransmitting #1 (NAT) to 192.168.1.254:5061:
INVITE sip:2001@192.168.1.1:5061;transport=udp SIP/2.0
Via: SIP/2.0/UDP 192.168.1.3:5060;branch=z9hG4bK62c0859a;rport
From: "ITC INC" ;tag=as3842a626
To:
Contact:
Call-ID: 07078c055d3b82e72beb4c14117771d8@192.168.1.3
CSeq: 102 INVITE
User-Agent: InnoMedia SIP MTA6328-2Re v3.0.77
Max-Forwards: 70
Date: Wed, 02 May 2007 19:31:19 GMT
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Content-Type: application/sdp
Content-Length: 236

v=0
o=root 2758 2758 IN IP4 192.168.1.3
s=session
c=IN IP4 192.168.1.3
t=0 0
m=audio 19714 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 - - - -

---
May 2 12:31:19 VERBOSE[734] logger.c:
<-- SIP read from 192.168.1.254:5061:
SIP/2.0 100 Trying
Via: SIP/2.0/UDP 192.168.1.3:5060;branch=z9hG4bK62c0859a;rport
From: "ITC INC" ;tag=as3842a626
To:
Call-ID: 07078c055d3b82e72beb4c14117771d8@192.168.1.3
Date: Wed, 02 May 2007 19:31:19 GMT
CSeq: 102 INVITE
Server: Cisco-CP7940G/8.0
Contact:
Allow: ACK,BYE,CANCEL,INVITE,NOTIFY,OPTIONS,REFER,REGISTER,UPDATE
Remote-Party-ID: "2001" ;party=called;id-type=subscriber;privacy=off;screen=yes
Content-Length: 0


May 2 12:31:19 VERBOSE[734] logger.c: --- (12 headers 0 lines) ---
May 2 12:31:19 DEBUG[734] chan_sip.c: (Provisional) Stopping retransmission (but retaining packet) on '07078c055d3b82e72beb4c14117771d8@192.168.1.3' Request 102: Found
May 2 12:31:19 VERBOSE[734] logger.c:
<-- SIP read from 192.168.1.254:5061:
SIP/2.0 180 Ringing
Via: SIP/2.0/UDP 192.168.1.3:5060;branch=z9hG4bK62c0859a;rport
From: "ITC INC" ;tag=as3842a626
To: ;tag=00141c22475e1c193c9aedc6-5e54f4cb
Call-ID: 07078c055d3b82e72beb4c14117771d8@192.168.1.3
Date: Wed, 02 May 2007 19:31:19 GMT
CSeq: 102 INVITE
Server: Cisco-CP7940G/8.0
Contact:
Allow: ACK,BYE,CANCEL,INVITE,NOTIFY,OPTIONS,REFER,REGISTER,UPDATE
Remote-Party-ID: "2001" ;party=called;id-type=subscriber;privacy=off;screen=yes
Content-Length: 0


May 2 12:31:19 VERBOSE[734] logger.c: --- (12 headers 0 lines) ---
May 2 12:31:19 DEBUG[734] chan_sip.c: (Provisional) Stopping retransmission (but retaining packet) on '07078c055d3b82e72beb4c14117771d8@192.168.1.3' Request 102: Found
May 2 12:31:19 VERBOSE[5221] logger.c: -- SIP/2001-09bfac90 is ringing
May 2 12:31:19 VERBOSE[734] logger.c:
<-- SIP read from 192.168.1.254:5061:
SIP/2.0 180 Ringing
Via: SIP/2.0/UDP 192.168.1.3:5060;branch=z9hG4bK62c0859a;rport
From: "ITC INC" ;tag=as3842a626
To: ;tag=00141c22475e1c193c9aedc6-5e54f4cb
Call-ID: 07078c055d3b82e72beb4c14117771d8@192.168.1.3
Date: Wed, 02 May 2007 19:31:19 GMT
CSeq: 102 INVITE
Server: Cisco-CP7940G/8.0
Contact:
Allow: ACK,BYE,CANCEL,INVITE,NOTIFY,OPTIONS,REFER,REGISTER,UPDATE
Remote-Party-ID: "2001" ;party=called;id-type=subscriber;privacy=off;screen=yes
Content-Length: 0


May 2 12:31:19 VERBOSE[734] logger.c: --- (12 headers 0 lines) ---
May 2 12:31:19 DEBUG[734] chan_sip.c: (Provisional) Stopping retransmission (but retaining packet) on '07078c055d3b82e72beb4c14117771d8@192.168.1.3' Request 102: Found
May 2 12:31:19 VERBOSE[5221] logger.c: -- SIP/2001-09bfac90 is ringing
May 2 12:31:19 VERBOSE[5221] logger.c: -- SIP/2001-09bfac90 is ringing