Okay, this seems like a lot but here you go. I had to cut out some duplicate stuff about extensions to get under the posting limit size:
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] pbx.c: Executing [09253131111@from-trunk:1] Set("SIP/09253131111-00000029",
"__DIRECTION=INBOUND") in new stack
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] pbx.c: Executing [09253131111@from-trunk:2] Gosub("SIP/09253131111-00000029", "sub-
record-check,s,1(in,09253131111,dontcare)") in new stack
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] pbx.c: Executing [s@sub-record-check:1] GotoIf("SIP/09253131111-00000029", "0?
initialized") in new stack
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] pbx.c: Executing [s@sub-record-check:2] Set("SIP/09253131111-00000029",
"__REC_STATUS=INITIALIZED") in new stack
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] pbx.c: Executing [s@sub-record-check:3] Set("SIP/09253131111-00000029", "NOW=1461272941")
in new stack
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] pbx.c: Executing [s@sub-record-check:4] Set("SIP/09253131111-00000029", "__DAY=21") in
new stack
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] pbx.c: Executing [s@sub-record-check:5] Set("SIP/09253131111-00000029", "__MONTH=04") in
new stack
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] pbx.c: Executing [s@sub-record-check:6] Set("SIP/09253131111-00000029", "__YEAR=2016") in
new stack
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] pbx.c: Executing [s@sub-record-check:7] Set("SIP/09253131111-00000029",
"__TIMESTR=20160421-140901") in new stack
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] pbx.c: Executing [s@sub-record-check:8] Set("SIP/09253131111-00000029",
"__FROMEXTEN=unknown") in new stack
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] pbx.c: Executing [s@sub-record-check:9] Set("SIP/09253131111-00000029", "__MON_FMT=wav")
in new stack
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] pbx.c: Executing [s@sub-record-check:10] NoOp("SIP/09253131111-00000029", "Recordings
initialized") in new stack
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] pbx.c: Executing [s@sub-record-check:11] ExecIf("SIP/09253131111-00000029", "0?Set
(ARG3=dontcare)") in new stack
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] pbx.c: Executing [s@sub-record-check:12] Set("SIP/09253131111-00000029",
"REC_POLICY_MODE_SAVE=") in new stack
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] pbx.c: Executing [s@sub-record-check:13] ExecIf("SIP/09253131111-00000029", "0?Set
(REC_STATUS=NO)") in new stack
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] pbx.c: Executing [s@sub-record-check:14] GotoIf("SIP/09253131111-00000029", "2?
checkaction") in new stack
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] pbx.c: Goto (sub-record-check,s,17)
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] pbx.c: Executing [s@sub-record-check:17] GotoIf("SIP/09253131111-00000029", "1?sub-
record-check,in,1") in new stack
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] pbx.c: Goto (sub-record-check,in,1)
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] pbx.c: Executing [in@sub-record-check:1] NoOp("SIP/09253131111-00000029", "Inbound
Recording Check to 09253131111") in new stack
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] pbx.c: Executing [in@sub-record-check:2] Set("SIP/09253131111-00000029",
"FROMEXTEN=unknown") in new stack
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] pbx.c: Executing [in@sub-record-check:3] ExecIf("SIP/09253131111-00000029", "11?Set
(FROMEXTEN=09253131111)") in new stack
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] pbx.c: Executing [in@sub-record-check:4] Gosub("SIP/09253131111-00000029",
"recordcheck,1(dontcare,in,09253131111)") in new stack
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] pbx.c: Executing [recordcheck@sub-record-check:1] NoOp("SIP/09253131111-00000029",
"Starting recording check against dontcare") in new stack
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] pbx.c: Executing [recordcheck@sub-record-check:2] Goto("SIP/09253131111-00000029",
"dontcare") in new stack
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] pbx.c: Goto (sub-record-check,recordcheck,3)
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] pbx.c: Executing [recordcheck@sub-record-check:3] Return("SIP/09253131111-00000029", "")
in new stack
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] pbx.c: Executing [in@sub-record-check:5] Return("SIP/09253131111-00000029", "") in new
stack
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] pbx.c: Executing [09253131111@from-trunk:3] Set("SIP/09253131111-00000029",
"__FROM_DID=09253131111") in new stack
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] pbx.c: Executing [09253131111@from-trunk:4] Set("SIP/09253131111-00000029", "CDR(did)
=09253131111") in new stack
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] pbx.c: Executing [09253131111@from-trunk:5] ExecIf("SIP/09253131111-00000029", "0 ?Set
(CALLERID(name)=09253131111)") in new stack
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] pbx.c: Executing [09253131111@from-trunk:6] Set("SIP/09253131111-00000029", "CHANNEL
(musicclass)=customuploaded") in new stack
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] pbx.c: Executing [09253131111@from-trunk:7] Set("SIP/09253131111-00000029",
"__MOHCLASS=customuploaded") in new stack
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] pbx.c: Executing [09253131111@from-trunk:8] Set("SIP/09253131111-00000029",
"__REVERSAL_REJECT=FALSE") in new stack
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] pbx.c: Executing [09253131111@from-trunk:9] GotoIf("SIP/09253131111-00000029", "1?post-
reverse-charge") in new stack
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] pbx.c: Goto (from-trunk,09253131111,11)
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] pbx.c: Executing [09253131111@from-trunk:11] NoOp("SIP/09253131111-00000029", "") in new
stack
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] pbx.c: Executing [09253131111@from-trunk:12] Set("SIP/09253131111-00000029",
"__CALLINGNAMEPRES_SV=allowed_not_screened") in new stack
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] pbx.c: Executing [09253131111@from-trunk:13] Set("SIP/09253131111-00000029",
"__CALLINGNUMPRES_SV=allowed_not_screened") in new stack
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] pbx.c: Executing [09253131111@from-trunk:14] Set("SIP/09253131111-00000029", "CALLERID
(name-pres)=allowed_not_screened") in new stack
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] pbx.c: Executing [09253131111@from-trunk:15] Set("SIP/09253131111-00000029", "CALLERID
(num-pres)=allowed_not_screened") in new stack
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] pbx.c: Executing [09253131111@from-trunk:16] NoOp("SIP/09253131111-00000029", "CallerID
Entry Point") in new stack
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] pbx.c: Executing [09253131111@from-trunk:17] Goto("SIP/09253131111-00000029", "ext-
group,1,1") in new stack
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] pbx.c: Goto (ext-group,1,1)
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] pbx.c: Executing [1@ext-group:1] Progress("SIP/09253131111-00000029", "") in new stack
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] pbx.c: Executing [1@ext-group:2] Macro("SIP/09253131111-00000029", "user-callerid,") in
new stack
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] pbx.c: Executing [s@macro-user-callerid:1] Set("SIP/09253131111-00000029",
"TOUCH_MONITOR=1461272941.41") in new stack
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] pbx.c: Executing [s@macro-user-callerid:2] Set("SIP/09253131111-00000029",
"AMPUSER=09253131111") in new stack
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] pbx.c: Executing [s@macro-user-callerid:3] GotoIf("SIP/09253131111-00000029", "0?report")
in new stack
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] pbx.c: Executing [s@macro-user-callerid:4] ExecIf("SIP/09253131111-00000029", "1?Set
(REALCALLERIDNUM=09253131111)") in new stack
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] pbx.c: Executing [s@macro-user-callerid:5] Set("SIP/09253131111-00000029",
"AMPUSER=09253131111") in new stack
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] pbx.c: Executing [s@macro-user-callerid:6] GotoIf("SIP/09253131111-00000029", "0?limit")
in new stack
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] pbx.c: Executing [s@macro-user-callerid:7] Set("SIP/09253131111-00000029",
"AMPUSERCIDNAME=Home Line") in new stack
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] pbx.c: Executing [s@macro-user-callerid:8] GotoIf("SIP/09253131111-00000029", "0?report")
in new stack
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] pbx.c: Executing [s@macro-user-callerid:9] Set("SIP/09253131111-00000029",
"AMPUSERCID=09253131111") in new stack
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] pbx.c: Executing [s@macro-user-callerid:10] Set("SIP/09253131111-00000029",
"__DIAL_OPTIONS=Ttr") in new stack
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] pbx.c: Executing [s@macro-user-callerid:11] Set("SIP/09253131111-00000029", "CALLERID
(all)="Home Line" <09253131111>") in new stack
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] pbx.c: Executing [s@macro-user-callerid:12] GotoIf("SIP/09253131111-00000029", "0?limit")
in new stack
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] pbx.c: Executing [s@macro-user-callerid:13] ExecIf("SIP/09253131111-00000029", "0?Set
(GROUP(concurrency_limit)=09253131111)") in new stack
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] pbx.c: Executing [s@macro-user-callerid:14] GotoIf("SIP/09253131111-00000029", "0?
continue") in new stack
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] pbx.c: Executing [s@macro-user-callerid:15] Set("SIP/09253131111-00000029", "__TTL=64")
in new stack
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] pbx.c: Executing [s@macro-user-callerid:16] GotoIf("SIP/09253131111-00000029", "1?
continue") in new stack
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] pbx.c: Goto (macro-user-callerid,s,27)
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] pbx.c: Executing [s@macro-user-callerid:27] Set("SIP/09253131111-00000029", "CALLERID
(number)=09253131111") in new stack
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] pbx.c: Executing [s@macro-user-callerid:28] Set("SIP/09253131111-00000029", "CALLERID
(name)=Home Line") in new stack
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] pbx.c: Executing [s@macro-user-callerid:29] Set("SIP/09253131111-00000029", "CDR(cnum)
=09253131111") in new stack
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] pbx.c: Executing [s@macro-user-callerid:30] Set("SIP/09253131111-00000029", "CDR(cnam)
=Home Line") in new stack
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] pbx.c: Executing [s@macro-user-callerid:31] Set("SIP/09253131111-00000029", "CHANNEL
(language)=en") in new stack
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] pbx.c: Executing [1@ext-group:3] Macro("SIP/09253131111-00000029", "blkvm-setifempty,")
in new stack
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] pbx.c: Executing [s@macro-blkvm-setifempty:1] GotoIf("SIP/09253131111-00000029", "1?
init") in new stack
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] pbx.c: Goto (macro-blkvm-setifempty,s,4)
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] pbx.c: Executing [s@macro-blkvm-setifempty:4] Set("SIP/09253131111-00000029",
"__BLKVM_CHANNEL=SIP/09253131111-00000029") in new stack
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] pbx.c: Executing [s@macro-blkvm-setifempty:5] Set("SIP/09253131111-00000029", "SHARED
(BLKVM,SIP/09253131111-00000029)=TRUE") in new stack
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] pbx.c: Executing [s@macro-blkvm-setifempty:6] Set("SIP/09253131111-00000029",
"GOSUB_RETVAL=TRUE") in new stack
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] pbx.c: Executing [s@macro-blkvm-setifempty:7] MacroExit("SIP/09253131111-00000029", "")
in new stack
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] pbx.c: Executing [1@ext-group:4] GotoIf("SIP/09253131111-00000029", "1?skipov") in new
stack
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] pbx.c: Goto (ext-group,1,7)
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] pbx.c: Executing [1@ext-group:7] Set("SIP/09253131111-00000029", "RRNODEST=") in new
stack
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] pbx.c: Executing [1@ext-group:8] Set("SIP/09253131111-00000029", "__NODEST=1") in new
stack
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] pbx.c: Executing [1@ext-group:9] GosubIf("SIP/09253131111-00000029", "0?sub-rgsetcid,s,1
()") in new stack
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] pbx.c: Executing [1@ext-group:10] Gosub("SIP/09253131111-00000029", "sub-record-
check,s,1(rg,1,dontcare)") in new stack
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] pbx.c: Executing [s@sub-record-check:1] GotoIf("SIP/09253131111-00000029", "11?
initialized") in new stack
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] pbx.c: Goto (sub-record-check,s,10)
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] pbx.c: Executing [s@sub-record-check:10] NoOp("SIP/09253131111-00000029", "Recordings
initialized") in new stack
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] pbx.c: Executing [s@sub-record-check:11] ExecIf("SIP/09253131111-00000029", "0?Set
(ARG3=dontcare)") in new stack
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] pbx.c: Executing [s@sub-record-check:12] Set("SIP/09253131111-00000029",
"REC_POLICY_MODE_SAVE=") in new stack
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] pbx.c: Executing [s@sub-record-check:13] ExecIf("SIP/09253131111-00000029", "0?Set
(REC_STATUS=NO)") in new stack
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] pbx.c: Executing [s@sub-record-check:14] GotoIf("SIP/09253131111-00000029", "2?
checkaction") in new stack
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] pbx.c: Goto (sub-record-check,s,17)
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] pbx.c: Executing [s@sub-record-check:17] GotoIf("SIP/09253131111-00000029", "0?sub-
record-check,rg,1") in new stack
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] pbx.c: Executing [s@sub-record-check:18] NoOp("SIP/09253131111-00000029", "Generic rg
Recording Check - 09253131111 1") in new stack
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] pbx.c: Executing [s@sub-record-check:19] Gosub("SIP/09253131111-00000029",
"recordcheck,1(dontcare,rg,1)") in new stack
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] pbx.c: Executing [recordcheck@sub-record-check:1] NoOp("SIP/09253131111-00000029",
"Starting recording check against dontcare") in new stack
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] pbx.c: Executing [recordcheck@sub-record-check:2] Goto("SIP/09253131111-00000029",
"dontcare") in new stack
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] pbx.c: Goto (sub-record-check,recordcheck,3)
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] pbx.c: Executing [recordcheck@sub-record-check:3] Return("SIP/09253131111-00000029", "")
in new stack
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] pbx.c: Executing [s@sub-record-check:20] Return("SIP/09253131111-00000029", "") in new
stack
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] pbx.c: Executing [1@ext-group:11] Set("SIP/09253131111-00000029",
"RingGroupMethod=ringall") in new stack
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] pbx.c: Executing [1@ext-group:12] Macro("SIP/09253131111-00000029", "dial,35,m
(customuploaded)Tt,10-11-12-13-14-15") in new stack
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] pbx.c: Executing [s@macro-dial:1] NoOp("SIP/09253131111-00000029", "Blind Transfer: ,
Attended Transfer: , User: 09253131111, Alert Info: ") in new stack
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] pbx.c: Executing [s@macro-dial:2] ExecIf("SIP/09253131111-00000029", "1?Set
(ALERT_INFO=)") in new stack
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] pbx.c: Executing [s@macro-dial:3] ExecIf("SIP/09253131111-00000029", "0?Set
(ALERT_INFO=)") in new stack
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] pbx.c: Executing [s@macro-dial:4] ExecIf("SIP/09253131111-00000029", "0?Set
(ALERT_INFO=)") in new stack
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] pbx.c: Executing [s@macro-dial:5] ExecIf("SIP/09253131111-00000029", "1?Set(CHANNEL
(musicclass)=customuploaded)") in new stack
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] pbx.c: Executing [s@macro-dial:6] AGI("SIP/09253131111-00000029", "dialparties.agi") in
new stack
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] res_agi.c: Launched AGI Script /var/lib/asterisk/agi-bin/dialparties.agi
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] res_agi.c: dialparties.agi: Starting New Dialparties.agi
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] res_agi.c: dialparties.agi: Caller ID name is 'Home Line' number is '09253131111'
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] res_agi.c: dialparties.agi: Methodology of ring is 'ringall'
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] res_agi.c: dialparties.agi: Added extension 15 to extension map
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] res_agi.c: dialparties.agi: Extension 10 cf is disabled
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] res_agi.c: dialparties.agi: Extension 15 cf is disabled
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] res_agi.c: dialparties.agi: Extension 10 do not disturb is disabled
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] res_agi.c: dialparties.agi: Extension 14 do not disturb is disabled
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] res_agi.c: dialparties.agi: Extension 15 do not disturb is disabled
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] res_agi.c: dialparties.agi: dbset CALLTRACE/14 to 09253131111
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] res_agi.c: dialparties.agi: dbset CALLTRACE/15 to 09253131111
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] res_agi.c: dialparties.agi: Filtered ARG3: 10-11-12-13-14-15
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] res_agi.c: <SIP/09253131111-00000029>AGI Script dialparties.agi completed, returning 0
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] pbx.c: Executing [s@macro-dial:9] NoOp("SIP/09253131111-00000029", "Returned from
dialparties with groups to dial") in new stack
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] pbx.c: Executing [s@macro-dial:10] Set("SIP/09253131111-00000029", "LOOPCNT=6") in new
stack
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] pbx.c: Executing [s@macro-dial:11] Set("SIP/09253131111-00000029", "ITER=1") in new stack
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] pbx.c: Executing [s@macro-dial:12] Set("SIP/09253131111-00000029", "EXTTOCALL=10") in new
stack
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] pbx.c: Executing [s@macro-dial:13] NoOp("SIP/09253131111-00000029", "Working with 10") in
new stack
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] pbx.c: Executing [s@macro-dial:14] Set("SIP/09253131111-00000029", "ITER=2") in new stack
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] pbx.c: Executing [s@macro-dial:15] GotoIf("SIP/09253131111-00000029", "1?ndloopbegin") in
new stack
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] pbx.c: Goto (macro-dial,s,12)
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] pbx.c: Executing [s@macro-dial:12] Set("SIP/09253131111-00000029", "EXTTOCALL=11") in new
stack
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] pbx.c: Executing [s@macro-dial:13] NoOp("SIP/09253131111-00000029", "Working with 11") in
new stack
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] pbx.c: Executing [s@macro-dial:14] Set("SIP/09253131111-00000029", "ITER=3") in new stack
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] pbx.c: Executing [s@macro-dial:15] GotoIf("SIP/09253131111-00000029", "1?ndloopbegin") in
new stack
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] pbx.c: Goto (macro-dial,s,12)
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] pbx.c: Goto (macro-dial,s,12)
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] pbx.c: Executing [s@macro-dial:12] Set("SIP/09253131111-00000029", "EXTTOCALL=15") in new
stack
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] pbx.c: Executing [s@macro-dial:13] NoOp("SIP/09253131111-00000029", "Working with 15") in
new stack
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] pbx.c: Executing [s@macro-dial:14] Set("SIP/09253131111-00000029", "ITER=7") in new stack
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] pbx.c: Executing [s@macro-dial:15] GotoIf("SIP/09253131111-00000029", "0?ndloopbegin") in
new stack
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] pbx.c: Executing [s@macro-dial:16] Macro("SIP/09253131111-00000029", "dial-ringall-
predial-hook,") in new stack
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] pbx.c: Executing [s@macro-dial-ringall-predial-hook:1] MacroExit("SIP/09253131111-
00000029", "") in new stack
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] pbx.c: Executing [s@macro-dial:17] Dial("SIP/09253131111-00000029",
"SIP/10&SIP/11&SIP/12&SIP/13&SIP/14&SIP/15,35,m(customuploaded)tM(auto-blkvm)b(func-apply-sipheaders^s^1),") in new stack
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] netsock2.c: Using SIP RTP TOS bits 184
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] netsock2.c: Using SIP RTP CoS mark 5
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] netsock2.c: Using SIP RTP CoS mark 5
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] netsock2.c: Using SIP RTP TOS bits 184
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] netsock2.c: Using SIP RTP CoS mark 5
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] app_stack.c: SIP/10-0000002a Internal Gosub(func-apply-sipheaders,s,1) start
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] pbx.c: Executing [s@func-apply-sipheaders:1] NoOp("SIP/10-0000002a", "Applying SIP
Headers to channel") in new stack
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] pbx.c: Executing [s@func-apply-sipheaders:2] Set("SIP/10-0000002a", "SIPHEADERKEYS=") in
new stack
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] pbx.c: Executing [s@func-apply-sipheaders:3] While("SIP/10-0000002a", "0") in new stack
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] app_while.c: Jumping to priority 7
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] pbx.c: Executing [s@func-apply-sipheaders:8] Return("SIP/10-0000002a", "") in new stack
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] app_stack.c: Spawn extension (from-internal, 1, 1) exited non-zero on 'SIP/10-0000002a'
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] app_stack.c: SIP/10-0000002a Internal Gosub(func-apply-sipheaders,s,1) complete
GOSUB_RETVAL=
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] app_stack.c: SIP/11-0000002b Internal Gosub(func-apply-sipheaders,s,1) start
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] pbx.c: Executing [s@func-apply-sipheaders:1] NoOp("SIP/11-0000002b", "Applying SIP
Headers to channel") in new stack
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] pbx.c: Executing [s@func-apply-sipheaders:2] Set("SIP/11-0000002b", "SIPHEADERKEYS=") in
new stack
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] pbx.c: Executing [s@func-apply-sipheaders:3] While("SIP/11-0000002b", "0") in new stack
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] app_while.c: Jumping to priority 7
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] pbx.c: Executing [s@func-apply-sipheaders:8] Return("SIP/11-0000002b", "") in new stack
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] app_stack.c: Spawn extension (from-internal, 1, 1) exited non-zero on 'SIP/11-0000002b'
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] app_stack.c: SIP/11-0000002b Internal Gosub(func-apply-sipheaders,s,1) complete
GOSUB_RETVAL=
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] app_stack.c: SIP/12-0000002c Internal Gosub(func-apply-sipheaders,s,1) start
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] pbx.c: Executing [s@func-apply-sipheaders:1] NoOp("SIP/12-0000002c", "Applying SIP
Headers to channel") in new stack
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] pbx.c: Executing [s@func-apply-sipheaders:2] Set("SIP/12-0000002c", "SIPHEADERKEYS=") in
new stack
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] pbx.c: Executing [s@func-apply-sipheaders:3] While("SIP/12-0000002c", "0") in new stack
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] app_while.c: Jumping to priority 7
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] pbx.c: Executing [s@func-apply-sipheaders:8] Return("SIP/12-0000002c", "") in new stack
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] app_stack.c: Spawn extension (from-internal, 1, 1) exited non-zero on 'SIP/12-0000002c'
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] app_stack.c: SIP/12-0000002c Internal Gosub(func-apply-sipheaders,s,1) complete
GOSUB_RETVAL=
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] app_stack.c: SIP/13-0000002d Internal Gosub(func-apply-sipheaders,s,1) start
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] pbx.c: Executing [s@func-apply-sipheaders:1] NoOp("SIP/13-0000002d", "Applying SIP
Headers to channel") in new stack
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] pbx.c: Executing [s@func-apply-sipheaders:2] Set("SIP/13-0000002d", "SIPHEADERKEYS=") in
new stack
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] pbx.c: Executing [s@func-apply-sipheaders:3] While("SIP/13-0000002d", "0") in new stack
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] app_while.c: Jumping to priority 7
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] pbx.c: Executing [s@func-apply-sipheaders:8] Return("SIP/13-0000002d", "") in new stack
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] app_stack.c: Spawn extension (from-internal, 1, 1) exited non-zero on 'SIP/13-0000002d'
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] app_stack.c: SIP/13-0000002d Internal Gosub(func-apply-sipheaders,s,1) complete
GOSUB_RETVAL=
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] app_stack.c: SIP/14-0000002e Internal Gosub(func-apply-sipheaders,s,1) start
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] pbx.c: Executing [s@func-apply-sipheaders:1] NoOp("SIP/14-0000002e", "Applying SIP
Headers to channel") in new stack
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] pbx.c: Executing [s@func-apply-sipheaders:2] Set("SIP/14-0000002e", "SIPHEADERKEYS=") in
new stack
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] pbx.c: Executing [s@func-apply-sipheaders:3] While("SIP/14-0000002e", "0") in new stack
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] app_while.c: Jumping to priority 7
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] pbx.c: Executing [s@func-apply-sipheaders:8] Return("SIP/14-0000002e", "") in new stack
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] app_stack.c: Spawn extension (from-internal, 1, 1) exited non-zero on 'SIP/14-0000002e'
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] app_stack.c: SIP/14-0000002e Internal Gosub(func-apply-sipheaders,s,1) complete
GOSUB_RETVAL=
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] app_stack.c: SIP/15-0000002f Internal Gosub(func-apply-sipheaders,s,1) start
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] pbx.c: Executing [s@func-apply-sipheaders:1] NoOp("SIP/15-0000002f", "Applying SIP
Headers to channel") in new stack
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] pbx.c: Executing [s@func-apply-sipheaders:2] Set("SIP/15-0000002f", "SIPHEADERKEYS=") in
new stack
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] pbx.c: Executing [s@func-apply-sipheaders:3] While("SIP/15-0000002f", "0") in new stack
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] app_while.c: Jumping to priority 7
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] pbx.c: Executing [s@func-apply-sipheaders:8] Return("SIP/15-0000002f", "") in new stack
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] app_stack.c: Spawn extension (from-internal, 1, 1) exited non-zero on 'SIP/15-0000002f'
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] app_stack.c: SIP/15-0000002f Internal Gosub(func-apply-sipheaders,s,1) complete
GOSUB_RETVAL=
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] app_dial.c: Called SIP/10
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] app_dial.c: Called SIP/11
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] app_dial.c: Called SIP/15
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] res_musiconhold.c: Started music on hold, class 'customuploaded', on channel
'SIP/09253131111-00000029'
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] app_dial.c: SIP/15-0000002f connected line has changed. Saving it until answer for
SIP/09253131111-00000029
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] app_dial.c: SIP/10-0000002a connected line has changed. Saving it until answer for
SIP/09253131111-00000029
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] app_dial.c: SIP/11-0000002b is ringing
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] app_dial.c: SIP/10-0000002a is ringing
[
[2016-04-21 14:09:01] VERBOSE[30414][C-0000000c] app_dial.c: SIP/15-0000002f is ringing
[2016-04-21 14:09:07] VERBOSE[30414][C-0000000c] res_musiconhold.c: Stopped music on hold on SIP/09253131111-00000029
[2016-04-21 14:09:07] VERBOSE[30414][C-0000000c] app_macro.c: Spawn extension (macro-dial, s, 17) exited non-zero on 'SIP/09253131111-
00000029' in macro 'dial'
[2016-04-21 14:09:07] VERBOSE[30414][C-0000000c] pbx.c: Spawn extension (ext-group, 1, 12) exited non-zero on 'SIP/09253131111-00000029'
[2016-04-21 14:09:07] VERBOSE[30414][C-0000000c] pbx.c: Executing [h@ext-group:1] Macro("SIP/09253131111-00000029", "hangupcall,") in new
stack
[2016-04-21 14:09:07] VERBOSE[30414][C-0000000c] pbx.c: Executing [s@macro-hangupcall:1] GotoIf("SIP/09253131111-00000029", "1?theend") in
new stack
[2016-04-21 14:09:07] VERBOSE[30414][C-0000000c] pbx.c: Goto (macro-hangupcall,s,3)
[2016-04-21 14:09:07] VERBOSE[30414][C-0000000c] pbx.c: Executing [s@macro-hangupcall:3] ExecIf("SIP/09253131111-00000029", "0?Set(CDR
(recordingfile)=)") in new stack
[2016-04-21 14:09:07] VERBOSE[30414][C-0000000c] pbx.c: Executing [s@macro-hangupcall:4] Hangup("SIP/09253131111-00000029", "") in new
stack
[2016-04-21 14:09:07] VERBOSE[30414][C-0000000c] app_macro.c: Spawn extension (macro-hangupcall, s, 4) exited non-zero on
'SIP/09253131111-00000029' in macro 'hangupcall'
[2016-04-21 14:09:07] VERBOSE[30414][C-0000000c] pbx.c: Spawn extension (ext-group, h, 1) exited non-zero on 'SIP/09253131111-00000029'