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

Trel trel.nadal at gmail.com
Thu Oct 18 01:27:49 MSD 2012 

+1 on this issue, identical logs. i believed it to be a google voice
issue and mitigated it by dialing out through a secondary provider =)


On Sun, Oct 14, 2012 at 12:02 PM, PhilQ <philq at qsystemsengineering.com> wrote:
> 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.
>
> _________________________________________________________________________
> Professional FreeSWITCH Consulting Services:
> consulting at freeswitch.org
> http://www.freeswitchsolutions.com
>
> 
> 
>
> Official FreeSWITCH Sites
> http://www.freeswitch.org
> http://wiki.freeswitch.org
> http://www.cluecon.com
>
> FreeSWITCH-users mailing list
> FreeSWITCH-users at lists.freeswitch.org
> http://lists.freeswitch.org/mailman/listinfo/freeswitch-users
> UNSUBSCRIBE:http://lists.freeswitch.org/mailman/options/freeswitch-users
> http://www.freeswitch.org

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