Freepbx issue. This one pertains to IVR, but I have seen this odd behavior with other modules. Please assist

User: New to this, but not overly stupid. Using the PAP2T adapter, Atom PC, and FreePBX 2.8.1.4.

Fact 1: I created a google voice number. I then used the GV module to set-up the GV account. I can call out from this number and it consistently works as expected.

Fact 2: when I call into this number while using the “Apps” IVR, the system works as expected … “Thank you for calling Nerd Vittles”

Fact 3: I created a new IVR (“Test”) and set it as the destination on the incoming route. Sometimes, the system says “Thank you for calling, Please hold a moment while we try to locate”. Other times, the system does not respond and I go to GV voicemail.

I restarted asterisk and have tried rebooting the Atom. Nothing.

Any ideas?

Log after amportal restart, system does not respond and call goes to Google VM:

-- Executing [[email protected]@googlein:1] NoOp("Gtalk/+1XXXXXXXXXX-0a81", "Receiving GoogleVoice call") in new stack
-- Executing [[email protected]@googlein:2] Set("Gtalk/+1XXXXXXXXXX-0a81", "CALLERID(name)=+1XXXXXXXXXX") in new stack
-- Executing [[email protected]@googlein:3] GotoIf("Gtalk/+1XXXXXXXXXX-0a81", "0?notrim") in new stack
-- Executing [[email protected]@googlein:4] Set("Gtalk/+1XXXXXXXXXX-0a81", "CALLERID(name)=XXXXXXXXXX") in new stack
-- Executing [[email protected]@googlein:5] Set("Gtalk/+1XXXXXXXXXX-0a81", "CALLERID(number)=XXXXXXXXXX") in new stack
-- Executing [[email protected]@googlein:6] Answer("Gtalk/+1XXXXXXXXXX-0a81", "") in new stack
-- Executing [[email protected]@googlein:7] Wait("Gtalk/+1XXXXXXXXXX-0a81", "1") in new stack
-- Executing [[email protected]@googlein:8] SendDTMF("Gtalk/+1XXXXXXXXXX-0a81", "1") in new stack
-- Executing [[email protected]@googlein:9] Goto("Gtalk/+1XXXXXXXXXX-0a81", "from-trunk,XXXXXXXXXX,1") in new stack
-- Goto (from-trunk,XXXXXXXXXX,1)
-- Executing [XXXXXXXXXX@from-trunk:1] Set("Gtalk/+1XXXXXXXXXX-0a81", "__FROM_DID=XXXXXXXXXX") in new stack
-- Executing [XXXXXXXXXX@from-trunk:2] Gosub("Gtalk/+1XXXXXXXXXX-0a81", "cidlookup,cidlookup_3,1") in new stack
-- Executing [cidlookup_3@cidlookup:1] GotoIf("Gtalk/+1XXXXXXXXXX-0a81", "1?cidlookup,cidlookup_return,1") in new stack
-- Goto (cidlookup,cidlookup_return,1)
-- Executing [cidlookup_return@cidlookup:1] ExecIf("Gtalk/+1XXXXXXXXXX-0a81", "1?Set(CALLERID(name)=PALO ALTO, CA)") in new stack
-- Executing [cidlookup_return@cidlookup:2] Return("Gtalk/+1XXXXXXXXXX-0a81", "") in new stack
-- Executing [XXXXXXXXXX@from-trunk:3] Gosub("Gtalk/+1XXXXXXXXXX-0a81", "app-blacklist-check,s,1") in new stack
-- Executing [s@app-blacklist-check:1] GotoIf("Gtalk/+1XXXXXXXXXX-0a81", "0?blacklisted") in new stack
-- Executing [s@app-blacklist-check:2] Set("Gtalk/+1XXXXXXXXXX-0a81", "CALLED_BLACKLIST=1") in new stack
-- Executing [s@app-blacklist-check:3] Return("Gtalk/+1XXXXXXXXXX-0a81", "") in new stack
-- Executing [XXXXXXXXXX@from-trunk:4] ExecIf("Gtalk/+1XXXXXXXXXX-0a81", "0 ?Set(CALLERID(name)=XXXXXXXXXX)") in new stack
-- Executing [XXXXXXXXXX@from-trunk:5] Set("Gtalk/+1XXXXXXXXXX-0a81", "__CALLINGPRES_SV=allowed_not_screened") in new stack
-- Executing [XXXXXXXXXX@from-trunk:6] Set("Gtalk/+1XXXXXXXXXX-0a81", "CALLERPRES()=allowed_not_screened") in new stack
-- Executing [XXXXXXXXXX@from-trunk:7] Goto("Gtalk/+1XXXXXXXXXX-0a81", "ivr-4,s,1") in new stack
-- Goto (ivr-4,s,1)
-- Executing [s@ivr-4:1] Set("Gtalk/+1XXXXXXXXXX-0a81", "MSG=custom/nv-GenericWelcome") in new stack
-- Executing [s@ivr-4:2] Set("Gtalk/+1XXXXXXXXXX-0a81", "LOOPCOUNT=0") in new stack
-- Executing [s@ivr-4:3] Set("Gtalk/+1XXXXXXXXXX-0a81", "__DIR-CONTEXT=") in new stack
-- Executing [s@ivr-4:4] Set("Gtalk/+1XXXXXXXXXX-0a81", "_IVR_CONTEXT_ivr-4=") in new stack
-- Executing [s@ivr-4:5] Set("Gtalk/+1XXXXXXXXXX-0a81", "_IVR_CONTEXT=ivr-4") in new stack
-- Executing [s@ivr-4:6] GotoIf("Gtalk/+1XXXXXXXXXX-0a81", "1?begin") in new stack
-- Goto (ivr-4,s,9)
-- Executing [s@ivr-4:9] Set("Gtalk/+1XXXXXXXXXX-0a81", "TIMEOUT(digit)=3") in new stack
-- Digit timeout set to 3.000
-- Executing [s@ivr-4:10] Set("Gtalk/+1XXXXXXXXXX-0a81", "TIMEOUT(response)=10") in new stack
-- Response timeout set to 10.000
-- Executing [s@ivr-4:11] Set("Gtalk/+1XXXXXXXXXX-0a81", "__IVR_RETVM=") in new stack
-- Executing [s@ivr-4:12] ExecIf("Gtalk/+1XXXXXXXXXX-0a81", "1?Background(custom/nv-GenericWelcome)") in new stack
-- <Gtalk/+1XXXXXXXXXX-0a81> Playing 'custom/nv-GenericWelcome.gsm' (language 'en')
-- Executing [s@ivr-4:13] WaitExten("Gtalk/+1XXXXXXXXXX-0a81", ",") in new stack

== Spawn extension (ivr-4, s, 13) exited non-zero on ‘Gtalk/+1XXXXXXXXXX-0a81’
– Executing [h@ivr-4:1] Hangup(“Gtalk/+1XXXXXXXXXX-0a81”, “”) in new stack
== Spawn extension (ivr-4, h, 1) exited non-zero on ‘Gtalk/+1XXXXXXXXXX-0a81’

Log after amportal restart and system responds “thank you for calling…locating”
– Executing [[email protected]@googlein:1] NoOp(“Gtalk/+1XXXXXXXXXX-2711”, “Receiving GoogleVoice call”) in new stack
– Executing [[email protected]@googlein:2] Set(“Gtalk/+1XXXXXXXXXX-2711”, “CALLERID(name)=+1XXXXXXXXXX”) in new stack
– Executing [[email protected]@googlein:3] GotoIf(“Gtalk/+1XXXXXXXXXX-2711”, “0?notrim”) in new stack
– Executing [[email protected]@googlein:4] Set(“Gtalk/+1XXXXXXXXXX-2711”, “CALLERID(name)=XXXXXXXXXX”) in new stack
– Executing [[email protected]@googlein:5] Set(“Gtalk/+1XXXXXXXXXX-2711”, “CALLERID(number)=XXXXXXXXXX”) in new stack
– Executing [[email protected]@googlein:6] Answer(“Gtalk/+1XXXXXXXXXX-2711”, “”) in new stack
– Executing [[email protected]@googlein:7] Wait(“Gtalk/+1XXXXXXXXXX-2711”, “1”) in new stack
– Executing [[email protected]@googlein:8] SendDTMF(“Gtalk/+1XXXXXXXXXX-2711”, “1”) in new stack
– Executing [[email protected]@googlein:9] Goto(“Gtalk/+1XXXXXXXXXX-2711”, “from-trunk,XXXXXXXXXX,1”) in new stack
– Goto (from-trunk,XXXXXXXXXX,1)
– Executing [XXXXXXXXXX@from-trunk:1] Set(“Gtalk/+1XXXXXXXXXX-2711”, “__FROM_DID=XXXXXXXXXX”) in new stack
– Executing [XXXXXXXXXX@from-trunk:2] Gosub(“Gtalk/+1XXXXXXXXXX-2711”, “cidlookup,cidlookup_3,1”) in new stack
– Executing [cidlookup_3@cidlookup:1] GotoIf(“Gtalk/+1XXXXXXXXXX-2711”, “1?cidlookup,cidlookup_return,1”) in new stack
– Goto (cidlookup,cidlookup_return,1)
– Executing [cidlookup_return@cidlookup:1] ExecIf(“Gtalk/+1XXXXXXXXXX-2711”, “1?Set(CALLERID(name)=PALO ALTO, CA)”) in new stack
– Executing [cidlookup_return@cidlookup:2] Return(“Gtalk/+1XXXXXXXXXX-2711”, “”) in new stack
– Executing [XXXXXXXXXX@from-trunk:3] Gosub(“Gtalk/+1XXXXXXXXXX-2711”, “app-blacklist-check,s,1”) in new stack
– Executing [s@app-blacklist-check:1] GotoIf(“Gtalk/+1XXXXXXXXXX-2711”, “0?blacklisted”) in new stack
– Executing [s@app-blacklist-check:2] Set(“Gtalk/+1XXXXXXXXXX-2711”, “CALLED_BLACKLIST=1”) in new stack
– Executing [s@app-blacklist-check:3] Return(“Gtalk/+1XXXXXXXXXX-2711”, “”) in new stack
– Executing [XXXXXXXXXX@from-trunk:4] ExecIf(“Gtalk/+1XXXXXXXXXX-2711”, “0 ?Set(CALLERID(name)=XXXXXXXXXX)”) in new stack
– Executing [XXXXXXXXXX@from-trunk:5] Set(“Gtalk/+1XXXXXXXXXX-2711”, “__CALLINGPRES_SV=allowed_not_screened”) in new stack
– Executing [XXXXXXXXXX@from-trunk:6] Set(“Gtalk/+1XXXXXXXXXX-2711”, “CALLERPRES()=allowed_not_screened”) in new stack
– Executing [XXXXXXXXXX@from-trunk:7] Goto(“Gtalk/+1XXXXXXXXXX-2711”, “ivr-4,s,1”) in new stack
– Goto (ivr-4,s,1)
– Executing [s@ivr-4:1] Set(“Gtalk/+1XXXXXXXXXX-2711”, “MSG=custom/nv-GenericWelcome”) in new stack
– Executing [s@ivr-4:2] Set(“Gtalk/+1XXXXXXXXXX-2711”, “LOOPCOUNT=0”) in new stack
– Executing [s@ivr-4:3] Set(“Gtalk/+1XXXXXXXXXX-2711”, “__DIR-CONTEXT=”) in new stack
– Executing [s@ivr-4:4] Set(“Gtalk/+1XXXXXXXXXX-2711”, “_IVR_CONTEXT_ivr-4=”) in new stack
– Executing [s@ivr-4:5] Set(“Gtalk/+1XXXXXXXXXX-2711”, “_IVR_CONTEXT=ivr-4”) in new stack
– Executing [s@ivr-4:6] GotoIf(“Gtalk/+1XXXXXXXXXX-2711”, “1?begin”) in new stack
– Goto (ivr-4,s,9)
– Executing [s@ivr-4:9] Set(“Gtalk/+1XXXXXXXXXX-2711”, “TIMEOUT(digit)=3”) in new stack
– Digit timeout set to 3.000
– Executing [s@ivr-4:10] Set(“Gtalk/+1XXXXXXXXXX-2711”, “TIMEOUT(response)=10”) in new stack
– Response timeout set to 10.000
– Executing [s@ivr-4:11] Set(“Gtalk/+1XXXXXXXXXX-2711”, “__IVR_RETVM=”) in new stack
– Executing [s@ivr-4:12] ExecIf(“Gtalk/+1XXXXXXXXXX-2711”, “1?Background(custom/nv-GenericWelcome)”) in new stack
– <Gtalk/+1XXXXXXXXXX-2711> Playing ‘custom/nv-GenericWelcome.gsm’ (language ‘en’)
– Executing [s@ivr-4:13] WaitExten(“Gtalk/+1XXXXXXXXXX-2711”, “,”) in new stack
== Spawn extension (ivr-4, s, 13) exited non-zero on ‘Gtalk/+1XXXXXXXXXX-2711’
– Executing [h@ivr-4:1] Hangup(“Gtalk/+1XXXXXXXXXX-2711”, “”) in new stack
== Spawn extension (ivr-4, h, 1) exited non-zero on ‘Gtalk/+1XXXXXXXXXX-2711’

The generic system recording I chose was the problem. When creating a recording and using it for the IVR, it worked.

The other issue of the call going to GV voicemail I believe was simply that my PBX ring delay (default=20) was apparently close enough to GV resulting in random behavior as to which handled the call. I shortened it to 15.