OK Sheriff, I've gone through this. This says that destination on kamailio end is ringing and keeps on ringing state. What Im more interested in are the sip traces on port 5060 the story thats happening in between the destination phone and the kamailo..if u know what I mean !!


On Sat, May 26, 2012 at 8:06 PM, Sherif Omran <sherifomran2000@yahoo.com> wrote:
Hi Sanny,

Please see the following links

http://pastebin.freeswitch.org/19175
http://pastebin.freeswitch.org/19174


regards,
Sherif


--- On Sat, 5/26/12, SamyGo <govoiper@gmail.com> wrote:

From: SamyGo <govoiper@gmail.com>
Subject: Re: [Freeswitch-users] Freeswitch (SBC + Media) does not know that gateway phone is picked up
To: "FreeSWITCH Users Help" <freeswitch-users@lists.freeswitch.org>
Date: Saturday, May 26, 2012, 5:52 PM

Hey Sherrif,
You might wanna resend the pcap file as I couldn;t find any single INVITE or any call in that capture. Please review.

BR
SaGo.

On Sat, May 26, 2012 at 1:05 AM, Sherif Omran <sherifomran2000@yahoo.com> wrote:
Hello Sammy

FS uses port 6090 and registers the GW. When a call comes, it rings the extension but then gives a busy signal and FS keeps ringing till I cancel the call from the GW.


  1. recv 477 bytes from udp/[217.74.179.29]:5060 at 19:52:05.662344:
  2.    ------------------------------------------------------------------------
  3.    CANCEL sip:gw+trunk_1000@78.138.90.58:6090;transport=udp;gw=trunk_1000 SIP/2.0
  4.    Via: SIP/2.0/UDP 217.74.179.29;branch=z9hG4bKe567.13b0b9e.0
  5.    Via: SIP/2.0/UDP 217.74.179.28:5060;branch=z9hG4bK16d1a257;rport=5060
  6.    From: "+41793940965" <sip:+41793940965@bluesip.net>;tag=as1dccab06
  9.    CSeq: 102 CANCEL
  10.    User-Agent: blueSIP PSTN GW
  11.    Max-Forwards: 69
  12.    Content-Length: 0
  13.    P-hint: USRLOC




--- On Fri, 5/25/12, SamyGo <govoiper@gmail.com> wrote:

From: SamyGo <govoiper@gmail.com>
Subject: Re: [Freeswitch-users] Freeswitch (SBC + Media) does not know that gateway phone is picked up
To: "FreeSWITCH Users Help" <freeswitch-users@lists.freeswitch.org>
Date: Friday, May 25, 2012, 10:46 AM

Hi again,
If you want kamailio register to the provider then use UAC module. Kamailio will use the username/password and register with the provider.

Regards,
Sammy


On Fri, May 25, 2012 at 12:20 PM, Sherif Omran <sherifomran2000@yahoo.com> wrote:
Dear Sammy,

Thank you for your question ... Yes, the GW is registered through FS because I did not know how to register it to kamailio. But it seems better to register it to kamailio.
One more information, calls from 1001 to 1002 go to kamailio then to FS then return back to kamailio smoothly. Thus I would suggest that I change registering the gateway from FS to kamailio. but How to?



--- On Fri, 5/25/12, SamyGo <govoiper@gmail.com> wrote:

From: SamyGo <govoiper@gmail.com>
Subject: Re: [Freeswitch-users] Freeswitch (SBC + Media) does not know that gateway phone is picked up
To: "FreeSWITCH Users Help" <freeswitch-users@lists.freeswitch.org>
Date: Friday, May 25, 2012, 8:08 AM

Hi,
These are SIP traces on FreeSWITCH console, whereas you are saying and it seems that kamailio is not detecting the answering (200 OK)of the call from extension 1002. Please, can you take a sip trace..!! I see you've both kamailio and FS on same server! Please take a pcap from the linux console using the following command.

#tcpdump -i any -s 0 -w new-call-trace.pcap -vvv

Please be quick on turning the sip trace on and off as quickly as possible to avoid extra packets. Once done open the file in wireshark ; apply filter "sip || rtp" and then save the resulting capture in separate file. Send us the new file to analyse.

One more silly question probably, I see REGISTERs coming to your FS as well and the calls to gateways are made from FS too !!, umm...just thinking what are you using kamailio for!!?

Thanks
BR,
Sammy


On Fri, May 25, 2012 at 4:40 AM, Sherif Omran <sherifomran2000@yahoo.com> wrote:
Hi all,

here is the siptrace: To figure it out
1- gateway called bluesip.net. It send invide using caller number@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@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@internal> tracelevel
-ERR tracelevel Command not found!

freeswitch@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@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@bluesip.net>;tag=as00589402
   To: <sip:+498972101087914@bluesip.net>
   Contact: <sip:+41793940965@217.74.179.28>
   Call-ID: 4253e4bd2d624caf7f18faf85390b68f@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@bluesip.net>;tag=as00589402
   To: <sip:+498972101087914@bluesip.net>
   Call-ID: 4253e4bd2d624caf7f18faf85390b68f@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@bluesip.net [69cd22e6-a5f5-11e1-a833-7b1f73a7ffcf]
2012-05-24 23:08:45.840749 [DEBUG] sofia.c:5532 Channel sofia/internal/+41793940965@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@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@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@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@bluesip.net [BREAK]
2012-05-24 23:08:45.840749 [DEBUG] switch_core_state_machine.c:362 (sofia/internal/+41793940965@bluesip.net) Running State Change CS_INIT
2012-05-24 23:08:45.840749 [DEBUG] switch_core_state_machine.c:401 (sofia/internal/+41793940965@bluesip.net) State INIT
2012-05-24 23:08:45.840749 [DEBUG] mod_sofia.c:85 sofia/internal/+41793940965@bluesip.net SOFIA INIT
2012-05-24 23:08:45.840749 [DEBUG] mod_sofia.c:125 (sofia/internal/+41793940965@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@bluesip.net [BREAK]
2012-05-24 23:08:45.840749 [DEBUG] switch_core_state_machine.c:401 (sofia/internal/+41793940965@bluesip.net) State INIT going to sleep
2012-05-24 23:08:45.840749 [DEBUG] switch_core_state_machine.c:362 (sofia/internal/+41793940965@bluesip.net) Running State Change CS_ROUTING
2012-05-24 23:08:45.840749 [DEBUG] switch_channel.c:1886 (sofia/internal/+41793940965@bluesip.net) Callstate Change DOWN -> RINGING
2012-05-24 23:08:45.840749 [DEBUG] switch_core_state_machine.c:410 (sofia/internal/+41793940965@bluesip.net) State ROUTING
2012-05-24 23:08:45.840749 [DEBUG] mod_sofia.c:148 sofia/internal/+41793940965@bluesip.net SOFIA ROUTING
2012-05-24 23:08:45.840749 [DEBUG] switch_core_state_machine.c:104 sofia/internal/+41793940965@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@bluesip.net parsing [public->from_kamailio] continue=false
Dialplan: sofia/internal/+41793940965@bluesip.net Regex (PASS) [from_kamailio] destination_number(kb-1002) =~ /^(.+)$/ break=on-false
Dialplan: sofia/internal/+41793940965@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@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@bluesip.net [BREAK]
2012-05-24 23:08:45.840749 [DEBUG] switch_core_state_machine.c:410 (sofia/internal/+41793940965@bluesip.net) State ROUTING going to sleep
2012-05-24 23:08:45.840749 [DEBUG] switch_core_state_machine.c:362 (sofia/internal/+41793940965@bluesip.net) Running State Change CS_EXECUTE
2012-05-24 23:08:45.840749 [DEBUG] switch_core_state_machine.c:417 (sofia/internal/+41793940965@bluesip.net) State EXECUTE
2012-05-24 23:08:45.840749 [DEBUG] mod_sofia.c:241 sofia/internal/+41793940965@bluesip.net SOFIA EXECUTE
2012-05-24 23:08:45.840749 [DEBUG] switch_core_state_machine.c:192 sofia/internal/+41793940965@bluesip.net Standard EXECUTE
EXECUTE sofia/internal/+41793940965@bluesip.net transfer(kb-1002 XML default)
2012-05-24 23:08:45.840749 [DEBUG] switch_ivr.c:1711 (sofia/internal/+41793940965@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@bluesip.net [BREAK]
2012-05-24 23:08:45.840749 [DEBUG] switch_core_session.c:731 Send signal sofia/internal/+41793940965@bluesip.net [BREAK]
2012-05-24 23:08:45.840749 [NOTICE] switch_ivr.c:1717 Transfer sofia/internal/+41793940965@bluesip.net to XML[kb-1002@default]
2012-05-24 23:08:45.840749 [DEBUG] switch_core_state_machine.c:417 (sofia/internal/+41793940965@bluesip.net) State EXECUTE going to sleep
2012-05-24 23:08:45.840749 [DEBUG] switch_core_state_machine.c:362 (sofia/internal/+41793940965@bluesip.net) Running State Change CS_ROUTING
2012-05-24 23:08:45.840749 [DEBUG] switch_core_state_machine.c:410 (sofia/internal/+41793940965@bluesip.net) State ROUTING
2012-05-24 23:08:45.840749 [DEBUG] mod_sofia.c:148 sofia/internal/+41793940965@bluesip.net SOFIA ROUTING
2012-05-24 23:08:45.840749 [DEBUG] switch_core_state_machine.c:104 sofia/internal/+41793940965@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@bluesip.net parsing [default->vbox] continue=false
Dialplan: sofia/internal/+41793940965@bluesip.net Regex (FAIL) [vbox] destination_number(kb-1002) =~ /^vb-([0-9][0-9][0-9])$/ break=on-false
Dialplan: sofia/internal/+41793940965@bluesip.net parsing [default->vmenu] continue=false
Dialplan: sofia/internal/+41793940965@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@bluesip.net parsing [default->kbridge] continue=false
Dialplan: sofia/internal/+41793940965@bluesip.net Regex (PASS) [kbridge] destination_number(kb-1002) =~ /^kb-(.+)$/ break=on-false
Dialplan: sofia/internal/+41793940965@bluesip.net Action set(proxy_media=true)
Dialplan: sofia/internal/+41793940965@bluesip.net Action set(call_timeout=50)
Dialplan: sofia/internal/+41793940965@bluesip.net Action set(continue_on_fail=true)
Dialplan: sofia/internal/+41793940965@bluesip.net Action set(hangup_after_bridge=true)
Dialplan: sofia/internal/+41793940965@bluesip.net Action set(sip_invite_domain=78.138.90.58)
Dialplan: sofia/internal/+41793940965@bluesip.net Action export(sip_contact_user=ufs)
Dialplan: sofia/internal/+41793940965@bluesip.net Action bridge(sofia/78.138.90.58/1002@78.138.90.58:5060)
Dialplan: sofia/internal/+41793940965@bluesip.net Action answer()
Dialplan: sofia/internal/+41793940965@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@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@bluesip.net [BREAK]
2012-05-24 23:08:45.840749 [DEBUG] switch_core_state_machine.c:410 (sofia/internal/+41793940965@bluesip.net) State ROUTING going to sleep
2012-05-24 23:08:45.840749 [DEBUG] switch_core_state_machine.c:362 (sofia/internal/+41793940965@bluesip.net) Running State Change CS_EXECUTE
2012-05-24 23:08:45.840749 [DEBUG] switch_core_state_machine.c:417 (sofia/internal/+41793940965@bluesip.net) State EXECUTE
2012-05-24 23:08:45.840749 [DEBUG] mod_sofia.c:241 sofia/internal/+41793940965@bluesip.net SOFIA EXECUTE
2012-05-24 23:08:45.840749 [DEBUG] switch_core_state_machine.c:192 sofia/internal/+41793940965@bluesip.net Standard EXECUTE
EXECUTE sofia/internal/+41793940965@bluesip.net set(proxy_media=true)
2012-05-24 23:08:45.840749 [DEBUG] mod_dptools.c:1281 sofia/internal/+41793940965@bluesip.net SET [proxy_media]=[true]
EXECUTE sofia/internal/+41793940965@bluesip.net set(call_timeout=50)
2012-05-24 23:08:45.840749 [DEBUG] mod_dptools.c:1281 sofia/internal/+41793940965@bluesip.net SET [call_timeout]=[50]
EXECUTE sofia/internal/+41793940965@bluesip.net set(continue_on_fail=true)
2012-05-24 23:08:45.840749 [DEBUG] mod_dptools.c:1281 sofia/internal/+41793940965@bluesip.net SET [continue_on_fail]=[true]
EXECUTE sofia/internal/+41793940965@bluesip.net set(hangup_after_bridge=true)
2012-05-24 23:08:45.840749 [DEBUG] mod_dptools.c:1281 sofia/internal/+41793940965@bluesip.net SET [hangup_after_bridge]=[true]
EXECUTE sofia/internal/+41793940965@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@bluesip.net SET [sip_invite_domain]=[78.138.90.58]
EXECUTE sofia/internal/+41793940965@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@bluesip.net bridge(sofia/78.138.90.58/1002@78.138.90.58:5060)
2012-05-24 23:08:45.840749 [DEBUG] switch_channel.c:1047 sofia/internal/+41793940965@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@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@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@78.138.90.58:5060 [BREAK]
2012-05-24 23:08:45.840749 [DEBUG] switch_core_state_machine.c:362 (sofia/internal/1002@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@78.138.90.58:5060) State INIT
2012-05-24 23:08:45.840749 [DEBUG] mod_sofia.c:85 sofia/internal/1002@78.138.90.58:5060 SOFIA INIT
2012-05-24 23:08:45.840749 [DEBUG] sofia_glue.c:1871 sofia/internal/1002@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@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@78.138.90.58>;tag=tgNrSS1jQFS8F
   To: <sip:1002@78.138.90.58:5060>
   Call-ID: 412c8312-2098-1230-8985-00163e6bb553
   CSeq: 28614398 INVITE
   Contact: <sip:ufs@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@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@78.138.90.58:5060 [BREAK]
2012-05-24 23:08:45.840749 [DEBUG] mod_sofia.c:125 (sofia/internal/1002@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@78.138.90.58:5060 [BREAK]
2012-05-24 23:08:45.840749 [DEBUG] switch_core_state_machine.c:401 (sofia/internal/1002@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@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@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@78.138.90.58:5060) State ROUTING
2012-05-24 23:08:45.840749 [DEBUG] mod_sofia.c:148 sofia/internal/1002@78.138.90.58:5060 SOFIA ROUTING
2012-05-24 23:08:45.840749 [DEBUG] switch_ivr_originate.c:66 (sofia/internal/1002@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@78.138.90.58:5060 [BREAK]
2012-05-24 23:08:45.840749 [DEBUG] switch_core_state_machine.c:410 (sofia/internal/1002@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@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@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@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@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@78.138.90.58>;tag=tgNrSS1jQFS8F
   To: <sip:1002@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@78.138.90.58>;tag=tgNrSS1jQFS8F
   To: <sip:1002@78.138.90.58>;tag=qCSaB2YJOAsAr2dJ-RWNMhPoDdn1RFvs
   CSeq: 28614398 INVITE
   Contact: "Mama" <sip:1002@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@78.138.90.58:5060 [BREAK]
2012-05-24 23:08:47.400741 [DEBUG] switch_core_session.c:877 Send signal sofia/internal/1002@78.138.90.58:5060 [BREAK]
2012-05-24 23:08:47.400741 [DEBUG] sofia.c:5532 Channel sofia/internal/1002@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@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@bluesip.net>;tag=as00589402
   To: <sip:+498972101087914@bluesip.net>;tag=S7UZQygFt62Nm
   Call-ID: 4253e4bd2d624caf7f18faf85390b68f@bluesip.net
   CSeq: 102 INVITE
   Contact: <sip:+498972101087914@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@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@bluesip.net [BREAK]
2012-05-24 23:08:47.400741 [NOTICE] mod_sofia.c:2514 Ring-Ready sofia/internal/+41793940965@bluesip.net!
2012-05-24 23:08:47.400741 [DEBUG] sofia.c:5532 Channel sofia/internal/+41793940965@bluesip.net entering state [early][180]
2012-05-24 23:08:47.400741 [DEBUG] switch_core_session.c:731 Send signal sofia/internal/+41793940965@bluesip.net [BREAK]
2012-05-24 23:08:47.400741 [NOTICE] switch_ivr_originate.c:483 Ring Ready sofia/internal/+41793940965@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@78.138.90.58>;tag=tgNrSS1jQFS8F
   To: <sip:1002@78.138.90.58:5060>;tag=549D2DD03BBA7C67
   Call-ID: 412c8312-2098-1230-8985-00163e6bb553
   CSeq: 28614398 INVITE
   Contact: <sip:1002@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@78.138.90.58:5060 [BREAK]
2012-05-24 23:08:47.800746 [DEBUG] switch_core_session.c:877 Send signal sofia/internal/1002@78.138.90.58:5060 [BREAK]
2012-05-24 23:08:47.800746 [DEBUG] sofia.c:5532 Channel sofia/internal/1002@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@78.138.90.58>;tag=tgNrSS1jQFS8F
   To: <sip:1002@78.138.90.58>;tag=qCSaB2YJOAsAr2dJ-RWNMhPoDdn1RFvs
   CSeq: 28614398 INVITE
   Contact: "Mama" <sip:1002@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@78.138.90.58:5060 [BREAK]
2012-05-24 23:08:48.180741 [DEBUG] switch_core_session.c:877 Send signal sofia/internal/1002@78.138.90.58:5060 [BREAK]
2012-05-24 23:08:48.180741 [DEBUG] sofia.c:5532 Channel sofia/internal/1002@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@78.138.90.58>;tag=tgNrSS1jQFS8F
   To: <sip:1002@78.138.90.58:5060>;tag=549D2DD03BBA7C67
   Call-ID: 412c8312-2098-1230-8985-00163e6bb553
   CSeq: 28614398 INVITE
   Contact: <sip:1002@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@78.138.90.58:5060 [BREAK]
2012-05-24 23:08:48.400744 [DEBUG] switch_core_session.c:877 Send signal sofia/internal/1002@78.138.90.58:5060 [BREAK]
2012-05-24 23:08:48.400744 [DEBUG] sofia.c:5532 Channel sofia/internal/1002@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@78.138.90.58>;tag=tgNrSS1jQFS8F
   To: <sip:1002@78.138.90.58:5060>;tag=549D2DD03BBA7C67
   Call-ID: 412c8312-2098-1230-8985-00163e6bb553
   CSeq: 28614398 INVITE
   Contact: <sip:1002@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@78.138.90.58:5060 [BREAK]
2012-05-24 23:08:49.280742 [DEBUG] switch_core_session.c:877 Send signal sofia/internal/1002@78.138.90.58:5060 [BREAK]
2012-05-24 23:08:49.280742 [DEBUG] sofia.c:5532 Channel sofia/internal/1002@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@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@bluesip.net>;tag=as00589402
   To: <sip:+498972101087914@bluesip.net>
   Call-ID: 4253e4bd2d624caf7f18faf85390b68f@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@bluesip.net>;tag=as00589402
   To: <sip:+498972101087914@bluesip.net>;tag=S7UZQygFt62Nm
   Call-ID: 4253e4bd2d624caf7f18faf85390b68f@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@bluesip.net>;tag=as00589402
   To: <sip:+498972101087914@bluesip.net>;tag=S7UZQygFt62Nm
   Call-ID: 4253e4bd2d624caf7f18faf85390b68f@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@bluesip.net [BREAK]
2012-05-24 23:09:15.060742 [DEBUG] switch_core_session.c:877 Send signal sofia/internal/+41793940965@bluesip.net [BREAK]
2012-05-24 23:09:15.060742 [DEBUG] switch_core_session.c:877 Send signal sofia/internal/+41793940965@bluesip.net [BREAK]
2012-05-24 23:09:15.060742 [DEBUG] sofia.c:5532 Channel sofia/internal/+41793940965@bluesip.net entering state [terminated][487]
2012-05-24 23:09:15.060742 [DEBUG] switch_channel.c:2848 (sofia/internal/+41793940965@bluesip.net) Callstate Change RINGING -> HANGUP
2012-05-24 23:09:15.060742 [NOTICE] sofia.c:6301 Hangup sofia/internal/+41793940965@bluesip.net [CS_EXECUTE] [ORIGINATOR_CANCEL]
2012-05-24 23:09:15.060742 [DEBUG] switch_channel.c:2871 Send signal sofia/internal/+41793940965@bluesip.net [KILL]
2012-05-24 23:09:15.060742 [DEBUG] switch_core_session.c:1182 Send signal sofia/internal/+41793940965@bluesip.net [BREAK]
2012-05-24 23:09:15.060742 [DEBUG] switch_channel.c:2848 (sofia/internal/1002@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@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@78.138.90.58:5060 [KILL]
2012-05-24 23:09:15.060742 [DEBUG] switch_core_state_machine.c:362 (sofia/internal/1002@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@78.138.90.58:5060) State HANGUP
2012-05-24 23:09:15.060742 [DEBUG] mod_sofia.c:469 Channel sofia/internal/1002@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@78.138.90.58:5060
2012-05-24 23:09:15.060742 [DEBUG] switch_core_state_machine.c:47 sofia/internal/1002@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@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@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@78.138.90.58:5060 [BREAK]
2012-05-24 23:09:15.060742 [DEBUG] switch_core_state_machine.c:362 (sofia/internal/1002@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@78.138.90.58:5060) State REPORTING
2012-05-24 23:09:15.060742 [DEBUG] switch_core_state_machine.c:79 sofia/internal/1002@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@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@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@78.138.90.58:5060 [BREAK]
2012-05-24 23:09:15.060742 [DEBUG] switch_core_session.c:1382 Session 9 (sofia/internal/1002@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@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@78.138.90.58:5060) Ended
2012-05-24 23:09:15.060742 [NOTICE] switch_core_session.c:1402 Close Channel sofia/internal/1002@78.138.90.58:5060 [CS_DESTROY]
2012-05-24 23:09:15.060742 [DEBUG] switch_core_state_machine.c:491 (sofia/internal/1002@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@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@78.138.90.58:5060) State DESTROY
2012-05-24 23:09:15.060742 [DEBUG] mod_sofia.c:374 sofia/internal/1002@78.138.90.58:5060 SOFIA DESTROY
2012-05-24 23:09:15.060742 [DEBUG] switch_core_state_machine.c:86 sofia/internal/1002@78.138.90.58:5060 Standard DESTROY
2012-05-24 23:09:15.060742 [DEBUG] switch_core_state_machine.c:504 (sofia/internal/1002@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@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@bluesip.net) State EXECUTE going to sleep
2012-05-24 23:09:15.060742 [DEBUG] switch_core_state_machine.c:362 (sofia/internal/+41793940965@bluesip.net) Running State Change CS_HANGUP
2012-05-24 23:09:15.060742 [DEBUG] switch_core_state_machine.c:602 (sofia/internal/+41793940965@bluesip.net) State HANGUP
2012-05-24 23:09:15.060742 [DEBUG] mod_sofia.c:469 Channel sofia/internal/+41793940965@bluesip.net hanging up, cause: ORIGINATOR_CANCEL
2012-05-24 23:09:15.060742 [DEBUG] switch_core_state_machine.c:47 sofia/internal/+41793940965@bluesip.net Standard HANGUP, cause: ORIGINATOR_CANCEL
2012-05-24 23:09:15.060742 [DEBUG] switch_core_state_machine.c:602 (sofia/internal/+41793940965@bluesip.net) State HANGUP going to sleep
2012-05-24 23:09:15.060742 [DEBUG] switch_core_state_machine.c:393 (sofia/internal/+41793940965@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@bluesip.net [BREAK]
2012-05-24 23:09:15.060742 [DEBUG] switch_core_state_machine.c:362 (sofia/internal/+41793940965@bluesip.net) Running State Change CS_REPORTING
2012-05-24 23:09:15.060742 [DEBUG] switch_core_state_machine.c:662 (sofia/internal/+41793940965@bluesip.net) State REPORTING
send 383 bytes to udp/[78.138.90.58]:5060 at 23:09:15.087695:
   ------------------------------------------------------------------------
   CANCEL sip:1002@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@78.138.90.58>;tag=tgNrSS1jQFS8F
   To: <sip:1002@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@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@bluesip.net>;tag=as00589402
   Call-ID: 4253e4bd2d624caf7f18faf85390b68f@bluesip.net
   To: <sip:+498972101087914@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@bluesip.net Standard REPORTING, cause: ORIGINATOR_CANCEL
2012-05-24 23:09:15.120807 [DEBUG] switch_core_state_machine.c:662 (sofia/internal/+41793940965@bluesip.net) State REPORTING going to sleep
2012-05-24 23:09:15.120807 [DEBUG] switch_core_state_machine.c:387 (sofia/internal/+41793940965@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@bluesip.net [BREAK]
2012-05-24 23:09:15.120807 [DEBUG] switch_core_session.c:1382 Session 8 (sofia/internal/+41793940965@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@bluesip.net) Ended
2012-05-24 23:09:15.120807 [NOTICE] switch_core_session.c:1402 Close Channel sofia/internal/+41793940965@bluesip.net [CS_DESTROY]
2012-05-24 23:09:15.120807 [DEBUG] switch_core_state_machine.c:491 (sofia/internal/+41793940965@bluesip.net) Callstate Change HANGUP -> DOWN
2012-05-24 23:09:15.120807 [DEBUG] switch_core_state_machine.c:494 (sofia/internal/+41793940965@bluesip.net) Running State Change CS_DESTROY
2012-05-24 23:09:15.120807 [DEBUG] switch_core_state_machine.c:504 (sofia/internal/+41793940965@bluesip.net) State DESTROY
2012-05-24 23:09:15.120807 [DEBUG] mod_sofia.c:374 sofia/internal/+41793940965@bluesip.net SOFIA DESTROY
2012-05-24 23:09:15.120807 [DEBUG] switch_core_state_machine.c:86 sofia/internal/+41793940965@bluesip.net Standard DESTROY
2012-05-24 23:09:15.120807 [DEBUG] switch_core_state_machine.c:504 (sofia/internal/+41793940965@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@78.138.90.58>;tag=tgNrSS1jQFS8F
   To: <sip:1002@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@78.138.90.58>;tag=tgNrSS1jQFS8F
   To: <sip:1002@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@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@78.138.90.58>;tag=tgNrSS1jQFS8F
   To: <sip:1002@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@sip.pcfone.com>;tag=wOP.FVT4Crk8O5E.bB7BWUhNO6QG91IG
   To: "Sherif 1003" <sip:1002@sip.pcfone.com>
   Call-ID: ohCjnPIlf85DIrNvetdMy.1RQM-rLj4e
   CSeq: 60926 REGISTER
   User-Agent: Telephone 1.0.2
   Contact: "Sherif 1003" <sip:1002@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@sip.pcfone.com>;tag=wOP.FVT4Crk8O5E.bB7BWUhNO6QG91IG
   To: "Sherif 1003" <sip:1002@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@sip.pcfone.com>;tag=wOP.FVT4Crk8O5E.bB7BWUhNO6QG91IG
   To: "Sherif 1003" <sip:1002@sip.pcfone.com>
   Call-ID: ohCjnPIlf85DIrNvetdMy.1RQM-rLj4e
   CSeq: 60927 REGISTER
   User-Agent: Telephone 1.0.2
   Contact: "Sherif 1003" <sip:1002@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@sip.pcfone.com>;tag=wOP.FVT4Crk8O5E.bB7BWUhNO6QG91IG
   To: "Sherif 1003" <sip:1002@sip.pcfone.com>;tag=v279vF3SH15DQ
   Call-ID: ohCjnPIlf85DIrNvetdMy.1RQM-rLj4e
   CSeq: 60927 REGISTER
   Contact: <sip:1002@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@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@41.34.123.243:58881>;ob
   Max-Forwards: 70
   From: <sip:1002@78.138.90.58>;tag=XB12yamXeav0j
   To: <sip:1002@78.138.90.58>
   Call-ID: e0efa252-2098-1230-8985-00163e6bb553
   CSeq: 28614532 NOTIFY
   Contact: <sip:mod_sofia@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@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@78.138.90.58>;tag=XB12yamXeav0j
   To: <sip:1002@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@sip.pcfone.com>;tag=JBSXL5WZLWbLzpvRFoTSP5x8qQslI1ST
   To: "Sherif 1003" <sip:1002@sip.pcfone.com>
   Call-ID: ohCjnPIlf85DIrNvetdMy.1RQM-rLj4e
   CSeq: 60928 REGISTER
   User-Agent: Telephone 1.0.2
   Contact: "Sherif 1003" <sip:1002@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@sip.pcfone.com>;tag=JBSXL5WZLWbLzpvRFoTSP5x8qQslI1ST
   To: "Sherif 1003" <sip:1002@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@sip.pcfone.com>;tag=JBSXL5WZLWbLzpvRFoTSP5x8qQslI1ST
   To: "Sherif 1003" <sip:1002@sip.pcfone.com>
   Call-ID: ohCjnPIlf85DIrNvetdMy.1RQM-rLj4e
   CSeq: 60929 REGISTER
   User-Agent: Telephone 1.0.2
   Contact: "Sherif 1003" <sip:1002@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@sip.pcfone.com>;tag=JBSXL5WZLWbLzpvRFoTSP5x8qQslI1ST
   To: "Sherif 1003" <sip:1002@sip.pcfone.com>;tag=ZXKm20N48U85S
   Call-ID: ohCjnPIlf85DIrNvetdMy.1RQM-rLj4e
   CSeq: 60929 REGISTER
   Contact: <sip:1002@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@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@41.34.123.243:58881>;ob
   Max-Forwards: 70
   From: <sip:1002@78.138.90.58>;tag=06cD4U6754yrN
   To: <sip:1002@78.138.90.58>
   Call-ID: 91100602-2099-1230-8985-00163e6bb553
   CSeq: 28614680 NOTIFY
   Contact: <sip:mod_sofia@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@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@78.138.90.58>;tag=06cD4U6754yrN
   To: <sip:1002@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@sip.pcfone.com>;tag=qfUt1fQWuE-Ro3zWld6X0r6WidtD6B1q
   To: "Sherif 1003" <sip:1002@sip.pcfone.com>
   Call-ID: ohCjnPIlf85DIrNvetdMy.1RQM-rLj4e
   CSeq: 60930 REGISTER
   User-Agent: Telephone 1.0.2
   Contact: "Sherif 1003" <sip:1002@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@sip.pcfone.com>;tag=qfUt1fQWuE-Ro3zWld6X0r6WidtD6B1q
   To: "Sherif 1003" <sip:1002@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@sip.pcfone.com>;tag=qfUt1fQWuE-Ro3zWld6X0r6WidtD6B1q
   To: "Sherif 1003" <sip:1002@sip.pcfone.com>
   Call-ID: ohCjnPIlf85DIrNvetdMy.1RQM-rLj4e
   CSeq: 60931 REGISTER
   User-Agent: Telephone 1.0.2
   Contact: "Sherif 1003" <sip:1002@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@sip.pcfone.com>;tag=qfUt1fQWuE-Ro3zWld6X0r6WidtD6B1q
   To: "Sherif 1003" <sip:1002@sip.pcfone.com>;tag=2rZy7H8e0pByc
   Call-ID: ohCjnPIlf85DIrNvetdMy.1RQM-rLj4e
   CSeq: 60931 REGISTER
   Contact: <sip:1002@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@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@41.34.123.243:58881>;ob
   Max-Forwards: 70
   From: <sip:1002@78.138.90.58>;tag=31rQ9cSjXZ1gr
   To: <sip:1002@78.138.90.58>
   Call-ID: 4130a1ce-209a-1230-8985-00163e6bb553
   CSeq: 28614828 NOTIFY
   Contact: <sip:mod_sofia@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@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@78.138.90.58>;tag=31rQ9cSjXZ1gr
   To: <sip:1002@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@bluesip.net;transport=udp>;tag=6r0vBQZS650Fg
   To: <sip:bluesip/sherifomran@bluesip.net;transport=udp>
   Call-ID: 2a3e4696-a5b9-11e1-a7ed-7b1f73a7ffcf
   CSeq: 28601493 REGISTER
   Contact: <sip:gw+trunk_1000@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@bluesip.net;transport=udp>;tag=6r0vBQZS650Fg
   To: <sip:bluesip/sherifomran@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@bluesip.net;transport=udp>;tag=6r0vBQZS650Fg
   To: <sip:bluesip/sherifomran@bluesip.net;transport=udp>
   Call-ID: 2a3e4696-a5b9-11e1-a7ed-7b1f73a7ffcf
   CSeq: 28601494 REGISTER
   Contact: <sip:gw+trunk_1000@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@bluesip.net;transport=udp>;tag=6r0vBQZS650Fg
   To: <sip:bluesip/sherifomran@bluesip.net;transport=udp>;tag=0354a2e1b960c9cc2279eca4e5f84e20.2017
   Call-ID: 2a3e4696-a5b9-11e1-a7ed-7b1f73a7ffcf
   CSeq: 28601494 REGISTER
   Contact: <sip:gw+trunk_1000@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@sip.pcfone.com>;tag=FYtvWBFYne8l.-.5Q6JgnCajTtrXQY-K
   To: "Sherif 1003" <sip:1002@sip.pcfone.com>
   Call-ID: ohCjnPIlf85DIrNvetdMy.1RQM-rLj4e
   CSeq: 60932 REGISTER
   User-Agent: Telephone 1.0.2
   Contact: "Sherif 1003" <sip:1002@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@sip.pcfone.com>;tag=FYtvWBFYne8l.-.5Q6JgnCajTtrXQY-K
   To: "Sherif 1003" <sip:1002@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@sip.pcfone.com>;tag=FYtvWBFYne8l.-.5Q6JgnCajTtrXQY-K
   To: "Sherif 1003" <sip:1002@sip.pcfone.com>
   Call-ID: ohCjnPIlf85DIrNvetdMy.1RQM-rLj4e
   CSeq: 60932 REGISTER
   User-Agent: Telephone 1.0.2
   Contact: "Sherif 1003" <sip:1002@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@sip.pcfone.com>;tag=FYtvWBFYne8l.-.5Q6JgnCajTtrXQY-K
   To: "Sherif 1003" <sip:1002@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@sip.pcfone.com>;tag=FYtvWBFYne8l.-.5Q6JgnCajTtrXQY-K
   To: "Sherif 1003" <sip:1002@sip.pcfone.com>
   Call-ID: ohCjnPIlf85DIrNvetdMy.1RQM-rLj4e
   CSeq: 60933 REGISTER
   User-Agent: Telephone 1.0.2
   Contact: "Sherif 1003" <sip:1002@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@sip.pcfone.com>;tag=FYtvWBFYne8l.-.5Q6JgnCajTtrXQY-K
   To: "Sherif 1003" <sip:1002@sip.pcfone.com>;tag=5KB9c3tSQHepF
   Call-ID: ohCjnPIlf85DIrNvetdMy.1RQM-rLj4e
   CSeq: 60933 REGISTER
   Contact: <sip:1002@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@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@41.34.123.243:58881>;ob
   Max-Forwards: 70
   From: <sip:1002@78.138.90.58>;tag=6v41eyBXmt48a
   To: <sip:1002@78.138.90.58>
   Call-ID: f19dfbce-209a-1230-8985-00163e6bb553
   CSeq: 28614976 NOTIFY
   Contact: <sip:mod_sofia@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@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@78.138.90.58>;tag=6v41eyBXmt48a
   To: <sip:1002@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@bluesip.net;transport=udp>;tag=5F739Uep9vaXm
   To: <sip:bluesip/salahomran@bluesip.net;transport=udp>
   Call-ID: 2a3e49c0-a5b9-11e1-a7ee-7b1f73a7ffcf
   CSeq: 28601493 REGISTER
   Contact: <sip:gw+trunk_1002@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@bluesip.net;transport=udp>;tag=5F739Uep9vaXm
   To: <sip:bluesip/salahomran@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@bluesip.net;transport=udp>;tag=5F739Uep9vaXm
   To: <sip:bluesip/salahomran@bluesip.net;transport=udp>
   Call-ID: 2a3e49c0-a5b9-11e1-a7ee-7b1f73a7ffcf
   CSeq: 28601494 REGISTER
   Contact: <sip:gw+trunk_1002@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@bluesip.net;transport=udp>;tag=5F739Uep9vaXm
   To: <sip:bluesip/salahomran@bluesip.net;transport=udp>;tag=0354a2e1b960c9cc2279eca4e5f84e20.4b5c
   Call-ID: 2a3e49c0-a5b9-11e1-a7ee-7b1f73a7ffcf
   CSeq: 28601494 REGISTER
   Contact: <sip:gw+trunk_1002@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@sip.pcfone.com>;tag=Pz.t-0tGXj3saM.ehBiB1EAMmmitze1D
   To: "Sherif 1003" <sip:1002@sip.pcfone.com>
   Call-ID: ohCjnPIlf85DIrNvetdMy.1RQM-rLj4e
   CSeq: 60934 REGISTER
   User-Agent: Telephone 1.0.2
   Contact: "Sherif 1003" <sip:1002@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@sip.pcfone.com>;tag=Pz.t-0tGXj3saM.ehBiB1EAMmmitze1D
   To: "Sherif 1003" <sip:1002@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@sip.pcfone.com>;tag=Pz.t-0tGXj3saM.ehBiB1EAMmmitze1D
   To: "Sherif 1003" <sip:1002@sip.pcfone.com>
   Call-ID: ohCjnPIlf85DIrNvetdMy.1RQM-rLj4e
   CSeq: 60935 REGISTER
   User-Agent: Telephone 1.0.2
   Contact: "Sherif 1003" <sip:1002@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@sip.pcfone.com>;tag=Pz.t-0tGXj3saM.ehBiB1EAMmmitze1D
   To: "Sherif 1003" <sip:1002@sip.pcfone.com>;tag=8eQKjmD4ecHej
   Call-ID: ohCjnPIlf85DIrNvetdMy.1RQM-rLj4e
   CSeq: 60935 REGISTER
   Contact: <sip:1002@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@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@41.34.123.243:58881>;ob
   Max-Forwards: 70
   From: <sip:1002@78.138.90.58>;tag=9QgcmFy7BN70D
   To: <sip:1002@78.138.90.58>
   Call-ID: a1be7708-209b-1230-8985-00163e6bb553
   CSeq: 28615124 NOTIFY
   Contact: <sip:mod_sofia@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@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@78.138.90.58>;tag=9QgcmFy7BN70D
   To: <sip:1002@78.138.90.58>;tag=z9hG4bKK7gFZ15FSet5B
   CSeq: 28615124 NOTIFY
   Content-Length:  0
  
   ------------------------------------------------------------------------
freeswitch@internal>


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

From: Kristian Kielhofner <kris@kriskinc.com>
Subject: Re: [Freeswitch-users] Freeswitch (SBC + Media) does not know that gateway phone is picked up
To: "FreeSWITCH Users Help" <freeswitch-users@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@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@freeswitch.org
> http://www.freeswitchsolutions.com
>
> FreeSWITCH-powered IP PBX: The CudaTel Communication Server
> http://www.cudatel.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@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@freeswitch.org
http://www.freeswitchsolutions.com

FreeSWITCH-powered IP PBX: The CudaTel Communication Server
http://www.cudatel.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@lists.freeswitch.org
http://lists.freeswitch.org/mailman/listinfo/freeswitch-users
UNSUBSCRIBE:http://lists.freeswitch.org/mailman/options/freeswitch-users
http://www.freeswitch.org

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

FreeSWITCH-powered IP PBX: The CudaTel Communication Server
http://www.cudatel.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@lists.freeswitch.org
http://lists.freeswitch.org/mailman/listinfo/freeswitch-users
UNSUBSCRIBE:http://lists.freeswitch.org/mailman/options/freeswitch-users
http://www.freeswitch.org



-----Inline Attachment Follows-----

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

FreeSWITCH-powered IP PBX: The CudaTel Communication Server
http://www.cudatel.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@lists.freeswitch.org
http://lists.freeswitch.org/mailman/listinfo/freeswitch-users
UNSUBSCRIBE:http://lists.freeswitch.org/mailman/options/freeswitch-users
http://www.freeswitch.org

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

FreeSWITCH-powered IP PBX: The CudaTel Communication Server
http://www.cudatel.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@lists.freeswitch.org
http://lists.freeswitch.org/mailman/listinfo/freeswitch-users
UNSUBSCRIBE:http://lists.freeswitch.org/mailman/options/freeswitch-users
http://www.freeswitch.org



-----Inline Attachment Follows-----

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

FreeSWITCH-powered IP PBX: The CudaTel Communication Server
http://www.cudatel.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@lists.freeswitch.org
http://lists.freeswitch.org/mailman/listinfo/freeswitch-users
UNSUBSCRIBE:http://lists.freeswitch.org/mailman/options/freeswitch-users
http://www.freeswitch.org

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

FreeSWITCH-powered IP PBX: The CudaTel Communication Server
http://www.cudatel.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@lists.freeswitch.org
http://lists.freeswitch.org/mailman/listinfo/freeswitch-users
UNSUBSCRIBE:http://lists.freeswitch.org/mailman/options/freeswitch-users
http://www.freeswitch.org



-----Inline Attachment Follows-----

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

FreeSWITCH-powered IP PBX: The CudaTel Communication Server
http://www.cudatel.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@lists.freeswitch.org
http://lists.freeswitch.org/mailman/listinfo/freeswitch-users
UNSUBSCRIBE:http://lists.freeswitch.org/mailman/options/freeswitch-users
http://www.freeswitch.org

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

FreeSWITCH-powered IP PBX: The CudaTel Communication Server
http://www.cudatel.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@lists.freeswitch.org
http://lists.freeswitch.org/mailman/listinfo/freeswitch-users
UNSUBSCRIBE:http://lists.freeswitch.org/mailman/options/freeswitch-users
http://www.freeswitch.org