CID Superfecta not working

[gotel]

Hello all, This is the first time I have posted so... I installed CID Superfecta and it appears to be working when I use the debug feature, however when I place an incoming call to my PBX to test the lookup, it does not work. Only the phone number is displayed and not the name. I tried removing the CID Superfecta and CallerID Lookup Sources modules and re-adding them but still no joy. I am running FreePBX 2.5.1.5

Thanks,

 

[bbguy5]

Can you re-install and then do an asterisk -rv and copy/paste what you get when you receive an incoming call? I had to tweak Callerid superfecta to work for me (with 5 digit numbers for Avaya EC500 incoming from work PBX)

 

[gotel]

Ok I removed the CID superfecta and did the reload the here is the output. I have the incoming number in asteridex but it's still not working.

pbx*CLI>
-- Executing [7575126012@from-trunk:1] Set("SIP/craiggoss-b76022b0", "__FROM_DID=7575126012") in new stack
-- Executing [7575126012@from-trunk:2] Gosub("SIP/craiggoss-b76022b0", "app-blacklist-check|s|1") in new stack
-- Executing [s@app-blacklist-check:1] LookupBlacklist("SIP/craiggoss-b76022b0", "") in new stack
-- Executing [s@app-blacklist-check:2] GotoIf("SIP/craiggoss-b76022b0", "0?blacklisted") in new stack
-- Executing [s@app-blacklist-check:3] Return("SIP/craiggoss-b76022b0", "") in new stack
-- Executing [7575126012@from-trunk:3] ExecIf("SIP/craiggoss-b76022b0", "0 |Set|CALLERID(name)=2407231054") in new stack
-- Executing [7575126012@from-trunk:4] Set("SIP/craiggoss-b76022b0", "FAX_RX=system") in new stack
-- Executing [7575126012@from-trunk:5] Set("SIP/craiggoss-b76022b0", "[email protected]") in new stack
-- Executing [7575126012@from-trunk:6] Answer("SIP/craiggoss-b76022b0", "") in new stack
-- Executing [7575126012@from-trunk:7] PlayTones("SIP/craiggoss-b76022b0", "ring") in new stack
-- Executing [7575126012@from-trunk:8] NVFaxDetect("SIP/craiggoss-b76022b0", "5|t") in new stack
-- Executing [7575126012@from-trunk:9] Set("SIP/craiggoss-b76022b0", "__CALLINGPRES_SV=allowed_not_screened") in new stack
-- Executing [7575126012@from-trunk:10] SetCallerPres("SIP/craiggoss-b76022b0", "allowed_not_screened") in new stack
-- Executing [7575126012@from-trunk:11] Goto("SIP/craiggoss-b76022b0", "ext-group|601|1") in new stack
-- Goto (ext-group,601,1)
-- Executing [601@ext-group:1] Macro("SIP/craiggoss-b76022b0", "user-callerid|") in new stack
-- Executing [s@macro-user-callerid:1] Set("SIP/craiggoss-b76022b0", "AMPUSER=2407231054") in new stack
-- Executing [s@macro-user-callerid:2] GotoIf("SIP/craiggoss-b76022b0", "0?report") in new stack
-- Executing [s@macro-user-callerid:3] ExecIf("SIP/craiggoss-b76022b0", "1|Set|REALCALLERIDNUM=2407231054") in new stack
-- Executing [s@macro-user-callerid:4] Set("SIP/craiggoss-b76022b0", "AMPUSER=") in new stack
-- Executing [s@macro-user-callerid:5] Set("SIP/craiggoss-b76022b0", "AMPUSERCIDNAME=") in new stack
-- Executing [s@macro-user-callerid:6] GotoIf("SIP/craiggoss-b76022b0", "1?report") in new stack
-- Goto (macro-user-callerid,s,11)
-- Executing [s@macro-user-callerid:11] GotoIf("SIP/craiggoss-b76022b0", "0?continue") in new stack
-- Executing [s@macro-user-callerid:12] Set("SIP/craiggoss-b76022b0", "__TTL=64") in new stack
-- Executing [s@macro-user-callerid:13] GotoIf("SIP/craiggoss-b76022b0", "1?continue") in new stack
-- Goto (macro-user-callerid,s,20)
-- Executing [s@macro-user-callerid:20] NoOp("SIP/craiggoss-b76022b0", "Using CallerID "2407231054" <2407231054>") in new stack
-- Executing [601@ext-group:2] GotoIf("SIP/craiggoss-b76022b0", "1?skipdb") in new stack
-- Goto (ext-group,601,4)
-- Executing [601@ext-group:4] Set("SIP/craiggoss-b76022b0", "__NODEST=") in new stack
-- Executing [601@ext-group:5] Set("SIP/craiggoss-b76022b0", "__BLKVM_OVERRIDE=BLKVM/601/SIP/craiggoss-b76022b0") in new stack
-- Executing [601@ext-group:6] Set("SIP/craiggoss-b76022b0", "__BLKVM_BASE=601") in new stack
-- Executing [601@ext-group:7] Set("SIP/craiggoss-b76022b0", "DB(BLKVM/601/SIP/craiggoss-b76022b0)=TRUE") in new stack
-- Executing [601@ext-group:8] Set("SIP/craiggoss-b76022b0", "RRNODEST=") in new stack
-- Executing [601@ext-group:9] Set("SIP/craiggoss-b76022b0", "__NODEST=601") in new stack
-- Executing [601@ext-group:10] Set("SIP/craiggoss-b76022b0", "RecordMethod=Group") in new stack
-- Executing [601@ext-group:11] Macro("SIP/craiggoss-b76022b0", "record-enable|1001-1002-1003|Group") in new stack
-- Executing [s@macro-record-enable:1] GotoIf("SIP/craiggoss-b76022b0", "1?check") in new stack
-- Goto (macro-record-enable,s,4)
-- Executing [s@macro-record-enable:4] AGI("SIP/craiggoss-b76022b0", "recordingcheck|20090524-222538|1243218332.34") in new stack
-- Launched AGI Script /var/lib/asterisk/agi-bin/recordingcheck
-- AGI Script recordingcheck completed, returning 0
-- Executing [s@macro-record-enable:5] MacroExit("SIP/craiggoss-b76022b0", "") in new stack
-- Executing [601@ext-group:12] Set("SIP/craiggoss-b76022b0", "RingGroupMethod=ringall") in new stack
-- Executing [601@ext-group:13] Macro("SIP/craiggoss-b76022b0", "dial|20|tr|1001-1002-1003") in new stack
-- Executing [s@macro-dial:1] GotoIf("SIP/craiggoss-b76022b0", "1?dial") in new stack
-- Goto (macro-dial,s,3)
-- Executing [s@macro-dial:3] AGI("SIP/craiggoss-b76022b0", "dialparties.agi") in new stack
-- Launched AGI Script /var/lib/asterisk/agi-bin/dialparties.agi
dialparties.agi: Starting New Dialparties.agi
== Parsing '/etc/asterisk/manager.conf': Found
== Parsing '/etc/asterisk/manager_additional.conf': Found
== Parsing '/etc/asterisk/manager_custom.conf': Found
== Manager 'admin' logged on from 127.0.0.1
dialparties.agi: Caller ID name is '2407231054' number is '2407231054'
dialparties.agi: USE_CONFIRMATION: 'FALSE'
dialparties.agi: RINGGROUP_INDEX: ''
dialparties.agi: Methodology of ring is 'ringall'
-- dialparties.agi: Added extension 1001 to extension map
-- dialparties.agi: Added extension 1002 to extension map
-- dialparties.agi: Added extension 1003 to extension map
-- dialparties.agi: Extension 1001 cf is disabled
-- dialparties.agi: Extension 1002 cf is disabled
-- dialparties.agi: Extension 1003 cf is disabled
-- dialparties.agi: Extension 1001 do not disturb is disabled
-- dialparties.agi: Extension 1002 do not disturb is disabled
-- dialparties.agi: Extension 1003 do not disturb is disabled
> dialparties.agi: extnum 1001 has: cw: 1; hascfb: 0 [] hascfu: 0 []
-- dialparties.agi: dbset CALLTRACE/1001 to 2407231054
> dialparties.agi: extnum 1002 has: cw: 1; hascfb: 0 [] hascfu: 0 []
-- dialparties.agi: dbset CALLTRACE/1002 to 2407231054
> dialparties.agi: extnum 1003 has: cw: 1; hascfb: 0 [] hascfu: 0 []
-- dialparties.agi: dbset CALLTRACE/1003 to 2407231054
-- dialparties.agi: Filtered ARG3: 1001-1002-1003
> dialparties.agi: NODEST: 601 adding M(auto-blkvm) to dialopts: trM(auto-blkvm)
> dialparties.agi: NODEST: 601 blkvm enabled macro already in dialopts: trM(auto-blkvm)
== Manager 'admin' logged off from 127.0.0.1
-- AGI Script dialparties.agi completed, returning 0
-- Executing [s@macro-dial:7] Dial("SIP/craiggoss-b76022b0", "SIP/1001&SIP/1002&SIP/1003|20|trM(auto-blkvm)") in new stack
-- Called 1001
-- SIP/1001-09cb1be0 is ringing
-- SIP/1001-09cb1be0 is ringing
-- ast_get_srv: SRV lookup for '_sip._udp.fwd.pulver.com' mapped to host fwd.pulver.com, port 5060
== Spawn extension (macro-dial, s, 7) exited non-zero on 'SIP/craiggoss-b76022b0' in macro 'dial'
== Spawn extension (macro-dial, s, 7) exited non-zero on 'SIP/craiggoss-b76022b0'
-- Executing [h@macro-dial:1] Macro("SIP/craiggoss-b76022b0", "hangupcall") in new stack
-- Executing [s@macro-hangupcall:1] ResetCDR("SIP/craiggoss-b76022b0", "vw") in new stack
-- Executing [s@macro-hangupcall:2] NoCDR("SIP/craiggoss-b76022b0", "") in new stack
-- Executing [s@macro-hangupcall:3] GotoIf("SIP/craiggoss-b76022b0", "1?skiprg") in new stack
-- Goto (macro-hangupcall,s,6)
-- Executing [s@macro-hangupcall:6] GotoIf("SIP/craiggoss-b76022b0", "0?skipblkvm") in new stack
-- Executing [s@macro-hangupcall:7] NoOp("SIP/craiggoss-b76022b0", "Cleaning Up Block VM Flag: BLKVM/601/SIP/craiggoss-b76022b0") in new stack
-- Executing [s@macro-hangupcall:8] DBdel("SIP/craiggoss-b76022b0", "BLKVM/601/SIP/craiggoss-b76022b0") in new stack
-- DBdel: family=BLKVM, key=601/SIP/craiggoss-b76022b0
-- Executing [s@macro-hangupcall:9] GotoIf("SIP/craiggoss-b76022b0", "1?theend") in new stack
-- Goto (macro-hangupcall,s,11)
-- Executing [s@macro-hangupcall:11] Hangup("SIP/craiggoss-b76022b0", "") in new stack
== Spawn extension (macro-hangupcall, s, 11) exited non-zero on 'SIP/craiggoss-b76022b0' in macro 'hangupcall'
== Spawn extension (macro-hangupcall, s, 11) exited non-zero on 'SIP/craiggoss-b76022b0'

 

[FitnessPlus]

In FreePBX and look at the inbound routes. Make sure that you have Superfecta selected as the caller ID source on each inbound route that you want to use this with.

If these are properly specified, please provide the debug output that appears to be working.

 

[tshif]

We recently learned that the TrixBoxCe version of FreePBX lacks a behavior that Mainline FreePBX has (in 2.5.x); Specifically, If a user creates an inbound DID by putting it in the EXTENSION page, TrixFreePBX does NOT create a visible INBOUND ROUTE, and therefor those DIDs can not be routed through any CID Lookup In freePBX.

In this case, the solutions was to delete the DID reference in the Extension page, and create a Inbound ROute on the Inbound ROute page - which can thenbe run through Caller ID lookups in FreePBX.

DO you think it might be the same thing in this case?

 

[gotel]

In FreePBX and look at the inbound routes. Make sure that you have Superfecta selected as the caller ID source on each inbound route that you want to use this with.

If these are properly specified, please provide the debug output that appears to be working.

duh... yes I needed to set the caller id source for each inbound route..
Thanks,
Gotel

 

[cpuchalski]

We recently learned that the TrixBoxCe version of FreePBX lacks a behavior that Mainline FreePBX has (in 2.5.x); Specifically, If a user creates an inbound DID by putting it in the EXTENSION page, TrixFreePBX does NOT create a visible INBOUND ROUTE, and therefor those DIDs can not be routed through any CID Lookup In freePBX.

In this case, the solutions was to delete the DID reference in the Extension page, and create a Inbound ROute on the Inbound ROute page - which can thenbe run through Caller ID lookups in FreePBX.

DO you think it might be the same thing in this case?

Hi guys,
I'm running into the same issue as gotel and an earlier poster, kelinger, who I think you're referring to here.

I'm running TrixboxCE 2.6.2.2, but don't see the same problem with routes being created when adding a DID to the EXTENSIONS page.

Other than that, my problem appears to be the same. I can get debug to show callerid information, but it's not getting displayed on my phone (Aastra 57i/fw2.5.0.82).

I believe I have everything configured correctly, freepbx userid/pw set, Superfecta set on the inbound routes, Superfecta configured in CallerID Sources, "Agree and Save" license terms, etc.

Here is my asterisk output on an inbound call:

-- Accepting call from '7635425000' to '5800' on channel 0/18, span 2
-- Executing [5800@from-zaptel:1] Set("Zap/18-1", "DID=5800") in new stack
-- Executing [5800@from-zaptel:2] Goto("Zap/18-1", "s|1") in new stack
-- Goto (from-zaptel,s,1)
-- Executing [s@from-zaptel:1] NoOp("Zap/18-1", "Entering from-zaptel with DID == 5800") in new stack
-- Executing [s@from-zaptel:2] Ringing("Zap/18-1", "") in new stack
-- Executing [s@from-zaptel:3] Set("Zap/18-1", "DID=5800") in new stack
-- Executing [s@from-zaptel:4] NoOp("Zap/18-1", "DID is now 5800") in new stack
-- Executing [s@from-zaptel:5] GotoIf("Zap/18-1", "1?zapok:notzap") in new stack
-- Goto (from-zaptel,s,8)
-- Executing [s@from-zaptel:8] NoOp("Zap/18-1", "Is a Zaptel Channel") in new stack
-- Executing [s@from-zaptel:9] Set("Zap/18-1", "CHAN=18-1") in new stack
-- Executing [s@from-zaptel:10] Set("Zap/18-1", "CHAN=18") in new stack
-- Executing [s@from-zaptel:11] Macro("Zap/18-1", "from-zaptel-18|5800|1") in new stack
-- Executing [s@from-zaptel:12] NoOp("Zap/18-1", "Returned from Macro from-zaptel-18") in new stack
-- Executing [s@from-zaptel:13] Goto("Zap/18-1", "from-pstn|5800|1") in new stack
-- Goto (from-pstn,5800,1)
-- Executing [5800@from-pstn:1] GotoIf("Zap/18-1", "1?ext-local|5800|1") in new stack
-- Goto (ext-local,5800,1)
-- Executing [5800@ext-local:1] Macro("Zap/18-1", "exten-vm|5800|5800") in new stack
-- Executing [s@macro-exten-vm:1] Macro("Zap/18-1", "user-callerid") in new stack
-- Executing [s@macro-user-callerid:1] Set("Zap/18-1", "AMPUSER=7635425000") in new stack
-- Executing [s@macro-user-callerid:2] GotoIf("Zap/18-1", "0?report") in new stack
-- Executing [s@macro-user-callerid:3] ExecIf("Zap/18-1", "1|Set|REALCALLERIDNUM=7635425000") in new stack
-- Executing [s@macro-user-callerid:4] Set("Zap/18-1", "AMPUSER=") in new stack
-- Executing [s@macro-user-callerid:5] Set("Zap/18-1", "AMPUSERCIDNAME=") in new stack
-- Executing [s@macro-user-callerid:6] GotoIf("Zap/18-1", "1?report") in new stack
-- Goto (macro-user-callerid,s,11)
-- Executing [s@macro-user-callerid:11] GotoIf("Zap/18-1", "0?continue") in new stack
-- Executing [s@macro-user-callerid:12] Set("Zap/18-1", "__TTL=64") in new stack
-- Executing [s@macro-user-callerid:13] GotoIf("Zap/18-1", "1?continue") in new stack
-- Goto (macro-user-callerid,s,20)
-- Executing [s@macro-user-callerid:20] NoOp("Zap/18-1", "Using CallerID "" <7635425000>") in new stack
-- Executing [s@macro-exten-vm:2] Set("Zap/18-1", "RingGroupMethod=none") in new stack
-- Executing [s@macro-exten-vm:3] Set("Zap/18-1", "VMBOX=5800") in new stack
-- Executing [s@macro-exten-vm:4] Set("Zap/18-1", "EXTTOCALL=5800") in new stack
-- Executing [s@macro-exten-vm:5] Set("Zap/18-1", "CFUEXT=") in new stack
-- Executing [s@macro-exten-vm:6] Set("Zap/18-1", "CFBEXT=") in new stack
-- Executing [s@macro-exten-vm:7] Set("Zap/18-1", "RT=15") in new stack
-- Executing [s@macro-exten-vm:8] Macro("Zap/18-1", "record-enable|5800|IN") in new stack
-- Executing [s@macro-record-enable:1] GotoIf("Zap/18-1", "1?check") in new stack
-- Goto (macro-record-enable,s,4)
-- Executing [s@macro-record-enable:4] AGI("Zap/18-1", "recordingcheck|20090526-103441|1243352081.18") in new stack
-- Launched AGI Script /var/lib/asterisk/agi-bin/recordingcheck
recordingcheck|20090526-103441|1243352081.18: Inbound recording not enabled
-- AGI Script recordingcheck completed, returning 0
-- Executing [s@macro-record-enable:5] MacroExit("Zap/18-1", "") in new stack
-- Executing [s@macro-exten-vm:9] Macro("Zap/18-1", "dial|15|Ttr|5800") in new stack
-- Executing [s@macro-dial:1] GotoIf("Zap/18-1", "1?dial") in new stack
-- Goto (macro-dial,s,3)
-- Executing [s@macro-dial:3] AGI("Zap/18-1", "dialparties.agi") in new stack
-- Launched AGI Script /var/lib/asterisk/agi-bin/dialparties.agi
dialparties.agi: Starting New Dialparties.agi
== Parsing '/etc/asterisk/manager.conf': Found
== Parsing '/etc/asterisk/manager_additional.conf': Found
== Parsing '/etc/asterisk/manager_custom.conf': Found
== Manager 'admin' logged on from 127.0.0.1
dialparties.agi: Caller ID name is 'unknown' number is '7635425000'
dialparties.agi: USE_CONFIRMATION: 'FALSE'
dialparties.agi: RINGGROUP_INDEX: ''
dialparties.agi: Methodology of ring is 'none'
-- dialparties.agi: Added extension 5800 to extension map
-- dialparties.agi: Extension 5800 cf is disabled
-- dialparties.agi: Extension 5800 do not disturb is disabled
> dialparties.agi: extnum 5800 has: cw: 1; hascfb: 0 [] hascfu: 0 []
> dialparties.agi: ExtensionState: 0
-- dialparties.agi: dbset CALLTRACE/5800 to 7635425000
-- dialparties.agi: Filtered ARG3: 5800
== Manager 'admin' logged off from 127.0.0.1
-- AGI Script dialparties.agi completed, returning 0
-- Executing [s@macro-dial:7] Dial("Zap/18-1", "SIP/5800|15|Ttr") in new stack
-- Called 5800
-- SIP/5800-09d7cca0 is ringing
-- Channel 0/18, span 2 got hangup request, cause 16
== Spawn extension (macro-dial, s, 7) exited non-zero on 'Zap/18-1' in macro 'dial'
== Spawn extension (macro-dial, s, 7) exited non-zero on 'Zap/18-1' in macro 'exten-vm'
== Spawn extension (macro-dial, s, 7) exited non-zero on 'Zap/18-1'
-- Executing [h@macro-dial:1] Macro("Zap/18-1", "hangupcall") in new stack
-- Executing [s@macro-hangupcall:1] ResetCDR("Zap/18-1", "vw") in new stack
-- Executing [s@macro-hangupcall:2] NoCDR("Zap/18-1", "") in new stack
-- Executing [s@macro-hangupcall:3] GotoIf("Zap/18-1", "1?skiprg") in new stack
-- Goto (macro-hangupcall,s,6)
-- Executing [s@macro-hangupcall:6] GotoIf("Zap/18-1", "1?skipblkvm") in new stack
-- Goto (macro-hangupcall,s,9)
-- Executing [s@macro-hangupcall:9] GotoIf("Zap/18-1", "1?theend") in new stack
-- Goto (macro-hangupcall,s,11)
-- Executing [s@macro-hangupcall:11] Hangup("Zap/18-1", "") in new stack
== Spawn extension (macro-hangupcall, s, 11) exited non-zero on 'Zap/18-1' in macro 'hangupcall'
== Spawn extension (macro-hangupcall, s, 11) exited non-zero on 'Zap/18-1'
-- Hungup 'Zap/18-1'

 

[tshif]

I don't think the from-pstn context goes through the same inbound path as the non-digital lines.

I don't see any sign of it passing through the blacklist filter. (If you don't have it installed in FreePBX, install it. It will make a log entry that will help us.) On most systems, FreePBX passes the call throught the assigned CID lookup source right after checking the blacklist for the number. Its one of the first 4-6 lines that appear in the CLI.

The line that gets logged includes the word "cidlookup", which i dont see happening at all in your CLI.

I dont know enough about how channels and banks and groups are set up to understand - but id say your inbound routes - at least as far as the CID assignments, are being ignored.

Anyone with more experience working with digital lines (from-pstn) - please chime in if you have any info about how these calls might be bypassing the FreePBX configuration for CID Lookup.

 

[foneman]

Same but with from-zaptel

I too am having the same thing. I get no caller ID but Debug does show. Phone is aastra 55i. Will look at logs and post as well. Using POTS lines with TDM800PF Digium card.
Here is the log:

-- Executing [s@from-zaptel:4] NoOp("Zap/1-1", "DID is now s") in new stack
-- Executing [s@from-zaptel:5] GotoIf("Zap/1-1", "1?zapok:notzap") in new st ack
-- Goto (from-zaptel,s,8)
-- Executing [s@from-zaptel:8] NoOp("Zap/1-1", "Is a Zaptel Channel") in new stack
-- Executing [s@from-zaptel:9] Set("Zap/1-1", "CHAN=1-1") in new stack
-- Executing [s@from-zaptel:10] Set("Zap/1-1", "CHAN=1") in new stack
-- Executing [s@from-zaptel:11] Macro("Zap/1-1", "from-zaptel-1|s|1") in new stack
-- Executing [s@macro-from-zaptel-1:1] NoOp("Zap/1-1", "Entering macro-from- zaptel-1 with DID = s and setting to: 6156908650") in new stack
-- Executing [s@macro-from-zaptel-1:2] Set("Zap/1-1", "__FROM_DID=6156908650 ") in new stack
-- Executing [s@macro-from-zaptel-1:3] Goto("Zap/1-1", "from-trunk|615690865 0|1") in new stack
-- Goto (from-trunk,6156908650,1)
== Channel 'Zap/1-1' jumping out of macro 'from-zaptel-1'
-- Executing [6156908650@from-trunk:1] Set("Zap/1-1", "__FROM_DID=6156908650 ") in new stack
-- Executing [6156908650@from-trunk:2] Gosub("Zap/1-1", "cidlookup|cidlookup _2|1") in new stack
-- Executing [cidlookup_2@cidlookup:1] Set("Zap/1-1", "CALLERID(name)=") in new stack
-- Executing [cidlookup_2@cidlookup:2] Return("Zap/1-1", "") in new stack
-- Executing [6156908650@from-trunk:3] Gosub("Zap/1-1", "app-blacklist-check |s|1") in new stack
-- Executing [s@app-blacklist-check:1] LookupBlacklist("Zap/1-1", "") in new stack
-- Executing [s@app-blacklist-check:2] GotoIf("Zap/1-1", "0?blacklisted") in new stack
-- Executing [s@app-blacklist-check:3] Return("Zap/1-1", "") in new stack
-- Executing [6156908650@from-trunk:4] ExecIf("Zap/1-1", "1 |Set|CALLERID(na me)=6153353322") in new stack
-- Executing [6156908650@from-trunk:5] SetMusicOnHold("Zap/1-1", "Trial") in new stack
-- Executing [6156908650@from-trunk:6] Set("Zap/1-1", "__MOHCLASS=Trial") in new stack
-- Executing [6156908650@from-trunk:7] Set("Zap/1-1", "FAX_RX=1100") in new stack
-- Executing [6156908650@from-trunk:8] Set("Zap/1-1", "FAX_RX_EMAIL=fax@midt nvoip.com") in new stack
-- Executing [6156908650@from-trunk:9] Answer("Zap/1-1", "") in new stack
-- Executing [6156908650@from-trunk:10] Wait("Zap/1-1", "3") in new stack
-- Executing [6156908650@from-trunk:11] Set("Zap/1-1", "__CALLINGPRES_SV=all owed_not_screened") in new stack
-- Executing [6156908650@from-trunk:12] SetCallerPres("Zap/1-1", "allowed_no t_screened") in new stack
-- Executing [6156908650@from-trunk:13] Set("Zap/1-1", "__ALERT_INFO=info=<B ellcore-dr4>") in new stack
-- Executing [6156908650@from-trunk:14] Goto("Zap/1-1", "app-daynight|0|1") in new stack
-- Goto (app-daynight,0,1)
-- Executing [0@app-daynight:1] GotoIf("Zap/1-1", "0?timeconditions|1|1:time conditions|1|1") in new stack
-- Goto (timeconditions,1,1)
-- Executing [1@timeconditions:1] GotoIfTime("Zap/1-1", "17:00-07:59|mon-fri |*|*?ivr-8|s|1") in new stack
-- Goto (ivr-8,s,1)
-- Executing [s@ivr-8:1] Set("Zap/1-1", "MSG=custom/AFTERHOURS&custom/OPTION SCHANGED&custom/MAINMENU") in new stack
-- Executing [s@ivr-8:2] Set("Zap/1-1", "LOOPCOUNT=0") in new stack
-- Executing [s@ivr-8:3] Set("Zap/1-1", "__DIR-CONTEXT=default") in new stac k
-- Executing [s@ivr-8:4] Set("Zap/1-1", "_IVR_CONTEXT_ivr-8=") in new stack
-- Executing [s@ivr-8:5] Set("Zap/1-1", "_IVR_CONTEXT=ivr-8") in new stack
-- Executing [s@ivr-8:6] GotoIf("Zap/1-1", "1?begin") in new stack
-- Goto (ivr-8,s,9)
-- Executing [s@ivr-8:9] Set("Zap/1-1", "TIMEOUT(digit)=3") in new stack
-- Digit timeout set to 3
-- Executing [s@ivr-8:10] Set("Zap/1-1", "TIMEOUT(response)=10") in new stac k
-- Response timeout set to 10
-- Executing [s@ivr-8:11] Set("Zap/1-1", "__IVR_RETVM=") in new stack
-- Executing [s@ivr-8:12] ExecIf("Zap/1-1", "1|Background|custom/AFTERHOURS& custom/OPTIONSCHANGED&custom/MAINMENU") in new stack

Part 1

 

[foneman]

Part 2

-- <Zap/1-1> Playing 'custom/AFTERHOURS' (language 'en')
-- <Zap/1-1> Playing 'custom/OPTIONSCHANGED' (language 'en')
-- <Zap/1-1> Playing 'custom/MAINMENU' (language 'en')
== CDR updated on Zap/1-1
-- Executing [200@ivr-8:1] ExecIf("Zap/1-1", "0|dbDel|") in new stack
-- Executing [200@ivr-8:2] Set("Zap/1-1", "__NODEST=") in new stack
-- Executing [200@ivr-8:3] Goto("Zap/1-1", "from-did-direct|200|1") in new stack
-- Goto (from-did-direct,200,1)
-- Executing [200@from-did-direct:1] GotoIf("Zap/1-1", "1?ext-local|200|1") in new stack
-- Goto (ext-local,200,1)
-- Executing [200@ext-local:1] Macro("Zap/1-1", "exten-vm|200|200") in new stack
-- Executing [s@macro-exten-vm:1] Macro("Zap/1-1", "user-callerid") in new stack
-- Executing [s@macro-user-callerid:1] Set("Zap/1-1", "AMPUSER=6153353322") in new stack
-- Executing [s@macro-user-callerid:2] GotoIf("Zap/1-1", "0?report") in new stack
-- Executing [s@macro-user-callerid:3] ExecIf("Zap/1-1", "1|Set|REALCALLERIDNUM=6153353322") in new stack
-- Executing [s@macro-user-callerid:4] Set("Zap/1-1", "AMPUSER=") in new stack
-- Executing [s@macro-user-callerid:5] Set("Zap/1-1", "AMPUSERCIDNAME=") in new stack
-- Executing [s@macro-user-callerid:6] GotoIf("Zap/1-1", "1?report") in new stack
-- Goto (macro-user-callerid,s,11)
-- Executing [s@macro-user-callerid:11] GotoIf("Zap/1-1", "0?continue") in new stack
-- Executing [s@macro-user-callerid:12] Set("Zap/1-1", "__TTL=64") in new stack
-- Executing [s@macro-user-callerid:13] GotoIf("Zap/1-1", "1?continue") in new stack
-- Goto (macro-user-callerid,s,20)
-- Executing [s@macro-user-callerid:20] NoOp("Zap/1-1", "Using CallerID "6153353322" <6153353322>") in new stack
-- Executing [s@macro-exten-vm:2] Set("Zap/1-1", "RingGroupMethod=none") in new stack
-- Executing [s@macro-exten-vm:3] Set("Zap/1-1", "VMBOX=200") in new stack
-- Executing [s@macro-exten-vm:4] Set("Zap/1-1", "EXTTOCALL=200") in new stack
-- Executing [s@macro-exten-vm:5] Set("Zap/1-1", "CFUEXT=") in new stack
-- Executing [s@macro-exten-vm:6] Set("Zap/1-1", "CFBEXT=") in new stack
-- Executing [s@macro-exten-vm:7] Set("Zap/1-1", "RT=15") in new stack
-- Executing [s@macro-exten-vm:8] Macro("Zap/1-1", "record-enable|200|IN") in new stack
-- Executing [s@macro-record-enable:1] GotoIf("Zap/1-1", "1?check") in new stack
-- Goto (macro-record-enable,s,4)
-- Executing [s@macro-record-enable:4] AGI("Zap/1-1", "recordingcheck|20090602-171926|1243981150.3546") in new stack
-- Launched AGI Script /var/lib/asterisk/agi-bin/recordingcheck
recordingcheck|20090602-171926|1243981150.3546: PHPAGI internal configuration:
recordingcheck|20090602-171926|1243981150.3546: Array
recordingcheck|20090602-171926|1243981150.3546: (
recordingcheck|20090602-171926|1243981150.3546: [phpagi] => Array
recordingcheck|20090602-171926|1243981150.3546: (
recordingcheck|20090602-171926|1243981150.3546: [debug] => 1
recordingcheck|20090602-171926|1243981150.3546: [error_handler] => 1
recordingcheck|20090602-171926|1243981150.3546: [admin] => root@localhost
recordingcheck|20090602-171926|1243981150.3546: [hostname] => localhost
recordingcheck|20090602-171926|1243981150.3546: [tempdir] => /var/spool/asterisk/tmp/
recordingcheck|20090602-171926|1243981150.3546: )
recordingcheck|20090602-171926|1243981150.3546:
recordingcheck|20090602-171926|1243981150.3546: [asmanager] => Array
recordingcheck|20090602-171926|1243981150.3546: (
recordingcheck|20090602-171926|1243981150.3546: [server] => localhost
recordingcheck|20090602-171926|1243981150.3546: [port] => 5038
recordingcheck|20090602-171926|1243981150.3546: [username] => meetme
recordingcheck|20090602-171926|1243981150.3546: [secret] => meetme3
recordingcheck|20090602-171926|1243981150.3546: )
recordingcheck|20090602-171926|1243981150.3546:
recordingcheck|20090602-171926|1243981150.3546: [fastagi] => Array
recordingcheck|20090602-171926|1243981150.3546: (
recordingcheck|20090602-171926|1243981150.3546: [#setuid] => 1
recordingcheck|20090602-171926|1243981150.3546: [#basedir] => /var/lib/asterisk/agi-bin/
recordingcheck|20090602-171926|1243981150.3546: )
recordingcheck|20090602-171926|1243981150.3546:
recordingcheck|20090602-171926|1243981150.3546: [festival] => Array
recordingcheck|20090602-171926|1243981150.3546: (
recordingcheck|20090602-171926|1243981150.3546: [text2wave] => /usr/bin/text2wave
recordingcheck|20090602-171926|1243981150.3546: )
recordingcheck|20090602-171926|1243981150.3546:
recordingcheck|20090602-171926|1243981150.3546: [cepstral] => Array
recordingcheck|20090602-171926|1243981150.3546: (
recordingcheck|20090602-171926|1243981150.3546: [#swift] => /opt/swift/bin/swift
recordingcheck|20090602-171926|1243981150.3546: [#voice] => David
recordingcheck|20090602-171926|1243981150.3546: )
recordingcheck|20090602-171926|1243981150.3546:
recordingcheck|20090602-171926|1243981150.3546: )
recordingcheck|20090602-171926|1243981150.3546:
recordingcheck|20090602-171926|1243981150.3546: Inbound recording not enabled
-- AGI Script recordingcheck completed, returning 0
-- Executing [s@macro-record-enable:5] MacroExit("Zap/1-1", "") in new stack
-- Executing [s@macro-exten-vm:9] Macro("Zap/1-1", "dial|15|tmw|200") in new stack
-- Executing [s@macro-dial:1] GotoIf("Zap/1-1", "0?dial") in new stack
-- Executing [s@macro-dial:2] SetMusicOnHold("Zap/1-1", "Trial") in new stack
-- Executing [s@macro-dial:3] AGI("Zap/1-1", "dialparties.agi") in new stack
-- Launched AGI Script /var/lib/asterisk/agi-bin/dialparties.agi
dialparties.agi: PHPAGI internal configuration:
dialparties.agi: Array
dialparties.agi: (
dialparties.agi: [phpagi] => Array
dialparties.agi: (
dialparties.agi: [debug] => 1
dialparties.agi: [error_handler] => 1
dialparties.agi: [admin] => root@localhost
dialparties.agi: [hostname] => localhost
dialparties.agi: [tempdir] => /var/spool/asterisk/tmp/
dialparties.agi: )
dialparties.agi:
dialparties.agi: [asmanager] => Array
dialparties.agi: (
dialparties.agi: [server] => localhost
dialparties.agi: [port] => 5038
dialparties.agi: [username] => meetme
dialparties.agi: [secret] => meetme3
dialparties.agi: )
dialparties.agi:
dialparties.agi: [fastagi] => Array
dialparties.agi: (
dialparties.agi: [#setuid] => 1
dialparties.agi: [#basedir] => /var/lib/asterisk/agi-bin/
dialparties.agi: )
dialparties.agi:
dialparties.agi: [festival] => Array
dialparties.agi: (
dialparties.agi: [text2wave] => /usr/bin/text2wave
dialparties.agi: )
dialparties.agi:
dialparties.agi: [cepstral] => Array
dialparties.agi: (
dialparties.agi: [#swift] => /opt/swift/bin/swift
dialparties.agi: [#voice] => David
dialparties.agi: )
dialparties.agi:
dialparties.agi: )
dialparties.agi:
dialparties.agi: Starting New Dialparties.agi
== Parsing '/etc/asterisk/manager.conf': Found
== Parsing '/etc/asterisk/manager_additional.conf': Found
== Parsing '/etc/asterisk/manager_custom.conf': Found
== Manager 'admin' logged on from 127.0.0.1
dialparties.agi: Caller ID name is '6153353322' number is '6153353322'
> dialparties.agi: Setting Alert-Info: info=<Bellcore-dr4>
-- AGI Script Executing Application: (SIPAddHeader) Options: (Alert-Info:info=<Bellcore-dr4>)
dialparties.agi: USE_CONFIRMATION: 'FALSE'
dialparties.agi: RINGGROUP_INDEX: ''
dialparties.agi: Methodology of ring is 'none'
-- dialparties.agi: Added extension 200 to extension map
> dialparties.agi: Extension 200 has call screening off
-- dialparties.agi: Extension 200 cf is disabled
-- dialparties.agi: Extension 200 do not disturb is disabled
> dialparties.agi: extnum 200 has: cw: 1; hascfb: 0 [] hascfu: 0 []
> dialparties.agi: ExtensionState: 0
-- dialparties.agi: dbset CALLTRACE/200 to 6153353322
-- dialparties.agi: Filtered ARG3: 200

 

[foneman]

Finally Part 3

== Manager 'admin' logged off from 127.0.0.1
-- AGI Script dialparties.agi completed, returning 0
-- Executing [s@macro-dial:7] Dial("Zap/1-1", "SIP/200|15|tmw") in new stack
-- Called 200
-- Started music on hold, class 'Trial', on Zap/1-1
Extension Changed 200[ext-local] new state Ringing for Notify User 204
Extension Changed 200[ext-local] new state Ringing for Notify User 214
Extension Changed 200[ext-local] new state Ringing for Notify User 201
-- SIP/200-0a332498 is ringing
-- Stopped music on hold on Zap/1-1
Extension Changed 200[ext-local] new state Idle for Notify User 204
Extension Changed 200[ext-local] new state Idle for Notify User 214
Extension Changed 200[ext-local] new state Idle for Notify User 201
== Spawn extension (macro-dial, s, 7) exited non-zero on 'Zap/1-1' in macro 'dial'
== Spawn extension (macro-dial, s, 7) exited non-zero on 'Zap/1-1' in macro 'exten-vm'
== Spawn extension (macro-dial, s, 7) exited non-zero on 'Zap/1-1'
-- Executing [h@macro-dial:1] Macro("Zap/1-1", "hangupcall") in new stack
-- Executing [s@macro-hangupcall:1] ResetCDR("Zap/1-1", "vw") in new stack
-- Executing [s@macro-hangupcall:2] NoCDR("Zap/1-1", "") in new stack
-- Executing [s@macro-hangupcall:3] GotoIf("Zap/1-1", "1?skiprg") in new stack
-- Goto (macro-hangupcall,s,6)
-- Executing [s@macro-hangupcall:6] GotoIf("Zap/1-1", "1?skipblkvm") in new stack
-- Goto (macro-hangupcall,s,9)
-- Executing [s@macro-hangupcall:9] GotoIf("Zap/1-1", "1?theend") in new stack
-- Goto (macro-hangupcall,s,11)
-- Executing [s@macro-hangupcall:11] Hangup("Zap/1-1", "") in new stack
== Spawn extension (macro-hangupcall, s, 11) exited non-zero on 'Zap/1-1' in macro 'hangupcall'
== Spawn extension (macro-hangupcall, s, 11) exited non-zero on 'Zap/1-1'
-- Hungup 'Zap/1-1'[/quote]
I hope anyway....

 

[tshif]

Foneman - If you blacklist a number, and then call in to these lines - is the blacklisting working?

Im trying to understand these failures. They seem to happen more often with ZAP and digital lines. Unfortunately, these are the lines that I have the least experience setting up inbound routes for in FreePBX.

What were really trying to figure out is if the zap lines are going through a traditional inbound route - cause if they are, and Superfecta is selected - I cant think of a thing that would make it fail. I just don't know if the inbound routes are working the same.

 

[foneman]

Blacklist.

I blacklisted my number and here is the output:
== Parsing '/etc/asterisk/asterisk.conf': Found
Connected to Asterisk 1.4.21.2 currently running on tncomm (pid = 3216)
Verbosity is at least 7
-- Starting simple switch on 'Zap/1-1'
-- Executing [s@from-zaptel:1] NoOp("Zap/1-1", "Entering from-zaptel with DI D == ") in new stack
-- Executing [s@from-zaptel:2] Ringing("Zap/1-1", "") in new stack
-- Executing [s@from-zaptel:3] Set("Zap/1-1", "DID=s") in new stack
-- Executing [s@from-zaptel:4] NoOp("Zap/1-1", "DID is now s") in new stack
-- Executing [s@from-zaptel:5] GotoIf("Zap/1-1", "1?zapok:notzap") in new st ack
-- Goto (from-zaptel,s,8)
-- Executing [s@from-zaptel:8] NoOp("Zap/1-1", "Is a Zaptel Channel") in new stack
-- Executing [s@from-zaptel:9] Set("Zap/1-1", "CHAN=1-1") in new stack
-- Executing [s@from-zaptel:10] Set("Zap/1-1", "CHAN=1") in new stack
-- Executing [s@from-zaptel:11] Macro("Zap/1-1", "from-zaptel-1|s|1") in new stack
-- Executing [s@macro-from-zaptel-1:1] NoOp("Zap/1-1", "Entering macro-from- zaptel-1 with DID = s and setting to: 6156908650") in new stack
-- Executing [s@macro-from-zaptel-1:2] Set("Zap/1-1", "__FROM_DID=6156908650 ") in new stack
-- Executing [s@macro-from-zaptel-1:3] Goto("Zap/1-1", "from-trunk|615690865 0|1") in new stack
-- Goto (from-trunk,6156908650,1)
== Channel 'Zap/1-1' jumping out of macro 'from-zaptel-1'
-- Executing [6156908650@from-trunk:1] Set("Zap/1-1", "__FROM_DID=6156908650 ") in new stack
-- Executing [6156908650@from-trunk:2] Gosub("Zap/1-1", "cidlookup|cidlookup _2|1") in new stack
-- Executing [cidlookup_2@cidlookup:1] Set("Zap/1-1", "CALLERID(name)=") in new stack
-- Executing [cidlookup_2@cidlookup:2] Return("Zap/1-1", "") in new stack
-- Executing [6156908650@from-trunk:3] Gosub("Zap/1-1", "app-blacklist-check |s|1") in new stack
-- Executing [s@app-blacklist-check:1] LookupBlacklist("Zap/1-1", "") in new stack
-- Executing [s@app-blacklist-check:2] GotoIf("Zap/1-1", "1?blacklisted") in new stack
-- Goto (app-blacklist-check,s,4)
-- Executing [s@app-blacklist-check:4] Answer("Zap/1-1", "") in new stack
-- Executing [s@app-blacklist-check:5] Wait("Zap/1-1", "1") in new stack
-- Executing [s@app-blacklist-check:6] Zapateller("Zap/1-1", "") in new stack
-- Executing [s@app-blacklist-check:7] Playback("Zap/1-1", "ss-noservice") in new stack
-- <Zap/1-1> Playing 'ss-noservice' (language 'en')
== Spawn extension (app-blacklist-check, s, 7) exited non-zero on 'Zap/1-1'
-- Hungup 'Zap/1-1'

 

[foneman]

Part 2

I got "the number you are trying to reach is not in service" recording from our pbx.

 

[FitnessPlus]

Please make sure that the caller ID source is properly selected when looking at the inbound route for that trunk.

 

[foneman]

Inbound

The inbound route is correct with Superfecta listed.

 

[FitnessPlus]

Can you post your debug info...I'm curious what sources you are using?

Also, if you are using the "Trunk Provided" source, please disable that one and see if that changes anything.

 

[foneman]

debug

Searching Asterisk Phonebook ... Gary Bailey
result

took 0.0007 seconds.

result

took 0.0003 seconds.

Searching Google ... not found
result

took 0.3312 seconds.

Searching PhoneSpamFilter ... value of 0 returned ... Not a SPAM caller
result

took 0.3636 seconds.

Searching AsteriDex ... not found
result

took 0.0011 seconds.

Searching Superfecta Cache ... not found
result

took 0.0013 seconds.

Looking for Provided Caller ID ... not found
result

took 0.0009 seconds.

Searching SugarCRM ... not found
result

took 0.0164 seconds.

Could not list all due to limits of the forum

 

[foneman]

Cli

-- Starting simple switch on 'Zap/1-1'
-- Executing [s@from-zaptel:1] NoOp("Zap/1-1", "Entering from-zaptel with DID == ") in new stack
-- Executing [s@from-zaptel:2] Ringing("Zap/1-1", "") in new stack
-- Executing [s@from-zaptel:3] Set("Zap/1-1", "DID=s") in new stack
-- Executing [s@from-zaptel:4] NoOp("Zap/1-1", "DID is now s") in new stack
-- Executing [s@from-zaptel:5] GotoIf("Zap/1-1", "1?zapok:notzap") in new stack
-- Goto (from-zaptel,s,8)
-- Executing [s@from-zaptel:8] NoOp("Zap/1-1", "Is a Zaptel Channel") in new stack
-- Executing [s@from-zaptel:9] Set("Zap/1-1", "CHAN=1-1") in new stack
-- Executing [s@from-zaptel:10] Set("Zap/1-1", "CHAN=1") in new stack
-- Executing [s@from-zaptel:11] Macro("Zap/1-1", "from-zaptel-1|s|1") in new stack
-- Executing [s@macro-from-zaptel-1:1] NoOp("Zap/1-1", "Entering macro-from-zaptel-1 with DID = s and setting to: 6156908650") in new stack
-- Executing [s@macro-from-zaptel-1:2] Set("Zap/1-1", "__FROM_DID=6156908650") in new stack
-- Executing [s@macro-from-zaptel-1:3] Goto("Zap/1-1", "from-trunk|6156908650|1") in new stack
-- Goto (from-trunk,6156908650,1)
== Channel 'Zap/1-1' jumping out of macro 'from-zaptel-1'
-- Executing [6156908650@from-trunk:1] Set("Zap/1-1", "__FROM_DID=6156908650") in new stack
-- Executing [6156908650@from-trunk:2] Gosub("Zap/1-1", "cidlookup|cidlookup_3|1") in new stack
-- Executing [cidlookup_3@cidlookup:1] Set("Zap/1-1", "CALLERID(name)=") in new stack
-- Executing [cidlookup_3@cidlookup:2] Return("Zap/1-1", "") in new stack
-- Executing [6156908650@from-trunk:3] Gosub("Zap/1-1", "app-blacklist-check|s|1") in new stack
-- Executing [s@app-blacklist-check:1] LookupBlacklist("Zap/1-1", "") in new stack
-- Executing [s@app-blacklist-check:2] GotoIf("Zap/1-1", "0?blacklisted") in new stack
-- Executing [s@app-blacklist-check:3] Return("Zap/1-1", "") in new stack
-- Executing [6156908650@from-trunk:4] ExecIf("Zap/1-1", "1 |Set|CALLERID(name)=6153353322") in new stack
-- Executing [6156908650@from-trunk:5] SetMusicOnHold("Zap/1-1", "Trial") in new stack
-- Executing [6156908650@from-trunk:6] Set("Zap/1-1", "__MOHCLASS=Trial") in new stack
-- Executing [6156908650@from-trunk:7] Set("Zap/1-1", "FAX_RX=1100") in new stack
-- Executing [6156908650@from-trunk:8] Set("Zap/1-1", "[email protected]") in new stack
-- Executing [6156908650@from-trunk:9] Answer("Zap/1-1", "") in new stack
-- Executing [6156908650@from-trunk:10] Wait("Zap/1-1", "3") in new stack
-- Executing [6156908650@from-trunk:11] Set("Zap/1-1", "__CALLINGPRES_SV=allowed_not_screened") in new stack
-- Executing [6156908650@from-trunk:12] SetCallerPres("Zap/1-1", "allowed_not_screened") in new stack
-- Executing [6156908650@from-trunk:13] Set("Zap/1-1", "__ALERT_INFO=info=<Bellcore-dr4>") in new stack
-- Executing [6156908650@from-trunk:14] Goto("Zap/1-1", "app-daynight|0|1") in new stack
-- Goto (app-daynight,0,1)
-- Executing [0@app-daynight:1] GotoIf("Zap/1-1", "0?timeconditions|1|1:timeconditions|1|1") in new stack
-- Goto (timeconditions,1,1)
-- Executing [1@timeconditions:1] GotoIfTime("Zap/1-1", "17:00-07:59|mon-fri|*|*?ivr-8|s|1") in new stack
-- Executing [1@timeconditions:2] Goto("Zap/1-1", "timeconditions|3|1") in new stack
-- Goto (timeconditions,3,1)
-- Executing [3@timeconditions:1] GotoIfTime("Zap/1-1", "00:00-23:59|sat-sun|*|*?ivr-8|s|1") in new stack
-- Executing [3@timeconditions:2] Goto("Zap/1-1", "timeconditions|2|1") in new stack
-- Goto (timeconditions,2,1)
-- Executing [2@timeconditions:1] GotoIfTime("Zap/1-1", "12:00-16:59|mon-fri|*|*?ext-group|3005|1") in new stack
-- Executing [2@timeconditions:2] Goto("Zap/1-1", "ext-group|3004|1") in new stack
-- Goto (ext-group,3004,1)
-- Executing [3004@ext-group:1] Macro("Zap/1-1", "user-callerid|") in new stack
-- Executing [s@macro-user-callerid:1] Set("Zap/1-1", "AMPUSER=6153353322") in new stack
-- Executing [s@macro-user-callerid:2] GotoIf("Zap/1-1", "0?report") in new stack
-- Executing [s@macro-user-callerid:3] ExecIf("Zap/1-1", "1|Set|REALCALLERIDNUM=6153353322") in new stack
-- Executing [s@macro-user-callerid:4] Set("Zap/1-1", "AMPUSER=") in new stack
-- Executing [s@macro-user-callerid:5] Set("Zap/1-1", "AMPUSERCIDNAME=") in new stack
-- Executing [s@macro-user-callerid:6] GotoIf("Zap/1-1", "1?report") in new stack
-- Goto (macro-user-callerid,s,11)
-- Executing [s@macro-user-callerid:11] GotoIf("Zap/1-1", "0?continue") in new stack
-- Executing [s@macro-user-callerid:12] Set("Zap/1-1", "__TTL=64") in new stack
-- Executing [s@macro-user-callerid:13] GotoIf("Zap/1-1", "1?continue") in new stack
-- Goto (macro-user-callerid,s,20)
-- Executing [s@macro-user-callerid:20] NoOp("Zap/1-1", "Using CallerID "6153353322" <6153353322>") in new stack
-- Executing [3004@ext-group:2] GotoIf("Zap/1-1", "1?skipdb") in new stack
-- Goto (ext-group,3004,4)
-- Executing [3004@ext-group:4] Set("Zap/1-1", "__NODEST=") in new stack
-- Executing [3004@ext-group:5] Set("Zap/1-1", "__BLKVM_OVERRIDE=BLKVM/3004/Zap/1-1") in new stack
-- Executing [3004@ext-group:6] Set("Zap/1-1", "__BLKVM_BASE=3004") in new stack
-- Executing [3004@ext-group:7] Set("Zap/1-1", "DB(BLKVM/3004/Zap/1-1)=TRUE") in new stack
-- Executing [3004@ext-group:8] Set("Zap/1-1", "RRNODEST=") in new stack
-- Executing [3004@ext-group:9] Set("Zap/1-1", "__NODEST=3004") in new stack
-- Executing [3004@ext-group:10] Set("Zap/1-1", "RecordMethod=Group") in new stack
-- Executing [3004@ext-group:11] Macro("Zap/1-1", "record-enable|200-201-205|Group") in new stack
-- Executing [s@macro-record-enable:1] GotoIf("Zap/1-1", "1?check") in new stack
-- Goto (macro-record-enable,s,4)
-- Executing [s@macro-record-enable:4] AGI("Zap/1-1", "recordingcheck|20090603-092427|1244039062.3616") in new stack
-- Launched AGI Script /var/lib/asterisk/agi-bin/recordingcheck
recordingcheck|20090603-092427|1244039062.3616: PHPAGI internal configuration:
recordingcheck|20090603-092427|1244039062.3616: Array
recordingcheck|20090603-092427|1244039062.3616: (
recordingcheck|20090603-092427|1244039062.3616: [phpagi] => Array
recordingcheck|20090603-092427|1244039062.3616: (
recordingcheck|20090603-092427|1244039062.3616: [debug] => 1
recordingcheck|20090603-092427|1244039062.3616: [error_handler] => 1
recordingcheck|20090603-092427|1244039062.3616: [admin] => root@localhost
recordingcheck|20090603-092427|1244039062.3616: [hostname] => localhost
recordingcheck|20090603-092427|1244039062.3616: [tempdir] => /var/spool/asterisk/tmp/
recordingcheck|20090603-092427|1244039062.3616: )
recordingcheck|20090603-092427|1244039062.3616:
recordingcheck|20090603-092427|1244039062.3616: [asmanager] => Array
recordingcheck|20090603-092427|1244039062.3616: (
recordingcheck|20090603-092427|1244039062.3616: [server] => localhost
recordingcheck|20090603-092427|1244039062.3616: [port] => 5038
recordingcheck|20090603-092427|1244039062.3616: [username] => meetme
recordingcheck|20090603-092427|1244039062.3616: [secret] => meetme3
recordingcheck|20090603-092427|1244039062.3616: )
recordingcheck|20090603-092427|1244039062.3616:
recordingcheck|20090603-092427|1244039062.3616: [fastagi] => Array
recordingcheck|20090603-092427|1244039062.3616: (
recordingcheck|20090603-092427|1244039062.3616: [#setuid] => 1
recordingcheck|20090603-092427|1244039062.3616: [#basedir] => /var/lib/asterisk/agi-bin/
recordingcheck|20090603-092427|1244039062.3616: )
recordingcheck|20090603-092427|1244039062.3616:
recordingcheck|20090603-092427|1244039062.3616: [festival] => Array
recordingcheck|20090603-092427|1244039062.3616: (
recordingcheck|20090603-092427|1244039062.3616: [text2wave] => /usr/bin/text2wave
recordingcheck|20090603-092427|1244039062.3616: )
recordingcheck|20090603-092427|1244039062.3616:
recordingcheck|20090603-092427|1244039062.3616: [cepstral] => Array
recordingcheck|20090603-092427|1244039062.3616: (
recordingcheck|20090603-092427|1244039062.3616: [#swift] => /opt/swift/bin/swift
recordingcheck|20090603-092427|1244039062.3616: [#voice] => David
recordingcheck|20090603-092427|1244039062.3616: )
recordingcheck|20090603-092427|1244039062.3616:
recordingcheck|20090603-092427|1244039062.3616: )
recordingcheck|20090603-092427|1244039062.3616:
-- AGI Script recordingcheck completed, returning 0
-- Executing [s@macro-record-enable:5] MacroExit("Zap/1-1", "") in new stack
-- Executing [3004@ext-group:12] Set("Zap/1-1", "RingGroupMethod=ringall") in new stack
-- Executing [3004@ext-group:13] Macro("Zap/1-1", "dial|20|tmw|200-201-205") in new stack
-- Executing [s@macro-dial:1] GotoIf("Zap/1-1", "0?dial") in new stack
-- Executing [s@macro-dial:2] SetMusicOnHold("Zap/1-1", "Trial") in new stack
-- Executing [s@macro-dial:3] AGI("Zap/1-1", "dialparties.agi") in new stack
-- Launched AGI Script /var/lib/asterisk/agi-bin
dialparties.agi: Starting New Dialparties.agi
== Parsing '/etc/asterisk/manager.conf': Found
== Parsing '/etc/asterisk/manager_additional.conf': Found
== Parsing '/etc/asterisk/manager_custom.conf': Found
== Manager 'admin' logged on from 127.0.0.1
dialparties.agi: Caller ID name is '6153353322' number is '6153353322'

 

[FitnessPlus]

Searching Asterisk Phonebook ... Gary Bailey
result

took 0.0007 seconds.

result

took 0.0003 seconds.

Is that exactly what you're seeing in the debug? If so it appears there is some information missing about which source took 0.0003 seconds. Please look at your list of enabled sources and let me know what source should be there, and disable that source and see if it fixes your issue.

Infact, disable all sources and add them back one by one until the issue shows up again.