Hello
First i am sorry if my english is not good at all. I try to do my best and i hope you will understand my question.
I have switched to freepbx 13 and i have a problem with voicemail.
I have put a voicemail on extension 55.
When i configure inbound route to send directly to the voicemail, it works perfectly. So the voice mail itself is ok.
Ben when i configure inbound route to send to an extension ( 55 ) and on this extension, i configure the “optional destinations” to the same voicemail on no answer, it hangup instead of sending the caller to the voice mail.
Here is the logs.
Somone can help me please to understand what is wrong ?
Thank you
[2015-09-30 21:53:59] VERBOSE[2102][C-00010b0a] netsock2.c: == Using SIP RTP TOS bits 184
[2015-09-30 21:53:59] VERBOSE[2102][C-00010b0a] netsock2.c: == Using SIP RTP CoS mark 5
[2015-09-30 21:53:59] VERBOSE[1970][C-00010b0a] pbx.c: -- Executing [s@custom-get-did-ovh:1] Goto("SIP/ovh2-0000043c", "from-trunk,01xxxxxx,1") in new stack
[2015-09-30 21:53:59] VERBOSE[1970][C-00010b0a] pbx.c: -- Goto (from-trunk,01xxxxxx,1)
[2015-09-30 21:53:59] VERBOSE[1970][C-00010b0a] pbx.c: -- Executing [01xxxxxx@from-trunk:1] Set("SIP/ovh2-0000043c", "__FROM_DID=01xxxxxx") in new stack
[2015-09-30 21:53:59] VERBOSE[1970][C-00010b0a] pbx.c: -- Executing [01xxxxxx@from-trunk:2] Set("SIP/ovh2-0000043c", "CHANNEL(language)=fr") in new stack
[2015-09-30 21:53:59] VERBOSE[1970][C-00010b0a] pbx.c: -- Executing [01xxxxxx@from-trunk:3] Gosub("SIP/ovh2-0000043c", "sub-record-check,s,1(in,01xxxxxx,force)") in new stack
[2015-09-30 21:53:59] VERBOSE[1970][C-00010b0a] pbx.c: -- Executing [s@sub-record-check:1] GotoIf("SIP/ovh2-0000043c", "0?initialized") in new stack
[2015-09-30 21:53:59] VERBOSE[1970][C-00010b0a] pbx.c: -- Executing [s@sub-record-check:2] Set("SIP/ovh2-0000043c", "__REC_STATUS=INITIALIZED") in new stack
[2015-09-30 21:53:59] VERBOSE[1970][C-00010b0a] pbx.c: -- Executing [s@sub-record-check:3] Set("SIP/ovh2-0000043c", "NOW=1443642839") in new stack
[2015-09-30 21:53:59] VERBOSE[1970][C-00010b0a] pbx.c: -- Executing [s@sub-record-check:4] Set("SIP/ovh2-0000043c", "__DAY=30") in new stack
[2015-09-30 21:53:59] VERBOSE[1970][C-00010b0a] pbx.c: -- Executing [s@sub-record-check:5] Set("SIP/ovh2-0000043c", "__MONTH=09") in new stack
[2015-09-30 21:53:59] VERBOSE[1970][C-00010b0a] pbx.c: -- Executing [s@sub-record-check:6] Set("SIP/ovh2-0000043c", "__YEAR=2015") in new stack
[2015-09-30 21:53:59] VERBOSE[1970][C-00010b0a] pbx.c: -- Executing [s@sub-record-check:7] Set("SIP/ovh2-0000043c", "__TIMESTR=20150930-215359") in new stack
[2015-09-30 21:53:59] VERBOSE[1970][C-00010b0a] pbx.c: -- Executing [s@sub-record-check:8] Set("SIP/ovh2-0000043c", "__FROMEXTEN=unknown") in new stack
[2015-09-30 21:53:59] VERBOSE[1970][C-00010b0a] pbx.c: -- Executing [s@sub-record-check:9] Set("SIP/ovh2-0000043c", "__MON_FMT=wav") in new stack
[2015-09-30 21:53:59] VERBOSE[1970][C-00010b0a] pbx.c: -- Executing [s@sub-record-check:10] NoOp("SIP/ovh2-0000043c", "Recordings initialized") in new stack
[2015-09-30 21:53:59] VERBOSE[1970][C-00010b0a] pbx.c: -- Executing [s@sub-record-check:11] ExecIf("SIP/ovh2-0000043c", "0?Set(ARG3=dontcare)") in new stack
[2015-09-30 21:53:59] VERBOSE[1970][C-00010b0a] pbx.c: -- Executing [s@sub-record-check:12] Set("SIP/ovh2-0000043c", "REC_POLICY_MODE_SAVE=") in new stack
[2015-09-30 21:53:59] VERBOSE[1970][C-00010b0a] pbx.c: -- Executing [s@sub-record-check:13] ExecIf("SIP/ovh2-0000043c", "0?Set(REC_STATUS=NO)") in new stack
[2015-09-30 21:53:59] VERBOSE[1970][C-00010b0a] pbx.c: -- Executing [s@sub-record-check:14] GotoIf("SIP/ovh2-0000043c", "2?checkaction") in new stack
[2015-09-30 21:53:59] VERBOSE[1970][C-00010b0a] pbx.c: -- Goto (sub-record-check,s,17)
[2015-09-30 21:53:59] VERBOSE[1970][C-00010b0a] pbx.c: -- Executing [s@sub-record-check:17] GotoIf("SIP/ovh2-0000043c", "1?sub-record-check,in,1") in new stack
[2015-09-30 21:53:59] VERBOSE[1970][C-00010b0a] pbx.c: -- Goto (sub-record-check,in,1)
[2015-09-30 21:53:59] VERBOSE[1970][C-00010b0a] pbx.c: -- Executing [in@sub-record-check:1] NoOp("SIP/ovh2-0000043c", "Inbound Recording Check to 01xxxxxx") in new stack
[2015-09-30 21:53:59] VERBOSE[1970][C-00010b0a] pbx.c: -- Executing [in@sub-record-check:2] Set("SIP/ovh2-0000043c", "FROMEXTEN=unknown") in new stack
[2015-09-30 21:53:59] VERBOSE[1970][C-00010b0a] pbx.c: -- Executing [in@sub-record-check:3] ExecIf("SIP/ovh2-0000043c", "10?Set(FROMEXTEN=06xxxxxxxx)") in new stack
[2015-09-30 21:53:59] VERBOSE[1970][C-00010b0a] pbx.c: -- Executing [in@sub-record-check:4] Gosub("SIP/ovh2-0000043c", "recordcheck,1(force,in,01xxxxxx)") in new stack
[2015-09-30 21:53:59] VERBOSE[1970][C-00010b0a] pbx.c: -- Executing [recordcheck@sub-record-check:1] NoOp("SIP/ovh2-0000043c", "Starting recording check against force") in new stack
[2015-09-30 21:53:59] VERBOSE[1970][C-00010b0a] pbx.c: -- Executing [recordcheck@sub-record-check:2] Goto("SIP/ovh2-0000043c", "force") in new stack
[2015-09-30 21:53:59] VERBOSE[1970][C-00010b0a] pbx.c: -- Goto (sub-record-check,recordcheck,5)
[2015-09-30 21:53:59] VERBOSE[1970][C-00010b0a] pbx.c: -- Executing [recordcheck@sub-record-check:5] Set("SIP/ovh2-0000043c", "__REC_POLICY_MODE=FORCE") in new stack
[2015-09-30 21:53:59] VERBOSE[1970][C-00010b0a] pbx.c: -- Executing [recordcheck@sub-record-check:6] GotoIf("SIP/ovh2-0000043c", "1?startrec") in new stack
[2015-09-30 21:53:59] VERBOSE[1970][C-00010b0a] pbx.c: -- Goto (sub-record-check,recordcheck,16)
[2015-09-30 21:53:59] VERBOSE[1970][C-00010b0a] pbx.c: -- Executing [recordcheck@sub-record-check:16] NoOp("SIP/ovh2-0000043c", "Starting recording: in, 01xxxxxx") in new stack
[2015-09-30 21:53:59] VERBOSE[1970][C-00010b0a] pbx.c: -- Executing [recordcheck@sub-record-check:17] Set("SIP/ovh2-0000043c", "AUDIOHOOK_INHERIT(MixMonitor)=yes") in new stack
[2015-09-30 21:53:59] VERBOSE[1970][C-00010b0a] pbx.c: -- Executing [recordcheck@sub-record-check:18] Set("SIP/ovh2-0000043c", "__CALLFILENAME=in-01xxxxxx-06xxxxxxxx-20150930-215359-1443642839.527960") in new stack
[2015-09-30 21:53:59] VERBOSE[1970][C-00010b0a] pbx.c: -- Executing [recordcheck@sub-record-check:19] MixMonitor("SIP/ovh2-0000043c", "2015/09/30/in-01xxxxxx-06xxxxxxxx-20150930-215359-1443642839.527960.wav,ai(LOCAL_MIXMON_ID),") in new stack
[2015-09-30 21:53:59] VERBOSE[1972][C-00010b0a] app_mixmonitor.c: == Begin MixMonitor Recording SIP/ovh2-0000043c
[2015-09-30 21:53:59] VERBOSE[1970][C-00010b0a] pbx.c: -- Executing [recordcheck@sub-record-check:20] Set("SIP/ovh2-0000043c", "__MIXMON_ID=0x7f5cf4003a50") in new stack
[2015-09-30 21:53:59] VERBOSE[1970][C-00010b0a] pbx.c: -- Executing [recordcheck@sub-record-check:21] Set("SIP/ovh2-0000043c", "__RECORD_ID=SIP/ovh2-0000043c") in new stack
[2015-09-30 21:53:59] VERBOSE[1970][C-00010b0a] pbx.c: -- Executing [recordcheck@sub-record-check:22] Set("SIP/ovh2-0000043c", "__REC_STATUS=RECORDING") in new stack
[2015-09-30 21:53:59] VERBOSE[1970][C-00010b0a] pbx.c: -- Executing [recordcheck@sub-record-check:23] Set("SIP/ovh2-0000043c", "CDR(recordingfile)=in-01xxxxxx-06xxxxxxxx-20150930-215359-1443642839.527960.wav") in new stack
[2015-09-30 21:53:59] VERBOSE[1970][C-00010b0a] pbx.c: -- Executing [recordcheck@sub-record-check:24] Return("SIP/ovh2-0000043c", "") in new stack
[2015-09-30 21:53:59] VERBOSE[1970][C-00010b0a] pbx.c: -- Executing [in@sub-record-check:5] Return("SIP/ovh2-0000043c", "") in new stack
[2015-09-30 21:53:59] VERBOSE[1970][C-00010b0a] pbx.c: -- Executing [01xxxxxx@from-trunk:4] Gosub("SIP/ovh2-0000043c", "app-blacklist-check,s,1()") in new stack
[2015-09-30 21:53:59] VERBOSE[1970][C-00010b0a] pbx.c: -- Executing [s@app-blacklist-check:1] GotoIf("SIP/ovh2-0000043c", "0?blacklisted") in new stack
[2015-09-30 21:53:59] VERBOSE[1970][C-00010b0a] pbx.c: -- Executing [s@app-blacklist-check:2] Set("SIP/ovh2-0000043c", "CALLED_BLACKLIST=1") in new stack
[2015-09-30 21:53:59] VERBOSE[1970][C-00010b0a] pbx.c: -- Executing [s@app-blacklist-check:3] Return("SIP/ovh2-0000043c", "") in new stack
[2015-09-30 21:53:59] VERBOSE[1970][C-00010b0a] pbx.c: -- Executing [01xxxxxx@from-trunk:5] Set("SIP/ovh2-0000043c", "CDR(did)=01xxxxxx") in new stack
[2015-09-30 21:53:59] VERBOSE[1970][C-00010b0a] pbx.c: -- Executing [01xxxxxx@from-trunk:6] ExecIf("SIP/ovh2-0000043c", "0 ?Set(CALLERID(name)=06xxxxxxxx)") in new stack
[2015-09-30 21:53:59] VERBOSE[1970][C-00010b0a] pbx.c: -- Executing [01xxxxxx@from-trunk:7] Set("SIP/ovh2-0000043c", "CHANNEL(musicclass)=default") in new stack
[2015-09-30 21:53:59] VERBOSE[1970][C-00010b0a] pbx.c: -- Executing [01xxxxxx@from-trunk:8] Set("SIP/ovh2-0000043c", "__MOHCLASS=default") in new stack
[2015-09-30 21:53:59] VERBOSE[1970][C-00010b0a] pbx.c: -- Executing [01xxxxxx@from-trunk:9] Set("SIP/ovh2-0000043c", "__REVERSAL_REJECT=FALSE") in new stack
[2015-09-30 21:53:59] VERBOSE[1970][C-00010b0a] pbx.c: -- Executing [01xxxxxx@from-trunk:10] GotoIf("SIP/ovh2-0000043c", "1?post-reverse-charge") in new stack
[2015-09-30 21:53:59] VERBOSE[1970][C-00010b0a] pbx.c: -- Goto (from-trunk,01xxxxxx,12)
[2015-09-30 21:53:59] VERBOSE[1970][C-00010b0a] pbx.c: -- Executing [01xxxxxx@from-trunk:12] NoOp("SIP/ovh2-0000043c", "") in new stack
[2015-09-30 21:53:59] VERBOSE[1970][C-00010b0a] pbx.c: -- Executing [01xxxxxx@from-trunk:13] Set("SIP/ovh2-0000043c", "__CALLINGNAMEPRES_SV=allowed_not_screened") in new stack
[2015-09-30 21:53:59] VERBOSE[1970][C-00010b0a] pbx.c: -- Executing [01xxxxxx@from-trunk:14] Set("SIP/ovh2-0000043c", "__CALLINGNUMPRES_SV=allowed_not_screened") in new stack
[2015-09-30 21:53:59] VERBOSE[1970][C-00010b0a] pbx.c: -- Executing [01xxxxxx@from-trunk:15] Set("SIP/ovh2-0000043c", "CALLERID(name-pres)=allowed_not_screened") in new stack
[2015-09-30 21:53:59] VERBOSE[1970][C-00010b0a] pbx.c: -- Executing [01xxxxxx@from-trunk:16] Set("SIP/ovh2-0000043c", "CALLERID(num-pres)=allowed_not_screened") in new stack
[2015-09-30 21:53:59] VERBOSE[1970][C-00010b0a] pbx.c: -- Executing [01xxxxxx@from-trunk:17] Goto("SIP/ovh2-0000043c", "from-did-direct,55,1") in new stack
[2015-09-30 21:53:59] VERBOSE[1970][C-00010b0a] pbx.c: -- Goto (from-did-direct,55,1)
[2015-09-30 21:53:59] VERBOSE[1970][C-00010b0a] pbx.c: -- Executing [55@from-did-direct:1] GotoIf("SIP/ovh2-0000043c", "0?ext-local,55,1") in new stack
[2015-09-30 21:53:59] VERBOSE[1970][C-00010b0a] pbx.c: -- Executing [55@from-did-direct:2] Macro("SIP/ovh2-0000043c", "user-callerid,") in new stack
[2015-09-30 21:53:59] VERBOSE[1970][C-00010b0a] pbx.c: -- Executing [s@macro-user-callerid:1] Set("SIP/ovh2-0000043c", "TOUCH_MONITOR=1443642839.527960") in new stack
[2015-09-30 21:53:59] VERBOSE[1970][C-00010b0a] pbx.c: -- Executing [s@macro-user-callerid:2] Set("SIP/ovh2-0000043c", "AMPUSER=06xxxxxxxx") in new stack
[2015-09-30 21:53:59] VERBOSE[1970][C-00010b0a] pbx.c: -- Executing [s@macro-user-callerid:3] GotoIf("SIP/ovh2-0000043c", "0?report") in new stack
[2015-09-30 21:53:59] VERBOSE[1970][C-00010b0a] pbx.c: -- Executing [s@macro-user-callerid:4] ExecIf("SIP/ovh2-0000043c", "1?Set(REALCALLERIDNUM=06xxxxxxxx)") in new stack
[2015-09-30 21:53:59] VERBOSE[1970][C-00010b0a] pbx.c: -- Executing [s@macro-user-callerid:5] Set("SIP/ovh2-0000043c", "AMPUSER=") in new stack
[2015-09-30 21:53:59] VERBOSE[1970][C-00010b0a] pbx.c: -- Executing [s@macro-user-callerid:6] GotoIf("SIP/ovh2-0000043c", "0?limit") in new stack
[2015-09-30 21:53:59] VERBOSE[1970][C-00010b0a] pbx.c: -- Executing [s@macro-user-callerid:7] Set("SIP/ovh2-0000043c", "AMPUSERCIDNAME=") in new stack
[2015-09-30 21:53:59] VERBOSE[1970][C-00010b0a] pbx.c: -- Executing [s@macro-user-callerid:8] GotoIf("SIP/ovh2-0000043c", "1?report") in new stack
[2015-09-30 21:53:59] VERBOSE[1970][C-00010b0a] pbx.c: -- Goto (macro-user-callerid,s,16)
[2015-09-30 21:53:59] VERBOSE[1970][C-00010b0a] pbx.c: -- Executing [s@macro-user-callerid:16] GotoIf("SIP/ovh2-0000043c", "0?continue") in new stack
[2015-09-30 21:53:59] VERBOSE[1970][C-00010b0a] pbx.c: -- Executing [s@macro-user-callerid:17] ExecIf("SIP/ovh2-0000043c", "1?Set(__CALLEE_ACCOUNCODE=)") in new stack
[2015-09-30 21:53:59] VERBOSE[1970][C-00010b0a] pbx.c: -- Executing [s@macro-user-callerid:18] Set("SIP/ovh2-0000043c", "__TTL=64") in new stack
[2015-09-30 21:53:59] VERBOSE[1970][C-00010b0a] pbx.c: -- Executing [s@macro-user-callerid:19] GotoIf("SIP/ovh2-0000043c", "1?continue") in new stack
[2015-09-30 21:53:59] VERBOSE[1970][C-00010b0a] pbx.c: -- Goto (macro-user-callerid,s,30)
[2015-09-30 21:53:59] VERBOSE[1970][C-00010b0a] pbx.c: -- Executing [s@macro-user-callerid:30] Set("SIP/ovh2-0000043c", "CALLERID(number)=06xxxxxxxx") in new stack
[2015-09-30 21:53:59] VERBOSE[1970][C-00010b0a] pbx.c: -- Executing [s@macro-user-callerid:31] Set("SIP/ovh2-0000043c", "CALLERID(name)=06xxxxxxxx") in new stack
[2015-09-30 21:53:59] VERBOSE[1970][C-00010b0a] pbx.c: -- Executing [s@macro-user-callerid:32] Set("SIP/ovh2-0000043c", "CDR(cnum)=06xxxxxxxx") in new stack
[2015-09-30 21:53:59] VERBOSE[1970][C-00010b0a] pbx.c: -- Executing [s@macro-user-callerid:33] Set("SIP/ovh2-0000043c", "CDR(cnam)=06xxxxxxxx") in new stack
[2015-09-30 21:53:59] VERBOSE[1970][C-00010b0a] pbx.c: -- Executing [s@macro-user-callerid:34] Set("SIP/ovh2-0000043c", "CHANNEL(language)=fr") in new stack
[2015-09-30 21:53:59] VERBOSE[1970][C-00010b0a] pbx.c: -- Executing [55@from-did-direct:3] Set("SIP/ovh2-0000043c", "DIAL_OPTIONS=TtrI") in new stack
[2015-09-30 21:53:59] VERBOSE[1970][C-00010b0a] pbx.c: -- Executing [55@from-did-direct:4] Set("SIP/ovh2-0000043c", "CONNECTEDLINE(num,i)=55") in new stack
[2015-09-30 21:53:59] VERBOSE[1970][C-00010b0a] pbx.c: -- Executing [55@from-did-direct:5] Gosub("SIP/ovh2-0000043c", "sub-presencestate-display,s,1(55)") in new stack
[2015-09-30 21:53:59] WARNING[1970][C-00010b0a] func_presencestate.c: PRESENCE_STATE unknown
[2015-09-30 21:53:59] VERBOSE[1970][C-00010b0a] pbx.c: -- Executing [s@sub-presencestate-display:1] Goto("SIP/ovh2-0000043c", "state-,1") in new stack
[2015-09-30 21:53:59] VERBOSE[1970][C-00010b0a] pbx.c: -- Goto (sub-presencestate-display,state-,1)
[2015-09-30 21:53:59] VERBOSE[1970][C-00010b0a] pbx.c: -- Executing [state-@sub-presencestate-display:1] Set("SIP/ovh2-0000043c", "PRESENCESTATE_DISPLAY=") in new stack
[2015-09-30 21:53:59] VERBOSE[1970][C-00010b0a] pbx.c: -- Executing [state-@sub-presencestate-display:2] Return("SIP/ovh2-0000043c", "") in new stack
[2015-09-30 21:53:59] VERBOSE[1970][C-00010b0a] pbx.c: -- Executing [55@from-did-direct:6] Set("SIP/ovh2-0000043c", "CONNECTEDLINE(name)=55") in new stack
[2015-09-30 21:53:59] VERBOSE[1970][C-00010b0a] pbx.c: -- Executing [55@from-did-direct:7] Set("SIP/ovh2-0000043c", "FM_DIALSTATUS=NOT_INUSE") in new stack
[2015-09-30 21:53:59] VERBOSE[1970][C-00010b0a] pbx.c: -- Executing [55@from-did-direct:8] Set("SIP/ovh2-0000043c", "__EXTTOCALL=55") in new stack
[2015-09-30 21:53:59] VERBOSE[1970][C-00010b0a] pbx.c: -- Executing [55@from-did-direct:9] Set("SIP/ovh2-0000043c", "__PICKUPMARK=55") in new stack
[2015-09-30 21:53:59] VERBOSE[1970][C-00010b0a] pbx.c: -- Executing [55@from-did-direct:10] Macro("SIP/ovh2-0000043c", "blkvm-setifempty,") in new stack
[2015-09-30 21:53:59] VERBOSE[1970][C-00010b0a] pbx.c: -- Executing [s@macro-blkvm-setifempty:1] GotoIf("SIP/ovh2-0000043c", "1?init") in new stack
[2015-09-30 21:53:59] VERBOSE[1970][C-00010b0a] pbx.c: -- Goto (macro-blkvm-setifempty,s,4)
[2015-09-30 21:53:59] VERBOSE[1970][C-00010b0a] pbx.c: -- Executing [s@macro-blkvm-setifempty:4] Set("SIP/ovh2-0000043c", "__BLKVM_CHANNEL=SIP/ovh2-0000043c") in new stack
[2015-09-30 21:53:59] VERBOSE[1970][C-00010b0a] pbx.c: -- Executing [s@macro-blkvm-setifempty:5] Set("SIP/ovh2-0000043c", "SHARED(BLKVM,SIP/ovh2-0000043c)=TRUE") in new stack
[2015-09-30 21:53:59] VERBOSE[1970][C-00010b0a] pbx.c: -- Executing [s@macro-blkvm-setifempty:6] Set("SIP/ovh2-0000043c", "GOSUB_RETVAL=TRUE") in new stack
[2015-09-30 21:53:59] VERBOSE[1970][C-00010b0a] pbx.c: -- Executing [s@macro-blkvm-setifempty:7] MacroExit("SIP/ovh2-0000043c", "") in new stack
[2015-09-30 21:53:59] VERBOSE[1970][C-00010b0a] pbx.c: -- Executing [55@from-did-direct:11] GotoIf("SIP/ovh2-0000043c", "1?skipov") in new stack
[2015-09-30 21:53:59] VERBOSE[1970][C-00010b0a] pbx.c: -- Goto (from-did-direct,55,14)
[2015-09-30 21:53:59] VERBOSE[1970][C-00010b0a] pbx.c: -- Executing [55@from-did-direct:14] Set("SIP/ovh2-0000043c", "RRNODEST=") in new stack
[2015-09-30 21:53:59] VERBOSE[1970][C-00010b0a] pbx.c: -- Executing [55@from-did-direct:15] Set("SIP/ovh2-0000043c", "__NODEST=55") in new stack
[2015-09-30 21:53:59] VERBOSE[1970][C-00010b0a] pbx.c: -- Executing [55@from-did-direct:16] GosubIf("SIP/ovh2-0000043c", "0?sub-fmsetcid,s,1()") in new stack
[2015-09-30 21:53:59] VERBOSE[1970][C-00010b0a] pbx.c: -- Executing [55@from-did-direct:17] Set("SIP/ovh2-0000043c", "RecordMethod=Group") in new stack
[2015-09-30 21:53:59] VERBOSE[1970][C-00010b0a] pbx.c: -- Executing [55@from-did-direct:18] Gosub("SIP/ovh2-0000043c", "sub-record-check,s,1(exten,55,)") in new stack
[2015-09-30 21:53:59] VERBOSE[1970][C-00010b0a] pbx.c: -- Executing [s@sub-record-check:1] GotoIf("SIP/ovh2-0000043c", "10?initialized") in new stack
[2015-09-30 21:53:59] VERBOSE[1970][C-00010b0a] pbx.c: -- Goto (sub-record-check,s,10)
[2015-09-30 21:53:59] VERBOSE[1970][C-00010b0a] pbx.c: -- Executing [s@sub-record-check:10] NoOp("SIP/ovh2-0000043c", "Recordings initialized") in new stack
[2015-09-30 21:53:59] VERBOSE[1970][C-00010b0a] pbx.c: -- Executing [s@sub-record-check:11] ExecIf("SIP/ovh2-0000043c", "1?Set(ARG3=dontcare)") in new stack
[2015-09-30 21:53:59] VERBOSE[1970][C-00010b0a] pbx.c: -- Executing [s@sub-record-check:12] Set("SIP/ovh2-0000043c", "REC_POLICY_MODE_SAVE=FORCE") in new stack
[2015-09-30 21:53:59] VERBOSE[1970][C-00010b0a] pbx.c: -- Executing [s@sub-record-check:13] ExecIf("SIP/ovh2-0000043c", "0?Set(REC_STATUS=NO)") in new stack
[2015-09-30 21:53:59] VERBOSE[1970][C-00010b0a] pbx.c: -- Executing [s@sub-record-check:14] GotoIf("SIP/ovh2-0000043c", "5?checkaction") in new stack
[2015-09-30 21:53:59] VERBOSE[1970][C-00010b0a] pbx.c: -- Goto (sub-record-check,s,17)
[2015-09-30 21:53:59] VERBOSE[1970][C-00010b0a] pbx.c: -- Executing [s@sub-record-check:17] GotoIf("SIP/ovh2-0000043c", "1?sub-record-check,exten,1") in new stack
[2015-09-30 21:53:59] VERBOSE[1970][C-00010b0a] pbx.c: -- Goto (sub-record-check,exten,1)
[2015-09-30 21:53:59] VERBOSE[1970][C-00010b0a] pbx.c: -- Executing [exten@sub-record-check:1] NoOp("SIP/ovh2-0000043c", "Exten Recording Check between 06xxxxxxxx and 55") in new stack
[2015-09-30 21:53:59] VERBOSE[1970][C-00010b0a] pbx.c: -- Executing [exten@sub-record-check:2] Set("SIP/ovh2-0000043c", "CALLTYPE=external") in new stack
[2015-09-30 21:53:59] VERBOSE[1970][C-00010b0a] pbx.c: -- Executing [exten@sub-record-check:3] ExecIf("SIP/ovh2-0000043c", "0?Set(CALLTYPE=)") in new stack
[2015-09-30 21:53:59] VERBOSE[1970][C-00010b0a] pbx.c: -- Executing [exten@sub-record-check:4] Set("SIP/ovh2-0000043c", "CALLEE=yes") in new stack
[2015-09-30 21:53:59] VERBOSE[1970][C-00010b0a] pbx.c: -- Executing [exten@sub-record-check:5] ExecIf("SIP/ovh2-0000043c", "0?Set(CALLEE=dontcare)") in new stack
[2015-09-30 21:53:59] VERBOSE[1970][C-00010b0a] pbx.c: -- Executing [exten@sub-record-check:6] GotoIf("SIP/ovh2-0000043c", "1?callee") in new stack
[2015-09-30 21:53:59] VERBOSE[1970][C-00010b0a] pbx.c: -- Goto (sub-record-check,exten,11)
[2015-09-30 21:53:59] VERBOSE[1970][C-00010b0a] pbx.c: -- Executing [exten@sub-record-check:11] Gosub("SIP/ovh2-0000043c", "recordcheck,1(yes,external,55)") in new stack
[2015-09-30 21:53:59] VERBOSE[1970][C-00010b0a] pbx.c: -- Executing [recordcheck@sub-record-check:1] NoOp("SIP/ovh2-0000043c", "Starting recording check against yes") in new stack
[2015-09-30 21:53:59] VERBOSE[1970][C-00010b0a] pbx.c: -- Executing [recordcheck@sub-record-check:2] Goto("SIP/ovh2-0000043c", "yes") in new stack
[2015-09-30 21:53:59] VERBOSE[1970][C-00010b0a] pbx.c: -- Goto (sub-record-check,recordcheck,9)
[2015-09-30 21:53:59] VERBOSE[1970][C-00010b0a] pbx.c: -- Executing [recordcheck@sub-record-check:9] ExecIf("SIP/ovh2-0000043c", "1?Return()") in new stack
[2015-09-30 21:53:59] VERBOSE[1970][C-00010b0a] pbx.c: -- Executing [exten@sub-record-check:12] Return("SIP/ovh2-0000043c", "") in new stack
[2015-09-30 21:53:59] VERBOSE[1970][C-00010b0a] pbx.c: -- Executing [55@from-did-direct:19] Set("SIP/ovh2-0000043c", "RingGroupMethod=ringallv2-prim") in new stack
[2015-09-30 21:53:59] VERBOSE[1970][C-00010b0a] pbx.c: -- Executing [55@from-did-direct:20] Set("SIP/ovh2-0000043c", "_FMGRP=55") in new stack
[2015-09-30 21:53:59] VERBOSE[1970][C-00010b0a] pbx.c: -- Executing [55@from-did-direct:21] GotoIf("SIP/ovh2-0000043c", "0?doconfirm") in new stack
[2015-09-30 21:53:59] VERBOSE[1970][C-00010b0a] pbx.c: -- Executing [55@from-did-direct:22] Macro("SIP/ovh2-0000043c", "dial,27,TtrI,55") in new stack
[2015-09-30 21:53:59] VERBOSE[1970][C-00010b0a] pbx.c: -- Executing [s@macro-dial:1] GotoIf("SIP/ovh2-0000043c", "0?dial") in new stack
[2015-09-30 21:53:59] VERBOSE[1970][C-00010b0a] pbx.c: -- Executing [s@macro-dial:2] Set("SIP/ovh2-0000043c", "CHANNEL(musicclass)=default") in new stack
[2015-09-30 21:53:59] VERBOSE[1970][C-00010b0a] pbx.c: -- Executing [s@macro-dial:3] AGI("SIP/ovh2-0000043c", "dialparties.agi") in new stack
[2015-09-30 21:53:59] VERBOSE[1970][C-00010b0a] res_agi.c: -- Launched AGI Script /var/lib/asterisk/agi-bin/dialparties.agi
[2015-09-30 21:53:59] VERBOSE[1970][C-00010b0a] res_agi.c: dialparties.agi: Starting New Dialparties.agi
[2015-09-30 21:53:59] VERBOSE[1970][C-00010b0a] res_agi.c: dialparties.agi: Caller ID name is '06xxxxxxxx' number is '06xxxxxxxx'
[2015-09-30 21:53:59] VERBOSE[1970][C-00010b0a] res_agi.c: dialparties.agi: Methodology of ring is 'ringallv2-prim'
[2015-09-30 21:53:59] VERBOSE[1970][C-00010b0a] res_agi.c: -- dialparties.agi: Added extension 55 to extension map
[2015-09-30 21:53:59] VERBOSE[1970][C-00010b0a] res_agi.c: -- dialparties.agi: Extension 55 cf is disabled
[2015-09-30 21:53:59] VERBOSE[1970][C-00010b0a] res_agi.c: -- dialparties.agi: Extension 55 do not disturb is disabled
[2015-09-30 21:53:59] VERBOSE[1970][C-00010b0a] res_agi.c: dialparties.agi: EXTENSION_STATE: 0 (NOT_INUSE)
[2015-09-30 21:53:59] VERBOSE[1970][C-00010b0a] res_agi.c: dialparties.agi: Extension 55 has ExtensionState: 0
[2015-09-30 21:53:59] VERBOSE[1970][C-00010b0a] res_agi.c: dialparties.agi: EXTENSION_STATE: 0 (NOT_INUSE)
[2015-09-30 21:53:59] VERBOSE[1970][C-00010b0a] res_agi.c: dialparties.agi: Extension 55 has ExtensionState: 0
[2015-09-30 21:53:59] VERBOSE[1970][C-00010b0a] res_agi.c: -- dialparties.agi: Checking CW and CFB status for extension 55
[2015-09-30 21:53:59] VERBOSE[1970][C-00010b0a] res_agi.c: -- dialparties.agi: dbset CALLTRACE/55 to 06xxxxxxxx
[2015-09-30 21:53:59] VERBOSE[1970][C-00010b0a] res_agi.c: -- dialparties.agi: Filtered ARG3: 55
[2015-09-30 21:53:59] VERBOSE[1970][C-00010b0a] res_agi.c: -- <SIP/ovh2-0000043c>AGI Script dialparties.agi completed, returning 0
[2015-09-30 21:53:59] VERBOSE[1970][C-00010b0a] pbx.c: -- Executing [s@macro-dial:7] Dial("SIP/ovh2-0000043c", "SIP/55,27,TtrIM(auto-blkvm)b(func-apply-sipheaders^s^1),") in new stack
[2015-09-30 21:53:59] VERBOSE[1970][C-00010b0a] netsock2.c: == Using SIP RTP TOS bits 184
[2015-09-30 21:53:59] VERBOSE[1970][C-00010b0a] netsock2.c: == Using SIP RTP CoS mark 5
[2015-09-30 21:53:59] VERBOSE[1970][C-00010b0a] app_stack.c: -- SIP/55-0000043d Internal Gosub(func-apply-sipheaders,s,1) start
[2015-09-30 21:53:59] VERBOSE[1970][C-00010b0a] pbx.c: -- Executing [s@func-apply-sipheaders:1] NoOp("SIP/55-0000043d", "Applying SIP Headers to channel") in new stack
[2015-09-30 21:53:59] VERBOSE[1970][C-00010b0a] pbx.c: -- Executing [s@func-apply-sipheaders:2] Set("SIP/55-0000043d", "SIPHEADERKEYS=") in new stack
[2015-09-30 21:53:59] VERBOSE[1970][C-00010b0a] pbx.c: -- Executing [s@func-apply-sipheaders:3] While("SIP/55-0000043d", "0") in new stack
[2015-09-30 21:53:59] VERBOSE[1970][C-00010b0a] app_while.c: -- Jumping to priority 7
[2015-09-30 21:53:59] VERBOSE[1970][C-00010b0a] pbx.c: -- Executing [s@func-apply-sipheaders:8] Return("SIP/55-0000043d", "") in new stack
[2015-09-30 21:53:59] VERBOSE[1970][C-00010b0a] app_stack.c: == Spawn extension (from-internal, 55, 1) exited non-zero on 'SIP/55-0000043d'
[2015-09-30 21:53:59] VERBOSE[1970][C-00010b0a] app_stack.c: -- SIP/55-0000043d Internal Gosub(func-apply-sipheaders,s,1) complete GOSUB_RETVAL=
[2015-09-30 21:53:59] VERBOSE[1970][C-00010b0a] app_dial.c: -- Called SIP/55
[2015-09-30 21:53:59] VERBOSE[1970][C-00010b0a] app_dial.c: -- Connected line update to SIP/ovh2-0000043c prevented.
[2015-09-30 21:53:59] VERBOSE[1970][C-00010b0a] app_dial.c: -- SIP/55-0000043d is ringing
[2015-09-30 21:54:24] VERBOSE[1970][C-00010b0a] app_macro.c: == Spawn extension (macro-dial, s, 7) exited non-zero on 'SIP/ovh2-0000043c' in macro 'dial'
[2015-09-30 21:54:24] VERBOSE[1970][C-00010b0a] pbx.c: == Spawn extension (from-did-direct, 55, 22) exited non-zero on 'SIP/ovh2-0000043c'
[2015-09-30 21:54:24] VERBOSE[1970][C-00010b0a] pbx.c: -- Executing [h@from-did-direct:1] Macro("SIP/ovh2-0000043c", "hangupcall,") in new stack
[2015-09-30 21:54:24] VERBOSE[1970][C-00010b0a] pbx.c: -- Executing [s@macro-hangupcall:1] GotoIf("SIP/ovh2-0000043c", "1?theend") in new stack
[2015-09-30 21:54:24] VERBOSE[1970][C-00010b0a] pbx.c: -- Goto (macro-hangupcall,s,3)
[2015-09-30 21:54:24] VERBOSE[1970][C-00010b0a] pbx.c: -- Executing [s@macro-hangupcall:3] ExecIf("SIP/ovh2-0000043c", "0?Set(CDR(recordingfile)=)") in new stack
[2015-09-30 21:54:24] VERBOSE[1970][C-00010b0a] pbx.c: -- Executing [s@macro-hangupcall:4] Hangup("SIP/ovh2-0000043c", "") in new stack
[2015-09-30 21:54:24] VERBOSE[1970][C-00010b0a] app_macro.c: == Spawn extension (macro-hangupcall, s, 4) exited non-zero on 'SIP/ovh2-0000043c' in macro 'hangupcall'
[2015-09-30 21:54:24] VERBOSE[1970][C-00010b0a] pbx.c: == Spawn extension (from-did-direct, h, 1) exited non-zero on 'SIP/ovh2-0000043c'
[2015-09-30 21:54:24] VERBOSE[1972][C-00010b0a] app_mixmonitor.c: == MixMonitor close filestream (mixed)
[2015-09-30 21:54:24] VERBOSE[1972][C-00010b0a] app_mixmonitor.c: == End MixMonitor Recording SIP/ovh2-0000043c