I did a little censoring, but I used the same numbers.
[2018-04-17 18:35:42] ERROR[2049] netsock2.c: getaddrinfo("pbx", "(null)", ...): Name or service not known
[2018-04-17 18:35:42] WARNING[2049] acl.c: Unable to lookup 'pbx'
[2018-04-17 18:35:42] VERBOSE[16423][C-0000001d] pbx.c: Executing [s@im-censored:1] NoOp("Motif/+11234567890-c240", "Receiving DID: 1112223333") in new stack
[2018-04-17 18:35:42] VERBOSE[16423][C-0000001d] pbx.c: Executing [s@im-censored:2] NoOp("Motif/+11234567890-c240", "s") in new stack
[2018-04-17 18:35:42] VERBOSE[16423][C-0000001d] pbx.c: Executing [s@im-censored:3] GotoIf("Motif/+11234567890-c240", "0?ex") in new stack
[2018-04-17 18:35:42] VERBOSE[16423][C-0000001d] pbx.c: Executing [s@im-censored:4] Set("Motif/+11234567890-c240", "CALLERID(name)=+11234567890") in new stack
[2018-04-17 18:35:42] VERBOSE[16423][C-0000001d] pbx.c: Executing [s@im-censored:5] Goto("Motif/+11234567890-c240", "int") in new stack
[2018-04-17 18:35:42] VERBOSE[16423][C-0000001d] pbx_builtins.c: Goto (im-censored,s,8)
[2018-04-17 18:35:42] VERBOSE[16423][C-0000001d] pbx.c: Executing [s@im-censored:8] GotoIf("Motif/+11234567890-c240", "0?nextstop") in new stack
[2018-04-17 18:35:42] VERBOSE[16423][C-0000001d] pbx.c: Executing [s@im-censored:9] Set("Motif/+11234567890-c240", "CALLERID(name)=1234567890") in new stack
[2018-04-17 18:35:42] VERBOSE[16423][C-0000001d] pbx.c: Executing [s@im-censored:10] GotoIf("Motif/+11234567890-c240", "1?notrim") in new stack
[2018-04-17 18:35:42] VERBOSE[16423][C-0000001d] pbx_builtins.c: Goto (im-censored,s,13)
[2018-04-17 18:35:42] VERBOSE[16423][C-0000001d] pbx.c: Executing [s@im-censored:13] Set("Motif/+11234567890-c240", "CALLERID(number)=1234567890") in new stack
[2018-04-17 18:35:42] VERBOSE[16423][C-0000001d] pbx.c: Executing [s@im-censored:14] Set("Motif/+11234567890-c240", "DIAL_OPTIONS=TtraD(:1)") in new stack
[2018-04-17 18:35:42] VERBOSE[16423][C-0000001d] pbx.c: Executing [s@im-censored:15] Goto("Motif/+11234567890-c240", "from-trunk,1112223333,1") in new stack
[2018-04-17 18:35:42] VERBOSE[16423][C-0000001d] pbx_builtins.c: Goto (from-trunk,1112223333,1)
[2018-04-17 18:35:42] VERBOSE[16423][C-0000001d] pbx.c: Executing [1112223333@from-trunk:1] Set("Motif/+11234567890-c240", "__DIRECTION=INBOUND") in new stack
[2018-04-17 18:35:42] VERBOSE[16423][C-0000001d] pbx.c: Executing [1112223333@from-trunk:2] Gosub("Motif/+11234567890-c240", "sub-record-check,s,1(in,1112223333,dontcare)") in new stack
[2018-04-17 18:35:42] VERBOSE[16423][C-0000001d] pbx.c: Executing [s@sub-record-check:1] GotoIf("Motif/+11234567890-c240", "0?initialized") in new stack
[2018-04-17 18:35:42] VERBOSE[16423][C-0000001d] pbx.c: Executing [s@sub-record-check:2] Set("Motif/+11234567890-c240", "__REC_STATUS=INITIALIZED") in new stack
[2018-04-17 18:35:42] VERBOSE[16423][C-0000001d] pbx.c: Executing [s@sub-record-check:3] Set("Motif/+11234567890-c240", "NOW=1524004542") in new stack
[2018-04-17 18:35:42] VERBOSE[16423][C-0000001d] pbx.c: Executing [s@sub-record-check:4] Set("Motif/+11234567890-c240", "__DAY=17") in new stack
[2018-04-17 18:35:42] VERBOSE[16423][C-0000001d] pbx.c: Executing [s@sub-record-check:5] Set("Motif/+11234567890-c240", "__MONTH=04") in new stack
[2018-04-17 18:35:42] VERBOSE[16423][C-0000001d] pbx.c: Executing [s@sub-record-check:6] Set("Motif/+11234567890-c240", "__YEAR=2018") in new stack
[2018-04-17 18:35:42] VERBOSE[16423][C-0000001d] pbx.c: Executing [s@sub-record-check:7] Set("Motif/+11234567890-c240", "__TIMESTR=20180417-183542") in new stack
[2018-04-17 18:35:42] VERBOSE[16423][C-0000001d] pbx.c: Executing [s@sub-record-check:8] Set("Motif/+11234567890-c240", "__FROMEXTEN=unknown") in new stack
[2018-04-17 18:35:42] VERBOSE[16423][C-0000001d] pbx.c: Executing [s@sub-record-check:9] Set("Motif/+11234567890-c240", "__MON_FMT=wav") in new stack
[2018-04-17 18:35:42] VERBOSE[16423][C-0000001d] pbx.c: Executing [s@sub-record-check:10] NoOp("Motif/+11234567890-c240", "Recordings initialized") in new stack
[2018-04-17 18:35:42] VERBOSE[16423][C-0000001d] pbx.c: Executing [s@sub-record-check:11] ExecIf("Motif/+11234567890-c240", "0?Set(ARG3=dontcare)") in new stack
[2018-04-17 18:35:42] VERBOSE[16423][C-0000001d] pbx.c: Executing [s@sub-record-check:12] Set("Motif/+11234567890-c240", "REC_POLICY_MODE_SAVE=") in new stack
[2018-04-17 18:35:42] VERBOSE[16423][C-0000001d] pbx.c: Executing [s@sub-record-check:13] ExecIf("Motif/+11234567890-c240", "0?Set(REC_STATUS=NO)") in new stack
[2018-04-17 18:35:42] VERBOSE[16423][C-0000001d] pbx.c: Executing [s@sub-record-check:14] GotoIf("Motif/+11234567890-c240", "2?checkaction") in new stack
[2018-04-17 18:35:42] VERBOSE[16423][C-0000001d] pbx_builtins.c: Goto (sub-record-check,s,17)
[2018-04-17 18:35:42] VERBOSE[16423][C-0000001d] pbx.c: Executing [s@sub-record-check:17] GotoIf("Motif/+11234567890-c240", "1?sub-record-check,in,1") in new stack
[2018-04-17 18:35:42] VERBOSE[16423][C-0000001d] pbx_builtins.c: Goto (sub-record-check,in,1)
[2018-04-17 18:35:42] VERBOSE[16423][C-0000001d] pbx.c: Executing [in@sub-record-check:1] NoOp("Motif/+11234567890-c240", "Inbound Recording Check to 1112223333") in new stack
[2018-04-17 18:35:42] VERBOSE[16423][C-0000001d] pbx.c: Executing [in@sub-record-check:2] Set("Motif/+11234567890-c240", "FROMEXTEN=unknown") in new stack
[2018-04-17 18:35:42] VERBOSE[16423][C-0000001d] pbx.c: Executing [in@sub-record-check:3] ExecIf("Motif/+11234567890-c240", "10?Set(FROMEXTEN=1234567890)") in new stack
[2018-04-17 18:35:42] VERBOSE[16423][C-0000001d] pbx.c: Executing [in@sub-record-check:4] Gosub("Motif/+11234567890-c240", "recordcheck,1(dontcare,in,1112223333)") in new stack
[2018-04-17 18:35:42] VERBOSE[16423][C-0000001d] pbx.c: Executing [recordcheck@sub-record-check:1] NoOp("Motif/+11234567890-c240", "Starting recording check against dontcare") in new stack
[2018-04-17 18:35:42] VERBOSE[16423][C-0000001d] pbx.c: Executing [recordcheck@sub-record-check:2] Goto("Motif/+11234567890-c240", "dontcare") in new stack
[2018-04-17 18:35:42] VERBOSE[16423][C-0000001d] pbx_builtins.c: Goto (sub-record-check,recordcheck,3)
[2018-04-17 18:35:42] VERBOSE[16423][C-0000001d] pbx.c: Executing [recordcheck@sub-record-check:3] Return("Motif/+11234567890-c240", "") in new stack
[2018-04-17 18:35:42] VERBOSE[16423][C-0000001d] pbx.c: Executing [in@sub-record-check:5] Return("Motif/+11234567890-c240", "") in new stack
[2018-04-17 18:35:42] VERBOSE[16423][C-0000001d] pbx.c: Executing [1112223333@from-trunk:3] Gosub("Motif/+11234567890-c240", "app-blacklist-check,s,1()") in new stack
[2018-04-17 18:35:42] VERBOSE[16423][C-0000001d] pbx.c: Executing [s@app-blacklist-check:1] GotoIf("Motif/+11234567890-c240", "0?blacklisted") in new stack
[2018-04-17 18:35:42] VERBOSE[16423][C-0000001d] pbx.c: Executing [s@app-blacklist-check:2] Set("Motif/+11234567890-c240", "CALLED_BLACKLIST=1") in new stack
[2018-04-17 18:35:42] VERBOSE[16423][C-0000001d] pbx.c: Executing [s@app-blacklist-check:3] Return("Motif/+11234567890-c240", "") in new stack
[2018-04-17 18:35:42] VERBOSE[16423][C-0000001d] pbx.c: Executing [1112223333@from-trunk:4] Set("Motif/+11234567890-c240", "__FROM_DID=1112223333") in new stack
[2018-04-17 18:35:42] VERBOSE[16423][C-0000001d] pbx.c: Executing [1112223333@from-trunk:5] Set("Motif/+11234567890-c240", "CDR(did)=1112223333") in new stack
[2018-04-17 18:35:42] VERBOSE[16423][C-0000001d] pbx.c: Executing [1112223333@from-trunk:6] ExecIf("Motif/+11234567890-c240", "0 ?Set(CALLERID(name)=1234567890)") in new stack
[2018-04-17 18:35:42] VERBOSE[16423][C-0000001d] pbx.c: Executing [1112223333@from-trunk:7] Set("Motif/+11234567890-c240", "__MOHCLASS=") in new stack
[2018-04-17 18:35:42] VERBOSE[16423][C-0000001d] pbx.c: Executing [1112223333@from-trunk:8] Ringing("Motif/+11234567890-c240", "") in new stack
[2018-04-17 18:35:42] VERBOSE[16423][C-0000001d] pbx.c: Executing [1112223333@from-trunk:9] Set("Motif/+11234567890-c240", "__RINGINGSENT=TRUE") in new stack
[2018-04-17 18:35:42] VERBOSE[16423][C-0000001d] pbx.c: Executing [1112223333@from-trunk:10] Set("Motif/+11234567890-c240", "__REVERSAL_REJECT=FALSE") in new stack
[2018-04-17 18:35:42] VERBOSE[16423][C-0000001d] pbx.c: Executing [1112223333@from-trunk:11] GotoIf("Motif/+11234567890-c240", "1?post-reverse-charge") in new stack
[2018-04-17 18:35:42] VERBOSE[16423][C-0000001d] pbx_builtins.c: Goto (from-trunk,1112223333,13)
[2018-04-17 18:35:42] VERBOSE[16423][C-0000001d] pbx.c: Executing [1112223333@from-trunk:13] NoOp("Motif/+11234567890-c240", "") in new stack
[2018-04-17 18:35:42] VERBOSE[16423][C-0000001d] pbx.c: Executing [1112223333@from-trunk:14] Wait("Motif/+11234567890-c240", "2") in new stack
[2018-04-17 18:35:44] VERBOSE[16423][C-0000001d] pbx.c: Executing [1112223333@from-trunk:15] Set("Motif/+11234567890-c240", "__CALLINGNAMEPRES_SV=allowed_not_screened") in new stack
[2018-04-17 18:35:44] VERBOSE[16423][C-0000001d] pbx.c: Executing [1112223333@from-trunk:16] Set("Motif/+11234567890-c240", "__CALLINGNUMPRES_SV=allowed_not_screened") in new stack
[2018-04-17 18:35:44] VERBOSE[16423][C-0000001d] pbx.c: Executing [1112223333@from-trunk:17] Set("Motif/+11234567890-c240", "CALLERID(name-pres)=allowed_not_screened") in new stack
[2018-04-17 18:35:44] VERBOSE[16423][C-0000001d] pbx.c: Executing [1112223333@from-trunk:18] Set("Motif/+11234567890-c240", "CALLERID(num-pres)=allowed_not_screened") in new stack
[2018-04-17 18:35:44] VERBOSE[16423][C-0000001d] pbx.c: Executing [1112223333@from-trunk:19] NoOp("Motif/+11234567890-c240", "CallerID Entry Point") in new stack
[2018-04-17 18:35:44] VERBOSE[16423][C-0000001d] pbx.c: Executing [1112223333@from-trunk:20] Set("Motif/+11234567890-c240", "FAX_DEST=app-blackhole^hangup^1") in new stack
[2018-04-17 18:35:44] VERBOSE[16423][C-0000001d] pbx.c: Executing [1112223333@from-trunk:21] Set("Motif/+11234567890-c240", "FAXOPT(faxdetect)=yes") in new stack
[2018-04-17 18:35:44] VERBOSE[16423][C-0000001d] pbx.c: Executing [1112223333@from-trunk:22] Answer("Motif/+11234567890-c240", "") in new stack
[2018-04-17 18:35:44] VERBOSE[16423][C-0000001d] pbx.c: Executing [1112223333@from-trunk:23] Wait("Motif/+11234567890-c240", "2") in new stack
[2018-04-17 18:35:46] VERBOSE[16423][C-0000001d] pbx.c: Executing [1112223333@from-trunk:24] Goto("Motif/+11234567890-c240", "ext-group,778,1") in new stack
[2018-04-17 18:35:46] VERBOSE[16423][C-0000001d] pbx_builtins.c: Goto (ext-group,778,1)
[2018-04-17 18:35:46] VERBOSE[16423][C-0000001d] pbx.c: Executing [778@ext-group:1] GotoIf("Motif/+11234567890-c240", "0?cid") in new stack
[2018-04-17 18:35:46] VERBOSE[16423][C-0000001d] pbx.c: Executing [778@ext-group:2] PlayTones("Motif/+11234567890-c240", "ring") in new stack
[2018-04-17 18:35:46] VERBOSE[16423][C-0000001d] pbx.c: Executing [778@ext-group:3] Progress("Motif/+11234567890-c240", "") in new stack
[2018-04-17 18:35:46] NOTICE[16423][C-0000001d] chan_motif.c: Don't know how to indicate condition '14'
[2018-04-17 18:35:46] VERBOSE[16423][C-0000001d] pbx.c: Executing [778@ext-group:4] Macro("Motif/+11234567890-c240", "user-callerid,") in new stack
[2018-04-17 18:35:46] VERBOSE[16423][C-0000001d] pbx.c: Executing [s@macro-user-callerid:1] Set("Motif/+11234567890-c240", "TOUCH_MONITOR=1524004542.67") in new stack
[2018-04-17 18:35:46] VERBOSE[16423][C-0000001d] pbx.c: Executing [s@macro-user-callerid:2] Set("Motif/+11234567890-c240", "AMPUSER=1234567890") in new stack
[2018-04-17 18:35:46] VERBOSE[16423][C-0000001d] pbx.c: Executing [s@macro-user-callerid:3] GotoIf("Motif/+11234567890-c240", "0?report") in new stack
[2018-04-17 18:35:46] VERBOSE[16423][C-0000001d] pbx.c: Executing [s@macro-user-callerid:4] ExecIf("Motif/+11234567890-c240", "1?Set(REALCALLERIDNUM=1234567890)") in new stack
[2018-04-17 18:35:46] VERBOSE[16423][C-0000001d] pbx.c: Executing [s@macro-user-callerid:5] Set("Motif/+11234567890-c240", "AMPUSER=") in new stack
[2018-04-17 18:35:46] VERBOSE[16423][C-0000001d] pbx.c: Executing [s@macro-user-callerid:6] GotoIf("Motif/+11234567890-c240", "0?limit") in new stack
[2018-04-17 18:35:46] VERBOSE[16423][C-0000001d] pbx.c: Executing [s@macro-user-callerid:7] Set("Motif/+11234567890-c240", "AMPUSERCIDNAME=") in new stack
[2018-04-17 18:35:46] VERBOSE[16423][C-0000001d] pbx.c: Executing [s@macro-user-callerid:8] GotoIf("Motif/+11234567890-c240", "1?report") in new stack
[2018-04-17 18:35:46] VERBOSE[16423][C-0000001d] pbx_builtins.c: Goto (macro-user-callerid,s,14)
[2018-04-17 18:35:46] VERBOSE[16423][C-0000001d] pbx.c: Executing [s@macro-user-callerid:14] GotoIf("Motif/+11234567890-c240", "0?continue") in new stack
[2018-04-17 18:35:46] VERBOSE[16423][C-0000001d] pbx.c: Executing [s@macro-user-callerid:15] Set("Motif/+11234567890-c240", "__TTL=6") in new stack
[2018-04-17 18:35:46] VERBOSE[16423][C-0000001d] pbx.c: Executing [s@macro-user-callerid:16] GotoIf("Motif/+11234567890-c240", "1?continue") in new stack
[2018-04-17 18:35:46] VERBOSE[16423][C-0000001d] pbx_builtins.c: Goto (macro-user-callerid,s,27)
[2018-04-17 18:35:46] VERBOSE[16423][C-0000001d] pbx.c: Executing [s@macro-user-callerid:27] Set("Motif/+11234567890-c240", "CALLERID(number)=1234567890") in new stack
[2018-04-17 18:35:46] VERBOSE[16423][C-0000001d] pbx.c: Executing [s@macro-user-callerid:28] Set("Motif/+11234567890-c240", "CALLERID(name)=1234567890") in new stack
[2018-04-17 18:35:46] VERBOSE[16423][C-0000001d] pbx.c: Executing [s@macro-user-callerid:29] GotoIf("Motif/+11234567890-c240", "0?cnum") in new stack
[2018-04-17 18:35:46] VERBOSE[16423][C-0000001d] pbx.c: Executing [s@macro-user-callerid:30] Set("Motif/+11234567890-c240", "CDR(cnam)=1234567890") in new stack
[2018-04-17 18:35:46] VERBOSE[16423][C-0000001d] pbx.c: Executing [s@macro-user-callerid:31] Set("Motif/+11234567890-c240", "CDR(cnum)=1234567890") in new stack
[2018-04-17 18:35:46] VERBOSE[16423][C-0000001d] pbx.c: Executing [s@macro-user-callerid:32] Set("Motif/+11234567890-c240", "CHANNEL(language)=en") in new stack
[2018-04-17 18:35:46] VERBOSE[16423][C-0000001d] pbx.c: Executing [778@ext-group:5] Macro("Motif/+11234567890-c240", "blkvm-setifempty,") in new stack
[2018-04-17 18:35:46] VERBOSE[16423][C-0000001d] pbx.c: Executing [s@macro-blkvm-setifempty:1] GotoIf("Motif/+11234567890-c240", "1?init") in new stack
[2018-04-17 18:35:46] VERBOSE[16423][C-0000001d] pbx_builtins.c: Goto (macro-blkvm-setifempty,s,4)
[2018-04-17 18:35:46] VERBOSE[16423][C-0000001d] pbx.c: Executing [s@macro-blkvm-setifempty:4] Set("Motif/+11234567890-c240", "__BLKVM_CHANNEL=Motif/+11234567890-c240") in new stack
[2018-04-17 18:35:46] VERBOSE[16423][C-0000001d] pbx.c: Executing [s@macro-blkvm-setifempty:5] Set("Motif/+11234567890-c240", "SHARED(BLKVM,Motif/+11234567890-c240)=TRUE") in new stack
[2018-04-17 18:35:46] VERBOSE[16423][C-0000001d] pbx.c: Executing [s@macro-blkvm-setifempty:6] Set("Motif/+11234567890-c240", "GOSUB_RETVAL=TRUE") in new stack
[2018-04-17 18:35:46] VERBOSE[16423][C-0000001d] pbx.c: Executing [s@macro-blkvm-setifempty:7] MacroExit("Motif/+11234567890-c240", "") in new stack
[2018-04-17 18:35:46] VERBOSE[16423][C-0000001d] pbx.c: Executing [778@ext-group:6] GotoIf("Motif/+11234567890-c240", "1?skipov") in new stack
[2018-04-17 18:35:46] VERBOSE[16423][C-0000001d] pbx_builtins.c: Goto (ext-group,778,9)
[2018-04-17 18:35:46] VERBOSE[16423][C-0000001d] pbx.c: Executing [778@ext-group:9] Set("Motif/+11234567890-c240", "RRNODEST=") in new stack
[2018-04-17 18:35:46] VERBOSE[16423][C-0000001d] pbx.c: Executing [778@ext-group:10] Set("Motif/+11234567890-c240", "__NODEST=778") in new stack
[2018-04-17 18:35:46] VERBOSE[16423][C-0000001d] pbx.c: Executing [778@ext-group:11] GosubIf("Motif/+11234567890-c240", "0?sub-rgsetcid,s,1()") in new stack
[2018-04-17 18:35:46] VERBOSE[16423][C-0000001d] pbx.c: Executing [778@ext-group:12] Gosub("Motif/+11234567890-c240", "sub-record-check,s,1(rg,778,dontcare)") in new stack
[2018-04-17 18:35:46] VERBOSE[16423][C-0000001d] pbx.c: Executing [s@sub-record-check:1] GotoIf("Motif/+11234567890-c240", "10?initialized") in new stack
[2018-04-17 18:35:46] VERBOSE[16423][C-0000001d] pbx_builtins.c: Goto (sub-record-check,s,10)
[2018-04-17 18:35:46] VERBOSE[16423][C-0000001d] pbx.c: Executing [s@sub-record-check:10] NoOp("Motif/+11234567890-c240", "Recordings initialized") in new stack
[2018-04-17 18:35:46] VERBOSE[16423][C-0000001d] pbx.c: Executing [s@sub-record-check:11] ExecIf("Motif/+11234567890-c240", "0?Set(ARG3=dontcare)") in new stack
[2018-04-17 18:35:46] VERBOSE[16423][C-0000001d] pbx.c: Executing [s@sub-record-check:12] Set("Motif/+11234567890-c240", "REC_POLICY_MODE_SAVE=") in new stack
[2018-04-17 18:35:46] VERBOSE[16423][C-0000001d] pbx.c: Executing [s@sub-record-check:13] ExecIf("Motif/+11234567890-c240", "0?Set(REC_STATUS=NO)") in new stack
[2018-04-17 18:35:46] VERBOSE[16423][C-0000001d] pbx.c: Executing [s@sub-record-check:14] GotoIf("Motif/+11234567890-c240", "2?checkaction") in new stack
[2018-04-17 18:35:46] VERBOSE[16423][C-0000001d] pbx_builtins.c: Goto (sub-record-check,s,17)
[2018-04-17 18:35:46] VERBOSE[16423][C-0000001d] pbx.c: Executing [s@sub-record-check:17] GotoIf("Motif/+11234567890-c240", "0?sub-record-check,rg,1") in new stack
[2018-04-17 18:35:46] VERBOSE[16423][C-0000001d] pbx.c: Executing [s@sub-record-check:18] NoOp("Motif/+11234567890-c240", "Generic rg Recording Check - 1234567890 778") in new stack
[2018-04-17 18:35:46] VERBOSE[16423][C-0000001d] pbx.c: Executing [s@sub-record-check:19] Gosub("Motif/+11234567890-c240", "recordcheck,1(dontcare,rg,778)") in new stack
[2018-04-17 18:35:46] VERBOSE[16423][C-0000001d] pbx.c: Executing [recordcheck@sub-record-check:1] NoOp("Motif/+11234567890-c240", "Starting recording check against dontcare") in new stack
[2018-04-17 18:35:46] VERBOSE[16423][C-0000001d] pbx.c: Executing [recordcheck@sub-record-check:2] Goto("Motif/+11234567890-c240", "dontcare") in new stack
[2018-04-17 18:35:46] VERBOSE[16423][C-0000001d] pbx_builtins.c: Goto (sub-record-check,recordcheck,3)
[2018-04-17 18:35:46] VERBOSE[16423][C-0000001d] pbx.c: Executing [recordcheck@sub-record-check:3] Return("Motif/+11234567890-c240", "") in new stack
[2018-04-17 18:35:46] VERBOSE[16423][C-0000001d] pbx.c: Executing [s@sub-record-check:20] Return("Motif/+11234567890-c240", "") in new stack
[2018-04-17 18:35:46] VERBOSE[16423][C-0000001d] pbx.c: Executing [778@ext-group:13] Set("Motif/+11234567890-c240", "RingGroupMethod=ringall") in new stack
[2018-04-17 18:35:46] VERBOSE[16423][C-0000001d] pbx.c: Executing [778@ext-group:14] Macro("Motif/+11234567890-c240", "dial,20,TtraD(:1),771-773") in new stack
[2018-04-17 18:35:46] VERBOSE[16423][C-0000001d] pbx.c: Executing [s@macro-dial:1] NoOp("Motif/+11234567890-c240", "Blind Transfer: , Attended Transfer: , User: , Alert Info: ") in new stack
[2018-04-17 18:35:46] VERBOSE[16423][C-0000001d] pbx.c: Executing [s@macro-dial:2] ExecIf("Motif/+11234567890-c240", "0?Set(ALERT_INFO=)") in new stack
[2018-04-17 18:35:46] VERBOSE[16423][C-0000001d] pbx.c: Executing [s@macro-dial:3] ExecIf("Motif/+11234567890-c240", "0?Set(ALERT_INFO=)") in new stack
[2018-04-17 18:35:46] VERBOSE[16423][C-0000001d] pbx.c: Executing [s@macro-dial:4] ExecIf("Motif/+11234567890-c240", "0?Set(ALERT_INFO=)") in new stack
[2018-04-17 18:35:46] VERBOSE[16423][C-0000001d] pbx.c: Executing [s@macro-dial:5] ExecIf("Motif/+11234567890-c240", "0?Set(CHANNEL(musicclass)=)") in new stack
[2018-04-17 18:35:46] VERBOSE[16423][C-0000001d] pbx.c: Executing [s@macro-dial:6] AGI("Motif/+11234567890-c240", "dialparties.agi") in new stack
[2018-04-17 18:35:46] VERBOSE[16423][C-0000001d] res_agi.c: Launched AGI Script /var/lib/asterisk/agi-bin/dialparties.agi
[2018-04-17 18:35:47] VERBOSE[16423][C-0000001d] res_agi.c: dialparties.agi: Starting New Dialparties.agi
[2018-04-17 18:35:47] VERBOSE[16423][C-0000001d] res_agi.c: dialparties.agi: Caller ID name is '1234567890' number is '1234567890'
[2018-04-17 18:35:47] VERBOSE[16423][C-0000001d] res_agi.c: dialparties.agi: CW Ignore is:
[2018-04-17 18:35:47] VERBOSE[16423][C-0000001d] res_agi.c: dialparties.agi: CF Ignore is:
[2018-04-17 18:35:47] VERBOSE[16423][C-0000001d] res_agi.c: dialparties.agi: CW IN_USE/BUSY is: 1
[2018-04-17 18:35:47] VERBOSE[16423][C-0000001d] res_agi.c: dialparties.agi: Methodology of ring is 'ringall'
[2018-04-17 18:35:47] VERBOSE[16423][C-0000001d] res_agi.c: dialparties.agi: Added extension 771 to extension map
[2018-04-17 18:35:47] VERBOSE[16423][C-0000001d] res_agi.c: dialparties.agi: Added extension 773 to extension map
[2018-04-17 18:35:47] VERBOSE[16423][C-0000001d] res_agi.c: dialparties.agi: Extension 771 cf is disabled
[2018-04-17 18:35:47] VERBOSE[16423][C-0000001d] res_agi.c: dialparties.agi: Extension 773 cf is disabled
[2018-04-17 18:35:47] VERBOSE[16423][C-0000001d] res_agi.c: dialparties.agi: Extension 771 do not disturb is disabled
[2018-04-17 18:35:47] VERBOSE[16423][C-0000001d] res_agi.c: dialparties.agi: Extension 773 do not disturb is disabled
[2018-04-17 18:35:47] VERBOSE[16423][C-0000001d] res_agi.c: dialparties.agi: dbset CALLTRACE/771 to 1234567890
[2018-04-17 18:35:47] VERBOSE[16423][C-0000001d] res_agi.c: dialparties.agi: dbset CALLTRACE/773 to 1234567890
[2018-04-17 18:35:47] VERBOSE[16423][C-0000001d] res_agi.c: dialparties.agi: Filtered ARG3: 771-773
[2018-04-17 18:35:47] VERBOSE[16423][C-0000001d] res_agi.c: <Motif/+11234567890-c240>AGI Script dialparties.agi completed, returning 0
[2018-04-17 18:35:47] VERBOSE[16423][C-0000001d] pbx.c: Executing [s@macro-dial:9] NoOp("Motif/+11234567890-c240", "Returned from dialparties with groups to dial") in new stack
[2018-04-17 18:35:47] VERBOSE[16423][C-0000001d] pbx.c: Executing [s@macro-dial:10] NoOp("Motif/+11234567890-c240", "ringall array ") in new stack
[2018-04-17 18:35:47] VERBOSE[16423][C-0000001d] pbx.c: Executing [s@macro-dial:11] Set("Motif/+11234567890-c240", "__FMGL_DIAL=") in new stack
[2018-04-17 18:35:47] VERBOSE[16423][C-0000001d] pbx.c: Executing [s@macro-dial:12] Set("Motif/+11234567890-c240", "LOOPCNT=2") in new stack
[2018-04-17 18:35:47] VERBOSE[16423][C-0000001d] pbx.c: Executing [s@macro-dial:13] Set("Motif/+11234567890-c240", "ITER=1") in new stack
[2018-04-17 18:35:47] VERBOSE[16423][C-0000001d] pbx.c: Executing [s@macro-dial:14] Set("Motif/+11234567890-c240", "EXTTOCALL=771") in new stack
[2018-04-17 18:35:47] VERBOSE[16423][C-0000001d] pbx.c: Executing [s@macro-dial:15] NoOp("Motif/+11234567890-c240", "Working with 771") in new stack
[2018-04-17 18:35:47] VERBOSE[16423][C-0000001d] pbx.c: Executing [s@macro-dial:16] ExecIf("Motif/+11234567890-c240", "0?Set(HASH(__SIPHEADERS,Alert-Info)=Normal;volume=)") in new stack
[2018-04-17 18:35:47] VERBOSE[16423][C-0000001d] pbx.c: Executing [s@macro-dial:17] ExecIf("Motif/+11234567890-c240", "0?Set(HASH(__SIPHEADERS,Alert-Info)=Normal;volume=)") in new stack
[2018-04-17 18:35:47] VERBOSE[16423][C-0000001d] pbx.c: Executing [s@macro-dial:18] Set("Motif/+11234567890-c240", "ITER=2") in new stack
[2018-04-17 18:35:47] VERBOSE[16423][C-0000001d] pbx.c: Executing [s@macro-dial:19] GotoIf("Motif/+11234567890-c240", "1?ndloopbegin") in new stack
[2018-04-17 18:35:47] VERBOSE[16423][C-0000001d] pbx_builtins.c: Goto (macro-dial,s,14)
[2018-04-17 18:35:47] VERBOSE[16423][C-0000001d] pbx.c: Executing [s@macro-dial:14] Set("Motif/+11234567890-c240", "EXTTOCALL=773") in new stack
[2018-04-17 18:35:47] VERBOSE[16423][C-0000001d] pbx.c: Executing [s@macro-dial:15] NoOp("Motif/+11234567890-c240", "Working with 773") in new stack
[2018-04-17 18:35:47] VERBOSE[16423][C-0000001d] pbx.c: Executing [s@macro-dial:16] ExecIf("Motif/+11234567890-c240", "0?Set(HASH(__SIPHEADERS,Alert-Info)=Normal;volume=)") in new stack
[2018-04-17 18:35:47] VERBOSE[16423][C-0000001d] pbx.c: Executing [s@macro-dial:17] ExecIf("Motif/+11234567890-c240", "0?Set(HASH(__SIPHEADERS,Alert-Info)=Normal;volume=)") in new stack
[2018-04-17 18:35:47] VERBOSE[16423][C-0000001d] pbx.c: Executing [s@macro-dial:18] Set("Motif/+11234567890-c240", "ITER=3") in new stack
[2018-04-17 18:35:47] VERBOSE[16423][C-0000001d] pbx.c: Executing [s@macro-dial:19] GotoIf("Motif/+11234567890-c240", "0?ndloopbegin") in new stack
[2018-04-17 18:35:47] VERBOSE[16423][C-0000001d] pbx.c: Executing [s@macro-dial:20] Macro("Motif/+11234567890-c240", "dial-ringall-predial-hook,") in new stack
[2018-04-17 18:35:47] VERBOSE[16423][C-0000001d] pbx.c: Executing [s@macro-dial-ringall-predial-hook:1] MacroExit("Motif/+11234567890-c240", "") in new stack
[2018-04-17 18:35:47] VERBOSE[16423][C-0000001d] pbx.c: Executing [s@macro-dial:21] Dial("Motif/+11234567890-c240", "SIP/771&SIP/773,20,traD(:1)M(auto-blkvm)b(func-apply-sipheaders^s^1),") in new stack
[2018-04-17 18:35:47] ERROR[16423][C-0000001d] netsock2.c: getaddrinfo("pbx", "(null)", ...): Name or service not known
[2018-04-17 18:35:47] WARNING[16423][C-0000001d] acl.c: Unable to lookup 'pbx'
[2018-04-17 18:35:47] VERBOSE[16423][C-0000001d] netsock2.c: Using SIP RTP TOS bits 184
[2018-04-17 18:35:47] VERBOSE[16423][C-0000001d] netsock2.c: Using SIP RTP CoS mark 5
[2018-04-17 18:35:47] ERROR[16423][C-0000001d] netsock2.c: getaddrinfo("pbx", "(null)", ...): Name or service not known
[2018-04-17 18:35:47] WARNING[16423][C-0000001d] acl.c: Unable to lookup 'pbx'
[2018-04-17 18:35:47] VERBOSE[16423][C-0000001d] netsock2.c: Using SIP RTP TOS bits 184
[2018-04-17 18:35:47] VERBOSE[16423][C-0000001d] netsock2.c: Using SIP RTP CoS mark 5
[2018-04-17 18:35:47] VERBOSE[16423][C-0000001d] app_stack.c: SIP/771-00000020 Internal Gosub(func-apply-sipheaders,s,1) start
[2018-04-17 18:35:47] VERBOSE[16423][C-0000001d] pbx.c: Executing [s@func-apply-sipheaders:1] NoOp("SIP/771-00000020", "Applying SIP Headers to channel") in new stack
[2018-04-17 18:35:47] VERBOSE[16423][C-0000001d] pbx.c: Executing [s@func-apply-sipheaders:2] Set("SIP/771-00000020", "SIPHEADERKEYS=") in new stack
[2018-04-17 18:35:47] VERBOSE[16423][C-0000001d] pbx.c: Executing [s@func-apply-sipheaders:3] While("SIP/771-00000020", "0") in new stack
[2018-04-17 18:35:47] VERBOSE[16423][C-0000001d] app_while.c: Jumping to priority 7
[2018-04-17 18:35:47] VERBOSE[16423][C-0000001d] pbx.c: Executing [s@func-apply-sipheaders:8] Return("SIP/771-00000020", "") in new stack
[2018-04-17 18:35:47] VERBOSE[16423][C-0000001d] app_stack.c: Spawn extension (from-internal, 778, 1) exited non-zero on 'SIP/771-00000020'
[2018-04-17 18:35:47] VERBOSE[16423][C-0000001d] app_stack.c: SIP/771-00000020 Internal Gosub(func-apply-sipheaders,s,1) complete GOSUB_RETVAL=
[2018-04-17 18:35:47] VERBOSE[16423][C-0000001d] app_stack.c: SIP/773-00000021 Internal Gosub(func-apply-sipheaders,s,1) start
[2018-04-17 18:35:47] VERBOSE[16423][C-0000001d] pbx.c: Executing [s@func-apply-sipheaders:1] NoOp("SIP/773-00000021", "Applying SIP Headers to channel") in new stack
[2018-04-17 18:35:47] VERBOSE[16423][C-0000001d] pbx.c: Executing [s@func-apply-sipheaders:2] Set("SIP/773-00000021", "SIPHEADERKEYS=") in new stack
[2018-04-17 18:35:47] VERBOSE[16423][C-0000001d] pbx.c: Executing [s@func-apply-sipheaders:3] While("SIP/773-00000021", "0") in new stack
[2018-04-17 18:35:47] VERBOSE[16423][C-0000001d] app_while.c: Jumping to priority 7
[2018-04-17 18:35:47] VERBOSE[16423][C-0000001d] pbx.c: Executing [s@func-apply-sipheaders:8] Return("SIP/773-00000021", "") in new stack
[2018-04-17 18:35:47] VERBOSE[16423][C-0000001d] app_stack.c: Spawn extension (from-internal, 778, 1) exited non-zero on 'SIP/773-00000021'
[2018-04-17 18:35:47] VERBOSE[16423][C-0000001d] app_stack.c: SIP/773-00000021 Internal Gosub(func-apply-sipheaders,s,1) complete GOSUB_RETVAL=
[2018-04-17 18:35:47] VERBOSE[16423][C-0000001d] app_dial.c: Called SIP/771
[2018-04-17 18:35:47] VERBOSE[16423][C-0000001d] app_dial.c: Called SIP/773
[2018-04-17 18:35:47] VERBOSE[16423][C-0000001d] app_dial.c: SIP/773-00000021 connected line has changed. Saving it until answer for Motif/+11234567890-c240
[2018-04-17 18:35:47] VERBOSE[16423][C-0000001d] app_dial.c: SIP/771-00000020 connected line has changed. Saving it until answer for Motif/+11234567890-c240
[2018-04-17 18:35:47] VERBOSE[16423][C-0000001d] app_dial.c: SIP/773-00000021 is ringing
[2018-04-17 18:35:47] VERBOSE[16423][C-0000001d] app_dial.c: SIP/771-00000020 is ringing
[2018-04-17 18:35:59] VERBOSE[16423][C-0000001d] app_macro.c: Spawn extension (macro-dial, s, 21) exited non-zero on 'Motif/+11234567890-c240' in macro 'dial'
[2018-04-17 18:35:59] VERBOSE[16423][C-0000001d] pbx.c: Spawn extension (ext-group, 778, 14) exited non-zero on 'Motif/+11234567890-c240'
[2018-04-17 18:35:59] VERBOSE[16423][C-0000001d] pbx.c: Executing [h@ext-group:1] Macro("Motif/+11234567890-c240", "hangupcall,") in new stack
[2018-04-17 18:35:59] VERBOSE[16423][C-0000001d] pbx.c: Executing [s@macro-hangupcall:1] GotoIf("Motif/+11234567890-c240", "1?theend") in new stack
[2018-04-17 18:35:59] VERBOSE[16423][C-0000001d] pbx_builtins.c: Goto (macro-hangupcall,s,3)
[2018-04-17 18:35:59] VERBOSE[16423][C-0000001d] pbx.c: Executing [s@macro-hangupcall:3] ExecIf("Motif/+11234567890-c240", "0?Set(CDR(recordingfile)=)") in new stack
[2018-04-17 18:35:59] VERBOSE[16423][C-0000001d] pbx.c: Executing [s@macro-hangupcall:4] Hangup("Motif/+11234567890-c240", "") in new stack
[2018-04-17 18:35:59] VERBOSE[16423][C-0000001d] app_macro.c: Spawn extension (macro-hangupcall, s, 4) exited non-zero on 'Motif/+11234567890-c240' in macro 'hangupcall'
[2018-04-17 18:35:59] VERBOSE[16423][C-0000001d] pbx.c: Spawn extension (ext-group, h, 1) exited non-zero on 'Motif/+11234567890-c240'