FS & gtalk

5 Nov 2011

      Hi all,

I have trouble getting FS and gtalk correctly. The only thing I can do is
calling a gmail user connected on gtalk (on Windows XP) from the SIP phone I
registered in the jingle profile (extension 1000). Gtalk displays the
message "Incoming Call From Extension 1000 1000" but when the call is
accepted by clicking the button on the gtalk UI, I am not sure the call is
established (there is no audio and the communication is cleared after 51
seconds). 

I am running an up-to-date release of FS (from GIT) on Ubuntu server 10.10
behind NAT. I have installed the gnutls library and recompiled FS. 

*Sofia status gives:
*
                     Name          Type                                      
Data      State
=================================================================================================
              192.168.0.3         alias                                  
internal      ALIASED
                 internal       profile            
sip:mod_sofia@192.168.0.3:5060      RUNNING (0)
                 internal       profile            
sip:mod_sofia@192.168.0.3:5061      RUNNING (0) (TLS)
                 external       profile            
sip:mod_sofia@192.168.0.3:5080      RUNNING (0)
                 external       profile            
sip:mod_sofia@192.168.0.3:5081      RUNNING (0) (TLS)
    external::example.com       gateway                   
sip:joeuser@example.com      NOREG
            internal-ipv6       profile                  
sip:mod_sofia@[::1]:5060      RUNNING (0)
            internal-ipv6       profile                  
sip:mod_sofia@[::1]:5061      RUNNING (0) (TLS)
=================================================================================================
3 profiles 1 alias

*Below is the debug trace before answering the call:
*
Dialplan: sofia/internal/1000@192.168.0.3 Action answer()
Dialplan: sofia/internal/1000@192.168.0.3 Action
bridge(dingaling/gmail.com/gilles.gerlinger@gmail.com)
2011-11-05 19:55:10.214583 [DEBUG] switch_core_state_machine.c:154
(sofia/internal/1000@192.168.0.3) State Change CS_ROUTING -> CS_EXECUTE
2011-11-05 19:55:10.214583 [DEBUG] switch_core_session.c:1177 Send signal
sofia/internal/1000@192.168.0.3 [BREAK]
2011-11-05 19:55:10.214583 [DEBUG] switch_core_state_machine.c:410
(sofia/internal/1000@192.168.0.3) State ROUTING going to sleep
2011-11-05 19:55:10.214583 [DEBUG] switch_core_state_machine.c:362
(sofia/internal/1000@192.168.0.3) Running State Change CS_EXECUTE
2011-11-05 19:55:10.214583 [DEBUG] switch_core_state_machine.c:417
(sofia/internal/1000@192.168.0.3) State EXECUTE
2011-11-05 19:55:10.214583 [DEBUG] mod_sofia.c:241
sofia/internal/1000@192.168.0.3 SOFIA EXECUTE
2011-11-05 19:55:10.214583 [DEBUG] switch_core_state_machine.c:192
sofia/internal/1000@192.168.0.3 Standard EXECUTE
EXECUTE sofia/internal/1000@192.168.0.3
hash(insert/192.168.0.3-spymap/1000/af83062a-07df-11e1-8d0e-cdd18f8a8f7f)
EXECUTE sofia/internal/1000@192.168.0.3
hash(insert/192.168.0.3-last_dial/1000/4545)
EXECUTE sofia/internal/1000@192.168.0.3
hash(insert/192.168.0.3-last_dial/global/af83062a-07df-11e1-8d0e-cdd18f8a8f7f)
EXECUTE sofia/internal/1000@192.168.0.3 set(RFC2822_DATE=Sat, 05 Nov 2011
19:55:10 +0100)
2011-11-05 19:55:10.307908 [DEBUG] mod_dptools.c:1177
sofia/internal/1000@192.168.0.3 SET [RFC2822_DATE]=[Sat, 05 Nov 2011
19:55:10 +0100]
EXECUTE sofia/internal/1000@192.168.0.3 answer()
2011-11-05 19:55:10.307908 [DEBUG] sofia_glue.c:3100 AUDIO RTP
[sofia/internal/1000@192.168.0.3] 192.168.0.3 port 20082 -> 192.168.0.7 port
7078 codec: 111 ms: 20
2011-11-05 19:55:10.307908 [DEBUG] switch_rtp.c:1642 Starting timer [soft]
320 bytes per 20ms
2011-11-05 19:55:10.307908 [DEBUG] sofia_glue.c:3363 Set 2833 dtmf send
payload to 101
2011-11-05 19:55:10.307908 [DEBUG] sofia_glue.c:3369 Set 2833 dtmf receive
payload to 101
2011-11-05 19:55:10.307908 [DEBUG] mod_sofia.c:746 Local SDP
sofia/internal/1000@192.168.0.3:
v=0
o=FreeSWITCH 1320499228 1320499229 IN IP4 192.168.0.3
s=FreeSWITCH
c=IN IP4 192.168.0.3
t=0 0
m=audio 20082 RTP/AVP 111 101
a=rtpmap:111 speex/16000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=silenceSupp:off - - - -
a=ptime:20
a=sendrecv

2011-11-05 19:55:10.307908 [DEBUG] switch_core_session.c:726 Send signal
sofia/internal/1000@192.168.0.3 [BREAK]
2011-11-05 19:55:10.307908 [DEBUG] switch_channel.c:3161
(sofia/internal/1000@192.168.0.3) Callstate Change RINGING -> ACTIVE

*And here is what is displayed after I accept the call in the gtalk UI:
*

2011-11-05 19:55:10.307908 [NOTICE] mod_dptools.c:1044 Channel
[sofia/internal/1000@192.168.0.3] has been answered
EXECUTE sofia/internal/1000@192.168.0.3
bridge(dingaling/gmail.com/gilles.gerlinger@gmail.com)
2011-11-05 19:55:10.307908 [DEBUG] switch_ivr_originate.c:1884 Parsing
global variables
2011-11-05 19:55:10.307908 [DEBUG] switch_core_session.c:872 Send signal
sofia/internal/1000@192.168.0.3 [BREAK]
2011-11-05 19:55:10.307908 [DEBUG] switch_core_session.c:872 Send signal
sofia/internal/1000@192.168.0.3 [BREAK]
2011-11-05 19:55:10.307908 [DEBUG] switch_core_session.c:872 Send signal
sofia/internal/1000@192.168.0.3 [BREAK]
2011-11-05 19:55:10.307908 [DEBUG] switch_core_session.c:872 Send signal
sofia/internal/1000@192.168.0.3 [BREAK]
2011-11-05 19:55:11.053751 [NOTICE] switch_channel.c:915 New Channel
dingaling/gmail.com/gilles.gerlinger@gmail.com
[af9bd074-07df-11e1-8d13-cdd18f8a8f7f]
2011-11-05 19:55:11.053751 [DEBUG] mod_dingaling.c:1861
(dingaling/gmail.com/gilles.gerlinger@gmail.com) State Change CS_NEW ->
CS_INIT
2011-11-05 19:55:11.053751 [DEBUG] switch_core_session.c:1177 Send signal
dingaling/gmail.com/gilles.gerlinger@gmail.com [BREAK]
2011-11-05 19:55:11.053751 [DEBUG] mod_dingaling.c:1396
dingaling/gmail.com/gilles.gerlinger@gmail.com CHANNEL KILL
2011-11-05 19:55:11.053751 [DEBUG] sofia.c:5283 Channel
sofia/internal/1000@192.168.0.3 entering state [completed][200]
2011-11-05 19:55:11.053751 [DEBUG] sofia.c:5283 Channel
sofia/internal/1000@192.168.0.3 entering state [ready][200]
2011-11-05 19:55:11.053751 [DEBUG] switch_core_state_machine.c:362
(dingaling/gmail.com/gilles.gerlinger@gmail.com) Running State Change
CS_INIT
2011-11-05 19:55:11.053751 [DEBUG] switch_core_state_machine.c:401
(dingaling/gmail.com/gilles.gerlinger@gmail.com) State INIT
2011-11-05 19:55:11.073909 [NOTICE] mod_dingaling.c:1158 Ring-Ready
dingaling/gmail.com/gilles.gerlinger@gmail.com!
2011-11-05 19:55:11.073909 [DEBUG] mod_dingaling.c:1111 Don't have my codec
yet here's one
2011-11-05 19:55:11.073909 [DEBUG] mod_dingaling.c:1131 Send Describe
[PCMU@8000]
2011-11-05 19:55:11.773513 [DEBUG] mod_dingaling.c:3017 using Existing
session for 9602298177
2011-11-05 19:55:11.873506 [DEBUG] mod_dingaling.c:3017 using Existing
session for 9602298177
2011-11-05 19:55:11.873506 [DEBUG] mod_dingaling.c:3356 1 candidates
2011-11-05 19:55:11.873506 [DEBUG] mod_dingaling.c:3392 candidates
192.168.0.7:1408

freeswitch@wetalk>
freeswitch@wetalk> 2011-11-05 19:55:21.093499 [DEBUG] mod_dingaling.c:1056
Send Candidate 82.237.228.27:27456 [9tEsX2r0J2lRtJ4V]
2011-11-05 19:55:22.493584 [DEBUG] mod_dingaling.c:3017 using Existing
session for 9602298177
2011-11-05 19:55:22.493584 [DEBUG] mod_dingaling.c:3270 Already decided on a
codec
2011-11-05 19:55:24.093532 [DEBUG] mod_dingaling.c:3017 using Existing
session for 9602298177
2011-11-05 19:55:24.093532 [DEBUG] mod_dingaling.c:3356 2 candidates
2011-11-05 19:55:24.093532 [DEBUG] mod_dingaling.c:3392 candidates
192.168.0.7:1408
2011-11-05 19:55:24.093532 [DEBUG] mod_dingaling.c:3392 candidates
192.168.0.7:1425
2011-11-05 19:55:26.393902 [DEBUG] mod_dingaling.c:3017 using Existing
session for 9602298177
2011-11-05 19:55:26.393902 [DEBUG] mod_dingaling.c:3356 3 candidates

etc...

*On the other hand I cannot call from gtalk the SIP phone registered with a
gmail account (extension 1000). Below is the debugging trace:
*
2011-11-05 19:50:37.633502 [DEBUG] mod_dingaling.c:3150 Creating an identity
for 3626629742 gilles.gerlinger@gmail.com/Talk.v104900E9C7C
<gilles.gerlinger@gmail.com/Talk.v104900E9C7C> 1000
2011-11-05 19:50:37.633502 [NOTICE] switch_channel.c:915 New Channel
dingaling/1000 [0d146fa0-07df-11e1-8d0a-cdd18f8a8f7f]
2011-11-05 19:50:37.633502 [DEBUG] mod_dingaling.c:3178 Creating a session
for 3626629742
2011-11-05 19:50:37.633502 [NOTICE] switch_channel.c:913 Rename Channel
dingaling/1000->DingaLing/new [0d146fa0-07df-11e1-8d0a-cdd18f8a8f7f]
2011-11-05 19:50:37.633502 [DEBUG] mod_dingaling.c:3182 (DingaLing/new)
State Change CS_NEW -> CS_INIT
2011-11-05 19:50:37.633502 [DEBUG] switch_core_session.c:1177 Send signal
DingaLing/new [BREAK]
2011-11-05 19:50:37.633502 [DEBUG] mod_dingaling.c:1396 DingaLing/new
CHANNEL KILL
2011-11-05 19:50:37.633502 [DEBUG] mod_dingaling.c:3284 12 payloads
2011-11-05 19:50:37.633502 [DEBUG] mod_dingaling.c:3286 Available Payload
ISAC 103
2011-11-05 19:50:37.633502 [DEBUG] mod_dingaling.c:3294 compare ISAC
103/16000 to PCMU 0/8000
2011-11-05 19:50:37.633502 [DEBUG] mod_dingaling.c:3286 Available Payload
IPCMWB 97
2011-11-05 19:50:37.633502 [DEBUG] mod_dingaling.c:3294 compare IPCMWB
97/16000 to PCMU 0/8000
2011-11-05 19:50:37.633502 [DEBUG] mod_dingaling.c:3286 Available Payload
speex 99
2011-11-05 19:50:37.633502 [DEBUG] mod_dingaling.c:3294 compare speex
99/16000 to PCMU 0/8000
2011-11-05 19:50:37.633502 [DEBUG] mod_dingaling.c:3286 Available Payload
G723 4
2011-11-05 19:50:37.633502 [DEBUG] mod_dingaling.c:3294 compare G723 4/8000
to PCMU 0/8000
2011-11-05 19:50:37.633502 [DEBUG] mod_dingaling.c:3286 Available Payload
speex 98
2011-11-05 19:50:37.633502 [DEBUG] mod_dingaling.c:3294 compare speex
98/8000 to PCMU 0/8000
2011-11-05 19:50:37.633502 [DEBUG] mod_dingaling.c:3286 Available Payload
EG711U 100
2011-11-05 19:50:37.633502 [DEBUG] mod_dingaling.c:3294 compare EG711U
100/8000 to PCMU 0/8000
2011-11-05 19:50:37.633502 [DEBUG] mod_dingaling.c:3286 Available Payload
EG711A 101
2011-11-05 19:50:37.633502 [DEBUG] mod_dingaling.c:3294 compare EG711A
101/8000 to PCMU 0/8000
2011-11-05 19:50:37.633502 [DEBUG] mod_dingaling.c:3286 Available Payload
PCMU 0
2011-11-05 19:50:37.633502 [DEBUG] mod_dingaling.c:3294 compare PCMU 0/8000
to PCMU 0/8000
2011-11-05 19:50:37.633502 [DEBUG] mod_dingaling.c:3305 Choosing Payload
index 0 PCMU 0
2011-11-05 19:50:37.633502 [DEBUG] mod_dingaling.c:1131 Send Describe
[PCMU@8000]
2011-11-05 19:50:37.633502 [DEBUG] switch_core_state_machine.c:362
(DingaLing/new) Running State Change CS_INIT
2011-11-05 19:50:37.633502 [DEBUG] switch_core_state_machine.c:401
(DingaLing/new) State INIT
2011-11-05 19:50:37.633502 [NOTICE] mod_dingaling.c:1158 Ring-Ready
DingaLing/new!
2011-11-05 19:50:37.733589 [DEBUG] mod_dingaling.c:3017 using Existing
session for 3626629742
2011-11-05 19:50:37.733589 [DEBUG] mod_dingaling.c:3356 1 candidates
2011-11-05 19:50:37.733589 [DEBUG] mod_dingaling.c:3392 candidates
192.168.0.7:1206
2011-11-05 19:50:47.633968 [DEBUG] mod_dingaling.c:1056 Send Candidate
82.237.228.27:27976 [jHNpXRZxqmR2Fdhe]
2011-11-05 19:50:48.213494 [DEBUG] mod_dingaling.c:3017 using Existing
session for 3626629742
2011-11-05 19:50:48.213494 [DEBUG] mod_dingaling.c:3356 1 candidates
2011-11-05 19:50:48.213494 [DEBUG] mod_dingaling.c:3392 candidates
192.168.0.7:1206
2011-11-05 19:50:50.213524 [DEBUG] mod_dingaling.c:3017 using Existing
session for 3626629742
2011-11-05 19:50:50.213524 [DEBUG] mod_dingaling.c:3356 2 candidates
2011-11-05 19:50:50.213524 [DEBUG] mod_dingaling.c:3392 candidates
192.168.0.7:1206
2011-11-05 19:50:50.213524 [DEBUG] mod_dingaling.c:3392 candidates
192.168.0.7:1211
2011-11-05 19:50:52.514082 [DEBUG] mod_dingaling.c:3017 using Existing
session for 3626629742
2011-11-05 19:50:52.514082 [DEBUG] mod_dingaling.c:3356 3 candidates
2011-11-05 19:50:52.514082 [DEBUG] mod_dingaling.c:3392 candidates
192.168.0.7:1206
2011-11-05 19:50:52.514082 [DEBUG] mod_dingaling.c:3392 candidates
192.168.0.7:1211
2011-11-05 19:50:52.514082 [DEBUG] mod_dingaling.c:3392 candidates
209.85.229.126:19294
2011-11-05 19:50:52.613655 [DEBUG] mod_dingaling.c:3017 using Existing
session for 3626629742
2011-11-05 19:50:52.613655 [DEBUG] mod_dingaling.c:3356 3 candidates
2011-11-05 19:50:52.613655 [DEBUG] mod_dingaling.c:3392 candidates
192.168.0.7:1206
2011-11-05 19:50:52.613655 [DEBUG] mod_dingaling.c:3392 candidates
192.168.0.7:1211
2011-11-05 19:50:52.613655 [DEBUG] mod_dingaling.c:3392 candidates
209.85.229.126:443
2011-11-05 19:50:57.673499 [DEBUG] mod_dingaling.c:3017 using Existing
session for 3626629742

*Thank you in advance for your help.
Gilles Gerlinger
*

--
View this message in context: http://freeswitch-users.2379917.n2.nabble.com/FS-gtalk-tp6966503p6966503.htm...
Sent from the freeswitch-users mailing list archive at Nabble.com.

gigerlin

tags

participants (1)