Agent announcement not being played

Hello,
Can you help me to check why the agent announcement is not being played?
It is set on the Queue app, but not being played when answered.

[2016-05-16 12:06:02] VERBOSE[32343][C-000095e9] pbx.c: Executing [[email protected]:1] Set("SIP/1100101-000094cf", "CDR(no_sentido)=internal") in new stack
[2016-05-16 12:06:02] VERBOSE[32343][C-000095e9] pbx.c: Executing [[email protected]:2] ExecIf("SIP/1100101-000094cf", "0?Set(CDR(no_sentido)=transferencia)") in new stack
[2016-05-16 12:06:02] VERBOSE[32343][C-000095e9] pbx.c: Executing [[email protected]:3] Macro("SIP/1100101-000094cf", "blkvm-set,reset") in new stack
[2016-05-16 12:06:02] VERBOSE[32343][C-000095e9] pbx.c: Executing [[email protected]:1] ExecIf("SIP/1100101-000094cf", "1?Set(__BLKVM_CHANNEL=SIP/1100101-000094cf)") in new stack
[2016-05-16 12:06:02] VERBOSE[32343][C-000095e9] pbx.c: Executing [[email protected]:2] Set("SIP/1100101-000094cf", "SHARED(BLKVM,SIP/1100101-000094cf)=TRUE") in new stack
[2016-05-16 12:06:02] VERBOSE[32343][C-000095e9] pbx.c: Executing [[email protected]:3] Set("SIP/1100101-000094cf", "GOSUB_RETVAL=TRUE") in new stack
[2016-05-16 12:06:02] VERBOSE[32343][C-000095e9] pbx.c: Executing [[email protected]:4] MacroExit("SIP/1100101-000094cf", "") in new stack
[2016-05-16 12:06:02] VERBOSE[32343][C-000095e9] pbx.c: Executing [[email protected]:4] ExecIf("SIP/1100101-000094cf", "1?Set(_DIAL_OPTIONS=TtrM(auto-blkvm))") in new stack
[2016-05-16 12:06:02] VERBOSE[32343][C-000095e9] pbx.c: Executing [[email protected]:5] Set("SIP/1100101-000094cf", "__NODEST=1199777") in new stack
[2016-05-16 12:06:02] VERBOSE[32343][C-000095e9] pbx.c: Executing [[email protected]:6] Set("SIP/1100101-000094cf", "QCIDPP=") in new stack
[2016-05-16 12:06:02] VERBOSE[32343][C-000095e9] pbx.c: Executing [[email protected]:7] Set("SIP/1100101-000094cf", "VQ_CIDPP=") in new stack
[2016-05-16 12:06:02] VERBOSE[32343][C-000095e9] pbx.c: Executing [[email protected]:8] ExecIf("SIP/1100101-000094cf", "0?Macro(prepend-cid,)") in new stack
[2016-05-16 12:06:02] VERBOSE[32343][C-000095e9] pbx.c: Executing [[email protected]:9] Set("SIP/1100101-000094cf", "QAINFO=") in new stack
[2016-05-16 12:06:02] VERBOSE[32343][C-000095e9] pbx.c: Executing [[email protected]:10] Set("SIP/1100101-000094cf", "VQ_AINFO=") in new stack
[2016-05-16 12:06:02] VERBOSE[32343][C-000095e9] pbx.c: Executing [[email protected]:11] ExecIf("SIP/1100101-000094cf", "0?Set(__ALERT_INFO=)") in new stack
[2016-05-16 12:06:02] VERBOSE[32343][C-000095e9] pbx.c: Executing [[email protected]:12] Set("SIP/1100101-000094cf", "QJOINMSG=") in new stack
[2016-05-16 12:06:02] VERBOSE[32343][C-000095e9] pbx.c: Executing [[email protected]:13] Set("SIP/1100101-000094cf", "VQ_JOINMSG=") in new stack
[2016-05-16 12:06:02] VERBOSE[32343][C-000095e9] pbx.c: Executing [[email protected]:14] Set("SIP/1100101-000094cf", "QRETRY=") in new stack
[2016-05-16 12:06:02] VERBOSE[32343][C-000095e9] pbx.c: Executing [[email protected]:15] Set("SIP/1100101-000094cf", "VQ_RETRY=") in new stack
[2016-05-16 12:06:02] VERBOSE[32343][C-000095e9] pbx.c: Executing [[email protected]:16] Set("SIP/1100101-000094cf", "QOPTIONS=t") in new stack
[2016-05-16 12:06:02] VERBOSE[32343][C-000095e9] pbx.c: Executing [[email protected]:17] Set("SIP/1100101-000094cf", "VQ_OPTIONS=") in new stack
[2016-05-16 12:06:02] VERBOSE[32343][C-000095e9] pbx.c: Executing [[email protected]:18] Set("SIP/1100101-000094cf", "QGOSUB=") in new stack
[2016-05-16 12:06:02] VERBOSE[32343][C-000095e9] pbx.c: Executing [[email protected]:19] Set("SIP/1100101-000094cf", "VQ_GOSUB=") in new stack
[2016-05-16 12:06:02] VERBOSE[32343][C-000095e9] pbx.c: Executing [[email protected]:20] Set("SIP/1100101-000094cf", "QAGI=") in new stack
[2016-05-16 12:06:02] VERBOSE[32343][C-000095e9] pbx.c: Executing [[email protected]:21] Set("SIP/1100101-000094cf", "VQ_AGI=") in new stack
[2016-05-16 12:06:02] VERBOSE[32343][C-000095e9] pbx.c: Executing [[email protected]:22] Set("SIP/1100101-000094cf", "QRULE=") in new stack
[2016-05-16 12:06:02] VERBOSE[32343][C-000095e9] pbx.c: Executing [[email protected]:23] Set("SIP/1100101-000094cf", "VQ_RULE=") in new stack
[2016-05-16 12:06:02] VERBOSE[32343][C-000095e9] pbx.c: Executing [[email protected]:24] Set("SIP/1100101-000094cf", "QPOSITION=") in new stack
[2016-05-16 12:06:02] VERBOSE[32343][C-000095e9] pbx.c: Executing [[email protected]:25] Set("SIP/1100101-000094cf", "VQ_POSITION=") in new stack
[2016-05-16 12:06:02] VERBOSE[32343][C-000095e9] pbx.c: Executing [[email protected]:26] Gosub("SIP/1100101-000094cf", "sub-record-check,s,1(q,1199777,no)") in new stack
[2016-05-16 12:06:02] VERBOSE[32343][C-000095e9] pbx.c: Executing [[email protected]:1] GotoIf("SIP/1100101-000094cf", "0?initialized") in new stack
[2016-05-16 12:06:02] VERBOSE[32343][C-000095e9] pbx.c: Executing [[email protected]:2] Set("SIP/1100101-000094cf", "__REC_STATUS=INITIALIZED") in new stack
[2016-05-16 12:06:02] VERBOSE[32343][C-000095e9] pbx.c: Executing [[email protected]:3] Set("SIP/1100101-000094cf", "NOW=1463411162") in new stack
[2016-05-16 12:06:02] VERBOSE[32343][C-000095e9] pbx.c: Executing [[email protected]:4] Set("SIP/1100101-000094cf", "__DAY=16") in new stack
[2016-05-16 12:06:02] VERBOSE[32343][C-000095e9] pbx.c: Executing [[email protected]:5] Set("SIP/1100101-000094cf", "__MONTH=05") in new stack
[2016-05-16 12:06:02] VERBOSE[32343][C-000095e9] pbx.c: Executing [[email protected]:6] Set("SIP/1100101-000094cf", "__YEAR=2016") in new stack
[2016-05-16 12:06:02] VERBOSE[32343][C-000095e9] pbx.c: Executing [[email protected]:7] Set("SIP/1100101-000094cf", "__TIMESTR=20160516-120602") in new stack
[2016-05-16 12:06:02] VERBOSE[32343][C-000095e9] pbx.c: Executing [[email protected]:8] Set("SIP/1100101-000094cf", "__FROMEXTEN=unknown") in new stack
[2016-05-16 12:06:02] VERBOSE[32343][C-000095e9] pbx.c: Executing [[email protected]:9] Set("SIP/1100101-000094cf", "__MON_FMT=WAV") in new stack
[2016-05-16 12:06:02] VERBOSE[32343][C-000095e9] pbx.c: Executing [[email protected]:10] NoOp("SIP/1100101-000094cf", "Recordings initialized") in new stack
[2016-05-16 12:06:02] VERBOSE[32343][C-000095e9] pbx.c: Executing [[email protected]:11] ExecIf("SIP/1100101-000094cf", "0?Set(ARG3=dontcare)") in new stack
[2016-05-16 12:06:02] VERBOSE[32343][C-000095e9] pbx.c: Executing [[email protected]:12] Set("SIP/1100101-000094cf", "REC_POLICY_MODE_SAVE=") in new stack
[2016-05-16 12:06:02] VERBOSE[32343][C-000095e9] pbx.c: Executing [[email protected]:13] ExecIf("SIP/1100101-000094cf", "0?Set(REC_STATUS=NO)") in new stack
[2016-05-16 12:06:02] VERBOSE[32343][C-000095e9] pbx.c: Executing [[email protected]:14] GotoIf("SIP/1100101-000094cf", "1?checkaction") in new stack
[2016-05-16 12:06:02] VERBOSE[32343][C-000095e9] pbx.c: Goto (sub-record-check,s,17)
[2016-05-16 12:06:02] VERBOSE[32343][C-000095e9] pbx.c: Executing [[email protected]:17] GotoIf("SIP/1100101-000094cf", "0?sub-record-check,q,1") in new stack
[2016-05-16 12:06:02] VERBOSE[32343][C-000095e9] pbx.c: Executing [[email protected]:18] NoOp("SIP/1100101-000094cf", "Generic q Recording Check - unknown 1199777") in new stack
[2016-05-16 12:06:02] VERBOSE[32343][C-000095e9] pbx.c: Executing [[email protected]:19] Gosub("SIP/1100101-000094cf", "recordcheck,1(no,q,1199777)") in new stack
[2016-05-16 12:06:02] VERBOSE[32343][C-000095e9] pbx.c: Executing [[email protected]:1] NoOp("SIP/1100101-000094cf", "Starting recording check against no") in new stack
[2016-05-16 12:06:02] VERBOSE[32343][C-000095e9] pbx.c: Executing [[email protected]:2] Goto("SIP/1100101-000094cf", "no") in new stack
[2016-05-16 12:06:02] VERBOSE[32343][C-000095e9] pbx.c: Goto (sub-record-check,recordcheck,12)
[2016-05-16 12:06:02] VERBOSE[32343][C-000095e9] pbx.c: Executing [[email protected]:12] Set("SIP/1100101-000094cf", "__REC_POLICY_MODE=NO") in new stack
[2016-05-16 12:06:02] VERBOSE[32343][C-000095e9] pbx.c: Executing [[email protected]:13] Return("SIP/1100101-000094cf", "") in new stack
[2016-05-16 12:06:02] VERBOSE[32343][C-000095e9] pbx.c: Executing [[email protected]:20] Return("SIP/1100101-000094cf", "") in new stack
[2016-05-16 12:06:02] VERBOSE[32343][C-000095e9] pbx.c: Executing [[email protected]:27] Set("SIP/1100101-000094cf", "__CWIGNORE=TRUE") in new stack
[2016-05-16 12:06:02] VERBOSE[32343][C-000095e9] pbx.c: Executing [[email protected]:28] ExecIf("SIP/1100101-000094cf", "0?Playback(, )") in new stack
[2016-05-16 12:06:02] VERBOSE[32343][C-000095e9] pbx.c: Executing [[email protected]:29] QueueLog("SIP/1100101-000094cf", "1199777,1463411162.108061,NONE,DID,") in new stack
[2016-05-16 12:06:02] VERBOSE[32343][C-000095e9] pbx.c: Executing [[email protected]:30] Set("SIP/1100101-000094cf", "QAANNOUNCE=custom/cliente_consorcio") in new stack
[2016-05-16 12:06:02] VERBOSE[32343][C-000095e9] pbx.c: Executing [[email protected]:31] Set("SIP/1100101-000094cf", "VQ_AANNOUNCE=") in new stack
[2016-05-16 12:06:02] VERBOSE[32343][C-000095e9] pbx.c: Executing [[email protected]:32] Set("SIP/1100101-000094cf", "QMOH=") in new stack
[2016-05-16 12:06:02] VERBOSE[32343][C-000095e9] pbx.c: Executing [[email protected]:33] Set("SIP/1100101-000094cf", "VQ_MOH=") in new stack
[2016-05-16 12:06:02] VERBOSE[32343][C-000095e9] pbx.c: Executing [[email protected]:34] ExecIf("SIP/1100101-000094cf", "0?Set(__MOHCLASS=)") in new stack
[2016-05-16 12:06:02] VERBOSE[32343][C-000095e9] pbx.c: Executing [[email protected]:35] ExecIf("SIP/1100101-000094cf", "0?Set(CHANNEL(musicclass)=)") in new stack
[2016-05-16 12:06:02] VERBOSE[32343][C-000095e9] pbx.c: Executing [[email protected]:36] Set("SIP/1100101-000094cf", "QMAXWAIT=") in new stack
[2016-05-16 12:06:02] VERBOSE[32343][C-000095e9] pbx.c: Executing [[email protected]:37] Set("SIP/1100101-000094cf", "VQ_MAXWAIT=") in new stack
[2016-05-16 12:06:02] VERBOSE[32343][C-000095e9] pbx.c: Executing [[email protected]:38] Set("SIP/1100101-000094cf", "QUEUENUM=1199777") in new stack
[2016-05-16 12:06:02] VERBOSE[32343][C-000095e9] pbx.c: Executing [[email protected]:39] Set("SIP/1100101-000094cf", "QUEUEJOINTIME=1463411162") in new stack
[2016-05-16 12:06:02] VERBOSE[32343][C-000095e9] pbx.c: Executing [[email protected]:40] Queue("SIP/1100101-000094cf", "1199777,t,,custom/cliente_consorcio,,,,,,") in new stack
[2016-05-16 12:06:02] VERBOSE[32343][C-000095e9] res_musiconhold.c: Started music on hold, class 'default', on channel 'SIP/1100101-000094cf'
[2016-05-16 12:06:02] VERBOSE[32343][C-000095e9] app_queue.c: Called Local/[email protected]/n
[2016-05-16 12:06:02] VERBOSE[32345][C-000095e9] pbx.c: Executing [[email protected]:1] Set("Local/[email protected];2", "QAGENT=1100106") in new stack
[2016-05-16 12:06:02] VERBOSE[32345][C-000095e9] pbx.c: Executing [[email protected]:2] Goto("Local/[email protected];2", "1199777,1") in new stack
[2016-05-16 12:06:02] VERBOSE[32345][C-000095e9] pbx.c: Goto (from-queue,1199777,1)
[2016-05-16 12:06:02] VERBOSE[32345][C-000095e9] pbx.c: Executing [[email protected]:1] Goto("Local/[email protected];2", "from-internal,1100106,1") in new stack
[2016-05-16 12:06:02] VERBOSE[32345][C-000095e9] pbx.c: Goto (from-internal,1100106,1)
[2016-05-16 12:06:02] VERBOSE[32345][C-000095e9] pbx.c: Executing [[email protected]:1] Set("Local/[email protected];2", "CDR(no_sentido)=internal") in new stack
[2016-05-16 12:06:02] VERBOSE[32345][C-000095e9] pbx.c: Executing [[email protected]:2] ExecIf("Local/[email protected];2", "0?Set(CDR(no_sentido)=transferencia)") in new stack
[2016-05-16 12:06:02] VERBOSE[32345][C-000095e9] pbx.c: Executing [[email protected]:3] Set("Local/[email protected];2", "DIAL_OPTIONS=TtrM(auto-blkvm)I") in new stack
[2016-05-16 12:06:02] VERBOSE[32345][C-000095e9] pbx.c: Executing [[email protected]:4] Set("Local/[email protected];2", "CONNECTEDLINE(num,i)=1100106") in new stack
[2016-05-16 12:06:02] VERBOSE[32345][C-000095e9] pbx.c: Executing [[email protected]:5] Gosub("Local/[email protected];2", "sub-presencestate-display,s,1(1100106)") in new stack
[2016-05-16 12:06:02] VERBOSE[32345][C-000095e9] pbx.c: Executing [[email protected]:1] Goto("Local/[email protected];2", "state-not_set,1") in new stack
[2016-05-16 12:06:02] VERBOSE[32345][C-000095e9] pbx.c: Goto (sub-presencestate-display,state-not_set,1)
[2016-05-16 12:06:02] VERBOSE[32345][C-000095e9] pbx.c: Executing [[email protected]:1] Set("Local/[email protected];2", "PRESENCESTATE_DISPLAY=") in new stack
[2016-05-16 12:06:02] VERBOSE[32345][C-000095e9] pbx.c: Executing [[email protected]:2] Return("Local/[email protected];2", "") in new stack
[2016-05-16 12:06:02] VERBOSE[32345][C-000095e9] pbx.c: Executing [[email protected]:6] Set("Local/[email protected];2", "CONNECTEDLINE(name)=1100106") in new stack
[2016-05-16 12:06:02] VERBOSE[32345][C-000095e9] pbx.c: Executing [[email protected]:7] Set("Local/[email protected];2", "FM_DIALSTATUS=NOT_INUSE") in new stack
[2016-05-16 12:06:02] VERBOSE[32345][C-000095e9] pbx.c: Executing [[email protected]:8] Set("Local/[email protected];2", "__EXTTOCALL=1100106") in new stack
[2016-05-16 12:06:02] VERBOSE[32345][C-000095e9] pbx.c: Executing [[email protected]:9] Set("Local/[email protected];2", "__PICKUPMARK=1100106") in new stack
[2016-05-16 12:06:02] VERBOSE[32345][C-000095e9] pbx.c: Executing [[email protected]:10] Macro("Local/[email protected];2", "blkvm-setifempty,") in new stack
[2016-05-16 12:06:02] VERBOSE[32343][C-000095e9] app_queue.c: Local/[email protected];1 connected line has changed. Saving it until answer for SIP/1100101-000094cf
[2016-05-16 12:06:02] VERBOSE[32345][C-000095e9] pbx.c: Executing [[email protected]:1] GotoIf("Local/[email protected];2", "0?init") in new stack
[2016-05-16 12:06:02] VERBOSE[32345][C-000095e9] pbx.c: Executing [[email protected]:2] Set("Local/[email protected];2", "GOSUB_RETVAL=TRUE") in new stack
[2016-05-16 12:06:02] VERBOSE[32345][C-000095e9] pbx.c: Executing [[email protected]:3] MacroExit("Local/[email protected];2", "") in new stack
[2016-05-16 12:06:02] VERBOSE[32345][C-000095e9] pbx.c: Executing [[email protected]:11] GotoIf("Local/[email protected];2", "1?skipov") in new stack
[2016-05-16 12:06:02] VERBOSE[32345][C-000095e9] pbx.c: Goto (from-internal,1100106,14)
[2016-05-16 12:06:02] VERBOSE[32345][C-000095e9] pbx.c: Executing [[email protected]:14] Set("Local/[email protected];2", "RRNODEST=1199777") in new stack
[2016-05-16 12:06:02] VERBOSE[32345][C-000095e9] pbx.c: Executing [[email protected]:15] Set("Local/[email protected];2", "__NODEST=1100106") in new stack
[2016-05-16 12:06:02] VERBOSE[32345][C-000095e9] pbx.c: Executing [[email protected]:16] GosubIf("Local/[email protected];2", "0?sub-fmsetcid,s,1()") in new stack
[2016-05-16 12:06:02] VERBOSE[32345][C-000095e9] pbx.c: Executing [[email protected]:17] Set("Local/[email protected];2", "RecordMethod=Group") in new stack
[2016-05-16 12:06:02] VERBOSE[32345][C-000095e9] pbx.c: Executing [[email protected]:18] Gosub("Local/[email protected];2", "sub-record-check,s,1(exten,1100106,)") in new stack
[2016-05-16 12:06:02] VERBOSE[32345][C-000095e9] pbx.c: Executing [[email protected]:1] GotoIf("Local/[email protected];2", "7?initialized") in new stack
[2016-05-16 12:06:02] VERBOSE[32345][C-000095e9] pbx.c: Goto (sub-record-check,s,10)
[2016-05-16 12:06:02] VERBOSE[32345][C-000095e9] pbx.c: Executing [[email protected]:10] NoOp("Local/[email protected];2", "Recordings initialized") in new stack
[2016-05-16 12:06:02] VERBOSE[32345][C-000095e9] pbx.c: Executing [[email protected]:11] ExecIf("Local/[email protected];2", "1?Set(ARG3=dontcare)") in new stack
[2016-05-16 12:06:02] VERBOSE[32345][C-000095e9] pbx.c: Executing [[email protected]:12] Set("Local/[email protected];2", "REC_POLICY_MODE_SAVE=NO") in new stack
[2016-05-16 12:06:02] VERBOSE[32345][C-000095e9] pbx.c: Executing [[email protected]:13] ExecIf("Local/[email protected];2", "0?Set(REC_STATUS=NO)") in new stack
[2016-05-16 12:06:02] VERBOSE[32345][C-000095e9] pbx.c: Executing [[email protected]:14] GotoIf("Local/[email protected];2", "5?checkaction") in new stack
[2016-05-16 12:06:02] VERBOSE[32345][C-000095e9] pbx.c: Goto (sub-record-check,s,17)
[2016-05-16 12:06:02] VERBOSE[32345][C-000095e9] pbx.c: Executing [[email protected]:17] GotoIf("Local/[email protected];2", "1?sub-record-check,exten,1") in new stack
[2016-05-16 12:06:02] VERBOSE[32345][C-000095e9] pbx.c: Goto (sub-record-check,exten,1)
[2016-05-16 12:06:02] VERBOSE[32345][C-000095e9] pbx.c: Executing [[email protected]:1] NoOp("Local/[email protected];2", "Exten Recording Check between unknown and 1100106") in new stack
[2016-05-16 12:06:02] VERBOSE[32345][C-000095e9] pbx.c: Executing [[email protected]:2] Set("Local/[email protected];2", "CALLTYPE=internal") in new stack
[2016-05-16 12:06:02] VERBOSE[32345][C-000095e9] pbx.c: Executing [[email protected]:3] ExecIf("Local/[email protected];2", "0?Set(CALLTYPE=)") in new stack
[2016-05-16 12:06:02] VERBOSE[32345][C-000095e9] pbx.c: Executing [[email protected]:4] Set("Local/[email protected];2", "CALLEE=never") in new stack
[2016-05-16 12:06:02] VERBOSE[32345][C-000095e9] pbx.c: Executing [[email protected]:5] ExecIf("Local/[email protected];2", "0?Set(CALLEE=dontcare)") in new stack
[2016-05-16 12:06:02] VERBOSE[32345][C-000095e9] pbx.c: Executing [[email protected]:6] GotoIf("Local/[email protected];2", "0?callee") in new stack
[2016-05-16 12:06:02] VERBOSE[32345][C-000095e9] pbx.c: Executing [[email protected]:7] GotoIf("Local/[email protected];2", "0?caller") in new stack
[2016-05-16 12:06:02] VERBOSE[32345][C-000095e9] pbx.c: Executing [[email protected]:8] ExecIf("Local/[email protected];2", "0?Set(CALLER_PRI=):Set(CALLER_PRI=0)") in new stack
[2016-05-16 12:06:02] VERBOSE[32345][C-000095e9] pbx.c: Executing [[email protected]:9] ExecIf("Local/[email protected];2", "2?Set(CALLEE_PRI=10):Set(CALLEE_PRI=0)") in new stack
[2016-05-16 12:06:02] VERBOSE[32345][C-000095e9] pbx.c: Executing [[email protected]:10] GotoIf("Local/[email protected];2", "0?caller:callee") in new stack
[2016-05-16 12:06:02] VERBOSE[32345][C-000095e9] pbx.c: Goto (sub-record-check,exten,11)
[2016-05-16 12:06:02] VERBOSE[32345][C-000095e9] pbx.c: Executing [[email protected]:11] Gosub("Local/[email protected];2", "recordcheck,1(never,internal,1100106)") in new stack
[2016-05-16 12:06:02] VERBOSE[32345][C-000095e9] pbx.c: Executing [[email protected]:1] NoOp("Local/[email protected];2", "Starting recording check against never") in new stack
[2016-05-16 12:06:02] VERBOSE[32345][C-000095e9] pbx.c: Executing [[email protected]:2] Goto("Local/[email protected];2", "never") in new stack
[2016-05-16 12:06:02] VERBOSE[32345][C-000095e9] pbx.c: Goto (sub-record-check,recordcheck,14)
[2016-05-16 12:06:02] VERBOSE[32345][C-000095e9] pbx.c: Executing [[email protected]:14] Set("Local/[email protected];2", "__REC_POLICY_MODE=NEVER") in new stack
[2016-05-16 12:06:02] VERBOSE[32345][C-000095e9] pbx.c: Executing [[email protected]:15] Goto("Local/[email protected];2", "stoprec") in new stack
[2016-05-16 12:06:02] VERBOSE[32345][C-000095e9] pbx.c: Goto (sub-record-check,recordcheck,25)
[2016-05-16 12:06:02] VERBOSE[32345][C-000095e9] pbx.c: Executing [[email protected]:25] NoOp("Local/[email protected];2", "Stopping recording: internal, 1100106") in new stack
[2016-05-16 12:06:02] VERBOSE[32345][C-000095e9] pbx.c: Executing [[email protected]:26] Set("Local/[email protected];2", "__REC_STATUS=STOPPED") in new stack
[2016-05-16 12:06:02] VERBOSE[32345][C-000095e9] pbx.c: Executing [[email protected]:27] System("Local/[email protected];2", "/var/lib/asterisk/bin/stoprecording.php "Local/[email protected];2"") in new stack
[2016-05-16 12:06:02] VERBOSE[32345][C-000095e9] pbx.c: Executing [[email protected]:28] Return("Local/[email protected];2", "") in new stack
[2016-05-16 12:06:02] VERBOSE[32345][C-000095e9] pbx.c: Executing [[email protected]:12] Return("Local/[email protected];2", "") in new stack
[2016-05-16 12:06:02] VERBOSE[32345][C-000095e9] pbx.c: Executing [[email protected]:19] Set("Local/[email protected];2", "RingGroupMethod=ringallv2-prim") in new stack
[2016-05-16 12:06:02] VERBOSE[32345][C-000095e9] pbx.c: Executing [[email protected]:20] Set("Local/[email protected];2", "_FMGRP=1100106") in new stack
[2016-05-16 12:06:02] VERBOSE[32345][C-000095e9] pbx.c: Executing [[email protected]:21] GotoIf("Local/[email protected];2", "0?doconfirm") in new stack
[2016-05-16 12:06:02] VERBOSE[32345][C-000095e9] pbx.c: Executing [[email protected]:22] Macro("Local/[email protected];2", "dial,27,TtrM(auto-blkvm)I,1100106") in new stack
[2016-05-16 12:06:02] VERBOSE[32345][C-000095e9] pbx.c: Executing [[email protected]:1] NoOp("Local/[email protected];2", "Blind Transfer: , Attended Transfer: , User: , Alert Info: ") in new stack
[2016-05-16 12:06:02] VERBOSE[32345][C-000095e9] pbx.c: Executing [[email protected]:2] ExecIf("Local/[email protected];2", "0?Set(ALERT_INFO=)") in new stack
[2016-05-16 12:06:02] VERBOSE[32345][C-000095e9] pbx.c: Executing [[email protected]:3] ExecIf("Local/[email protected];2", "0?Set(ALERT_INFO=)") in new stack
[2016-05-16 12:06:02] VERBOSE[32345][C-000095e9] pbx.c: Executing [[email protected]:4] ExecIf("Local/[email protected];2", "0?Set(ALERT_INFO=)") in new stack
[2016-05-16 12:06:02] VERBOSE[32345][C-000095e9] pbx.c: Executing [[email protected]:5] ExecIf("Local/[email protected];2", "0?Set(CHANNEL(musicclass)=)") in new stack
[2016-05-16 12:06:02] VERBOSE[32345][C-000095e9] pbx.c: Executing [[email protected]:6] AGI("Local/[email protected];2", "dialparties.agi") in new stack
[2016-05-16 12:06:02] VERBOSE[32345][C-000095e9] res_agi.c: Launched AGI Script /var/lib/asterisk/agi-bin/dialparties.agi
[2016-05-16 12:06:02] VERBOSE[32345][C-000095e9] res_agi.c: dialparties.agi: Starting New Dialparties.agi
[2016-05-16 12:06:02] VERBOSE[32345][C-000095e9] res_agi.c: dialparties.agi: Caller ID name is '1100101' number is '1100101'
[2016-05-16 12:06:02] VERBOSE[32345][C-000095e9] res_agi.c: dialparties.agi: Methodology of ring is  'ringallv2-prim'
[2016-05-16 12:06:02] VERBOSE[32345][C-000095e9] res_agi.c: dialparties.agi: Added extension 1100106 to extension map
[2016-05-16 12:06:02] VERBOSE[32345][C-000095e9] res_agi.c: dialparties.agi: Extension 1100106 cf is disabled
[2016-05-16 12:06:02] VERBOSE[32345][C-000095e9] res_agi.c: dialparties.agi: Extension 1100106 do not disturb is disabled
[2016-05-16 12:06:02] VERBOSE[32345][C-000095e9] res_agi.c: dialparties.agi: EXTENSION_STATE: 0 (NOT_INUSE)
[2016-05-16 12:06:02] VERBOSE[32345][C-000095e9] res_agi.c: dialparties.agi: Extension 1100106 has ExtensionState: 0
[2016-05-16 12:06:02] VERBOSE[32345][C-000095e9] res_agi.c: dialparties.agi: EXTENSION_STATE: 0 (NOT_INUSE)
[2016-05-16 12:06:02] VERBOSE[32345][C-000095e9] res_agi.c: dialparties.agi: Extension 1100106 has ExtensionState: 0
[2016-05-16 12:06:02] VERBOSE[32345][C-000095e9] res_agi.c: dialparties.agi: Checking CW and CFB status for extension 1100106
[2016-05-16 12:06:02] VERBOSE[32345][C-000095e9] res_agi.c: dialparties.agi: dbset CALLTRACE/1100106 to 1100101
[2016-05-16 12:06:02] VERBOSE[32345][C-000095e9] res_agi.c: dialparties.agi: Filtered ARG3: 1100106
[2016-05-16 12:06:02] VERBOSE[32345][C-000095e9] res_agi.c: <Local/[email protected];2>AGI Script dialparties.agi completed, returning 0
[2016-05-16 12:06:02] VERBOSE[32345][C-000095e9] pbx.c: Executing [[email protected]:9] NoOp("Local/[email protected];2", "Returned from dialparties with groups to dial") in new stack
[2016-05-16 12:06:02] VERBOSE[32345][C-000095e9] pbx.c: Executing [[email protected]:10] Set("Local/[email protected];2", "LOOPCNT=1") in new stack
[2016-05-16 12:06:02] VERBOSE[32345][C-000095e9] pbx.c: Executing [[email protected]:11] Set("Local/[email protected];2", "ITER=1") in new stack
[2016-05-16 12:06:02] VERBOSE[32345][C-000095e9] pbx.c: Executing [[email protected]:12] Set("Local/[email protected];2", "EXTTOCALL=1100106") in new stack
[2016-05-16 12:06:02] VERBOSE[32345][C-000095e9] pbx.c: Executing [[email protected]:13] NoOp("Local/[email protected];2", "Working with 1100106") in new stack
[2016-05-16 12:06:02] VERBOSE[32345][C-000095e9] pbx.c: Executing [[email protected]:14] Set("Local/[email protected];2", "ITER=2") in new stack
[2016-05-16 12:06:02] VERBOSE[32345][C-000095e9] pbx.c: Executing [[email protected]:15] GotoIf("Local/[email protected];2", "0?ndloopbegin") in new stack
[2016-05-16 12:06:02] VERBOSE[32345][C-000095e9] pbx.c: Executing [[email protected]:16] Macro("Local/[email protected];2", "dial-ringall-predial-hook,") in new stack
[2016-05-16 12:06:02] VERBOSE[32345][C-000095e9] pbx.c: Executing [[email protected]:1] MacroExit("Local/[email protected];2", "") in new stack
[2016-05-16 12:06:02] VERBOSE[32345][C-000095e9] pbx.c: Executing [[email protected]:17] Dial("Local/[email protected];2", "SIP/1100106,27,TtrM(auto-blkvm)Ib(func-apply-sipheaders^s^1),") in new stack
[2016-05-16 12:06:02] VERBOSE[32345][C-000095e9] netsock2.c: Using SIP RTP TOS bits 184
[2016-05-16 12:06:02] VERBOSE[32345][C-000095e9] netsock2.c: Using SIP RTP CoS mark 5
[2016-05-16 12:06:02] VERBOSE[32345][C-000095e9] app_stack.c: SIP/1100106-000094d0 Internal Gosub(func-apply-sipheaders,s,1) start
[2016-05-16 12:06:02] VERBOSE[32345][C-000095e9] pbx.c: Executing [[email protected]:1] NoOp("SIP/1100106-000094d0", "Applying SIP Headers to channel") in new stack
[2016-05-16 12:06:02] VERBOSE[32345][C-000095e9] pbx.c: Executing [[email protected]:2] Set("SIP/1100106-000094d0", "SIPHEADERKEYS=") in new stack
[2016-05-16 12:06:02] VERBOSE[32345][C-000095e9] pbx.c: Executing [[email protected]:3] While("SIP/1100106-000094d0", "0") in new stack
[2016-05-16 12:06:02] VERBOSE[32345][C-000095e9] app_while.c: Jumping to priority 7
[2016-05-16 12:06:02] VERBOSE[32345][C-000095e9] pbx.c: Executing [[email protected]:8] Return("SIP/1100106-000094d0", "") in new stack
[2016-05-16 12:06:02] VERBOSE[32345][C-000095e9] app_stack.c: Spawn extension (from-internal, 1100106, 1) exited non-zero on 'SIP/1100106-000094d0'
[2016-05-16 12:06:02] VERBOSE[32345][C-000095e9] app_stack.c: SIP/1100106-000094d0 Internal Gosub(func-apply-sipheaders,s,1) complete GOSUB_RETVAL=
[2016-05-16 12:06:02] VERBOSE[32345][C-000095e9] app_dial.c: Called SIP/1100106
[2016-05-16 12:06:02] VERBOSE[32345][C-000095e9] app_dial.c: Connected line update to Local/[email protected];2 prevented.
[2016-05-16 12:06:02] VERBOSE[32343][C-000095e9] app_queue.c: Local/[email protected];1 is ringing
[2016-05-16 12:06:03] VERBOSE[32345][C-000095e9] app_dial.c: SIP/1100106-000094d0 is ringing
[2016-05-16 12:06:03] VERBOSE[32343][C-000095e9] app_queue.c: Local/[email protected];1 is ringing
[2016-05-16 12:06:05] VERBOSE[32345][C-000095e9] app_dial.c: Connected line update to Local/[email protected];2 prevented.
[2016-05-16 12:06:05] VERBOSE[32345][C-000095e9] app_dial.c: SIP/1100106-000094d0 answered Local/[email protected];2
[2016-05-16 12:06:05] VERBOSE[32345][C-000095e9] pbx.c: Executing [[email protected]:1] Set("SIP/1100106-000094d0", "__MACRO_RESULT=") in new stack
[2016-05-16 12:06:05] VERBOSE[32345][C-000095e9] pbx.c: Executing [[email protected]:2] Set("SIP/1100106-000094d0", "CFIGNORE=") in new stack
[2016-05-16 12:06:05] VERBOSE[32345][C-000095e9] pbx.c: Executing [[email protected]:3] Set("SIP/1100106-000094d0", "MASTER_CHANNEL(CFIGNORE)=") in new stack
[2016-05-16 12:06:05] VERBOSE[32345][C-000095e9] pbx.c: Executing [[email protected]:4] Set("SIP/1100106-000094d0", "FORWARD_CONTEXT=from-internal") in new stack
[2016-05-16 12:06:05] VERBOSE[32345][C-000095e9] pbx.c: Executing [[email protected]:5] Set("SIP/1100106-000094d0", "MASTER_CHANNEL(FORWARD_CONTEXT)=from-internal") in new stack
[2016-05-16 12:06:05] VERBOSE[32345][C-000095e9] pbx.c: Executing [[email protected]:6] Macro("SIP/1100106-000094d0", "blkvm-clr,") in new stack
[2016-05-16 12:06:05] VERBOSE[32345][C-000095e9] pbx.c: Executing [[email protected]:1] Set("SIP/1100106-000094d0", "SHARED(BLKVM,SIP/1100101-000094cf)=") in new stack
[2016-05-16 12:06:05] VERBOSE[32345][C-000095e9] pbx.c: Executing [[email protected]:2] Set("SIP/1100106-000094d0", "GOSUB_RETVAL=") in new stack
[2016-05-16 12:06:05] VERBOSE[32345][C-000095e9] pbx.c: Executing [[email protected]:3] MacroExit("SIP/1100106-000094d0", "") in new stack
[2016-05-16 12:06:05] VERBOSE[32345][C-000095e9] pbx.c: Executing [[email protected]:7] ExecIf("SIP/1100106-000094d0", "0?Set(MASTER_CHANNEL(CONNECTEDLINE(num))=1100106)") in new stack
[2016-05-16 12:06:05] VERBOSE[32345][C-000095e9] pbx.c: Executing [[email protected]:8] ExecIf("SIP/1100106-000094d0", "0?Set(MASTER_CHANNEL(CONNECTEDLINE(name))=1100106)") in new stack
[2016-05-16 12:06:05] VERBOSE[32357][C-000095e9] bridge_channel.c: Channel SIP/1100106-000094d0 joined 'simple_bridge' basic-bridge <f4163f60-93bc-4793-8448-29e8ce7285a4>
[2016-05-16 12:06:05] VERBOSE[32345][C-000095e9] bridge_channel.c: Channel Local/[email protected];2 joined 'simple_bridge' basic-bridge <f4163f60-93bc-4793-8448-29e8ce7285a4>
[2016-05-16 12:06:05] VERBOSE[32343][C-000095e9] app_queue.c: Local/[email protected];1 answered SIP/1100101-000094cf
[2016-05-16 12:06:05] VERBOSE[32343][C-000095e9] res_musiconhold.c: Stopped music on hold on SIP/1100101-000094cf
[2016-05-16 12:06:05] VERBOSE[32358][C-000095e9] bridge_channel.c: Channel Local/[email protected];1 joined 'simple_bridge' basic-bridge <3d7706ae-2c04-4b40-92b7-ff564b270fdc>
[2016-05-16 12:06:05] VERBOSE[32343][C-000095e9] bridge_channel.c: Channel SIP/1100101-000094cf joined 'simple_bridge' basic-bridge <3d7706ae-2c04-4b40-92b7-ff564b270fdc>
[2016-05-16 12:06:08] VERBOSE[32357][C-000095e9] bridge_channel.c: Channel SIP/1100106-000094d0 left 'simple_bridge' basic-bridge <f4163f60-93bc-4793-8448-29e8ce7285a4>
[2016-05-16 12:06:08] VERBOSE[32345][C-000095e9] bridge_channel.c: Channel Local/[email protected];2 left 'simple_bridge' basic-bridge <f4163f60-93bc-4793-8448-29e8ce7285a4>

Core 13.0.84
Queues 13.0.23
FreePBX Framework 13.0.119

Thanks in advance.

This is my guess. It doesn’t look like you have a valid file name in your announcement, maybe?

Thanks, but it’s not it.
The file is in the corret place: /var/lib/asterisk/sounds/pt_BR/custom/cliente_consorcio.* and it has the right privileges (asterisk).

Any other sugestion?

Is that the full file name? Not *.wav or *.something?

To check the content of the file, try setting up an extension that just plays the announcement. I’d also check to see if there needs to be an extension on the file. I don’t know how Asterisk will react to a file that doesn’t have an extension for context?

Asterisk explicitly requires that you do NOT use an extension, the base filename will be examined and a file that can best be used/transcoded with minimal cost will be used.

The way asterisk looks for sound files is to start in /var/lib/asterisk/sounds/language/*

language in your case needs to be pt_BR and needs to be set on the channel with SetLaanguage() , if a usable file cannot be found there it will look in /var/lib/asterisk/sounds/*

usauable means that is encoded correctly and can be played by the current codec in use , if you are using for example g729 then without enough licenses then .wav won’t be usable, if you are sure of the file put it in /var/lib/asterisk/sounds/custom/ as well, just in case your channel is not pt_BR

Thank you both.
In fact that was the problem, one of the channels was not set to pt_BR.
Oddly the Asterisk doesn’t show a message like “file not found” or whatever.

I’ve copied the files to en/custom, just int case, but fixed the channel to be pt_BR.

Thank’s again

Glad you got it working but ideally that should be just /var/lib/asterisk/custom/ to be failsafe :wink:

Depends on FreePBX version. FreePBX 12 has custom sound files in:

/var/lib/asterisk/sounds/custom

FreePBX 13 has custom files in

/var/lib/asterisk/sounds/<lang_code>/custom

But both ultimately go to the lowest common directory in ${astvardatadir}/* as defined in the ./asterisk.conf file which is wherever you set it up on install, unless specific then the “language” directory generally defaulting to the language set in the channel which by default is of course “en” failing that in ${astvardatadir}/ , just add ${astvardatadir}/custom to always pin one, all work, try it :slight_smile: