This is the log file with verbosity 5:
[quote][2015-04-30 11:41:07] VERBOSE[1894] chan_iax2.c: – Accepting UNAUTHENTICATED call from 192.168.14.221:
ü – > requested format = ulaw,
ü – > requested prefs = (ulaw|slin|alaw),
ü – > actual format = ulaw,
ü – > host prefs = (gsm|ulaw|alaw|h263),
ü – > priority = mine
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] pbx.c: – Executing [14000@from-pstn:1] Goto(“IAX2/acege14-66”, “ext-did,14000,1”) in new stack
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] pbx.c: – Goto (ext-did,14000,1)
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] pbx.c: – Executing [14000@ext-did:1] Set(“IAX2/acege14-66”, “__FROM_DID=14000”) in new stack
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] pbx.c: – Executing [14000@ext-did:2] Set(“IAX2/acege14-66”, “CDR(did)=14000”) in new stack
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] pbx.c: – Executing [14000@ext-did:3] ExecIf(“IAX2/acege14-66”, “0 ?Set(CALLERID(name)=3491331316)”) in new stack
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] pbx.c: – Executing [14000@ext-did:4] Set(“IAX2/acege14-66”, “CHANNEL(musicclass)=default”) in new stack
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] pbx.c: – Executing [14000@ext-did:5] Set(“IAX2/acege14-66”, “__MOHCLASS=default”) in new stack
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] pbx.c: – Executing [14000@ext-did:6] Set(“IAX2/acege14-66”, “__CALLINGPRES_SV=allowed_not_screened”) in new stack
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] pbx.c: – Executing [14000@ext-did:7] Set(“IAX2/acege14-66”, “CALLERPRES()=allowed_not_screened”) in new stack
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] pbx.c: – Executing [14000@ext-did:8] Goto(“IAX2/acege14-66”, “timeconditions,1,1”) in new stack
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] pbx.c: – Goto (timeconditions,1,1)
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] pbx.c: – Executing [1@timeconditions:1] GotoIfTime(“IAX2/acege14-66”, “14:00-18:00,mon-fri,1-31,jan-dec?truestate”) in new stack
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] pbx.c: – Executing [1@timeconditions:2] GotoIfTime(“IAX2/acege14-66”, “08:00-12:00,mon-fri,1-31,jan-dec?truestate”) in new stack
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] pbx.c: – Goto (timeconditions,1,10)
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] pbx.c: – Executing [1@timeconditions:10] GotoIf(“IAX2/acege14-66”, “0?falsegoto”) in new stack
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] pbx.c: – Executing [1@timeconditions:11] ExecIf(“IAX2/acege14-66”, “0?Set(DB(TC/1)=)”) in new stack
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] pbx.c: – Executing [1@timeconditions:12] Set(“IAX2/acege14-66”, “DEVICE_STATE(Custom:TC1)=NOT_INUSE”) in new stack
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] pbx.c: – Executing [1@timeconditions:13] ExecIf(“IAX2/acege14-66”, “0?Set(DEVICE_STATE(Custom:TCSTICKY)=INUSE)”) in new stack
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] pbx.c: – Executing [1@timeconditions:14] GotoIf(“IAX2/acege14-66”, “1?ext-group,14001,1”) in new stack
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] pbx.c: – Goto (ext-group,14001,1)
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] pbx.c: – Executing [14001@ext-group:1] Macro(“IAX2/acege14-66”, “user-callerid,”) in new stack
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] pbx.c: – Executing [s@macro-user-callerid:1] Set(“IAX2/acege14-66”, “TOUCH_MONITOR=1430386867.78”) in new stack
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] pbx.c: – Executing [s@macro-user-callerid:2] Set(“IAX2/acege14-66”, “AMPUSER=3491331316”) in new stack
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] pbx.c: – Executing [s@macro-user-callerid:3] GotoIf(“IAX2/acege14-66”, “0?report”) in new stack
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] pbx.c: – Executing [s@macro-user-callerid:4] ExecIf(“IAX2/acege14-66”, “1?Set(REALCALLERIDNUM=3491331316)”) in new stack
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] pbx.c: – Executing [s@macro-user-callerid:5] Set(“IAX2/acege14-66”, “AMPUSER=”) in new stack
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] pbx.c: – Executing [s@macro-user-callerid:6] GotoIf(“IAX2/acege14-66”, “0?limit”) in new stack
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] pbx.c: – Executing [s@macro-user-callerid:7] Set(“IAX2/acege14-66”, “AMPUSERCIDNAME=”) in new stack
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] pbx.c: – Executing [s@macro-user-callerid:8] GotoIf(“IAX2/acege14-66”, “1?report”) in new stack
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] pbx.c: – Goto (macro-user-callerid,s,15)
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] pbx.c: – Executing [s@macro-user-callerid:15] GotoIf(“IAX2/acege14-66”, “0?continue”) in new stack
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] pbx.c: – Executing [s@macro-user-callerid:16] ExecIf(“IAX2/acege14-66”, “1?Set(__CALLEE_ACCOUNCODE=)”) in new stack
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] pbx.c: – Executing [s@macro-user-callerid:17] Set(“IAX2/acege14-66”, “__TTL=64”) in new stack
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] pbx.c: – Executing [s@macro-user-callerid:18] GotoIf(“IAX2/acege14-66”, “1?continue”) in new stack
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] pbx.c: – Goto (macro-user-callerid,s,29)
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] pbx.c: – Executing [s@macro-user-callerid:29] Set(“IAX2/acege14-66”, “CALLERID(number)=3491331316”) in new stack
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] pbx.c: – Executing [s@macro-user-callerid:30] Set(“IAX2/acege14-66”, “CALLERID(name)=orgdab”) in new stack
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] pbx.c: – Executing [s@macro-user-callerid:31] Set(“IAX2/acege14-66”, “CDR(cnum)=3491331316”) in new stack
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] pbx.c: – Executing [s@macro-user-callerid:32] Set(“IAX2/acege14-66”, “CDR(cnam)=orgdab”) in new stack
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] pbx.c: – Executing [s@macro-user-callerid:33] Set(“IAX2/acege14-66”, “CHANNEL(language)=it”) in new stack
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] pbx.c: – Executing [14001@ext-group:2] Macro(“IAX2/acege14-66”, “blkvm-setifempty,”) in new stack
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] pbx.c: – Executing [s@macro-blkvm-setifempty:1] GotoIf(“IAX2/acege14-66”, “1?init”) in new stack
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] pbx.c: – Goto (macro-blkvm-setifempty,s,4)
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] pbx.c: – Executing [s@macro-blkvm-setifempty:4] Set(“IAX2/acege14-66”, “__BLKVM_CHANNEL=IAX2/acege14-66”) in new stack
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] pbx.c: – Executing [s@macro-blkvm-setifempty:5] Set(“IAX2/acege14-66”, “SHARED(BLKVM,IAX2/acege14-66)=TRUE”) in new stack
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] pbx.c: – Executing [s@macro-blkvm-setifempty:6] Set(“IAX2/acege14-66”, “GOSUB_RETVAL=TRUE”) in new stack
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] pbx.c: – Executing [s@macro-blkvm-setifempty:7] MacroExit(“IAX2/acege14-66”, “”) in new stack
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] pbx.c: – Executing [14001@ext-group:3] GotoIf(“IAX2/acege14-66”, “1?skipov”) in new stack
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] pbx.c: – Goto (ext-group,14001,6)
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] pbx.c: – Executing [14001@ext-group:6] Set(“IAX2/acege14-66”, “RRNODEST=”) in new stack
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] pbx.c: – Executing [14001@ext-group:7] Set(“IAX2/acege14-66”, “__NODEST=14001”) in new stack
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] pbx.c: – Executing [14001@ext-group:8] GosubIf(“IAX2/acege14-66”, “0?sub-rgsetcid,s,1()”) in new stack
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] pbx.c: – Executing [14001@ext-group:9] Gosub(“IAX2/acege14-66”, “sub-record-check,s,1(rg,14001,dontcare)”) in new stack
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] pbx.c: – Executing [s@sub-record-check:1] Set(“IAX2/acege14-66”, “REC_POLICY_MODE_SAVE=”) in new stack
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] pbx.c: – Executing [s@sub-record-check:2] GotoIf(“IAX2/acege14-66”, “1?check”) in new stack
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] pbx.c: – Goto (sub-record-check,s,7)
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] pbx.c: – Executing [s@sub-record-check:7] Set(“IAX2/acege14-66”, “__MON_FMT=wav”) in new stack
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] pbx.c: – Executing [s@sub-record-check:8] GotoIf(“IAX2/acege14-66”, “1?next”) in new stack
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] pbx.c: – Goto (sub-record-check,s,11)
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] pbx.c: – Executing [s@sub-record-check:11] ExecIf(“IAX2/acege14-66”, “0?Return()”) in new stack
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] pbx.c: – Executing [s@sub-record-check:12] ExecIf(“IAX2/acege14-66”, “1?Set(__REC_POLICY_MODE=dontcare)”) in new stack
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] pbx.c: – Executing [s@sub-record-check:13] GotoIf(“IAX2/acege14-66”, “0?rg,1”) in new stack
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] pbx.c: – Executing [s@sub-record-check:14] Set(“IAX2/acege14-66”, “__REC_STATUS=INITIALIZED”) in new stack
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] pbx.c: – Executing [s@sub-record-check:15] Set(“IAX2/acege14-66”, “NOW=1430386867”) in new stack
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] pbx.c: – Executing [s@sub-record-check:16] Set(“IAX2/acege14-66”, “__DAY=30”) in new stack
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] pbx.c: – Executing [s@sub-record-check:17] Set(“IAX2/acege14-66”, “__MONTH=04”) in new stack
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] pbx.c: – Executing [s@sub-record-check:18] Set(“IAX2/acege14-66”, “__YEAR=2015”) in new stack
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] pbx.c: – Executing [s@sub-record-check:19] Set(“IAX2/acege14-66”, “__TIMESTR=20150430-114107”) in new stack
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] pbx.c: – Executing [s@sub-record-check:20] Set(“IAX2/acege14-66”, “__FROMEXTEN=3491331316”) in new stack
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] pbx.c: – Executing [s@sub-record-check:21] Set(“IAX2/acege14-66”, “__CALLFILENAME=rg-14001-3491331316-20150430-114107-1430386867.78”) in new stack
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] pbx.c: – Executing [s@sub-record-check:22] Goto(“IAX2/acege14-66”, “rg,1”) in new stack
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] pbx.c: – Goto (sub-record-check,rg,1)
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] pbx.c: – Executing [rg@sub-record-check:1] GosubIf(“IAX2/acege14-66”, “0?record,1(rg,dontcare,3491331316)”) in new stack
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] pbx.c: – Executing [rg@sub-record-check:2] Return(“IAX2/acege14-66”, “”) in new stack
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] pbx.c: – Executing [14001@ext-group:10] Set(“IAX2/acege14-66”, “RingGroupMethod=ringall”) in new stack
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] pbx.c: – Executing [14001@ext-group:11] Macro(“IAX2/acege14-66”, “dial,60,m(default)Tt,1441-1460-1461-1451”) in new stack
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] pbx.c: – Executing [s@macro-dial:1] GotoIf(“IAX2/acege14-66”, “0?dial”) in new stack
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] pbx.c: – Executing [s@macro-dial:2] SetMusicOnHold(“IAX2/acege14-66”, “default”) in new stack
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] pbx.c: – Executing [s@macro-dial:3] AGI(“IAX2/acege14-66”, “dialparties.agi”) in new stack
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] res_agi.c: – Launched AGI Script /var/lib/asterisk/agi-bin/dialparties.agi
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] res_agi.c: dialparties.agi: Starting New Dialparties.agi
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] res_agi.c: dialparties.agi: Caller ID name is ‘orgdab’ number is ‘3491331316’
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] res_agi.c: dialparties.agi: Methodology of ring is ‘ringall’
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] res_agi.c: – dialparties.agi: Added extension 1441 to extension map
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] res_agi.c: – dialparties.agi: Added extension 1460 to extension map
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] res_agi.c: – dialparties.agi: Added extension 1461 to extension map
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] res_agi.c: – dialparties.agi: Added extension 1451 to extension map
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] res_agi.c: – dialparties.agi: Extension 1441 cf is disabled
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] res_agi.c: – dialparties.agi: Extension 1460 cf is disabled
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] res_agi.c: – dialparties.agi: Extension 1461 cf is disabled
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] res_agi.c: – dialparties.agi: Extension 1451 cf is disabled
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] res_agi.c: – dialparties.agi: Extension 1441 do not disturb is disabled
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] res_agi.c: – dialparties.agi: Extension 1460 do not disturb is disabled
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] res_agi.c: – dialparties.agi: Extension 1461 do not disturb is disabled
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] res_agi.c: – dialparties.agi: Extension 1451 do not disturb is disabled
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] res_agi.c: – dialparties.agi: dbset CALLTRACE/1441 to 3491331316
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] res_agi.c: – dialparties.agi: dbset CALLTRACE/1460 to 3491331316
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] res_agi.c: – dialparties.agi: dbset CALLTRACE/1461 to 3491331316
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] res_agi.c: – dialparties.agi: dbset CALLTRACE/1451 to 3491331316
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] res_agi.c: – dialparties.agi: Filtered ARG3: 1441-1460-1461-1451
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] res_agi.c: – <IAX2/acege14-66>AGI Script dialparties.agi completed, returning 0
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] pbx.c: – Executing [s@macro-dial:7] Dial(“IAX2/acege14-66”, “SIP/1441&SIP/1460&SIP/1461&SIP/1451,60,m(default)TtM(auto-blkvm)”) in new stack
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] netsock2.c: == Using SIP RTP TOS bits 184
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] netsock2.c: == Using SIP RTP CoS mark 5
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] netsock2.c: == Using SIP RTP TOS bits 184
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] netsock2.c: == Using SIP RTP CoS mark 5
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] netsock2.c: == Using SIP RTP TOS bits 184
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] netsock2.c: == Using SIP RTP CoS mark 5
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] netsock2.c: == Using SIP RTP TOS bits 184
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] netsock2.c: == Using SIP RTP CoS mark 5
[2015-04-30 11:41:07] VERBOSE[1835] chan_sip.c: == Extension Changed 1441[ext-local] new state Ringing for Notify User 1460
[2015-04-30 11:41:07] VERBOSE[1835] chan_sip.c: == Extension Changed 1441[ext-local] new state Ringing for Notify User 1441
[2015-04-30 11:41:07] VERBOSE[1835] chan_sip.c: == Extension Changed 1441[ext-local] new state Ringing for Notify User 1451
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] app_dial.c: – Called SIP/1441
[2015-04-30 11:41:07] VERBOSE[1835] chan_sip.c: == Extension Changed 1460[ext-local] new state Ringing for Notify User 1460
[2015-04-30 11:41:07] VERBOSE[1835] chan_sip.c: == Extension Changed 1460[ext-local] new state Ringing for Notify User 1451
[2015-04-30 11:41:07] VERBOSE[1835] chan_sip.c: == Extension Changed 1460[ext-local] new state Ringing for Notify User 1441
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] app_dial.c: – Called SIP/1460
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] app_dial.c: – Called SIP/1461
[2015-04-30 11:41:07] VERBOSE[1835] chan_sip.c: == Extension Changed 1451[ext-local] new state Ringing for Notify User 1460
[2015-04-30 11:41:07] VERBOSE[1835] chan_sip.c: == Extension Changed 1451[ext-local] new state Ringing for Notify User 1451
[2015-04-30 11:41:07] VERBOSE[1835] chan_sip.c: == Extension Changed 1451[ext-local] new state Ringing for Notify User 1441
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] app_dial.c: – Called SIP/1451
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] res_musiconhold.c: – Started music on hold, class ‘default’, on IAX2/acege14-66
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] app_dial.c: – SIP/1451-0000003f connected line has changed. Saving it until answer for IAX2/acege14-66
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] app_dial.c: – SIP/1461-0000003e connected line has changed. Saving it until answer for IAX2/acege14-66
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] app_dial.c: – SIP/1460-0000003d connected line has changed. Saving it until answer for IAX2/acege14-66
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] app_dial.c: – SIP/1441-0000003c connected line has changed. Saving it until answer for IAX2/acege14-66
[2015-04-30 11:41:07] VERBOSE[1835] chan_sip.c: == Extension Changed 1441[ext-local] new state Ringing for Notify User 1460
[2015-04-30 11:41:07] VERBOSE[1835] chan_sip.c: == Extension Changed 1441[ext-local] new state Ringing for Notify User 1441 (queued)
[2015-04-30 11:41:07] VERBOSE[1835] chan_sip.c: == Extension Changed 1441[ext-local] new state Ringing for Notify User 1451
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] app_dial.c: – SIP/1441-0000003c is ringing
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] app_dial.c: – SIP/1461-0000003e is ringing
[2015-04-30 11:41:07] VERBOSE[1835] chan_sip.c: == Extension Changed 1460[ext-local] new state Ringing for Notify User 1460 (queued)
[2015-04-30 11:41:07] VERBOSE[1835] chan_sip.c: == Extension Changed 1460[ext-local] new state Ringing for Notify User 1451
[2015-04-30 11:41:07] VERBOSE[1835] chan_sip.c: == Extension Changed 1460[ext-local] new state Ringing for Notify User 1441 (queued)
[2015-04-30 11:41:07] VERBOSE[2645][C-00000012] app_dial.c: – SIP/1460-0000003d is ringing
[2015-04-30 11:41:08] VERBOSE[1835] chan_sip.c: == Extension Changed 1451[ext-local] new state Ringing for Notify User 1460 (queued)
[2015-04-30 11:41:08] VERBOSE[1835] chan_sip.c: == Extension Changed 1451[ext-local] new state Ringing for Notify User 1451 (queued)
[2015-04-30 11:41:08] VERBOSE[1835] chan_sip.c: == Extension Changed 1451[ext-local] new state Ringing for Notify User 1441 (queued)
[2015-04-30 11:41:08] VERBOSE[2645][C-00000012] app_dial.c: – SIP/1451-0000003f is ringing
[2015-04-30 11:41:08] VERBOSE[2645][C-00000012] app_dial.c: – SIP/1441-0000003c is ringing
[2015-04-30 11:41:08] VERBOSE[2645][C-00000012] app_dial.c: – SIP/1451-0000003f is ringing
[2015-04-30 11:41:08] VERBOSE[2645][C-00000012] app_dial.c: – SIP/1451-0000003f is ringing
[2015-04-30 11:41:08] VERBOSE[2645][C-00000012] app_dial.c: – SIP/1460-0000003d is ringing
[2015-04-30 11:41:09] VERBOSE[2645][C-00000012] app_dial.c: – SIP/1441-0000003c is ringing
[2015-04-30 11:41:09] VERBOSE[2645][C-00000012] app_dial.c: – SIP/1451-0000003f is ringing
[2015-04-30 11:41:09] VERBOSE[2645][C-00000012] app_dial.c: – SIP/1460-0000003d is ringing
[2015-04-30 11:41:11] VERBOSE[2645][C-00000012] app_dial.c: – SIP/1441-0000003c is ringing
[2015-04-30 11:41:11] VERBOSE[2645][C-00000012] app_dial.c: – SIP/1451-0000003f is ringing
[2015-04-30 11:41:11] VERBOSE[2645][C-00000012] app_dial.c: – SIP/1460-0000003d is ringing
[2015-04-30 11:41:12] VERBOSE[1835] chan_sip.c: == Extension Changed 1451[ext-local] new state InUse for Notify User 1460
[2015-04-30 11:41:12] VERBOSE[1835] chan_sip.c: == Extension Changed 1451[ext-local] new state InUse for Notify User 1451
[2015-04-30 11:41:12] VERBOSE[1835] chan_sip.c: == Extension Changed 1451[ext-local] new state InUse for Notify User 1441
[2015-04-30 11:41:12] VERBOSE[2645][C-00000012] app_dial.c: – SIP/1451-0000003f connected line has changed. Saving it until answer for IAX2/acege14-66
[2015-04-30 11:41:12] VERBOSE[2645][C-00000012] app_dial.c: – SIP/1451-0000003f answered IAX2/acege14-66
[2015-04-30 11:41:12] VERBOSE[1835] chan_sip.c: == Extension Changed 1441[ext-local] new state Idle for Notify User 1460
[2015-04-30 11:41:12] VERBOSE[1835] chan_sip.c: == Extension Changed 1441[ext-local] new state Idle for Notify User 1441
[2015-04-30 11:41:12] VERBOSE[1835] chan_sip.c: == Extension Changed 1441[ext-local] new state Idle for Notify User 1451
[2015-04-30 11:41:12] VERBOSE[1835] chan_sip.c: == Extension Changed 1460[ext-local] new state Idle for Notify User 1460
[2015-04-30 11:41:12] VERBOSE[1835] chan_sip.c: == Extension Changed 1460[ext-local] new state Idle for Notify User 1451
[2015-04-30 11:41:12] VERBOSE[1835] chan_sip.c: == Extension Changed 1460[ext-local] new state Idle for Notify User 1441
[2015-04-30 11:41:12] VERBOSE[2645][C-00000012] pbx.c: – Executing [s@macro-auto-blkvm:1] Set(“SIP/1451-0000003f”, “__MACRO_RESULT=”) in new stack
[2015-04-30 11:41:12] VERBOSE[2645][C-00000012] pbx.c: – Executing [s@macro-auto-blkvm:2] Set(“SIP/1451-0000003f”, “CFIGNORE=”) in new stack
[2015-04-30 11:41:12] VERBOSE[2645][C-00000012] pbx.c: – Executing [s@macro-auto-blkvm:3] Set(“SIP/1451-0000003f”, “MASTER_CHANNEL(CFIGNORE)=”) in new stack
[2015-04-30 11:41:12] VERBOSE[2645][C-00000012] pbx.c: – Executing [s@macro-auto-blkvm:4] Set(“SIP/1451-0000003f”, “FORWARD_CONTEXT=from-internal”) in new stack
[2015-04-30 11:41:12] VERBOSE[2645][C-00000012] pbx.c: – Executing [s@macro-auto-blkvm:5] Set(“SIP/1451-0000003f”, “MASTER_CHANNEL(FORWARD_CONTEXT)=from-internal”) in new stack
[2015-04-30 11:41:12] VERBOSE[2645][C-00000012] pbx.c: – Executing [s@macro-auto-blkvm:6] Macro(“SIP/1451-0000003f”, “blkvm-clr,”) in new stack
[2015-04-30 11:41:12] VERBOSE[2645][C-00000012] pbx.c: – Executing [s@macro-blkvm-clr:1] Set(“SIP/1451-0000003f”, “SHARED(BLKVM,IAX2/acege14-66)=”) in new stack
[2015-04-30 11:41:12] VERBOSE[2645][C-00000012] pbx.c: – Executing [s@macro-blkvm-clr:2] Set(“SIP/1451-0000003f”, “GOSUB_RETVAL=”) in new stack
[2015-04-30 11:41:12] VERBOSE[2645][C-00000012] pbx.c: – Executing [s@macro-blkvm-clr:3] MacroExit(“SIP/1451-0000003f”, “”) in new stack
[2015-04-30 11:41:12] VERBOSE[2645][C-00000012] pbx.c: – Executing [s@macro-auto-blkvm:7] ExecIf(“SIP/1451-0000003f”, “0?Set(MASTER_CHANNEL(CONNECTEDLINE(num))=1451)”) in new stack
[2015-04-30 11:41:12] VERBOSE[2645][C-00000012] pbx.c: – Executing [s@macro-auto-blkvm:8] ExecIf(“SIP/1451-0000003f”, “0?Set(MASTER_CHANNEL(CONNECTEDLINE(name))=Tecnici ORG-DAB)”) in new stack
[2015-04-30 11:41:12] VERBOSE[2645][C-00000012] res_musiconhold.c: – Stopped music on hold on IAX2/acege14-66
[2015-04-30 11:41:19] VERBOSE[2645][C-00000012] pbx.c: – Executing [h@macro-dial:1] Macro(“IAX2/acege14-66”, “hangupcall”) in new stack
[2015-04-30 11:41:19] VERBOSE[2645][C-00000012] pbx.c: – Executing [s@macro-hangupcall:1] GotoIf(“IAX2/acege14-66”, “1?theend”) in new stack
[2015-04-30 11:41:19] VERBOSE[2645][C-00000012] pbx.c: – Goto (macro-hangupcall,s,3)
[2015-04-30 11:41:19] VERBOSE[2645][C-00000012] pbx.c: – Executing [s@macro-hangupcall:3] ExecIf(“IAX2/acege14-66”, “0?Set(CDR(recordingfile)=)”) in new stack
[2015-04-30 11:41:19] VERBOSE[2645][C-00000012] pbx.c: – Executing [s@macro-hangupcall:4] Hangup(“IAX2/acege14-66”, “”) in new stack
[2015-04-30 11:41:19] VERBOSE[2645][C-00000012] app_macro.c: == Spawn extension (macro-hangupcall, s, 4) exited non-zero on ‘IAX2/acege14-66’ in macro ‘hangupcall’
[2015-04-30 11:41:19] VERBOSE[2645][C-00000012] pbx.c: == Spawn extension (macro-dial, h, 1) exited non-zero on ‘IAX2/acege14-66’
[2015-04-30 11:41:19] VERBOSE[1835] chan_sip.c: == Extension Changed 1451[ext-local] new state Idle for Notify User 1460
[2015-04-30 11:41:19] VERBOSE[1835] chan_sip.c: == Extension Changed 1451[ext-local] new state Idle for Notify User 1451
[2015-04-30 11:41:19] VERBOSE[1835] chan_sip.c: == Extension Changed 1451[ext-local] new state Idle for Notify User 1441
[2015-04-30 11:41:19] VERBOSE[2645][C-00000012] app_macro.c: == Spawn extension (macro-dial, s, 7) exited non-zero on ‘IAX2/acege14-66’ in macro ‘dial’
[2015-04-30 11:41:19] VERBOSE[2645][C-00000012] pbx.c: == Spawn extension (ext-group, 14001, 11) exited non-zero on ‘IAX2/acege14-66’
[2015-04-30 11:41:19] VERBOSE[2645][C-00000012] chan_iax2.c: – Hungup ‘IAX2/acege14-66’[/quote]