GVoice In Flames No More

ghurty

Senior Member
Joined
Jan 13, 2009
Messages
852
Reaction score
4
Google voice inbound has just died on me.

The log shows it hitting the asterisk box. It plays the dtmf, then goes to the next step. However to the caller it just keeps on ringing. I tried it with three purple boxes that I have and I have the same problem with each of them (I turned off the need for dtmf 1 but it still doesnt work):


Code:
[2011-03-21 19:31:38] VERBOSE[5147] pbx.c: -- Executing [[email protected]@googlein:1] GotoIf("Gtalk/+18885551212-6908", "1?googlein1,s,1:googlein2,s,1]") in new stack
[2011-03-21 19:31:38] VERBOSE[5147] pbx.c: -- Goto (googlein1,s,1)
[2011-03-21 19:31:38] VERBOSE[5147] pbx.c: -- Executing [s@googlein1:1] Wait("Gtalk/+18885551212-6908", "1") in new stack
[2011-03-21 19:31:39] VERBOSE[5147] pbx.c: -- Executing [s@googlein1:2] Set("Gtalk/+18885551212-6908", "[email protected]") in new stack
[2011-03-21 19:31:39] VERBOSE[5147] pbx.c: -- Executing [s@googlein1:3] JabberSend("Gtalk/+18885551212-6908", "asterisk,[email protected],Incoming Google Voice Call: 8885551212") in new stack
[2011-03-21 19:31:39] VERBOSE[5147] pbx.c: -- Executing [s@googlein1:4] Set("Gtalk/+18885551212-6908", "CALLERID(number)=8885551212") in new stack
[2011-03-21 19:31:39] VERBOSE[5147] pbx.c: -- Executing [s@googlein1:5] Set("Gtalk/+18885551212-6908", "CALLERID(name)=8885551212") in new stack
[2011-03-21 19:31:39] VERBOSE[5147] pbx.c: -- Executing [s@googlein1:6] Answer("Gtalk/+18885551212-6908", "") in new stack
[2011-03-21 19:31:40] VERBOSE[5147] pbx.c: -- Executing [s@googlein1:7] Wait("Gtalk/+18885551212-6908", "2") in new stack
[2011-03-21 19:31:42] VERBOSE[5147] pbx.c: -- Executing [s@googlein1:8] SendDTMF("Gtalk/+18885551212-6908", "1") in new stack
[2011-03-21 19:31:42] VERBOSE[5147] pbx.c: -- Executing [s@googlein1:9] Goto("Gtalk/+18885551212-6908", "from-trunk,gv-incoming,1") in new stack
[2011-03-21 19:31:42] VERBOSE[5147] pbx.c: -- Goto (from-trunk,gv-incoming,1)
[2011-03-21 19:31:42] VERBOSE[5147] pbx.c: -- Executing [gv-incoming@from-trunk:1] Set("Gtalk/+18885551212-6908", "__FROM_DID=gv-incoming") in new stack
[2011-03-21 19:31:42] VERBOSE[5147] pbx.c: -- Executing [gv-incoming@from-trunk:2] Gosub("Gtalk/+18885551212-6908", "app-blacklist-check,s,1") in new stack
[2011-03-21 19:31:42] VERBOSE[5147] pbx.c: -- Executing [s@app-blacklist-check:1] GotoIf("Gtalk/+18885551212-6908", "0?blacklisted") in new stack
[2011-03-21 19:31:42] VERBOSE[5147] pbx.c: -- Executing [s@app-blacklist-check:2] Set("Gtalk/+18885551212-6908", "CALLED_BLACKLIST=1") in new stack
[2011-03-21 19:31:42] VERBOSE[5147] pbx.c: -- Executing [s@app-blacklist-check:3] Return("Gtalk/+18885551212-6908", "") in new stack
[2011-03-21 19:31:42] VERBOSE[5147] pbx.c: -- Executing [gv-incoming@from-trunk:3] Gosub("Gtalk/+18885551212-6908", "cidlookup,cidlookup_3,1") in new stack
[2011-03-21 19:31:42] VERBOSE[5149] manager.c: == Manager 'admin' logged on from 127.0.0.1
[2011-03-21 19:31:44] VERBOSE[5149] manager.c: == Manager 'admin' logged off from 127.0.0.1
[2011-03-21 19:31:44] VERBOSE[5147] pbx.c: -- Executing [cidlookup_3@cidlookup:1] Set("Gtalk/+18885551212-6908", "CALLERID(name)=John Smith") in new stack
[2011-03-21 19:31:44] VERBOSE[5147] pbx.c: -- Executing [cidlookup_3@cidlookup:2] Return("Gtalk/+18885551212-6908", "") in new stack
[2011-03-21 19:31:44] VERBOSE[5147] pbx.c: -- Executing [gv-incoming@from-trunk:4] ExecIf("Gtalk/+18885551212-6908", "0 ?Set(CALLERID(name)=8885551212)") in new stack
[2011-03-21 19:31:44] VERBOSE[5147] pbx.c: -- Executing [gv-incoming@from-trunk:5] Ringing("Gtalk/+18885551212-6908", "") in new stack
[2011-03-21 19:31:44] VERBOSE[5147] pbx.c: -- Executing [gv-incoming@from-trunk:6] Set("Gtalk/+18885551212-6908", "__CALLINGPRES_SV=allowed_not_screened") in new stack
[2011-03-21 19:31:44] VERBOSE[5147] pbx.c: -- Executing [gv-incoming@from-trunk:7] Set("Gtalk/+18885551212-6908", "CALLERPRES()=allowed_not_screened") in new stack
[2011-03-21 19:31:44] VERBOSE[5147] pbx.c: -- Executing [gv-incoming@from-trunk:8] Goto("Gtalk/+18885551212-6908", "timeconditions,1,1") in new stack
[2011-03-21 19:31:44] VERBOSE[5147] pbx.c: -- Goto (timeconditions,1,1)
[2011-03-21 19:31:44] VERBOSE[5147] pbx.c: -- Executing [1@timeconditions:1] GotoIfTime("Gtalk/+18885551212-6908", "08:00-17:30,mon-fri,*,*?ivr-4,s,1") in new stack
[2011-03-21 19:31:44] VERBOSE[5147] pbx.c: -- Executing [1@timeconditions:2] Goto("Gtalk/+18885551212-6908", "ivr-5,s,1") in new stack
[2011-03-21 19:31:44] VERBOSE[5147] pbx.c: -- Goto (ivr-5,s,1)
[2011-03-21 19:31:44] VERBOSE[5147] pbx.c: -- Executing [s@ivr-5:1] Set("Gtalk/+18885551212-6908", "MSG=custom/test") in new stack
[2011-03-21 19:31:44] VERBOSE[5147] pbx.c: -- Executing [s@ivr-5:2] Set("Gtalk/+18885551212-6908", "LOOPCOUNT=0") in new stack
[2011-03-21 19:31:44] VERBOSE[5147] pbx.c: -- Executing [s@ivr-5:3] Set("Gtalk/+18885551212-6908", "__DIR-CONTEXT=") in new stack
[2011-03-21 19:31:44] VERBOSE[5147] pbx.c: -- Executing [s@ivr-5:4] Set("Gtalk/+18885551212-6908", "_IVR_CONTEXT_ivr-5=") in new stack
[2011-03-21 19:31:44] VERBOSE[5147] pbx.c: -- Executing [s@ivr-5:5] Set("Gtalk/+18885551212-6908", "_IVR_CONTEXT=ivr-5") in new stack
[2011-03-21 19:31:44] VERBOSE[5147] pbx.c: -- Executing [s@ivr-5:6] GotoIf("Gtalk/+18885551212-6908", "1?begin") in new stack
[2011-03-21 19:31:44] VERBOSE[5147] pbx.c: -- Goto (ivr-5,s,9)
[2011-03-21 19:31:44] VERBOSE[5147] pbx.c: -- Executing [s@ivr-5:9] Set("Gtalk/+18885551212-6908", "TIMEOUT(digit)=3") in new stack
[2011-03-21 19:31:44] VERBOSE[5147] func_timeout.c: -- Digit timeout set to 3.000
[2011-03-21 19:31:44] VERBOSE[5147] pbx.c: -- Executing [s@ivr-5:10] Set("Gtalk/+18885551212-6908", "TIMEOUT(response)=10") in new stack
[2011-03-21 19:31:44] VERBOSE[5147] func_timeout.c: -- Response timeout set to 10.000
[2011-03-21 19:31:44] VERBOSE[5147] pbx.c: -- Executing [s@ivr-5:11] Set("Gtalk/+18885551212-6908", "__IVR_RETVM=") in new stack
[2011-03-21 19:31:44] VERBOSE[5147] pbx.c: -- Executing [s@ivr-5:12] ExecIf("Gtalk/+18885551212-6908", "1?Background(custom/test)") in new stack
[2011-03-21 19:31:44] VERBOSE[5147] file.c: -- <Gtalk/+18885551212-6908> Playing 'custom/test.slin' (language 'en')
 

RXWatcher

Member
Joined
Oct 19, 2010
Messages
37
Reaction score
1
I think I'm seeing the same thing..I even rebuilt my server thinking I toasted something. In my case the caller never hears the 'please wait while we find someone..." and my ext 701 rings for 1 ring before its passed onto to the google voice voicemail.

[2011-03-21 19:55:55] VERBOSE[4171] pbx.c: -- Goto (googlein,s,6)
[2011-03-21 19:55:55] VERBOSE[4171] pbx.c: -- Executing [s@googlein:6] Answer("Gtalk/+8885551215-9ff8", "") in new stack
[2011-03-21 19:55:56] VERBOSE[4171] pbx.c: -- Executing [s@googlein:7] SendDTMF("Gtalk/+8885551215-9ff8", "1") in new stack
[2011-03-21 19:55:56] VERBOSE[4171] pbx.c: -- Executing [s@googlein:8] Goto("Gtalk/+8885551215-9ff8", "from-trunk,gv-incoming,1") in new stack
[2011-03-21 19:55:56] VERBOSE[4171] pbx.c: -- Goto (from-trunk,gv-incoming,1)
[2011-03-21 19:55:56] VERBOSE[4171] pbx.c: -- Executing [gv-incoming@from-trunk:1] Set("Gtalk/+8885551215-9ff8", "__FROM_DID=gv-incoming") in new stack
[2011-03-21 19:55:56] VERBOSE[4171] pbx.c: -- Executing [gv-incoming@from-trunk:2] Gosub("Gtalk/+8885551215-9ff8", "app-blacklist-check,s,1") in new stack
[2011-03-21 19:55:56] VERBOSE[4171] pbx.c: -- Executing [s@app-blacklist-check:1] GotoIf("Gtalk/+8885551215-9ff8", "0?blacklisted") in new stack
[2011-03-21 19:55:56] VERBOSE[4171] pbx.c: -- Executing [s@app-blacklist-check:2] Set("Gtalk/+8885551215-9ff8", "CALLED_BLACKLIST=1") in new stack
[2011-03-21 19:55:56] VERBOSE[4171] pbx.c: -- Executing [s@app-blacklist-check:3] Return("Gtalk/+8885551215-9ff8", "") in new stack
[2011-03-21 19:55:56] VERBOSE[4171] pbx.c: -- Executing [gv-incoming@from-trunk:3] Gosub("Gtalk/+8885551215-9ff8", "cidlookup,cidlookup_3,1") in new stack
[2011-03-21 19:55:56] VERBOSE[4174] manager.c: == Manager 'admin' logged on from 127.0.0.1
[2011-03-21 19:55:59] VERBOSE[4174] manager.c: == Manager 'admin' logged off from 127.0.0.1
[2011-03-21 19:55:59] NOTICE[3242] chan_sip.c: -- Registration for '[email protected]' timed out, trying again (Attempt #789)
[2011-03-21 19:55:59] VERBOSE[4171] pbx.c: -- Executing [cidlookup_3@cidlookup:1] Set("Gtalk/+8885551215-9ff8", "CALLERID(name)=AMES, IA") in new stack
[2011-03-21 19:55:59] VERBOSE[4171] pbx.c: -- Executing [cidlookup_3@cidlookup:2] Return("Gtalk/+8885551215-9ff8", "") in new stack
[2011-03-21 19:55:59] VERBOSE[4171] pbx.c: -- Executing [gv-incoming@from-trunk:4] ExecIf("Gtalk/+8885551215-9ff8", "0 ?Set(CALLERID(name)=8885551215)") in new stack
[2011-03-21 19:55:59] WARNING[4171] func_callerid.c: CALLERPRES is deprecated. Use CALLERID(name-pres) or CALLERID(num-pres) instead.
[2011-03-21 19:55:59] VERBOSE[4171] pbx.c: -- Executing [gv-incoming@from-trunk:5] Set("Gtalk/+8885551215-9ff8", "__CALLINGPRES_SV=allowed_not_screened") in new stack
[2011-03-21 19:55:59] VERBOSE[4171] pbx.c: -- Executing [gv-incoming@from-trunk:6] Set("Gtalk/+8885551215-9ff8", "CALLERPRES()=allowed_not_screened") in new stack
[2011-03-21 19:55:59] VERBOSE[4171] pbx.c: -- Executing [gv-incoming@from-trunk:7] Goto("Gtalk/+8885551215-9ff8", "app-daynight,1,1") in new stack
[2011-03-21 19:55:59] VERBOSE[4171] pbx.c: -- Goto (app-daynight,1,1)
[2011-03-21 19:55:59] VERBOSE[4171] pbx.c: -- Executing [1@app-daynight:1] GotoIf("Gtalk/+8885551215-9ff8", "0?ext-local,vmu701,1:ivr-3,s,1") in new stack
[2011-03-21 19:55:59] VERBOSE[4171] pbx.c: -- Goto (ivr-3,s,1)
[2011-03-21 19:55:59] VERBOSE[4171] pbx.c: -- Executing [s@ivr-3:1] Set("Gtalk/+8885551215-9ff8", "MSG=custom/nv-GenericWelcome") in new stack
[2011-03-21 19:55:59] VERBOSE[4171] pbx.c: -- Executing [s@ivr-3:2] Set("Gtalk/+8885551215-9ff8", "LOOPCOUNT=0") in new stack
[2011-03-21 19:55:59] VERBOSE[4171] pbx.c: -- Executing [s@ivr-3:3] Set("Gtalk/+8885551215-9ff8", "__DIR-CONTEXT=default") in new stack
[2011-03-21 19:55:59] VERBOSE[4171] pbx.c: -- Executing [s@ivr-3:4] Set("Gtalk/+8885551215-9ff8", "_IVR_CONTEXT_ivr-3=") in new stack
[2011-03-21 19:55:59] VERBOSE[4171] pbx.c: -- Executing [s@ivr-3:5] Set("Gtalk/+8885551215-9ff8", "_IVR_CONTEXT=ivr-3") in new stack
[2011-03-21 19:55:59] VERBOSE[4171] pbx.c: -- Executing [s@ivr-3:6] GotoIf("Gtalk/+8885551215-9ff8", "1?begin") in new stack
[2011-03-21 19:55:59] VERBOSE[4171] pbx.c: -- Goto (ivr-3,s,9)
[2011-03-21 19:55:59] VERBOSE[4171] pbx.c: -- Executing [s@ivr-3:9] Set("Gtalk/+8885551215-9ff8", "TIMEOUT(digit)=3") in new stack
[2011-03-21 19:55:59] VERBOSE[4171] func_timeout.c: -- Digit timeout set to 3.000
[2011-03-21 19:55:59] VERBOSE[4171] pbx.c: -- Executing [s@ivr-3:10] Set("Gtalk/+8885551215-9ff8", "TIMEOUT(response)=5") in new stack
[2011-03-21 19:55:59] VERBOSE[4171] func_timeout.c: -- Response timeout set to 5.000
[2011-03-21 19:55:59] VERBOSE[4171] pbx.c: -- Executing [s@ivr-3:11] Set("Gtalk/+8885551215-9ff8", "__IVR_RETVM=") in new stack
[2011-03-21 19:55:59] VERBOSE[4171] pbx.c: -- Executing [s@ivr-3:12] ExecIf("Gtalk/+8885551215-9ff8", "1?Background(custom/nv-GenericWelcome)") in new stack
[2011-03-21 19:55:59] VERBOSE[4171] file.c: -- <Gtalk/+8885551215-9ff8> Playing 'custom/nv-GenericWelcome.gsm' (language 'en')
[2011-03-21 19:55:59] NOTICE[3242] chan_sip.c: Failed to authenticate on REGISTER to '[email protected]' (Tries 3)
[2011-03-21 19:56:00] VERBOSE[4176] manager.c: == Manager 'admin' logged on from 127.0.0.1
[2011-03-21 19:56:01] VERBOSE[4176] manager.c: == Manager 'admin' logged off from 127.0.0.1
[2011-03-21 19:56:04] VERBOSE[4171] pbx.c: -- Executing [s@ivr-3:13] WaitExten("Gtalk/+8885551215-9ff8", ",") in new stack
[2011-03-21 19:56:07] VERBOSE[4184] manager.c: == Manager 'admin' logged on from 127.0.0.1
[2011-03-21 19:56:09] VERBOSE[4184] manager.c: == Manager 'admin' logged off from 127.0.0.1
[2011-03-21 19:56:09] VERBOSE[4171] pbx.c: -- Timeout on Gtalk/+8885551215-9ff8, going to 't'
[2011-03-21 19:56:09] WARNING[4171] func_db.c: DB_DELETE requires an argument, DB_DELETE(<family>/<key>)
[2011-03-21 19:56:09] VERBOSE[4171] pbx.c: -- Executing [t@ivr-3:1] NoOp("Gtalk/+8885551215-9ff8", "Deleting: ") in new stack
[2011-03-21 19:56:09] VERBOSE[4171] pbx.c: -- Executing [t@ivr-3:2] Set("Gtalk/+8885551215-9ff8", "__NODEST=") in new stack
[2011-03-21 19:56:09] VERBOSE[4171] pbx.c: -- Executing [t@ivr-3:3] Goto("Gtalk/+8885551215-9ff8", "ext-group,777,1") in new stack
[2011-03-21 19:56:09] VERBOSE[4171] pbx.c: -- Goto (ext-group,777,1)
[2011-03-21 19:56:09] VERBOSE[4171] pbx.c: -- Executing [777@ext-group:1] Macro("Gtalk/+8885551215-9ff8", "user-callerid,") in new stack
[2011-03-21 19:56:09] VERBOSE[4171] pbx.c: -- Executing [s@macro-user-callerid:1] Set("Gtalk/+8885551215-9ff8", "AMPUSER=5155098325") in new stack
[2011-03-21 19:56:09] VERBOSE[4171] pbx.c: -- Executing [s@macro-user-callerid:2] GotoIf("Gtalk/+8885551215-9ff8", "0?report") in new stack
[2011-03-21 19:56:09] VERBOSE[4171] pbx.c: -- Executing [s@macro-user-callerid:3] ExecIf("Gtalk/+8885551215-9ff8", "1?Set(REALCALLERIDNUM=8885551215)") in new stack
[2011-03-21 19:56:09] VERBOSE[4171] pbx.c: -- Executing [s@macro-user-callerid:4] Set("Gtalk/+8885551215-9ff8", "AMPUSER=") in new stack
[2011-03-21 19:56:09] VERBOSE[4171] pbx.c: -- Executing [s@macro-user-callerid:5] Set("Gtalk/+8885551215-9ff8", "AMPUSERCIDNAME=") in new stack
[2011-03-21 19:56:09] VERBOSE[4171] pbx.c: -- Executing [s@macro-user-callerid:6] GotoIf("Gtalk/+8885551215-9ff8", "1?report") in new stack
[2011-03-21 19:56:09] VERBOSE[4171] pbx.c: -- Goto (macro-user-callerid,s,10)
[2011-03-21 19:56:09] VERBOSE[4171] pbx.c: -- Executing [s@macro-user-callerid:10] GotoIf("Gtalk/+8885551215-9ff8", "0?continue") in new stack
[2011-03-21 19:56:09] VERBOSE[4171] pbx.c: -- Executing [s@macro-user-callerid:11] Set("Gtalk/+8885551215-9ff8", "__TTL=64") in new stack
[2011-03-21 19:56:09] VERBOSE[4171] pbx.c: -- Executing [s@macro-user-callerid:12] GotoIf("Gtalk/+8885551215-9ff8", "1?continue") in new stack
[2011-03-21 19:56:09] VERBOSE[4171] pbx.c: -- Goto (macro-user-callerid,s,19)
[2011-03-21 19:56:09] VERBOSE[4171] pbx.c: -- Executing [s@macro-user-callerid:19] Set("Gtalk/+8885551215-9ff8", "CALLERID(number)=8885551215") in new stack
[2011-03-21 19:56:09] VERBOSE[4171] pbx.c: -- Executing [s@macro-user-callerid:20] Set("Gtalk/+8885551215-9ff8", "CALLERID(name)=AMES, IA") in new stack
[2011-03-21 19:56:09] VERBOSE[4171] pbx.c: -- Executing [s@macro-user-callerid:21] NoOp("Gtalk/+8885551215-9ff8", "Using CallerID "AMES, IA" <8885551215>") in new stack
[2011-03-21 19:56:09] VERBOSE[4171] pbx.c: -- Executing [777@ext-group:2] GotoIf("Gtalk/+8885551215-9ff8", "1?skipdb") in new stack
[2011-03-21 19:56:09] VERBOSE[4171] pbx.c: -- Goto (ext-group,777,4)
[2011-03-21 19:56:09] VERBOSE[4171] pbx.c: -- Executing [777@ext-group:4] Set("Gtalk/+8885551215-9ff8", "__NODEST=") in new stack
[2011-03-21 19:56:09] VERBOSE[4171] pbx.c: -- Executing [777@ext-group:5] Set("Gtalk/+8885551215-9ff8", "__BLKVM_OVERRIDE=BLKVM/777/Gtalk/+8885551215-9ff8") in new stack
[2011-03-21 19:56:09] VERBOSE[4171] pbx.c: -- Executing [777@ext-group:6] Set("Gtalk/+8885551215-9ff8", "__BLKVM_BASE=777") in new stack
 

dadale73

New Member
Joined
Feb 24, 2011
Messages
6
Reaction score
0
Same thing in mine.... its something to do with the call screening...

when it rings just press 1 and see if you get the caller.....
 

ghurty

Senior Member
Joined
Jan 13, 2009
Messages
852
Reaction score
4
Same thing in mine.... its something to do with the call screening...

when it rings just press 1 and see if you get the caller.....

I disabled call screening in google voice and it still didnt work
 

darmock

PIAF Developer
Joined
Oct 18, 2007
Messages
2,892
Reaction score
98
While I feel your pain it is probably not related to PIAF rather it is google tinkering with things. I have an up to the minute purple running in the lab and GV is intermittent.

Odd that my obi100 works just fine though.


Tom
 

dadale73

New Member
Joined
Feb 24, 2011
Messages
6
Reaction score
0
i have call screening turned off too.. but if i pick up the phone when it rings and press 1, it answers and connects...
 

ghurty

Senior Member
Joined
Jan 13, 2009
Messages
852
Reaction score
4
SOLUTION:It appears that google voice has increased the time before it prompts you to "accept the call". So to fix, go to etc\asterisk\extensions_custom.conf.
Go down to [googlein]
change the 2 to and 8 by exten => s,n,Wait

Any number less the 8 sometimes will work sometimes wont. I tried it with 8 about 25 times in a row and it worked everytime.

Code:
exten => s,n,Set(CALLERID(number)=${CALLERID(name):2:10})
exten => s,n,Set(CALLERID(name)=${CALLERID(number)})
exten => s,n,Answer
exten => s,n,Wait(8)
exten => s,n,SendDTMF(1)
exten => s,n,Goto(from-trunk,gv-incoming,1)

Thank You for everyone who helped.
 
Joined
Jun 29, 2009
Messages
258
Reaction score
0
I'm glad that has worked for some of you. It has not for me. I've tried taking out the automatic send of the "1" and passing the call to the destination extension and I notice that often the prompt never even plays before it goes to voicemail.

I suspect that there are two things the need to happen to make this work more reliably:

  1. actual detection of the start of the prompt. I actually thought I had read somewhere that later version of Asterisk did have some kind of voice detection feature but darned if I can find it.
  2. longer duration DTMF tone playback. Unfortunately SendDTMF produces a fixed length tone and as far as I can tell there's no way to change it, and my suspicion is it's now just a BIT too short. It's almost as if someone said, hmmm, Asterisk can only send a 250ms tone (or whatever it is), let's require 260 ms for the tone to be recognized. Or something like that.

I may be way off base here but that's what my thinking is.
 

ghurty

Senior Member
Joined
Jan 13, 2009
Messages
852
Reaction score
4
I noticed that when i pick up using the gmail google voice applet, it plays the prompt almost right away.

I think that for some reason, with asterisk it is taking time for it to send the "answer" signal to google voice.

What happens if you send it to the extension and pick up. Do you hear the prompt, and if yes what happens if you press "1"
 
Joined
Jun 29, 2009
Messages
258
Reaction score
0
What happens if you send it to the extension and pick up. Do you hear the prompt, and if yes what happens if you press "1"

A certain percentage of the time I never hear the prompt at all - it just sounds like there is no one there. But if I do hear the prompt and press "1", the call connects through, provided I do it before the call is sent to GV voicemail. And one thing I note is that the timeout to voicemail does NOT restart when you answer, so if you pick it up on say the fourth ring you had better be quick about entering that "1".

Tonight it's just not playing the prompt at all, with rare exceptions. And when it doesn't play the prompt, pressing "1" has no effect at all.
 

tiggerpaws

Member
Joined
Feb 6, 2011
Messages
105
Reaction score
6
You won't belive this, but I just let mine go, and it started workign again. Did Google dink around again or did mine load an automatic update? Yikes. Scary stuff. But I cannot grumble, it works. On the other paw, I decreased the number of caller ID lookups to decrease the wait after the first ring, to the system answering/sending to extension, and maybe that did somethign?
 
Joined
Jun 29, 2009
Messages
258
Reaction score
0
Just a suggestion: If anyone is able to attend this webinar tomorrow, you might want to ask whether the Digium folks are aware of this issue, and also ask if they can suggest any specific workarounds.

I just did a series of five test calls. On three out of five I got the prompt and was able to press 1 to accept. On the other two I got dead air (after answering the call) and was never given the prompt to accept the call before it went to Google's voicemail. This is sending the call direct to an extension, not trying to have Asterisk intercept it.

So far this issue does not seem to have affected incoming calls on my Obihai devices at all. This makes me think that there just may be an issue specific to the Asterisk channel driver, that only Digium might be able to fix.
 

wardmundy

Nerd Uno
Joined
Oct 12, 2007
Messages
19,168
Reaction score
5,199
cheapflights1.png
 

malcolmd

Guru
Joined
Aug 12, 2010
Messages
101
Reaction score
7
Can anyone get me a jabber log (set debug=yes in the general section of jabber.conf) of a call failing?

I saw this a week ago, but it was very intermittent and I'm not able to reproduce it thus far today after many attempts. So, I've got successful call logs. Someone's got an unsuccessful log, let's compare.

..just when I thought I had my Powerpoint complete for Thursday's presentation... :(
 

eCase

New Member
Joined
Jan 26, 2011
Messages
161
Reaction score
0
Jabber Log (Sort of)

Hey Guys:

malcolmd, last night I did a fresh install of PIAF Purple on my box. This morning I ran the following, in order:
update-programs, update-fixes, passwd-manager, incrediblepbx18x install script, update-programs, update-fixes, passwd-master (just to be safe)

From my LAN connected pc, I browsed to the freepbx gui to check the Model Admin. There was only one module listed in red, it was one of the FreePBX Framework modules in the Basic Section.
I did not choose to upgrade to FreePBX 2.9beta.

I am able to dial-out with no problems. When dialing in (from my non-linked cell) it rings my configured softphone (xtn 701) I answer it and can hear nothing, and then after 4 seconds the call ends from the perspective of the softphone, but the in-caller is then sent to the gmail account voicemail.

I believe my issue is related to this thread - I have a copy of all the Asterisk Log files since the fresh install late last night - you are welcome to a non-screened copy if you want.
I will also prepare a Jabber debug log for you.
PM me with your email address.
Thanks
 

wardmundy

Nerd Uno
Joined
Oct 12, 2007
Messages
19,168
Reaction score
5,199
There seems to be a timing quirk that's been injected at Google's end. For those using Incredible PBX, new downloads already have been patched. For existing installs, here's the fix that seems to work. In /etc/asterisk, edit extensions_custom.conf. Scroll down toward the end of the file to the [googlein] context. Make the last 5 lines of the context (beginning with the regcall Answer line) look like this and then restart Asterisk:

exten => s,n(regcall),Answer
exten => s,n,Wait(8)
exten => s,n,SendDTMF(1)
;exten => s,n(regcall),Set(DIAL_OPTIONS=${DIAL_OPTIONS}aD:)1))
exten => s,n,Goto(from-trunk,gv-incoming,1)
 

eCase

New Member
Joined
Jan 26, 2011
Messages
161
Reaction score
0
Jabber Debug 1

I set the Jabber.conf to:
debug=yes

I then called in from my cell, answered the call at xtn 701, and after 4 seconds the call ends from the perspective of the softphone, but the in-caller is then sent to the gmail account voicemail.
Log: (Screened for forum)

[2011-03-22 10:36:03] VERBOSE[18287] res_jabber.c:
JABBER: asterisk INCOMING:
[2011-03-22 10:36:57] VERBOSE[18287] res_jabber.c:
JABBER: asterisk INCOMING: <iq from="[email protected]/srvres-MTAuMjE4LjM5LjE0MDo5ODUy" to="[email protected]/Talk2DFF6610" id="jingle:10.218.39.140-2714797:1:D928BA93" type="set"><ses:session type="initiate" id="[email protected]" initiator="[email protected]/srvres-MTAuMjE4LjM5LjE0MDo5ODUy" 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"/><pho:payload-type id="101" name="telephone-event"/></pho:description><transport behind-symmetric-nat="false" can-receive-from-symmetric-nat="false" xmlns="http://www.google.com/transport/raw-udp"/><transport xmlns="http://www.google.com/transport/p2p"/></ses:session></iq>
[2011-03-22 10:36:57] VERBOSE[18287] res_jabber.c:
JABBER: asterisk OUTGOING: <iq type='result' from='[email protected]/Talk2DFF6610' to='[email protected]/srvres-MTAuMjE4LjM5LjE0MDo5ODUy' id='jingle:10.218.39.140-2714797:1:D928BA93'/>
[2011-03-22 10:36:57] VERBOSE[21671] pbx.c: -- Executing [[email protected]@googlein:1] Wait("Gtalk/+19998887777-c290", "1") in new stack
[2011-03-22 10:36:58] VERBOSE[21671] pbx.c: -- Executing [[email protected]@googlein:2] Set("Gtalk/+19998887777-c290", "[email protected]") in new stack
[2011-03-22 10:36:58] VERBOSE[21671] pbx.c: -- Executing [[email protected]@googlein:3] JabberSend("Gtalk/+19998887777-c290", "asterisk,[email protected],Incoming Google Voice Call: 9998887777") in new stack
[2011-03-22 10:36:58] VERBOSE[21671] res_jabber.c:
JABBER: asterisk OUTGOING: <message type='chat' to='[email protected]' from='[email protected]/Talk2DFF6610'><body>Incoming Google Voice Call: 9998887777</body></message>
[2011-03-22 10:36:58] VERBOSE[21671] pbx.c: -- Executing [[email protected]@googlein:4] Set("Gtalk/+19998887777-c290", "CALLERID(number)=9998887777") in new stack
[2011-03-22 10:36:58] VERBOSE[21671] pbx.c: -- Executing [[email protected]@googlein:5] Set("Gtalk/+19998887777-c290", "CALLERID(name)=9998887777") in new stack
[2011-03-22 10:36:58] VERBOSE[21671] pbx.c: -- Executing [[email protected]@googlein:6] GotoIf("Gtalk/+19998887777-c290", "0?bridged") in new stack
[2011-03-22 10:36:58] VERBOSE[21671] pbx.c: -- Executing [[email protected]@googlein:7] Goto("Gtalk/+19998887777-c290", "s,regcall") in new stack
[2011-03-22 10:36:58] VERBOSE[21671] pbx.c: -- Goto (googlein,s,6)
[2011-03-22 10:36:58] VERBOSE[21671] pbx.c: -- Executing [s@googlein:6] Answer("Gtalk/+19998887777-c290", "") in new stack
[2011-03-22 10:36:58] VERBOSE[21671] res_jabber.c:
JABBER: asterisk OUTGOING: <iq type='set' to='[email protected]/srvres-MTAuMjE4LjM5LjE0MDo5ODUy' from='[email protected]/Talk2DFF6610' id='aaaar'><session xmlns='http://www.google.com/session' type='accept' initiator='[email protected]/srvres-MTAuMjE4LjM5LjE0MDo5ODUy' id='[email protected]'><description xmlns='http://www.google.com/session/phone' xml:lang='en'><payload-type id='0' name='PCMU' clockrate='8000' bitrate='64000'/><payload-type id='100' name='EG711U' clockrate='8000' bitrate='64000'/><payload-type id='101' name='telephone-event' clockrate='8000'/></description></session></iq>
[2011-03-22 10:36:59] VERBOSE[21671] pbx.c: -- Executing [s@googlein:7] SendDTMF("Gtalk/+19998887777-c290", "1") in new stack
[2011-03-22 10:36:59] VERBOSE[21671] pbx.c: -- Executing [s@googlein:8] Goto("Gtalk/+19998887777-c290", "from-trunk,gv-incoming,1") in new stack
[2011-03-22 10:36:59] VERBOSE[21671] pbx.c: -- Goto (from-trunk,gv-incoming,1)
[2011-03-22 10:36:59] VERBOSE[21671] pbx.c: -- Executing [gv-incoming@from-trunk:1] Set("Gtalk/+19998887777-c290", "__FROM_DID=gv-incoming") in new stack
[2011-03-22 10:36:59] VERBOSE[21671] pbx.c: -- Executing [gv-incoming@from-trunk:2] Gosub("Gtalk/+19998887777-c290", "app-blacklist-check,s,1") in new stack
[2011-03-22 10:36:59] VERBOSE[21671] pbx.c: -- Executing [s@app-blacklist-check:1] GotoIf("Gtalk/+19998887777-c290", "0?blacklisted") in new stack
[2011-03-22 10:36:59] VERBOSE[21671] pbx.c: -- Executing [s@app-blacklist-check:2] Set("Gtalk/+19998887777-c290", "CALLED_BLACKLIST=1") in new stack
[2011-03-22 10:36:59] VERBOSE[21671] pbx.c: -- Executing [s@app-blacklist-check:3] Return("Gtalk/+19998887777-c290", "") in new stack
[2011-03-22 10:36:59] VERBOSE[21671] pbx.c: -- Executing [gv-incoming@from-trunk:3] Gosub("Gtalk/+19998887777-c290", "cidlookup,cidlookup_3,1") in new stack
[2011-03-22 10:36:59] VERBOSE[21675] manager.c: == Manager 'admin' logged on from 127.0.0.1
[2011-03-22 10:37:02] VERBOSE[21675] manager.c: == Manager 'admin' logged off from 127.0.0.1
[2011-03-22 10:37:02] VERBOSE[21671] pbx.c: -- Executing [cidlookup_3@cidlookup:1] Set("Gtalk/+19998887777-c290", "CALLERID(name)=NWYRCYZN07, NY") in new stack
[2011-03-22 10:37:02] VERBOSE[21671] pbx.c: -- Executing [cidlookup_3@cidlookup:2] Return("Gtalk/+19998887777-c290", "") in new stack
[2011-03-22 10:37:02] VERBOSE[21671] pbx.c: -- Executing [gv-incoming@from-trunk:4] ExecIf("Gtalk/+19998887777-c290", "0 ?Set(CALLERID(name)=9998887777)") in new stack
[2011-03-22 10:37:02] VERBOSE[21671] pbx.c: -- Executing [gv-incoming@from-trunk:5] Set("Gtalk/+19998887777-c290", "__CALLINGPRES_SV=allowed_not_screened") in new stack
[2011-03-22 10:37:02] VERBOSE[21671] pbx.c: -- Executing [gv-incoming@from-trunk:6] Set("Gtalk/+19998887777-c290", "CALLERPRES()=allowed_not_screened") in new stack
[2011-03-22 10:37:02] VERBOSE[21671] pbx.c: -- Executing [gv-incoming@from-trunk:7] Goto("Gtalk/+19998887777-c290", "app-daynight,1,1") in new stack
[2011-03-22 10:37:02] VERBOSE[21671] pbx.c: -- Goto (app-daynight,1,1)
 

malcolmd

Guru
Joined
Aug 12, 2010
Messages
101
Reaction score
7
Many thanks.

Try Ward's suggestion from above and put the Wait after the Answer, and bump its wait time up to several (8) seconds. Another kind soul sent me a log a few minutes before yours. The results were such that when I pass a call directly to a phone w/ a Dial that includes the DTMF, I don't have the problem, nor do I have the problem if I use SendDTMF after no wait and then go directly to a Dial. But, I do have the problem if I go to an application like Background following the Answer and SendDTMF..unless I add a Wait for a period of at least a couple of seconds.

Agree w/ Ward. There's some kind of timing thing here that Google's iffy about.

Alternatively, if anyone can figure out how to truly disable Google's call screening, then maybe all of this DTMF sending bit goes away and we avoid all future headaches. We've no idea, at the present, on how to do that.
 

wardmundy

Nerd Uno
Joined
Oct 12, 2007
Messages
19,168
Reaction score
5,199
Ethan: Did you read my post above?? Your log shows that you haven't modified the dialplan. And the result you're describing is what happens if you don't :crazy:

[2011-03-22 10:36:58] VERBOSE[21671] pbx.c: -- Executing [s@googlein:6] Answer("Gtalk/+19998887777-c290", "") in new stack
[2011-03-22 10:36:58] VERBOSE[21671] res_jabber.c:
JABBER: asterisk OUTGOING: <iq type='set' to='[email protected]/srvres-MTAuMjE4LjM5LjE0MDo5ODUy' from='[email protected]/Talk2DFF6610' id='aaaar'><session xmlns='http://www.google.com/session' type='accept' initiator='[email protected]/srvres-MTAuMjE4LjM5LjE0MDo5ODUy' id='[email protected]'><description xmlns='http://www.google.com/session/phone' xml:lang='en'><payload-type id='0' name='PCMU' clockrate='8000' bitrate='64000'/><payload-type id='100' name='EG711U' clockrate='8000' bitrate='64000'/><payload-type id='101' name='telephone-event' clockrate='8000'/></description></session></iq>
[2011-03-22 10:36:59] VERBOSE[21671] pbx.c: -- Executing [s@googlein:7] SendDTMF("Gtalk/+19998887777-c290", "1") in new stack
 

Members online

Forum statistics

Threads
25,782
Messages
167,509
Members
19,202
Latest member
pbxnewguy
Get 3CX - Absolutely Free!

Link up your team and customers Phone System Live Chat Video Conferencing

Hosted or Self-managed. Up to 10 users free forever. No credit card. Try risk free.

3CX
A 3CX Account with that email already exists. You will be redirected to the Customer Portal to sign in or reset your password if you've forgotten it.
Top