[Freeswitch-users] Freeswitch (SBC + Media) does not know that gateway phone is picked up

Sherif Omran sherifomran2000 at yahoo.com
Fri May 25 03:40:17 MSD 2012


Hi all,

here is the siptrace: To figure it out
1- gateway called bluesip.net. It send invide using caller number at bluesip.net
2- This call should go to extension kb-1002. kb means go from freeswitch port 6090 to kamailio port 5060
3- It should go to call extension 1002 in Kamailio
4- Extension 1002 rings but when I reply, it does not notice I replied


./fs_cli 
            _____ ____     ____ _     ___              
           |  ___/ ___|   / ___| |   |_ _|             
           | |_  \___ \  | |   | |    | |            
           |  _|  ___) | | |___| |___ | |              
           |_|   |____/   \____|_____|___|            

*******************************************************
* Anthony Minessale II, Ken Rice,                     *
* Michael Jerris, Travis Cross                        *
* FreeSWITCH (http://www.freeswitch.org)              *
* Paypal Donations Appreciated: paypal at freeswitch.org *
* Brought to you by ClueCon http://www.cluecon.com/   *
*******************************************************

Type /help <enter> to see a list of commands


+OK log level  [7]
freeswitch at internal> tracelevel
-ERR tracelevel Command not found!

freeswitch at internal> sofia global siptrace on
+OK Global siptrace on
recv 1104 bytes from udp/[217.74.179.29]:5060 at 23:08:45.822962:
   ------------------------------------------------------------------------
   INVITE sip:gw+trunk_1000 at 78.138.90.58:6090;transport=udp;gw=trunk_1000 SIP/2.0
   Record-Route: <sip:217.74.179.29;ftag=as00589402;lr=on>
   Via: SIP/2.0/UDP 217.74.179.29;branch=z9hG4bK0dc9.284b9a03.0
   Via: SIP/2.0/UDP 217.74.179.28:5060;branch=z9hG4bK086622bb;rport=5060
   From: "+41793940965" <sip:+41793940965 at bluesip.net>;tag=as00589402
   To: <sip:+498972101087914 at bluesip.net>
   Contact: <sip:+41793940965 at 217.74.179.28>
   Call-ID: 4253e4bd2d624caf7f18faf85390b68f at bluesip.net
   CSeq: 102 INVITE
   User-Agent: blueSIP PSTN GW
   Max-Forwards: 69
   Date: Thu, 24 May 2012 23:08:44 GMT
   Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
   Supported: replaces
   Content-Type: application/sdp
   Content-Length: 367
   P-hint: USRLOC
   
   v=0
   o=root 20076 20076 IN IP4 217.74.179.28
   s=session
   c=IN IP4 217.74.179.28
   t=0 0
   m=audio 25626 RTP/AVP 8 0 18 111 3 101
   a=rtpmap:8 PCMA/8000
   a=rtpmap:0 PCMU/8000
   a=rtpmap:18 G729/8000
   a=fmtp:18 annexb=no
   a=rtpmap:111 G726-32/8000
   a=rtpmap:3 GSM/8000
   a=rtpmap:101 telephone-event/8000
   a=fmtp:101 0-16
   a=silenceSupp:off - - - -
   a=ptime:20
   a=sendrecv
   ------------------------------------------------------------------------
send 494 bytes to udp/[217.74.179.29]:5060 at 23:08:45.844486:
   ------------------------------------------------------------------------
   SIP/2.0 100 Trying
   Via: SIP/2.0/UDP 217.74.179.29;branch=z9hG4bK0dc9.284b9a03.0
   Via: SIP/2.0/UDP 217.74.179.28:5060;branch=z9hG4bK086622bb;rport=5060
   Record-Route: <sip:217.74.179.29;ftag=as00589402;lr=on>
   From: "+41793940965" <sip:+41793940965 at bluesip.net>;tag=as00589402
   To: <sip:+498972101087914 at bluesip.net>
   Call-ID: 4253e4bd2d624caf7f18faf85390b68f at bluesip.net
   CSeq: 102 INVITE
   User-Agent: FreeSWITCH-mod_sofia/1.1.beta1-git-8edfd01 2012-03-24 05-16-47 +0000
   Content-Length: 0
   
   ------------------------------------------------------------------------
2012-05-24 23:08:45.840749 [NOTICE] switch_channel.c:926 New Channel sofia/internal/+41793940965 at bluesip.net [69cd22e6-a5f5-11e1-a833-7b1f73a7ffcf]
2012-05-24 23:08:45.840749 [DEBUG] sofia.c:5532 Channel sofia/internal/+41793940965 at bluesip.net entering state [received][100]
2012-05-24 23:08:45.840749 [DEBUG] sofia.c:5543 Remote SDP:
v=0
o=root 20076 20076 IN IP4 217.74.179.28
s=session
c=IN IP4 217.74.179.28
t=0 0
m=audio 25626 RTP/AVP 8 0 18 111 3 101
a=rtpmap:8 PCMA/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=rtpmap:111 G726-32/8000
a=rtpmap:3 GSM/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=silenceSupp:off - - - -
a=ptime:20

2012-05-24 23:08:45.840749 [DEBUG] sofia_glue.c:4879 Audio Codec Compare [PCMA:8:8000:20:64000]/[iLBC:97:8000:30:13330]
2012-05-24 23:08:45.840749 [DEBUG] sofia_glue.c:4879 Audio Codec Compare [PCMU:0:8000:20:64000]/[iLBC:97:8000:30:13330]
2012-05-24 23:08:45.840749 [DEBUG] sofia_glue.c:4879 Audio Codec Compare [G729:18:8000:20:8000]/[iLBC:97:8000:30:13330]
2012-05-24 23:08:45.840749 [DEBUG] sofia_glue.c:4879 Audio Codec Compare [G726-32:111:8000:20:0]/[iLBC:97:8000:30:13330]
2012-05-24 23:08:45.840749 [DEBUG] sofia_glue.c:4879 Audio Codec Compare [GSM:3:8000:20:13200]/[iLBC:97:8000:30:13330]
2012-05-24 23:08:45.840749 [DEBUG] sofia_glue.c:4879 Audio Codec Compare [telephone-event:101:8000:20:0]/[iLBC:97:8000:30:13330]
2012-05-24 23:08:45.840749 [DEBUG] sofia_glue.c:5000 Set 2833 dtmf send/recv payload to 101
2012-05-24 23:08:45.840749 [DEBUG] sofia_glue.c:4879 Audio Codec Compare [PCMA:8:8000:20:64000]/[G7221:115:32000:20:48000]
2012-05-24 23:08:45.840749 [DEBUG] sofia_glue.c:4879 Audio Codec Compare [PCMU:0:8000:20:64000]/[G7221:115:32000:20:48000]
2012-05-24 23:08:45.840749 [DEBUG] sofia_glue.c:4879 Audio Codec Compare [G729:18:8000:20:8000]/[G7221:115:32000:20:48000]
2012-05-24 23:08:45.840749 [DEBUG] sofia_glue.c:4879 Audio Codec Compare [G726-32:111:8000:20:0]/[G7221:115:32000:20:48000]
2012-05-24 23:08:45.840749 [DEBUG] sofia_glue.c:4879 Audio Codec Compare [GSM:3:8000:20:13200]/[G7221:115:32000:20:48000]
2012-05-24 23:08:45.840749 [DEBUG] sofia_glue.c:4879 Audio Codec Compare [telephone-event:101:8000:20:0]/[G7221:115:32000:20:48000]
2012-05-24 23:08:45.840749 [DEBUG] sofia_glue.c:5000 Set 2833 dtmf send/recv payload to 101
2012-05-24 23:08:45.840749 [DEBUG] sofia_glue.c:4879 Audio Codec Compare [PCMA:8:8000:20:64000]/[G7221:107:16000:20:32000]
2012-05-24 23:08:45.840749 [DEBUG] sofia_glue.c:4879 Audio Codec Compare [PCMU:0:8000:20:64000]/[G7221:107:16000:20:32000]
2012-05-24 23:08:45.840749 [DEBUG] sofia_glue.c:4879 Audio Codec Compare [G729:18:8000:20:8000]/[G7221:107:16000:20:32000]
2012-05-24 23:08:45.840749 [DEBUG] sofia_glue.c:4879 Audio Codec Compare [G726-32:111:8000:20:0]/[G7221:107:16000:20:32000]
2012-05-24 23:08:45.840749 [DEBUG] sofia_glue.c:4879 Audio Codec Compare [GSM:3:8000:20:13200]/[G7221:107:16000:20:32000]
2012-05-24 23:08:45.840749 [DEBUG] sofia_glue.c:4879 Audio Codec Compare [telephone-event:101:8000:20:0]/[G7221:107:16000:20:32000]
2012-05-24 23:08:45.840749 [DEBUG] sofia_glue.c:5000 Set 2833 dtmf send/recv payload to 101
2012-05-24 23:08:45.840749 [DEBUG] sofia_glue.c:4879 Audio Codec Compare [PCMA:8:8000:20:64000]/[G722:9:8000:20:64000]
2012-05-24 23:08:45.840749 [DEBUG] sofia_glue.c:4879 Audio Codec Compare [PCMU:0:8000:20:64000]/[G722:9:8000:20:64000]
2012-05-24 23:08:45.840749 [DEBUG] sofia_glue.c:4879 Audio Codec Compare [G729:18:8000:20:8000]/[G722:9:8000:20:64000]
2012-05-24 23:08:45.840749 [DEBUG] sofia_glue.c:4879 Audio Codec Compare [G726-32:111:8000:20:0]/[G722:9:8000:20:64000]
2012-05-24 23:08:45.840749 [DEBUG] sofia_glue.c:4879 Audio Codec Compare [GSM:3:8000:20:13200]/[G722:9:8000:20:64000]
2012-05-24 23:08:45.840749 [DEBUG] sofia_glue.c:4879 Audio Codec Compare [telephone-event:101:8000:20:0]/[G722:9:8000:20:64000]
2012-05-24 23:08:45.840749 [DEBUG] sofia_glue.c:5000 Set 2833 dtmf send/recv payload to 101
2012-05-24 23:08:45.840749 [DEBUG] sofia_glue.c:4879 Audio Codec Compare [PCMA:8:8000:20:64000]/[PCMU:0:8000:20:64000]
2012-05-24 23:08:45.840749 [DEBUG] sofia_glue.c:4879 Audio Codec Compare [PCMU:0:8000:20:64000]/[PCMU:0:8000:20:64000]
2012-05-24 23:08:45.840749 [DEBUG] sofia_glue.c:2996 Set Codec sofia/internal/+41793940965 at bluesip.net PCMU/8000 20 ms 160 samples 64000 bits
2012-05-24 23:08:45.840749 [DEBUG] switch_core_codec.c:111 sofia/internal/+41793940965 at bluesip.net Original read codec set to PCMU:0
2012-05-24 23:08:45.840749 [DEBUG] sofia_glue.c:5000 Set 2833 dtmf send/recv payload to 101
2012-05-24 23:08:45.840749 [DEBUG] sofia.c:5757 (sofia/internal/+41793940965 at bluesip.net) State Change CS_NEW -> CS_INIT
2012-05-24 23:08:45.840749 [DEBUG] switch_core_session.c:1182 Send signal sofia/internal/+41793940965 at bluesip.net [BREAK]
2012-05-24 23:08:45.840749 [DEBUG] switch_core_state_machine.c:362 (sofia/internal/+41793940965 at bluesip.net) Running State Change CS_INIT
2012-05-24 23:08:45.840749 [DEBUG] switch_core_state_machine.c:401 (sofia/internal/+41793940965 at bluesip.net) State INIT
2012-05-24 23:08:45.840749 [DEBUG] mod_sofia.c:85 sofia/internal/+41793940965 at bluesip.net SOFIA INIT
2012-05-24 23:08:45.840749 [DEBUG] mod_sofia.c:125 (sofia/internal/+41793940965 at bluesip.net) State Change CS_INIT -> CS_ROUTING
2012-05-24 23:08:45.840749 [DEBUG] switch_core_session.c:1182 Send signal sofia/internal/+41793940965 at bluesip.net [BREAK]
2012-05-24 23:08:45.840749 [DEBUG] switch_core_state_machine.c:401 (sofia/internal/+41793940965 at bluesip.net) State INIT going to sleep
2012-05-24 23:08:45.840749 [DEBUG] switch_core_state_machine.c:362 (sofia/internal/+41793940965 at bluesip.net) Running State Change CS_ROUTING
2012-05-24 23:08:45.840749 [DEBUG] switch_channel.c:1886 (sofia/internal/+41793940965 at bluesip.net) Callstate Change DOWN -> RINGING
2012-05-24 23:08:45.840749 [DEBUG] switch_core_state_machine.c:410 (sofia/internal/+41793940965 at bluesip.net) State ROUTING
2012-05-24 23:08:45.840749 [DEBUG] mod_sofia.c:148 sofia/internal/+41793940965 at bluesip.net SOFIA ROUTING
2012-05-24 23:08:45.840749 [DEBUG] switch_core_state_machine.c:104 sofia/internal/+41793940965 at bluesip.net Standard ROUTING
2012-05-24 23:08:45.840749 [INFO] mod_dialplan_xml.c:485 Processing +41793940965 <+41793940965>->kb-1002 in context public
Dialplan: sofia/internal/+41793940965 at bluesip.net parsing [public->from_kamailio] continue=false
Dialplan: sofia/internal/+41793940965 at bluesip.net Regex (PASS) [from_kamailio] destination_number(kb-1002) =~ /^(.+)$/ break=on-false
Dialplan: sofia/internal/+41793940965 at bluesip.net Action transfer(kb-1002 XML default) 
2012-05-24 23:08:45.840749 [DEBUG] switch_core_state_machine.c:154 (sofia/internal/+41793940965 at bluesip.net) State Change CS_ROUTING -> CS_EXECUTE
2012-05-24 23:08:45.840749 [DEBUG] switch_core_session.c:1182 Send signal sofia/internal/+41793940965 at bluesip.net [BREAK]
2012-05-24 23:08:45.840749 [DEBUG] switch_core_state_machine.c:410 (sofia/internal/+41793940965 at bluesip.net) State ROUTING going to sleep
2012-05-24 23:08:45.840749 [DEBUG] switch_core_state_machine.c:362 (sofia/internal/+41793940965 at bluesip.net) Running State Change CS_EXECUTE
2012-05-24 23:08:45.840749 [DEBUG] switch_core_state_machine.c:417 (sofia/internal/+41793940965 at bluesip.net) State EXECUTE
2012-05-24 23:08:45.840749 [DEBUG] mod_sofia.c:241 sofia/internal/+41793940965 at bluesip.net SOFIA EXECUTE
2012-05-24 23:08:45.840749 [DEBUG] switch_core_state_machine.c:192 sofia/internal/+41793940965 at bluesip.net Standard EXECUTE
EXECUTE sofia/internal/+41793940965 at bluesip.net transfer(kb-1002 XML default)
2012-05-24 23:08:45.840749 [DEBUG] switch_ivr.c:1711 (sofia/internal/+41793940965 at bluesip.net) State Change CS_EXECUTE -> CS_ROUTING
2012-05-24 23:08:45.840749 [DEBUG] switch_core_session.c:1182 Send signal sofia/internal/+41793940965 at bluesip.net [BREAK]
2012-05-24 23:08:45.840749 [DEBUG] switch_core_session.c:731 Send signal sofia/internal/+41793940965 at bluesip.net [BREAK]
2012-05-24 23:08:45.840749 [NOTICE] switch_ivr.c:1717 Transfer sofia/internal/+41793940965 at bluesip.net to XML[kb-1002 at default]
2012-05-24 23:08:45.840749 [DEBUG] switch_core_state_machine.c:417 (sofia/internal/+41793940965 at bluesip.net) State EXECUTE going to sleep
2012-05-24 23:08:45.840749 [DEBUG] switch_core_state_machine.c:362 (sofia/internal/+41793940965 at bluesip.net) Running State Change CS_ROUTING
2012-05-24 23:08:45.840749 [DEBUG] switch_core_state_machine.c:410 (sofia/internal/+41793940965 at bluesip.net) State ROUTING
2012-05-24 23:08:45.840749 [DEBUG] mod_sofia.c:148 sofia/internal/+41793940965 at bluesip.net SOFIA ROUTING
2012-05-24 23:08:45.840749 [DEBUG] switch_core_state_machine.c:104 sofia/internal/+41793940965 at bluesip.net Standard ROUTING
2012-05-24 23:08:45.840749 [INFO] mod_dialplan_xml.c:485 Processing +41793940965 <+41793940965>->kb-1002 in context default
Dialplan: sofia/internal/+41793940965 at bluesip.net parsing [default->vbox] continue=false
Dialplan: sofia/internal/+41793940965 at bluesip.net Regex (FAIL) [vbox] destination_number(kb-1002) =~ /^vb-([0-9][0-9][0-9])$/ break=on-false
Dialplan: sofia/internal/+41793940965 at bluesip.net parsing [default->vmenu] continue=false
Dialplan: sofia/internal/+41793940965 at bluesip.net Regex (FAIL) [vmenu] destination_number(kb-1002) =~ /^vm-([0-9][0-9][0-9][0-9])$/ break=on-false
Dialplan: sofia/internal/+41793940965 at bluesip.net parsing [default->kbridge] continue=false
Dialplan: sofia/internal/+41793940965 at bluesip.net Regex (PASS) [kbridge] destination_number(kb-1002) =~ /^kb-(.+)$/ break=on-false
Dialplan: sofia/internal/+41793940965 at bluesip.net Action set(proxy_media=true) 
Dialplan: sofia/internal/+41793940965 at bluesip.net Action set(call_timeout=50) 
Dialplan: sofia/internal/+41793940965 at bluesip.net Action set(continue_on_fail=true) 
Dialplan: sofia/internal/+41793940965 at bluesip.net Action set(hangup_after_bridge=true) 
Dialplan: sofia/internal/+41793940965 at bluesip.net Action set(sip_invite_domain=78.138.90.58) 
Dialplan: sofia/internal/+41793940965 at bluesip.net Action export(sip_contact_user=ufs) 
Dialplan: sofia/internal/+41793940965 at bluesip.net Action bridge(sofia/78.138.90.58/1002 at 78.138.90.58:5060) 
Dialplan: sofia/internal/+41793940965 at bluesip.net Action answer() 
Dialplan: sofia/internal/+41793940965 at bluesip.net Action voicemail(default ${domain_name} 1002) 
2012-05-24 23:08:45.840749 [DEBUG] switch_core_state_machine.c:154 (sofia/internal/+41793940965 at bluesip.net) State Change CS_ROUTING -> CS_EXECUTE
2012-05-24 23:08:45.840749 [DEBUG] switch_core_session.c:1182 Send signal sofia/internal/+41793940965 at bluesip.net [BREAK]
2012-05-24 23:08:45.840749 [DEBUG] switch_core_state_machine.c:410 (sofia/internal/+41793940965 at bluesip.net) State ROUTING going to sleep
2012-05-24 23:08:45.840749 [DEBUG] switch_core_state_machine.c:362 (sofia/internal/+41793940965 at bluesip.net) Running State Change CS_EXECUTE
2012-05-24 23:08:45.840749 [DEBUG] switch_core_state_machine.c:417 (sofia/internal/+41793940965 at bluesip.net) State EXECUTE
2012-05-24 23:08:45.840749 [DEBUG] mod_sofia.c:241 sofia/internal/+41793940965 at bluesip.net SOFIA EXECUTE
2012-05-24 23:08:45.840749 [DEBUG] switch_core_state_machine.c:192 sofia/internal/+41793940965 at bluesip.net Standard EXECUTE
EXECUTE sofia/internal/+41793940965 at bluesip.net set(proxy_media=true)
2012-05-24 23:08:45.840749 [DEBUG] mod_dptools.c:1281 sofia/internal/+41793940965 at bluesip.net SET [proxy_media]=[true]
EXECUTE sofia/internal/+41793940965 at bluesip.net set(call_timeout=50)
2012-05-24 23:08:45.840749 [DEBUG] mod_dptools.c:1281 sofia/internal/+41793940965 at bluesip.net SET [call_timeout]=[50]
EXECUTE sofia/internal/+41793940965 at bluesip.net set(continue_on_fail=true)
2012-05-24 23:08:45.840749 [DEBUG] mod_dptools.c:1281 sofia/internal/+41793940965 at bluesip.net SET [continue_on_fail]=[true]
EXECUTE sofia/internal/+41793940965 at bluesip.net set(hangup_after_bridge=true)
2012-05-24 23:08:45.840749 [DEBUG] mod_dptools.c:1281 sofia/internal/+41793940965 at bluesip.net SET [hangup_after_bridge]=[true]
EXECUTE sofia/internal/+41793940965 at bluesip.net set(sip_invite_domain=78.138.90.58)
2012-05-24 23:08:45.840749 [DEBUG] mod_dptools.c:1281 sofia/internal/+41793940965 at bluesip.net SET [sip_invite_domain]=[78.138.90.58]
EXECUTE sofia/internal/+41793940965 at bluesip.net export(sip_contact_user=ufs)
2012-05-24 23:08:45.840749 [DEBUG] switch_channel.c:1093 EXPORT (export_vars) [sip_contact_user]=[ufs]
EXECUTE sofia/internal/+41793940965 at bluesip.net bridge(sofia/78.138.90.58/1002 at 78.138.90.58:5060)
2012-05-24 23:08:45.840749 [DEBUG] switch_channel.c:1047 sofia/internal/+41793940965 at bluesip.net EXPORTING[export_vars] [sip_contact_user]=[ufs] to event
2012-05-24 23:08:45.840749 [DEBUG] switch_ivr_originate.c:1884 Parsing global variables
2012-05-24 23:08:45.840749 [NOTICE] switch_channel.c:926 New Channel sofia/internal/1002 at 78.138.90.58:5060 [69ce1f0c-a5f5-11e1-a838-7b1f73a7ffcf]
2012-05-24 23:08:45.840749 [DEBUG] mod_sofia.c:4691 (sofia/internal/1002 at 78.138.90.58:5060) State Change CS_NEW -> CS_INIT
2012-05-24 23:08:45.840749 [DEBUG] switch_core_session.c:1182 Send signal sofia/internal/1002 at 78.138.90.58:5060 [BREAK]
2012-05-24 23:08:45.840749 [DEBUG] switch_core_state_machine.c:362 (sofia/internal/1002 at 78.138.90.58:5060) Running State Change CS_INIT
2012-05-24 23:08:45.840749 [DEBUG] switch_core_state_machine.c:401 (sofia/internal/1002 at 78.138.90.58:5060) State INIT
2012-05-24 23:08:45.840749 [DEBUG] mod_sofia.c:85 sofia/internal/1002 at 78.138.90.58:5060 SOFIA INIT
2012-05-24 23:08:45.840749 [DEBUG] sofia_glue.c:1871 sofia/internal/1002 at 78.138.90.58:5060 Patched SDP
---
v=0
o=root 20076 20076 IN IP4 217.74.179.28
s=session
c=IN IP4 217.74.179.28
t=0 0
m=audio 25626 RTP/AVP 8 0 18 111 3 101
a=rtpmap:8 PCMA/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=rtpmap:111 G726-32/8000
a=rtpmap:3 GSM/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=silenceSupp:off - - - -
a=ptime:20

+++
v=0
o=FreeSWITCH 1629510322 1629510323 IN IP4 78.138.90.58
s=FreeSWITCH
c=IN IP4 78.138.90.58
t=0 0
m=audio 31178 RTP/AVP 8 0 18 111 3 101
a=rtpmap:8 PCMA/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=rtpmap:111 G726-32/8000
a=rtpmap:3 GSM/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=silenceSupp:off - - - -
a=ptime:20

send 1340 bytes to udp/[78.138.90.58]:5060 at 23:08:45.852280:
   ------------------------------------------------------------------------
   INVITE sip:1002 at 78.138.90.58:5060 SIP/2.0
   Via: SIP/2.0/UDP 78.138.90.58:6090;rport;branch=z9hG4bKapDgFeegKyHXK
   Max-Forwards: 67
   From: "+41793940965" <sip:+41793940965 at 78.138.90.58>;tag=tgNrSS1jQFS8F
   To: <sip:1002 at 78.138.90.58:5060>
   Call-ID: 412c8312-2098-1230-8985-00163e6bb553
   CSeq: 28614398 INVITE
   Contact: <sip:ufs at 78.138.90.58:6090>
   User-Agent: FreeSWITCH-mod_sofia/1.1.beta1-git-8edfd01 2012-03-24 05-16-47 +0000
   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, UPDATE, INFO, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
   Supported: timer, 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: 372
   P-hint: USRLOC
   X-FS-Support: update_display,send_info
   Remote-Party-ID: "+41793940965" <sip:+41793940965 at 78.138.90.58>;party=calling;screen=yes;privacy=off
   
   v=0
   o=FreeSWITCH 1629510322 1629510323 IN IP4 78.138.90.58
   s=FreeSWITCH
   c=IN IP4 78.138.90.58
   t=0 0
   m=audio 31178 RTP/AVP 8 0 18 111 3 101
   a=rtpmap:8 PCMA/8000
   a=rtpmap:0 PCMU/8000
   a=rtpmap:18 G729/8000
   a=fmtp:18 annexb=no
   a=rtpmap:111 G726-32/8000
   a=rtpmap:3 GSM/8000
   a=rtpmap:101 telephone-event/8000
   a=fmtp:101 0-16
   a=silenceSupp:off - - - -
   a=ptime:20
   ------------------------------------------------------------------------
2012-05-24 23:08:45.840749 [DEBUG] switch_core_session.c:877 Send signal sofia/internal/1002 at 78.138.90.58:5060 [BREAK]
2012-05-24 23:08:45.840749 [DEBUG] mod_sofia.c:125 (sofia/internal/1002 at 78.138.90.58:5060) State Change CS_INIT -> CS_ROUTING
2012-05-24 23:08:45.840749 [DEBUG] switch_core_session.c:1182 Send signal sofia/internal/1002 at 78.138.90.58:5060 [BREAK]
2012-05-24 23:08:45.840749 [DEBUG] switch_core_state_machine.c:401 (sofia/internal/1002 at 78.138.90.58:5060) State INIT going to sleep
2012-05-24 23:08:45.840749 [DEBUG] switch_core_state_machine.c:362 (sofia/internal/1002 at 78.138.90.58:5060) Running State Change CS_ROUTING
2012-05-24 23:08:45.840749 [DEBUG] switch_channel.c:1886 (sofia/internal/1002 at 78.138.90.58:5060) Callstate Change DOWN -> RINGING
2012-05-24 23:08:45.840749 [DEBUG] switch_core_state_machine.c:410 (sofia/internal/1002 at 78.138.90.58:5060) State ROUTING
2012-05-24 23:08:45.840749 [DEBUG] mod_sofia.c:148 sofia/internal/1002 at 78.138.90.58:5060 SOFIA ROUTING
2012-05-24 23:08:45.840749 [DEBUG] switch_ivr_originate.c:66 (sofia/internal/1002 at 78.138.90.58:5060) State Change CS_ROUTING -> CS_CONSUME_MEDIA
2012-05-24 23:08:45.840749 [DEBUG] switch_core_session.c:1182 Send signal sofia/internal/1002 at 78.138.90.58:5060 [BREAK]
2012-05-24 23:08:45.840749 [DEBUG] switch_core_state_machine.c:410 (sofia/internal/1002 at 78.138.90.58:5060) State ROUTING going to sleep
2012-05-24 23:08:45.840749 [DEBUG] switch_core_state_machine.c:362 (sofia/internal/1002 at 78.138.90.58:5060) Running State Change CS_CONSUME_MEDIA
2012-05-24 23:08:45.840749 [DEBUG] switch_core_state_machine.c:429 (sofia/internal/1002 at 78.138.90.58:5060) State CONSUME_MEDIA
2012-05-24 23:08:45.840749 [DEBUG] switch_core_state_machine.c:429 (sofia/internal/1002 at 78.138.90.58:5060) State CONSUME_MEDIA going to sleep
2012-05-24 23:08:45.840749 [DEBUG] sofia.c:5532 Channel sofia/internal/1002 at 78.138.90.58:5060 entering state [calling][0]
recv 365 bytes from udp/[78.138.90.58]:5060 at 23:08:45.964309:
   ------------------------------------------------------------------------
   SIP/2.0 100 trying -- your call is important to us
   Via: SIP/2.0/UDP 78.138.90.58:6090;rport=6090;branch=z9hG4bKapDgFeegKyHXK
   From: "+41793940965" <sip:+41793940965 at 78.138.90.58>;tag=tgNrSS1jQFS8F
   To: <sip:1002 at 78.138.90.58:5060>
   Call-ID: 412c8312-2098-1230-8985-00163e6bb553
   CSeq: 28614398 INVITE
   Server: kamailio (3.1.5 (x86_64/linux))
   Content-Length: 0
   
   ------------------------------------------------------------------------
recv 496 bytes from udp/[78.138.90.58]:5060 at 23:08:47.414766:
   ------------------------------------------------------------------------
   SIP/2.0 180 Ringing
   Via: SIP/2.0/UDP 78.138.90.58:6090;rport=6090;branch=z9hG4bKapDgFeegKyHXK
   Record-Route: <sip:78.138.90.58;lr;nat=yes>
   Call-ID: 412c8312-2098-1230-8985-00163e6bb553
   From: "+41793940965" <sip:+41793940965 at 78.138.90.58>;tag=tgNrSS1jQFS8F
   To: <sip:1002 at 78.138.90.58>;tag=qCSaB2YJOAsAr2dJ-RWNMhPoDdn1RFvs
   CSeq: 28614398 INVITE
   Contact: "Mama" <sip:1002 at 192.168.178.1:6060;transport=UDP>
   Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, INFO, REGISTER
   Content-Length:  0
   
   ------------------------------------------------------------------------
2012-05-24 23:08:47.400741 [DEBUG] switch_core_session.c:877 Send signal sofia/internal/1002 at 78.138.90.58:5060 [BREAK]
2012-05-24 23:08:47.400741 [DEBUG] switch_core_session.c:877 Send signal sofia/internal/1002 at 78.138.90.58:5060 [BREAK]
2012-05-24 23:08:47.400741 [DEBUG] sofia.c:5532 Channel sofia/internal/1002 at 78.138.90.58:5060 entering state [proceeding][180]
2012-05-24 23:08:47.400741 [NOTICE] sofia.c:5624 Ring-Ready sofia/internal/1002 at 78.138.90.58:5060!
send 995 bytes to udp/[217.74.179.29]:5060 at 23:08:47.416538:
   ------------------------------------------------------------------------
   SIP/2.0 180 Ringing
   Via: SIP/2.0/UDP 217.74.179.29;branch=z9hG4bK0dc9.284b9a03.0
   Via: SIP/2.0/UDP 217.74.179.28:5060;branch=z9hG4bK086622bb;rport=5060
   Record-Route: <sip:217.74.179.29;ftag=as00589402;lr=on>
   From: "+41793940965" <sip:+41793940965 at bluesip.net>;tag=as00589402
   To: <sip:+498972101087914 at bluesip.net>;tag=S7UZQygFt62Nm
   Call-ID: 4253e4bd2d624caf7f18faf85390b68f at bluesip.net
   CSeq: 102 INVITE
   Contact: <sip:+498972101087914 at 78.138.90.58:6090;transport=udp>
   User-Agent: FreeSWITCH-mod_sofia/1.1.beta1-git-8edfd01 2012-03-24 05-16-47 +0000
   Accept: application/sdp
   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, UPDATE, INFO, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
   Supported: timer, precondition, path, replaces
   Allow-Events: talk, hold, presence, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
   Content-Length: 0
   Remote-Party-ID: "Outbound Call" <sip:1002 at bluesip.net>;party=calling;privacy=off;screen=no
   
   ------------------------------------------------------------------------
2012-05-24 23:08:47.400741 [DEBUG] switch_core_session.c:877 Send signal sofia/internal/+41793940965 at bluesip.net [BREAK]
2012-05-24 23:08:47.400741 [NOTICE] mod_sofia.c:2514 Ring-Ready sofia/internal/+41793940965 at bluesip.net!
2012-05-24 23:08:47.400741 [DEBUG] sofia.c:5532 Channel sofia/internal/+41793940965 at bluesip.net entering state [early][180]
2012-05-24 23:08:47.400741 [DEBUG] switch_core_session.c:731 Send signal sofia/internal/+41793940965 at bluesip.net [BREAK]
2012-05-24 23:08:47.400741 [NOTICE] switch_ivr_originate.c:483 Ring Ready sofia/internal/+41793940965 at bluesip.net!
recv 496 bytes from udp/[78.138.90.58]:5060 at 23:08:47.804967:
   ------------------------------------------------------------------------
   SIP/2.0 180 Ringing
   Via: SIP/2.0/UDP 78.138.90.58:6090;rport=6090;branch=z9hG4bKapDgFeegKyHXK
   Record-Route: <sip:78.138.90.58;lr=on;nat=yes>
   From: "+41793940965" <sip:+41793940965 at 78.138.90.58>;tag=tgNrSS1jQFS8F
   To: <sip:1002 at 78.138.90.58:5060>;tag=549D2DD03BBA7C67
   Call-ID: 412c8312-2098-1230-8985-00163e6bb553
   CSeq: 28614398 INVITE
   Contact: <sip:1002 at 41.34.123.243;uniq=6AC0DF4D2E498C8ACA82CB22226B9>
   User-Agent: AVM FRITZ!Box Fon WLAN 7170 29.04.80 (Jan 27 2010)
   Content-Length: 0
   
   ------------------------------------------------------------------------
2012-05-24 23:08:47.800746 [DEBUG] switch_core_session.c:877 Send signal sofia/internal/1002 at 78.138.90.58:5060 [BREAK]
2012-05-24 23:08:47.800746 [DEBUG] switch_core_session.c:877 Send signal sofia/internal/1002 at 78.138.90.58:5060 [BREAK]
2012-05-24 23:08:47.800746 [DEBUG] sofia.c:5532 Channel sofia/internal/1002 at 78.138.90.58:5060 entering state [proceeding][180]
recv 496 bytes from udp/[78.138.90.58]:5060 at 23:08:48.182098:
   ------------------------------------------------------------------------
   SIP/2.0 180 Ringing
   Via: SIP/2.0/UDP 78.138.90.58:6090;rport=6090;branch=z9hG4bKapDgFeegKyHXK
   Record-Route: <sip:78.138.90.58;lr;nat=yes>
   Call-ID: 412c8312-2098-1230-8985-00163e6bb553
   From: "+41793940965" <sip:+41793940965 at 78.138.90.58>;tag=tgNrSS1jQFS8F
   To: <sip:1002 at 78.138.90.58>;tag=qCSaB2YJOAsAr2dJ-RWNMhPoDdn1RFvs
   CSeq: 28614398 INVITE
   Contact: "Mama" <sip:1002 at 192.168.178.1:6060;transport=UDP>
   Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, INFO, REGISTER
   Content-Length:  0
   
   ------------------------------------------------------------------------
2012-05-24 23:08:48.180741 [DEBUG] switch_core_session.c:877 Send signal sofia/internal/1002 at 78.138.90.58:5060 [BREAK]
2012-05-24 23:08:48.180741 [DEBUG] switch_core_session.c:877 Send signal sofia/internal/1002 at 78.138.90.58:5060 [BREAK]
2012-05-24 23:08:48.180741 [DEBUG] sofia.c:5532 Channel sofia/internal/1002 at 78.138.90.58:5060 entering state [proceeding][180]
recv 496 bytes from udp/[78.138.90.58]:5060 at 23:08:48.417403:
   ------------------------------------------------------------------------
   SIP/2.0 180 Ringing
   Via: SIP/2.0/UDP 78.138.90.58:6090;rport=6090;branch=z9hG4bKapDgFeegKyHXK
   Record-Route: <sip:78.138.90.58;lr=on;nat=yes>
   From: "+41793940965" <sip:+41793940965 at 78.138.90.58>;tag=tgNrSS1jQFS8F
   To: <sip:1002 at 78.138.90.58:5060>;tag=549D2DD03BBA7C67
   Call-ID: 412c8312-2098-1230-8985-00163e6bb553
   CSeq: 28614398 INVITE
   Contact: <sip:1002 at 41.34.123.243;uniq=6AC0DF4D2E498C8ACA82CB22226B9>
   User-Agent: AVM FRITZ!Box Fon WLAN 7170 29.04.80 (Jan 27 2010)
   Content-Length: 0
   
   ------------------------------------------------------------------------
2012-05-24 23:08:48.400744 [DEBUG] switch_core_session.c:877 Send signal sofia/internal/1002 at 78.138.90.58:5060 [BREAK]
2012-05-24 23:08:48.400744 [DEBUG] switch_core_session.c:877 Send signal sofia/internal/1002 at 78.138.90.58:5060 [BREAK]
2012-05-24 23:08:48.400744 [DEBUG] sofia.c:5532 Channel sofia/internal/1002 at 78.138.90.58:5060 entering state [proceeding][180]
recv 496 bytes from udp/[78.138.90.58]:5060 at 23:08:49.292599:
   ------------------------------------------------------------------------
   SIP/2.0 180 Ringing
   Via: SIP/2.0/UDP 78.138.90.58:6090;rport=6090;branch=z9hG4bKapDgFeegKyHXK
   Record-Route: <sip:78.138.90.58;lr=on;nat=yes>
   From: "+41793940965" <sip:+41793940965 at 78.138.90.58>;tag=tgNrSS1jQFS8F
   To: <sip:1002 at 78.138.90.58:5060>;tag=549D2DD03BBA7C67
   Call-ID: 412c8312-2098-1230-8985-00163e6bb553
   CSeq: 28614398 INVITE
   Contact: <sip:1002 at 41.34.123.243;uniq=6AC0DF4D2E498C8ACA82CB22226B9>
   User-Agent: AVM FRITZ!Box Fon WLAN 7170 29.04.80 (Jan 27 2010)
   Content-Length: 0
   
   ------------------------------------------------------------------------
2012-05-24 23:08:49.280742 [DEBUG] switch_core_session.c:877 Send signal sofia/internal/1002 at 78.138.90.58:5060 [BREAK]
2012-05-24 23:08:49.280742 [DEBUG] switch_core_session.c:877 Send signal sofia/internal/1002 at 78.138.90.58:5060 [BREAK]
2012-05-24 23:08:49.280742 [DEBUG] sofia.c:5532 Channel sofia/internal/1002 at 78.138.90.58:5060 entering state [proceeding][180]
recv 478 bytes from udp/[217.74.179.29]:5060 at 23:09:15.066012:
   ------------------------------------------------------------------------
   CANCEL sip:gw+trunk_1000 at 78.138.90.58:6090;transport=udp;gw=trunk_1000 SIP/2.0
   Via: SIP/2.0/UDP 217.74.179.29;branch=z9hG4bK0dc9.284b9a03.0
   Via: SIP/2.0/UDP 217.74.179.28:5060;branch=z9hG4bK086622bb;rport=5060
   From: "+41793940965" <sip:+41793940965 at bluesip.net>;tag=as00589402
   To: <sip:+498972101087914 at bluesip.net>
   Call-ID: 4253e4bd2d624caf7f18faf85390b68f at bluesip.net
   CSeq: 102 CANCEL
   User-Agent: blueSIP PSTN GW
   Max-Forwards: 69
   Content-Length: 0
   P-hint: USRLOC
   
   ------------------------------------------------------------------------
send 369 bytes to udp/[217.74.179.29]:5060 at 23:09:15.066176:
   ------------------------------------------------------------------------
   SIP/2.0 200 OK
   Via: SIP/2.0/UDP 217.74.179.29;branch=z9hG4bK0dc9.284b9a03.0
   Via: SIP/2.0/UDP 217.74.179.28:5060;branch=z9hG4bK086622bb;rport=5060
   From: "+41793940965" <sip:+41793940965 at bluesip.net>;tag=as00589402
   To: <sip:+498972101087914 at bluesip.net>;tag=S7UZQygFt62Nm
   Call-ID: 4253e4bd2d624caf7f18faf85390b68f at bluesip.net
   CSeq: 102 CANCEL
   Content-Length: 0
   
   ------------------------------------------------------------------------
send 766 bytes to udp/[217.74.179.29]:5060 at 23:09:15.066289:
   ------------------------------------------------------------------------
   SIP/2.0 487 Request Terminated
   Via: SIP/2.0/UDP 217.74.179.29;branch=z9hG4bK0dc9.284b9a03.0
   Via: SIP/2.0/UDP 217.74.179.28:5060;branch=z9hG4bK086622bb;rport=5060
   From: "+41793940965" <sip:+41793940965 at bluesip.net>;tag=as00589402
   To: <sip:+498972101087914 at bluesip.net>;tag=S7UZQygFt62Nm
   Call-ID: 4253e4bd2d624caf7f18faf85390b68f at bluesip.net
   CSeq: 102 INVITE
   User-Agent: FreeSWITCH-mod_sofia/1.1.beta1-git-8edfd01 2012-03-24 05-16-47 +0000
   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, UPDATE, INFO, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
   Supported: timer, precondition, path, replaces
   Allow-Events: talk, hold, presence, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
   Content-Length: 0
   
   ------------------------------------------------------------------------
2012-05-24 23:09:15.060742 [DEBUG] switch_core_session.c:877 Send signal sofia/internal/+41793940965 at bluesip.net [BREAK]
2012-05-24 23:09:15.060742 [DEBUG] switch_core_session.c:877 Send signal sofia/internal/+41793940965 at bluesip.net [BREAK]
2012-05-24 23:09:15.060742 [DEBUG] switch_core_session.c:877 Send signal sofia/internal/+41793940965 at bluesip.net [BREAK]
2012-05-24 23:09:15.060742 [DEBUG] sofia.c:5532 Channel sofia/internal/+41793940965 at bluesip.net entering state [terminated][487]
2012-05-24 23:09:15.060742 [DEBUG] switch_channel.c:2848 (sofia/internal/+41793940965 at bluesip.net) Callstate Change RINGING -> HANGUP
2012-05-24 23:09:15.060742 [NOTICE] sofia.c:6301 Hangup sofia/internal/+41793940965 at bluesip.net [CS_EXECUTE] [ORIGINATOR_CANCEL]
2012-05-24 23:09:15.060742 [DEBUG] switch_channel.c:2871 Send signal sofia/internal/+41793940965 at bluesip.net [KILL]
2012-05-24 23:09:15.060742 [DEBUG] switch_core_session.c:1182 Send signal sofia/internal/+41793940965 at bluesip.net [BREAK]
2012-05-24 23:09:15.060742 [DEBUG] switch_channel.c:2848 (sofia/internal/1002 at 78.138.90.58:5060) Callstate Change RINGING -> HANGUP
2012-05-24 23:09:15.060742 [NOTICE] switch_ivr_originate.c:3182 Hangup sofia/internal/1002 at 78.138.90.58:5060 [CS_CONSUME_MEDIA] [ORIGINATOR_CANCEL]
2012-05-24 23:09:15.060742 [DEBUG] switch_channel.c:2871 Send signal sofia/internal/1002 at 78.138.90.58:5060 [KILL]
2012-05-24 23:09:15.060742 [DEBUG] switch_core_state_machine.c:362 (sofia/internal/1002 at 78.138.90.58:5060) Running State Change CS_HANGUP
2012-05-24 23:09:15.060742 [DEBUG] switch_core_state_machine.c:602 (sofia/internal/1002 at 78.138.90.58:5060) State HANGUP
2012-05-24 23:09:15.060742 [DEBUG] mod_sofia.c:469 Channel sofia/internal/1002 at 78.138.90.58:5060 hanging up, cause: ORIGINATOR_CANCEL
2012-05-24 23:09:15.060742 [DEBUG] mod_sofia.c:523 Sending CANCEL to sofia/internal/1002 at 78.138.90.58:5060
2012-05-24 23:09:15.060742 [DEBUG] switch_core_state_machine.c:47 sofia/internal/1002 at 78.138.90.58:5060 Standard HANGUP, cause: ORIGINATOR_CANCEL
2012-05-24 23:09:15.060742 [DEBUG] switch_core_state_machine.c:602 (sofia/internal/1002 at 78.138.90.58:5060) State HANGUP going to sleep
2012-05-24 23:09:15.060742 [DEBUG] switch_core_state_machine.c:393 (sofia/internal/1002 at 78.138.90.58:5060) State Change CS_HANGUP -> CS_REPORTING
2012-05-24 23:09:15.060742 [DEBUG] switch_core_session.c:1182 Send signal sofia/internal/1002 at 78.138.90.58:5060 [BREAK]
2012-05-24 23:09:15.060742 [DEBUG] switch_core_state_machine.c:362 (sofia/internal/1002 at 78.138.90.58:5060) Running State Change CS_REPORTING
2012-05-24 23:09:15.060742 [DEBUG] switch_core_state_machine.c:662 (sofia/internal/1002 at 78.138.90.58:5060) State REPORTING
2012-05-24 23:09:15.060742 [DEBUG] switch_core_state_machine.c:79 sofia/internal/1002 at 78.138.90.58:5060 Standard REPORTING, cause: ORIGINATOR_CANCEL
2012-05-24 23:09:15.060742 [DEBUG] switch_core_state_machine.c:662 (sofia/internal/1002 at 78.138.90.58:5060) State REPORTING going to sleep
2012-05-24 23:09:15.060742 [DEBUG] switch_core_state_machine.c:387 (sofia/internal/1002 at 78.138.90.58:5060) State Change CS_REPORTING -> CS_DESTROY
2012-05-24 23:09:15.060742 [DEBUG] switch_core_session.c:1182 Send signal sofia/internal/1002 at 78.138.90.58:5060 [BREAK]
2012-05-24 23:09:15.060742 [DEBUG] switch_core_session.c:1382 Session 9 (sofia/internal/1002 at 78.138.90.58:5060) Locked, Waiting on external entities
2012-05-24 23:09:15.060742 [DEBUG] switch_core_session.c:1182 Send signal sofia/internal/1002 at 78.138.90.58:5060 [BREAK]
2012-05-24 23:09:15.060742 [DEBUG] switch_ivr_originate.c:3358 Originate Cancelled by originator termination Cause: 487 [ORIGINATOR_CANCEL]
2012-05-24 23:09:15.060742 [NOTICE] switch_core_session.c:1400 Session 9 (sofia/internal/1002 at 78.138.90.58:5060) Ended
2012-05-24 23:09:15.060742 [NOTICE] switch_core_session.c:1402 Close Channel sofia/internal/1002 at 78.138.90.58:5060 [CS_DESTROY]
2012-05-24 23:09:15.060742 [DEBUG] switch_core_state_machine.c:491 (sofia/internal/1002 at 78.138.90.58:5060) Callstate Change HANGUP -> DOWN
2012-05-24 23:09:15.060742 [DEBUG] switch_core_state_machine.c:494 (sofia/internal/1002 at 78.138.90.58:5060) Running State Change CS_DESTROY
2012-05-24 23:09:15.060742 [DEBUG] switch_core_state_machine.c:504 (sofia/internal/1002 at 78.138.90.58:5060) State DESTROY
2012-05-24 23:09:15.060742 [DEBUG] mod_sofia.c:374 sofia/internal/1002 at 78.138.90.58:5060 SOFIA DESTROY
2012-05-24 23:09:15.060742 [DEBUG] switch_core_state_machine.c:86 sofia/internal/1002 at 78.138.90.58:5060 Standard DESTROY
2012-05-24 23:09:15.060742 [DEBUG] switch_core_state_machine.c:504 (sofia/internal/1002 at 78.138.90.58:5060) State DESTROY going to sleep
2012-05-24 23:09:15.060742 [INFO] mod_dptools.c:2922 Originate Failed.  Cause: ORIGINATOR_CANCEL
2012-05-24 23:09:15.060742 [DEBUG] switch_core_session.c:2287 sofia/internal/+41793940965 at bluesip.net skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)
2012-05-24 23:09:15.060742 [DEBUG] switch_core_state_machine.c:417 (sofia/internal/+41793940965 at bluesip.net) State EXECUTE going to sleep
2012-05-24 23:09:15.060742 [DEBUG] switch_core_state_machine.c:362 (sofia/internal/+41793940965 at bluesip.net) Running State Change CS_HANGUP
2012-05-24 23:09:15.060742 [DEBUG] switch_core_state_machine.c:602 (sofia/internal/+41793940965 at bluesip.net) State HANGUP
2012-05-24 23:09:15.060742 [DEBUG] mod_sofia.c:469 Channel sofia/internal/+41793940965 at bluesip.net hanging up, cause: ORIGINATOR_CANCEL
2012-05-24 23:09:15.060742 [DEBUG] switch_core_state_machine.c:47 sofia/internal/+41793940965 at bluesip.net Standard HANGUP, cause: ORIGINATOR_CANCEL
2012-05-24 23:09:15.060742 [DEBUG] switch_core_state_machine.c:602 (sofia/internal/+41793940965 at bluesip.net) State HANGUP going to sleep
2012-05-24 23:09:15.060742 [DEBUG] switch_core_state_machine.c:393 (sofia/internal/+41793940965 at bluesip.net) State Change CS_HANGUP -> CS_REPORTING
2012-05-24 23:09:15.060742 [DEBUG] switch_core_session.c:1182 Send signal sofia/internal/+41793940965 at bluesip.net [BREAK]
2012-05-24 23:09:15.060742 [DEBUG] switch_core_state_machine.c:362 (sofia/internal/+41793940965 at bluesip.net) Running State Change CS_REPORTING
2012-05-24 23:09:15.060742 [DEBUG] switch_core_state_machine.c:662 (sofia/internal/+41793940965 at bluesip.net) State REPORTING
send 383 bytes to udp/[78.138.90.58]:5060 at 23:09:15.087695:
   ------------------------------------------------------------------------
   CANCEL sip:1002 at 78.138.90.58:5060 SIP/2.0
   Via: SIP/2.0/UDP 78.138.90.58:6090;rport;branch=z9hG4bKapDgFeegKyHXK
   Max-Forwards: 67
   From: "+41793940965" <sip:+41793940965 at 78.138.90.58>;tag=tgNrSS1jQFS8F
   To: <sip:1002 at 78.138.90.58:5060>
   Call-ID: 412c8312-2098-1230-8985-00163e6bb553
   CSeq: 28614398 CANCEL
   Reason: FreeSWITCH;cause=487;text="ORIGINATOR_CANCEL"
   Content-Length: 0
   
   ------------------------------------------------------------------------
recv 408 bytes from udp/[217.74.179.29]:5060 at 23:09:15.094670:
   ------------------------------------------------------------------------
   ACK sip:gw+trunk_1000 at 78.138.90.58:6090;transport=udp;gw=trunk_1000 SIP/2.0
   Via: SIP/2.0/UDP 217.74.179.29;branch=z9hG4bK0dc9.284b9a03.0
   From: "+41793940965" <sip:+41793940965 at bluesip.net>;tag=as00589402
   Call-ID: 4253e4bd2d624caf7f18faf85390b68f at bluesip.net
   To: <sip:+498972101087914 at bluesip.net>;tag=S7UZQygFt62Nm
   CSeq: 102 ACK
   User-Agent: Sip EXpress router(0.9.7 (i386/linux))
   Content-Length: 0
   
   ------------------------------------------------------------------------
2012-05-24 23:09:15.120807 [DEBUG] switch_core_state_machine.c:79 sofia/internal/+41793940965 at bluesip.net Standard REPORTING, cause: ORIGINATOR_CANCEL
2012-05-24 23:09:15.120807 [DEBUG] switch_core_state_machine.c:662 (sofia/internal/+41793940965 at bluesip.net) State REPORTING going to sleep
2012-05-24 23:09:15.120807 [DEBUG] switch_core_state_machine.c:387 (sofia/internal/+41793940965 at bluesip.net) State Change CS_REPORTING -> CS_DESTROY
2012-05-24 23:09:15.120807 [DEBUG] switch_core_session.c:1182 Send signal sofia/internal/+41793940965 at bluesip.net [BREAK]
2012-05-24 23:09:15.120807 [DEBUG] switch_core_session.c:1382 Session 8 (sofia/internal/+41793940965 at bluesip.net) Locked, Waiting on external entities
2012-05-24 23:09:15.120807 [NOTICE] switch_core_session.c:1400 Session 8 (sofia/internal/+41793940965 at bluesip.net) Ended
2012-05-24 23:09:15.120807 [NOTICE] switch_core_session.c:1402 Close Channel sofia/internal/+41793940965 at bluesip.net [CS_DESTROY]
2012-05-24 23:09:15.120807 [DEBUG] switch_core_state_machine.c:491 (sofia/internal/+41793940965 at bluesip.net) Callstate Change HANGUP -> DOWN
2012-05-24 23:09:15.120807 [DEBUG] switch_core_state_machine.c:494 (sofia/internal/+41793940965 at bluesip.net) Running State Change CS_DESTROY
2012-05-24 23:09:15.120807 [DEBUG] switch_core_state_machine.c:504 (sofia/internal/+41793940965 at bluesip.net) State DESTROY
2012-05-24 23:09:15.120807 [DEBUG] mod_sofia.c:374 sofia/internal/+41793940965 at bluesip.net SOFIA DESTROY
2012-05-24 23:09:15.120807 [DEBUG] switch_core_state_machine.c:86 sofia/internal/+41793940965 at bluesip.net Standard DESTROY
2012-05-24 23:09:15.120807 [DEBUG] switch_core_state_machine.c:504 (sofia/internal/+41793940965 at bluesip.net) State DESTROY going to sleep
recv 378 bytes from udp/[78.138.90.58]:5060 at 23:09:15.135235:
   ------------------------------------------------------------------------
   SIP/2.0 200 canceling
   Via: SIP/2.0/UDP 78.138.90.58:6090;rport=6090;branch=z9hG4bKapDgFeegKyHXK
   From: "+41793940965" <sip:+41793940965 at 78.138.90.58>;tag=tgNrSS1jQFS8F
   To: <sip:1002 at 78.138.90.58:5060>;tag=a6a1c5f60faecf035a1ae5b6e96e979a-8c5b
   Call-ID: 412c8312-2098-1230-8985-00163e6bb553
   CSeq: 28614398 CANCEL
   Server: kamailio (3.1.5 (x86_64/linux))
   Content-Length: 0
   
   ------------------------------------------------------------------------
recv 446 bytes from udp/[78.138.90.58]:5060 at 23:09:16.419308:
   ------------------------------------------------------------------------
   SIP/2.0 487 Request Terminated
   Via: SIP/2.0/UDP 78.138.90.58:6090;rport=6090;branch=z9hG4bKapDgFeegKyHXK
   Record-Route: <sip:78.138.90.58;lr;nat=yes>
   Call-ID: 412c8312-2098-1230-8985-00163e6bb553
   From: "+41793940965" <sip:+41793940965 at 78.138.90.58>;tag=tgNrSS1jQFS8F
   To: <sip:1002 at 78.138.90.58>;tag=qCSaB2YJOAsAr2dJ-RWNMhPoDdn1RFvs
   CSeq: 28614398 INVITE
   Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, INFO, REGISTER
   Content-Length:  0
   
   ------------------------------------------------------------------------
send 354 bytes to udp/[78.138.90.58]:5060 at 23:09:16.419543:
   ------------------------------------------------------------------------
   ACK sip:1002 at 78.138.90.58:5060 SIP/2.0
   Via: SIP/2.0/UDP 78.138.90.58:6090;rport;branch=z9hG4bKapDgFeegKyHXK
   Max-Forwards: 67
   From: "+41793940965" <sip:+41793940965 at 78.138.90.58>;tag=tgNrSS1jQFS8F
   To: <sip:1002 at 78.138.90.58>;tag=qCSaB2YJOAsAr2dJ-RWNMhPoDdn1RFvs
   Call-ID: 412c8312-2098-1230-8985-00163e6bb553
   CSeq: 28614398 ACK
   Content-Length: 0
   
   ------------------------------------------------------------------------
recv 564 bytes from udp/[41.34.123.243]:58881 at 23:13:13.612418:
   ------------------------------------------------------------------------
   REGISTER sip:sip.pcfone.com:6090 SIP/2.0
   Via: SIP/2.0/UDP 192.168.178.26:58881;rport;branch=z9hG4bKPjHQIpv.ACHGbXD4IHrMr72LwZ8kYdlu-J
   Max-Forwards: 70
   From: "Sherif 1003" <sip:1002 at sip.pcfone.com>;tag=wOP.FVT4Crk8O5E.bB7BWUhNO6QG91IG
   To: "Sherif 1003" <sip:1002 at sip.pcfone.com>
   Call-ID: ohCjnPIlf85DIrNvetdMy.1RQM-rLj4e
   CSeq: 60926 REGISTER
   User-Agent: Telephone 1.0.2
   Contact: "Sherif 1003" <sip:1002 at 192.168.178.26:58881;ob>
   Expires: 300
   Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS
   Content-Length:  0
   
   ------------------------------------------------------------------------
send 744 bytes to udp/[41.34.123.243]:58881 at 23:13:13.613152:
   ------------------------------------------------------------------------
   SIP/2.0 401 Unauthorized
   Via: SIP/2.0/UDP 192.168.178.26:58881;rport=58881;branch=z9hG4bKPjHQIpv.ACHGbXD4IHrMr72LwZ8kYdlu-J;received=41.34.123.243
   From: "Sherif 1003" <sip:1002 at sip.pcfone.com>;tag=wOP.FVT4Crk8O5E.bB7BWUhNO6QG91IG
   To: "Sherif 1003" <sip:1002 at sip.pcfone.com>;tag=USeHUmjpmrFUB
   Call-ID: ohCjnPIlf85DIrNvetdMy.1RQM-rLj4e
   CSeq: 60926 REGISTER
   User-Agent: FreeSWITCH-mod_sofia/1.1.beta1-git-8edfd01 2012-03-24 05-16-47 +0000
   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, UPDATE, INFO, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
   Supported: timer, precondition, path, replaces
   WWW-Authenticate: Digest realm="sip.pcfone.com", nonce="09674764-a5f6-11e1-a83c-7b1f73a7ffcf", algorithm=MD5, qop="auth"
   Content-Length: 0
   
   ------------------------------------------------------------------------
recv 830 bytes from udp/[41.34.123.243]:58881 at 23:13:13.847746:
   ------------------------------------------------------------------------
   REGISTER sip:sip.pcfone.com:6090 SIP/2.0
   Via: SIP/2.0/UDP 192.168.178.26:58881;rport;branch=z9hG4bKPjFr7rIAdAJPRQd2GmrMrVib3KT3Rup0q4
   Max-Forwards: 70
   From: "Sherif 1003" <sip:1002 at sip.pcfone.com>;tag=wOP.FVT4Crk8O5E.bB7BWUhNO6QG91IG
   To: "Sherif 1003" <sip:1002 at sip.pcfone.com>
   Call-ID: ohCjnPIlf85DIrNvetdMy.1RQM-rLj4e
   CSeq: 60927 REGISTER
   User-Agent: Telephone 1.0.2
   Contact: "Sherif 1003" <sip:1002 at 192.168.178.26:58881;ob>
   Expires: 300
   Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS
   Authorization: Digest username="1002", realm="sip.pcfone.com", nonce="09674764-a5f6-11e1-a83c-7b1f73a7ffcf", uri="sip:sip.pcfone.com:6090", response="3bc0bb82a803ded3641704c4eb78d58f", algorithm=MD5, cnonce="sc3AJfhuev18Bs-L-6tORmI.RXlxnPOz", qop=auth, nc=00000001
   Content-Length:  0
   
   ------------------------------------------------------------------------
send 706 bytes to udp/[41.34.123.243]:58881 at 23:13:13.850197:
   ------------------------------------------------------------------------
   SIP/2.0 200 OK
   Via: SIP/2.0/UDP 192.168.178.26:58881;rport=58881;branch=z9hG4bKPjFr7rIAdAJPRQd2GmrMrVib3KT3Rup0q4;received=41.34.123.243
   From: "Sherif 1003" <sip:1002 at sip.pcfone.com>;tag=wOP.FVT4Crk8O5E.bB7BWUhNO6QG91IG
   To: "Sherif 1003" <sip:1002 at sip.pcfone.com>;tag=v279vF3SH15DQ
   Call-ID: ohCjnPIlf85DIrNvetdMy.1RQM-rLj4e
   CSeq: 60927 REGISTER
   Contact: <sip:1002 at 192.168.178.26:58881;ob>;expires=300
   Date: Thu, 24 May 2012 23:13:13 GMT
   User-Agent: FreeSWITCH-mod_sofia/1.1.beta1-git-8edfd01 2012-03-24 05-16-47 +0000
   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, UPDATE, INFO, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
   Supported: timer, precondition, path, replaces
   Content-Length: 0
   
   ------------------------------------------------------------------------
send 989 bytes to udp/[41.34.123.243]:58881 at 23:13:13.888717:
   ------------------------------------------------------------------------
   NOTIFY sip:1002 at 192.168.178.26:58881;ob SIP/2.0
   Via: SIP/2.0/UDP 78.138.90.58:6090;rport;branch=z9hG4bKBZ68g9yKg77FF
   Route: <sip:1002 at 41.34.123.243:58881>;ob
   Max-Forwards: 70
   From: <sip:1002 at 78.138.90.58>;tag=XB12yamXeav0j
   To: <sip:1002 at 78.138.90.58>
   Call-ID: e0efa252-2098-1230-8985-00163e6bb553
   CSeq: 28614532 NOTIFY
   Contact: <sip:mod_sofia at 78.138.90.58:6090>
   User-Agent: FreeSWITCH-mod_sofia/1.1.beta1-git-8edfd01 2012-03-24 05-16-47 +0000
   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, UPDATE, INFO, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
   Supported: timer, 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: 91
   
   Messages-Waiting: yes
   Message-Account: sip:1002 at 78.138.90.58
   Voice-Message: 1/0 (0/0)
   
   ------------------------------------------------------------------------
recv 308 bytes from udp/[41.34.123.243]:58881 at 23:13:14.298850:
   ------------------------------------------------------------------------
   SIP/2.0 200 OK
   Via: SIP/2.0/UDP 78.138.90.58:6090;rport=6090;received=78.138.90.58;branch=z9hG4bKBZ68g9yKg77FF
   Call-ID: e0efa252-2098-1230-8985-00163e6bb553
   From: <sip:1002 at 78.138.90.58>;tag=XB12yamXeav0j
   To: <sip:1002 at 78.138.90.58>;tag=z9hG4bKBZ68g9yKg77FF
   CSeq: 28614532 NOTIFY
   Content-Length:  0
   
   ------------------------------------------------------------------------
recv 564 bytes from udp/[41.34.123.243]:58881 at 23:18:09.067232:
   ------------------------------------------------------------------------
   REGISTER sip:sip.pcfone.com:6090 SIP/2.0
   Via: SIP/2.0/UDP 192.168.178.26:58881;rport;branch=z9hG4bKPjABTslmZIhiHYRtLqQn2erwmX19MVYHFC
   Max-Forwards: 70
   From: "Sherif 1003" <sip:1002 at sip.pcfone.com>;tag=JBSXL5WZLWbLzpvRFoTSP5x8qQslI1ST
   To: "Sherif 1003" <sip:1002 at sip.pcfone.com>
   Call-ID: ohCjnPIlf85DIrNvetdMy.1RQM-rLj4e
   CSeq: 60928 REGISTER
   User-Agent: Telephone 1.0.2
   Contact: "Sherif 1003" <sip:1002 at 192.168.178.26:58881;ob>
   Expires: 300
   Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS
   Content-Length:  0
   
   ------------------------------------------------------------------------
send 744 bytes to udp/[41.34.123.243]:58881 at 23:18:09.068121:
   ------------------------------------------------------------------------
   SIP/2.0 401 Unauthorized
   Via: SIP/2.0/UDP 192.168.178.26:58881;rport=58881;branch=z9hG4bKPjABTslmZIhiHYRtLqQn2erwmX19MVYHFC;received=41.34.123.243
   From: "Sherif 1003" <sip:1002 at sip.pcfone.com>;tag=JBSXL5WZLWbLzpvRFoTSP5x8qQslI1ST
   To: "Sherif 1003" <sip:1002 at sip.pcfone.com>;tag=ymtU0540BKjKe
   Call-ID: ohCjnPIlf85DIrNvetdMy.1RQM-rLj4e
   CSeq: 60928 REGISTER
   User-Agent: FreeSWITCH-mod_sofia/1.1.beta1-git-8edfd01 2012-03-24 05-16-47 +0000
   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, UPDATE, INFO, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
   Supported: timer, precondition, path, replaces
   WWW-Authenticate: Digest realm="sip.pcfone.com", nonce="b9821df4-a5f6-11e1-a83d-7b1f73a7ffcf", algorithm=MD5, qop="auth"
   Content-Length: 0
   
   ------------------------------------------------------------------------
recv 830 bytes from udp/[41.34.123.243]:58881 at 23:18:09.302944:
   ------------------------------------------------------------------------
   REGISTER sip:sip.pcfone.com:6090 SIP/2.0
   Via: SIP/2.0/UDP 192.168.178.26:58881;rport;branch=z9hG4bKPjRgtztcy63vMT97Jp5pH9n311li5EFeY-
   Max-Forwards: 70
   From: "Sherif 1003" <sip:1002 at sip.pcfone.com>;tag=JBSXL5WZLWbLzpvRFoTSP5x8qQslI1ST
   To: "Sherif 1003" <sip:1002 at sip.pcfone.com>
   Call-ID: ohCjnPIlf85DIrNvetdMy.1RQM-rLj4e
   CSeq: 60929 REGISTER
   User-Agent: Telephone 1.0.2
   Contact: "Sherif 1003" <sip:1002 at 192.168.178.26:58881;ob>
   Expires: 300
   Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS
   Authorization: Digest username="1002", realm="sip.pcfone.com", nonce="b9821df4-a5f6-11e1-a83d-7b1f73a7ffcf", uri="sip:sip.pcfone.com:6090", response="519c6301816b51f3bbb98d97a347e92b", algorithm=MD5, cnonce="sc3AJfhuev18Bs-L-6tORmI.RXlxnPOz", qop=auth, nc=00000001
   Content-Length:  0
   
   ------------------------------------------------------------------------
send 706 bytes to udp/[41.34.123.243]:58881 at 23:18:09.305217:
   ------------------------------------------------------------------------
   SIP/2.0 200 OK
   Via: SIP/2.0/UDP 192.168.178.26:58881;rport=58881;branch=z9hG4bKPjRgtztcy63vMT97Jp5pH9n311li5EFeY-;received=41.34.123.243
   From: "Sherif 1003" <sip:1002 at sip.pcfone.com>;tag=JBSXL5WZLWbLzpvRFoTSP5x8qQslI1ST
   To: "Sherif 1003" <sip:1002 at sip.pcfone.com>;tag=ZXKm20N48U85S
   Call-ID: ohCjnPIlf85DIrNvetdMy.1RQM-rLj4e
   CSeq: 60929 REGISTER
   Contact: <sip:1002 at 192.168.178.26:58881;ob>;expires=300
   Date: Thu, 24 May 2012 23:18:09 GMT
   User-Agent: FreeSWITCH-mod_sofia/1.1.beta1-git-8edfd01 2012-03-24 05-16-47 +0000
   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, UPDATE, INFO, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
   Supported: timer, precondition, path, replaces
   Content-Length: 0
   
   ------------------------------------------------------------------------
send 989 bytes to udp/[41.34.123.243]:58881 at 23:18:09.379987:
   ------------------------------------------------------------------------
   NOTIFY sip:1002 at 192.168.178.26:58881;ob SIP/2.0
   Via: SIP/2.0/UDP 78.138.90.58:6090;rport;branch=z9hG4bKc8Z1j4FQDgy2a
   Route: <sip:1002 at 41.34.123.243:58881>;ob
   Max-Forwards: 70
   From: <sip:1002 at 78.138.90.58>;tag=06cD4U6754yrN
   To: <sip:1002 at 78.138.90.58>
   Call-ID: 91100602-2099-1230-8985-00163e6bb553
   CSeq: 28614680 NOTIFY
   Contact: <sip:mod_sofia at 78.138.90.58:6090>
   User-Agent: FreeSWITCH-mod_sofia/1.1.beta1-git-8edfd01 2012-03-24 05-16-47 +0000
   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, UPDATE, INFO, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
   Supported: timer, 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: 91
   
   Messages-Waiting: yes
   Message-Account: sip:1002 at 78.138.90.58
   Voice-Message: 1/0 (0/0)
   
   ------------------------------------------------------------------------
recv 308 bytes from udp/[41.34.123.243]:58881 at 23:18:09.773629:
   ------------------------------------------------------------------------
   SIP/2.0 200 OK
   Via: SIP/2.0/UDP 78.138.90.58:6090;rport=6090;received=78.138.90.58;branch=z9hG4bKc8Z1j4FQDgy2a
   Call-ID: 91100602-2099-1230-8985-00163e6bb553
   From: <sip:1002 at 78.138.90.58>;tag=06cD4U6754yrN
   To: <sip:1002 at 78.138.90.58>;tag=z9hG4bKc8Z1j4FQDgy2a
   CSeq: 28614680 NOTIFY
   Content-Length:  0
   
   ------------------------------------------------------------------------
recv 564 bytes from udp/[41.34.123.243]:58881 at 23:23:04.522455:
   ------------------------------------------------------------------------
   REGISTER sip:sip.pcfone.com:6090 SIP/2.0
   Via: SIP/2.0/UDP 192.168.178.26:58881;rport;branch=z9hG4bKPjaCuKyaBi6ZqcsHyp5HhH3WthJStom0SK
   Max-Forwards: 70
   From: "Sherif 1003" <sip:1002 at sip.pcfone.com>;tag=qfUt1fQWuE-Ro3zWld6X0r6WidtD6B1q
   To: "Sherif 1003" <sip:1002 at sip.pcfone.com>
   Call-ID: ohCjnPIlf85DIrNvetdMy.1RQM-rLj4e
   CSeq: 60930 REGISTER
   User-Agent: Telephone 1.0.2
   Contact: "Sherif 1003" <sip:1002 at 192.168.178.26:58881;ob>
   Expires: 300
   Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS
   Content-Length:  0
   
   ------------------------------------------------------------------------
send 744 bytes to udp/[41.34.123.243]:58881 at 23:23:04.523158:
   ------------------------------------------------------------------------
   SIP/2.0 401 Unauthorized
   Via: SIP/2.0/UDP 192.168.178.26:58881;rport=58881;branch=z9hG4bKPjaCuKyaBi6ZqcsHyp5HhH3WthJStom0SK;received=41.34.123.243
   From: "Sherif 1003" <sip:1002 at sip.pcfone.com>;tag=qfUt1fQWuE-Ro3zWld6X0r6WidtD6B1q
   To: "Sherif 1003" <sip:1002 at sip.pcfone.com>;tag=1F655pQB3DNBH
   Call-ID: ohCjnPIlf85DIrNvetdMy.1RQM-rLj4e
   CSeq: 60930 REGISTER
   User-Agent: FreeSWITCH-mod_sofia/1.1.beta1-git-8edfd01 2012-03-24 05-16-47 +0000
   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, UPDATE, INFO, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
   Supported: timer, precondition, path, replaces
   WWW-Authenticate: Digest realm="sip.pcfone.com", nonce="699cfe2a-a5f7-11e1-a83e-7b1f73a7ffcf", algorithm=MD5, qop="auth"
   Content-Length: 0
   
   ------------------------------------------------------------------------
recv 830 bytes from udp/[41.34.123.243]:58881 at 23:23:04.757798:
   ------------------------------------------------------------------------
   REGISTER sip:sip.pcfone.com:6090 SIP/2.0
   Via: SIP/2.0/UDP 192.168.178.26:58881;rport;branch=z9hG4bKPjs8SAufeoyzCLrePaAbYhZuOqQF47XIc-
   Max-Forwards: 70
   From: "Sherif 1003" <sip:1002 at sip.pcfone.com>;tag=qfUt1fQWuE-Ro3zWld6X0r6WidtD6B1q
   To: "Sherif 1003" <sip:1002 at sip.pcfone.com>
   Call-ID: ohCjnPIlf85DIrNvetdMy.1RQM-rLj4e
   CSeq: 60931 REGISTER
   User-Agent: Telephone 1.0.2
   Contact: "Sherif 1003" <sip:1002 at 192.168.178.26:58881;ob>
   Expires: 300
   Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS
   Authorization: Digest username="1002", realm="sip.pcfone.com", nonce="699cfe2a-a5f7-11e1-a83e-7b1f73a7ffcf", uri="sip:sip.pcfone.com:6090", response="23a886934aaf049f08432b6bb226bd37", algorithm=MD5, cnonce="sc3AJfhuev18Bs-L-6tORmI.RXlxnPOz", qop=auth, nc=00000001
   Content-Length:  0
   
   ------------------------------------------------------------------------
send 706 bytes to udp/[41.34.123.243]:58881 at 23:23:04.759924:
   ------------------------------------------------------------------------
   SIP/2.0 200 OK
   Via: SIP/2.0/UDP 192.168.178.26:58881;rport=58881;branch=z9hG4bKPjs8SAufeoyzCLrePaAbYhZuOqQF47XIc-;received=41.34.123.243
   From: "Sherif 1003" <sip:1002 at sip.pcfone.com>;tag=qfUt1fQWuE-Ro3zWld6X0r6WidtD6B1q
   To: "Sherif 1003" <sip:1002 at sip.pcfone.com>;tag=2rZy7H8e0pByc
   Call-ID: ohCjnPIlf85DIrNvetdMy.1RQM-rLj4e
   CSeq: 60931 REGISTER
   Contact: <sip:1002 at 192.168.178.26:58881;ob>;expires=300
   Date: Thu, 24 May 2012 23:23:04 GMT
   User-Agent: FreeSWITCH-mod_sofia/1.1.beta1-git-8edfd01 2012-03-24 05-16-47 +0000
   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, UPDATE, INFO, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
   Supported: timer, precondition, path, replaces
   Content-Length: 0
   
   ------------------------------------------------------------------------
send 989 bytes to udp/[41.34.123.243]:58881 at 23:23:04.872728:
   ------------------------------------------------------------------------
   NOTIFY sip:1002 at 192.168.178.26:58881;ob SIP/2.0
   Via: SIP/2.0/UDP 78.138.90.58:6090;rport;branch=z9hG4bKDHStmZ0taSmNp
   Route: <sip:1002 at 41.34.123.243:58881>;ob
   Max-Forwards: 70
   From: <sip:1002 at 78.138.90.58>;tag=31rQ9cSjXZ1gr
   To: <sip:1002 at 78.138.90.58>
   Call-ID: 4130a1ce-209a-1230-8985-00163e6bb553
   CSeq: 28614828 NOTIFY
   Contact: <sip:mod_sofia at 78.138.90.58:6090>
   User-Agent: FreeSWITCH-mod_sofia/1.1.beta1-git-8edfd01 2012-03-24 05-16-47 +0000
   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, UPDATE, INFO, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
   Supported: timer, 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: 91
   
   Messages-Waiting: yes
   Message-Account: sip:1002 at 78.138.90.58
   Voice-Message: 1/0 (0/0)
   
   ------------------------------------------------------------------------
recv 308 bytes from udp/[41.34.123.243]:58881 at 23:23:05.269063:
   ------------------------------------------------------------------------
   SIP/2.0 200 OK
   Via: SIP/2.0/UDP 78.138.90.58:6090;rport=6090;received=78.138.90.58;branch=z9hG4bKDHStmZ0taSmNp
   Call-ID: 4130a1ce-209a-1230-8985-00163e6bb553
   From: <sip:1002 at 78.138.90.58>;tag=31rQ9cSjXZ1gr
   To: <sip:1002 at 78.138.90.58>;tag=z9hG4bKDHStmZ0taSmNp
   CSeq: 28614828 NOTIFY
   Content-Length:  0
   
   ------------------------------------------------------------------------
send 916 bytes to udp/[217.74.179.29]:5060 at 23:25:45.873423:
   ------------------------------------------------------------------------
   REGISTER sip:bluesip.net;transport=udp SIP/2.0
   Via: SIP/2.0/UDP 78.138.90.58:6090;rport;branch=z9hG4bKetjKptHy71a8H
   Max-Forwards: 70
   From: <sip:bluesip/sherifomran at bluesip.net;transport=udp>;tag=6r0vBQZS650Fg
   To: <sip:bluesip/sherifomran at bluesip.net;transport=udp>
   Call-ID: 2a3e4696-a5b9-11e1-a7ed-7b1f73a7ffcf
   CSeq: 28601493 REGISTER
   Contact: <sip:gw+trunk_1000 at 78.138.90.58:6090;transport=udp;gw=trunk_1000>
   Expires: 3600
   User-Agent: FreeSWITCH-mod_sofia/1.1.beta1-git-8edfd01 2012-03-24 05-16-47 +0000
   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, UPDATE, INFO, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
   Supported: timer, precondition, path, replaces
   Authorization: Digest username="bluesip/sherifomran", realm="bluesip.net", nonce="4fbeba4ebfb7414d5c701bd6f994402d551f3acf", algorithm=MD5, uri="sip:bluesip.net;transport=udp", response="c6cdcafe0418e519bc9ee0d8fa3d4d74"
   Content-Length: 0
   
   ------------------------------------------------------------------------
recv 722 bytes from udp/[217.74.179.29]:5060 at 23:25:45.904176:
   ------------------------------------------------------------------------
   SIP/2.0 401 Unauthorized
   Via: SIP/2.0/UDP 78.138.90.58:6090;rport=6090;branch=z9hG4bKetjKptHy71a8H
   From: <sip:bluesip/sherifomran at bluesip.net;transport=udp>;tag=6r0vBQZS650Fg
   To: <sip:bluesip/sherifomran at bluesip.net;transport=udp>;tag=0354a2e1b960c9cc2279eca4e5f84e20.1b16
   Call-ID: 2a3e4696-a5b9-11e1-a7ed-7b1f73a7ffcf
   CSeq: 28601493 REGISTER
   WWW-Authenticate: Digest realm="bluesip.net", nonce="4fbec4a4ba82d1f1feaa32ccec01b85be054fcf7", stale=true
   Server: Sip EXpress router (0.9.7 (i386/linux))
   Content-Length: 0
   Warning: 392 217.74.179.29:5060 "Noisy feedback tells:  pid=5455 req_src_ip=78.138.90.58 req_src_port=6090 in_uri=sip:bluesip.net;transport=udp out_uri=sip:bluesip.net;transport=udp via_cnt==1"
   
   ------------------------------------------------------------------------
send 916 bytes to udp/[217.74.179.29]:5060 at 23:25:45.904416:
   ------------------------------------------------------------------------
   REGISTER sip:bluesip.net;transport=udp SIP/2.0
   Via: SIP/2.0/UDP 78.138.90.58:6090;rport;branch=z9hG4bKF3BcrN214a1tD
   Max-Forwards: 70
   From: <sip:bluesip/sherifomran at bluesip.net;transport=udp>;tag=6r0vBQZS650Fg
   To: <sip:bluesip/sherifomran at bluesip.net;transport=udp>
   Call-ID: 2a3e4696-a5b9-11e1-a7ed-7b1f73a7ffcf
   CSeq: 28601494 REGISTER
   Contact: <sip:gw+trunk_1000 at 78.138.90.58:6090;transport=udp;gw=trunk_1000>
   Expires: 3600
   User-Agent: FreeSWITCH-mod_sofia/1.1.beta1-git-8edfd01 2012-03-24 05-16-47 +0000
   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, UPDATE, INFO, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
   Supported: timer, precondition, path, replaces
   Authorization: Digest username="bluesip/sherifomran", realm="bluesip.net", nonce="4fbec4a4ba82d1f1feaa32ccec01b85be054fcf7", algorithm=MD5, uri="sip:bluesip.net;transport=udp", response="4c09dbe4b9accb52d4104b40dfe20040"
   Content-Length: 0
   
   ------------------------------------------------------------------------
recv 699 bytes from udp/[217.74.179.29]:5060 at 23:25:45.935046:
   ------------------------------------------------------------------------
   SIP/2.0 200 OK
   Via: SIP/2.0/UDP 78.138.90.58:6090;rport=6090;branch=z9hG4bKF3BcrN214a1tD
   From: <sip:bluesip/sherifomran at bluesip.net;transport=udp>;tag=6r0vBQZS650Fg
   To: <sip:bluesip/sherifomran at bluesip.net;transport=udp>;tag=0354a2e1b960c9cc2279eca4e5f84e20.2017
   Call-ID: 2a3e4696-a5b9-11e1-a7ed-7b1f73a7ffcf
   CSeq: 28601494 REGISTER
   Contact: <sip:gw+trunk_1000 at 78.138.90.58:6090;transport=udp;gw=trunk_1000>;q=0.5;expires=3600
   Server: Sip EXpress router (0.9.7 (i386/linux))
   Content-Length: 0
   Warning: 392 217.74.179.29:5060 "Noisy feedback tells:  pid=5462 req_src_ip=78.138.90.58 req_src_port=6090 in_uri=sip:bluesip.net;transport=udp out_uri=sip:bluesip.net;transport=udp via_cnt==1"
   
   ------------------------------------------------------------------------
recv 564 bytes from udp/[41.34.123.243]:58881 at 23:27:59.977057:
   ------------------------------------------------------------------------
   REGISTER sip:sip.pcfone.com:6090 SIP/2.0
   Via: SIP/2.0/UDP 192.168.178.26:58881;rport;branch=z9hG4bKPjEOP.a4NoXqoFqC7a.k2tyeX-mYlUyH4H
   Max-Forwards: 70
   From: "Sherif 1003" <sip:1002 at sip.pcfone.com>;tag=FYtvWBFYne8l.-.5Q6JgnCajTtrXQY-K
   To: "Sherif 1003" <sip:1002 at sip.pcfone.com>
   Call-ID: ohCjnPIlf85DIrNvetdMy.1RQM-rLj4e
   CSeq: 60932 REGISTER
   User-Agent: Telephone 1.0.2
   Contact: "Sherif 1003" <sip:1002 at 192.168.178.26:58881;ob>
   Expires: 300
   Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS
   Content-Length:  0
   
   ------------------------------------------------------------------------
send 744 bytes to udp/[41.34.123.243]:58881 at 23:27:59.977960:
   ------------------------------------------------------------------------
   SIP/2.0 401 Unauthorized
   Via: SIP/2.0/UDP 192.168.178.26:58881;rport=58881;branch=z9hG4bKPjEOP.a4NoXqoFqC7a.k2tyeX-mYlUyH4H;received=41.34.123.243
   From: "Sherif 1003" <sip:1002 at sip.pcfone.com>;tag=FYtvWBFYne8l.-.5Q6JgnCajTtrXQY-K
   To: "Sherif 1003" <sip:1002 at sip.pcfone.com>;tag=4ajgB89Nt8Q3K
   Call-ID: ohCjnPIlf85DIrNvetdMy.1RQM-rLj4e
   CSeq: 60932 REGISTER
   User-Agent: FreeSWITCH-mod_sofia/1.1.beta1-git-8edfd01 2012-03-24 05-16-47 +0000
   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, UPDATE, INFO, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
   Supported: timer, precondition, path, replaces
   WWW-Authenticate: Digest realm="sip.pcfone.com", nonce="19b7cc40-a5f8-11e1-a83f-7b1f73a7ffcf", algorithm=MD5, qop="auth"
   Content-Length: 0
   
   ------------------------------------------------------------------------
recv 564 bytes from udp/[41.34.123.243]:58881 at 23:28:00.468487:
   ------------------------------------------------------------------------
   REGISTER sip:sip.pcfone.com:6090 SIP/2.0
   Via: SIP/2.0/UDP 192.168.178.26:58881;rport;branch=z9hG4bKPjEOP.a4NoXqoFqC7a.k2tyeX-mYlUyH4H
   Max-Forwards: 70
   From: "Sherif 1003" <sip:1002 at sip.pcfone.com>;tag=FYtvWBFYne8l.-.5Q6JgnCajTtrXQY-K
   To: "Sherif 1003" <sip:1002 at sip.pcfone.com>
   Call-ID: ohCjnPIlf85DIrNvetdMy.1RQM-rLj4e
   CSeq: 60932 REGISTER
   User-Agent: Telephone 1.0.2
   Contact: "Sherif 1003" <sip:1002 at 192.168.178.26:58881;ob>
   Expires: 300
   Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS
   Content-Length:  0
   
   ------------------------------------------------------------------------
send 744 bytes to udp/[41.34.123.243]:58881 at 23:28:00.468679:
   ------------------------------------------------------------------------
   SIP/2.0 401 Unauthorized
   Via: SIP/2.0/UDP 192.168.178.26:58881;rport=58881;branch=z9hG4bKPjEOP.a4NoXqoFqC7a.k2tyeX-mYlUyH4H;received=41.34.123.243
   From: "Sherif 1003" <sip:1002 at sip.pcfone.com>;tag=FYtvWBFYne8l.-.5Q6JgnCajTtrXQY-K
   To: "Sherif 1003" <sip:1002 at sip.pcfone.com>;tag=4ajgB89Nt8Q3K
   Call-ID: ohCjnPIlf85DIrNvetdMy.1RQM-rLj4e
   CSeq: 60932 REGISTER
   User-Agent: FreeSWITCH-mod_sofia/1.1.beta1-git-8edfd01 2012-03-24 05-16-47 +0000
   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, UPDATE, INFO, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
   Supported: timer, precondition, path, replaces
   WWW-Authenticate: Digest realm="sip.pcfone.com", nonce="19b7cc40-a5f8-11e1-a83f-7b1f73a7ffcf", algorithm=MD5, qop="auth"
   Content-Length: 0
   
   ------------------------------------------------------------------------
recv 830 bytes from udp/[41.34.123.243]:58881 at 23:28:00.717446:
   ------------------------------------------------------------------------
   REGISTER sip:sip.pcfone.com:6090 SIP/2.0
   Via: SIP/2.0/UDP 192.168.178.26:58881;rport;branch=z9hG4bKPj0iHd3-XUjnvcBs9DiJUCWzrmYTmWxFLt
   Max-Forwards: 70
   From: "Sherif 1003" <sip:1002 at sip.pcfone.com>;tag=FYtvWBFYne8l.-.5Q6JgnCajTtrXQY-K
   To: "Sherif 1003" <sip:1002 at sip.pcfone.com>
   Call-ID: ohCjnPIlf85DIrNvetdMy.1RQM-rLj4e
   CSeq: 60933 REGISTER
   User-Agent: Telephone 1.0.2
   Contact: "Sherif 1003" <sip:1002 at 192.168.178.26:58881;ob>
   Expires: 300
   Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS
   Authorization: Digest username="1002", realm="sip.pcfone.com", nonce="19b7cc40-a5f8-11e1-a83f-7b1f73a7ffcf", uri="sip:sip.pcfone.com:6090", response="e21c903e6d98b5adcdd2eefa75217157", algorithm=MD5, cnonce="sc3AJfhuev18Bs-L-6tORmI.RXlxnPOz", qop=auth, nc=00000001
   Content-Length:  0
   
   ------------------------------------------------------------------------
send 706 bytes to udp/[41.34.123.243]:58881 at 23:28:00.719947:
   ------------------------------------------------------------------------
   SIP/2.0 200 OK
   Via: SIP/2.0/UDP 192.168.178.26:58881;rport=58881;branch=z9hG4bKPj0iHd3-XUjnvcBs9DiJUCWzrmYTmWxFLt;received=41.34.123.243
   From: "Sherif 1003" <sip:1002 at sip.pcfone.com>;tag=FYtvWBFYne8l.-.5Q6JgnCajTtrXQY-K
   To: "Sherif 1003" <sip:1002 at sip.pcfone.com>;tag=5KB9c3tSQHepF
   Call-ID: ohCjnPIlf85DIrNvetdMy.1RQM-rLj4e
   CSeq: 60933 REGISTER
   Contact: <sip:1002 at 192.168.178.26:58881;ob>;expires=300
   Date: Thu, 24 May 2012 23:28:00 GMT
   User-Agent: FreeSWITCH-mod_sofia/1.1.beta1-git-8edfd01 2012-03-24 05-16-47 +0000
   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, UPDATE, INFO, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
   Supported: timer, precondition, path, replaces
   Content-Length: 0
   
   ------------------------------------------------------------------------
send 989 bytes to udp/[41.34.123.243]:58881 at 23:28:00.868368:
   ------------------------------------------------------------------------
   NOTIFY sip:1002 at 192.168.178.26:58881;ob SIP/2.0
   Via: SIP/2.0/UDP 78.138.90.58:6090;rport;branch=z9hG4bKgc54SgK51KQDS
   Route: <sip:1002 at 41.34.123.243:58881>;ob
   Max-Forwards: 70
   From: <sip:1002 at 78.138.90.58>;tag=6v41eyBXmt48a
   To: <sip:1002 at 78.138.90.58>
   Call-ID: f19dfbce-209a-1230-8985-00163e6bb553
   CSeq: 28614976 NOTIFY
   Contact: <sip:mod_sofia at 78.138.90.58:6090>
   User-Agent: FreeSWITCH-mod_sofia/1.1.beta1-git-8edfd01 2012-03-24 05-16-47 +0000
   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, UPDATE, INFO, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
   Supported: timer, 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: 91
   
   Messages-Waiting: yes
   Message-Account: sip:1002 at 78.138.90.58
   Voice-Message: 1/0 (0/0)
   
   ------------------------------------------------------------------------
recv 308 bytes from udp/[41.34.123.243]:58881 at 23:28:01.128279:
   ------------------------------------------------------------------------
   SIP/2.0 200 OK
   Via: SIP/2.0/UDP 78.138.90.58:6090;rport=6090;received=78.138.90.58;branch=z9hG4bKgc54SgK51KQDS
   Call-ID: f19dfbce-209a-1230-8985-00163e6bb553
   From: <sip:1002 at 78.138.90.58>;tag=6v41eyBXmt48a
   To: <sip:1002 at 78.138.90.58>;tag=z9hG4bKgc54SgK51KQDS
   CSeq: 28614976 NOTIFY
   Content-Length:  0
   
   ------------------------------------------------------------------------
send 913 bytes to udp/[217.74.179.29]:5060 at 23:28:11.885213:
   ------------------------------------------------------------------------
   REGISTER sip:bluesip.net;transport=udp SIP/2.0
   Via: SIP/2.0/UDP 78.138.90.58:6090;rport;branch=z9hG4bKHNyXUB48yvD0m
   Max-Forwards: 70
   From: <sip:bluesip/salahomran at bluesip.net;transport=udp>;tag=5F739Uep9vaXm
   To: <sip:bluesip/salahomran at bluesip.net;transport=udp>
   Call-ID: 2a3e49c0-a5b9-11e1-a7ee-7b1f73a7ffcf
   CSeq: 28601493 REGISTER
   Contact: <sip:gw+trunk_1002 at 78.138.90.58:6090;transport=udp;gw=trunk_1002>
   Expires: 3600
   User-Agent: FreeSWITCH-mod_sofia/1.1.beta1-git-8edfd01 2012-03-24 05-16-47 +0000
   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, UPDATE, INFO, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
   Supported: timer, precondition, path, replaces
   Authorization: Digest username="bluesip/salahomran", realm="bluesip.net", nonce="4fbebeca79a5cff7c417463814cb9054d313bcac", algorithm=MD5, uri="sip:bluesip.net;transport=udp", response="bb1babb90f4ea1dc8fbb9aa45d6038c7"
   Content-Length: 0
   
   ------------------------------------------------------------------------
recv 720 bytes from udp/[217.74.179.29]:5060 at 23:28:11.915764:
   ------------------------------------------------------------------------
   SIP/2.0 401 Unauthorized
   Via: SIP/2.0/UDP 78.138.90.58:6090;rport=6090;branch=z9hG4bKHNyXUB48yvD0m
   From: <sip:bluesip/salahomran at bluesip.net;transport=udp>;tag=5F739Uep9vaXm
   To: <sip:bluesip/salahomran at bluesip.net;transport=udp>;tag=0354a2e1b960c9cc2279eca4e5f84e20.3454
   Call-ID: 2a3e49c0-a5b9-11e1-a7ee-7b1f73a7ffcf
   CSeq: 28601493 REGISTER
   WWW-Authenticate: Digest realm="bluesip.net", nonce="4fbec5365af3a174f1cc0b8cda770c727d25bf71", stale=true
   Server: Sip EXpress router (0.9.7 (i386/linux))
   Content-Length: 0
   Warning: 392 217.74.179.29:5060 "Noisy feedback tells:  pid=5462 req_src_ip=78.138.90.58 req_src_port=6090 in_uri=sip:bluesip.net;transport=udp out_uri=sip:bluesip.net;transport=udp via_cnt==1"
   
   ------------------------------------------------------------------------
send 913 bytes to udp/[217.74.179.29]:5060 at 23:28:11.915914:
   ------------------------------------------------------------------------
   REGISTER sip:bluesip.net;transport=udp SIP/2.0
   Via: SIP/2.0/UDP 78.138.90.58:6090;rport;branch=z9hG4bKjyQpX6mcv53jg
   Max-Forwards: 70
   From: <sip:bluesip/salahomran at bluesip.net;transport=udp>;tag=5F739Uep9vaXm
   To: <sip:bluesip/salahomran at bluesip.net;transport=udp>
   Call-ID: 2a3e49c0-a5b9-11e1-a7ee-7b1f73a7ffcf
   CSeq: 28601494 REGISTER
   Contact: <sip:gw+trunk_1002 at 78.138.90.58:6090;transport=udp;gw=trunk_1002>
   Expires: 3600
   User-Agent: FreeSWITCH-mod_sofia/1.1.beta1-git-8edfd01 2012-03-24 05-16-47 +0000
   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, UPDATE, INFO, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
   Supported: timer, precondition, path, replaces
   Authorization: Digest username="bluesip/salahomran", realm="bluesip.net", nonce="4fbec5365af3a174f1cc0b8cda770c727d25bf71", algorithm=MD5, uri="sip:bluesip.net;transport=udp", response="e260f6dc9b01ce8bea8c5ffb36bd4ddd"
   Content-Length: 0
   
   ------------------------------------------------------------------------
recv 697 bytes from udp/[217.74.179.29]:5060 at 23:28:11.946325:
   ------------------------------------------------------------------------
   SIP/2.0 200 OK
   Via: SIP/2.0/UDP 78.138.90.58:6090;rport=6090;branch=z9hG4bKjyQpX6mcv53jg
   From: <sip:bluesip/salahomran at bluesip.net;transport=udp>;tag=5F739Uep9vaXm
   To: <sip:bluesip/salahomran at bluesip.net;transport=udp>;tag=0354a2e1b960c9cc2279eca4e5f84e20.4b5c
   Call-ID: 2a3e49c0-a5b9-11e1-a7ee-7b1f73a7ffcf
   CSeq: 28601494 REGISTER
   Contact: <sip:gw+trunk_1002 at 78.138.90.58:6090;transport=udp;gw=trunk_1002>;q=0.5;expires=3600
   Server: Sip EXpress router (0.9.7 (i386/linux))
   Content-Length: 0
   Warning: 392 217.74.179.29:5060 "Noisy feedback tells:  pid=5455 req_src_ip=78.138.90.58 req_src_port=6090 in_uri=sip:bluesip.net;transport=udp out_uri=sip:bluesip.net;transport=udp via_cnt==1"
   
   ------------------------------------------------------------------------
recv 564 bytes from udp/[41.34.123.243]:58881 at 23:32:55.930149:
   ------------------------------------------------------------------------
   REGISTER sip:sip.pcfone.com:6090 SIP/2.0
   Via: SIP/2.0/UDP 192.168.178.26:58881;rport;branch=z9hG4bKPjuk8DZGiaXfN7BmtslNHVjtSg5UXsbHXc
   Max-Forwards: 70
   From: "Sherif 1003" <sip:1002 at sip.pcfone.com>;tag=Pz.t-0tGXj3saM.ehBiB1EAMmmitze1D
   To: "Sherif 1003" <sip:1002 at sip.pcfone.com>
   Call-ID: ohCjnPIlf85DIrNvetdMy.1RQM-rLj4e
   CSeq: 60934 REGISTER
   User-Agent: Telephone 1.0.2
   Contact: "Sherif 1003" <sip:1002 at 192.168.178.26:58881;ob>
   Expires: 300
   Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS
   Content-Length:  0
   
   ------------------------------------------------------------------------
send 744 bytes to udp/[41.34.123.243]:58881 at 23:32:55.931324:
   ------------------------------------------------------------------------
   SIP/2.0 401 Unauthorized
   Via: SIP/2.0/UDP 192.168.178.26:58881;rport=58881;branch=z9hG4bKPjuk8DZGiaXfN7BmtslNHVjtSg5UXsbHXc;received=41.34.123.243
   From: "Sherif 1003" <sip:1002 at sip.pcfone.com>;tag=Pz.t-0tGXj3saM.ehBiB1EAMmmitze1D
   To: "Sherif 1003" <sip:1002 at sip.pcfone.com>;tag=75XtgSv0H3tUp
   Call-ID: ohCjnPIlf85DIrNvetdMy.1RQM-rLj4e
   CSeq: 60934 REGISTER
   User-Agent: FreeSWITCH-mod_sofia/1.1.beta1-git-8edfd01 2012-03-24 05-16-47 +0000
   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, UPDATE, INFO, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
   Supported: timer, precondition, path, replaces
   WWW-Authenticate: Digest realm="sip.pcfone.com", nonce="ca1eabee-a5f8-11e1-a840-7b1f73a7ffcf", algorithm=MD5, qop="auth"
   Content-Length: 0
   
   ------------------------------------------------------------------------
recv 830 bytes from udp/[41.34.123.243]:58881 at 23:32:56.165624:
   ------------------------------------------------------------------------
   REGISTER sip:sip.pcfone.com:6090 SIP/2.0
   Via: SIP/2.0/UDP 192.168.178.26:58881;rport;branch=z9hG4bKPjKEQ6xXmyzZxA6vCM88vHNYAOPAjUqFsD
   Max-Forwards: 70
   From: "Sherif 1003" <sip:1002 at sip.pcfone.com>;tag=Pz.t-0tGXj3saM.ehBiB1EAMmmitze1D
   To: "Sherif 1003" <sip:1002 at sip.pcfone.com>
   Call-ID: ohCjnPIlf85DIrNvetdMy.1RQM-rLj4e
   CSeq: 60935 REGISTER
   User-Agent: Telephone 1.0.2
   Contact: "Sherif 1003" <sip:1002 at 192.168.178.26:58881;ob>
   Expires: 300
   Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS
   Authorization: Digest username="1002", realm="sip.pcfone.com", nonce="ca1eabee-a5f8-11e1-a840-7b1f73a7ffcf", uri="sip:sip.pcfone.com:6090", response="ff6d12f4ec7abd4b37187222b3af2358", algorithm=MD5, cnonce="sc3AJfhuev18Bs-L-6tORmI.RXlxnPOz", qop=auth, nc=00000001
   Content-Length:  0
   
   ------------------------------------------------------------------------
send 706 bytes to udp/[41.34.123.243]:58881 at 23:32:56.168033:
   ------------------------------------------------------------------------
   SIP/2.0 200 OK
   Via: SIP/2.0/UDP 192.168.178.26:58881;rport=58881;branch=z9hG4bKPjKEQ6xXmyzZxA6vCM88vHNYAOPAjUqFsD;received=41.34.123.243
   From: "Sherif 1003" <sip:1002 at sip.pcfone.com>;tag=Pz.t-0tGXj3saM.ehBiB1EAMmmitze1D
   To: "Sherif 1003" <sip:1002 at sip.pcfone.com>;tag=8eQKjmD4ecHej
   Call-ID: ohCjnPIlf85DIrNvetdMy.1RQM-rLj4e
   CSeq: 60935 REGISTER
   Contact: <sip:1002 at 192.168.178.26:58881;ob>;expires=300
   Date: Thu, 24 May 2012 23:32:56 GMT
   User-Agent: FreeSWITCH-mod_sofia/1.1.beta1-git-8edfd01 2012-03-24 05-16-47 +0000
   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, UPDATE, INFO, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
   Supported: timer, precondition, path, replaces
   Content-Length: 0
   
   ------------------------------------------------------------------------
send 989 bytes to udp/[41.34.123.243]:58881 at 23:32:56.360238:
   ------------------------------------------------------------------------
   NOTIFY sip:1002 at 192.168.178.26:58881;ob SIP/2.0
   Via: SIP/2.0/UDP 78.138.90.58:6090;rport;branch=z9hG4bKK7gFZ15FSet5B
   Route: <sip:1002 at 41.34.123.243:58881>;ob
   Max-Forwards: 70
   From: <sip:1002 at 78.138.90.58>;tag=9QgcmFy7BN70D
   To: <sip:1002 at 78.138.90.58>
   Call-ID: a1be7708-209b-1230-8985-00163e6bb553
   CSeq: 28615124 NOTIFY
   Contact: <sip:mod_sofia at 78.138.90.58:6090>
   User-Agent: FreeSWITCH-mod_sofia/1.1.beta1-git-8edfd01 2012-03-24 05-16-47 +0000
   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, UPDATE, INFO, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
   Supported: timer, 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: 91
   
   Messages-Waiting: yes
   Message-Account: sip:1002 at 78.138.90.58
   Voice-Message: 1/0 (0/0)
   
   ------------------------------------------------------------------------
recv 308 bytes from udp/[41.34.123.243]:58881 at 23:32:56.623554:
   ------------------------------------------------------------------------
   SIP/2.0 200 OK
   Via: SIP/2.0/UDP 78.138.90.58:6090;rport=6090;received=78.138.90.58;branch=z9hG4bKK7gFZ15FSet5B
   Call-ID: a1be7708-209b-1230-8985-00163e6bb553
   From: <sip:1002 at 78.138.90.58>;tag=9QgcmFy7BN70D
   To: <sip:1002 at 78.138.90.58>;tag=z9hG4bKK7gFZ15FSet5B
   CSeq: 28615124 NOTIFY
   Content-Length:  0
   
   ------------------------------------------------------------------------
freeswitch at internal> 


--- On Thu, 5/24/12, Kristian Kielhofner <kris at kriskinc.com> wrote:

From: Kristian Kielhofner <kris at kriskinc.com>
Subject: Re: [Freeswitch-users] Freeswitch (SBC + Media) does not know that gateway phone is picked up
To: "FreeSWITCH Users Help" <freeswitch-users at lists.freeswitch.org>
Date: Thursday, May 24, 2012, 7:51 PM

Siptrace and logs please.

On Thu, May 24, 2012 at 11:53 AM, Sherif Omran
<sherifomran2000 at yahoo.com> wrote:
>
> Hi all,
>
> My topology is as follows:
>
> Kamailio  -> FS (SBS+Media server)
>
> I came across an issue with my system as follows.  I have a Hardphone registered. When I do local call inside kamailio, it gets to FS and returns back well and FS understands when I lift the handset. However, I added a gateway (german landline server), when I call my self from another phone, the call gets to FS and then transmits to Kamailio, it rings my extension but when I lift the handset FS does not notice it and keeps ringing.
>
> Any body has an Idea? Here is my gateway trunk.
>
> <include>
>         <gateway name="trunk_1000">
>         <param name="realm" value="bluesip.net"/>
>         <param name="username" value="bluesip/xxxxxx"/>
>         <param name="register" value="true"/>
>         <param name="extension" value="kb-1002"/>
>       </gateway>
> </include>
>
> thanks in advance
> Sherif Omran
>
>
> _________________________________________________________________________
> Professional FreeSWITCH Consulting Services:
> consulting at freeswitch.org
> http://www.freeswitchsolutions.com
>
> 
> 
>
> Official FreeSWITCH Sites
> http://www.freeswitch.org
> http://wiki.freeswitch.org
> http://www.cluecon.com
>
> Join Us At ClueCon - Aug 7-9, 2012
>
> FreeSWITCH-users mailing list
> FreeSWITCH-users at lists.freeswitch.org
> http://lists.freeswitch.org/mailman/listinfo/freeswitch-users
> UNSUBSCRIBE:http://lists.freeswitch.org/mailman/options/freeswitch-users
> http://www.freeswitch.org
>



--
Kristian Kielhofner

_________________________________________________________________________
Professional FreeSWITCH Consulting Services:
consulting at freeswitch.org
http://www.freeswitchsolutions.com




Official FreeSWITCH Sites
http://www.freeswitch.org
http://wiki.freeswitch.org
http://www.cluecon.com

Join Us At ClueCon - Aug 7-9, 2012

FreeSWITCH-users mailing list
FreeSWITCH-users at lists.freeswitch.org
http://lists.freeswitch.org/mailman/listinfo/freeswitch-users
UNSUBSCRIBE:http://lists.freeswitch.org/mailman/options/freeswitch-users
http://www.freeswitch.org
-------------- next part --------------
An HTML attachment was scrubbed...
URL: http://lists.freeswitch.org/pipermail/freeswitch-users/attachments/20120524/682d1664/attachment-0001.html 


Join us at ClueCon 2011 Aug 9-11, 2011
More information about the FreeSWITCH-users mailing list