Problems with "Misc Destinations" [SOLVED]

FreePBX
1

Problems with “Misc Destinations”

Use it often Misc Destinations, but a new machine with FreePBX does not work he answers me to check the number … yet the number is right and works on other FreePBX …

I do not understand why I should respond well

I noticed that the log appeared this message, do not know if there is a link:

“[09/22/2016 10:03:00] ERROR [55042] phone_message.c: Unable to build dialplan routing - invalid license”

2

I attach a log that I am sure to be related to the problem with “Misc Destinations”

as you see the call arrives from 01821985498 to 0195090356

and after 15 seconds try to send her to 3383033927 but he failed but do not understand why …

to follow the LOG:

[2016-09-22 11:06:08] VERBOSE[43984][C-000000cc] pbx.c: Executing [0192075897@from-pstn-toheader:1] Goto(“SIP/01821985498_in-00000230”, “from-pstn,0195090356,1”) in new stack
[2016-09-22 11:06:08] VERBOSE[43984][C-000000cc] pbx_builtins.c: Goto (from-pstn,0195090356,1)
[2016-09-22 11:06:08] VERBOSE[43984][C-000000cc] pbx.c: Executing [0195090356@from-pstn:1] Set(“SIP/01821985498_in-00000230”, “__DIRECTION=INBOUND”) in new stack
[2016-09-22 11:06:08] VERBOSE[43984][C-000000cc] pbx.c: Executing [0195090356@from-pstn:2] Gosub(“SIP/01821985498_in-00000230”, “sub-record-check,s,1(in,0195090356,yes)”) in new stack
[2016-09-22 11:06:08] VERBOSE[43984][C-000000cc] pbx.c: Executing [s@sub-record-check:1] GotoIf(“SIP/01821985498_in-00000230”, “0?initialized”) in new stack
[2016-09-22 11:06:08] VERBOSE[43984][C-000000cc] pbx.c: Executing [s@sub-record-check:2] Set(“SIP/01821985498_in-00000230”, “__REC_STATUS=INITIALIZED”) in new stack
[2016-09-22 11:06:08] VERBOSE[43984][C-000000cc] pbx.c: Executing [s@sub-record-check:3] Set(“SIP/01821985498_in-00000230”, “NOW=1474535168”) in new stack
[2016-09-22 11:06:08] VERBOSE[43984][C-000000cc] pbx.c: Executing [s@sub-record-check:4] Set(“SIP/01821985498_in-00000230”, “__DAY=22”) in new stack
[2016-09-22 11:06:08] VERBOSE[43984][C-000000cc] pbx.c: Executing [s@sub-record-check:5] Set(“SIP/01821985498_in-00000230”, “__MONTH=09”) in new stack
[2016-09-22 11:06:08] VERBOSE[43984][C-000000cc] pbx.c: Executing [s@sub-record-check:6] Set(“SIP/01821985498_in-00000230”, “__YEAR=2016”) in new stack
[2016-09-22 11:06:08] VERBOSE[43984][C-000000cc] pbx.c: Executing [s@sub-record-check:7] Set(“SIP/01821985498_in-00000230”, “__TIMESTR=20160922-110608”) in new stack
[2016-09-22 11:06:08] VERBOSE[43984][C-000000cc] pbx.c: Executing [s@sub-record-check:8] Set(“SIP/01821985498_in-00000230”, “__FROMEXTEN=unknown”) in new stack
[2016-09-22 11:06:08] VERBOSE[43984][C-000000cc] pbx.c: Executing [s@sub-record-check:9] Set(“SIP/01821985498_in-00000230”, “__MON_FMT=wav”) in new stack
[2016-09-22 11:06:08] VERBOSE[43984][C-000000cc] pbx.c: Executing [s@sub-record-check:10] NoOp(“SIP/01821985498_in-00000230”, “Recordings initialized”) in new stack
[2016-09-22 11:06:08] VERBOSE[43984][C-000000cc] pbx.c: Executing [s@sub-record-check:11] ExecIf(“SIP/01821985498_in-00000230”, “0?Set(ARG3=dontcare)”) in new stack
[2016-09-22 11:06:08] VERBOSE[43984][C-000000cc] pbx.c: Executing [s@sub-record-check:12] Set(“SIP/01821985498_in-00000230”, “REC_POLICY_MODE_SAVE=”) in new stack
[2016-09-22 11:06:08] VERBOSE[43984][C-000000cc] pbx.c: Executing [s@sub-record-check:13] ExecIf(“SIP/01821985498_in-00000230”, “0?Set(REC_STATUS=NO)”) in new stack
[2016-09-22 11:06:08] VERBOSE[43984][C-000000cc] pbx.c: Executing [s@sub-record-check:14] GotoIf(“SIP/01821985498_in-00000230”, “2?checkaction”) in new stack
[2016-09-22 11:06:08] VERBOSE[43984][C-000000cc] pbx_builtins.c: Goto (sub-record-check,s,17)
[2016-09-22 11:06:08] VERBOSE[43984][C-000000cc] pbx.c: Executing [s@sub-record-check:17] GotoIf(“SIP/01821985498_in-00000230”, “1?sub-record-check,in,1”) in new stack
[2016-09-22 11:06:08] VERBOSE[43984][C-000000cc] pbx_builtins.c: Goto (sub-record-check,in,1)
[2016-09-22 11:06:08] VERBOSE[43984][C-000000cc] pbx.c: Executing [in@sub-record-check:1] NoOp(“SIP/01821985498_in-00000230”, “Inbound Recording Check to 0195090356”) in new stack
[2016-09-22 11:06:08] VERBOSE[43984][C-000000cc] pbx.c: Executing [in@sub-record-check:2] Set(“SIP/01821985498_in-00000230”, “FROMEXTEN=unknown”) in new stack
[2016-09-22 11:06:08] VERBOSE[43984][C-000000cc] pbx.c: Executing [in@sub-record-check:3] ExecIf(“SIP/01821985498_in-00000230”, “11?Set(FROMEXTEN=01821985421)”) in new stack
[2016-09-22 11:06:08] VERBOSE[43984][C-000000cc] pbx.c: Executing [in@sub-record-check:4] Gosub(“SIP/01821985498_in-00000230”, “recordcheck,1(yes,in,0195090356)”) in new stack
[2016-09-22 11:06:08] VERBOSE[43984][C-000000cc] pbx.c: Executing [recordcheck@sub-record-check:1] NoOp(“SIP/01821985498_in-00000230”, “Starting recording check against yes”) in new stack
[2016-09-22 11:06:08] VERBOSE[43984][C-000000cc] pbx.c: Executing [recordcheck@sub-record-check:2] Goto(“SIP/01821985498_in-00000230”, “yes”) in new stack
[2016-09-22 11:06:08] VERBOSE[43984][C-000000cc] pbx_builtins.c: Goto (sub-record-check,recordcheck,9)
[2016-09-22 11:06:08] VERBOSE[43984][C-000000cc] pbx.c: Executing [recordcheck@sub-record-check:9] ExecIf(“SIP/01821985498_in-00000230”, “0?Return()”) in new stack
[2016-09-22 11:06:08] VERBOSE[43984][C-000000cc] pbx.c: Executing [recordcheck@sub-record-check:10] Set(“SIP/01821985498_in-00000230”, “__REC_POLICY_MODE=YES”) in new stack
[2016-09-22 11:06:08] VERBOSE[43984][C-000000cc] pbx.c: Executing [recordcheck@sub-record-check:11] Goto(“SIP/01821985498_in-00000230”, “startrec”) in new stack
[2016-09-22 11:06:08] VERBOSE[43984][C-000000cc] pbx_builtins.c: Goto (sub-record-check,recordcheck,16)
[2016-09-22 11:06:08] VERBOSE[43984][C-000000cc] pbx.c: Executing [recordcheck@sub-record-check:16] NoOp(“SIP/01821985498_in-00000230”, “Starting recording: in, 0195090356”) in new stack
[2016-09-22 11:06:08] VERBOSE[43984][C-000000cc] pbx.c: Executing [recordcheck@sub-record-check:17] Set(“SIP/01821985498_in-00000230”, “AUDIOHOOK_INHERIT(MixMonitor)=yes”) in new stack
[2016-09-22 11:06:08] VERBOSE[43984][C-000000cc] pbx.c: Executing [recordcheck@sub-record-check:18] Set(“SIP/01821985498_in-00000230”, “__CALLFILENAME=in-0195090356-01821985421-20160922-110608-1474535168.682”) in new stack
[2016-09-22 11:06:08] VERBOSE[43984][C-000000cc] pbx.c: Executing [recordcheck@sub-record-check:19] MixMonitor(“SIP/01821985498_in-00000230”, “2016/09/22/in-0195090356-01821985421-20160922-110608-1474535168.682.wav,ai(LOCAL_MIXMON_ID),”) in new stack
[2016-09-22 11:06:08] VERBOSE[43984][C-000000cc] pbx.c: Executing [recordcheck@sub-record-check:20] Set(“SIP/01821985498_in-00000230”, “__MIXMON_ID=0x7fcc5151b1e0”) in new stack
[2016-09-22 11:06:08] VERBOSE[43984][C-000000cc] pbx.c: Executing [recordcheck@sub-record-check:21] Set(“SIP/01821985498_in-00000230”, “__RECORD_ID=SIP/01821985498_in-00000230”) in new stack
[2016-09-22 11:06:08] VERBOSE[43984][C-000000cc] pbx.c: Executing [recordcheck@sub-record-check:22] Set(“SIP/01821985498_in-00000230”, “__REC_STATUS=RECORDING”) in new stack
[2016-09-22 11:06:08] VERBOSE[43984][C-000000cc] pbx.c: Executing [recordcheck@sub-record-check:23] Set(“SIP/01821985498_in-00000230”, “CDR(recordingfile)=in-0195090356-01821985421-20160922-110608-1474535168.682.wav”) in new stack
[2016-09-22 11:06:08] VERBOSE[43984][C-000000cc] pbx.c: Executing [recordcheck@sub-record-check:24] Return(“SIP/01821985498_in-00000230”, “”) in new stack
[2016-09-22 11:06:08] VERBOSE[43984][C-000000cc] pbx.c: Executing [in@sub-record-check:5] Return(“SIP/01821985498_in-00000230”, “”) in new stack
[2016-09-22 11:06:08] VERBOSE[43984][C-000000cc] pbx.c: Executing [0195090356@from-pstn:3] Gosub(“SIP/01821985498_in-00000230”, “app-blacklist-check,s,1()”) in new stack
[2016-09-22 11:06:08] VERBOSE[43984][C-000000cc] pbx.c: Executing [s@app-blacklist-check:1] GotoIf(“SIP/01821985498_in-00000230”, “0?blacklisted”) in new stack
[2016-09-22 11:06:08] VERBOSE[43984][C-000000cc] pbx.c: Executing [s@app-blacklist-check:2] Set(“SIP/01821985498_in-00000230”, “CALLED_BLACKLIST=1”) in new stack
[2016-09-22 11:06:08] VERBOSE[43984][C-000000cc] pbx.c: Executing [s@app-blacklist-check:3] Return(“SIP/01821985498_in-00000230”, “”) in new stack
[2016-09-22 11:06:08] VERBOSE[43984][C-000000cc] pbx.c: Executing [0195090356@from-pstn:4] Set(“SIP/01821985498_in-00000230”, “__FROM_DID=0195090356”) in new stack
[2016-09-22 11:06:08] VERBOSE[43984][C-000000cc] pbx.c: Executing [0195090356@from-pstn:5] Set(“SIP/01821985498_in-00000230”, “CDR(did)=0195090356”) in new stack
[2016-09-22 11:06:08] VERBOSE[43984][C-000000cc] pbx.c: Executing [0195090356@from-pstn:6] ExecIf(“SIP/01821985498_in-00000230”, “1 ?Set(CALLERID(name)=01821985421)”) in new stack
[2016-09-22 11:06:08] VERBOSE[43984][C-000000cc] pbx.c: Executing [0195090356@from-pstn:7] Set(“SIP/01821985498_in-00000230”, “__MOHCLASS=”) in new stack
[2016-09-22 11:06:08] VERBOSE[43984][C-000000cc] pbx.c: Executing [0195090356@from-pstn:8] Set(“SIP/01821985498_in-00000230”, “__REVERSAL_REJECT=FALSE”) in new stack
[2016-09-22 11:06:08] VERBOSE[43984][C-000000cc] pbx.c: Executing [0195090356@from-pstn:9] GotoIf(“SIP/01821985498_in-00000230”, “1?post-reverse-charge”) in new stack
[2016-09-22 11:06:08] VERBOSE[43984][C-000000cc] pbx_builtins.c: Goto (from-pstn,0195090356,11)
[2016-09-22 11:06:08] VERBOSE[43984][C-000000cc] pbx.c: Executing [0195090356@from-pstn:11] NoOp(“SIP/01821985498_in-00000230”, “”) in new stack
[2016-09-22 11:06:08] VERBOSE[43984][C-000000cc] pbx.c: Executing [0195090356@from-pstn:12] Set(“SIP/01821985498_in-00000230”, “__CALLINGNAMEPRES_SV=allowed_not_screened”) in new stack
[2016-09-22 11:06:08] VERBOSE[43984][C-000000cc] pbx.c: Executing [0195090356@from-pstn:13] Set(“SIP/01821985498_in-00000230”, “__CALLINGNUMPRES_SV=allowed_not_screened”) in new stack
[2016-09-22 11:06:08] VERBOSE[43984][C-000000cc] pbx.c: Executing [0195090356@from-pstn:14] Set(“SIP/01821985498_in-00000230”, “CALLERID(name-pres)=allowed_not_screened”) in new stack
[2016-09-22 11:06:08] VERBOSE[43984][C-000000cc] pbx.c: Executing [0195090356@from-pstn:15] Set(“SIP/01821985498_in-00000230”, “CALLERID(num-pres)=allowed_not_screened”) in new stack
[2016-09-22 11:06:08] VERBOSE[43984][C-000000cc] pbx.c: Executing [0195090356@from-pstn:16] NoOp(“SIP/01821985498_in-00000230”, “CallerID Entry Point”) in new stack
[2016-09-22 11:06:08] VERBOSE[43984][C-000000cc] pbx.c: Executing [0195090356@from-pstn:17] Goto(“SIP/01821985498_in-00000230”, “app-daynight,2,1”) in new stack
[2016-09-22 11:06:08] VERBOSE[43984][C-000000cc] pbx_builtins.c: Goto (app-daynight,2,1)
[2016-09-22 11:06:08] VERBOSE[43984][C-000000cc] pbx.c: Executing [2@app-daynight:1] GotoIf(“SIP/01821985498_in-00000230”, “0?app-announcement-4,s,1:timeconditions,8,1”) in new stack
[2016-09-22 11:06:08] VERBOSE[43984][C-000000cc] pbx_builtins.c: Goto (timeconditions,8,1)
[2016-09-22 11:06:08] VERBOSE[43984][C-000000cc] pbx.c: Executing [8@timeconditions:1] Set(“SIP/01821985498_in-00000230”, “DB(TC/8/INUSESTATE)=INUSE”) in new stack
[2016-09-22 11:06:08] VERBOSE[43984][C-000000cc] pbx.c: Executing [8@timeconditions:2] Set(“SIP/01821985498_in-00000230”, “DB(TC/8/NOT_INUSESTATE)=NOT_INUSE”) in new stack
[2016-09-22 11:06:08] VERBOSE[43987][C-000000cc] app_mixmonitor.c: Begin MixMonitor Recording SIP/01821985498_in-00000230
[2016-09-22 11:06:08] VERBOSE[43984][C-000000cc] pbx.c: Executing [8@timeconditions:3] GotoIfTime(“SIP/01821985498_in-00000230”, “08:30-12:30,mon-fri,,,Europe/Rome?truestate”) in new stack
[2016-09-22 11:06:08] VERBOSE[43984][C-000000cc] pbx_builtins.c: Goto (timeconditions,8,15)
[2016-09-22 11:06:08] VERBOSE[43984][C-000000cc] pbx.c: Executing [8@timeconditions:15] GotoIf(“SIP/01821985498_in-00000230”, “0?falsegoto”) in new stack
[2016-09-22 11:06:08] VERBOSE[43984][C-000000cc] pbx.c: Executing [8@timeconditions:16] ExecIf(“SIP/01821985498_in-00000230”, “0?Set(DB(TC/8)=)”) in new stack
[2016-09-22 11:06:08] VERBOSE[43984][C-000000cc] pbx.c: Executing [8@timeconditions:17] Set(“SIP/01821985498_in-00000230”, “DEVICE_STATE(Custom:TC8)=NOT_INUSE”) in new stack
[2016-09-22 11:06:08] VERBOSE[43984][C-000000cc] pbx.c: Executing [8@timeconditions:18] ExecIf(“SIP/01821985498_in-00000230”, “0?Set(DEVICE_STATE(Custom:TCSTICKY)=INUSE)”) in new stack
[2016-09-22 11:06:08] VERBOSE[43984][C-000000cc] pbx.c: Executing [8@timeconditions:19] GotoIf(“SIP/01821985498_in-00000230”, “1?ivr-3,s,1”) in new stack
[2016-09-22 11:06:08] VERBOSE[43984][C-000000cc] pbx_builtins.c: Goto (ivr-3,s,1)
[2016-09-22 11:06:08] VERBOSE[43984][C-000000cc] pbx.c: Executing [s@ivr-3:1] Set(“SIP/01821985498_in-00000230”, “INVALID_LOOPCOUNT=0”) in new stack
[2016-09-22 11:06:08] VERBOSE[43984][C-000000cc] pbx.c: Executing [s@ivr-3:2] Set(“SIP/01821985498_in-00000230”, “_IVR_CONTEXT_ivr-3=”) in new stack
[2016-09-22 11:06:08] VERBOSE[43984][C-000000cc] pbx.c: Executing [s@ivr-3:3] Set(“SIP/01821985498_in-00000230”, “_IVR_CONTEXT=ivr-3”) in new stack
[2016-09-22 11:06:08] VERBOSE[43984][C-000000cc] pbx.c: Executing [s@ivr-3:4] Set(“SIP/01821985498_in-00000230”, “__IVR_RETVM=”) in new stack
[2016-09-22 11:06:08] VERBOSE[43984][C-000000cc] pbx.c: Executing [s@ivr-3:5] GotoIf(“SIP/01821985498_in-00000230”, “0?skip”) in new stack
[2016-09-22 11:06:08] VERBOSE[43984][C-000000cc] pbx.c: Executing [s@ivr-3:6] Answer(“SIP/01821985498_in-00000230”, “”) in new stack
[2016-09-22 11:06:08] NOTICE[1765] chan_sip.c: Received SIP subscribe for peer without mailbox: 32
[2016-09-22 11:06:08] VERBOSE[43984][C-000000cc] pbx.c: Executing [s@ivr-3:7] Wait(“SIP/01821985498_in-00000230”, “1”) in new stack
[2016-09-22 11:06:09] NOTICE[1765] chan_sip.c: Received SIP subscribe for peer without mailbox: 32
[2016-09-22 11:06:09] VERBOSE[43984][C-000000cc] pbx.c: Executing [s@ivr-3:8] Set(“SIP/01821985498_in-00000230”, “IVR_MSG=custom/5038-08-montato”) in new stack
[2016-09-22 11:06:09] VERBOSE[43984][C-000000cc] pbx.c: Executing [s@ivr-3:9] Set(“SIP/01821985498_in-00000230”, “TIMEOUT(digit)=3”) in new stack
[2016-09-22 11:06:09] VERBOSE[43984][C-000000cc] func_timeout.c: Digit timeout set to 3.000
[2016-09-22 11:06:09] VERBOSE[43984][C-000000cc] pbx.c: Executing [s@ivr-3:10] ExecIf(“SIP/01821985498_in-00000230”, “1?Background(custom/5038-08-montato)”) in new stack
[2016-09-22 11:06:09] VERBOSE[43984][C-000000cc] file.c: <SIP/01821985498_in-00000230> Playing ‘custom/5038-08-montato.slin’ (language ‘it’)
[2016-09-22 11:06:14] VERBOSE[43984][C-000000cc] pbx.c: Executing [1@ivr-3:1] Goto(“SIP/01821985498_in-00000230”, “ext-group,608,1”) in new stack
[2016-09-22 11:06:14] VERBOSE[43984][C-000000cc] pbx_builtins.c: Goto (ext-group,608,1)
[2016-09-22 11:06:14] VERBOSE[43984][C-000000cc] pbx.c: Executing [608@ext-group:1] GotoIf(“SIP/01821985498_in-00000230”, “0?cid”) in new stack
[2016-09-22 11:06:14] VERBOSE[43984][C-000000cc] pbx.c: Executing [608@ext-group:2] PlayTones(“SIP/01821985498_in-00000230”, “ring”) in new stack
[2016-09-22 11:06:14] VERBOSE[43984][C-000000cc] pbx.c: Executing [608@ext-group:3] Progress(“SIP/01821985498_in-00000230”, “”) in new stack
[2016-09-22 11:06:14] VERBOSE[43984][C-000000cc] pbx.c: Executing [608@ext-group:4] Macro(“SIP/01821985498_in-00000230”, “user-callerid,”) in new stack
[2016-09-22 11:06:14] VERBOSE[43984][C-000000cc] pbx.c: Executing [s@macro-user-callerid:1] Set(“SIP/01821985498_in-00000230”, “TOUCH_MONITOR=1474535168.682”) in new stack
[2016-09-22 11:06:14] VERBOSE[43984][C-000000cc] pbx.c: Executing [s@macro-user-callerid:2] Set(“SIP/01821985498_in-00000230”, “AMPUSER=01821985421”) in new stack
[2016-09-22 11:06:14] VERBOSE[43984][C-000000cc] pbx.c: Executing [s@macro-user-callerid:3] GotoIf(“SIP/01821985498_in-00000230”, “0?report”) in new stack
[2016-09-22 11:06:14] VERBOSE[43984][C-000000cc] pbx.c: Executing [s@macro-user-callerid:4] ExecIf(“SIP/01821985498_in-00000230”, “1?Set(REALCALLERIDNUM=01821985421)”) in new stack
[2016-09-22 11:06:14] VERBOSE[43984][C-000000cc] pbx.c: Executing [s@macro-user-callerid:5] Set(“SIP/01821985498_in-00000230”, “AMPUSER=”) in new stack
[2016-09-22 11:06:14] VERBOSE[43984][C-000000cc] pbx.c: Executing [s@macro-user-callerid:6] GotoIf(“SIP/01821985498_in-00000230”, “0?limit”) in new stack
[2016-09-22 11:06:14] VERBOSE[43984][C-000000cc] pbx.c: Executing [s@macro-user-callerid:7] Set(“SIP/01821985498_in-00000230”, “AMPUSERCIDNAME=”) in new stack
[2016-09-22 11:06:14] VERBOSE[43984][C-000000cc] pbx.c: Executing [s@macro-user-callerid:8] GotoIf(“SIP/01821985498_in-00000230”, “1?report”) in new stack
[2016-09-22 11:06:14] VERBOSE[43984][C-000000cc] pbx_builtins.c: Goto (macro-user-callerid,s,15)
[2016-09-22 11:06:14] VERBOSE[43984][C-000000cc] pbx.c: Executing [s@macro-user-callerid:15] GotoIf(“SIP/01821985498_in-00000230”, “0?continue”) in new stack
[2016-09-22 11:06:14] VERBOSE[43984][C-000000cc] pbx.c: Executing [s@macro-user-callerid:16] ExecIf(“SIP/01821985498_in-00000230”, “1?Set(__CALLEE_ACCOUNCODE=)”) in new stack
[2016-09-22 11:06:14] VERBOSE[43984][C-000000cc] pbx.c: Executing [s@macro-user-callerid:17] Set(“SIP/01821985498_in-00000230”, “__TTL=64”) in new stack
[2016-09-22 11:06:14] VERBOSE[43984][C-000000cc] pbx.c: Executing [s@macro-user-callerid:18] GotoIf(“SIP/01821985498_in-00000230”, “1?continue”) in new stack
[2016-09-22 11:06:14] VERBOSE[43984][C-000000cc] pbx_builtins.c: Goto (macro-user-callerid,s,29)
[2016-09-22 11:06:14] VERBOSE[43984][C-000000cc] pbx.c: Executing [s@macro-user-callerid:29] Set(“SIP/01821985498_in-00000230”, “CALLERID(number)=01821985421”) in new stack
[2016-09-22 11:06:14] VERBOSE[43984][C-000000cc] pbx.c: Executing [s@macro-user-callerid:30] Set(“SIP/01821985498_in-00000230”, “CALLERID(name)=01821985421”) in new stack
[2016-09-22 11:06:14] VERBOSE[43984][C-000000cc] pbx.c: Executing [s@macro-user-callerid:31] GotoIf(“SIP/01821985498_in-00000230”, “0?cnum”) in new stack
[2016-09-22 11:06:14] VERBOSE[43984][C-000000cc] pbx.c: Executing [s@macro-user-callerid:32] Set(“SIP/01821985498_in-00000230”, “CDR(cnam)=01821985421”) in new stack
[2016-09-22 11:06:14] VERBOSE[43984][C-000000cc] pbx.c: Executing [s@macro-user-callerid:33] Set(“SIP/01821985498_in-00000230”, “CDR(cnum)=01821985421”) in new stack
[2016-09-22 11:06:14] VERBOSE[43984][C-000000cc] pbx.c: Executing [s@macro-user-callerid:34] Set(“SIP/01821985498_in-00000230”, “CHANNEL(language)=it”) in new stack
[2016-09-22 11:06:14] VERBOSE[43984][C-000000cc] pbx.c: Executing [608@ext-group:5] Macro(“SIP/01821985498_in-00000230”, “blkvm-setifempty,”) in new stack
[2016-09-22 11:06:14] VERBOSE[43984][C-000000cc] pbx.c: Executing [s@macro-blkvm-setifempty:1] GotoIf(“SIP/01821985498_in-00000230”, “1?init”) in new stack
[2016-09-22 11:06:14] VERBOSE[43984][C-000000cc] pbx_builtins.c: Goto (macro-blkvm-setifempty,s,4)
[2016-09-22 11:06:14] VERBOSE[43984][C-000000cc] pbx.c: Executing [s@macro-blkvm-setifempty:4] Set(“SIP/01821985498_in-00000230”, “__BLKVM_CHANNEL=SIP/01821985498_in-00000230”) in new stack
[2016-09-22 11:06:14] VERBOSE[43984][C-000000cc] pbx.c: Executing [s@macro-blkvm-setifempty:5] Set(“SIP/01821985498_in-00000230”, “SHARED(BLKVM,SIP/01821985498_in-00000230)=TRUE”) in new stack
[2016-09-22 11:06:14] VERBOSE[43984][C-000000cc] pbx.c: Executing [s@macro-blkvm-setifempty:6] Set(“SIP/01821985498_in-00000230”, “GOSUB_RETVAL=TRUE”) in new stack
[2016-09-22 11:06:14] VERBOSE[43984][C-000000cc] pbx.c: Executing [s@macro-blkvm-setifempty:7] MacroExit(“SIP/01821985498_in-00000230”, “”) in new stack

3

[2016-09-22 11:06:14] VERBOSE[43984][C-000000cc] pbx.c: Executing [608@ext-group:6] GotoIf(“SIP/01821985498_in-00000230”, “1?skipov”) in new stack
[2016-09-22 11:06:14] VERBOSE[43984][C-000000cc] pbx_builtins.c: Goto (ext-group,608,9)
[2016-09-22 11:06:14] VERBOSE[43984][C-000000cc] pbx.c: Executing [608@ext-group:9] Set(“SIP/01821985498_in-00000230”, “RRNODEST=”) in new stack
[2016-09-22 11:06:14] VERBOSE[43984][C-000000cc] pbx.c: Executing [608@ext-group:10] Set(“SIP/01821985498_in-00000230”, “__NODEST=608”) in new stack
[2016-09-22 11:06:14] VERBOSE[43984][C-000000cc] pbx.c: Executing [608@ext-group:11] GosubIf(“SIP/01821985498_in-00000230”, “0?sub-rgsetcid,s,1()”) in new stack
[2016-09-22 11:06:14] VERBOSE[43984][C-000000cc] pbx.c: Executing [608@ext-group:12] Set(“SIP/01821985498_in-00000230”, “__PICKUPMARK=608”) in new stack
[2016-09-22 11:06:14] VERBOSE[43984][C-000000cc] pbx.c: Executing [608@ext-group:13] Gosub(“SIP/01821985498_in-00000230”, “sub-record-check,s,1(rg,608,dontcare)”) in new stack
[2016-09-22 11:06:14] VERBOSE[43984][C-000000cc] pbx.c: Executing [s@sub-record-check:1] GotoIf(“SIP/01821985498_in-00000230”, “11?initialized”) in new stack
[2016-09-22 11:06:14] VERBOSE[43984][C-000000cc] pbx_builtins.c: Goto (sub-record-check,s,10)
[2016-09-22 11:06:14] VERBOSE[43984][C-000000cc] pbx.c: Executing [s@sub-record-check:10] NoOp(“SIP/01821985498_in-00000230”, “Recordings initialized”) in new stack
[2016-09-22 11:06:14] VERBOSE[43984][C-000000cc] pbx.c: Executing [s@sub-record-check:11] ExecIf(“SIP/01821985498_in-00000230”, “0?Set(ARG3=dontcare)”) in new stack
[2016-09-22 11:06:14] VERBOSE[43984][C-000000cc] pbx.c: Executing [s@sub-record-check:12] Set(“SIP/01821985498_in-00000230”, “REC_POLICY_MODE_SAVE=YES”) in new stack
[2016-09-22 11:06:14] VERBOSE[43984][C-000000cc] pbx.c: Executing [s@sub-record-check:13] ExecIf(“SIP/01821985498_in-00000230”, “0?Set(REC_STATUS=NO)”) in new stack
[2016-09-22 11:06:14] VERBOSE[43984][C-000000cc] pbx.c: Executing [s@sub-record-check:14] GotoIf(“SIP/01821985498_in-00000230”, “2?checkaction”) in new stack
[2016-09-22 11:06:14] VERBOSE[43984][C-000000cc] pbx_builtins.c: Goto (sub-record-check,s,17)
[2016-09-22 11:06:14] VERBOSE[43984][C-000000cc] pbx.c: Executing [s@sub-record-check:17] GotoIf(“SIP/01821985498_in-00000230”, “0?sub-record-check,rg,1”) in new stack
[2016-09-22 11:06:14] VERBOSE[43984][C-000000cc] pbx.c: Executing [s@sub-record-check:18] NoOp(“SIP/01821985498_in-00000230”, “Generic rg Recording Check - 01821985421 608”) in new stack
[2016-09-22 11:06:14] VERBOSE[43984][C-000000cc] pbx.c: Executing [s@sub-record-check:19] Gosub(“SIP/01821985498_in-00000230”, “recordcheck,1(dontcare,rg,608)”) in new stack
[2016-09-22 11:06:14] VERBOSE[43984][C-000000cc] pbx.c: Executing [recordcheck@sub-record-check:1] NoOp(“SIP/01821985498_in-00000230”, “Starting recording check against dontcare”) in new stack
[2016-09-22 11:06:14] VERBOSE[43984][C-000000cc] pbx.c: Executing [recordcheck@sub-record-check:2] Goto(“SIP/01821985498_in-00000230”, “dontcare”) in new stack
[2016-09-22 11:06:14] VERBOSE[43984][C-000000cc] pbx_builtins.c: Goto (sub-record-check,recordcheck,3)
[2016-09-22 11:06:14] VERBOSE[43984][C-000000cc] pbx.c: Executing [recordcheck@sub-record-check:3] Return(“SIP/01821985498_in-00000230”, “”) in new stack
[2016-09-22 11:06:14] VERBOSE[43984][C-000000cc] pbx.c: Executing [s@sub-record-check:20] Return(“SIP/01821985498_in-00000230”, “”) in new stack
[2016-09-22 11:06:14] VERBOSE[43984][C-000000cc] pbx.c: Executing [608@ext-group:14] Set(“SIP/01821985498_in-00000230”, “RingGroupMethod=ringall”) in new stack
[2016-09-22 11:06:14] VERBOSE[43984][C-000000cc] pbx.c: Executing [608@ext-group:15] Macro(“SIP/01821985498_in-00000230”, “dial,15,Ttr,67-68”) in new stack
[2016-09-22 11:06:14] VERBOSE[43984][C-000000cc] pbx.c: Executing [s@macro-dial:1] NoOp(“SIP/01821985498_in-00000230”, "Blind Transfer: , Attended Transfer: , User: , Alert Info: ") in new stack
[2016-09-22 11:06:14] VERBOSE[43984][C-000000cc] pbx.c: Executing [s@macro-dial:2] ExecIf(“SIP/01821985498_in-00000230”, “0?Set(ALERT_INFO=)”) in new stack
[2016-09-22 11:06:14] VERBOSE[43984][C-000000cc] pbx.c: Executing [s@macro-dial:3] ExecIf(“SIP/01821985498_in-00000230”, “0?Set(ALERT_INFO=)”) in new stack
[2016-09-22 11:06:14] VERBOSE[43984][C-000000cc] pbx.c: Executing [s@macro-dial:4] ExecIf(“SIP/01821985498_in-00000230”, “0?Set(ALERT_INFO=)”) in new stack
[2016-09-22 11:06:14] VERBOSE[43984][C-000000cc] pbx.c: Executing [s@macro-dial:5] ExecIf(“SIP/01821985498_in-00000230”, “0?Set(CHANNEL(musicclass)=)”) in new stack
[2016-09-22 11:06:14] VERBOSE[43984][C-000000cc] pbx.c: Executing [s@macro-dial:6] AGI(“SIP/01821985498_in-00000230”, “dialparties.agi”) in new stack
[2016-09-22 11:06:14] VERBOSE[43984][C-000000cc] res_agi.c: Launched AGI Script /var/lib/asterisk/agi-bin/dialparties.agi
[2016-09-22 11:06:14] VERBOSE[43984][C-000000cc] res_agi.c: dialparties.agi: Starting New Dialparties.agi
[2016-09-22 11:06:14] VERBOSE[43984][C-000000cc] res_agi.c: dialparties.agi: Caller ID name is ‘01821985421’ number is ‘01821985421’
[2016-09-22 11:06:14] VERBOSE[43984][C-000000cc] res_agi.c: dialparties.agi: Methodology of ring is ‘ringall’
[2016-09-22 11:06:14] VERBOSE[43984][C-000000cc] res_agi.c: dialparties.agi: Added extension 67 to extension map
[2016-09-22 11:06:14] VERBOSE[43984][C-000000cc] res_agi.c: dialparties.agi: Added extension 68 to extension map
[2016-09-22 11:06:14] VERBOSE[43984][C-000000cc] res_agi.c: dialparties.agi: Extension 67 cf is disabled
[2016-09-22 11:06:14] VERBOSE[43984][C-000000cc] res_agi.c: dialparties.agi: Extension 68 cf is disabled
[2016-09-22 11:06:14] VERBOSE[43984][C-000000cc] res_agi.c: dialparties.agi: Extension 67 do not disturb is disabled
[2016-09-22 11:06:14] VERBOSE[43984][C-000000cc] res_agi.c: dialparties.agi: Extension 68 do not disturb is disabled
[2016-09-22 11:06:14] VERBOSE[43984][C-000000cc] res_agi.c: dialparties.agi: EXTENSION_STATE: 0 (NOT_INUSE)
[2016-09-22 11:06:14] VERBOSE[43984][C-000000cc] res_agi.c: dialparties.agi: Extension 67 has ExtensionState: 0
[2016-09-22 11:06:14] VERBOSE[43984][C-000000cc] res_agi.c: dialparties.agi: Checking CW and CFB status for extension 67
[2016-09-22 11:06:14] VERBOSE[43984][C-000000cc] res_agi.c: dialparties.agi: dbset CALLTRACE/67 to 01821985421
[2016-09-22 11:06:14] VERBOSE[43984][C-000000cc] res_agi.c: dialparties.agi: EXTENSION_STATE: 0 (NOT_INUSE)
[2016-09-22 11:06:14] VERBOSE[43984][C-000000cc] res_agi.c: dialparties.agi: Extension 68 has ExtensionState: 0
[2016-09-22 11:06:14] VERBOSE[43984][C-000000cc] res_agi.c: dialparties.agi: Checking CW and CFB status for extension 68
[2016-09-22 11:06:14] VERBOSE[43984][C-000000cc] res_agi.c: dialparties.agi: dbset CALLTRACE/68 to 01821985421
[2016-09-22 11:06:14] VERBOSE[43984][C-000000cc] res_agi.c: dialparties.agi: Filtered ARG3: 67-68
[2016-09-22 11:06:14] VERBOSE[43984][C-000000cc] res_agi.c: <SIP/01821985498_in-00000230>AGI Script dialparties.agi completed, returning 0
[2016-09-22 11:06:14] VERBOSE[43984][C-000000cc] pbx.c: Executing [s@macro-dial:9] NoOp(“SIP/01821985498_in-00000230”, “Returned from dialparties with groups to dial”) in new stack
[2016-09-22 11:06:14] VERBOSE[43984][C-000000cc] pbx.c: Executing [s@macro-dial:10] Set(“SIP/01821985498_in-00000230”, “LOOPCNT=2”) in new stack
[2016-09-22 11:06:14] VERBOSE[43984][C-000000cc] pbx.c: Executing [s@macro-dial:11] Set(“SIP/01821985498_in-00000230”, “ITER=1”) in new stack
[2016-09-22 11:06:14] VERBOSE[43984][C-000000cc] pbx.c: Executing [s@macro-dial:12] Set(“SIP/01821985498_in-00000230”, “EXTTOCALL=67”) in new stack
[2016-09-22 11:06:14] VERBOSE[43984][C-000000cc] pbx.c: Executing [s@macro-dial:13] NoOp(“SIP/01821985498_in-00000230”, “Working with 67”) in new stack
[2016-09-22 11:06:14] VERBOSE[43984][C-000000cc] pbx.c: Executing [s@macro-dial:14] Set(“SIP/01821985498_in-00000230”, “ITER=2”) in new stack
[2016-09-22 11:06:14] VERBOSE[43984][C-000000cc] pbx.c: Executing [s@macro-dial:15] GotoIf(“SIP/01821985498_in-00000230”, “1?ndloopbegin”) in new stack
[2016-09-22 11:06:14] VERBOSE[43984][C-000000cc] pbx_builtins.c: Goto (macro-dial,s,12)
[2016-09-22 11:06:14] VERBOSE[43984][C-000000cc] pbx.c: Executing [s@macro-dial:12] Set(“SIP/01821985498_in-00000230”, “EXTTOCALL=68”) in new stack
[2016-09-22 11:06:14] VERBOSE[43984][C-000000cc] pbx.c: Executing [s@macro-dial:13] NoOp(“SIP/01821985498_in-00000230”, “Working with 68”) in new stack
[2016-09-22 11:06:14] VERBOSE[43984][C-000000cc] pbx.c: Executing [s@macro-dial:14] Set(“SIP/01821985498_in-00000230”, “ITER=3”) in new stack
[2016-09-22 11:06:14] VERBOSE[43984][C-000000cc] pbx.c: Executing [s@macro-dial:15] GotoIf(“SIP/01821985498_in-00000230”, “0?ndloopbegin”) in new stack
[2016-09-22 11:06:14] VERBOSE[43984][C-000000cc] pbx.c: Executing [s@macro-dial:16] Macro(“SIP/01821985498_in-00000230”, “dial-ringall-predial-hook,”) in new stack
[2016-09-22 11:06:14] VERBOSE[43984][C-000000cc] pbx.c: Executing [s@macro-dial-ringall-predial-hook:1] MacroExit(“SIP/01821985498_in-00000230”, “”) in new stack
[2016-09-22 11:06:14] VERBOSE[43984][C-000000cc] pbx.c: Executing [s@macro-dial:17] Dial(“SIP/01821985498_in-00000230”, “SIP/67&SIP/68,15,trM(auto-blkvm)b(func-apply-sipheaders^s^1),”) in new stack
[2016-09-22 11:06:14] VERBOSE[43984][C-000000cc] netsock2.c: Using SIP RTP CoS mark 5
[2016-09-22 11:06:14] VERBOSE[43984][C-000000cc] netsock2.c: Using SIP RTP CoS mark 5
[2016-09-22 11:06:14] VERBOSE[43984][C-000000cc] app_stack.c: SIP/67-00000231 Internal Gosub(func-apply-sipheaders,s,1) start
[2016-09-22 11:06:14] VERBOSE[43984][C-000000cc] pbx.c: Executing [s@func-apply-sipheaders:1] NoOp(“SIP/67-00000231”, “Applying SIP Headers to channel”) in new stack
[2016-09-22 11:06:14] VERBOSE[43984][C-000000cc] pbx.c: Executing [s@func-apply-sipheaders:2] Set(“SIP/67-00000231”, “SIPHEADERKEYS=”) in new stack
[2016-09-22 11:06:14] VERBOSE[43984][C-000000cc] pbx.c: Executing [s@func-apply-sipheaders:3] While(“SIP/67-00000231”, “0”) in new stack
[2016-09-22 11:06:14] VERBOSE[43984][C-000000cc] app_while.c: Jumping to priority 6
[2016-09-22 11:06:14] VERBOSE[43984][C-000000cc] pbx.c: Executing [s@func-apply-sipheaders:7] Return(“SIP/67-00000231”, “”) in new stack
[2016-09-22 11:06:14] VERBOSE[43984][C-000000cc] app_stack.c: Spawn extension (from-internal, 608, 1) exited non-zero on ‘SIP/67-00000231’
[2016-09-22 11:06:14] VERBOSE[43984][C-000000cc] app_stack.c: SIP/67-00000231 Internal Gosub(func-apply-sipheaders,s,1) complete GOSUB_RETVAL=
[2016-09-22 11:06:14] VERBOSE[43984][C-000000cc] app_stack.c: SIP/68-00000232 Internal Gosub(func-apply-sipheaders,s,1) start
[2016-09-22 11:06:14] VERBOSE[43984][C-000000cc] pbx.c: Executing [s@func-apply-sipheaders:1] NoOp(“SIP/68-00000232”, “Applying SIP Headers to channel”) in new stack
[2016-09-22 11:06:14] VERBOSE[43984][C-000000cc] pbx.c: Executing [s@func-apply-sipheaders:2] Set(“SIP/68-00000232”, “SIPHEADERKEYS=”) in new stack
[2016-09-22 11:06:14] VERBOSE[43984][C-000000cc] pbx.c: Executing [s@func-apply-sipheaders:3] While(“SIP/68-00000232”, “0”) in new stack
[2016-09-22 11:06:14] VERBOSE[43984][C-000000cc] app_while.c: Jumping to priority 6
[2016-09-22 11:06:14] VERBOSE[43984][C-000000cc] pbx.c: Executing [s@func-apply-sipheaders:7] Return(“SIP/68-00000232”, “”) in new stack
[2016-09-22 11:06:14] VERBOSE[43984][C-000000cc] app_stack.c: Spawn extension (from-internal, 608, 1) exited non-zero on ‘SIP/68-00000232’
[2016-09-22 11:06:14] VERBOSE[43984][C-000000cc] app_stack.c: SIP/68-00000232 Internal Gosub(func-apply-sipheaders,s,1) complete GOSUB_RETVAL=
[2016-09-22 11:06:14] VERBOSE[43984][C-000000cc] app_dial.c: Called SIP/67
[2016-09-22 11:06:14] VERBOSE[43984][C-000000cc] app_dial.c: Called SIP/68
[2016-09-22 11:06:14] VERBOSE[43984][C-000000cc] app_dial.c: SIP/68-00000232 connected line has changed. Saving it until answer for SIP/01821985498_in-00000230
[2016-09-22 11:06:14] VERBOSE[43984][C-000000cc] app_dial.c: SIP/67-00000231 connected line has changed. Saving it until answer for SIP/01821985498_in-00000230
[2016-09-22 11:06:14] VERBOSE[43984][C-000000cc] app_dial.c: SIP/67-00000231 is ringing
[2016-09-22 11:06:14] VERBOSE[43984][C-000000cc] app_dial.c: SIP/68-00000232 is ringing
[2016-09-22 11:06:16] NOTICE[1765] chan_sip.c: Received SIP subscribe for peer without mailbox: 34
[2016-09-22 11:06:17] NOTICE[1765] chan_sip.c: Received SIP subscribe for peer without mailbox: 34
[2016-09-22 11:06:23] NOTICE[1765] chan_sip.c: Received SIP subscribe for peer without mailbox: 33
[2016-09-22 11:06:25] NOTICE[1765] chan_sip.c: Received SIP subscribe for peer without mailbox: 33
[2016-09-22 11:06:25] NOTICE[1765] chan_sip.c: Received SIP subscribe for peer without mailbox: 27
[2016-09-22 11:06:26] NOTICE[1765] chan_sip.c: Received SIP subscribe for peer without mailbox: 27
[2016-09-22 11:06:29] VERBOSE[43984][C-000000cc] app_dial.c: Nobody picked up in 15000 ms
[2016-09-22 11:06:29] VERBOSE[43984][C-000000cc] pbx.c: Executing [s@macro-dial:18] Set(“SIP/01821985498_in-00000230”, “DIALSTATUS=NOANSWER”) in new stack
[2016-09-22 11:06:29] VERBOSE[43984][C-000000cc] pbx.c: Executing [s@macro-dial:19] GosubIf(“SIP/01821985498_in-00000230”, “0?NOANSWER,1()”) in new stack
[2016-09-22 11:06:29] VERBOSE[43984][C-000000cc] pbx.c: Executing [s@macro-dial:20] NoOp(“SIP/01821985498_in-00000230”, “Returning since nobody answered”) in new stack
[2016-09-22 11:06:29] VERBOSE[43984][C-000000cc] pbx.c: Executing [s@macro-dial:21] MacroExit(“SIP/01821985498_in-00000230”, “”) in new stack
[2016-09-22 11:06:29] VERBOSE[43984][C-000000cc] pbx.c: Executing [608@ext-group:16] Gosub(“SIP/01821985498_in-00000230”, “sub-record-cancel,s,1()”) in new stack
[2016-09-22 11:06:29] VERBOSE[43984][C-000000cc] pbx.c: Executing [s@sub-record-cancel:1] Return(“SIP/01821985498_in-00000230”, “”) in new stack
[2016-09-22 11:06:29] VERBOSE[43984][C-000000cc] pbx.c: Executing [608@ext-group:17] Set(“SIP/01821985498_in-00000230”, “RingGroupMethod=”) in new stack
[2016-09-22 11:06:29] VERBOSE[43984][C-000000cc] pbx.c: Executing [608@ext-group:18] GotoIf(“SIP/01821985498_in-00000230”, “0?nodest”) in new stack
[2016-09-22 11:06:29] VERBOSE[43984][C-000000cc] pbx.c: Executing [608@ext-group:19] Set(“SIP/01821985498_in-00000230”, “__PICKUPMARK=”) in new stack
[2016-09-22 11:06:29] VERBOSE[43984][C-000000cc] pbx.c: Executing [608@ext-group:20] Set(“SIP/01821985498_in-00000230”, “__NODEST=”) in new stack
[2016-09-22 11:06:29] VERBOSE[43984][C-000000cc] pbx.c: Executing [608@ext-group:21] Macro(“SIP/01821985498_in-00000230”, “blkvm-clr,”) in new stack
[2016-09-22 11:06:29] VERBOSE[43984][C-000000cc] pbx.c: Executing [s@macro-blkvm-clr:1] Set(“SIP/01821985498_in-00000230”, “SHARED(BLKVM,SIP/01821985498_in-00000230)=”) in new stack
[2016-09-22 11:06:29] VERBOSE[43984][C-000000cc] pbx.c: Executing [s@macro-blkvm-clr:2] Set(“SIP/01821985498_in-00000230”, “GOSUB_RETVAL=”) in new stack
[2016-09-22 11:06:29] VERBOSE[43984][C-000000cc] pbx.c: Executing [s@macro-blkvm-clr:3] MacroExit(“SIP/01821985498_in-00000230”, “”) in new stack
[2016-09-22 11:06:29] VERBOSE[43984][C-000000cc] pbx.c: Executing [608@ext-group:22] Goto(“SIP/01821985498_in-00000230”, “ext-miscdests,1,1”) in new stack
[2016-09-22 11:06:29] VERBOSE[43984][C-000000cc] pbx_builtins.c: Goto (ext-miscdests,1,1)
[2016-09-22 11:06:29] VERBOSE[43984][C-000000cc] pbx.c: Executing [1@ext-miscdests:1] NoOp(“SIP/01821985498_in-00000230”, “MiscDest: Deviazione Cairo”) in new stack
[2016-09-22 11:06:29] VERBOSE[43984][C-000000cc] pbx.c: Executing [1@ext-miscdests:2] Goto(“SIP/01821985498_in-00000230”, “from-internal,3383033927,1”) in new stack
[2016-09-22 11:06:29] VERBOSE[43984][C-000000cc] pbx_builtins.c: Goto (from-internal,3383033927,1)
[2016-09-22 11:06:29] VERBOSE[43984][C-000000cc] pbx.c: Executing [3383033927@from-internal:1] Macro(“SIP/01821985498_in-00000230”, “user-callerid,LIMIT,EXTERNAL,”) in new stack
[2016-09-22 11:06:29] VERBOSE[43984][C-000000cc] pbx.c: Executing [s@macro-user-callerid:1] Set(“SIP/01821985498_in-00000230”, “TOUCH_MONITOR=1474535168.682”) in new stack
[2016-09-22 11:06:29] VERBOSE[43984][C-000000cc] pbx.c: Executing [s@macro-user-callerid:2] Set(“SIP/01821985498_in-00000230”, “AMPUSER=01821985421”) in new stack
[2016-09-22 11:06:29] VERBOSE[43984][C-000000cc] pbx.c: Executing [s@macro-user-callerid:3] GotoIf(“SIP/01821985498_in-00000230”, “0?report”) in new stack
[2016-09-22 11:06:29] VERBOSE[43984][C-000000cc] pbx.c: Executing [s@macro-user-callerid:4] ExecIf(“SIP/01821985498_in-00000230”, “0?Set(REALCALLERIDNUM=01821985421)”) in new stack
[2016-09-22 11:06:29] VERBOSE[43984][C-000000cc] pbx.c: Executing [s@macro-user-callerid:5] Set(“SIP/01821985498_in-00000230”, “AMPUSER=”) in new stack
[2016-09-22 11:06:29] VERBOSE[43984][C-000000cc] pbx.c: Executing [s@macro-user-callerid:6] GotoIf(“SIP/01821985498_in-00000230”, “0?limit”) in new stack
[2016-09-22 11:06:29] VERBOSE[43984][C-000000cc] pbx.c: Executing [s@macro-user-callerid:7] Set(“SIP/01821985498_in-00000230”, “AMPUSERCIDNAME=”) in new stack
[2016-09-22 11:06:29] VERBOSE[43984][C-000000cc] pbx.c: Executing [s@macro-user-callerid:8] GotoIf(“SIP/01821985498_in-00000230”, “1?report”) in new stack
[2016-09-22 11:06:29] VERBOSE[43984][C-000000cc] pbx_builtins.c: Goto (macro-user-callerid,s,15)
[2016-09-22 11:06:29] VERBOSE[43984][C-000000cc] pbx.c: Executing [s@macro-user-callerid:15] GotoIf(“SIP/01821985498_in-00000230”, “1?continue”) in new stack
[2016-09-22 11:06:29] VERBOSE[43984][C-000000cc] pbx_builtins.c: Goto (macro-user-callerid,s,29)
[2016-09-22 11:06:29] VERBOSE[43984][C-000000cc] pbx.c: Executing [s@macro-user-callerid:29] Set(“SIP/01821985498_in-00000230”, “CALLERID(number)=01821985421”) in new stack
[2016-09-22 11:06:29] VERBOSE[43984][C-000000cc] pbx.c: Executing [s@macro-user-callerid:30] Set(“SIP/01821985498_in-00000230”, “CALLERID(name)=01821985421”) in new stack
[2016-09-22 11:06:29] VERBOSE[43984][C-000000cc] pbx.c: Executing [s@macro-user-callerid:31] GotoIf(“SIP/01821985498_in-00000230”, “0?cnum”) in new stack
[2016-09-22 11:06:29] VERBOSE[43984][C-000000cc] pbx.c: Executing [s@macro-user-callerid:32] Set(“SIP/01821985498_in-00000230”, “CDR(cnam)=01821985421”) in new stack
[2016-09-22 11:06:29] VERBOSE[43984][C-000000cc] pbx.c: Executing [s@macro-user-callerid:33] Set(“SIP/01821985498_in-00000230”, “CDR(cnum)=01821985421”) in new stack
[2016-09-22 11:06:29] VERBOSE[43984][C-000000cc] pbx.c: Executing [s@macro-user-callerid:34] Set(“SIP/01821985498_in-00000230”, “CHANNEL(language)=it”) in new stack
[2016-09-22 11:06:29] VERBOSE[43984][C-000000cc] pbx.c: Executing [3383033927@from-internal:2] NoCDR(“SIP/01821985498_in-00000230”, “”) in new stack
[2016-09-22 11:06:29] VERBOSE[43984][C-000000cc] pbx.c: Executing [3383033927@from-internal:3] Progress(“SIP/01821985498_in-00000230”, “”) in new stack
[2016-09-22 11:06:29] VERBOSE[43984][C-000000cc] pbx.c: Executing [3383033927@from-internal:4] Wait(“SIP/01821985498_in-00000230”, “1”) in new stack
[2016-09-22 11:06:30] VERBOSE[43984][C-000000cc] pbx.c: Executing [3383033927@from-internal:5] Playback(“SIP/01821985498_in-00000230”, “silence/1&cannot-complete-as-dialed&check-number-dial-again,noanswer”) in new stack
[2016-09-22 11:06:30] VERBOSE[43984][C-000000cc] file.c: <SIP/01821985498_in-00000230> Playing ‘silence/1.alaw’ (language ‘it’)
[2016-09-22 11:06:31] VERBOSE[43984][C-000000cc] file.c: <SIP/01821985498_in-00000230> Playing ‘cannot-complete-as-dialed.alaw’ (language ‘it’)
[2016-09-22 11:06:35] VERBOSE[43984][C-000000cc] file.c: <SIP/01821985498_in-00000230> Playing ‘check-number-dial-again.alaw’ (language ‘it’)
[2016-09-22 11:06:36] VERBOSE[43984][C-000000cc] pbx.c: Executing [h@from-internal:1] Macro(“SIP/01821985498_in-00000230”, “hangupcall”) in new stack
[2016-09-22 11:06:36] VERBOSE[43984][C-000000cc] pbx.c: Executing [s@macro-hangupcall:1] GotoIf(“SIP/01821985498_in-00000230”, “1?theend”) in new stack
[2016-09-22 11:06:36] VERBOSE[43984][C-000000cc] pbx_builtins.c: Goto (macro-hangupcall,s,3)
[2016-09-22 11:06:36] VERBOSE[43984][C-000000cc] pbx.c: Executing [s@macro-hangupcall:3] ExecIf(“SIP/01821985498_in-00000230”, “0?Set(CDR(recordingfile)=)”) in new stack
[2016-09-22 11:06:36] VERBOSE[43984][C-000000cc] pbx.c: Executing [s@macro-hangupcall:4] Hangup(“SIP/01821985498_in-00000230”, “”) in new stack
[2016-09-22 11:06:36] VERBOSE[43984][C-000000cc] app_macro.c: Spawn extension (macro-hangupcall, s, 4) exited non-zero on ‘SIP/01821985498_in-00000230’ in macro ‘hangupcall’
[2016-09-22 11:06:36] VERBOSE[43984][C-000000cc] pbx.c: Spawn extension (from-internal, h, 1) exited non-zero on ‘SIP/01821985498_in-00000230’

4

--------------------------- please check the Deviazione Cairo, make sure it works as normal.
Unable to build dialplan routing - invalid license – this error maybe come from digium phone module, you can ignore or disable that if you do not buy the module.

5

found was a problem with outbound course

practically:

The call comes in …

time passes and tries to forward it to the destination …

but coming to outgoing routes does not know how to release the caller id and because the caller id is the caller id of incoming call … !!!

I DID NOT THINK ABOUT IT!

I appropriately modified routes outgoing and so now it works!