+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@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@192.168.1.6:5060 bridge(dingaling/gtalk/+1410969xxxx@voice.google.com) 2012-10-14 01:53:12.428295 [DEBUG] switch_channel.c:1072 sofia/internal/102@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@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@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@voice.google.com [BREAK] 2012-10-14 01:53:12.428295 [DEBUG] mod_dingaling.c:2024 dingaling/gtalk/+1410969xxxx@voice.google.com CHANNEL KILL 2012-10-14 01:53:12.428295 [DEBUG] switch_core_session.c:905 Send signal sofia/internal/102@192.168.1.6:5060 [BREAK] 2012-10-14 01:53:12.448460 [DEBUG] switch_core_state_machine.c:398 (dingaling/gtalk/+1410969xxxx@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@voice.google.com) State INIT 2012-10-14 01:53:12.448460 [NOTICE] mod_dingaling.c:1753 Ring-Ready dingaling/gtalk/+1410969xxxx@voice.google.com! 2012-10-14 01:53:12.448460 [DEBUG] sofia.c:6282 Channel sofia/internal/102@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@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@gmail.com/talk271AA90D" to="+1410969xxxx@voice.google.com" type="set" id="366"> <ses:session xmlns:ses="http://www.google.com/session" type="initiate" id="0021454872" initiator="gvusername@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@voice.google.com" to="gvusername@gmail.com/talk271AA90D" type="error" id="366"> <ses:session type="initiate" id="0021454872" initiator="gvusername@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@voice.google.com/srvres-MTAuNjguOC4zOjk4OTE=</redirect> <ses:redirect xmlns:ses="http://www.google.com/session">xmpp:+1410969xxxx@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@8000] 2012-10-14 01:53:13.608318 [NOTICE] libdingaling.c:1745 SecSEND: ------------------------------------------------------------------------------- <iq type="result" to="+1410969xxxx@voice.google.com" from="xmpp:+1410969xxxx@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@gmail.com/talk271AA90D" to="+1410969xxxx@voice.google.com/srvres-MTAuNjguOC4zOjk4OTE=" type="set" id="367"> <ses:session xmlns:ses="http://www.google.com/session" type="initiate" id="0021454872" initiator="gvusername@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@gmail.com/talk271AA90D" from="+1410969xxxx@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@gmail.com/talk271AA90D" from="+1410969xxxx@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@voice.google.com/srvres-MTAuNjguOC4zOjk4OTE=" to="gvusername@gmail.com/talk271AA90D" id="jingle:10.68.8.3-10726399:1:41658523" type="set"> <ses:session type="terminate" id="0021454872" initiator="gvusername@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@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@voice.google.com) Callstate Change DOWN -> HANGUP
-- View this message in context: http://freeswitch-users.2379917.n2.nabble.com/Problems-with-GV-mod-dingaling... Sent from the freeswitch-users mailing list archive at Nabble.com.
_________________________________________________________________________ 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
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