Agent announcement not being played

stuartalt · May 16, 2016, 3:44pm

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 [1199777@from-internal: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 [1199777@from-internal: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 [1199777@from-internal:3] Macro("SIP/1100101-000094cf", "blkvm-set,reset") in new stack
[2016-05-16 12:06:02] VERBOSE[32343][C-000095e9] pbx.c: Executing [s@macro-blkvm-set: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 [s@macro-blkvm-set: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 [s@macro-blkvm-set:3] Set("SIP/1100101-000094cf", "GOSUB_RETVAL=TRUE") in new stack
[2016-05-16 12:06:02] VERBOSE[32343][C-000095e9] pbx.c: Executing [s@macro-blkvm-set:4] MacroExit("SIP/1100101-000094cf", "") in new stack
[2016-05-16 12:06:02] VERBOSE[32343][C-000095e9] pbx.c: Executing [1199777@from-internal: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 [1199777@from-internal:5] Set("SIP/1100101-000094cf", "__NODEST=1199777") in new stack
[2016-05-16 12:06:02] VERBOSE[32343][C-000095e9] pbx.c: Executing [1199777@from-internal:6] Set("SIP/1100101-000094cf", "QCIDPP=") in new stack
[2016-05-16 12:06:02] VERBOSE[32343][C-000095e9] pbx.c: Executing [1199777@from-internal:7] Set("SIP/1100101-000094cf", "VQ_CIDPP=") in new stack
[2016-05-16 12:06:02] VERBOSE[32343][C-000095e9] pbx.c: Executing [1199777@from-internal: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 [1199777@from-internal:9] Set("SIP/1100101-000094cf", "QAINFO=") in new stack
[2016-05-16 12:06:02] VERBOSE[32343][C-000095e9] pbx.c: Executing [1199777@from-internal:10] Set("SIP/1100101-000094cf", "VQ_AINFO=") in new stack
[2016-05-16 12:06:02] VERBOSE[32343][C-000095e9] pbx.c: Executing [1199777@from-internal: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 [1199777@from-internal:12] Set("SIP/1100101-000094cf", "QJOINMSG=") in new stack
[2016-05-16 12:06:02] VERBOSE[32343][C-000095e9] pbx.c: Executing [1199777@from-internal:13] Set("SIP/1100101-000094cf", "VQ_JOINMSG=") in new stack
[2016-05-16 12:06:02] VERBOSE[32343][C-000095e9] pbx.c: Executing [1199777@from-internal:14] Set("SIP/1100101-000094cf", "QRETRY=") in new stack
[2016-05-16 12:06:02] VERBOSE[32343][C-000095e9] pbx.c: Executing [1199777@from-internal:15] Set("SIP/1100101-000094cf", "VQ_RETRY=") in new stack
[2016-05-16 12:06:02] VERBOSE[32343][C-000095e9] pbx.c: Executing [1199777@from-internal:16] Set("SIP/1100101-000094cf", "QOPTIONS=t") in new stack
[2016-05-16 12:06:02] VERBOSE[32343][C-000095e9] pbx.c: Executing [1199777@from-internal:17] Set("SIP/1100101-000094cf", "VQ_OPTIONS=") in new stack
[2016-05-16 12:06:02] VERBOSE[32343][C-000095e9] pbx.c: Executing [1199777@from-internal:18] Set("SIP/1100101-000094cf", "QGOSUB=") in new stack
[2016-05-16 12:06:02] VERBOSE[32343][C-000095e9] pbx.c: Executing [1199777@from-internal:19] Set("SIP/1100101-000094cf", "VQ_GOSUB=") in new stack
[2016-05-16 12:06:02] VERBOSE[32343][C-000095e9] pbx.c: Executing [1199777@from-internal:20] Set("SIP/1100101-000094cf", "QAGI=") in new stack
[2016-05-16 12:06:02] VERBOSE[32343][C-000095e9] pbx.c: Executing [1199777@from-internal:21] Set("SIP/1100101-000094cf", "VQ_AGI=") in new stack
[2016-05-16 12:06:02] VERBOSE[32343][C-000095e9] pbx.c: Executing [1199777@from-internal:22] Set("SIP/1100101-000094cf", "QRULE=") in new stack
[2016-05-16 12:06:02] VERBOSE[32343][C-000095e9] pbx.c: Executing [1199777@from-internal:23] Set("SIP/1100101-000094cf", "VQ_RULE=") in new stack
[2016-05-16 12:06:02] VERBOSE[32343][C-000095e9] pbx.c: Executing [1199777@from-internal:24] Set("SIP/1100101-000094cf", "QPOSITION=") in new stack
[2016-05-16 12:06:02] VERBOSE[32343][C-000095e9] pbx.c: Executing [1199777@from-internal:25] Set("SIP/1100101-000094cf", "VQ_POSITION=") in new stack
[2016-05-16 12:06:02] VERBOSE[32343][C-000095e9] pbx.c: Executing [1199777@from-internal: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 [s@sub-record-check:1] GotoIf("SIP/1100101-000094cf", "0?initialized") in new stack
[2016-05-16 12:06:02] VERBOSE[32343][C-000095e9] pbx.c: Executing [s@sub-record-check:2] Set("SIP/1100101-000094cf", "__REC_STATUS=INITIALIZED") in new stack
[2016-05-16 12:06:02] VERBOSE[32343][C-000095e9] pbx.c: Executing [s@sub-record-check:3] Set("SIP/1100101-000094cf", "NOW=1463411162") in new stack
[2016-05-16 12:06:02] VERBOSE[32343][C-000095e9] pbx.c: Executing [s@sub-record-check:4] Set("SIP/1100101-000094cf", "__DAY=16") in new stack
[2016-05-16 12:06:02] VERBOSE[32343][C-000095e9] pbx.c: Executing [s@sub-record-check:5] Set("SIP/1100101-000094cf", "__MONTH=05") in new stack
[2016-05-16 12:06:02] VERBOSE[32343][C-000095e9] pbx.c: Executing [s@sub-record-check:6] Set("SIP/1100101-000094cf", "__YEAR=2016") in new stack
[2016-05-16 12:06:02] VERBOSE[32343][C-000095e9] pbx.c: Executing [s@sub-record-check:7] Set("SIP/1100101-000094cf", "__TIMESTR=20160516-120602") in new stack
[2016-05-16 12:06:02] VERBOSE[32343][C-000095e9] pbx.c: Executing [s@sub-record-check:8] Set("SIP/1100101-000094cf", "__FROMEXTEN=unknown") in new stack
[2016-05-16 12:06:02] VERBOSE[32343][C-000095e9] pbx.c: Executing [s@sub-record-check:9] Set("SIP/1100101-000094cf", "__MON_FMT=WAV") in new stack
[2016-05-16 12:06:02] VERBOSE[32343][C-000095e9] pbx.c: Executing [s@sub-record-check:10] NoOp("SIP/1100101-000094cf", "Recordings initialized") in new stack
[2016-05-16 12:06:02] VERBOSE[32343][C-000095e9] pbx.c: Executing [s@sub-record-check: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 [s@sub-record-check: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 [s@sub-record-check: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 [s@sub-record-check: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 [s@sub-record-check: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 [s@sub-record-check: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 [s@sub-record-check: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 [recordcheck@sub-record-check: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 [recordcheck@sub-record-check: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 [recordcheck@sub-record-check: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 [recordcheck@sub-record-check:13] Return("SIP/1100101-000094cf", "") in new stack
[2016-05-16 12:06:02] VERBOSE[32343][C-000095e9] pbx.c: Executing [s@sub-record-check:20] Return("SIP/1100101-000094cf", "") in new stack
[2016-05-16 12:06:02] VERBOSE[32343][C-000095e9] pbx.c: Executing [1199777@from-internal:27] Set("SIP/1100101-000094cf", "__CWIGNORE=TRUE") in new stack
[2016-05-16 12:06:02] VERBOSE[32343][C-000095e9] pbx.c: Executing [1199777@from-internal:28] ExecIf("SIP/1100101-000094cf", "0?Playback(, )") in new stack
[2016-05-16 12:06:02] VERBOSE[32343][C-000095e9] pbx.c: Executing [1199777@from-internal: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 [1199777@from-internal: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 [1199777@from-internal:31] Set("SIP/1100101-000094cf", "VQ_AANNOUNCE=") in new stack
[2016-05-16 12:06:02] VERBOSE[32343][C-000095e9] pbx.c: Executing [1199777@from-internal:32] Set("SIP/1100101-000094cf", "QMOH=") in new stack
[2016-05-16 12:06:02] VERBOSE[32343][C-000095e9] pbx.c: Executing [1199777@from-internal:33] Set("SIP/1100101-000094cf", "VQ_MOH=") in new stack
[2016-05-16 12:06:02] VERBOSE[32343][C-000095e9] pbx.c: Executing [1199777@from-internal:34] ExecIf("SIP/1100101-000094cf", "0?Set(__MOHCLASS=)") in new stack
[2016-05-16 12:06:02] VERBOSE[32343][C-000095e9] pbx.c: Executing [1199777@from-internal: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 [1199777@from-internal:36] Set("SIP/1100101-000094cf", "QMAXWAIT=") in new stack
[2016-05-16 12:06:02] VERBOSE[32343][C-000095e9] pbx.c: Executing [1199777@from-internal:37] Set("SIP/1100101-000094cf", "VQ_MAXWAIT=") in new stack
[2016-05-16 12:06:02] VERBOSE[32343][C-000095e9] pbx.c: Executing [1199777@from-internal:38] Set("SIP/1100101-000094cf", "QUEUENUM=1199777") in new stack
[2016-05-16 12:06:02] VERBOSE[32343][C-000095e9] pbx.c: Executing [1199777@from-internal:39] Set("SIP/1100101-000094cf", "QUEUEJOINTIME=1463411162") in new stack
[2016-05-16 12:06:02] VERBOSE[32343][C-000095e9] pbx.c: Executing [1199777@from-internal: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/1100106@from-queue/n
[2016-05-16 12:06:02] VERBOSE[32345][C-000095e9] pbx.c: Executing [1100106@from-queue:1] Set("Local/1100106@from-queue-00000817;2", "QAGENT=1100106") in new stack
[2016-05-16 12:06:02] VERBOSE[32345][C-000095e9] pbx.c: Executing [1100106@from-queue:2] Goto("Local/1100106@from-queue-00000817;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 [1199777@from-queue:1] Goto("Local/1100106@from-queue-00000817;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 [1100106@from-internal:1] Set("Local/1100106@from-queue-00000817;2", "CDR(no_sentido)=internal") in new stack
[2016-05-16 12:06:02] VERBOSE[32345][C-000095e9] pbx.c: Executing [1100106@from-internal:2] ExecIf("Local/1100106@from-queue-00000817;2", "0?Set(CDR(no_sentido)=transferencia)") in new stack
[2016-05-16 12:06:02] VERBOSE[32345][C-000095e9] pbx.c: Executing [1100106@from-internal:3] Set("Local/1100106@from-queue-00000817;2", "DIAL_OPTIONS=TtrM(auto-blkvm)I") in new stack
[2016-05-16 12:06:02] VERBOSE[32345][C-000095e9] pbx.c: Executing [1100106@from-internal:4] Set("Local/1100106@from-queue-00000817;2", "CONNECTEDLINE(num,i)=1100106") in new stack
[2016-05-16 12:06:02] VERBOSE[32345][C-000095e9] pbx.c: Executing [1100106@from-internal:5] Gosub("Local/1100106@from-queue-00000817;2", "sub-presencestate-display,s,1(1100106)") in new stack
[2016-05-16 12:06:02] VERBOSE[32345][C-000095e9] pbx.c: Executing [s@sub-presencestate-display:1] Goto("Local/1100106@from-queue-00000817;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 [state-not_set@sub-presencestate-display:1] Set("Local/1100106@from-queue-00000817;2", "PRESENCESTATE_DISPLAY=") in new stack
[2016-05-16 12:06:02] VERBOSE[32345][C-000095e9] pbx.c: Executing [state-not_set@sub-presencestate-display:2] Return("Local/1100106@from-queue-00000817;2", "") in new stack
[2016-05-16 12:06:02] VERBOSE[32345][C-000095e9] pbx.c: Executing [1100106@from-internal:6] Set("Local/1100106@from-queue-00000817;2", "CONNECTEDLINE(name)=1100106") in new stack
[2016-05-16 12:06:02] VERBOSE[32345][C-000095e9] pbx.c: Executing [1100106@from-internal:7] Set("Local/1100106@from-queue-00000817;2", "FM_DIALSTATUS=NOT_INUSE") in new stack
[2016-05-16 12:06:02] VERBOSE[32345][C-000095e9] pbx.c: Executing [1100106@from-internal:8] Set("Local/1100106@from-queue-00000817;2", "__EXTTOCALL=1100106") in new stack
[2016-05-16 12:06:02] VERBOSE[32345][C-000095e9] pbx.c: Executing [1100106@from-internal:9] Set("Local/1100106@from-queue-00000817;2", "__PICKUPMARK=1100106") in new stack
[2016-05-16 12:06:02] VERBOSE[32345][C-000095e9] pbx.c: Executing [1100106@from-internal:10] Macro("Local/1100106@from-queue-00000817;2", "blkvm-setifempty,") in new stack
[2016-05-16 12:06:02] VERBOSE[32343][C-000095e9] app_queue.c: Local/1100106@from-queue-00000817;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 [s@macro-blkvm-setifempty:1] GotoIf("Local/1100106@from-queue-00000817;2", "0?init") in new stack
[2016-05-16 12:06:02] VERBOSE[32345][C-000095e9] pbx.c: Executing [s@macro-blkvm-setifempty:2] Set("Local/1100106@from-queue-00000817;2", "GOSUB_RETVAL=TRUE") in new stack
[2016-05-16 12:06:02] VERBOSE[32345][C-000095e9] pbx.c: Executing [s@macro-blkvm-setifempty:3] MacroExit("Local/1100106@from-queue-00000817;2", "") in new stack
[2016-05-16 12:06:02] VERBOSE[32345][C-000095e9] pbx.c: Executing [1100106@from-internal:11] GotoIf("Local/1100106@from-queue-00000817;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 [1100106@from-internal:14] Set("Local/1100106@from-queue-00000817;2", "RRNODEST=1199777") in new stack
[2016-05-16 12:06:02] VERBOSE[32345][C-000095e9] pbx.c: Executing [1100106@from-internal:15] Set("Local/1100106@from-queue-00000817;2", "__NODEST=1100106") in new stack
[2016-05-16 12:06:02] VERBOSE[32345][C-000095e9] pbx.c: Executing [1100106@from-internal:16] GosubIf("Local/1100106@from-queue-00000817;2", "0?sub-fmsetcid,s,1()") in new stack
[2016-05-16 12:06:02] VERBOSE[32345][C-000095e9] pbx.c: Executing [1100106@from-internal:17] Set("Local/1100106@from-queue-00000817;2", "RecordMethod=Group") in new stack
[2016-05-16 12:06:02] VERBOSE[32345][C-000095e9] pbx.c: Executing [1100106@from-internal:18] Gosub("Local/1100106@from-queue-00000817;2", "sub-record-check,s,1(exten,1100106,)") in new stack
[2016-05-16 12:06:02] VERBOSE[32345][C-000095e9] pbx.c: Executing [s@sub-record-check:1] GotoIf("Local/1100106@from-queue-00000817;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 [s@sub-record-check:10] NoOp("Local/1100106@from-queue-00000817;2", "Recordings initialized") in new stack
[2016-05-16 12:06:02] VERBOSE[32345][C-000095e9] pbx.c: Executing [s@sub-record-check:11] ExecIf("Local/1100106@from-queue-00000817;2", "1?Set(ARG3=dontcare)") in new stack
[2016-05-16 12:06:02] VERBOSE[32345][C-000095e9] pbx.c: Executing [s@sub-record-check:12] Set("Local/1100106@from-queue-00000817;2", "REC_POLICY_MODE_SAVE=NO") in new stack
[2016-05-16 12:06:02] VERBOSE[32345][C-000095e9] pbx.c: Executing [s@sub-record-check:13] ExecIf("Local/1100106@from-queue-00000817;2", "0?Set(REC_STATUS=NO)") in new stack
[2016-05-16 12:06:02] VERBOSE[32345][C-000095e9] pbx.c: Executing [s@sub-record-check:14] GotoIf("Local/1100106@from-queue-00000817;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 [s@sub-record-check:17] GotoIf("Local/1100106@from-queue-00000817;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 [exten@sub-record-check:1] NoOp("Local/1100106@from-queue-00000817;2", "Exten Recording Check between unknown and 1100106") in new stack
[2016-05-16 12:06:02] VERBOSE[32345][C-000095e9] pbx.c: Executing [exten@sub-record-check:2] Set("Local/1100106@from-queue-00000817;2", "CALLTYPE=internal") in new stack
[2016-05-16 12:06:02] VERBOSE[32345][C-000095e9] pbx.c: Executing [exten@sub-record-check:3] ExecIf("Local/1100106@from-queue-00000817;2", "0?Set(CALLTYPE=)") in new stack
[2016-05-16 12:06:02] VERBOSE[32345][C-000095e9] pbx.c: Executing [exten@sub-record-check:4] Set("Local/1100106@from-queue-00000817;2", "CALLEE=never") in new stack
[2016-05-16 12:06:02] VERBOSE[32345][C-000095e9] pbx.c: Executing [exten@sub-record-check:5] ExecIf("Local/1100106@from-queue-00000817;2", "0?Set(CALLEE=dontcare)") in new stack
[2016-05-16 12:06:02] VERBOSE[32345][C-000095e9] pbx.c: Executing [exten@sub-record-check:6] GotoIf("Local/1100106@from-queue-00000817;2", "0?callee") in new stack
[2016-05-16 12:06:02] VERBOSE[32345][C-000095e9] pbx.c: Executing [exten@sub-record-check:7] GotoIf("Local/1100106@from-queue-00000817;2", "0?caller") in new stack
[2016-05-16 12:06:02] VERBOSE[32345][C-000095e9] pbx.c: Executing [exten@sub-record-check:8] ExecIf("Local/1100106@from-queue-00000817;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 [exten@sub-record-check:9] ExecIf("Local/1100106@from-queue-00000817;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 [exten@sub-record-check:10] GotoIf("Local/1100106@from-queue-00000817;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 [exten@sub-record-check:11] Gosub("Local/1100106@from-queue-00000817;2", "recordcheck,1(never,internal,1100106)") in new stack
[2016-05-16 12:06:02] VERBOSE[32345][C-000095e9] pbx.c: Executing [recordcheck@sub-record-check:1] NoOp("Local/1100106@from-queue-00000817;2", "Starting recording check against never") in new stack
[2016-05-16 12:06:02] VERBOSE[32345][C-000095e9] pbx.c: Executing [recordcheck@sub-record-check:2] Goto("Local/1100106@from-queue-00000817;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 [recordcheck@sub-record-check:14] Set("Local/1100106@from-queue-00000817;2", "__REC_POLICY_MODE=NEVER") in new stack
[2016-05-16 12:06:02] VERBOSE[32345][C-000095e9] pbx.c: Executing [recordcheck@sub-record-check:15] Goto("Local/1100106@from-queue-00000817;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 [recordcheck@sub-record-check:25] NoOp("Local/1100106@from-queue-00000817;2", "Stopping recording: internal, 1100106") in new stack
[2016-05-16 12:06:02] VERBOSE[32345][C-000095e9] pbx.c: Executing [recordcheck@sub-record-check:26] Set("Local/1100106@from-queue-00000817;2", "__REC_STATUS=STOPPED") in new stack
[2016-05-16 12:06:02] VERBOSE[32345][C-000095e9] pbx.c: Executing [recordcheck@sub-record-check:27] System("Local/1100106@from-queue-00000817;2", "/var/lib/asterisk/bin/stoprecording.php "Local/1100106@from-queue-00000817;2"") in new stack
[2016-05-16 12:06:02] VERBOSE[32345][C-000095e9] pbx.c: Executing [recordcheck@sub-record-check:28] Return("Local/1100106@from-queue-00000817;2", "") in new stack
[2016-05-16 12:06:02] VERBOSE[32345][C-000095e9] pbx.c: Executing [exten@sub-record-check:12] Return("Local/1100106@from-queue-00000817;2", "") in new stack
[2016-05-16 12:06:02] VERBOSE[32345][C-000095e9] pbx.c: Executing [1100106@from-internal:19] Set("Local/1100106@from-queue-00000817;2", "RingGroupMethod=ringallv2-prim") in new stack
[2016-05-16 12:06:02] VERBOSE[32345][C-000095e9] pbx.c: Executing [1100106@from-internal:20] Set("Local/1100106@from-queue-00000817;2", "_FMGRP=1100106") in new stack
[2016-05-16 12:06:02] VERBOSE[32345][C-000095e9] pbx.c: Executing [1100106@from-internal:21] GotoIf("Local/1100106@from-queue-00000817;2", "0?doconfirm") in new stack
[2016-05-16 12:06:02] VERBOSE[32345][C-000095e9] pbx.c: Executing [1100106@from-internal:22] Macro("Local/1100106@from-queue-00000817;2", "dial,27,TtrM(auto-blkvm)I,1100106") in new stack
[2016-05-16 12:06:02] VERBOSE[32345][C-000095e9] pbx.c: Executing [s@macro-dial:1] NoOp("Local/1100106@from-queue-00000817;2", "Blind Transfer: , Attended Transfer: , User: , Alert Info: ") in new stack
[2016-05-16 12:06:02] VERBOSE[32345][C-000095e9] pbx.c: Executing [s@macro-dial:2] ExecIf("Local/1100106@from-queue-00000817;2", "0?Set(ALERT_INFO=)") in new stack
[2016-05-16 12:06:02] VERBOSE[32345][C-000095e9] pbx.c: Executing [s@macro-dial:3] ExecIf("Local/1100106@from-queue-00000817;2", "0?Set(ALERT_INFO=)") in new stack
[2016-05-16 12:06:02] VERBOSE[32345][C-000095e9] pbx.c: Executing [s@macro-dial:4] ExecIf("Local/1100106@from-queue-00000817;2", "0?Set(ALERT_INFO=)") in new stack
[2016-05-16 12:06:02] VERBOSE[32345][C-000095e9] pbx.c: Executing [s@macro-dial:5] ExecIf("Local/1100106@from-queue-00000817;2", "0?Set(CHANNEL(musicclass)=)") in new stack
[2016-05-16 12:06:02] VERBOSE[32345][C-000095e9] pbx.c: Executing [s@macro-dial:6] AGI("Local/1100106@from-queue-00000817;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/1100106@from-queue-00000817;2>AGI Script dialparties.agi completed, returning 0
[2016-05-16 12:06:02] VERBOSE[32345][C-000095e9] pbx.c: Executing [s@macro-dial:9] NoOp("Local/1100106@from-queue-00000817;2", "Returned from dialparties with groups to dial") in new stack
[2016-05-16 12:06:02] VERBOSE[32345][C-000095e9] pbx.c: Executing [s@macro-dial:10] Set("Local/1100106@from-queue-00000817;2", "LOOPCNT=1") in new stack
[2016-05-16 12:06:02] VERBOSE[32345][C-000095e9] pbx.c: Executing [s@macro-dial:11] Set("Local/1100106@from-queue-00000817;2", "ITER=1") in new stack
[2016-05-16 12:06:02] VERBOSE[32345][C-000095e9] pbx.c: Executing [s@macro-dial:12] Set("Local/1100106@from-queue-00000817;2", "EXTTOCALL=1100106") in new stack
[2016-05-16 12:06:02] VERBOSE[32345][C-000095e9] pbx.c: Executing [s@macro-dial:13] NoOp("Local/1100106@from-queue-00000817;2", "Working with 1100106") in new stack
[2016-05-16 12:06:02] VERBOSE[32345][C-000095e9] pbx.c: Executing [s@macro-dial:14] Set("Local/1100106@from-queue-00000817;2", "ITER=2") in new stack
[2016-05-16 12:06:02] VERBOSE[32345][C-000095e9] pbx.c: Executing [s@macro-dial:15] GotoIf("Local/1100106@from-queue-00000817;2", "0?ndloopbegin") in new stack
[2016-05-16 12:06:02] VERBOSE[32345][C-000095e9] pbx.c: Executing [s@macro-dial:16] Macro("Local/1100106@from-queue-00000817;2", "dial-ringall-predial-hook,") in new stack
[2016-05-16 12:06:02] VERBOSE[32345][C-000095e9] pbx.c: Executing [s@macro-dial-ringall-predial-hook:1] MacroExit("Local/1100106@from-queue-00000817;2", "") in new stack
[2016-05-16 12:06:02] VERBOSE[32345][C-000095e9] pbx.c: Executing [s@macro-dial:17] Dial("Local/1100106@from-queue-00000817;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 [s@func-apply-sipheaders: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 [s@func-apply-sipheaders:2] Set("SIP/1100106-000094d0", "SIPHEADERKEYS=") in new stack
[2016-05-16 12:06:02] VERBOSE[32345][C-000095e9] pbx.c: Executing [s@func-apply-sipheaders: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 [s@func-apply-sipheaders: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/1100106@from-queue-00000817;2 prevented.
[2016-05-16 12:06:02] VERBOSE[32343][C-000095e9] app_queue.c: Local/1100106@from-queue-00000817;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/1100106@from-queue-00000817;1 is ringing
[2016-05-16 12:06:05] VERBOSE[32345][C-000095e9] app_dial.c: Connected line update to Local/1100106@from-queue-00000817;2 prevented.
[2016-05-16 12:06:05] VERBOSE[32345][C-000095e9] app_dial.c: SIP/1100106-000094d0 answered Local/1100106@from-queue-00000817;2
[2016-05-16 12:06:05] VERBOSE[32345][C-000095e9] pbx.c: Executing [s@macro-auto-blkvm:1] Set("SIP/1100106-000094d0", "__MACRO_RESULT=") in new stack
[2016-05-16 12:06:05] VERBOSE[32345][C-000095e9] pbx.c: Executing [s@macro-auto-blkvm:2] Set("SIP/1100106-000094d0", "CFIGNORE=") in new stack
[2016-05-16 12:06:05] VERBOSE[32345][C-000095e9] pbx.c: Executing [s@macro-auto-blkvm:3] Set("SIP/1100106-000094d0", "MASTER_CHANNEL(CFIGNORE)=") in new stack
[2016-05-16 12:06:05] VERBOSE[32345][C-000095e9] pbx.c: Executing [s@macro-auto-blkvm: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 [s@macro-auto-blkvm: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 [s@macro-auto-blkvm:6] Macro("SIP/1100106-000094d0", "blkvm-clr,") in new stack
[2016-05-16 12:06:05] VERBOSE[32345][C-000095e9] pbx.c: Executing [s@macro-blkvm-clr: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 [s@macro-blkvm-clr:2] Set("SIP/1100106-000094d0", "GOSUB_RETVAL=") in new stack
[2016-05-16 12:06:05] VERBOSE[32345][C-000095e9] pbx.c: Executing [s@macro-blkvm-clr:3] MacroExit("SIP/1100106-000094d0", "") in new stack
[2016-05-16 12:06:05] VERBOSE[32345][C-000095e9] pbx.c: Executing [s@macro-auto-blkvm: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 [s@macro-auto-blkvm: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/1100106@from-queue-00000817;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/1100106@from-queue-00000817;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/1100106@from-queue-00000817;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/1100106@from-queue-00000817;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.

cynjut · May 16, 2016, 5:39pm

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

stuartalt · May 16, 2016, 5:56pm

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?

cynjut · May 16, 2016, 7:27pm

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?

dicko · May 16, 2016, 8:03pm

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.

dicko · May 16, 2016, 8:29pm

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

stuartalt · May 16, 2016, 10:03pm

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

dicko · May 16, 2016, 11:39pm

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

lgaetz · May 16, 2016, 11:49pm

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

dicko · May 16, 2016, 11:58pm

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