As you suggested i fired up Asterisk in verbose (asterisk -r -vvvv) whilst receiving a call.
which is very helpful for debuging by the way.
Here is the output of the log of the call.
I cannot see any references to the DDI actually dield in the CLI Log.
` == Using SIP RTP CoS mark 5
-- Executing [s@from-trunk:1] Set("SIP/PlivoTrunkxxxxxyyyyyzzzz", "__DIRECTION=INBOUND") in new stack
-- Executing [s@from-trunk:2] Gosub("SIP/PlivoTrunkxxxxxyyyyyzzzz", "sub-record-check,s,1(in,s,force)") in new stack
-- Executing [s@sub-record-check:1] GotoIf("SIP/PlivoTrunkxxxxxyyyyyzzzz", "0?initialized") in new stack
-- Executing [s@sub-record-check:2] Set("SIP/PlivoTrunkxxxxxyyyyyzzzz", "__REC_STATUS=INITIALIZED") in new stack
-- Executing [s@sub-record-check:3] Set("SIP/PlivoTrunkxxxxxyyyyyzzzz", "NOW=1483715367") in new stack
-- Executing [s@sub-record-check:4] Set("SIP/PlivoTrunkxxxxxyyyyyzzzz", "__DAY=06") in new stack
-- Executing [s@sub-record-check:5] Set("SIP/PlivoTrunkxxxxxyyyyyzzzz", "__MONTH=01") in new stack
-- Executing [s@sub-record-check:6] Set("SIP/PlivoTrunkxxxxxyyyyyzzzz", "__YEAR=2017") in new stack
-- Executing [s@sub-record-check:7] Set("SIP/PlivoTrunkxxxxxyyyyyzzzz", "__TIMESTR=20170106-150927") in new stack
-- Executing [s@sub-record-check:8] Set("SIP/PlivoTrunkxxxxxyyyyyzzzz", "__FROMEXTEN=unknown") in new stack
-- Executing [s@sub-record-check:9] Set("SIP/PlivoTrunkxxxxxyyyyyzzzz", "__MON_FMT=wav") in new stack
-- Executing [s@sub-record-check:10] NoOp("SIP/PlivoTrunkxxxxxyyyyyzzzz", "Recordings initialized") in new stack
-- Executing [s@sub-record-check:11] ExecIf("SIP/PlivoTrunkxxxxxyyyyyzzzz", "0?Set(ARG3=dontcare)") in new stack
-- Executing [s@sub-record-check:12] Set("SIP/PlivoTrunkxxxxxyyyyyzzzz", "REC_POLICY_MODE_SAVE=") in new stack
-- Executing [s@sub-record-check:13] ExecIf("SIP/PlivoTrunkxxxxxyyyyyzzzz", "0?Set(REC_STATUS=NO)") in new stack
-- Executing [s@sub-record-check:14] GotoIf("SIP/PlivoTrunkxxxxxyyyyyzzzz", "2?checkaction") in new stack
-- Goto (sub-record-check,s,17)
-- Executing [s@sub-record-check:17] GotoIf("SIP/PlivoTrunkxxxxxyyyyyzzzz", "1?sub-record-check,in,1") in new stack
-- Goto (sub-record-check,in,1)
-- Executing [in@sub-record-check:1] NoOp("SIP/PlivoTrunkxxxxxyyyyyzzzz", "Inbound Recording Check to s") in new stack
-- Executing [in@sub-record-check:2] Set("SIP/PlivoTrunkxxxxxyyyyyzzzz", "FROMEXTEN=unknown") in new stack
-- Executing [in@sub-record-check:3] ExecIf("SIP/PlivoTrunkxxxxxyyyyyzzzz", "13?Set(FROMEXTEN=+442070000000)") in new stack
-- Executing [in@sub-record-check:4] Gosub("SIP/PlivoTrunkxxxxxyyyyyzzzz", "recordcheck,1(force,in,s)") in new stack
-- Executing [recordcheck@sub-record-check:1] NoOp("SIP/PlivoTrunkxxxxxyyyyyzzzz", "Starting recording check against force") in new stack
-- Executing [recordcheck@sub-record-check:2] Goto("SIP/PlivoTrunkxxxxxyyyyyzzzz", "force") in new stack
-- Goto (sub-record-check,recordcheck,5)
-- Executing [recordcheck@sub-record-check:5] Set("SIP/PlivoTrunkxxxxxyyyyyzzzz", "__REC_POLICY_MODE=FORCE") in new stack
-- Executing [recordcheck@sub-record-check:6] GotoIf("SIP/PlivoTrunkxxxxxyyyyyzzzz", "1?startrec") in new stack
-- Goto (sub-record-check,recordcheck,16)
-- Executing [recordcheck@sub-record-check:16] NoOp("SIP/PlivoTrunkxxxxxyyyyyzzzz", "Starting recording: in, s") in new stack
-- Executing [recordcheck@sub-record-check:17] Set("SIP/PlivoTrunkxxxxxyyyyyzzzz", "AUDIOHOOK_INHERIT(MixMonitor)=yes") in new stack
-- Executing [recordcheck@sub-record-check:18] Set("SIP/PlivoTrunkxxxxxyyyyyzzzz", "__CALLFILENAME=in-s-+442070000000-20170106-150927-1483715367.158") in new stack
-- Executing [recordcheck@sub-record-check:19] MixMonitor("SIP/PlivoTrunkxxxxxyyyyyzzzz", "2017/01/06/in-s-+442070000000-20170106-150927-1483715367.158.wav,ai(LOCAL_MIXMON_ID),") in new stack
-- Executing [recordcheck@sub-record-check:20] Set("SIP/PlivoTrunkxxxxxyyyyyzzzz", "__MIXMON_ID=0x7fb3149ec360") in new stack
-- Executing [recordcheck@sub-record-check:21] Set("SIP/PlivoTrunkxxxxxyyyyyzzzz", "__RECORD_ID=SIP/PlivoTrunkxxxxxyyyyyzzzz") in new stack
-- Executing [recordcheck@sub-record-check:22] Set("SIP/PlivoTrunkxxxxxyyyyyzzzz", "__REC_STATUS=RECORDING") in new stack
-- Executing [recordcheck@sub-record-check:23] Set("SIP/PlivoTrunkxxxxxyyyyyzzzz", "CDR(recordingfile)=in-s-+442070000000-20170106-150927-1483715367.158.wav") in new stack
-- Executing [recordcheck@sub-record-check:24] Return("SIP/PlivoTrunkxxxxxyyyyyzzzz", "") in new stack
-- Executing [in@sub-record-check:5] Return("SIP/PlivoTrunkxxxxxyyyyyzzzz", "") in new stack
-- Executing [s@from-trunk:3] Gosub("SIP/PlivoTrunkxxxxxyyyyyzzzz", "app-blacklist-check,s,1()") in new stack
-- Executing [s@app-blacklist-check:1] GotoIf("SIP/PlivoTrunkxxxxxyyyyyzzzz", "0?blacklisted") in new stack
-- Executing [s@app-blacklist-check:2] Set("SIP/PlivoTrunkxxxxxyyyyyzzzz", "CALLED_BLACKLIST=1") in new stack
-- Executing [s@app-blacklist-check:3] Return("SIP/PlivoTrunkxxxxxyyyyyzzzz", "") in new stack
-- Executing [s@from-trunk:4] ExecIf("SIP/PlivoTrunkxxxxxyyyyyzzzz", "1?Set(__FROM_DID=s)") in new stack
-- Executing [s@from-trunk:5] Set("SIP/PlivoTrunkxxxxxyyyyyzzzz", "CDR(did)=s") in new stack
-- Executing [s@from-trunk:6] ExecIf("SIP/PlivoTrunkxxxxxyyyyyzzzz", "0 ?Set(CALLERID(name)=+442070000000)") in new stack
-- Executing [s@from-trunk:7] Set("SIP/PlivoTrunkxxxxxyyyyyzzzz", "__MOHCLASS=") in new stack
-- Executing [s@from-trunk:8] Set("SIP/PlivoTrunkxxxxxyyyyyzzzz", "__REVERSAL_REJECT=FALSE") in new stack
-- Executing [s@from-trunk:9] GotoIf("SIP/PlivoTrunkxxxxxyyyyyzzzz", "1?post-reverse-charge") in new stack
-- Goto (from-trunk,s,11)
-- Executing [s@from-trunk:11] NoOp("SIP/PlivoTrunkxxxxxyyyyyzzzz", "") in new stack
-- Executing [s@from-trunk:12] Set("SIP/PlivoTrunkxxxxxyyyyyzzzz", "__CALLINGNAMEPRES_SV=allowed_not_screened") in new stack
-- Executing [s@from-trunk:13] Set("SIP/PlivoTrunkxxxxxyyyyyzzzz", "__CALLINGNUMPRES_SV=allowed_not_screened") in new stack
-- Executing [s@from-trunk:14] Set("SIP/PlivoTrunkxxxxxyyyyyzzzz", "CALLERID(name-pres)=allowed_not_screened") in new stack
-- Executing [s@from-trunk:15] Set("SIP/PlivoTrunkxxxxxyyyyyzzzz", "CALLERID(num-pres)=allowed_not_screened") in new stack
-- Executing [s@from-trunk:16] NoOp("SIP/PlivoTrunkxxxxxyyyyyzzzz", "CallerID Entry Point") in new stack
-- Executing [s@from-trunk:17] Goto("SIP/PlivoTrunkxxxxxyyyyyzzzz", "ivr-3,s,1") in new stack
== Begin MixMonitor Recording SIP/PlivoTrunkxxxxxyyyyyzzzz
– Goto (ivr-3,s,1)
– Executing [s@ivr-3:1] Set(“SIP/PlivoTrunkxxxxxyyyyyzzzz”, “INVALID_LOOPCOUNT=0”) in new stack
– Executing [s@ivr-3:2] Set(“SIP/PlivoTrunkxxxxxyyyyyzzzz”, “_IVR_CONTEXT_ivr-3=”) in new stack
– Executing [s@ivr-3:3] Set(“SIP/PlivoTrunkxxxxxyyyyyzzzz”, “_IVR_CONTEXT=ivr-3”) in new stack
– Executing [s@ivr-3:4] Set(“SIP/PlivoTrunkxxxxxyyyyyzzzz”, “__IVR_RETVM=”) in new stack
– Executing [s@ivr-3:5] GotoIf(“SIP/PlivoTrunkxxxxxyyyyyzzzz”, “0?skip”) in new stack
– Executing [s@ivr-3:6] Answer(“SIP/PlivoTrunkxxxxxyyyyyzzzz”, “”) in new stack
> 0x7fb31879c9e0 – Probation passed - setting RTP source address to 52.59.63.228:16808
– Executing [s@ivr-3:7] Wait(“SIP/PlivoTrunkxxxxxyyyyyzzzz”, “1”) in new stack
– Executing [s@ivr-3:8] Set(“SIP/PlivoTrunkxxxxxyyyyyzzzz”, “IVR_MSG=custom/welcomeuncompressed”) in new stack
– Executing [s@ivr-3:9] Set(“SIP/PlivoTrunkxxxxxyyyyyzzzz”, “TIMEOUT(digit)=3”) in new stack
– Digit timeout set to 3.000
– Executing [s@ivr-3:10] ExecIf(“SIP/PlivoTrunkxxxxxyyyyyzzzz”, “1?Background(custom/welcomeuncompressed)”) in new stack
– <SIP/PlivoTrunkxxxxxyyyyyzzzz> Playing ‘custom/welcomeuncompressed.slin’ (language ‘en_GB’)
– Executing [s@ivr-3:11] WaitExten(“SIP/PlivoTrunkxxxxxyyyyyzzzz”, “1,”) in new stack
– Timeout on SIP/PlivoTrunkxxxxxyyyyyzzzz, going to ‘t’
– Executing [t@ivr-3:1] Goto(“SIP/PlivoTrunkxxxxxyyyyyzzzz”, “timeconditions,1,1”) in new stack
– Goto (timeconditions,1,1)
– Executing [1@timeconditions:1] Set(“SIP/PlivoTrunkxxxxxyyyyyzzzz”, “DB(TC/1/INUSESTATE)=INUSE”) in new stack
– Executing [1@timeconditions:2] Set(“SIP/PlivoTrunkxxxxxyyyyyzzzz”, “DB(TC/1/NOT_INUSESTATE)=NOT_INUSE”) in new stack
– Executing [1@timeconditions:3] GotoIfTime(“SIP/PlivoTrunkxxxxxyyyyyzzzz”, “09:00-19:00,mon-sat,,,Europe/London?truestate”) in new stack
– Goto (timeconditions,1,12)
– Executing [1@timeconditions:12] GotoIf(“SIP/PlivoTrunkxxxxxyyyyyzzzz”, “0?falsegoto”) in new stack
– Executing [1@timeconditions:13] ExecIf(“SIP/PlivoTrunkxxxxxyyyyyzzzz”, “0?Set(DB(TC/1)=)”) in new stack
– Executing [1@timeconditions:14] Set(“SIP/PlivoTrunkxxxxxyyyyyzzzz”, “DEVICE_STATE(Custom:TC1)=NOT_INUSE”) in new stack
– Executing [1@timeconditions:15] ExecIf(“SIP/PlivoTrunkxxxxxyyyyyzzzz”, “0?Set(DEVICE_STATE(Custom:TCSTICKY)=INUSE)”) in new stack
– Executing [1@timeconditions:16] GotoIf(“SIP/PlivoTrunkxxxxxyyyyyzzzz”, “1?ivr-2,s,1”) in new stack
– Goto (ivr-2,s,1)
– Executing [s@ivr-2:1] Set(“SIP/PlivoTrunkxxxxxyyyyyzzzz”, “TIMEOUT_LOOPCOUNT=0”) in new stack
– Executing [s@ivr-2:2] Set(“SIP/PlivoTrunkxxxxxyyyyyzzzz”, “INVALID_LOOPCOUNT=0”) in new stack
– Executing [s@ivr-2:3] Set(“SIP/PlivoTrunkxxxxxyyyyyzzzz”, “_IVR_CONTEXT_ivr-2=ivr-3”) in new stack
– Executing [s@ivr-2:4] Set(“SIP/PlivoTrunkxxxxxyyyyyzzzz”, “_IVR_CONTEXT=ivr-2”) in new stack
– Executing [s@ivr-2:5] Set(“SIP/PlivoTrunkxxxxxyyyyyzzzz”, “__IVR_RETVM=”) in new stack
– Executing [s@ivr-2:6] GotoIf(“SIP/PlivoTrunkxxxxxyyyyyzzzz”, “1?skip”) in new stack
– Goto (ivr-2,s,9)
– Executing [s@ivr-2:9] Set(“SIP/PlivoTrunkxxxxxyyyyyzzzz”, “IVR_MSG=custom/press12test”) in new stack
– Executing [s@ivr-2:10] Set(“SIP/PlivoTrunkxxxxxyyyyyzzzz”, “TIMEOUT(digit)=3”) in new stack
– Digit timeout set to 3.000
– Executing [s@ivr-2:11] ExecIf(“SIP/PlivoTrunkxxxxxyyyyyzzzz”, “1?Background(custom/press12test)”) in new stack
– <SIP/PlivoTrunkxxxxxyyyyyzzzz> Playing ‘custom/press12test.slin’ (language ‘en_GB’)
– Executing [s@ivr-2:12] WaitExten(“SIP/PlivoTrunkxxxxxyyyyyzzzz”, “5,”) in new stack
– Timeout on SIP/PlivoTrunkxxxxxyyyyyzzzz, going to ‘t’
– Executing [t@ivr-2:1] Set(“SIP/PlivoTrunkxxxxxyyyyyzzzz”, “TIMEOUT_LOOPCOUNT=1”) in new stack
– Executing [t@ivr-2:2] GotoIf(“SIP/PlivoTrunkxxxxxyyyyyzzzz”, “0?final”) in new stack
– Executing [t@ivr-2:3] Set(“SIP/PlivoTrunkxxxxxyyyyyzzzz”, “IVR_MSG=no-valid-responce-pls-try-again”) in new stack
– Executing [t@ivr-2:4] Goto(“SIP/PlivoTrunkxxxxxyyyyyzzzz”, “s,start”) in new stack
– Goto (ivr-2,s,10)
– Executing [s@ivr-2:10] Set(“SIP/PlivoTrunkxxxxxyyyyyzzzz”, “TIMEOUT(digit)=3”) in new stack
– Digit timeout set to 3.000
– Executing [s@ivr-2:11] ExecIf(“SIP/PlivoTrunkxxxxxyyyyyzzzz”, “1?Background(no-valid-responce-pls-try-again)”) in new stack
– <SIP/PlivoTrunkxxxxxyyyyyzzzz> Playing ‘no-valid-responce-pls-try-again.ulaw’ (language ‘en_GB’)
== Spawn extension (ivr-2, s, 11) exited non-zero on ‘SIP/PlivoTrunkxxxxxyyyyyzzzz’
– Executing [h@ivr-2:1] Hangup(“SIP/PlivoTrunkxxxxxyyyyyzzzz”, “”) in new stack
== Spawn extension (ivr-2, h, 1) exited non-zero on ‘SIP/PlivoTrunkxxxxxyyyyyzzzz’
== MixMonitor close filestream (mixed)
== End MixMonitor Recording SIP/PlivoTrunkxxxxxyyyyyzzzz`