Reply To: RE: CDR not showing recordings after upgrade to VitalPBX 2.3.0-3

VitalPBX Community Support General Discussion CDR not showing recordings after upgrade to VitalPBX 2.3.0-3 Reply To: RE: CDR not showing recordings after upgrade to VitalPBX 2.3.0-3

    lmoreira
    Participant
    Up
    0
    Down

    Call log:

    [2019-03-27 08:33:12] VERBOSE[22739][C-0000000f] netsock2.c: Using SIP RTP TOS bits 184
    [2019-03-27 08:33:12] VERBOSE[22739][C-0000000f] netsock2.c: Using SIP RTP CoS mark 5
    [2019-03-27 08:33:12] VERBOSE[23324][C-0000000f] pbx.c: Executing [32XXXXXX@cos-all:1] NoOp("SIP/8512-00000010", "More than on digit pattern") in new stack
    [2019-03-27 08:33:12] VERBOSE[23324][C-0000000f] pbx.c: Executing [32XXXXXX@cos-all:2] Gosub("SIP/8512-00000010", "s,1(32XXXXXX)") in new stack
    [2019-03-27 08:33:12] VERBOSE[23324][C-0000000f] pbx.c: Executing [s@cos-all:1] Set("SIP/8512-00000010", "EXTENSION=32XXXXXX") in new stack
    [2019-03-27 08:33:12] VERBOSE[23324][C-0000000f] pbx.c: Executing [s@cos-all:2] NoOp("SIP/8512-00000010", "Dialing 32XXXXXX from 8512") in new stack
    [2019-03-27 08:33:12] VERBOSE[23324][C-0000000f] pbx.c: Executing [s@cos-all:3] Gosub("SIP/8512-00000010", "sub-set-global-vars,s,1(32XXXXXX,parking-1)") in new stack
    [2019-03-27 08:33:12] VERBOSE[23324][C-0000000f] pbx.c: Executing [s@sub-set-global-vars:1] NoOp("SIP/8512-00000010", "Setting Global Vars") in new stack
    [2019-03-27 08:33:12] VERBOSE[23324][C-0000000f] pbx.c: Executing [s@sub-set-global-vars:2] Set("SIP/8512-00000010", "__PICKUPMARK=32XXXXXX") in new stack
    [2019-03-27 08:33:12] VERBOSE[23324][C-0000000f] pbx.c: Executing [s@sub-set-global-vars:3] Set("SIP/8512-00000010", "__PARKINGLOT=parking-1") in new stack
    [2019-03-27 08:33:12] VERBOSE[23324][C-0000000f] pbx.c: Executing [s@sub-set-global-vars:4] Set("SIP/8512-00000010", "__DYNAMIC_FEATURES=custom-features") in new stack
    [2019-03-27 08:33:12] VERBOSE[23324][C-0000000f] pbx.c: Executing [s@sub-set-global-vars:5] Set("SIP/8512-00000010", "ICALL=no") in new stack
    [2019-03-27 08:33:12] VERBOSE[23324][C-0000000f] pbx.c: Executing [s@sub-set-global-vars:6] Return("SIP/8512-00000010", "") in new stack
    [2019-03-27 08:33:12] VERBOSE[23324][C-0000000f] pbx.c: Executing [s@cos-all:4] Gosub("SIP/8512-00000010", "sub-set-call-vars,s,1(11c5e905fcdcc328,32XXXXXX,1,cos-all,ARS-all)") in new stack
    [2019-03-27 08:33:12] VERBOSE[23324][C-0000000f] pbx.c: Executing [s@sub-set-call-vars:1] NoOp("SIP/8512-00000010", "Setting Call Vars") in new stack
    [2019-03-27 08:33:12] VERBOSE[23324][C-0000000f] pbx.c: Executing [s@sub-set-call-vars:2] Set("SIP/8512-00000010", "_TENANT=11c5e905fcdcc328") in new stack
    [2019-03-27 08:33:12] VERBOSE[23324][C-0000000f] pbx.c: Executing [s@sub-set-call-vars:3] Set("SIP/8512-00000010", "_CALL_DESTINATION=32XXXXXX") in new stack
    [2019-03-27 08:33:12] VERBOSE[23324][C-0000000f] pbx.c: Executing [s@sub-set-call-vars:4] Set("SIP/8512-00000010", "_COS_ID=1") in new stack
    [2019-03-27 08:33:12] VERBOSE[23324][C-0000000f] pbx.c: Executing [s@sub-set-call-vars:5] Set("SIP/8512-00000010", "_COS_NAME=cos-all") in new stack
    [2019-03-27 08:33:12] VERBOSE[23324][C-0000000f] pbx.c: Executing [s@sub-set-call-vars:6] Set("SIP/8512-00000010", "_ARS=ARS-all") in new stack
    [2019-03-27 08:33:12] VERBOSE[23324][C-0000000f] pbx.c: Executing [s@sub-set-call-vars:7] Set("SIP/8512-00000010", "CDR(tenant)=vitalpbx") in new stack
    [2019-03-27 08:33:12] VERBOSE[23324][C-0000000f] pbx.c: Executing [s@sub-set-call-vars:8] Set("SIP/8512-00000010", "CDR(destination)=32XXXXXX") in new stack
    [2019-03-27 08:33:12] VERBOSE[23324][C-0000000f] pbx.c: Executing [s@sub-set-call-vars:9] Return("SIP/8512-00000010", "") in new stack
    [2019-03-27 08:33:12] VERBOSE[23324][C-0000000f] pbx.c: Executing [s@cos-all:5] Gosub("SIP/8512-00000010", "sub-construct-cid,s,1") in new stack
    [2019-03-27 08:33:12] VERBOSE[23324][C-0000000f] pbx.c: Executing [s@sub-construct-cid:1] NoOp("SIP/8512-00000010", "Building CID") in new stack
    [2019-03-27 08:33:12] VERBOSE[23324][C-0000000f] pbx.c: Executing [s@sub-construct-cid:2] Set("SIP/8512-00000010", "_CALL_SOURCE=8512") in new stack
    [2019-03-27 08:33:12] VERBOSE[23324][C-0000000f] pbx.c: Executing [s@sub-construct-cid:3] GotoIf("SIP/8512-00000010", "0?return") in new stack
    [2019-03-27 08:33:12] VERBOSE[23324][C-0000000f] pbx.c: Executing [s@sub-construct-cid:4] Gosub("SIP/8512-00000010", "sub-get-device-tree,s,1") in new stack
    [2019-03-27 08:33:12] VERBOSE[23324][C-0000000f] pbx.c: Executing [s@sub-get-device-tree:1] NoOp("SIP/8512-00000010", "Configuring Device Tree") in new stack
    [2019-03-27 08:33:12] VERBOSE[23324][C-0000000f] pbx.c: Executing [s@sub-get-device-tree:2] Set("SIP/8512-00000010", "DEV_TECHNOLOGY=SIP") in new stack
    [2019-03-27 08:33:12] VERBOSE[23324][C-0000000f] pbx.c: Executing [s@sub-get-device-tree:3] GosubIf("SIP/8512-00000010", "0?s-local,1") in new stack
    [2019-03-27 08:33:12] VERBOSE[23324][C-0000000f] pbx.c: Executing [s@sub-get-device-tree:4] GotoIf("SIP/8512-00000010", "0?return") in new stack
    [2019-03-27 08:33:12] VERBOSE[23324][C-0000000f] pbx.c: Executing [s@sub-get-device-tree:5] GotoIf("SIP/8512-00000010", "0?pjsip") in new stack
    [2019-03-27 08:33:12] VERBOSE[23324][C-0000000f] pbx.c: Executing [s@sub-get-device-tree:6] GotoIf("SIP/8512-00000010", "0?:digital") in new stack
    [2019-03-27 08:33:12] VERBOSE[23324][C-0000000f] pbx_builtins.c: Goto (sub-get-device-tree,s,12)
    [2019-03-27 08:33:12] VERBOSE[23324][C-0000000f] pbx.c: Executing [s@sub-get-device-tree:12] Set("SIP/8512-00000010", "DEV_USER=8512") in new stack
    [2019-03-27 08:33:12] VERBOSE[23324][C-0000000f] pbx.c: Executing [s@sub-get-device-tree:13] Set("SIP/8512-00000010", "INTERFACE=SIP/8512") in new stack
    [2019-03-27 08:33:12] VERBOSE[23324][C-0000000f] pbx.c: Executing [s@sub-get-device-tree:14] Set("SIP/8512-00000010", "DEV_TREE=devices/SIP/8512") in new stack
    [2019-03-27 08:33:12] VERBOSE[23324][C-0000000f] pbx.c: Executing [s@sub-get-device-tree:15] Return("SIP/8512-00000010", "") in new stack
    [2019-03-27 08:33:12] VERBOSE[23324][C-0000000f] pbx.c: Executing [s@sub-construct-cid:5] GotoIf("SIP/8512-00000010", "0?return") in new stack
    [2019-03-27 08:33:12] VERBOSE[23324][C-0000000f] pbx.c: Executing [s@sub-construct-cid:6] Set("SIP/8512-00000010", "_CALL_SOURCE=8512") in new stack
    [2019-03-27 08:33:12] VERBOSE[23324][C-0000000f] pbx.c: Executing [s@sub-construct-cid:7] ExecIf("SIP/8512-00000010", "1?Set(CALLERID(all)="Ramal 8512" <8512>)") in new stack
    [2019-03-27 08:33:12] VERBOSE[23324][C-0000000f] pbx.c: Executing [s@sub-construct-cid:8] Set("SIP/8512-00000010", "_CID_CONSTRUCTED=yes") in new stack
    [2019-03-27 08:33:12] VERBOSE[23324][C-0000000f] pbx.c: Executing [s@sub-construct-cid:9] NoOp("SIP/8512-00000010", "") in new stack
    [2019-03-27 08:33:12] VERBOSE[23324][C-0000000f] pbx.c: Executing [s@sub-construct-cid:10] Set("SIP/8512-00000010", "CDR(source)=8512") in new stack
    [2019-03-27 08:33:12] VERBOSE[23324][C-0000000f] pbx.c: Executing [s@sub-construct-cid:11] Return("SIP/8512-00000010", "") in new stack
    [2019-03-27 08:33:12] VERBOSE[23324][C-0000000f] pbx.c: Executing [s@cos-all:6] Gosub("SIP/8512-00000010", "set-global-tenant-vars,s,1") in new stack
    [2019-03-27 08:33:12] VERBOSE[23324][C-0000000f] pbx.c: Executing [s@set-global-tenant-vars:1] NoOp("SIP/8512-00000010", "Setting Global Vars for vitalpbx Tenant") in new stack
    [2019-03-27 08:33:12] VERBOSE[23324][C-0000000f] pbx.c: Executing [s@set-global-tenant-vars:2] Set("SIP/8512-00000010", "__TENANT_PATH=11c5e905fcdcc328") in new stack
    [2019-03-27 08:33:12] VERBOSE[23324][C-0000000f] pbx.c: Executing [s@set-global-tenant-vars:3] Set("SIP/8512-00000010", "__QUEUE_AGENTS_CONTEXT=queue-call-to-agents") in new stack
    [2019-03-27 08:33:12] VERBOSE[23324][C-0000000f] pbx.c: Executing [s@set-global-tenant-vars:4] Set("SIP/8512-00000010", "__FOLLOWME_CONTEXT=ext-followme") in new stack
    [2019-03-27 08:33:12] VERBOSE[23324][C-0000000f] pbx.c: Executing [s@set-global-tenant-vars:5] Set("SIP/8512-00000010", "__HINTS_CONTEXT=extension-hints") in new stack
    [2019-03-27 08:33:12] VERBOSE[23324][C-0000000f] pbx.c: Executing [s@set-global-tenant-vars:6] Return("SIP/8512-00000010", " ") in new stack
    [2019-03-27 08:33:12] VERBOSE[23324][C-0000000f] pbx.c: Executing [s@cos-all:7] NoOp("SIP/8512-00000010", "Check if is an Emergency Call") in new stack
    [2019-03-27 08:33:12] VERBOSE[23324][C-0000000f] pbx.c: Executing [s@cos-all:8] GotoIf("SIP/8512-00000010", "0?emergency-calls,32XXXXXX,1") in new stack
    [2019-03-27 08:33:12] VERBOSE[23324][C-0000000f] pbx.c: Executing [s@cos-all:9] Gosub("SIP/8512-00000010", "sub-lockphone-check,s,1") in new stack
    [2019-03-27 08:33:12] VERBOSE[23324][C-0000000f] pbx.c: Executing [s@sub-lockphone-check:1] NoOp("SIP/8512-00000010", "SUB: LOCK PHONE CHECK") in new stack
    [2019-03-27 08:33:12] VERBOSE[23324][C-0000000f] pbx.c: Executing [s@sub-lockphone-check:2] GotoIf("SIP/8512-00000010", "0?unlock") in new stack
    [2019-03-27 08:33:12] VERBOSE[23324][C-0000000f] pbx.c: Executing [s@sub-lockphone-check:3] GotoIf("SIP/8512-00000010", "0?unlock") in new stack
    [2019-03-27 08:33:12] VERBOSE[23324][C-0000000f] pbx.c: Executing [s@sub-lockphone-check:4] GotoIf("SIP/8512-00000010", "0?unlock") in new stack
    [2019-03-27 08:33:12] VERBOSE[23324][C-0000000f] pbx.c: Executing [s@sub-lockphone-check:5] GotoIf("SIP/8512-00000010", "0?unlock") in new stack
    [2019-03-27 08:33:12] VERBOSE[23324][C-0000000f] pbx.c: Executing [s@sub-lockphone-check:6] GotoIf("SIP/8512-00000010", "0?:unlock") in new stack
    [2019-03-27 08:33:12] VERBOSE[23324][C-0000000f] pbx_builtins.c: Goto (sub-lockphone-check,s,12)
    [2019-03-27 08:33:12] VERBOSE[23324][C-0000000f] pbx.c: Executing [s@sub-lockphone-check:12] Return("SIP/8512-00000010", "") in new stack
    [2019-03-27 08:33:12] VERBOSE[23324][C-0000000f] pbx.c: Executing [s@cos-all:10] Gosub("SIP/8512-00000010", "sub-local-dialing,s,1") in new stack
    [2019-03-27 08:33:12] VERBOSE[23324][C-0000000f] pbx.c: Executing [s@sub-local-dialing:1] NoOp("SIP/8512-00000010", "Trying to determine if is a local extension") in new stack
    [2019-03-27 08:33:12] VERBOSE[23324][C-0000000f] pbx.c: Executing [s@sub-local-dialing:2] GotoIf("SIP/8512-00000010", "0?:return") in new stack
    [2019-03-27 08:33:12] VERBOSE[23324][C-0000000f] pbx_builtins.c: Goto (sub-local-dialing,s,4)
    [2019-03-27 08:33:12] VERBOSE[23324][C-0000000f] pbx.c: Executing [s@sub-local-dialing:4] Return("SIP/8512-00000010", "") in new stack
    [2019-03-27 08:33:12] VERBOSE[23324][C-0000000f] pbx.c: Executing [s@cos-all:11] GosubIf("SIP/8512-00000010", "0?:cos-all-post,32XXXXXX,1") in new stack
    [2019-03-27 08:33:12] VERBOSE[23324][C-0000000f] pbx.c: Executing [32XXXXXX@cos-all-post:1] NoOp("SIP/8512-00000010", "SAIDA E1Virtual") in new stack
    [2019-03-27 08:33:12] VERBOSE[23324][C-0000000f] pbx.c: Executing [32XXXXXX@cos-all-post:2] Gosub("SIP/8512-00000010", "sub-setup-call-type,s,1(outgoing)") in new stack
    [2019-03-27 08:33:12] VERBOSE[23324][C-0000000f] pbx.c: Executing [s@sub-setup-call-type:1] NoOp("SIP/8512-00000010", "Determinating Call Type") in new stack
    [2019-03-27 08:33:12] VERBOSE[23324][C-0000000f] pbx.c: Executing [s@sub-setup-call-type:2] GotoIf("SIP/8512-00000010", "0?return") in new stack
    [2019-03-27 08:33:12] VERBOSE[23324][C-0000000f] pbx.c: Executing [s@sub-setup-call-type:3] Gosub("SIP/8512-00000010", "s-outgoing,1()") in new stack
    [2019-03-27 08:33:12] VERBOSE[23324][C-0000000f] pbx.c: Executing [s-outgoing@sub-setup-call-type:1] NoOp("SIP/8512-00000010", "Outgoing Call") in new stack
    [2019-03-27 08:33:12] VERBOSE[23324][C-0000000f] pbx.c: Executing [s-outgoing@sub-setup-call-type:2] Set("SIP/8512-00000010", "__CALL_TYPE=3") in new stack
    [2019-03-27 08:33:12] VERBOSE[23324][C-0000000f] pbx.c: Executing [s-outgoing@sub-setup-call-type:3] GosubIf("SIP/8512-00000010", "0?s-transit,1") in new stack
    [2019-03-27 08:33:12] VERBOSE[23324][C-0000000f] pbx.c: Executing [s-outgoing@sub-setup-call-type:4] Return("SIP/8512-00000010", "") in new stack
    [2019-03-27 08:33:12] VERBOSE[23324][C-0000000f] pbx.c: Executing [s@sub-setup-call-type:4] Set("SIP/8512-00000010", "__CALL_TYPE_CONFIGURED=yes") in new stack
    [2019-03-27 08:33:12] VERBOSE[23324][C-0000000f] pbx.c: Executing [s@sub-setup-call-type:5] Set("SIP/8512-00000010", "CDR(calltype)=3") in new stack
    [2019-03-27 08:33:12] VERBOSE[23324][C-0000000f] pbx.c: Executing [s@sub-setup-call-type:6] Return("SIP/8512-00000010", "") in new stack
    [2019-03-27 08:33:12] VERBOSE[23324][C-0000000f] pbx.c: Executing [32XXXXXX@cos-all-post:3] NoOp("SIP/8512-00000010", "Start to Dial Trunks") in new stack
    [2019-03-27 08:33:12] VERBOSE[23324][C-0000000f] pbx.c: Executing [32XXXXXX@cos-all-post:4] Set("SIP/8512-00000010", "DNID=08532XXXXXX") in new stack
    [2019-03-27 08:33:12] VERBOSE[23324][C-0000000f] pbx.c: Executing [32XXXXXX@cos-all-post:5] Goto("SIP/8512-00000010", "s-1,1") in new stack
    [2019-03-27 08:33:12] VERBOSE[23324][C-0000000f] pbx_builtins.c: Goto (cos-all-post,s-1,1)
    [2019-03-27 08:33:12] VERBOSE[23324][C-0000000f] pbx.c: Executing [s-1@cos-all-post:1] Gosub("SIP/8512-00000010", "sub-construct-cid,s-external,1") in new stack
    [2019-03-27 08:33:12] VERBOSE[23324][C-0000000f] pbx.c: Executing [s-external@sub-construct-cid:1] NoOp("SIP/8512-00000010", "Setting External CID") in new stack
    [2019-03-27 08:33:12] VERBOSE[23324][C-0000000f] pbx.c: Executing [s-external@sub-construct-cid:2] Set("SIP/8512-00000010", "EXTENSION_NUMBER=8512") in new stack
    [2019-03-27 08:33:12] VERBOSE[23324][C-0000000f] pbx.c: Executing [s-external@sub-construct-cid:3] Set("SIP/8512-00000010", "EMERGENCY_CALL=no") in new stack
    [2019-03-27 08:33:12] VERBOSE[23324][C-0000000f] pbx.c: Executing [s-external@sub-construct-cid:4] Set("SIP/8512-00000010", "EMERGENCY_CID=") in new stack
    [2019-03-27 08:33:12] VERBOSE[23324][C-0000000f] pbx.c: Executing [s-external@sub-construct-cid:5] ExecIf("SIP/8512-00000010", "1?Set(EMERGENCY_CID="Ramal 8512" <8512>)") in new stack
    [2019-03-27 08:33:12] VERBOSE[23324][C-0000000f] pbx.c: Executing [s-external@sub-construct-cid:6] ExecIf("SIP/8512-00000010", "1?Set(CALLERID(all)="Ramal 8512" <8512>)") in new stack
    [2019-03-27 08:33:12] VERBOSE[23324][C-0000000f] pbx.c: Executing [s-external@sub-construct-cid:7] GotoIf("SIP/8512-00000010", "0?:info") in new stack
    [2019-03-27 08:33:12] VERBOSE[23324][C-0000000f] pbx_builtins.c: Goto (sub-construct-cid,s-external,9)
    [2019-03-27 08:33:12] VERBOSE[23324][C-0000000f] pbx.c: Executing [s-external@sub-construct-cid:9] NoOp("SIP/8512-00000010", "CID Name: Ramal 8512, CID Number: 8512") in new stack
    [2019-03-27 08:33:12] VERBOSE[23324][C-0000000f] pbx.c: Executing [s-external@sub-construct-cid:10] Set("SIP/8512-00000010", "SEND_CID=yes") in new stack
    [2019-03-27 08:33:12] VERBOSE[23324][C-0000000f] pbx.c: Executing [s-external@sub-construct-cid:11] ExecIf("SIP/8512-00000010", "0?Set(CALLERID(name-pres)=prohib_passed_screen)") in new stack
    [2019-03-27 08:33:12] VERBOSE[23324][C-0000000f] pbx.c: Executing [s-external@sub-construct-cid:12] ExecIf("SIP/8512-00000010", "0?Set(CALLERID(num-pres)=prohib_passed_screen)") in new stack
    [2019-03-27 08:33:12] VERBOSE[23324][C-0000000f] pbx.c: Executing [s-external@sub-construct-cid:13] Set("SIP/8512-00000010", "CDR(source)=8512") in new stack
    [2019-03-27 08:33:12] VERBOSE[23324][C-0000000f] pbx.c: Executing [s-external@sub-construct-cid:14] Return("SIP/8512-00000010", "") in new stack
    [2019-03-27 08:33:12] VERBOSE[23324][C-0000000f] pbx.c: Executing [s-1@cos-all-post:2] Gosub("SIP/8512-00000010", "trk-1,08532XXXXXX,1(from-trk-grp)") in new stack
    [2019-03-27 08:33:12] VERBOSE[23324][C-0000000f] pbx.c: Executing [08532XXXXXX@trk-1:1] NoOp("SIP/8512-00000010", "Trunk: E1Virtual") in new stack
    [2019-03-27 08:33:12] VERBOSE[23324][C-0000000f] pbx.c: Executing [08532XXXXXX@trk-1:2] Gosub("SIP/8512-00000010", "sub-setup-call-type,s,1(outgoing,no)") in new stack
    [2019-03-27 08:33:12] VERBOSE[23324][C-0000000f] pbx.c: Executing [s@sub-setup-call-type:1] NoOp("SIP/8512-00000010", "Determinating Call Type") in new stack
    [2019-03-27 08:33:12] VERBOSE[2 3324][C-0000000f] pbx.c: Executing [s@sub-setup-call-type:2] GotoIf("SIP/8512-00000010", "0?return") in new stack
    [2019-03-27 08:33:12] VERBOSE[23324][C-0000000f] pbx.c: Executing [s@sub-setup-call-type:3] Gosub("SIP/8512-00000010", "s-outgoing,1(no)") in new stack
    [2019-03-27 08:33:12] VERBOSE[23324][C-0000000f] pbx.c: Executing [s-outgoing@sub-setup-call-type:1] NoOp("SIP/8512-00000010", "Outgoing Call") in new stack
    [2019-03-27 08:33:12] VERBOSE[23324][C-0000000f] pbx.c: Executing [s-outgoing@sub-setup-call-type:2] Set("SIP/8512-00000010", "__CALL_TYPE=3") in new stack
    [2019-03-27 08:33:12] VERBOSE[23324][C-0000000f] pbx.c: Executing [s-outgoing@sub-setup-call-type:3] GosubIf("SIP/8512-00000010", "0?s-transit,1") in new stack
    [2019-03-27 08:33:12] VERBOSE[23324][C-0000000f] pbx.c: Executing [s-outgoing@sub-setup-call-type:4] Return("SIP/8512-00000010", "") in new stack
    [2019-03-27 08:33:12] VERBOSE[23324][C-0000000f] pbx.c: Executing [s@sub-setup-call-type:4] Set("SIP/8512-00000010", "__CALL_TYPE_CONFIGURED=yes") in new stack
    [2019-03-27 08:33:12] VERBOSE[23324][C-0000000f] pbx.c: Executing [s@sub-setup-call-type:5] Set("SIP/8512-00000010", "CDR(calltype)=3") in new stack
    [2019-03-27 08:33:12] VERBOSE[23324][C-0000000f] pbx.c: Executing [s@sub-setup-call-type:6] Return("SIP/8512-00000010", "") in new stack
    [2019-03-27 08:33:12] VERBOSE[23324][C-0000000f] pbx.c: Executing [08532XXXXXX@trk-1:3] NoOp("SIP/8512-00000010", "Configuring Trunk Parameters") in new stack
    [2019-03-27 08:33:12] VERBOSE[23324][C-0000000f] pbx.c: Executing [08532XXXXXX@trk-1:4] Set("SIP/8512-00000010", "CHANNEL(musicclass)=default") in new stack
    [2019-03-27 08:33:12] VERBOSE[23324][C-0000000f] pbx.c: Executing [08532XXXXXX@trk-1:5] Set("SIP/8512-00000010", "__TRKDIALOPTIONS=kKtT") in new stack
    [2019-03-27 08:33:12] VERBOSE[23324][C-0000000f] pbx.c: Executing [08532XXXXXX@trk-1:6] Set("SIP/8512-00000010", "ORIGINATOR=8512") in new stack
    [2019-03-27 08:33:12] VERBOSE[23324][C-0000000f] pbx.c: Executing [08532XXXXXX@trk-1:7] Set("SIP/8512-00000010", "RING_TIME=90") in new stack
    [2019-03-27 08:33:12] VERBOSE[23324][C-0000000f] pbx.c: Executing [08532XXXXXX@trk-1:8] Set("SIP/8512-00000010", "CALLED=08532XXXXXX") in new stack
    [2019-03-27 08:33:12] VERBOSE[23324][C-0000000f] pbx.c: Executing [08532XXXXXX@trk-1:9] Dial("SIP/8512-00000010", "IAX2/voip_out/08532XXXXXX,90,kKtTU(sub-call-recording^11c5e905fcdcc328^8512^08532XXXXXX^no)") in new stack
    [2019-03-27 08:33:12] VERBOSE[23324][C-0000000f] app_dial.c: Called IAX2/voip_out/08532XXXXXX
    [2019-03-27 08:33:12] VERBOSE[21462][C-0000000f] chan_iax2.c: Call accepted by 189.126.65.174:4569 (format g722)
    [2019-03-27 08:33:12] VERBOSE[21462][C-0000000f] chan_iax2.c: Format for call is (g722)
    [2019-03-27 08:33:13] VERBOSE[23324][C-0000000f] app_dial.c: IAX2/voip_out-30473 is ringing
    [2019-03-27 08:33:19] VERBOSE[23324][C-0000000f] app_dial.c: IAX2/voip_out-30473 is making progress passing it to SIP/8512-00000010
    [2019-03-27 08:33:31] VERBOSE[23324][C-0000000f] app_dial.c: IAX2/voip_out-30473 answered SIP/8512-00000010
    [2019-03-27 08:33:31] VERBOSE[23324][C-0000000f] app_stack.c: IAX2/voip_out-30473 Internal Gosub(sub-call-recording,s,1(11c5e905fcdcc328,8512,08532XXXXXX,no)) start
    [2019-03-27 08:33:31] VERBOSE[23324][C-0000000f] pbx.c: Executing [s@sub-call-recording:1] NoOp("IAX2/voip_out-30473", "Check if call will be recorded") in new stack
    [2019-03-27 08:33:31] VERBOSE[23324][C-0000000f] pbx.c: Executing [s@sub-call-recording:2] NoOp("IAX2/voip_out-30473", "Call Type: 3 ") in new stack
    [2019-03-27 08:33:31] VERBOSE[23324][C-0000000f] pbx.c: Executing [s@sub-call-recording:3] Set("IAX2/voip_out-30473", "TENANT=11c5e905fcdcc328") in new stack
    [2019-03-27 08:33:31] VERBOSE[23324][C-0000000f] pbx.c: Executing [s@sub-call-recording:4] Set("IAX2/voip_out-30473", "CALLER=8512") in new stack
    [2019-03-27 08:33:31] VERBOSE[23324][C-0000000f] pbx.c: Executing [s@sub-call-recording:5] Set("IAX2/voip_out-30473", "CALLEE=08532XXXXXX") in new stack
    [2019-03-27 08:33:31] VERBOSE[23324][C-0000000f] pbx.c: Executing [s@sub-call-recording:6] Set("IAX2/voip_out-30473", "APP_FORCE=no") in new stack
    [2019-03-27 08:33:31] VERBOSE[23324][C-0000000f] pbx.c: Executing [s@sub-call-recording:7] GotoIf("IAX2/voip_out-30473", "1?:rec_no_available") in new stack
    [2019-03-27 08:33:31] VERBOSE[23324][C-0000000f] pbx.c: Executing [s@sub-call-recording:8] GotoIf("IAX2/voip_out-30473", "0?perform-recording") in new stack
    [2019-03-27 08:33:31] VERBOSE[23324][C-0000000f] pbx.c: Executing [s@sub-call-recording:9] GotoIf("IAX2/voip_out-30473", "0?perform-recording") in new stack
    [2019-03-27 08:33:31] VERBOSE[23324][C-0000000f] pbx.c: Executing [s@sub-call-recording:10] GotoIf("IAX2/voip_out-30473", "0?perform-recording") in new stack
    [2019-03-27 08:33:31] VERBOSE[23324][C-0000000f] pbx.c: Executing [s@sub-call-recording:11] GotoIf("IAX2/voip_out-30473", "0?perform-recording") in new stack
    [2019-03-27 08:33:31] VERBOSE[23324][C-0000000f] pbx.c: Executing [s@sub-call-recording:12] GotoIf("IAX2/voip_out-30473", "1?perform-recording") in new stack
    [2019-03-27 08:33:31] VERBOSE[23324][C-0000000f] pbx_builtins.c: Goto (sub-call-recording,s,14)
    [2019-03-27 08:33:31] VERBOSE[23324][C-0000000f] pbx.c: Executing [s@sub-call-recording:14] Gosub("IAX2/voip_out-30473", "sub-perform-recording,s,1") in new stack
    [2019-03-27 08:33:31] VERBOSE[23324][C-0000000f] pbx.c: Executing [s@sub-perform-recording:1] NoOp("IAX2/voip_out-30473", "Perform Call Recording") in new stack
    [2019-03-27 08:33:31] VERBOSE[23324][C-0000000f] pbx.c: Executing [s@sub-perform-recording:2] Set("IAX2/voip_out-30473", "AUDIO_FILE=1553686392.34") in new stack
    [2019-03-27 08:33:31] VERBOSE[23324][C-0000000f] pbx.c: Executing [s@sub-perform-recording:3] Set("IAX2/voip_out-30473", "RECORDING_FORMAT=wav") in new stack
    [2019-03-27 08:33:31] VERBOSE[23324][C-0000000f] pbx.c: Executing [s@sub-perform-recording:4] Set("IAX2/voip_out-30473", "REC_FILENAME=/var/spool/asterisk/monitor/2019/03/27/1553686392.34.wav") in new stack
    [2019-03-27 08:33:31] VERBOSE[23324][C-0000000f] pbx.c: Executing [s@sub-perform-recording:5] GotoIf("IAX2/voip_out-30473", "0?:set_rec_file") in new stack
    [2019-03-27 08:33:31] VERBOSE[23324][C-0000000f] pbx_builtins.c: Goto (sub-perform-recording,s,8)
    [2019-03-27 08:33:31] VERBOSE[23324][C-0000000f] pbx.c: Executing [s@sub-perform-recording:8] Set("IAX2/voip_out-30473", "CDR(recfile)=/var/spool/asterisk/monitor/2019/03/27/1553686392.34.wav") in new stack
    [2019-03-27 08:33:31] VERBOSE[23324][C-0000000f] pbx.c: Executing [s@sub-perform-recording:9] MixMonitor("IAX2/voip_out-30473", "/var/spool/asterisk/monitor/2019/03/27/1553686392.34.wav,b,") in new stack
    [2019-03-27 08:33:31] VERBOSE[23324][C-0000000f] pbx.c: Executing [s@sub-perform-recording:10] Set("IAX2/voip_out-30473", "_RECORDING_IN_PROGRESS=yes") in new stack
    [2019-03-27 08:33:31] VERBOSE[23324][C-0000000f] pbx.c: Executing [s@sub-perform-recording:11] Return("IAX2/voip_out-30473", "") in new stack
    [2019-03-27 08:33:31] VERBOSE[23324][C-0000000f] pbx.c: Executing [s@sub-call-recording:15] Goto("IAX2/voip_out-30473", "return") in new stack
    [2019-03-27 08:33:31] VERBOSE[23324][C-0000000f] pbx_builtins.c: Goto (sub-call-recording,s,17)
    [2019-03-27 08:33:31] VERBOSE[23324][C-0000000f] pbx.c: Executing [s@sub-call-recording:17] Return("IAX2/voip_out-30473", "") in new stack
    [2019-03-27 08:33:31] VERBOSE[23324][C-0000000f] app_stack.c: Spawn extension (trk-1-in, , 1) exited non-zero on 'IAX2/voip_out-30473'
    [2019-03-27 08:33:31] VERBOSE[23324][C-0000000f] app_stack.c: IAX2/voip_out-30473 Internal Gosub(sub-call-recording,s,1(11c5e905fcdcc328,8512,08532XXXXXX,no)) complete GOSUB_RETVAL=
    [2019-03-27 08:33:31] VERBOSE[23344][C-0000000f] app_mixmonitor.c: Begin MixMonitor Recording IAX2/voip_out-30473
    [2019-03-27 08:33:31] VERBOSE[23345][C-0000000f] bridge_channel.c: Channel IAX2/voip_ou t-30473 joined 'simple_bridge' basic-bridge <3bb5c9f7-b1c3-44b6-b17e-9ca4b188d1b1>
    [2019-03-27 08:33:31] VERBOSE[23324][C-0000000f] bridge_channel.c: Channel SIP/8512-00000010 joined 'simple_bridge' basic-bridge <3bb5c9f7-b1c3-44b6-b17e-9ca4b188d1b1>
    [2019-03-27 08:33:45] VERBOSE[23324][C-0000000f] bridge_channel.c: Channel SIP/8512-00000010 left 'simple_bridge' basic-bridge <3bb5c9f7-b1c3-44b6-b17e-9ca4b188d1b1>
    [2019-03-27 08:33:45] VERBOSE[23324][C-0000000f] pbx.c: Spawn extension (trk-1, 08532XXXXXX, 9) exited non-zero on 'SIP/8512-00000010'
    [2019-03-27 08:33:45] VERBOSE[23324][C-0000000f] pbx.c: Executing [h@trk-1:1] Gosub("SIP/8512-00000010", "dynamic-routing-out,s,1(8512,08532XXXXXX,ANSWER,1)") in new stack
    [2019-03-27 08:33:45] VERBOSE[23324][C-0000000f] pbx.c: Executing [s@dynamic-routing-out:1] NoOp("SIP/8512-00000010", "Test if save on dynamic routing list") in new stack
    [2019-03-27 08:33:45] VERBOSE[23324][C-0000000f] pbx.c: Executing [s@dynamic-routing-out:2] Set("SIP/8512-00000010", "CALLER=8512") in new stack
    [2019-03-27 08:33:45] VERBOSE[23324][C-0000000f] pbx.c: Executing [s@dynamic-routing-out:3] Set("SIP/8512-00000010", "CALLED=08532XXXXXX") in new stack
    [2019-03-27 08:33:45] VERBOSE[23324][C-0000000f] pbx.c: Executing [s@dynamic-routing-out:4] Set("SIP/8512-00000010", "STATUS=ANSWER") in new stack
    [2019-03-27 08:33:45] VERBOSE[23324][C-0000000f] pbx.c: Executing [s@dynamic-routing-out:5] Set("SIP/8512-00000010", "TRKID=1") in new stack
    [2019-03-27 08:33:45] VERBOSE[23324][C-0000000f] pbx.c: Executing [s@dynamic-routing-out:6] Set("SIP/8512-00000010", "ALLOW_DR=no") in new stack
    [2019-03-27 08:33:45] VERBOSE[23324][C-0000000f] pbx.c: Executing [s@dynamic-routing-out:7] Set("SIP/8512-00000010", "DYNROUTING_OMC=yes") in new stack
    [2019-03-27 08:33:45] VERBOSE[23324][C-0000000f] pbx.c: Executing [s@dynamic-routing-out:8] GotoIf("SIP/8512-00000010", "0?:comeback") in new stack
    [2019-03-27 08:33:45] VERBOSE[23324][C-0000000f] pbx_builtins.c: Goto (dynamic-routing-out,s,10)
    [2019-03-27 08:33:45] VERBOSE[23324][C-0000000f] pbx.c: Executing [s@dynamic-routing-out:10] Return("SIP/8512-00000010", "") in new stack
    [2019-03-27 08:33:45] VERBOSE[23324][C-0000000f] pbx.c: Executing [h@trk-1:2] Hangup("SIP/8512-00000010", "") in new stack
    [2019-03-27 08:33:45] VERBOSE[23324][C-0000000f] pbx.c: Spawn extension (trk-1, h, 2) exited non-zero on 'SIP/8512-00000010'
    [2019-03-27 08:33:45] VERBOSE[23345][C-0000000f] bridge_channel.c: Channel IAX2/voip_out-30473 left 'simple_bridge' basic-bridge <3bb5c9f7-b1c3-44b6-b17e-9ca4b188d1b1>
    [2019-03-27 08:33:45] VERBOSE[23345][C-0000000f] chan_iax2.c: Hungup 'IAX2/voip_out-30473'
    [2019-03-27 08:33:45] VERBOSE[23344][C-0000000f] app_mixmonitor.c: MixMonitor close filestream (mixed)
    [2019-03-27 08:33:45] VERBOSE[23344][C-0000000f] app_mixmonitor.c: End MixMonitor Recording IAX2/voip_out-30473
    0