Inbound call handling is not working on fowarded calls

current system: asterisk 11.6.0 and freepbx 2.11.0.37. i have done all the upgrades, this maybe my issue. lol

currently I was using google voice as my sip provider and as all you guys know, google changed their policy and now does not work with third parties. so i went to a different provider. so now i just forward my google voice number to vestalink.

so here is the issue: i have the new provider all setup. when i call the number they provide, my system sends the call to an ivr that i have setup. The ivr does answer the call. but when i call the google voice number, that is forwarded to the vestalink number, the call just rings and rings. i took a look at the logs directly to vestalink and one that was forwarded from google voice. the logs show the exact same thing. what is even stranger is that the google voice call says it was answered by the ivr. not sure where to look.

TIA

logs of good call (Vestalink)
[2014-05-02 09:03:24] VERBOSE[1905][C-000008e1] pbx.c: – Executing [[email protected]:1] NoOp(“SIP/vestalink-000008c3”, “Catch-All DID Match - Found XXXXXXXXXX - You probably want a DID for this.”) in new stack
[2014-05-02 09:03:24] VERBOSE[1905][C-000008e1] pbx.c: – Executing [[email protected]:2] Set(“SIP/vestalink-000008c3”, “__FROM_DID=XXXXXXXXXX”) in new stack
[2014-05-02 09:03:24] VERBOSE[1905][C-000008e1] pbx.c: – Executing [[email protected]:3] Goto(“SIP/vestalink-000008c3”, “ext-did,s,1”) in new stack
[2014-05-02 09:03:24] VERBOSE[1905][C-000008e1] pbx.c: – Goto (ext-did,s,1)
[2014-05-02 09:03:24] VERBOSE[1905][C-000008e1] pbx.c: – Executing [[email protected]:1] ExecIf(“SIP/vestalink-000008c3”, “0?Set(__FROM_DID=s)”) in new stack
[2014-05-02 09:03:24] VERBOSE[1905][C-000008e1] pbx.c: – Executing [[email protected]:2] Gosub(“SIP/vestalink-000008c3”, “app-blacklist-check,s,1()”) in new stack
[2014-05-02 09:03:24] VERBOSE[1905][C-000008e1] pbx.c: – Executing [[email protected]:1] GotoIf(“SIP/vestalink-000008c3”, “0?blacklisted”) in new stack
[2014-05-02 09:03:24] VERBOSE[1905][C-000008e1] pbx.c: – Executing [[email protected]:2] Set(“SIP/vestalink-000008c3”, “CALLED_BLACKLIST=1”) in new stack
[2014-05-02 09:03:24] VERBOSE[1905][C-000008e1] pbx.c: – Executing [[email protected]:3] Return(“SIP/vestalink-000008c3”, “”) in new stack
[2014-05-02 09:03:24] VERBOSE[1905][C-000008e1] pbx.c: – Executing [[email protected]:3] Set(“SIP/vestalink-000008c3”, “CDR(did)=XXXXXXXXXX”) in new stack
[2014-05-02 09:03:24] VERBOSE[1905][C-000008e1] pbx.c: – Executing [[email protected]:4] ExecIf(“SIP/vestalink-000008c3”, “0 ?Set(CALLERID(name)=XXXXXXXXXX)”) in new stack
[2014-05-02 09:03:24] VERBOSE[1905][C-000008e1] pbx.c: – Executing [[email protected]:5] Set(“SIP/vestalink-000008c3”, “CHANNEL(musicclass)=default”) in new stack
[2014-05-02 09:03:24] VERBOSE[1905][C-000008e1] pbx.c: – Executing [[email protected]:6] Set(“SIP/vestalink-000008c3”, “__MOHCLASS=default”) in new stack
[2014-05-02 09:03:24] VERBOSE[1905][C-000008e1] pbx.c: – Executing [[email protected]:7] Set(“SIP/vestalink-000008c3”, “__CALLINGPRES_SV=allowed_not_screened”) in new stack
[2014-05-02 09:03:24] VERBOSE[1905][C-000008e1] pbx.c: – Executing [[email protected]:8] Set(“SIP/vestalink-000008c3”, “CALLERPRES()=allowed_not_screened”) in new stack
[2014-05-02 09:03:24] VERBOSE[1905][C-000008e1] pbx.c: – Executing [[email protected]:9] Goto(“SIP/vestalink-000008c3”, “ivr-1,s,1”) in new stack
[2014-05-02 09:03:24] VERBOSE[1905][C-000008e1] pbx.c: – Goto (ivr-1,s,1)
[2014-05-02 09:03:24] VERBOSE[1905][C-000008e1] pbx.c: – Executing [[email protected]:1] Set(“SIP/vestalink-000008c3”, “TIMEOUT_LOOPCOUNT=0”) in new stack
[2014-05-02 09:03:24] VERBOSE[1905][C-000008e1] pbx.c: – Executing [[email protected]:2] Set(“SIP/vestalink-000008c3”, “INVALID_LOOPCOUNT=0”) in new stack
[2014-05-02 09:03:24] VERBOSE[1905][C-000008e1] pbx.c: – Executing [[email protected]:3] Set(“SIP/vestalink-000008c3”, “_IVR_CONTEXT_ivr-1=”) in new stack
[2014-05-02 09:03:24] VERBOSE[1905][C-000008e1] pbx.c: – Executing [[email protected]:4] Set(“SIP/vestalink-000008c3”, “_IVR_CONTEXT=ivr-1”) in new stack
[2014-05-02 09:03:24] VERBOSE[1905][C-000008e1] pbx.c: – Executing [[email protected]:5] Set(“SIP/vestalink-000008c3”, “__IVR_RETVM=RETURN”) in new stack
[2014-05-02 09:03:24] VERBOSE[1905][C-000008e1] pbx.c: – Executing [[email protected]:6] GotoIf(“SIP/vestalink-000008c3”, “0?skip”) in new stack
[2014-05-02 09:03:24] VERBOSE[1905][C-000008e1] pbx.c: – Executing [[email protected]:7] Answer(“SIP/vestalink-000008c3”, “”) in new stack
[2014-05-02 09:03:24] VERBOSE[1905][C-000008e1] pbx.c: – Executing [[email protected]:8] Wait(“SIP/vestalink-000008c3”, “1”) in new stack
[2014-05-02 09:03:25] VERBOSE[1905][C-000008e1] pbx.c: – Executing [[email protected]:9] Set(“SIP/vestalink-000008c3”, “IVR_MSG=custom/IVR-Main”) in new stack
[2014-05-02 09:03:25] VERBOSE[1905][C-000008e1] pbx.c: – Executing [[email protected]:10] Set(“SIP/vestalink-000008c3”, “TIMEOUT(digit)=3”) in new stack
[2014-05-02 09:03:25] VERBOSE[1905][C-000008e1] func_timeout.c: – Digit timeout set to 3.000
[2014-05-02 09:03:25] VERBOSE[1905][C-000008e1] pbx.c: – Executing [[email protected]:11] ExecIf(“SIP/vestalink-000008c3”, “1?Background(custom/IVR-Main)”) in new stack
[2014-05-02 09:03:25] VERBOSE[1905][C-000008e1] file.c: – <SIP/vestalink-000008c3> Playing ‘custom/IVR-Main.slin’ (language ‘en’)
[2014-05-02 09:03:30] VERBOSE[1905][C-000008e1] pbx.c: == Spawn extension (ivr-1, s, 11) exited non-zero on ‘SIP/vestalink-000008c3’
[2014-05-02 09:03:30] VERBOSE[1905][C-000008e1] pbx.c: – Executing [[email protected]:1] Hangup(“SIP/vestalink-000008c3”, “”) in new stack
[2014-05-02 09:03:30] VERBOSE[1905][C-000008e1] pbx.c: == Spawn extension (ivr-1, h, 1) exited non-zero on ‘SIP/vestalink-000008c3’

log of Bad call (Google voice forwarded)
[2014-05-02 09:01:40] VERBOSE[1828][C-000008e0] pbx.c: – Executing [[email protected]:1] NoOp(“SIP/vestalink-000008c2”, “Catch-All DID Match - Found XXXXXXXXXX - You probably want a DID for this.”) in new stack
[2014-05-02 09:01:40] VERBOSE[1828][C-000008e0] pbx.c: – Executing [[email protected]:2] Set(“SIP/vestalink-000008c2”, “__FROM_DID=XXXXXXXXXX”) in new stack
[2014-05-02 09:01:40] VERBOSE[1828][C-000008e0] pbx.c: – Executing [[email protected]:3] Goto(“SIP/vestalink-000008c2”, “ext-did,s,1”) in new stack
[2014-05-02 09:01:40] VERBOSE[1828][C-000008e0] pbx.c: – Goto (ext-did,s,1)
[2014-05-02 09:01:40] VERBOSE[1828][C-000008e0] pbx.c: – Executing [[email protected]:1] ExecIf(“SIP/vestalink-000008c2”, “0?Set(__FROM_DID=s)”) in new stack
[2014-05-02 09:01:40] VERBOSE[1828][C-000008e0] pbx.c: – Executing [[email protected]:2] Gosub(“SIP/vestalink-000008c2”, “app-blacklist-check,s,1()”) in new stack
[2014-05-02 09:01:40] VERBOSE[1828][C-000008e0] pbx.c: – Executing [[email protected]:1] GotoIf(“SIP/vestalink-000008c2”, “0?blacklisted”) in new stack
[2014-05-02 09:01:40] VERBOSE[1828][C-000008e0] pbx.c: – Executing [[email protected]:2] Set(“SIP/vestalink-000008c2”, “CALLED_BLACKLIST=1”) in new stack
[2014-05-02 09:01:40] VERBOSE[1828][C-000008e0] pbx.c: – Executing [[email protected]:3] Return(“SIP/vestalink-000008c2”, “”) in new stack
[2014-05-02 09:01:40] VERBOSE[1828][C-000008e0] pbx.c: – Executing [[email protected]:3] Set(“SIP/vestalink-000008c2”, “CDR(did)=XXXXXXXXXX”) in new stack
[2014-05-02 09:01:40] VERBOSE[1828][C-000008e0] pbx.c: – Executing [[email protected]:4] ExecIf(“SIP/vestalink-000008c2”, “0 ?Set(CALLERID(name)=6304899953)”) in new stack
[2014-05-02 09:01:40] VERBOSE[1828][C-000008e0] pbx.c: – Executing [[email protected]:5] Set(“SIP/vestalink-000008c2”, “CHANNEL(musicclass)=default”) in new stack
[2014-05-02 09:01:40] VERBOSE[1828][C-000008e0] pbx.c: – Executing [[email protected]:6] Set(“SIP/vestalink-000008c2”, “__MOHCLASS=default”) in new stack
[2014-05-02 09:01:40] VERBOSE[1828][C-000008e0] pbx.c: – Executing [[email protected]:7] Set(“SIP/vestalink-000008c2”, “__CALLINGPRES_SV=allowed_not_screened”) in new stack
[2014-05-02 09:01:40] VERBOSE[1828][C-000008e0] pbx.c: – Executing [[email protected]:8] Set(“SIP/vestalink-000008c2”, “CALLERPRES()=allowed_not_screened”) in new stack
[2014-05-02 09:01:40] VERBOSE[1828][C-000008e0] pbx.c: – Executing [[email protected]:9] Goto(“SIP/vestalink-000008c2”, “ivr-1,s,1”) in new stack
[2014-05-02 09:01:40] VERBOSE[1828][C-000008e0] pbx.c: – Goto (ivr-1,s,1)
[2014-05-02 09:01:40] VERBOSE[1828][C-000008e0] pbx.c: – Executing [[email protected]:1] Set(“SIP/vestalink-000008c2”, “TIMEOUT_LOOPCOUNT=0”) in new stack
[2014-05-02 09:01:40] VERBOSE[1828][C-000008e0] pbx.c: – Executing [[email protected]:2] Set(“SIP/vestalink-000008c2”, “INVALID_LOOPCOUNT=0”) in new stack
[2014-05-02 09:01:40] VERBOSE[1828][C-000008e0] pbx.c: – Executing [[email protected]:3] Set(“SIP/vestalink-000008c2”, “_IVR_CONTEXT_ivr-1=”) in new stack
[2014-05-02 09:01:40] VERBOSE[1828][C-000008e0] pbx.c: – Executing [[email protected]:4] Set(“SIP/vestalink-000008c2”, “_IVR_CONTEXT=ivr-1”) in new stack
[2014-05-02 09:01:40] VERBOSE[1828][C-000008e0] pbx.c: – Executing [[email protected]:5] Set(“SIP/vestalink-000008c2”, “__IVR_RETVM=RETURN”) in new stack
[2014-05-02 09:01:40] VERBOSE[1828][C-000008e0] pbx.c: – Executing [[email protected]:6] GotoIf(“SIP/vestalink-000008c2”, “0?skip”) in new stack
[2014-05-02 09:01:40] VERBOSE[1828][C-000008e0] pbx.c: – Executing [[email protected]:7] Answer(“SIP/vestalink-000008c2”, “”) in new stack
[2014-05-02 09:01:40] VERBOSE[1828][C-000008e0] pbx.c: – Executing [[email protected]:8] Wait(“SIP/vestalink-000008c2”, “1”) in new stack
[2014-05-02 09:01:41] VERBOSE[1828][C-000008e0] pbx.c: – Executing [[email protected]:9] Set(“SIP/vestalink-000008c2”, “IVR_MSG=custom/IVR-Main”) in new stack
[2014-05-02 09:01:41] VERBOSE[1828][C-000008e0] pbx.c: – Executing [[email protected]:10] Set(“SIP/vestalink-000008c2”, “TIMEOUT(digit)=3”) in new stack
[2014-05-02 09:01:41] VERBOSE[1828][C-000008e0] func_timeout.c: – Digit timeout set to 3.000
[2014-05-02 09:01:41] VERBOSE[1828][C-000008e0] pbx.c: – Executing [[email protected]:11] ExecIf(“SIP/vestalink-000008c2”, “1?Background(custom/IVR-Main)”) in new stack
[2014-05-02 09:01:41] VERBOSE[1828][C-000008e0] file.c: – <SIP/vestalink-000008c2> Playing ‘custom/IVR-Main.slin’ (language ‘en’)
[2014-05-02 09:02:03] VERBOSE[1828][C-000008e0] pbx.c: == Spawn extension (ivr-1, s, 11) exited non-zero on ‘SIP/vestalink-000008c2’
[2014-05-02 09:02:03] VERBOSE[1828][C-000008e0] pbx.c: – Executing [[email protected]:1] Hangup(“SIP/vestalink-000008c2”, “”) in new stack
[2014-05-02 09:02:03] VERBOSE[1828][C-000008e0] pbx.c: == Spawn extension (ivr-1, h, 1) exited non-zero on ‘SIP/vestalink-000008c2’

Do you have call screening set in google voice. I am thinking if you were to listen to this call you would hear google voice talking.

well, i do not have call screening turned on with google voice. but i directed the inbound call to my extension and sure enough, i hear a voice saying, “in case of a fast pickup, we ask that you enter 1 so that we know you are not a voicemail system”

anyone know how to stop that on google voice. of to delay freepbx from answering as “quickly” basically putting a delay in the pick up?

i found a couple forums that tells you to change the phone number google forwards to from mobile to either home or work. but it has not solve my problem. if i can just delay the ivr from pickup til maybe after 1 ring, then that should resolve my issue

i think i found the answer and was hoping for confirmation.

I found in the inbound route that i can set a pause before answering delay. that seam to have fixed my problem. there is also a “singal ringing”. i have not tried that yet.

each ivr has a context that will be included first, for example

[ivr-3] ; Name of IVR
include => ivr-3-custom
include => from-did-direct-ivr
exten => s,1,Set(TIMEOUT_LOOPCOUNT=0)
exten => s,n,Set(IVR_CONTEXT${CONTEXT}=${IVR_CONTEXT})
exten => s,n,Set(_IVR_CONTEXT=${CONTEXT})
exten => s,n,Set(__IVR_RETVM=)
exten => s,n,GotoIf($["${CDR(disposition)}" = “ANSWERED”]?skip)
exten => s,n,Answer
.
.
.

so in this case your [ivr-3-custom] is where any customizations belong

but would’t the ivr be triggered after asterisk answers the call? so i would have to catch the call handling before the actual answer

You have to read the context

include => ivr-3-custom
.
.
exten => s,n,Answer
.
.

is after the included context.