[Freeswitch-users] Problems with GV/mod_dingaling on latest stable version?

PhilQ philq at qsystemsengineering.com
Sun Oct 14 23:02:06 MSD 2012


Thanks Anthony.

Understood on the Jira...

I'm thinking that this problem is limited to me since I switched to HEAD,
did git clean, reset, modwipe, etc. and still have the same problem on the
other branch.

At least with dl_debug I can get some more detailed info although I'm still
not quite sure what's going on with Jingle.

The dns names are present due to the fact that ext_rtp_ip and ext_sip_ip are
set to host:pbxdomain.com which resolves to the IP address of the switch. 
This allows us to easily change the IP if there's a network problem, etc. 
It has been configured this way for quite some time, there have been no
recent changes.

Here are a few snippets of what seems to be relevant in case you or anyone
else has any ideas, trying to keep it short.  I also tried changing the
dialplan to get rid of the preceding '1' with no success, hadn't tried
removing the '+':

EXECUTE sofia/internal/102 at 192.168.1.6:5060
bridge(dingaling/gtalk/+1410969xxxx at voice.google.com)
2012-10-14 01:53:12.428295 [DEBUG] switch_channel.c:1072
sofia/internal/102 at 192.168.1.6:5060 EXPORTING[export_vars]
[domain_name]=[192.168.1.6] to event
2012-10-14 01:53:12.428295 [DEBUG] switch_ivr_originate.c:2005 Parsing
global variables
2012-10-14 01:53:12.428295 [DEBUG] mod_dingaling.c:990 Set Local Key [1
AES_CM_128_HMAC_SHA1_80 inline:bXRH7.........(removed)............dJMcb]
2012-10-14 01:53:12.428295 [DEBUG] mod_dingaling.c:990 Set Local Key [1
AES_CM_128_HMAC_SHA1_80 inline:gGOh1.........(removed)............xpywl]
2012-10-14 01:53:12.428295 [NOTICE] switch_channel.c:951 New Channel
dingaling/gtalk/+1410969xxxx at voice.google.com
[686c8d6b-6838-4491-8058-b133afe1d8f3]
2012-10-14 01:53:12.428295 [CRIT] libdingaling.c:363 Created Session
0021454872

2012-10-14 01:53:12.428295 [DEBUG] mod_dingaling.c:2595
(dingaling/gtalk/+1410969xxxx at voice.google.com) State Change CS_NEW ->
CS_INIT
2012-10-14 01:53:12.428295 [DEBUG] switch_core_session.c:1210 Send signal
dingaling/gtalk/+1410969xxxx at voice.google.com [BREAK]
2012-10-14 01:53:12.428295 [DEBUG] mod_dingaling.c:2024
dingaling/gtalk/+1410969xxxx at voice.google.com CHANNEL KILL
2012-10-14 01:53:12.428295 [DEBUG] switch_core_session.c:905 Send signal
sofia/internal/102 at 192.168.1.6:5060 [BREAK]
2012-10-14 01:53:12.448460 [DEBUG] switch_core_state_machine.c:398
(dingaling/gtalk/+1410969xxxx at voice.google.com) Running State Change CS_INIT
2012-10-14 01:53:12.448460 [DEBUG] switch_core_state_machine.c:437
(dingaling/gtalk/+1410969xxxx at voice.google.com) State INIT
2012-10-14 01:53:12.448460 [NOTICE] mod_dingaling.c:1753 Ring-Ready
dingaling/gtalk/+1410969xxxx at voice.google.com!
2012-10-14 01:53:12.448460 [DEBUG] sofia.c:6282 Channel
sofia/internal/102 at 192.168.1.6:5060 entering state [early][180]
2012-10-14 01:53:12.448460 [DEBUG] mod_dingaling.c:1629 Don't have my audio
codec yet here's one
2012-10-14 01:53:12.448460 [DEBUG] mod_dingaling.c:1675 Don't have video
codec.
2012-10-14 01:53:12.448460 [DEBUG] mod_dingaling.c:1686 Send Describe
[PCMU at 8000]
2012-10-14 01:53:13.388338 [CRIT] libdingaling.c:1830 Processing 1 packets
in retry queue

2012-10-14 01:53:13.388338 [CRIT] libdingaling.c:1847 Sending packet 366 (2
left)

2012-10-14 01:53:13.388338 [NOTICE] libdingaling.c:1745 SecSEND:
-------------------------------------------------------------------------------
<iq xmlns="jabber:client" from="gvusername at gmail.com/talk271AA90D"
to="+1410969xxxx at voice.google.com" type="set" id="366">
  <ses:session xmlns:ses="http://www.google.com/session" type="initiate"
id="0021454872" initiator="gvusername at gmail.com/talk271AA90D">
    <pho:description xmlns:pho="http://www.google.com/session/phone">
      <pho:payload-type id="0" name="PCMU" clockrate="8000" bitrate="64000"
xmlns:pho="http://www.google.com/session/phone"></pho:payload-type>
    </pho:description>
  </ses:session>
</iq>

2012-10-14 01:53:13.608318 [INFO] libdingaling.c:1743 SecRECV:
-------------------------------------------------------------------------------
<iq from="+1410969xxxx at voice.google.com"
to="gvusername at gmail.com/talk271AA90D" type="error" id="366">
  <ses:session type="initiate" id="0021454872"
initiator="gvusername at gmail.com/talk271AA90D"
xmlns:ses="http://www.google.com/session">
    <pho:description xmlns:pho="http://www.google.com/session/phone">
      <pho:payload-type id="0" name="PCMU" clockrate="8000"
bitrate="64000"></pho:payload-type>
    </pho:description>
  </ses:session>
  <error code="302" type="modify">
    <redirect
xmlns="urn:ietf:params:xml:ns:xmpp-stanzas">xmpp:+1410969xxxx at voice.google.com/srvres-MTAuNjguOC4zOjk4OTE=</redirect>
    <ses:redirect
xmlns:ses="http://www.google.com/session">xmpp:+1410969xxxx at voice.google.com/srvres-MTAuNjguOC4zOjk4OTE=</ses:redirect>
  </error>
</iq>

2012-10-14 01:53:13.608318 [CRIT] libdingaling.c:1305 Cancel packet 366

2012-10-14 01:53:13.608318 [CRIT] libdingaling.c:391 Message for Session
0021454872

2012-10-14 01:53:13.608318 [DEBUG] mod_dingaling.c:4055 using Existing
session for 0021454872
2012-10-14 01:53:13.608318 [DEBUG] mod_dingaling.c:1675 Don't have video
codec.
2012-10-14 01:53:13.608318 [DEBUG] mod_dingaling.c:1686 Send Describe
[PCMU at 8000]
2012-10-14 01:53:13.608318 [NOTICE] libdingaling.c:1745 SecSEND:
-------------------------------------------------------------------------------
<iq type="result" to="+1410969xxxx at voice.google.com"
from="xmpp:+1410969xxxx at voice.google.com/srvres-MTAuNjguOC4zOjk4OTE="
id="366">
  <query></query>
</iq>

2012-10-14 01:53:13.608318 [CRIT] libdingaling.c:1830 Processing 2 packets
in retry queue

2012-10-14 01:53:13.608318 [CRIT] libdingaling.c:1856 Discarding packet 366

2012-10-14 01:53:13.608318 [CRIT] libdingaling.c:1847 Sending packet 367 (2
left)

2012-10-14 01:53:13.608318 [NOTICE] libdingaling.c:1745 SecSEND:
-------------------------------------------------------------------------------
<iq xmlns="jabber:client" from="gvusername at gmail.com/talk271AA90D"
to="+1410969xxxx at voice.google.com/srvres-MTAuNjguOC4zOjk4OTE=" type="set"
id="367">
  <ses:session xmlns:ses="http://www.google.com/session" type="initiate"
id="0021454872" initiator="gvusername at gmail.com/talk271AA90D">
    <pho:description xmlns:pho="http://www.google.com/session/phone">
      <pho:payload-type id="0" name="PCMU" clockrate="8000" bitrate="64000"
xmlns:pho="http://www.google.com/session/phone"></pho:payload-type>
    </pho:description>
  </ses:session>
</iq>

2012-10-14 01:53:14.168342 [INFO] libdingaling.c:1743 SecRECV:
-------------------------------------------------------------------------------
<iq to="gvusername at gmail.com/talk271AA90D"
from="+1410969xxxx at voice.google.com/srvres-MTAuNjguOC4zOjk4OTE=" id="367"
type="result"></iq>

2012-10-14 01:53:14.168342 [CRIT] libdingaling.c:1305 Cancel packet 367

2012-10-14 01:53:14.168342 [CRIT] libdingaling.c:1830 Processing 1 packets
in retry queue

2012-10-14 01:53:14.168342 [CRIT] libdingaling.c:1856 Discarding packet 367

2012-10-14 01:53:22.468199 [DEBUG] mod_dingaling.c:1525 Send rtp Candidate
voip.mypbxdomain.com:24480 [DTfQI2DAbBjxcPVQ]
2012-10-14 01:53:22.468199 [DEBUG] mod_dingaling.c:1525 Send rtcp Candidate
voip.mypbxdomain.com:24481 [LwTkpnKqOUNRAzyt]
2012-10-14 01:53:22.468199 [DEBUG] mod_dingaling.c:1525 Send video_rtp
Candidate voip.mypbxdomain.com:24482 [DIQSWr9AbNhCdFVW]
2012-10-14 01:53:22.468199 [DEBUG] mod_dingaling.c:1525 Send video_rtcp
Candidate voip.mypbxdomain.com:24483 [8vBUMBkOeshiY24v]
2012-10-14 01:53:22.468199 [DEBUG] mod_dingaling.c:1565 Accepted 0 of 0 rtp
candidates.
2012-10-14 01:53:22.468199 [DEBUG] mod_dingaling.c:1567 Accepted 0 of 0 rtcp
candidates.
2012-10-14 01:53:22.468199 [DEBUG] mod_dingaling.c:1570 Accepted 0 of 0
video_rtp candidates
2012-10-14 01:53:22.468199 [DEBUG] mod_dingaling.c:1573 Accepted 0 of 0
video_rctp candidates
2012-10-14 01:53:22.968198 [CRIT] libdingaling.c:1830 Processing 4 packets
in retry queue


Then a bit further down, we appear to have some sort of session timeout and
a recipient unavailable message:


2012-10-14 01:53:23.368270 [INFO] libdingaling.c:1743 SecRECV:
-------------------------------------------------------------------------------
<iq to="gvusername at gmail.com/talk271AA90D"
from="+1410969xxxx at voice.google.com/srvres-MTAuNjguOC4zOjk4OTE=" id="371"
type="result"></iq>

2012-10-14 01:53:23.368270 [CRIT] libdingaling.c:1305 Cancel packet 371

2012-10-14 01:53:23.368270 [CRIT] libdingaling.c:1830 Processing 1 packets
in retry queue

2012-10-14 01:53:23.368270 [CRIT] libdingaling.c:1856 Discarding packet 371

2012-10-14 01:53:29.168109 [INFO] libdingaling.c:1743 SecRECV:
-------------------------------------------------------------------------------
<iq from="+1410969xxxx at voice.google.com/srvres-MTAuNjguOC4zOjk4OTE="
to="gvusername at gmail.com/talk271AA90D"
id="jingle:10.68.8.3-10726399:1:41658523" type="set">
  <ses:session type="terminate" id="0021454872"
initiator="gvusername at gmail.com/talk271AA90D"
xmlns:ses="http://www.google.com/session">
    <ses:reason>
      <ses:timeout></ses:timeout>
      <ses:text>Session timed out</ses:text>
    </ses:reason>
    <pho:recipient-unavailable
xmlns:pho="http://www.google.com/session/phone"></pho:recipient-unavailable>
  </ses:session>
</iq>

2012-10-14 01:53:29.168109 [CRIT] libdingaling.c:391 Message for Session
0021454872

2012-10-14 01:53:29.168109 [DEBUG] mod_dingaling.c:4055 using Existing
session for 0021454872
2012-10-14 01:53:29.168109 [DEBUG] mod_dingaling.c:4373 hungup
dingaling/gtalk/+1410969xxxx at voice.google.com
2012-10-14 01:53:29.168109 [DEBUG] mod_dingaling.c:801 Terminate called from
line 4374 state=CS_INIT
2012-10-14 01:53:29.168109 [DEBUG] switch_channel.c:2950
(dingaling/gtalk/+1410969xxxx at voice.google.com) Callstate Change DOWN ->
HANGUP




--
View this message in context: http://freeswitch-users.2379917.n2.nabble.com/Problems-with-GV-mod-dingaling-on-latest-stable-version-tp7583499p7583610.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