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

Sherif Omran sherifomran2000 at yahoo.com
Fri May 25 12:52:26 MSD 2012


Hello guys,

here is the trace (see the attachment)

kind regards
Sherif


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

From: SamyGo <govoiper at gmail.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: 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 at 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 at gmail.com> wrote:


From: SamyGo <govoiper at gmail.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: 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!!?


ThanksBR,Sammy

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


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



_________________________________________________________________________

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





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

_________________________________________________________________________
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


_________________________________________________________________________

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





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

_________________________________________________________________________
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/20120525/914945d5/attachment-0001.html 
-------------- next part --------------
A non-text attachment was scrubbed...
Name: call.pcap.zip
Type: application/x-zip-compressed
Size: 69987 bytes
Desc: not available
Url : http://lists.freeswitch.org/pipermail/freeswitch-users/attachments/20120525/914945d5/attachment-0001.bin 


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