New subject: Freeswitch (SBC + Media) does not know that gateway phone is picked up

25 May 2012

      Hello guys,

here is the trace (see the attachment)

kind regards
Sherif

--- 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!!?

ThanksBR,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

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

Sherif Omran

Sherif Omran

tags

participants (1)