All pastes #2129725 Raw Edit

Unnamed

public text v1 · immutable
#2129725 ·published 2012-03-18 21:47 UTC
rendered paste body
recv 1113 bytes from udp/[64.134.240.14]:34840 at 21:45:12.084783:
   ------------------------------------------------------------------------
   INVITE sip:9888@freeswitch.voipqso.com SIP/2.0
   Via: SIP/2.0/UDP 64.134.240.14:34840;rport;branch=z9hG4bKPjnNF9KX3qB68PhdxxqINUIjbioXmjn2UG
   Max-Forwards: 70
   From: <sip:2917@freeswitch.voipqso.com>;tag=hKTF4C4GSps4GCdB0uTQJ3hwVZeovlgH
   To: <sip:9888@freeswitch.voipqso.com>
   Contact: <sip:2917@64.134.240.14:34840;transport=UDP;ob>
   Call-ID: y385v8QmU7H9PwMQ2OzGiaJ82zQIiiCJ
   CSeq: 31861 INVITE
   Route: <sip:freeswitch.voipqso.com;transport=udp;lr>
   Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS
   Supported: replaces, 100rel, timer, norefersub
   Session-Expires: 1800
   Min-SE: 90
   User-Agent: CSipSimple r1108 / picasso-13
   Content-Type: application/sdp
   Content-Length:   388
   
   v=0
   o=- 3541095914 3541095914 IN IP4 192.168.5.37
   s=pjmedia
   c=IN IP4 192.168.5.37
   t=0 0
   a=X-nat:0
   m=audio 4000 RTP/AVP 9 107 106 105 0 8 101
   a=rtcp:4001 IN IP4 192.168.5.37
   a=rtpmap:9 G722/8000
   a=rtpmap:107 speex/32000
   a=rtpmap:106 speex/16000
   a=rtpmap:105 speex/8000
   a=rtpmap:0 PCMU/8000
   a=rtpmap:8 PCMA/8000
   a=sendrecv
   a=rtpmap:101 telephone-event/8000
   a=fmtp:101 0-15
   ------------------------------------------------------------------------
send 355 bytes to udp/[64.134.240.14]:34840 at 21:45:12.085144:
   ------------------------------------------------------------------------
   SIP/2.0 100 Trying
   Via: SIP/2.0/UDP 64.134.240.14:34840;rport=34840;branch=z9hG4bKPjnNF9KX3qB68PhdxxqINUIjbioXmjn2UG
   From: <sip:2917@freeswitch.voipqso.com>;tag=hKTF4C4GSps4GCdB0uTQJ3hwVZeovlgH
   To: <sip:9888@freeswitch.voipqso.com>
   Call-ID: y385v8QmU7H9PwMQ2OzGiaJ82zQIiiCJ
   CSeq: 31861 INVITE
   User-Agent: Configured by 2600hz!
   Content-Length: 0
   
   ------------------------------------------------------------------------
send 845 bytes to udp/[64.134.240.14]:34840 at 21:45:12.085774:
   ------------------------------------------------------------------------
   SIP/2.0 407 Proxy Authentication Required
   Via: SIP/2.0/UDP 64.134.240.14:34840;rport=34840;branch=z9hG4bKPjnNF9KX3qB68PhdxxqINUIjbioXmjn2UG
   From: <sip:2917@freeswitch.voipqso.com>;tag=hKTF4C4GSps4GCdB0uTQJ3hwVZeovlgH
   To: <sip:9888@freeswitch.voipqso.com>;tag=5c5vej2t9t0Ug
   Call-ID: y385v8QmU7H9PwMQ2OzGiaJ82zQIiiCJ
   CSeq: 31861 INVITE
   User-Agent: Configured by 2600hz!
   Accept: application/sdp
   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, UPDATE, INFO, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
   Supported: precondition, path, replaces
   Allow-Events: talk, hold, presence, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
   Proxy-Authenticate: Digest realm="freeswitch.voipqso.com", nonce="a3b0ebe6-7143-11e1-919c-698b09f7d618", algorithm=MD5, qop="auth"
   Content-Length: 0
   
   ------------------------------------------------------------------------
recv 427 bytes from udp/[64.134.240.14]:34840 at 21:45:12.193593:
   ------------------------------------------------------------------------
   ACK sip:9888@freeswitch.voipqso.com SIP/2.0
   Via: SIP/2.0/UDP 64.134.240.14:34840;rport;branch=z9hG4bKPjnNF9KX3qB68PhdxxqINUIjbioXmjn2UG
   Max-Forwards: 70
   From: <sip:2917@freeswitch.voipqso.com>;tag=hKTF4C4GSps4GCdB0uTQJ3hwVZeovlgH
   To: <sip:9888@freeswitch.voipqso.com>;tag=5c5vej2t9t0Ug
   Call-ID: y385v8QmU7H9PwMQ2OzGiaJ82zQIiiCJ
   CSeq: 31861 ACK
   Route: <sip:freeswitch.voipqso.com;transport=udp;lr>
   Content-Length:  0
   
   ------------------------------------------------------------------------
recv 1401 bytes from udp/[64.134.240.14]:34840 at 21:45:12.208814:
   ------------------------------------------------------------------------
   INVITE sip:9888@freeswitch.voipqso.com SIP/2.0
   Via: SIP/2.0/UDP 64.134.240.14:34840;rport;branch=z9hG4bKPj2.WJ3I2P1CXs7eKf0J0WIbByUAHVfIOA
   Max-Forwards: 70
   From: <sip:2917@freeswitch.voipqso.com>;tag=hKTF4C4GSps4GCdB0uTQJ3hwVZeovlgH
   To: <sip:9888@freeswitch.voipqso.com>
   Contact: <sip:2917@64.134.240.14:34840;transport=UDP;ob>
   Call-ID: y385v8QmU7H9PwMQ2OzGiaJ82zQIiiCJ
   CSeq: 31862 INVITE
   Route: <sip:freeswitch.voipqso.com;transport=udp;lr>
   Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS
   Supported: replaces, 100rel, timer, norefersub
   Session-Expires: 1800
   Min-SE: 90
   User-Agent: CSipSimple r1108 / picasso-13
   Proxy-Authorization: Digest username="2917", realm="freeswitch.voipqso.com", nonce="a3b0ebe6-7143-11e1-919c-698b09f7d618", uri="sip:9888@freeswitch.voipqso.com", response="4c8a8cae6c3cd93e6ec6a6223160f8aa", algorithm=MD5, cnonce="MjQEDzCSHdSWGqwmRSCKUM9iCvqTeGft", qop=auth, nc=00000001
   Content-Type: application/sdp
   Content-Length:   388
   
   v=0
   o=- 3541095914 3541095914 IN IP4 192.168.5.37
   s=pjmedia
   c=IN IP4 192.168.5.37
   t=0 0
   a=X-nat:0
   m=audio 4000 RTP/AVP 9 107 106 105 0 8 101
   a=rtcp:4001 IN IP4 192.168.5.37
   a=rtpmap:9 G722/8000
   a=rtpmap:107 speex/32000
   a=rtpmap:106 speex/16000
   a=rtpmap:105 speex/8000
   a=rtpmap:0 PCMU/8000
   a=rtpmap:8 PCMA/8000
   a=sendrecv
   a=rtpmap:101 telephone-event/8000
   a=fmtp:101 0-15
   ------------------------------------------------------------------------
send 355 bytes to udp/[64.134.240.14]:34840 at 21:45:12.209179:
   ------------------------------------------------------------------------
   SIP/2.0 100 Trying
   Via: SIP/2.0/UDP 64.134.240.14:34840;rport=34840;branch=z9hG4bKPj2.WJ3I2P1CXs7eKf0J0WIbByUAHVfIOA
   From: <sip:2917@freeswitch.voipqso.com>;tag=hKTF4C4GSps4GCdB0uTQJ3hwVZeovlgH
   To: <sip:9888@freeswitch.voipqso.com>
   Call-ID: y385v8QmU7H9PwMQ2OzGiaJ82zQIiiCJ
   CSeq: 31862 INVITE
   User-Agent: Configured by 2600hz!
   Content-Length: 0
   
   ------------------------------------------------------------------------
2012-03-19 01:45:12.197757 [NOTICE] switch_channel.c:926 New Channel sofia/sipinterface_1/2917@freeswitch.voipqso.com [a3c3fba0-7143-11e1-919d-698b09f7d618]
2012-03-19 01:45:12.197757 [DEBUG] sofia.c:5532 Channel sofia/sipinterface_1/2917@freeswitch.voipqso.com entering state [received][100]
2012-03-19 01:45:12.197757 [DEBUG] sofia.c:5543 Remote SDP:
v=0
o=- 3541095914 3541095914 IN IP4 192.168.5.37
s=pjmedia
c=IN IP4 192.168.5.37
t=0 0
a=X-nat:0
m=audio 4000 RTP/AVP 9 107 106 105 0 8 101
a=rtpmap:9 G722/8000
a=rtpmap:107 speex/32000
a=rtpmap:106 speex/16000
a=rtpmap:105 speex/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=rtcp:4001 IN IP4 192.168.5.37

2012-03-19 01:45:12.197757 [DEBUG] sofia_glue.c:4879 Audio Codec Compare [G722:9:8000:20:64000]/[G7221:115:32000:20:48000]
2012-03-19 01:45:12.197757 [DEBUG] sofia_glue.c:4879 Audio Codec Compare [G722:9:8000:20:64000]/[G7221:107:16000:20:32000]
2012-03-19 01:45:12.197757 [DEBUG] sofia_glue.c:4879 Audio Codec Compare [G722:9:8000:20:64000]/[G722:9:8000:20:64000]
2012-03-19 01:45:12.197757 [DEBUG] sofia_glue.c:2996 Set Codec sofia/sipinterface_1/2917@freeswitch.voipqso.com G722/8000 20 ms 160 samples 64000 bits
2012-03-19 01:45:12.197757 [DEBUG] switch_core_codec.c:111 sofia/sipinterface_1/2917@freeswitch.voipqso.com Original read codec set to G722:9
2012-03-19 01:45:12.197757 [DEBUG] sofia_glue.c:5000 Set 2833 dtmf send/recv payload to 101
2012-03-19 01:45:12.197757 [DEBUG] sofia.c:5757 (sofia/sipinterface_1/2917@freeswitch.voipqso.com) State Change CS_NEW -> CS_INIT
2012-03-19 01:45:12.197757 [DEBUG] switch_core_session.c:1182 Send signal sofia/sipinterface_1/2917@freeswitch.voipqso.com [BREAK]
2012-03-19 01:45:12.197757 [DEBUG] switch_core_state_machine.c:362 (sofia/sipinterface_1/2917@freeswitch.voipqso.com) Running State Change CS_INIT
2012-03-19 01:45:12.197757 [DEBUG] switch_core_state_machine.c:401 (sofia/sipinterface_1/2917@freeswitch.voipqso.com) State INIT
2012-03-19 01:45:12.197757 [DEBUG] mod_sofia.c:85 sofia/sipinterface_1/2917@freeswitch.voipqso.com SOFIA INIT
2012-03-19 01:45:12.197757 [DEBUG] mod_sofia.c:125 (sofia/sipinterface_1/2917@freeswitch.voipqso.com) State Change CS_INIT -> CS_ROUTING
2012-03-19 01:45:12.197757 [DEBUG] switch_core_session.c:1182 Send signal sofia/sipinterface_1/2917@freeswitch.voipqso.com [BREAK]
2012-03-19 01:45:12.197757 [DEBUG] switch_core_state_machine.c:401 (sofia/sipinterface_1/2917@freeswitch.voipqso.com) State INIT going to sleep
2012-03-19 01:45:12.197757 [DEBUG] switch_core_state_machine.c:362 (sofia/sipinterface_1/2917@freeswitch.voipqso.com) Running State Change CS_ROUTING
2012-03-19 01:45:12.197757 [CONSOLE] sofia_presence.c:1357 Event Thread Started
2012-03-19 01:45:12.197757 [DEBUG] switch_channel.c:1886 (sofia/sipinterface_1/2917@freeswitch.voipqso.com) Callstate Change DOWN -> RINGING
2012-03-19 01:45:12.197757 [DEBUG] switch_core_state_machine.c:410 (sofia/sipinterface_1/2917@freeswitch.voipqso.com) State ROUTING
2012-03-19 01:45:12.197757 [DEBUG] mod_sofia.c:148 sofia/sipinterface_1/2917@freeswitch.voipqso.com SOFIA ROUTING
2012-03-19 01:45:12.197757 [DEBUG] switch_core_state_machine.c:104 sofia/sipinterface_1/2917@freeswitch.voipqso.com Standard ROUTING
2012-03-19 01:45:12.197757 [INFO] mod_dialplan_xml.c:485 Processing 2917 <2917>->9888 in context context_1
Dialplan: sofia/sipinterface_1/2917@freeswitch.voipqso.com parsing [context_1->conditioning_callerid] continue=true
Dialplan: sofia/sipinterface_1/2917@freeswitch.voipqso.com Regex (PASS) [conditioning_callerid] ${internal_caller_id_number}(2917) =~ /^.+$/ break=on-false
Dialplan: sofia/sipinterface_1/2917@freeswitch.voipqso.com Action set(effective_caller_id_name=${internal_caller_id_name}) 
Dialplan: sofia/sipinterface_1/2917@freeswitch.voipqso.com Action set(effective_caller_id_number=${internal_caller_id_number}) 
Dialplan: sofia/sipinterface_1/2917@freeswitch.voipqso.com parsing [context_1->postroute_global] continue=true
Dialplan: sofia/sipinterface_1/2917@freeswitch.voipqso.com Absolute Condition [postroute_global]
Dialplan: sofia/sipinterface_1/2917@freeswitch.voipqso.com Action hash(insert/${domain_name}-spymap/${caller_id_number}/${uuid}) 
Dialplan: sofia/sipinterface_1/2917@freeswitch.voipqso.com Action hash(insert/${domain_name}-last_dial/${caller_id_number}/${destination_number}) 
Dialplan: sofia/sipinterface_1/2917@freeswitch.voipqso.com Action hash(insert/${domain_name}-last_dial/global/${uuid}) 
Dialplan: sofia/sipinterface_1/2917@freeswitch.voipqso.com Action set(RFC2822_DATE=${strftime(%a, %d %b %Y %T %z)}) 
Dialplan: sofia/sipinterface_1/2917@freeswitch.voipqso.com parsing [context_1->main_number_8] continue=true
Dialplan: sofia/sipinterface_1/2917@freeswitch.voipqso.com Regex (FAIL) [main_number_8] destination_number(9888) =~ /^\*9001$/ break=on-false
Dialplan: sofia/sipinterface_1/2917@freeswitch.voipqso.com parsing [context_1->main_number_9] continue=true
Dialplan: sofia/sipinterface_1/2917@freeswitch.voipqso.com Regex (PASS) [main_number_9] destination_number(9888) =~ /^9888$/ break=on-false
Dialplan: sofia/sipinterface_1/2917@freeswitch.voipqso.com Action set(call_timeout=30) 
Dialplan: sofia/sipinterface_1/2917@freeswitch.voipqso.com Action set(ringback=${us-ring}) 
Dialplan: sofia/sipinterface_1/2917@freeswitch.voipqso.com Action set(transfer_ringback=${us-ring}) 
Dialplan: sofia/sipinterface_1/2917@freeswitch.voipqso.com Action export(sip_callee_id_name=9888) 
Dialplan: sofia/sipinterface_1/2917@freeswitch.voipqso.com Action export(sip_callee_id_number=9888) 
Dialplan: sofia/sipinterface_1/2917@freeswitch.voipqso.com Action bridge({group_confirm_file=ivr/ivr-accept_reject_voicemail.wav,group_confirm_key=1,leg_timeout=}sofia/sipinterface_3/888@voipqso.com:5060) 
Dialplan: sofia/sipinterface_1/2917@freeswitch.voipqso.com Action hangup() 
Dialplan: sofia/sipinterface_1/2917@freeswitch.voipqso.com parsing [context_1->main_number_10] continue=true
Dialplan: sofia/sipinterface_1/2917@freeswitch.voipqso.com Regex (FAIL) [main_number_10] destination_number(9888) =~ /^2917$/ break=on-false
2012-03-19 01:45:12.197757 [DEBUG] switch_core_state_machine.c:154 (sofia/sipinterface_1/2917@freeswitch.voipqso.com) State Change CS_ROUTING -> CS_EXECUTE
2012-03-19 01:45:12.197757 [DEBUG] switch_core_session.c:1182 Send signal sofia/sipinterface_1/2917@freeswitch.voipqso.com [BREAK]
2012-03-19 01:45:12.197757 [DEBUG] switch_core_state_machine.c:410 (sofia/sipinterface_1/2917@freeswitch.voipqso.com) State ROUTING going to sleep
2012-03-19 01:45:12.197757 [DEBUG] switch_core_state_machine.c:362 (sofia/sipinterface_1/2917@freeswitch.voipqso.com) Running State Change CS_EXECUTE
2012-03-19 01:45:12.197757 [DEBUG] switch_core_state_machine.c:417 (sofia/sipinterface_1/2917@freeswitch.voipqso.com) State EXECUTE
2012-03-19 01:45:12.197757 [DEBUG] mod_sofia.c:241 sofia/sipinterface_1/2917@freeswitch.voipqso.com SOFIA EXECUTE
2012-03-19 01:45:12.197757 [DEBUG] switch_core_state_machine.c:192 sofia/sipinterface_1/2917@freeswitch.voipqso.com Standard EXECUTE
EXECUTE sofia/sipinterface_1/2917@freeswitch.voipqso.com set(effective_caller_id_name=n3glv csip)
2012-03-19 01:45:12.217750 [DEBUG] mod_dptools.c:1281 sofia/sipinterface_1/2917@freeswitch.voipqso.com SET [effective_caller_id_name]=[n3glv csip]
EXECUTE sofia/sipinterface_1/2917@freeswitch.voipqso.com set(effective_caller_id_number=2917)
2012-03-19 01:45:12.217750 [DEBUG] mod_dptools.c:1281 sofia/sipinterface_1/2917@freeswitch.voipqso.com SET [effective_caller_id_number]=[2917]
EXECUTE sofia/sipinterface_1/2917@freeswitch.voipqso.com hash(insert/freeswitch.voipqso.com-spymap/2917/a3c3fba0-7143-11e1-919d-698b09f7d618)
EXECUTE sofia/sipinterface_1/2917@freeswitch.voipqso.com hash(insert/freeswitch.voipqso.com-last_dial/2917/9888)
EXECUTE sofia/sipinterface_1/2917@freeswitch.voipqso.com hash(insert/freeswitch.voipqso.com-last_dial/global/a3c3fba0-7143-11e1-919d-698b09f7d618)
EXECUTE sofia/sipinterface_1/2917@freeswitch.voipqso.com set(RFC2822_DATE=Mon, 19 Mar 2012 01:45:12 +0400)
2012-03-19 01:45:12.217750 [DEBUG] mod_dptools.c:1281 sofia/sipinterface_1/2917@freeswitch.voipqso.com SET [RFC2822_DATE]=[Mon, 19 Mar 2012 01:45:12 +0400]
EXECUTE sofia/sipinterface_1/2917@freeswitch.voipqso.com set(call_timeout=30)
2012-03-19 01:45:12.217750 [DEBUG] mod_dptools.c:1281 sofia/sipinterface_1/2917@freeswitch.voipqso.com SET [call_timeout]=[30]
EXECUTE sofia/sipinterface_1/2917@freeswitch.voipqso.com set(ringback=%(2000,4000,440,480))
2012-03-19 01:45:12.217750 [DEBUG] mod_dptools.c:1281 sofia/sipinterface_1/2917@freeswitch.voipqso.com SET [ringback]=[%(2000,4000,440,480)]
EXECUTE sofia/sipinterface_1/2917@freeswitch.voipqso.com set(transfer_ringback=%(2000,4000,440,480))
2012-03-19 01:45:12.217750 [DEBUG] mod_dptools.c:1281 sofia/sipinterface_1/2917@freeswitch.voipqso.com SET [transfer_ringback]=[%(2000,4000,440,480)]
EXECUTE sofia/sipinterface_1/2917@freeswitch.voipqso.com export(sip_callee_id_name=9888)
2012-03-19 01:45:12.217750 [DEBUG] switch_channel.c:1093 EXPORT (export_vars) [sip_callee_id_name]=[9888]
EXECUTE sofia/sipinterface_1/2917@freeswitch.voipqso.com export(sip_callee_id_number=9888)
2012-03-19 01:45:12.217750 [DEBUG] switch_channel.c:1093 EXPORT (export_vars) [sip_callee_id_number]=[9888]
EXECUTE sofia/sipinterface_1/2917@freeswitch.voipqso.com bridge({group_confirm_file=ivr/ivr-accept_reject_voicemail.wav,group_confirm_key=1,leg_timeout=}sofia/sipinterface_3/888@voipqso.com:5060)
2012-03-19 01:45:12.217750 [DEBUG] switch_channel.c:1047 sofia/sipinterface_1/2917@freeswitch.voipqso.com EXPORTING[export_vars] [sip_callee_id_name]=[9888] to event
2012-03-19 01:45:12.217750 [DEBUG] switch_channel.c:1047 sofia/sipinterface_1/2917@freeswitch.voipqso.com EXPORTING[export_vars] [sip_callee_id_number]=[9888] to event
2012-03-19 01:45:12.217750 [DEBUG] switch_ivr_originate.c:1884 Parsing global variables
2012-03-19 01:45:12.217750 [DEBUG] switch_event.c:1522 Parsing variable [group_confirm_file]=[ivr/ivr-accept_reject_voicemail.wav]
2012-03-19 01:45:12.217750 [DEBUG] switch_event.c:1522 Parsing variable [group_confirm_key]=[1]
2012-03-19 01:45:12.217750 [NOTICE] switch_channel.c:926 New Channel sofia/sipinterface_3/888@voipqso.com:5060 [a3c5e87a-7143-11e1-91a2-698b09f7d618]
2012-03-19 01:45:12.217750 [DEBUG] mod_sofia.c:4691 (sofia/sipinterface_3/888@voipqso.com:5060) State Change CS_NEW -> CS_INIT
2012-03-19 01:45:12.217750 [DEBUG] switch_core_session.c:1182 Send signal sofia/sipinterface_3/888@voipqso.com:5060 [BREAK]
2012-03-19 01:45:12.217750 [DEBUG] switch_core_state_machine.c:362 (sofia/sipinterface_3/888@voipqso.com:5060) Running State Change CS_INIT
2012-03-19 01:45:12.217750 [DEBUG] switch_core_state_machine.c:401 (sofia/sipinterface_3/888@voipqso.com:5060) State INIT
2012-03-19 01:45:12.217750 [DEBUG] mod_sofia.c:85 sofia/sipinterface_3/888@voipqso.com:5060 SOFIA INIT
2012-03-19 01:45:12.217750 [DEBUG] switch_core_session.c:877 Send signal sofia/sipinterface_3/888@voipqso.com:5060 [BREAK]
2012-03-19 01:45:12.217750 [DEBUG] mod_sofia.c:125 (sofia/sipinterface_3/888@voipqso.com:5060) State Change CS_INIT -> CS_ROUTING
2012-03-19 01:45:12.217750 [DEBUG] switch_core_session.c:1182 Send signal sofia/sipinterface_3/888@voipqso.com:5060 [BREAK]
2012-03-19 01:45:12.217750 [DEBUG] switch_core_state_machine.c:401 (sofia/sipinterface_3/888@voipqso.com:5060) State INIT going to sleep
2012-03-19 01:45:12.217750 [DEBUG] switch_core_state_machine.c:362 (sofia/sipinterface_3/888@voipqso.com:5060) Running State Change CS_ROUTING
2012-03-19 01:45:12.217750 [DEBUG] switch_channel.c:1886 (sofia/sipinterface_3/888@voipqso.com:5060) Callstate Change DOWN -> RINGING
2012-03-19 01:45:12.217750 [DEBUG] switch_core_state_machine.c:410 (sofia/sipinterface_3/888@voipqso.com:5060) State ROUTING
2012-03-19 01:45:12.217750 [DEBUG] mod_sofia.c:148 sofia/sipinterface_3/888@voipqso.com:5060 SOFIA ROUTING
send 1199 bytes to udp/[8.22.205.37]:5060 at 21:45:12.225471:
   ------------------------------------------------------------------------
   INVITE sip:888@voipqso.com:5060 SIP/2.0
   Via: SIP/2.0/UDP 184.82.20.140:5080;rport;branch=z9hG4bK3jvct0mD0KZKN
   Max-Forwards: 69
   From: "n3glv csip" <sip:2917@184.82.20.140>;tag=3aj7mNecNtp0r
   To: <sip:888@voipqso.com:5060>
   Call-ID: 7b246953-ebe6-122f-d6b3-0708dcf9e7b4
   CSeq: 25717492 INVITE
   Contact: <sip:mod_sofia@184.82.20.140:5080>
   User-Agent: Configured by 2600hz!
   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, UPDATE, INFO, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
   Supported: precondition, path, replaces
   Allow-Events: talk, hold, presence, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
   Content-Type: application/sdp
   Content-Disposition: session
   Content-Length: 315
   X-FS-Support: update_display,send_info
   Remote-Party-ID: "n3glv csip" <sip:2917@184.82.20.140>;party=calling;screen=yes;privacy=off
   
   v=0
   o=FreeSWITCH 1332078906 1332078907 IN IP4 184.82.20.140
   s=FreeSWITCH
   c=IN IP4 184.82.20.140
   t=0 0
   m=audio 28206 RTP/AVP 9 98 99 0 8 3 101 13
   a=rtpmap:98 G7221/32000
   a=fmtp:98 bitrate=48000
   a=rtpmap:99 G7221/16000
   a=fmtp:99 bitrate=32000
   a=rtpmap:101 telephone-event/8000
   a=fmtp:101 0-16
   a=ptime:20
   ------------------------------------------------------------------------
2012-03-19 01:45:12.217750 [DEBUG] switch_ivr_originate.c:66 (sofia/sipinterface_3/888@voipqso.com:5060) State Change CS_ROUTING -> CS_CONSUME_MEDIA
2012-03-19 01:45:12.217750 [DEBUG] switch_core_session.c:1182 Send signal sofia/sipinterface_3/888@voipqso.com:5060 [BREAK]
2012-03-19 01:45:12.217750 [DEBUG] switch_core_state_machine.c:410 (sofia/sipinterface_3/888@voipqso.com:5060) State ROUTING going to sleep
2012-03-19 01:45:12.217750 [DEBUG] switch_core_state_machine.c:362 (sofia/sipinterface_3/888@voipqso.com:5060) Running State Change CS_CONSUME_MEDIA
2012-03-19 01:45:12.217750 [DEBUG] switch_core_state_machine.c:429 (sofia/sipinterface_3/888@voipqso.com:5060) State CONSUME_MEDIA
2012-03-19 01:45:12.217750 [DEBUG] switch_core_state_machine.c:429 (sofia/sipinterface_3/888@voipqso.com:5060) State CONSUME_MEDIA going to sleep
2012-03-19 01:45:12.217750 [DEBUG] sofia.c:5532 Channel sofia/sipinterface_3/888@voipqso.com:5060 entering state [calling][0]
recv 482 bytes from udp/[8.22.205.37]:5060 at 21:45:12.277057:
   ------------------------------------------------------------------------
   SIP/2.0 100 Trying
   Via: SIP/2.0/UDP 184.82.20.140:5080;branch=z9hG4bK3jvct0mD0KZKN;received=184.82.20.140;rport=5080
   From: "n3glv csip" <sip:2917@184.82.20.140>;tag=3aj7mNecNtp0r
   To: <sip:888@voipqso.com:5060>
   Call-ID: 7b246953-ebe6-122f-d6b3-0708dcf9e7b4
   CSeq: 25717492 INVITE
   Server: FPBX-2.8.1(1.8.3.3)
   Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH
   Supported: replaces, timer
   Contact: <sip:888@8.22.205.37:5060>
   Content-Length: 0
   
   ------------------------------------------------------------------------
recv 807 bytes from udp/[8.22.205.37]:5060 at 21:45:12.392003:
   ------------------------------------------------------------------------
   SIP/2.0 200 OK
   Via: SIP/2.0/UDP 184.82.20.140:5080;branch=z9hG4bK3jvct0mD0KZKN;received=184.82.20.140;rport=5080
   From: "n3glv csip" <sip:2917@184.82.20.140>;tag=3aj7mNecNtp0r
   To: <sip:888@voipqso.com:5060>;tag=as71d7a24b
   Call-ID: 7b246953-ebe6-122f-d6b3-0708dcf9e7b4
   CSeq: 25717492 INVITE
   Server: FPBX-2.8.1(1.8.3.3)
   Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH
   Supported: replaces, timer
   Contact: <sip:888@8.22.205.37:5060>
   Content-Type: application/sdp
   Content-Length: 281
   
   v=0
   o=root 1445719820 1445719820 IN IP4 8.22.205.37
   s=Asterisk PBX 1.8.3.3
   c=IN IP4 8.22.205.37
   t=0 0
   m=audio 19634 RTP/AVP 0 8 3 101
   a=rtpmap:0 PCMU/8000
   a=rtpmap:8 PCMA/8000
   a=rtpmap:3 GSM/8000
   a=rtpmap:101 telephone-event/8000
   a=fmtp:101 0-16
   a=ptime:20
   a=sendrecv
   ------------------------------------------------------------------------
2012-03-19 01:45:12.377734 [DEBUG] switch_core_session.c:877 Send signal sofia/sipinterface_3/888@voipqso.com:5060 [BREAK]
2012-03-19 01:45:12.377734 [DEBUG] switch_core_session.c:877 Send signal sofia/sipinterface_3/888@voipqso.com:5060 [BREAK]
2012-03-19 01:45:12.377734 [DEBUG] sofia.c:5532 Channel sofia/sipinterface_3/888@voipqso.com:5060 entering state [completing][200]
2012-03-19 01:45:12.377734 [DEBUG] sofia.c:5543 Remote SDP:
v=0
o=root 1445719820 1445719820 IN IP4 8.22.205.37
s=Asterisk PBX 1.8.3.3
c=IN IP4 8.22.205.37
t=0 0
m=audio 19634 RTP/AVP 0 8 3 101
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:3 GSM/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20

send 370 bytes to udp/[8.22.205.37]:5060 at 21:45:12.394349:
   ------------------------------------------------------------------------
   ACK sip:888@8.22.205.37:5060 SIP/2.0
   Via: SIP/2.0/UDP 184.82.20.140:5080;rport;branch=z9hG4bK4UN5UU5gXvN6g
   Max-Forwards: 70
   From: "n3glv csip" <sip:2917@184.82.20.140>;tag=3aj7mNecNtp0r
   To: <sip:888@voipqso.com:5060>;tag=as71d7a24b
   Call-ID: 7b246953-ebe6-122f-d6b3-0708dcf9e7b4
   CSeq: 25717492 ACK
   Contact: <sip:mod_sofia@184.82.20.140:5080>
   Content-Length: 0
   
   ------------------------------------------------------------------------
2012-03-19 01:45:12.377734 [DEBUG] switch_core_session.c:877 Send signal sofia/sipinterface_3/888@voipqso.com:5060 [BREAK]
2012-03-19 01:45:12.377734 [DEBUG] switch_core_session.c:877 Send signal sofia/sipinterface_3/888@voipqso.com:5060 [BREAK]
2012-03-19 01:45:12.377734 [DEBUG] sofia.c:5532 Channel sofia/sipinterface_3/888@voipqso.com:5060 entering state [ready][200]
2012-03-19 01:45:12.377734 [DEBUG] sofia_glue.c:4879 Audio Codec Compare [PCMU:0:8000:20:64000]/[G722:9:8000:20:64000]
2012-03-19 01:45:12.377734 [DEBUG] sofia_glue.c:4879 Audio Codec Compare [PCMU:0:8000:20:64000]/[G7221:115:32000:20:48000]
2012-03-19 01:45:12.377734 [DEBUG] sofia_glue.c:4879 Audio Codec Compare [PCMU:0:8000:20:64000]/[G7221:107:16000:20:32000]
2012-03-19 01:45:12.377734 [DEBUG] sofia_glue.c:4879 Audio Codec Compare [PCMU:0:8000:20:64000]/[G722:9:8000:20:64000]
2012-03-19 01:45:12.377734 [DEBUG] sofia_glue.c:4879 Audio Codec Compare [PCMU:0:8000:20:64000]/[PCMU:0:8000:20:64000]
2012-03-19 01:45:12.377734 [DEBUG] sofia_glue.c:2996 Set Codec sofia/sipinterface_3/888@voipqso.com:5060 PCMU/8000 20 ms 160 samples 64000 bits
2012-03-19 01:45:12.377734 [DEBUG] switch_core_codec.c:111 sofia/sipinterface_3/888@voipqso.com:5060 Original read codec set to PCMU:0
2012-03-19 01:45:12.377734 [DEBUG] sofia_glue.c:4993 Set 2833 dtmf send payload to 101
2012-03-19 01:45:12.377734 [DEBUG] sofia_glue.c:3248 AUDIO RTP [sofia/sipinterface_3/888@voipqso.com:5060] 184.82.20.140 port 28206 -> 8.22.205.37 port 19634 codec: 0 ms: 20
2012-03-19 01:45:12.377734 [DEBUG] switch_rtp.c:1661 Starting timer [soft] 160 bytes per 20ms
2012-03-19 01:45:12.397771 [DEBUG] sofia_glue.c:3512 Set 2833 dtmf send payload to 101
2012-03-19 01:45:12.397771 [DEBUG] sofia_glue.c:3518 Set 2833 dtmf receive payload to 101
2012-03-19 01:45:12.397771 [DEBUG] switch_channel.c:3190 (sofia/sipinterface_3/888@voipqso.com:5060) Callstate Change RINGING -> ACTIVE
2012-03-19 01:45:12.397771 [DEBUG] switch_channel.c:3202 Send signal sofia/sipinterface_1/2917@freeswitch.voipqso.com [BREAK]
2012-03-19 01:45:12.397771 [NOTICE] sofia.c:6246 Channel [sofia/sipinterface_3/888@voipqso.com:5060] has been answered
2012-03-19 01:45:12.397771 [DEBUG] switch_ivr_play_say.c:1306 Codec Activated L16@8000hz 1 channels 20ms
2012-03-19 01:45:13.517637 [DEBUG] switch_rtp.c:3210 Correct ip/port confirmed.
2012-03-19 01:45:18.397171 [DEBUG] switch_ivr_play_say.c:1678 done playing file /usr/local/freeswitch/sounds/en/us/callie/ivr/ivr-accept_reject_voicemail.wav
2012-03-19 01:45:23.397677 [DEBUG] switch_ivr_play_say.c:1306 Codec Activated L16@8000hz 1 channels 20ms
2012-03-19 01:45:29.417110 [DEBUG] switch_ivr_play_say.c:1678 done playing file /usr/local/freeswitch/sounds/en/us/callie/ivr/ivr-accept_reject_voicemail.wav
2012-03-19 01:45:34.436636 [DEBUG] switch_ivr_play_say.c:1306 Codec Activated L16@8000hz 1 channels 20ms
2012-03-19 01:45:40.458068 [DEBUG] switch_ivr_play_say.c:1678 done playing file /usr/local/freeswitch/sounds/en/us/callie/ivr/ivr-accept_reject_voicemail.wav
2012-03-19 01:45:42.017916 [DEBUG] switch_channel.c:2848 (sofia/sipinterface_3/888@voipqso.com:5060) Callstate Change ACTIVE -> HANGUP
2012-03-19 01:45:42.017916 [NOTICE] switch_ivr_originate.c:3182 Hangup sofia/sipinterface_3/888@voipqso.com:5060 [CS_CONSUME_MEDIA] [NO_ANSWER]
2012-03-19 01:45:42.017916 [DEBUG] switch_channel.c:2871 Send signal sofia/sipinterface_3/888@voipqso.com:5060 [KILL]
2012-03-19 01:45:42.017916 [DEBUG] switch_core_session.c:1182 Send signal sofia/sipinterface_3/888@voipqso.com:5060 [BREAK]
2012-03-19 01:45:42.017916 [INFO] mod_dptools.c:2922 Originate Failed.  Cause: NO_ANSWER
EXECUTE sofia/sipinterface_1/2917@freeswitch.voipqso.com hangup()
2012-03-19 01:45:42.017916 [DEBUG] switch_channel.c:2848 (sofia/sipinterface_1/2917@freeswitch.voipqso.com) Callstate Change RINGING -> HANGUP
2012-03-19 01:45:42.017916 [NOTICE] mod_dptools.c:1121 Hangup sofia/sipinterface_1/2917@freeswitch.voipqso.com [CS_EXECUTE] [NORMAL_CLEARING]
2012-03-19 01:45:42.017916 [DEBUG] switch_channel.c:2871 Send signal sofia/sipinterface_1/2917@freeswitch.voipqso.com [KILL]
2012-03-19 01:45:42.017916 [DEBUG] switch_core_session.c:1182 Send signal sofia/sipinterface_1/2917@freeswitch.voipqso.com [BREAK]
2012-03-19 01:45:42.017916 [DEBUG] switch_core_session.c:2287 sofia/sipinterface_1/2917@freeswitch.voipqso.com skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)
2012-03-19 01:45:42.017916 [DEBUG] switch_core_state_machine.c:417 (sofia/sipinterface_1/2917@freeswitch.voipqso.com) State EXECUTE going to sleep
2012-03-19 01:45:42.017916 [DEBUG] switch_core_state_machine.c:362 (sofia/sipinterface_1/2917@freeswitch.voipqso.com) Running State Change CS_HANGUP
2012-03-19 01:45:42.017916 [DEBUG] switch_core_state_machine.c:602 (sofia/sipinterface_1/2917@freeswitch.voipqso.com) State HANGUP
2012-03-19 01:45:42.017916 [DEBUG] mod_sofia.c:469 Channel sofia/sipinterface_1/2917@freeswitch.voipqso.com hanging up, cause: NORMAL_CLEARING
2012-03-19 01:45:42.017916 [DEBUG] mod_sofia.c:534 Responding to INVITE with: 480
send 849 bytes to udp/[64.134.240.14]:34840 at 21:45:42.022603:
   ------------------------------------------------------------------------
   SIP/2.0 480 Temporarily Unavailable
   Via: SIP/2.0/UDP 64.134.240.14:34840;rport=34840;branch=z9hG4bKPj2.WJ3I2P1CXs7eKf0J0WIbByUAHVfIOA
   From: <sip:2917@freeswitch.voipqso.com>;tag=hKTF4C4GSps4GCdB0uTQJ3hwVZeovlgH
   To: <sip:9888@freeswitch.voipqso.com>;tag=6NyNgDKy63pec
   Call-ID: y385v8QmU7H9PwMQ2OzGiaJ82zQIiiCJ
   CSeq: 31862 INVITE
   User-Agent: Configured by 2600hz!
   Accept: application/sdp
   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, UPDATE, INFO, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
   Supported: precondition, path, replaces
   Allow-Events: talk, hold, presence, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
   Reason: Q.850;cause=16;text="NORMAL_CLEARING"
   Content-Length: 0
   Remote-Party-ID: "9888" <sip:9888@freeswitch.voipqso.com>;party=calling;privacy=off;screen=no
   
   ------------------------------------------------------------------------
2012-03-19 01:45:42.017916 [DEBUG] switch_core_state_machine.c:47 sofia/sipinterface_1/2917@freeswitch.voipqso.com Standard HANGUP, cause: NORMAL_CLEARING
2012-03-19 01:45:42.017916 [DEBUG] switch_core_state_machine.c:602 (sofia/sipinterface_1/2917@freeswitch.voipqso.com) State HANGUP going to sleep
2012-03-19 01:45:42.017916 [DEBUG] switch_core_state_machine.c:393 (sofia/sipinterface_1/2917@freeswitch.voipqso.com) State Change CS_HANGUP -> CS_REPORTING
2012-03-19 01:45:42.017916 [DEBUG] switch_core_session.c:1182 Send signal sofia/sipinterface_1/2917@freeswitch.voipqso.com [BREAK]
2012-03-19 01:45:42.017916 [DEBUG] switch_core_state_machine.c:362 (sofia/sipinterface_1/2917@freeswitch.voipqso.com) Running State Change CS_REPORTING
2012-03-19 01:45:42.017916 [DEBUG] switch_core_state_machine.c:662 (sofia/sipinterface_1/2917@freeswitch.voipqso.com) State REPORTING
2012-03-19 01:45:42.017916 [DEBUG] switch_core_state_machine.c:79 sofia/sipinterface_1/2917@freeswitch.voipqso.com Standard REPORTING, cause: NORMAL_CLEARING
2012-03-19 01:45:42.017916 [DEBUG] switch_core_state_machine.c:662 (sofia/sipinterface_1/2917@freeswitch.voipqso.com) State REPORTING going to sleep
2012-03-19 01:45:42.017916 [DEBUG] switch_core_state_machine.c:387 (sofia/sipinterface_1/2917@freeswitch.voipqso.com) State Change CS_REPORTING -> CS_DESTROY
2012-03-19 01:45:42.017916 [DEBUG] switch_core_session.c:1182 Send signal sofia/sipinterface_1/2917@freeswitch.voipqso.com [BREAK]
2012-03-19 01:45:42.017916 [DEBUG] switch_core_session.c:1382 Session 1 (sofia/sipinterface_1/2917@freeswitch.voipqso.com) Locked, Waiting on external entities
2012-03-19 01:45:42.017916 [NOTICE] switch_core_session.c:1400 Session 1 (sofia/sipinterface_1/2917@freeswitch.voipqso.com) Ended
2012-03-19 01:45:42.017916 [NOTICE] switch_core_session.c:1402 Close Channel sofia/sipinterface_1/2917@freeswitch.voipqso.com [CS_DESTROY]
2012-03-19 01:45:42.017916 [DEBUG] switch_core_state_machine.c:491 (sofia/sipinterface_1/2917@freeswitch.voipqso.com) Callstate Change HANGUP -> DOWN
2012-03-19 01:45:42.017916 [DEBUG] switch_core_state_machine.c:494 (sofia/sipinterface_1/2917@freeswitch.voipqso.com) Running State Change CS_DESTROY
2012-03-19 01:45:42.017916 [DEBUG] switch_core_state_machine.c:504 (sofia/sipinterface_1/2917@freeswitch.voipqso.com) State DESTROY
2012-03-19 01:45:42.017916 [DEBUG] mod_sofia.c:374 sofia/sipinterface_1/2917@freeswitch.voipqso.com SOFIA DESTROY
2012-03-19 01:45:42.017916 [DEBUG] switch_core_state_machine.c:86 sofia/sipinterface_1/2917@freeswitch.voipqso.com Standard DESTROY
2012-03-19 01:45:42.017916 [DEBUG] switch_core_state_machine.c:504 (sofia/sipinterface_1/2917@freeswitch.voipqso.com) State DESTROY going to sleep
2012-03-19 01:45:42.017916 [DEBUG] switch_core_state_machine.c:362 (sofia/sipinterface_3/888@voipqso.com:5060) Running State Change CS_HANGUP
2012-03-19 01:45:42.017916 [DEBUG] switch_core_state_machine.c:602 (sofia/sipinterface_3/888@voipqso.com:5060) State HANGUP
2012-03-19 01:45:42.017916 [DEBUG] mod_sofia.c:469 Channel sofia/sipinterface_3/888@voipqso.com:5060 hanging up, cause: NO_ANSWER
2012-03-19 01:45:42.017916 [DEBUG] mod_sofia.c:513 Sending BYE to sofia/sipinterface_3/888@voipqso.com:5060
send 597 bytes to udp/[8.22.205.37]:5060 at 21:45:42.024647:
   ------------------------------------------------------------------------
   BYE sip:888@8.22.205.37:5060 SIP/2.0
   Via: SIP/2.0/UDP 184.82.20.140:5080;rport;branch=z9hG4bK54eyXppmt5BSc
   Max-Forwards: 70
   From: "n3glv csip" <sip:2917@184.82.20.140>;tag=3aj7mNecNtp0r
   To: <sip:888@voipqso.com:5060>;tag=as71d7a24b
   Call-ID: 7b246953-ebe6-122f-d6b3-0708dcf9e7b4
   CSeq: 25717493 BYE
   Contact: <sip:mod_sofia@184.82.20.140:5080>
   User-Agent: Configured by 2600hz!
   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, UPDATE, INFO, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
   Supported: precondition, path, replaces
   Reason: Q.850;cause=19;text="NO_ANSWER"
   Content-Length: 0
   
   ------------------------------------------------------------------------
2012-03-19 01:45:42.017916 [DEBUG] switch_core_state_machine.c:47 sofia/sipinterface_3/888@voipqso.com:5060 Standard HANGUP, cause: NO_ANSWER
2012-03-19 01:45:42.017916 [DEBUG] switch_core_state_machine.c:602 (sofia/sipinterface_3/888@voipqso.com:5060) State HANGUP going to sleep
2012-03-19 01:45:42.017916 [DEBUG] switch_core_state_machine.c:393 (sofia/sipinterface_3/888@voipqso.com:5060) State Change CS_HANGUP -> CS_REPORTING
2012-03-19 01:45:42.017916 [DEBUG] switch_core_session.c:1182 Send signal sofia/sipinterface_3/888@voipqso.com:5060 [BREAK]
2012-03-19 01:45:42.017916 [DEBUG] switch_core_state_machine.c:362 (sofia/sipinterface_3/888@voipqso.com:5060) Running State Change CS_REPORTING
2012-03-19 01:45:42.017916 [DEBUG] switch_core_state_machine.c:662 (sofia/sipinterface_3/888@voipqso.com:5060) State REPORTING
2012-03-19 01:45:42.017916 [DEBUG] switch_core_state_machine.c:79 sofia/sipinterface_3/888@voipqso.com:5060 Standard REPORTING, cause: NO_ANSWER
2012-03-19 01:45:42.017916 [DEBUG] switch_core_state_machine.c:662 (sofia/sipinterface_3/888@voipqso.com:5060) State REPORTING going to sleep
2012-03-19 01:45:42.017916 [DEBUG] switch_core_state_machine.c:387 (sofia/sipinterface_3/888@voipqso.com:5060) State Change CS_REPORTING -> CS_DESTROY
2012-03-19 01:45:42.017916 [DEBUG] switch_core_session.c:1182 Send signal sofia/sipinterface_3/888@voipqso.com:5060 [BREAK]
2012-03-19 01:45:42.017916 [DEBUG] switch_core_session.c:1382 Session 2 (sofia/sipinterface_3/888@voipqso.com:5060) Locked, Waiting on external entities
2012-03-19 01:45:42.037911 [NOTICE] switch_core_session.c:1400 Session 2 (sofia/sipinterface_3/888@voipqso.com:5060) Ended
2012-03-19 01:45:42.037911 [NOTICE] switch_core_session.c:1402 Close Channel sofia/sipinterface_3/888@voipqso.com:5060 [CS_DESTROY]
2012-03-19 01:45:42.037911 [DEBUG] switch_core_state_machine.c:491 (sofia/sipinterface_3/888@voipqso.com:5060) Callstate Change HANGUP -> DOWN
2012-03-19 01:45:42.037911 [DEBUG] switch_core_state_machine.c:494 (sofia/sipinterface_3/888@voipqso.com:5060) Running State Change CS_DESTROY
2012-03-19 01:45:42.037911 [DEBUG] switch_core_state_machine.c:504 (sofia/sipinterface_3/888@voipqso.com:5060) State DESTROY
2012-03-19 01:45:42.037911 [DEBUG] mod_sofia.c:374 sofia/sipinterface_3/888@voipqso.com:5060 SOFIA DESTROY
2012-03-19 01:45:42.037911 [DEBUG] switch_core_state_machine.c:86 sofia/sipinterface_3/888@voipqso.com:5060 Standard DESTROY
2012-03-19 01:45:42.037911 [DEBUG] switch_core_state_machine.c:504 (sofia/sipinterface_3/888@voipqso.com:5060) State DESTROY going to sleep
recv 453 bytes from udp/[8.22.205.37]:5060 at 21:45:42.075920:
   ------------------------------------------------------------------------
   SIP/2.0 200 OK
   Via: SIP/2.0/UDP 184.82.20.140:5080;branch=z9hG4bK54eyXppmt5BSc;received=184.82.20.140;rport=5080
   From: "n3glv csip" <sip:2917@184.82.20.140>;tag=3aj7mNecNtp0r
   To: <sip:888@voipqso.com:5060>;tag=as71d7a24b
   Call-ID: 7b246953-ebe6-122f-d6b3-0708dcf9e7b4
   CSeq: 25717493 BYE
   Server: FPBX-2.8.1(1.8.3.3)
   Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH
   Supported: replaces, timer
   Content-Length: 0
   
   ------------------------------------------------------------------------
recv 427 bytes from udp/[64.134.240.14]:34840 at 21:45:42.091125:
   ------------------------------------------------------------------------
   ACK sip:9888@freeswitch.voipqso.com SIP/2.0
   Via: SIP/2.0/UDP 64.134.240.14:34840;rport;branch=z9hG4bKPj2.WJ3I2P1CXs7eKf0J0WIbByUAHVfIOA
   Max-Forwards: 70
   From: <sip:2917@freeswitch.voipqso.com>;tag=hKTF4C4GSps4GCdB0uTQJ3hwVZeovlgH
   To: <sip:9888@freeswitch.voipqso.com>;tag=6NyNgDKy63pec
   Call-ID: y385v8QmU7H9PwMQ2OzGiaJ82zQIiiCJ
   CSeq: 31862 ACK
   Route: <sip:freeswitch.voipqso.com;transport=udp;lr>
   Content-Length:  0
   
   ------------------------------------------------------------------------
recv 526 bytes from udp/[64.134.240.14]:34840 at 21:45:42.618958:
   ------------------------------------------------------------------------
   REGISTER sip:freeswitch.voipqso.com SIP/2.0
   Via: SIP/2.0/UDP 64.134.240.14:34840;rport;branch=z9hG4bKPjNauiws3Tv9EPJ4c3rItWetGuv61brFDt
   Route: <sip:freeswitch.voipqso.com;transport=udp;lr>
   Max-Forwards: 70
   From: <sip:2917@freeswitch.voipqso.com>;tag=hW8iu3xTG0p2rl6y0oQgEFpJdXuR8K9G
   To: <sip:2917@freeswitch.voipqso.com>
   Call-ID: 9BWR55lyTqFtufCvsw5BfEPUAlbA007g
   CSeq: 6068 REGISTER
   User-Agent: CSipSimple r1108 / picasso-13
   Contact: <sip:2917@64.134.240.14:34840;transport=UDP;ob>
   Expires: 0
   Content-Length:  0
   
   ------------------------------------------------------------------------
send 661 bytes to udp/[64.134.240.14]:34840 at 21:45:42.619761:
   ------------------------------------------------------------------------
   SIP/2.0 401 Unauthorized
   Via: SIP/2.0/UDP 64.134.240.14:34840;rport=34840;branch=z9hG4bKPjNauiws3Tv9EPJ4c3rItWetGuv61brFDt
   From: <sip:2917@freeswitch.voipqso.com>;tag=hW8iu3xTG0p2rl6y0oQgEFpJdXuR8K9G
   To: <sip:2917@freeswitch.voipqso.com>;tag=7yQej8313cD1Q
   Call-ID: 9BWR55lyTqFtufCvsw5BfEPUAlbA007g
   CSeq: 6068 REGISTER
   User-Agent: Configured by 2600hz!
   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, UPDATE, INFO, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
   Supported: precondition, path, replaces
   WWW-Authenticate: Digest realm="freeswitch.voipqso.com", nonce="b5e40578-7143-11e1-91a6-698b09f7d618", algorithm=MD5, qop="auth"
   Content-Length: 0
   
   ------------------------------------------------------------------------
recv 803 bytes from udp/[64.134.240.14]:34840 at 21:45:42.695207:
   ------------------------------------------------------------------------
   REGISTER sip:freeswitch.voipqso.com SIP/2.0
   Via: SIP/2.0/UDP 64.134.240.14:34840;rport;branch=z9hG4bKPjYHcYFef9KJMgGcfZLc5sGAdxs3.9sh7d
   Route: <sip:freeswitch.voipqso.com;transport=udp;lr>
   Max-Forwards: 70
   From: <sip:2917@freeswitch.voipqso.com>;tag=hW8iu3xTG0p2rl6y0oQgEFpJdXuR8K9G
   To: <sip:2917@freeswitch.voipqso.com>
   Call-ID: 9BWR55lyTqFtufCvsw5BfEPUAlbA007g
   CSeq: 6069 REGISTER
   User-Agent: CSipSimple r1108 / picasso-13
   Contact: <sip:2917@64.134.240.14:34840;transport=UDP;ob>
   Expires: 0
   Authorization: Digest username="2917", realm="freeswitch.voipqso.com", nonce="b5e40578-7143-11e1-91a6-698b09f7d618", uri="sip:freeswitch.voipqso.com", response="c90110737a667d74b52cc67602d7cceb", algorithm=MD5, cnonce="Ls3xWU7Zhcg3ew8GbViOBmFSmT7PNGL7", qop=auth, nc=00000001
   Content-Length:  0
   
   ------------------------------------------------------------------------
send 558 bytes to udp/[64.134.240.14]:34840 at 21:45:42.697215:
   ------------------------------------------------------------------------
   SIP/2.0 200 OK
   Via: SIP/2.0/UDP 64.134.240.14:34840;rport=34840;branch=z9hG4bKPjYHcYFef9KJMgGcfZLc5sGAdxs3.9sh7d
   From: <sip:2917@freeswitch.voipqso.com>;tag=hW8iu3xTG0p2rl6y0oQgEFpJdXuR8K9G
   To: <sip:2917@freeswitch.voipqso.com>;tag=87g7K3m50N3KK
   Call-ID: 9BWR55lyTqFtufCvsw5BfEPUAlbA007g
   CSeq: 6069 REGISTER
   Date: Sat, 18 Mar 2012 21:45:42 GMT
   User-Agent: Configured by 2600hz!
   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, UPDATE, INFO, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
   Supported: precondition, path, replaces
   Content-Length: 0
   
   ------------------------------------------------------------------------
recv 605 bytes from udp/[64.134.240.14]:55640 at 21:45:45.709452:
   ------------------------------------------------------------------------
   REGISTER sip:freeswitch.voipqso.com SIP/2.0
   Via: SIP/2.0/UDP 192.168.5.37:55640;rport;branch=z9hG4bKPjrIRk4DtTwycdZXCW9Ad.mljUnPeXqCSj
   Route: <sip:freeswitch.voipqso.com;transport=udp;lr>
   Max-Forwards: 70
   From: <sip:2917@freeswitch.voipqso.com>;tag=2wGSWOtTX1ns4zcYUMD8N70vpT8YwJGm
   To: <sip:2917@freeswitch.voipqso.com>
   Call-ID: clBr26UVX9SAqtY20MGIXIiQ4YFb3ZUO
   CSeq: 57432 REGISTER
   User-Agent: CSipSimple r1108 / picasso-13
   Contact: <sip:2917@192.168.5.37:55640;ob>
   Expires: 900
   Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS
   Content-Length:  0
   
   ------------------------------------------------------------------------
send 684 bytes to udp/[64.134.240.14]:55640 at 21:45:45.710106:
   ------------------------------------------------------------------------
   SIP/2.0 401 Unauthorized
   Via: SIP/2.0/UDP 192.168.5.37:55640;rport=55640;branch=z9hG4bKPjrIRk4DtTwycdZXCW9Ad.mljUnPeXqCSj;received=64.134.240.14
   From: <sip:2917@freeswitch.voipqso.com>;tag=2wGSWOtTX1ns4zcYUMD8N70vpT8YwJGm
   To: <sip:2917@freeswitch.voipqso.com>;tag=9ga0Ny58XyS6e
   Call-ID: clBr26UVX9SAqtY20MGIXIiQ4YFb3ZUO
   CSeq: 57432 REGISTER
   User-Agent: Configured by 2600hz!
   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, UPDATE, INFO, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
   Supported: precondition, path, replaces
   WWW-Authenticate: Digest realm="freeswitch.voipqso.com", nonce="b7bb95d2-7143-11e1-91a7-698b09f7d618", algorithm=MD5, qop="auth"
   Content-Length: 0
   
   ------------------------------------------------------------------------
recv 882 bytes from udp/[64.134.240.14]:55640 at 21:45:46.043211:
   ------------------------------------------------------------------------
   REGISTER sip:freeswitch.voipqso.com SIP/2.0
   Via: SIP/2.0/UDP 192.168.5.37:55640;rport;branch=z9hG4bKPjxPKIFyf300vk5KexGvSEHk5KYc9v-hnn
   Route: <sip:freeswitch.voipqso.com;transport=udp;lr>
   Max-Forwards: 70
   From: <sip:2917@freeswitch.voipqso.com>;tag=2wGSWOtTX1ns4zcYUMD8N70vpT8YwJGm
   To: <sip:2917@freeswitch.voipqso.com>
   Call-ID: clBr26UVX9SAqtY20MGIXIiQ4YFb3ZUO
   CSeq: 57433 REGISTER
   User-Agent: CSipSimple r1108 / picasso-13
   Contact: <sip:2917@192.168.5.37:55640;ob>
   Expires: 900
   Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS
   Authorization: Digest username="2917", realm="freeswitch.voipqso.com", nonce="b7bb95d2-7143-11e1-91a7-698b09f7d618", uri="sip:freeswitch.voipqso.com", response="f6cae7475905ea12ee07e9462f7278e8", algorithm=MD5, cnonce="eCYyUKFoZFLhX9Z0LiQYoFV-AtIG-whm", qop=auth, nc=00000001
   Content-Length:  0
   
   ------------------------------------------------------------------------
send 636 bytes to udp/[64.134.240.14]:55640 at 21:45:46.045162:
   ------------------------------------------------------------------------
   SIP/2.0 200 OK
   Via: SIP/2.0/UDP 192.168.5.37:55640;rport=55640;branch=z9hG4bKPjxPKIFyf300vk5KexGvSEHk5KYc9v-hnn;received=64.134.240.14
   From: <sip:2917@freeswitch.voipqso.com>;tag=2wGSWOtTX1ns4zcYUMD8N70vpT8YwJGm
   To: <sip:2917@freeswitch.voipqso.com>;tag=at3rQSpcU7FSa
   Call-ID: clBr26UVX9SAqtY20MGIXIiQ4YFb3ZUO
   CSeq: 57433 REGISTER
   Contact: <sip:2917@192.168.5.37:55640;ob>;expires=900
   Date: Sat, 18 Mar 2012 21:45:46 GMT
   User-Agent: Configured by 2600hz!
   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, UPDATE, INFO, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
   Supported: precondition, path, replaces
   Content-Length: 0
   
   ------------------------------------------------------------------------
2012-03-19 01:45:46.037528 [CONSOLE] mod_voicemail.c:3860 Event Thread Started
send 915 bytes to udp/[64.134.240.14]:55640 at 21:45:46.083853:
   ------------------------------------------------------------------------
   NOTIFY sip:2917@192.168.5.37:55640;ob SIP/2.0
   Via: SIP/2.0/UDP 184.82.20.140;rport;branch=z9hG4bK52ccr9KFU2NNm
   Route: <sip:2917@64.134.240.14:55640>;ob
   Max-Forwards: 70
   From: <sip:2917@184.82.20.140>;tag=B3vHSm7Frg6Bp
   To: <sip:2917@184.82.20.140>
   Call-ID: 8f52e922-ebe6-122f-d6b3-0708dcf9e7b4
   CSeq: 25717509 NOTIFY
   Contact: <sip:mod_sofia@184.82.20.140:5060>
   User-Agent: Configured by 2600hz!
   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, UPDATE, INFO, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
   Supported: precondition, path, replaces
   Event: message-summary
   Allow-Events: talk, hold, presence, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
   Subscription-State: terminated;reason=noresource
   Content-Type: application/simple-message-summary
   Content-Length: 74
   
   Messages-Waiting: no
   Message-Account: sip:2917@freeswitch.voipqso.com
   
   ------------------------------------------------------------------------
recv 674 bytes from udp/[64.134.240.14]:55640 at 21:45:46.114397:
   ------------------------------------------------------------------------
   REGISTER sip:freeswitch.voipqso.com SIP/2.0
   Via: SIP/2.0/UDP 64.134.240.14:55640;rport;branch=z9hG4bKPjr1G3ypIZrXeG3uh8rca47wpCfQG4u3Ll
   Route: <sip:freeswitch.voipqso.com;transport=udp;lr>
   Max-Forwards: 70
   From: <sip:2917@freeswitch.voipqso.com>;tag=0YZNbAiND0F0MQQ8btYvd4Aod1Q4xQxx
   To: <sip:2917@freeswitch.voipqso.com>
   Call-ID: clBr26UVX9SAqtY20MGIXIiQ4YFb3ZUO
   CSeq: 57434 REGISTER
   User-Agent: CSipSimple r1108 / picasso-13
   Contact: <sip:2917@64.134.240.14:55640;transport=UDP;ob>
   Contact: <sip:2917@192.168.5.37:55640;ob>;expires=0
   Expires: 900
   Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS
   Content-Length:  0
   
   ------------------------------------------------------------------------
send 662 bytes to udp/[64.134.240.14]:55640 at 21:45:46.115115:
   ------------------------------------------------------------------------
   SIP/2.0 401 Unauthorized
   Via: SIP/2.0/UDP 64.134.240.14:55640;rport=55640;branch=z9hG4bKPjr1G3ypIZrXeG3uh8rca47wpCfQG4u3Ll
   From: <sip:2917@freeswitch.voipqso.com>;tag=0YZNbAiND0F0MQQ8btYvd4Aod1Q4xQxx
   To: <sip:2917@freeswitch.voipqso.com>;tag=ccpaUFrKNSvyH
   Call-ID: clBr26UVX9SAqtY20MGIXIiQ4YFb3ZUO
   CSeq: 57434 REGISTER
   User-Agent: Configured by 2600hz!
   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, UPDATE, INFO, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
   Supported: precondition, path, replaces
   WWW-Authenticate: Digest realm="freeswitch.voipqso.com", nonce="b7f9642a-7143-11e1-91a8-698b09f7d618", algorithm=MD5, qop="auth"
   Content-Length: 0
   
   ------------------------------------------------------------------------
recv 307 bytes from udp/[64.134.240.14]:55640 at 21:45:46.142184:
   ------------------------------------------------------------------------
   SIP/2.0 200 OK
   Via: SIP/2.0/UDP 184.82.20.140;rport=5060;received=184.82.20.140;branch=z9hG4bK52ccr9KFU2NNm
   Call-ID: 8f52e922-ebe6-122f-d6b3-0708dcf9e7b4
   From: <sip:2917@184.82.20.140>;tag=B3vHSm7Frg6Bp
   To: <sip:2917@184.82.20.140>;tag=z9hG4bK52ccr9KFU2NNm
   CSeq: 25717509 NOTIFY
   Content-Length:  0
   
   ------------------------------------------------------------------------
recv 951 bytes from udp/[64.134.240.14]:55640 at 21:45:46.182840:
   ------------------------------------------------------------------------
   REGISTER sip:freeswitch.voipqso.com SIP/2.0
   Via: SIP/2.0/UDP 64.134.240.14:55640;rport;branch=z9hG4bKPj0gwHQNM7jZUjg.QDSXPmZZ3q70TmZozR
   Route: <sip:freeswitch.voipqso.com;transport=udp;lr>
   Max-Forwards: 70
   From: <sip:2917@freeswitch.voipqso.com>;tag=0YZNbAiND0F0MQQ8btYvd4Aod1Q4xQxx
   To: <sip:2917@freeswitch.voipqso.com>
   Call-ID: clBr26UVX9SAqtY20MGIXIiQ4YFb3ZUO
   CSeq: 57435 REGISTER
   User-Agent: CSipSimple r1108 / picasso-13
   Contact: <sip:2917@64.134.240.14:55640;transport=UDP;ob>
   Contact: <sip:2917@192.168.5.37:55640;ob>;expires=0
   Expires: 900
   Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS
   Authorization: Digest username="2917", realm="freeswitch.voipqso.com", nonce="b7f9642a-7143-11e1-91a8-698b09f7d618", uri="sip:freeswitch.voipqso.com", response="72cd3f6637dc6be24703090cadca1362", algorithm=MD5, cnonce="eCYyUKFoZFLhX9Z0LiQYoFV-AtIG-whm", qop=auth, nc=00000001
   Content-Length:  0
   
   ------------------------------------------------------------------------
send 629 bytes to udp/[64.134.240.14]:55640 at 21:45:46.184899:
   ------------------------------------------------------------------------
   SIP/2.0 200 OK
   Via: SIP/2.0/UDP 64.134.240.14:55640;rport=55640;branch=z9hG4bKPj0gwHQNM7jZUjg.QDSXPmZZ3q70TmZozR
   From: <sip:2917@freeswitch.voipqso.com>;tag=0YZNbAiND0F0MQQ8btYvd4Aod1Q4xQxx
   To: <sip:2917@freeswitch.voipqso.com>;tag=DNF3va9pj2jHD
   Call-ID: clBr26UVX9SAqtY20MGIXIiQ4YFb3ZUO
   CSeq: 57435 REGISTER
   Contact: <sip:2917@64.134.240.14:55640;transport=UDP;ob>;expires=900
   Date: Sat, 18 Mar 2012 21:45:46 GMT
   User-Agent: Configured by 2600hz!
   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, UPDATE, INFO, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
   Supported: precondition, path, replaces
   Content-Length: 0
   
   ------------------------------------------------------------------------
send 888 bytes to udp/[64.134.240.14]:55640 at 21:45:46.285828:
   ------------------------------------------------------------------------
   NOTIFY sip:2917@64.134.240.14:55640;transport=UDP;ob SIP/2.0
   Via: SIP/2.0/UDP 184.82.20.140;rport;branch=z9hG4bK6B64S44jrBc8F
   Max-Forwards: 70
   From: <sip:2917@184.82.20.140>;tag=ey8Uy5StFB93r
   To: <sip:2917@184.82.20.140>
   Call-ID: 8f71ba83-ebe6-122f-d6b3-0708dcf9e7b4
   CSeq: 25717509 NOTIFY
   Contact: <sip:mod_sofia@184.82.20.140:5060>
   User-Agent: Configured by 2600hz!
   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, UPDATE, INFO, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
   Supported: precondition, path, replaces
   Event: message-summary
   Allow-Events: talk, hold, presence, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
   Subscription-State: terminated;reason=noresource
   Content-Type: application/simple-message-summary
   Content-Length: 74
   
   Messages-Waiting: no
   Message-Account: sip:2917@freeswitch.voipqso.com
   
   ------------------------------------------------------------------------
recv 307 bytes from udp/[64.134.240.14]:55640 at 21:45:46.345343:
   ------------------------------------------------------------------------
   SIP/2.0 200 OK
   Via: SIP/2.0/UDP 184.82.20.140;rport=5060;received=184.82.20.140;branch=z9hG4bK6B64S44jrBc8F
   Call-ID: 8f71ba83-ebe6-122f-d6b3-0708dcf9e7b4
   From: <sip:2917@184.82.20.140>;tag=ey8Uy5StFB93r
   To: <sip:2917@184.82.20.140>;tag=z9hG4bK6B64S44jrBc8F
   CSeq: 25717509 NOTIFY
   Content-Length:  0
   
   ------------------------------------------------------------------------
freeswitch@internal>