LastCallerID not Available for Blacklist or Call Trace

I’m running PIAF 1.7.5.6 on Asterisk 1.8.3.3 and FreePBX 2.9.0.5 Callcentric is my service provider and I have a single DID.

To allow incoming calls without “allowing anonymous SIP calls” I have implemented the config recommendations from this post:

http://www.dslreports.com/forum/r25249052-

Calls now come in normally and I see the incoming caller id and name on my phone as well as in the Call Logs But when i try to Blacklist the last caller with a *32, I get the message “unidentified or withheld and therefore cannot be called back”. I also get a “…from an unknown caller” message when I do a *69 call trace.

I do not understand why the last callerid is not available to these modules when it is available to be displayed on the phone and in the call logs.

I have also tried Asterisk 1.6.2 and FreePBX 2.8 and have the same symptoms.

Below is my asterisk log after the call comes in and ends with a failed Blacklist attempt:

[2011-05-27 20:59:24] VERBOSE[3541] pbx.c: -- Executing [[email protected]:1] NoOp("SIP/66.193.176.35-00000000", "Received incoming SIP connection from unknown peer to 17772404552") in new stack [2011-05-27 20:59:24] VERBOSE[3541] pbx.c: -- Executing [[email protected]:2] Set("SIP/66.193.176.35-00000000", "DID=17772404552") in new stack [2011-05-27 20:59:24] VERBOSE[3541] pbx.c: -- Executing [[email protected]:3] Goto("SIP/66.193.176.35-00000000", "s,1") in new stack [2011-05-27 20:59:24] VERBOSE[3541] pbx.c: -- Goto (from-sip-external-custom,s,1) [2011-05-27 20:59:24] VERBOSE[3541] pbx.c: -- Executing [[email protected]:1] GotoIf("SIP/66.193.176.35-00000000", "1?callcentric") in new stack [2011-05-27 20:59:24] VERBOSE[3541] pbx.c: -- Goto (from-sip-external-custom,s,5) [2011-05-27 20:59:24] ERROR[3541] pbx.c: Function SIP _HEADER not registered [2011-05-27 20:59:24] VERBOSE[3541] pbx.c: -- Executing [[email protected]:5] Set("SIP/66.193.176.35-00000000", "Var_FROM_DOMAIN=") in new stack [2011-05-27 20:59:24] VERBOSE[3541] pbx.c: -- Executing [[email protected]:6] GotoIf("SIP/66.193.176.35-00000000", "0?callcentric-next") in new stack [2011-05-27 20:59:24] VERBOSE[3541] pbx.c: -- Executing [[email protected]:7] GotoIf("SIP/66.193.176.35-00000000", "0?callcentric-next") in new stack [2011-05-27 20:59:24] VERBOSE[3541] pbx.c: -- Executing [[email protected]:8] GotoIf("SIP/66.193.176.35-00000000", "0?callcentric-next:checklang") in new stack [2011-05-27 20:59:24] VERBOSE[3541] pbx.c: -- Goto (from-sip-external-custom,s,12) [2011-05-27 20:59:24] VERBOSE[3541] pbx.c: -- Executing [[email protected]:12] GotoIf("SIP/66.193.176.35-00000000", "0?setlanguage:from-trunk,17772404552,1") in new stack [2011-05-27 20:59:24] VERBOSE[3541] pbx.c: -- Goto (from-trunk,17772404552,1) [2011-05-27 20:59:24] VERBOSE[3541] pbx.c: -- Executing [[email protected]:1] Set("SIP/66.193.176.35-00000000", "__FROM_DID=17772404552") in new stack [2011-05-27 20:59:24] VERBOSE[3541] pbx.c: -- Executing [[email protected]:2] Gosub("SIP/66.193.176.35-00000000", "app-blacklist-check,s,1") in new stack [2011-05-27 20:59:24] VERBOSE[3541] pbx.c: -- Executing [[email protected]:1] GotoIf("SIP/66.193.176.35-00000000", "0?check-blocked") in new stack [2011-05-27 20:59:24] VERBOSE[3541] pbx.c: -- Executing [[email protected]:2] GotoIf("SIP/66.193.176.35-00000000", "0?check-blocked") in new stack [2011-05-27 20:59:24] VERBOSE[3541] pbx.c: -- Executing [[email protected]:3] GotoIf("SIP/66.193.176.35-00000000", "0?check-blocked:check") in new stack [2011-05-27 20:59:24] VERBOSE[3541] pbx.c: -- Goto (app-blacklist-check,s,5) [2011-05-27 20:59:24] VERBOSE[3541] pbx.c: -- Executing [[email protected]:5] GotoIf("SIP/66.193.176.35-00000000", "0?blacklisted") in new stack [2011-05-27 20:59:24] VERBOSE[3541] pbx.c: -- Executing [[email protected]:6] Set("SIP/66.193.176.35-00000000", "CALLED_BLACKLIST=1") in new stack [2011-05-27 20:59:24] VERBOSE[3541] pbx.c: -- Executing [[email protected]:7] Return("SIP/66.193.176.35-00000000", "") in new stack [2011-05-27 20:59:24] VERBOSE[3541] pbx.c: -- Executing [[email protected]:3] ExecIf("SIP/66.193.176.35-00000000", "0 ?Set(CALLERID(name)=14025977609)") in new stack [2011-05-27 20:59:24] VERBOSE[3541] pbx.c: -- Executing [[email protected]:4] Set("SIP/66.193.176.35-00000000", "__CALLINGPRES_SV=allowed_not_screened") in new stack [2011-05-27 20:59:24] VERBOSE[3541] pbx.c: -- Executing [[email protected]:5] Set("SIP/66.193.176.35-00000000", "CALLERPRES()=allowed_not_screened") in new stack [2011-05-27 20:59:24] VERBOSE[3541] pbx.c: -- Executing [[email protected]:6] Goto("SIP/66.193.176.35-00000000", "from-did-direct,702,1") in new stack [2011-05-27 20:59:24] VERBOSE[3541] pbx.c: -- Goto (from-did-direct,702,1) [2011-05-27 20:59:24] VERBOSE[3541] pbx.c: -- Executing [[email protected]:1] Macro("SIP/66.193.176.35-00000000", "exten-vm,702,702") in new stack [2011-05-27 20:59:24] VERBOSE[3541] pbx.c: -- Executing [[email protected]:1] Macro("SIP/66.193.176.35-00000000", "user-callerid,") in new stack [2011-05-27 20:59:24] VERBOSE[3541] pbx.c: -- Executing [[email protected]:1] Set("SIP/66.193.176.35-00000000", "AMPUSER=14025977609") in new stack [2011-05-27 20:59:24] VERBOSE[3541] pbx.c: -- Executing [[email protected]:2] GotoIf("SIP/66.193.176.35-00000000", "0?report") in new stack [2011-05-27 20:59:24] VERBOSE[3541] pbx.c: -- Executing [[email protected]:3] ExecIf("SIP/66.193.176.35-00000000", "1?Set(REALCALLERIDNUM=14025977609)") in new stack [2011-05-27 20:59:24] VERBOSE[3541] pbx.c: -- Executing [[email protected]:4] Set("SIP/66.193.176.35-00000000", "AMPUSER=") in new stack [2011-05-27 20:59:24] VERBOSE[3541] pbx.c: -- Executing [[email protected]:5] Set("SIP/66.193.176.35-00000000", "AMPUSERCIDNAME=") in new stack [2011-05-27 20:59:24] VERBOSE[3541] pbx.c: -- Executing [[email protected]:6] GotoIf("SIP/66.193.176.35-00000000", "1?report") in new stack [2011-05-27 20:59:24] VERBOSE[3541] pbx.c: -- Goto (macro-user-callerid,s,10) [2011-05-27 20:59:24] VERBOSE[3541] pbx.c: -- Executing [[email protected]:10] GotoIf("SIP/66.193.176.35-00000000", "0?continue") in new stack [2011-05-27 20:59:24] VERBOSE[3541] pbx.c: -- Executing [[email protected]:11] Set("SIP/66.193.176.35-00000000", "__TTL=64") in new stack [2011-05-27 20:59:24] VERBOSE[3541] pbx.c: -- Executing [[email protected]:12] GotoIf("SIP/66.193.176.35-00000000", "1?continue") in new stack [2011-05-27 20:59:24] VERBOSE[3541] pbx.c: -- Goto (macro-user-callerid,s,19) [2011-05-27 20:59:24] VERBOSE[3541] pbx.c: -- Executing [[email protected]:19] Set("SIP/66.193.176.35-00000000", "CALLERID(number)=14025977609") in new stack [2011-05-27 20:59:24] VERBOSE[3541] pbx.c: -- Executing [[email protected]:20] Set("SIP/66.193.176.35-00000000", "CALLERID(name)=AVAYA INC") in new stack [2011-05-27 20:59:24] VERBOSE[3541] pbx.c: -- Executing [[email protected]:21] NoOp("SIP/66.193.176.35-00000000", "Using CallerID "AVAYA INC" <14025977609>") in new stack [2011-05-27 20:59:24] VERBOSE[3541] pbx.c: -- Executing [[email protected]:2] Set("SIP/66.193.176.35-00000000", "RingGroupMethod=none") in new stack [2011-05-27 20:59:24] VERBOSE[3541] pbx.c: -- Executing [[email protected]:3] Set("SIP/66.193.176.35-00000000", "VMBOX=702") in new stack [2011-05-27 20:59:24] VERBOSE[3541] pbx.c: -- Executing [[email protected]:4] Set("SIP/66.193.176.35-00000000", "__EXTTOCALL=702") in new stack [2011-05-27 20:59:24] VERBOSE[3541] pbx.c: -- Executing [[email protected]:5] Set("SIP/66.193.176.35-00000000", "CFUEXT=") in new stack [2011-05-27 20:59:24] VERBOSE[3541] pbx.c: -- Executing [[email protected]:6] Set("SIP/66.193.176.35-00000000", "CFBEXT=") in new stack [2011-05-27 20:59:24] VERBOSE[3541] pbx.c: -- Executing [[email protected]:7] Set("SIP/66.193.176.35-00000000", "RT=20") in new stack [2011-05-27 20:59:24] VERBOSE[3541] pbx.c: -- Executing [[email protected]:8] Macro("SIP/66.193.176.35-00000000", "record-enable,702,IN") in new stack [2011-05-27 20:59:24] VERBOSE[3541] pbx.c: -- Executing [[email protected]:1] GotoIf("SIP/66.193.176.35-00000000", "1?check") in new stack [2011-05-27 20:59:24] VERBOSE[3541] pbx.c: -- Goto (macro-record-enable,s,4) [2011-05-27 20:59:24] VERBOSE[3541] pbx.c: -- Executing [[email protected]:4] ExecIf("SIP/66.193.176.35-00000000", "0?MacroExit()") in new stack [2011-05-27 20:59:24] VERBOSE[3541] pbx.c: -- Executing [[email protected]:5] GotoIf("SIP/66.193.176.35-00000000", "0?Group:OUT") in new stack [2011-05-27 20:59:24] VERBOSE[3541] pbx.c: -- Goto (macro-record-enable,s,15) [2011-05-27 20:59:24] VERBOSE[3541] pbx.c: -- Executing [[email protected]:15] GotoIf("SIP/66.193.176.35-00000000", "1?IN") in new stack [2011-05-27 20:59:24] VERBOSE[3541] pbx.c: -- Goto (macro-record-enable,s,20) [2011-05-27 20:59:24] VERBOSE[3541] pbx.c: -- Executing [[email protected]:20] ExecIf("SIP/66.193.176.35-00000000", "1?MacroExit()") in new stack [2011-05-27 20:59:24] VERBOSE[3541] pbx.c: -- Executing [[email protected]:9] Macro("SIP/66.193.176.35-00000000", "dial-one,20,tW,702") in new stack [2011-05-27 20:59:24] VERBOSE[3541] pbx.c: -- Executing [[email protected]:1] Set("SIP/66.193.176.35-00000000", "DEXTEN=702") in new stack [2011-05-27 20:59:24] VERBOSE[3541] pbx.c: -- Executing [[email protected]:2] Set("SIP/66.193.176.35-00000000", "DIALSTATUS_CW=") in new stack [2011-05-27 20:59:24] VERBOSE[3541] pbx.c: -- Executing [[email protected]:3] GosubIf("SIP/66.193.176.35-00000000", "0?screen,1") in new stack [2011-05-27 20:59:24] VERBOSE[3541] pbx.c: -- Executing [[email protected]:4] GosubIf("SIP/66.193.176.35-00000000", "0?cf,1") in new stack [2011-05-27 20:59:24] VERBOSE[3541] pbx.c: -- Executing [[email protected]:5] GotoIf("SIP/66.193.176.35-00000000", "1?skip1") in new stack [2011-05-27 20:59:24] VERBOSE[3541] pbx.c: -- Goto (macro-dial-one,s,8) [2011-05-27 20:59:24] VERBOSE[3541] pbx.c: -- Executing [[email protected]:8] GotoIf("SIP/66.193.176.35-00000000", "0?nodial") in new stack [2011-05-27 20:59:24] VERBOSE[3541] pbx.c: -- Executing [[email protected]:9] GotoIf("SIP/66.193.176.35-00000000", "0?continue") in new stack [2011-05-27 20:59:24] VERBOSE[3541] pbx.c: -- Executing [[email protected]:10] Set("SIP/66.193.176.35-00000000", "EXTHASCW=ENABLED") in new stack [2011-05-27 20:59:24] VERBOSE[3541] pbx.c: -- Executing [[email protected]:11] GotoIf("SIP/66.193.176.35-00000000", "0?next1:cwinusebusy") in new stack [2011-05-27 20:59:24] VERBOSE[3541] pbx.c: -- Goto (macro-dial-one,s,23) [2011-05-27 20:59:24] VERBOSE[3541] pbx.c: -- Executing [[email protected]:23] GotoIf("SIP/66.193.176.35-00000000", "1?next3:continue") in new stack [2011-05-27 20:59:24] VERBOSE[3541] pbx.c: -- Goto (macro-dial-one,s,24) [2011-05-27 20:59:24] VERBOSE[3541] pbx.c: -- Executing [[email protected]:24] ExecIf("SIP/66.193.176.35-00000000", "0?Set(DIALSTATUS_CW=BUSY)") in new stack [2011-05-27 20:59:24] VERBOSE[3541] pbx.c: -- Executing [[email protected]:25] GotoIf("SIP/66.193.176.35-00000000", "0?nodial") in new stack [2011-05-27 20:59:24] VERBOSE[3541] pbx.c: -- Executing [[email protected]:26] GosubIf("SIP/66.193.176.35-00000000", "1?dstring,1:dlocal,1") in new stack [2011-05-27 20:59:24] VERBOSE[3541] pbx.c: -- Executing [[email protected]:1] Set("SIP/66.193.176.35-00000000", "DSTRING=") in new stack [2011-05-27 20:59:24] VERBOSE[3541] pbx.c: -- Executing [[email protected]:2] Set("SIP/66.193.176.35-00000000", "DEVICES=702") in new stack [2011-05-27 20:59:24] VERBOSE[3541] pbx.c: -- Executing [[email protected]:3] ExecIf("SIP/66.193.176.35-00000000", "0?Return()") in new stack [2011-05-27 20:59:24] VERBOSE[3541] pbx.c: -- Executing [[email protected]:4] ExecIf("SIP/66.193.176.35-00000000", "0?Set(DEVICES=02)") in new stack [2011-05-27 20:59:24] VERBOSE[3541] pbx.c: -- Executing [[email protected]:5] Set("SIP/66.193.176.35-00000000", "LOOPCNT=1") in new stack [2011-05-27 20:59:24] VERBOSE[3541] pbx.c: -- Executing [[email protected]:6] Set("SIP/66.193.176.35-00000000", "ITER=1") in new stack [2011-05-27 20:59:24] VERBOSE[3541] pbx.c: -- Executing [[email protected]:7] Set("SIP/66.193.176.35-00000000", "THISDIAL=SIP/702") in new stack [2011-05-27 20:59:24] VERBOSE[3541] pbx.c: -- Executing [[email protected]:8] GosubIf("SIP/66.193.176.35-00000000", "1?zap2dahdi,1") in new stack [2011-05-27 20:59:24] VERBOSE[3541] pbx.c: -- Executing [[email protected]:1] ExecIf("SIP/66.193.176.35-00000000", "0?Return()") in new stack [2011-05-27 20:59:24] VERBOSE[3541] pbx.c: -- Executing [[email protected]:2] Set("SIP/66.193.176.35-00000000", "NEWDIAL=") in new stack [2011-05-27 20:59:24] VERBOSE[3541] pbx.c: -- Executing [[email protected]:3] Set("SIP/66.193.176.35-00000000", "LOOPCNT2=1") in new stack [2011-05-27 20:59:24] VERBOSE[3541] pbx.c: -- Executing [[email protected]:4] Set("SIP/66.193.176.35-00000000", "ITER2=1") in new stack [2011-05-27 20:59:24] VERBOSE[3541] pbx.c: -- Executing [[email protected]:5] Set("SIP/66.193.176.35-00000000", "THISPART2=SIP/702") in new stack [2011-05-27 20:59:24] VERBOSE[3541] pbx.c: -- Executing [[email protected]:6] ExecIf("SIP/66.193.176.35-00000000", "0?Set(THISPART2=DAHDI/702)") in new stack [2011-05-27 20:59:24] VERBOSE[3541] pbx.c: -- Executing [[email protected]:7] Set("SIP/66.193.176.35-00000000", "NEWDIAL=SIP/702&") in new stack [2011-05-27 20:59:24] VERBOSE[3541] pbx.c: -- Executing [[email protected]:8] Set("SIP/66.193.176.35-00000000", "ITER2=2") in new stack [2011-05-27 20:59:24] VERBOSE[3541] pbx.c: -- Executing [[email protected]:9] GotoIf("SIP/66.193.176.35-00000000", "0?begin2") in new stack [2011-05-27 20:59:24] VERBOSE[3541] pbx.c: -- Executing [[email protected]:10] Set("SIP/66.193.176.35-00000000", "THISDIAL=SIP/702") in new stack [2011-05-27 20:59:24] VERBOSE[3541] pbx.c: -- Executing [[email protected]:11] Return("SIP/66.193.176.35-00000000", "") in new stack [2011-05-27 20:59:24] VERBOSE[3541] pbx.c: -- Executing [[email protected]:9] Set("SIP/66.193.176.35-00000000", "DSTRING=SIP/702&") in new stack [2011-05-27 20:59:24] VERBOSE[3541] pbx.c: -- Executing [[email protected]:10] Set("SIP/66.193.176.35-00000000", "ITER=2") in new stack [2011-05-27 20:59:24] VERBOSE[3541] pbx.c: -- Executing [[email protected]:11] GotoIf("SIP/66.193.176.35-00000000", "0?begin") in new stack [2011-05-27 20:59:24] VERBOSE[3541] pbx.c: -- Executing [[email protected]:12] Set("SIP/66.193.176.35-00000000", "DSTRING=SIP/702") in new stack [2011-05-27 20:59:24] VERBOSE[3541] pbx.c: -- Executing [[email protected]:13] Return("SIP/66.193.176.35-00000000", "") in new stack [2011-05-27 20:59:24] VERBOSE[3541] pbx.c: -- Executing [[email protected]:27] GotoIf("SIP/66.193.176.35-00000000", "0?nodial") in new stack [2011-05-27 20:59:24] VERBOSE[3541] pbx.c: -- Executing [[email protected]:28] GotoIf("SIP/66.193.176.35-00000000", "1?skiptrace") in new stack [2011-05-27 20:59:24] VERBOSE[3541] pbx.c: -- Goto (macro-dial-one,s,30) [2011-05-27 20:59:24] VERBOSE[3541] pbx.c: -- Executing [[email protected]:30] Set("SIP/66.193.176.35-00000000", "D_OPTIONS=tW") in new stack [2011-05-27 20:59:24] VERBOSE[3541] pbx.c: -- Executing [[email protected]:31] ExecIf("SIP/66.193.176.35-00000000", "0?SIPAddHeader(Alert-Info: )") in new stack [2011-05-27 20:59:24] VERBOSE[3541] pbx.c: -- Executing [[email protected]:32] ExecIf("SIP/66.193.176.35-00000000", "0?SIPAddHeader()") in new stack [2011-05-27 20:59:24] VERBOSE[3541] pbx.c: -- Executing [[email protected]:33] ExecIf("SIP/66.193.176.35-00000000", "0?Set(CHANNEL(musicclass)=)") in new stack [2011-05-27 20:59:24] VERBOSE[3541] pbx.c: -- Executing [[email protected]:34] GosubIf("SIP/66.193.176.35-00000000", "0?qwait,1") in new stack [2011-05-27 20:59:24] VERBOSE[3541] pbx.c: -- Executing [[email protected]:35] Set("SIP/66.193.176.35-00000000", "__CWIGNORE=") in new stack [2011-05-27 20:59:24] VERBOSE[3541] pbx.c: -- Executing [[email protected]:36] Set("SIP/66.193.176.35-00000000", "__KEEPCID=TRUE") in new stack [2011-05-27 20:59:24] VERBOSE[3541] pbx.c: -- Executing [[email protected]:37] Dial("SIP/66.193.176.35-00000000", "SIP/702,20,tW") in new stack [2011-05-27 20:59:24] VERBOSE[3541] netsock.c: == Using SIP RTP TOS bits 184 [2011-05-27 20:59:24] VERBOSE[3541] netsock.c: == Using SIP RTP CoS mark 5 [2011-05-27 20:59:24] VERBOSE[3541] app_dial.c: -- Called 702 [2011-05-27 20:59:24] VERBOSE[3541] app_dial.c: -- SIP/702-00000001 is ringing [2011-05-27 20:59:29] VERBOSE[3542] manager.c: == Manager 'admin' logged on from 127.0.0.1 [2011-05-27 20:59:30] VERBOSE[3542] manager.c: == Manager 'admin' logged off from 127.0.0.1 [2011-05-27 20:59:32] VERBOSE[3541] app_dial.c: -- SIP/702-00000001 answered SIP/66.193.176.35-00000000 [2011-05-27 20:59:36] VERBOSE[3550] manager.c: == Manager 'admin' logged on from 127.0.0.1 [2011-05-27 20:59:37] VERBOSE[3550] manager.c: == Manager 'admin' logged off from 127.0.0.1 [2011-05-27 20:59:40] VERBOSE[3541] pbx.c: -- Executing [[email protected]:1] Macro("SIP/66.193.176.35-00000000", "hangupcall,") in new stack [2011-05-27 20:59:40] VERBOSE[3541] pbx.c: -- Executing [[email protected]:1] GotoIf("SIP/66.193.176.35-00000000", "1?skiprg") in new stack [2011-05-27 20:59:40] VERBOSE[3541] pbx.c: -- Goto (macro-hangupcall,s,4) [2011-05-27 20:59:40] VERBOSE[3541] pbx.c: -- Executing [[email protected]:4] GotoIf("SIP/66.193.176.35-00000000", "1?skipblkvm") in new stack [2011-05-27 20:59:40] VERBOSE[3541] pbx.c: -- Goto (macro-hangupcall,s,7) [2011-05-27 20:59:40] VERBOSE[3541] pbx.c: -- Executing [[email protected]:7] GotoIf("SIP/66.193.176.35-00000000", "1?theend") in new stack [2011-05-27 20:59:40] VERBOSE[3541] pbx.c: -- Goto (macro-hangupcall,s,9) [2011-05-27 20:59:40] VERBOSE[3541] pbx.c: -- Executing [[email protected]:9] Hangup("SIP/66.193.176.35-00000000", "") in new stack [2011-05-27 20:59:40] VERBOSE[3541] app_macro.c: == Spawn extension (macro-hangupcall, s, 9) exited non-zero on 'SIP/66.193.176.35-00000000' in macro 'hangupcall' [2011-05-27 20:59:40] VERBOSE[3541] app_macro.c: == Spawn extension (macro-dial-one, s, 37) exited non-zero on 'SIP/66.193.176.35-00000000' in macro 'dial-one' [2011-05-27 20:59:40] VERBOSE[3541] app_macro.c: == Spawn extension (macro-exten-vm, s, 9) exited non-zero on 'SIP/66.193.176.35-00000000' in macro 'exten-vm' [2011-05-27 20:59:40] VERBOSE[3541] pbx.c: == Spawn extension (from-did-direct, 702, 1) exited non-zero on 'SIP/66.193.176.35-00000000' [2011-05-27 20:59:42] VERBOSE[3558] manager.c: == Manager 'admin' logged on from 127.0.0.1 [2011-05-27 20:59:42] VERBOSE[3558] manager.c: == Manager 'admin' logged off from 127.0.0.1 [2011-05-27 20:59:43] VERBOSE[3561] manager.c: == Manager 'admin' logged on from 127.0.0.1 [2011-05-27 20:59:44] VERBOSE[3561] manager.c: == Manager 'admin' logged off from 127.0.0.1 [2011-05-27 20:59:50] VERBOSE[3569] manager.c: == Manager 'admin' logged on from 127.0.0.1 [2011-05-27 20:59:51] VERBOSE[3569] manager.c: == Manager 'admin' logged off from 127.0.0.1 [2011-05-27 20:59:57] VERBOSE[3577] manager.c: == Manager 'admin' logged on from 127.0.0.1 [2011-05-27 20:59:57] VERBOSE[3269] netsock.c: == Using SIP RTP TOS bits 184 [2011-05-27 20:59:57] VERBOSE[3269] netsock.c: == Using SIP RTP CoS mark 5 [2011-05-27 20:59:57] VERBOSE[3579] pbx.c: -- Executing [*[email protected]:1] Goto("SIP/702-00000002", "app-blacklist-last,s,1") in new stack [2011-05-27 20:59:57] VERBOSE[3579] pbx.c: -- Goto (app-blacklist-last,s,1) [2011-05-27 20:59:57] VERBOSE[3579] pbx.c: -- Executing [[email protected]:1] Answer("SIP/702-00000002", "") in new stack [2011-05-27 20:59:57] VERBOSE[3579] pbx.c: -- Executing [[email protected]:2] Wait("SIP/702-00000002", "1") in new stack [2011-05-27 20:59:58] VERBOSE[3577] manager.c: == Manager 'admin' logged off from 127.0.0.1 [2011-05-27 20:59:58] VERBOSE[3579] pbx.c: -- Executing [[email protected]:3] Set("SIP/702-00000002", "lastcaller=") in new stack [2011-05-27 20:59:58] VERBOSE[3579] pbx.c: -- Executing [[email protected]:4] GotoIf("SIP/702-00000002", "1?noinfo") in new stack [2011-05-27 20:59:58] VERBOSE[3579] pbx.c: -- Goto (app-blacklist-last,s,11) [2011-05-27 20:59:58] VERBOSE[3579] pbx.c: -- Executing [[email protected]:11] Playback("SIP/702-00000002", "unidentified-no-callback") in new stack [2011-05-27 20:59:58] VERBOSE[3579] file.c: -- <SIP/702-00000002> Playing 'unidentified-no-callback.gsm' (language 'en') [2011-05-27 21:00:02] VERBOSE[3579] pbx.c: == Spawn extension (app-blacklist-last, s, 11) exited non-zero on 'SIP/702-00000002'

Thanks for your help.