[Freeswitch-users] FS & gtalk

gigerlin gilles.gerlinger at free.fr
Sat Nov 5 22:04:10 MSK 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 at 192.168.0.3:5060      RUNNING (0)
                 internal       profile            
sip:mod_sofia at 192.168.0.3:5061      RUNNING (0) (TLS)
                 external       profile            
sip:mod_sofia at 192.168.0.3:5080      RUNNING (0)
                 external       profile            
sip:mod_sofia at 192.168.0.3:5081      RUNNING (0) (TLS)
    external::example.com       gateway                   
sip:joeuser at 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 at 192.168.0.3 Action answer()
Dialplan: sofia/internal/1000 at 192.168.0.3 Action
bridge(dingaling/gmail.com/gilles.gerlinger at gmail.com)
2011-11-05 19:55:10.214583 [DEBUG] switch_core_state_machine.c:154
(sofia/internal/1000 at 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 at 192.168.0.3 [BREAK]
2011-11-05 19:55:10.214583 [DEBUG] switch_core_state_machine.c:410
(sofia/internal/1000 at 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 at 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 at 192.168.0.3) State EXECUTE
2011-11-05 19:55:10.214583 [DEBUG] mod_sofia.c:241
sofia/internal/1000 at 192.168.0.3 SOFIA EXECUTE
2011-11-05 19:55:10.214583 [DEBUG] switch_core_state_machine.c:192
sofia/internal/1000 at 192.168.0.3 Standard EXECUTE
EXECUTE sofia/internal/1000 at 192.168.0.3
hash(insert/192.168.0.3-spymap/1000/af83062a-07df-11e1-8d0e-cdd18f8a8f7f)
EXECUTE sofia/internal/1000 at 192.168.0.3
hash(insert/192.168.0.3-last_dial/1000/4545)
EXECUTE sofia/internal/1000 at 192.168.0.3
hash(insert/192.168.0.3-last_dial/global/af83062a-07df-11e1-8d0e-cdd18f8a8f7f)
EXECUTE sofia/internal/1000 at 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 at 192.168.0.3 SET [RFC2822_DATE]=[Sat, 05 Nov 2011
19:55:10 +0100]
EXECUTE sofia/internal/1000 at 192.168.0.3 answer()
2011-11-05 19:55:10.307908 [DEBUG] sofia_glue.c:3100 AUDIO RTP
[sofia/internal/1000 at 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 at 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 at 192.168.0.3 [BREAK]
2011-11-05 19:55:10.307908 [DEBUG] switch_channel.c:3161
(sofia/internal/1000 at 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 at 192.168.0.3] has been answered
EXECUTE sofia/internal/1000 at 192.168.0.3
bridge(dingaling/gmail.com/gilles.gerlinger at 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 at 192.168.0.3 [BREAK]
2011-11-05 19:55:10.307908 [DEBUG] switch_core_session.c:872 Send signal
sofia/internal/1000 at 192.168.0.3 [BREAK]
2011-11-05 19:55:10.307908 [DEBUG] switch_core_session.c:872 Send signal
sofia/internal/1000 at 192.168.0.3 [BREAK]
2011-11-05 19:55:10.307908 [DEBUG] switch_core_session.c:872 Send signal
sofia/internal/1000 at 192.168.0.3 [BREAK]
2011-11-05 19:55:11.053751 [NOTICE] switch_channel.c:915 New Channel
dingaling/gmail.com/gilles.gerlinger at gmail.com
[af9bd074-07df-11e1-8d13-cdd18f8a8f7f]
2011-11-05 19:55:11.053751 [DEBUG] mod_dingaling.c:1861
(dingaling/gmail.com/gilles.gerlinger at 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 at gmail.com [BREAK]
2011-11-05 19:55:11.053751 [DEBUG] mod_dingaling.c:1396
dingaling/gmail.com/gilles.gerlinger at gmail.com CHANNEL KILL
2011-11-05 19:55:11.053751 [DEBUG] sofia.c:5283 Channel
sofia/internal/1000 at 192.168.0.3 entering state [completed][200]
2011-11-05 19:55:11.053751 [DEBUG] sofia.c:5283 Channel
sofia/internal/1000 at 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 at 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 at gmail.com) State INIT
2011-11-05 19:55:11.073909 [NOTICE] mod_dingaling.c:1158 Ring-Ready
dingaling/gmail.com/gilles.gerlinger at 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 at 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 at wetalk>
freeswitch at 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 at gmail.com/Talk.v104900E9C7C
<gilles.gerlinger at 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 at 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.html
Sent from the freeswitch-users mailing list archive at Nabble.com.



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